v0.7.6 EOFError when starting

Report & discuss bugs found in SABnzbd
Forum rules
Help us help you:
  • Are you using the latest stable version of SABnzbd? Downloads page.
  • Tell us what system you run SABnzbd on.
  • Adhere to the forum rules.
  • Do you experience problems during downloading?
    Check your connection in Status and Interface settings window.
    Use Test Server in Config > Servers.
    We will probably ask you to do a test using only basic settings.
  • Do you experience problems during repair or unpacking?
    Enable +Debug logging in the Status and Interface settings window and share the relevant parts of the log here using [ code ] sections.
Post Reply
Rutix
Newbie
Newbie
Posts: 4
Joined: November 30th, 2012, 11:33 am

v0.7.6 EOFError when starting

Post by Rutix »

Hi,

Since I upgraded to v0.7.6 I sometimes get this error:

Code: Select all

2012-11-30 17:16:16,744::ERROR::[__init__:942] Loading /usr/pbi/sabnzbd-amd64/data/admin/postproc1.sab failed
2012-11-30 17:16:16,744::INFO::[__init__:943] Traceback: 
Traceback (most recent call last):
  File "/usr/pbi/sabnzbd-amd64/SABnzbd/sabnzbd/__init__.py", line 934, in load_admin
    data = cPickle.load(f)
EOFError
It seems to happen when I shutdown sabnzb when it doing postprocessing stuff and when i start it again i see that error and the job it was working on it gone from my history.

Here is a bigger log:

Code: Select all

