[Savage702]

I just had to reload my Linux server, running Ubuntu 14.04, and a fresh install of Gotham 13.2.
Have MySQL setup, and I'm rescanning all my media. This was going ok till a reboot, and now, since the reboot, it won't scan anymore. It shows scanning, then quickly vanishes, as if it's not finding anything more.... but there's a lot more.

Turned on debugging, and started, did an update, and then closed out. Here is the log.
http://pastebin.com/zq41u6cw

Any ideas?

[wsnipex]

try touching smb://192.168.0.101/Movies or creating some file in there.

[Savage702]

I forgot to subscribe to this thread, sorry for the delayed response. I will try that... but I did notice all those odd errors with directories containing %20 for the spaces. I've no clue why some have that, and others don't. Also, this is a rebuild since my last box died, but the array is the same (SnapRAID, was mounted with AUFS but have now changed to MHDDFS because with the rebuild, AUFS started getting quirky as hell with their file whitelisting, etc.

Anyway, where I was going with that was... on my old build, this wasn't an issue that I recall. I didn't go looking for it, but when I went back and changed the Movie directories names that were listed with %20, it started scanning again, then as it started going through TV Shows, it stops scanning again, I look at the logs, and of course see TONS of those strange entries again.

These are the items I'm talking about:

Code:

15:53:30 T:140234871781120   DEBUG: OpenDir - Using authentication url smb://192.168.0.101/Movies/Hobbit%20-%20An%20Unexpected%20Journey%2c%20The%20(2012)

I guess it's possible that my renaming a bunch of the directories did the same/similar as touching the Movies directory?

Thanks for your thoughts and input. I will try your much simplier suggestion when I get home. I was looking into replacing all %20's with spaces using sed, or getting rid of spaces all together and replace all with underscores? That should negate any issues now and forever as far as that goes I'd think... but the Library scanning into SQL seems to take an eternity, even with local NFO's.

[wsnipex]

If renaming them helps, it might be due do locale differences between those filesystems.

[Savage702]

Well, I'm still having a bunch of issues. I thought it was due to spaces in the file names. I've now gone through and replaced spaces with underscores, and issues with crashing persists during library scan/scan for new content, although the crashes seem to be fewer. It's still taking me days to get through my library at this rate.

More information on my setup, if it helps:
XBMC 13.2 - Ubuntu 14.04 64bit & MySQL
SnapRAID 12TB array with mhddfs
Intel Core i3-2120T & 8GB RAM

I've realized now in the XBMC Crash log, all of these strange failed to parse errors. Although, I'm not sure where these are coming from, or if there is a way to tell where they're coming from? Searching for those strings reveals nothing.

Code:

06:19:35 T:140687492249344  NOTICE: -->Python Interpreter Initialized<--
06:19:35 T:140687465060096  NOTICE: Thread JobWorker start, auto delete: true
06:19:36 T:140688370272192  NOTICE: starting zeroconf publishing
06:19:36 T:140688370272192  NOTICE: ES: Starting event server
06:19:36 T:140687448274688  NOTICE: Thread JobWorker start, auto delete: true
06:19:36 T:140687439881984  NOTICE: Thread EventServer start, auto delete: false
06:19:36 T:140687439881984  NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
06:19:36 T:140687439881984  NOTICE: UDP: Listening on port 9777
06:19:36 T:140687022487296  NOTICE: Thread TCPServer start, auto delete: false
06:19:36 T:140687492249344  NOTICE: Thread JobWorker start, auto delete: true
06:19:36 T:140687014094592  NOTICE: Thread AlarmClock start, auto delete: false
06:19:36 T:140688370165504  NOTICE: Thread RSSReader start, auto delete: false
06:19:37 T:140687005701888  NOTICE: Thread JobWorker start, auto delete: true
06:19:45 T:140687022487296   ERROR: JSONRPC: Failed to parse '[hšt?Èb°í]'
06:20:06 T:140686993086208  NOTICE: Thread LanguageInvoker start, auto delete: false
06:20:06 T:140686993086208  NOTICE: -->Python Interpreter Initialized<--
06:21:10 T:140688370272192  NOTICE: Samba is idle. Closing the remaining connections
06:24:05 T:140687022487296   ERROR: JSONRPC: Failed to parse '{–óŠã–,–n±[X  ü}'
06:25:05 T:140687022487296   ERROR: JSONRPC: Failed to parse '[C3”ë]'
06:30:00 T:140687022487296   ERROR: JSONRPC: Failed to parse '{e'z¢Ã©*Ò}'
06:30:30 T:140687022487296   ERROR: JSONRPC: Failed to parse '{®ŠŽ}'
06:38:01 T:140687022487296   ERROR: JSONRPC: Failed to parse '[vøÛ¢E5+Í]'
06:40:55 T:140687964645120  NOTICE: Thread BackgroundLoader start, auto delete: false
06:41:00 T:140687964645120  NOTICE: Thread VideoInfoScanner start, auto delete: false
06:41:00 T:140687964645120  NOTICE: VideoInfoScanner: Starting scan ..
06:41:06 T:140687005701888  NOTICE: Thread JobWorker start, auto delete: true
06:41:18 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
06:44:18 T:140687022487296 WARNING: Previous line repeats 56 times.
06:44:18 T:140687022487296   ERROR: JSONRPC: Failed to parse '[âtŒtŽ]'
06:44:18 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
06:46:38 T:140687022487296 WARNING: Previous line repeats 41 times.
06:46:38 T:140687022487296   ERROR: JSONRPC: Failed to parse '{_ÓFã0-Š€xŠbÓ£Á$¥    ü^Æ}'
06:46:38 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
06:49:12 T:140687022487296 WARNING: Previous line repeats 83 times.
06:49:12 T:140687022487296   ERROR: JSONRPC: Failed to parse '[A}>n­Æ$ŠëˆŠák    t]'
06:49:12 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
06:49:38 T:140687022487296 WARNING: Previous line repeats 17 times.
06:49:38 T:140687022487296   ERROR: JSONRPC: Failed to parse '[¹>Æҁ›Ï§8,Ö»Ñ?"]'
06:49:38 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
06:54:08 T:140687022487296 WARNING: Previous line repeats 164 times.
06:54:08 T:140687022487296   ERROR: JSONRPC: Failed to parse '[‰Õéy†Ÿ6fÉ:UI 0oz]'
06:54:08 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
06:56:09 T:140687022487296 WARNING: Previous line repeats 68 times.
06:56:09 T:140687022487296   ERROR: JSONRPC: Failed to parse '{@9Uˆ%SýÌC슔™AÎÈíƒÍN}'
06:56:09 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
06:57:11 T:140687022487296 WARNING: Previous line repeats 23 times.
06:57:11 T:140687022487296   ERROR: JSONRPC: Failed to parse '{þyŸL,“‚˜í&úŽö˜æOΒ}'
06:57:11 T:140687022487296   ERROR: JSONRPC: Failed to parse '[}ùË.X˜;®ˆ]'
06:57:12 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
06:57:19 T:140687022487296 WARNING: Previous line repeats 2 times.
06:57:19 T:140687022487296   ERROR: JSONRPC: Failed to parse '[Ð]'
06:57:19 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
06:57:59 T:140687022487296 WARNING: Previous line repeats 20 times.
06:57:59 T:140687022487296   ERROR: JSONRPC: Failed to parse '{`L˜ÑˆÍ²“}'
06:57:59 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
06:58:58 T:140687465060096 WARNING: Previous line repeats 47 times.
06:58:58 T:140687465060096  NOTICE: Thread JobWorker start, auto delete: true
06:59:03 T:140687022487296   ERROR: JSONRPC: Failed to parse '{š¢JéšFå¹@ðdòØä}'
06:59:03 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
07:01:06 T:140687022487296 WARNING: Previous line repeats 86 times.
07:01:06 T:140687022487296   ERROR: JSONRPC: Failed to parse '[¯™]'
07:01:06 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
07:01:55 T:140687022487296 WARNING: Previous line repeats 25 times.
07:01:55 T:140687022487296   ERROR: JSONRPC: Failed to parse '{€„û9Ž7‡?
n²}'
07:01:55 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
07:02:01 T:140687022487296 WARNING: Previous line repeats 8 times.
07:02:01 T:140687022487296   ERROR: JSONRPC: Failed to parse '{ÛñQìé9á®}'
07:02:03 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
07:02:08 T:140687022487296 WARNING: Previous line repeats 8 times.
07:02:08 T:140687022487296   ERROR: JSONRPC: Failed to parse '{Û~Տ”'w$ÙŸHÒ;}'
07:02:08 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
07:05:16 T:140687022487296 WARNING: Previous line repeats 357 times.
07:05:16 T:140687022487296   ERROR: JSONRPC: Failed to parse '{†¥„ï4ššàÍZ€×î@˘X¡Žöí}'
07:05:16 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
07:10:35 T:140687022487296 WARNING: Previous line repeats 382 times.
07:10:35 T:140687022487296   ERROR: JSONRPC: Failed to parse '{=Æ}'
07:10:35 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
07:11:16 T:140687022487296 WARNING: Previous line repeats 53 times.
07:11:16 T:140687022487296   ERROR: JSONRPC: Failed to parse '[Foí!÷g{ã+ýÙ]'
07:11:16 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
07:14:17 T:140687022487296 WARNING: Previous line repeats 202 times.
07:14:17 T:140687022487296   ERROR: JSONRPC: Failed to parse '[Gí¯]'
07:14:17 T:140687022487296   ERROR: JSONRPC: Failed to parse '{àÞڏǞ;\qào"/}'
07:14:17 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
07:16:01 T:140687022487296 WARNING: Previous line repeats 51 times.
07:16:01 T:140687022487296   ERROR: JSONRPC: Failed to parse '{Vç}'
07:16:06 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
07:18:33 T:140687022487296 WARNING: Previous line repeats 54 times.
07:18:33 T:140687022487296   ERROR: JSONRPC: Failed to parse '{'«÷Žp Ìó     kº¬ªU¬K0EøÊx}'
07:18:33 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
07:18:44 T:140687022487296 WARNING: Previous line repeats 9 times.
07:18:44 T:140687022487296   ERROR: JSONRPC: Failed to parse '{"áá%ß]èF£Žé?Š';<ÁîD‹Ñ$¡}'
07:18:44 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
07:18:55 T:140687022487296 WARNING: Previous line repeats 7 times.
07:18:55 T:140687022487296   ERROR: JSONRPC: Failed to parse '[…jìÓGԏçµ*ü™Ñ]'
07:18:55 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
07:21:51 T:140687022487296 WARNING: Previous line repeats 185 times.
07:21:51 T:140687022487296   ERROR: JSONRPC: Failed to parse '[ú{ݹw, ï·Ô«€pŒTÊw;]'
07:21:51 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
07:23:07 T:140687022487296 WARNING: Previous line repeats 84 times.
07:23:07 T:140687022487296   ERROR: JSONRPC: Failed to parse '{òàò-ú€}'
07:23:07 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
07:23:33 T:140687022487296 WARNING: Previous line repeats 26 times.
07:23:33 T:140687022487296   ERROR: JSONRPC: Failed to parse '[6o·
!"mæ9Ēp/]'
07:23:33 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes
07:25:05 T:140687022487296 WARNING: Previous line repeats 77 times.
07:25:05 T:140687022487296   ERROR: JSONRPC: Failed to parse '[¶]'
07:25:05 T:140687964645120 WARNING: GetDurationFromMinuteString <runtime> should be in minutes. Interpreting '0' as 0 minutes