2012-11-29 23:57:43,847::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e19.1080p.bluray.x264-hdmi.r62 did not pass SFV check
2012-11-29 23:57:44,072::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e19.1080p.bluray.x264-hdmi.r63 did not pass SFV check
2012-11-29 23:57:44,248::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e19.1080p.bluray.x264-hdmi.r64 did not pass SFV check
2012-11-29 23:57:45,574::INFO::[postproc:558] Running repair on set fringe.s02e06.1080p.bluray.x264.sample-hdmi
2012-11-29 23:57:45,639::INFO::[newsunpack:793] Quick-check for fringe.s02e06.1080p.bluray.x264.sample-hdmi is OK, skipping repair
2012-11-29 23:57:45,642::INFO::[newsunpack:855] Deleting /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e06.1080p.bluray.x264.sample-hdmi.par2
2012-11-29 23:57:45,643::INFO::[postproc:558] Running repair on set fringe.s02e08.1080p.bluray.x264-hdmi
2012-11-29 23:57:45,709::INFO::[newsunpack:1348] Quick-check of file fringe.s02e08.1080p.bluray.x264-hdmi.r11 failed!
2012-11-29 23:57:45,709::INFO::[newsunpack:805] Scanning "/usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.par2"
2012-11-30 00:00:27,856::INFO::[newsunpack:996] Verified in 2 minutes 42 seconds, repair is required
2012-11-30 00:00:27,856::INFO::[newsunpack:1041] Need to fetch 55 more blocks, checking blocks
2012-11-30 00:00:27,856::INFO::[newsunpack:1063] 64 blocks available
2012-11-30 00:00:27,858::INFO::[newsunpack:1096] Added 64 blocks to Fringe.S02.1080p.BluRay.x264-HDMI
2012-11-30 00:00:27,893::INFO::[newsunpack:823] Par verify failed on /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.par2!
2012-11-30 00:00:28,023::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.r00 did not pass SFV check
2012-11-30 00:00:28,192::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.r01 did not pass SFV check
2012-11-30 00:00:28,375::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.r02 did not pass SFV check
2012-11-30 00:00:28,693::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.r03 did not pass SFV check
2012-11-30 00:00:28,826::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.r04 did not pass SFV check
2012-11-30 00:00:28,984::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.r05 did not pass SFV check
2012-11-30 00:00:29,144::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.r06 did not pass SFV check
2012-11-30 00:00:29,336::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.r07 did not pass SFV check
2012-11-30 00:00:29,499::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.r08 did not pass SFV check
2012-11-30 00:00:29,710::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.r09 did not pass SFV check
2012-11-30 00:00:29,878::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.r10 did not pass SFV check
2012-11-30 00:00:30,034::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.r11 did not pass SFV check
2012-11-30 00:00:30,171::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.r12 did not pass SFV check
2012-11-30 00:00:31,938::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.r21 did not pass SFV check
2012-11-30 00:00:41,938::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.r64 did not pass SFV check
2012-11-30 00:00:43,256::INFO::[newsunpack:1415] File /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e08.1080p.bluray.x264-hdmi.r69 did not pass SFV check
2012-11-30 00:00:43,751::INFO::[postproc:558] Running repair on set fringe.s02e07.1080p.bluray.x264.sample-hdmi
2012-11-30 00:00:43,816::INFO::[newsunpack:793] Quick-check for fringe.s02e07.1080p.bluray.x264.sample-hdmi is OK, skipping repair
2012-11-30 00:00:43,819::INFO::[newsunpack:855] Deleting /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e07.1080p.bluray.x264.sample-hdmi.par2
2012-11-30 00:00:43,820::INFO::[postproc:558] Running repair on set fringe.s02e13.1080p.bluray.x264.sample-hdmi
2012-11-30 00:00:43,883::INFO::[newsunpack:793] Quick-check for fringe.s02e13.1080p.bluray.x264.sample-hdmi is OK, skipping repair
2012-11-30 00:00:43,887::INFO::[newsunpack:855] Deleting /usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e13.1080p.bluray.x264.sample-hdmi.par2
2012-11-30 00:00:43,887::INFO::[postproc:558] Running repair on set fringe.s02e22.1080p.bluray.x264-hdmi
2012-11-30 00:00:43,951::INFO::[newsunpack:1348] Quick-check of file fringe.s02e22.1080p.bluray.x264-hdmi.r31 failed!
2012-11-30 00:00:43,952::INFO::[newsunpack:805] Scanning "/usr/pbi/sabnzbd-amd64/downloads/incomplete/Fringe.S02.1080p.BluRay.x264-HDMI/fringe.s02e22.1080p.bluray.x264-hdmi.par2"
2012-11-30 00:02:45,082::INFO::[__init__:359] SABnzbd shutting down...
2012-11-30 00:02:45,083::INFO::[__init__:897] Saving data for bookmarks.sab in /usr/pbi/sabnzbd-amd64/data/admin/bookmarks.sab
2012-11-30 00:02:45,083::INFO::[urlgrabber:72] URLGrabber shutting down
2012-11-30 00:02:45,141::INFO::[__init__:897] Saving data for watched_data.sab in /usr/pbi/sabnzbd-amd64/data/admin/watched_data.sab
2012-11-30 00:02:45,142::INFO::[dirscanner:256] Dirscanner shutting down
2012-11-30 00:02:46,380::INFO::[downloader:399] Shutting down
2012-11-30 00:02:46,381::INFO::[assembler:72] Shutting down
2012-11-30 00:02:46,381::INFO::[postproc:84] Saving postproc queue
2012-11-30 00:02:46,381::INFO::[__init__:897] Saving data for postproc1.sab in /usr/pbi/sabnzbd-amd64/data/admin/postproc1.sab
2012-11-30 00:03:51,641::WARNING::[__init__:169] Signal 15 caught, saving and exiting...
2012-11-30 00:03:51,641::INFO::[nzbqueue:211] Saving queue
2012-11-30 00:03:52,369::INFO::[__init__:897] Saving data for queue9.sab in /usr/pbi/sabnzbd-amd64/data/admin/queue9.sab
2012-11-30 00:03:52,369::INFO::[__init__:897] Saving data for totals9.sab in /usr/pbi/sabnzbd-amd64/data/admin/totals9.sab
2012-11-30 00:03:52,369::INFO::[__init__:897] Saving data for rss_data.sab in /usr/pbi/sabnzbd-amd64/data/admin/rss_data.sab
2012-11-30 00:03:52,369::INFO::[__init__:897] Saving data for bookmarks.sab in /usr/pbi/sabnzbd-amd64/data/admin/bookmarks.sab
2012-11-30 00:03:52,370::INFO::[__init__:897] Saving data for watched_data.sab in /usr/pbi/sabnzbd-amd64/data/admin/watched_data.sab
2012-11-30 00:03:52,370::INFO::[postproc:84] Saving postproc queue
2012-11-30 00:03:52,370::INFO::[__init__:897] Saving data for postproc1.sab in /usr/pbi/sabnzbd-amd64/data/admin/postproc1.sab
2012-11-30 17:16:16,214::INFO::[SABnzbd:1256] --------------------------------
2012-11-30 17:16:16,247::INFO::[SABnzbd:1257] SABnzbd.py-0.7.6 (rev=bc6b3091ebf54e7739235198f9bd054d1e190e0c)
2012-11-30 17:16:16,248::INFO::[SABnzbd:1269] Platform = posix
2012-11-30 17:16:16,248::INFO::[SABnzbd:1270] Python-version = 2.7.3 (default, Jul 25 2012, 18:09:22) 
[GCC 4.2.1 20070719  [FreeBSD]]
2012-11-30 17:16:16,248::INFO::[SABnzbd:1271] Arguments = /usr/pbi/sabnzbd-amd64/SABnzbd/SABnzbd.py -s 0.0.0.0 -f /usr/pbi/sabnzbd-amd64/data/config.ini --pid /usr/pbi/sabnzbd-amd64/data/ -d -b 0
2012-11-30 17:16:16,248::INFO::[SABnzbd:1284] Read INI file /usr/pbi/sabnzbd-amd64/data/config.ini
2012-11-30 17:16:16,285::INFO::[__init__:921] Loading data for bookmarks.sab from /usr/pbi/sabnzbd-amd64/data/admin/bookmarks.sab
2012-11-30 17:16:16,285::INFO::[__init__:921] Loading data for rss_data.sab from /usr/pbi/sabnzbd-amd64/data/admin/rss_data.sab
2012-11-30 17:16:16,293::INFO::[__init__:921] Loading data for totals9.sab from /usr/pbi/sabnzbd-amd64/data/admin/totals9.sab
2012-11-30 17:16:16,300::INFO::[postproc:90] Loading postproc queue
2012-11-30 17:16:16,300::INFO::[__init__:921] Loading data for postproc1.sab from /usr/pbi/sabnzbd-amd64/data/admin/postproc1.sab
2012-11-30 17:16:16,744::ERROR::[__init__:942] Loading /usr/pbi/sabnzbd-amd64/data/admin/postproc1.sab failed
2012-11-30 17:16:16,744::INFO::[__init__:943] Traceback: 
Traceback (most recent call last):
  File "/usr/pbi/sabnzbd-amd64/SABnzbd/sabnzbd/__init__.py", line 934, in load_admin
    data = cPickle.load(f)
EOFError
2012-11-30 17:16:16,781::INFO::[__init__:921] Loading data for queue9.sab from /usr/pbi/sabnzbd-amd64/data/admin/queue9.sab
2012-11-30 17:16:18,113::INFO::[__init__:921] Loading data for watched_data.sab from /usr/pbi/sabnzbd-amd64/data/admin/watched_data.sab
2012-11-30 17:16:18,130::INFO::[downloader:208] Resuming
2012-11-30 17:16:18,130::INFO::[__init__:323] All processes started
2012-11-30 17:16:18,130::INFO::[SABnzbd:334] Web dir is /usr/pbi/sabnzbd-amd64/SABnzbd/interfaces/Plush
2012-11-30 17:16:18,220::INFO::[SABnzbd:334] Web dir is /usr/pbi/sabnzbd-amd64/SABnzbd/interfaces/Config
2012-11-30 17:16:18,310::INFO::[SABnzbd:463] _yenc module... found!
2012-11-30 17:16:18,310::INFO::[SABnzbd:471] par2 binary... found (/usr/local/bin/par2)
2012-11-30 17:16:18,310::INFO::[SABnzbd:479] unrar binary... found (/usr/local/bin/unrar)
2012-11-30 17:16:18,310::INFO::[SABnzbd:484] unzip binary... found (/usr/local/bin/unzip)
2012-11-30 17:16:18,310::INFO::[SABnzbd:490] nice binary... found (/usr/bin/nice)
2012-11-30 17:16:18,310::INFO::[SABnzbd:496] ionice binary... NOT found!
2012-11-30 17:16:18,311::INFO::[SABnzbd:499] pyOpenSSL... found (True)
2012-11-30 17:16:18,313::INFO::[SABnzbd:1483] Starting web-interface on 0.0.0.0:8080
2012-11-30 17:16:18,313::INFO::[_cplogging:55] [30/Nov/2012:17:16:18] ENGINE Bus STARTING
2012-11-30 17:16:18,348::INFO::[_cplogging:55] [30/Nov/2012:17:16:18] ENGINE Started monitor thread '_TimeoutMonitor'.
2012-11-30 17:16:18,449::INFO::[_cplogging:55] [30/Nov/2012:17:16:18] ENGINE Serving on ::1:8080
2012-11-30 17:16:18,551::INFO::[_cplogging:55] [30/Nov/2012:17:16:18] ENGINE Serving on 0.0.0.0:8080
2012-11-30 17:16:18,551::INFO::[_cplogging:55] [30/Nov/2012:17:16:18] ENGINE Bus STARTED
2012-11-30 17:16:19,309::INFO::[SABnzbd:1552] Starting SABnzbd.py-0.7.6
2012-11-30 17:16:19,310::INFO::[dirscanner:265] Dirscanner starting up
2012-11-30 17:16:19,311::INFO::[urlgrabber:77] URLGrabber starting up
Anyone know why this is happening and how I can solve this? I don't mind it failing because then i could retry but it removes it from the history which means there is no easy way to add it to sabnzb again.
Top
User avatar
shypike
Administrator
Administrator
Posts: 19773
Joined: January 18th, 2008, 12:49 pm