[Savage702]

Turned debugging back on, and see where this is coming from, although not sure why:

Code:

22:40:41 T:139822494521088   DEBUG: Mysql execute: insert into actorlinkepisode (idActor, idepisode, strRole, iOrder) values(51058,12830,'Jimmy Palmer',15)
22:40:41 T:139822494521088   DEBUG: Mysql execute: update actors set strThumb='<thumb>http://thetvdb.com/banners/actors/127861.jpg</thumb>' where idActor=18510
22:40:41 T:139822494521088   DEBUG: Mysql execute: UPDATE art SET url='smb://192.168.0.101/TV-Current/NCIS/.actors/Rocky_Carroll.jpg' where art_id=16245
22:40:41 T:139822494521088   DEBUG: Mysql execute: insert into actorlinkepisode (idActor, idepisode, strRole, iOrder) values(18510,12830,'Leon Vance',16)
22:40:41 T:139822494521088   DEBUG: Mysql execute: insert into actors (idActor, strActor, strThumb) values( NULL, 'Frank Cardea|George Schenc','')
22:40:41 T:139822494521088   DEBUG: Mysql execute: insert into writerlinkepisode (idWriter,idEpisode) values(51706,12830)
22:40:41 T:139822494521088   DEBUG: Mysql execute: insert into directorlinkepisode (idDirector,idEpisode) values(42079,12830)
22:40:41 T:139822494521088   DEBUG: Mysql execute: INSERT INTO art(media_id, media_type, type, url) VALUES (12830, 'episode', 'thumb', 'smb://192.168.0.101/TV-Current/NCIS/Season_10/NCIS_S10E17_Prime_Suspect-thumb.jpg')
22:40:41 T:139822494521088   DEBUG: Mysql execute: update episode set c00='Prime Suspect',c01='A media frenzy surrounding an unidentified criminal prompts Gibbs’ barber to question his son’s involvement and ask for Gibbs’ help in ruling out his son as a prime suspect.',c02='',c03='7.500000',c04='Frank Cardea|George Schenc',c05='2013-03-05',c06='<thumb>http://thetvdb.com/banners/episodes/72108/4502733.jpg</thumb>',c07='',c08=NULL,c09='0',c10='James Whitmore, Jr.',c11='',c12='10',c13='17',c14='',c15='-1',c16='-1',c17='-1',c18='smb://192.168.0.101/TV-Current/NCIS/Season_10/NCIS_S10E17_Prime_Suspect.mp4',c19='3882',c20='' where idEpisode=12830
22:40:42 T:139822494521088   DEBUG: Mysql commit transaction
22:40:42 T:139822494521088   DEBUG: Mysql execute: update files set playCount=NULL,lastPlayed=NULL where idFile=27089
22:40:42 T:139822494521088   DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc
22:40:42 T:139822494521088   DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate
22:40:42 T:139822494521088   DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc
22:40:42 T:139822494521088   DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate
22:40:42 T:139821821441792    INFO: JSONRPC Server: Disconnection detected
22:40:42 T:139821821441792    INFO: Previous line repeats 1 times.
22:40:42 T:139821821441792   DEBUG: JSONRPC Server: New connection detected
22:40:42 T:139821821441792    INFO: JSONRPC Server: New connection added
22:40:42 T:139821821441792   DEBUG: JSONRPC Server: New connection detected
22:40:42 T:139821821441792    INFO: JSONRPC Server: New connection added

edit:

And pasting it above does not reflect what the log shows... sheesh.