Re: v0.7.6 EOFError when starting

Post by shypike »

Don't shutdown SABnzbd while it's post processing.
It needs to finish the current job, but the OS may become
impatient and terminate SABnzbd without giving it a chance to save its admin.
Top
Rutix
Newbie
Newbie
Posts: 4
Joined: November 30th, 2012, 11:33 am

Re: v0.7.6 EOFError when starting

Post by Rutix »

shypike wrote:Don't shutdown SABnzbd while it's post processing.
It needs to finish the current job, but the OS may become
impatient and terminate SABnzbd without giving it a chance to save its admin.
Alright. It needs to save the admin to appear in the history? Or why does it disappear from that?
Top
User avatar
shypike
Administrator
Administrator
Posts: 19773
Joined: January 18th, 2008, 12:49 pm

Re: v0.7.6 EOFError when starting

Post by shypike »

In this case it looks like SABnzbd was killed in the middle of writing to disk.
Top
Rutix
Newbie
Newbie
Posts: 4
Joined: November 30th, 2012, 11:33 am

Re: v0.7.6 EOFError when starting

Post by Rutix »

shypike wrote:In this case it looks like SABnzbd was killed in the middle of writing to disk.
Ahh ok. Sucks, seems i have to redownload then :). thanks for the help
Top
User avatar
shypike
Administrator
Administrator
Posts: 19773
Joined: January 18th, 2008, 12:49 pm

Re: v0.7.6 EOFError when starting

Post by shypike »

Not so fast.
Didn't the job end up as an orphan in Status->QueueRepair?
Top
Rutix
Newbie
Newbie
Posts: 4
Joined: November 30th, 2012, 11:33 am

Re: v0.7.6 EOFError when starting

Post by Rutix »

shypike wrote:Not so fast.
Didn't the job end up as an orphan in Status->QueueRepair?
Omg :D didnt know that existed! Pressed add and it came back unto the queue! Learned something new ^^
Top
Post Reply

Return to “Bug Reports”