Nov 24, 2018 03:57:51.864 [0x7f287fa8a710] INFO - Plex Media Scanner v1.13.9.5456-ecd600442 - ubuntu PC x86_64 - build: linux-ubuntu-x86_64 - GMT -05:00
Nov 24, 2018 03:57:51.865 [0x7f287fa8a710] INFO - Linux version: 3.10.71 (#1 SMP PREEMPT Thu Apr 16 22:06:01 CST 2015), language: en-US
Nov 24, 2018 03:57:51.865 [0x7f287fa8a710] INFO - Processor         Intel(R) Atom(TM) CPU D2550   @ 1.86GHz
Nov 24, 2018 03:57:51.865 [0x7f287fa8a710] INFO - /raid0/data/module/Plex/sys/Plex Media Scanner --analyze --item 50632,50739,50747,50654,50631,50738,50957,50953,50744,50645 --log-file-suffix  Analysis
Nov 24, 2018 03:57:51.901 [0x7f288abab7e0] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Nov 24, 2018 03:57:52.501 [0x7f288abab7e0] DEBUG - Analyzing media parts for item 50632 (Mexican Moon): 47893
Nov 24, 2018 03:57:52.506 [0x7f288abab7e0] DEBUG - [ID 48543] Media part analysis: /raid/NAS_Public/Music/Concrete Blonde/1993 - Mexican Moon/1.02 - Mexican Moon.m4a
Nov 24, 2018 03:57:52.532 [0x7f288abab7e0] DEBUG - [MI] Opening input file: "/raid/NAS_Public/Music/Concrete Blonde/1993 - Mexican Moon/1.02 - Mexican Moon.m4a"
Nov 24, 2018 03:57:52.533 [0x7f288abab7e0] DEBUG - [FFMPEG] - Opening '/raid/NAS_Public/Music/Concrete Blonde/1993 - Mexican Moon/1.02 - Mexican Moon.m4a' for reading
Nov 24, 2018 03:57:52.534 [0x7f288abab7e0] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Nov 24, 2018 03:57:52.563 [0x7f288abab7e0] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Nov 24, 2018 03:57:52.563 [0x7f288abab7e0] DEBUG - [FFMPEG] - ISO: File Type Major Brand: M4A 
Nov 24, 2018 03:57:52.564 [0x7f288abab7e0] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Nov 24, 2018 03:57:52.568 [0x7f288abab7e0] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 58998 bytes read:65536 seeks:0 nb_streams:1
Nov 24, 2018 03:57:52.578 [0x7f288abab7e0] DEBUG - [FFMPEG] - demuxer injecting skip 2112 / discard 0
Nov 24, 2018 03:57:52.579 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip 2112 / discard 0 samples due to side data
Nov 24, 2018 03:57:52.579 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip whole frame, skip left: 1088
Nov 24, 2018 03:57:52.579 [0x7f288abab7e0] DEBUG - [FFMPEG] - All info found
Nov 24, 2018 03:57:52.580 [0x7f288abab7e0] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 59005 bytes read:65536 seeks:0 frames:1
Nov 24, 2018 03:57:52.580 [0x7f288abab7e0] DEBUG - [MI] Opening "/raid/NAS_Public/Music/Concrete Blonde/1993 - Mexican Moon/1.02 - Mexican Moon.m4a" for MP4 atom parsing
Nov 24, 2018 03:57:52.580 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Nov 24, 2018 03:57:52.580 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found moov atom of size 56634 at offset 32
Nov 24, 2018 03:57:52.580 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found trak atom of size 55601 at offset 148
Nov 24, 2018 03:57:52.580 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found mdia atom of size 55501 at offset 248
Nov 24, 2018 03:57:52.581 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found minf atom of size 55427 at offset 322
Nov 24, 2018 03:57:52.581 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found stbl atom of size 55367 at offset 382
Nov 24, 2018 03:57:52.581 [0x7f288abab7e0] DEBUG - [MI]  * 656c61ef1f11d8efa7ee4aa97509dfb7e44e3628 [mp4 /aac] Duration: 305621ms, 0x0 (0.000000) 0.000fps, bitrate=128kbps, audio channels=2, optimizedForStreaming=1 ("/raid/NAS_Public/Music/Concrete Blonde/1993 - Mexican Moon/1.02 - Mexican Moon.m4a")
Nov 24, 2018 03:57:52.584 [0x7f288abab7e0] DEBUG - [MI] Importing 1 media chapters.
Nov 24, 2018 03:57:52.587 [0x7f288abab7e0] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:52.587 [0x7f288abab7e0] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:52.595 [0x7f288abab7e0] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:52.597 [0x7f288abab7e0] DEBUG - Updating metadata item (save) (Mexican Moon) with ID 50632
Nov 24, 2018 03:57:52.642 [0x7f288abab7e0] DEBUG - [MI] Closing input file: "/raid/NAS_Public/Music/Concrete Blonde/1993 - Mexican Moon/1.02 - Mexican Moon.m4a"
Nov 24, 2018 03:57:52.643 [0x7f288abab7e0] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 0 seeks
Nov 24, 2018 03:57:52.649 [0x7f288abab7e0] DEBUG - Updating media item 47893, size=4922053, metadata_item_id=50632
Nov 24, 2018 03:57:52.650 [0x7f288abab7e0] DEBUG - Updating part with ID=48543 [/raid/NAS_Public/Music/Concrete Blonde/1993 - Mexican Moon/1.02 - Mexican Moon.m4a]
Nov 24, 2018 03:57:52.681 [0x7f288abab7e0] DEBUG - Analyzing media parts for item 50739 (Backstabber): 47992
Nov 24, 2018 03:57:52.686 [0x7f288abab7e0] DEBUG - [ID 48642] Media part analysis: /raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.02 - Backstabber.m4a
Nov 24, 2018 03:57:52.738 [0x7f288abab7e0] DEBUG - [MI] Opening input file: "/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.02 - Backstabber.m4a"
Nov 24, 2018 03:57:52.738 [0x7f288abab7e0] DEBUG - [FFMPEG] - Opening '/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.02 - Backstabber.m4a' for reading
Nov 24, 2018 03:57:52.738 [0x7f288abab7e0] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Nov 24, 2018 03:57:52.762 [0x7f288abab7e0] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Nov 24, 2018 03:57:52.762 [0x7f288abab7e0] DEBUG - [FFMPEG] - ISO: File Type Major Brand: M4A 
Nov 24, 2018 03:57:52.762 [0x7f288abab7e0] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Nov 24, 2018 03:57:52.765 [0x7f288abab7e0] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 51240 bytes read:65536 seeks:0 nb_streams:1
Nov 24, 2018 03:57:52.767 [0x7f288abab7e0] DEBUG - [FFMPEG] - demuxer injecting skip 2112 / discard 0
Nov 24, 2018 03:57:52.767 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip 2112 / discard 0 samples due to side data
Nov 24, 2018 03:57:52.767 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip whole frame, skip left: 1088
Nov 24, 2018 03:57:52.767 [0x7f288abab7e0] DEBUG - [FFMPEG] - All info found
Nov 24, 2018 03:57:52.768 [0x7f288abab7e0] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 51247 bytes read:65536 seeks:0 frames:1
Nov 24, 2018 03:57:52.768 [0x7f288abab7e0] DEBUG - [MI] Opening "/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.02 - Backstabber.m4a" for MP4 atom parsing
Nov 24, 2018 03:57:52.768 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Nov 24, 2018 03:57:52.768 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found moov atom of size 46976 at offset 32
Nov 24, 2018 03:57:52.768 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found trak atom of size 45889 at offset 148
Nov 24, 2018 03:57:52.768 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found mdia atom of size 45789 at offset 248
Nov 24, 2018 03:57:52.769 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found minf atom of size 45715 at offset 322
Nov 24, 2018 03:57:52.769 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found stbl atom of size 45655 at offset 382
Nov 24, 2018 03:57:52.769 [0x7f288abab7e0] DEBUG - [MI]  * ba0be546b3e362053e3282b55f48d8bad262f525 [mp4 /aac] Duration: 251797ms, 0x0 (0.000000) 0.000fps, bitrate=129kbps, audio channels=2, optimizedForStreaming=1 ("/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.02 - Backstabber.m4a")
Nov 24, 2018 03:57:52.772 [0x7f288abab7e0] DEBUG - [MI] Importing 1 media chapters.
Nov 24, 2018 03:57:52.773 [0x7f288abab7e0] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:52.773 [0x7f288abab7e0] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:52.778 [0x7f288abab7e0] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:52.779 [0x7f288abab7e0] DEBUG - Updating metadata item (save) (Backstabber) with ID 50739
Nov 24, 2018 03:57:52.807 [0x7f288abab7e0] DEBUG - [MI] Closing input file: "/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.02 - Backstabber.m4a"
Nov 24, 2018 03:57:52.808 [0x7f288abab7e0] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 0 seeks
Nov 24, 2018 03:57:52.813 [0x7f288abab7e0] DEBUG - Updating media item 47992, size=4083516, metadata_item_id=50739
Nov 24, 2018 03:57:52.814 [0x7f288abab7e0] DEBUG - Updating part with ID=48642 [/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.02 - Backstabber.m4a]
Nov 24, 2018 03:57:52.844 [0x7f288abab7e0] DEBUG - Analyzing media parts for item 50747 (Necessary Evil): 48000
Nov 24, 2018 03:57:52.848 [0x7f288abab7e0] DEBUG - [ID 48650] Media part analysis: /raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.10 - Necessary Evil.m4a
Nov 24, 2018 03:57:52.910 [0x7f288abab7e0] DEBUG - [MI] Opening input file: "/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.10 - Necessary Evil.m4a"
Nov 24, 2018 03:57:52.910 [0x7f288abab7e0] DEBUG - [FFMPEG] - Opening '/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.10 - Necessary Evil.m4a' for reading
Nov 24, 2018 03:57:52.910 [0x7f288abab7e0] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Nov 24, 2018 03:57:52.927 [0x7f288abab7e0] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Nov 24, 2018 03:57:52.928 [0x7f288abab7e0] DEBUG - [FFMPEG] - ISO: File Type Major Brand: M4A 
Nov 24, 2018 03:57:52.928 [0x7f288abab7e0] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Nov 24, 2018 03:57:52.930 [0x7f288abab7e0] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 51240 bytes read:65536 seeks:0 nb_streams:1
Nov 24, 2018 03:57:52.932 [0x7f288abab7e0] DEBUG - [FFMPEG] - demuxer injecting skip 2112 / discard 0
Nov 24, 2018 03:57:52.932 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip 2112 / discard 0 samples due to side data
Nov 24, 2018 03:57:52.932 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip whole frame, skip left: 1088
Nov 24, 2018 03:57:52.932 [0x7f288abab7e0] DEBUG - [FFMPEG] - All info found
Nov 24, 2018 03:57:52.932 [0x7f288abab7e0] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 51247 bytes read:65536 seeks:0 frames:1
Nov 24, 2018 03:57:52.932 [0x7f288abab7e0] DEBUG - [MI] Opening "/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.10 - Necessary Evil.m4a" for MP4 atom parsing
Nov 24, 2018 03:57:52.933 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Nov 24, 2018 03:57:52.933 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found moov atom of size 33094 at offset 32
Nov 24, 2018 03:57:52.933 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found trak atom of size 32001 at offset 148
Nov 24, 2018 03:57:52.933 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found mdia atom of size 31901 at offset 248
Nov 24, 2018 03:57:52.933 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found minf atom of size 31827 at offset 322
Nov 24, 2018 03:57:52.933 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found stbl atom of size 31767 at offset 382
Nov 24, 2018 03:57:52.933 [0x7f288abab7e0] DEBUG - [MI]  * ffc31405bcb30215669632f4dac0053fa80f9ca3 [mp4 /aac] Duration: 174846ms, 0x0 (0.000000) 0.000fps, bitrate=130kbps, audio channels=2, optimizedForStreaming=1 ("/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.10 - Necessary Evil.m4a")
Nov 24, 2018 03:57:52.936 [0x7f288abab7e0] DEBUG - [MI] Importing 1 media chapters.
Nov 24, 2018 03:57:52.937 [0x7f288abab7e0] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:52.937 [0x7f288abab7e0] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:52.942 [0x7f288abab7e0] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:52.943 [0x7f288abab7e0] DEBUG - Updating metadata item (save) (Necessary Evil) with ID 50747
Nov 24, 2018 03:57:52.951 [0x7f288abab7e0] DEBUG - [MI] Closing input file: "/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.10 - Necessary Evil.m4a"
Nov 24, 2018 03:57:52.952 [0x7f288abab7e0] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 0 seeks
Nov 24, 2018 03:57:52.957 [0x7f288abab7e0] DEBUG - Updating media item 48000, size=2850389, metadata_item_id=50747
Nov 24, 2018 03:57:52.958 [0x7f288abab7e0] DEBUG - Updating part with ID=48650 [/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.10 - Necessary Evil.m4a]
Nov 24, 2018 03:57:52.971 [0x7f288abab7e0] DEBUG - Analyzing media parts for item 50654 (Tomorrow, Wendy): 47914
Nov 24, 2018 03:57:52.976 [0x7f288abab7e0] DEBUG - [ID 48564] Media part analysis: /raid/NAS_Public/Music/Concrete Blonde/1990 - Bloodletting/1.10 - Tomorrow, Wendy.m4a
Nov 24, 2018 03:57:53.037 [0x7f288abab7e0] DEBUG - [MI] Opening input file: "/raid/NAS_Public/Music/Concrete Blonde/1990 - Bloodletting/1.10 - Tomorrow, Wendy.m4a"
Nov 24, 2018 03:57:53.038 [0x7f288abab7e0] DEBUG - [FFMPEG] - Opening '/raid/NAS_Public/Music/Concrete Blonde/1990 - Bloodletting/1.10 - Tomorrow, Wendy.m4a' for reading
Nov 24, 2018 03:57:53.038 [0x7f288abab7e0] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Nov 24, 2018 03:57:53.054 [0x7f288abab7e0] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Nov 24, 2018 03:57:53.054 [0x7f288abab7e0] DEBUG - [FFMPEG] - ISO: File Type Major Brand: M4A 
Nov 24, 2018 03:57:53.055 [0x7f288abab7e0] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Nov 24, 2018 03:57:53.058 [0x7f288abab7e0] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 58314 bytes read:65536 seeks:0 nb_streams:1
Nov 24, 2018 03:57:53.059 [0x7f288abab7e0] DEBUG - [FFMPEG] - demuxer injecting skip 2112 / discard 0
Nov 24, 2018 03:57:53.060 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip 2112 / discard 0 samples due to side data
Nov 24, 2018 03:57:53.060 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip whole frame, skip left: 1088
Nov 24, 2018 03:57:53.060 [0x7f288abab7e0] DEBUG - [FFMPEG] - All info found
Nov 24, 2018 03:57:53.060 [0x7f288abab7e0] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 58321 bytes read:65536 seeks:0 frames:1
Nov 24, 2018 03:57:53.060 [0x7f288abab7e0] DEBUG - [MI] Opening "/raid/NAS_Public/Music/Concrete Blonde/1990 - Bloodletting/1.10 - Tomorrow, Wendy.m4a" for MP4 atom parsing
Nov 24, 2018 03:57:53.060 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Nov 24, 2018 03:57:53.060 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found moov atom of size 55944 at offset 32
Nov 24, 2018 03:57:53.061 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found trak atom of size 54905 at offset 148
Nov 24, 2018 03:57:53.061 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found mdia atom of size 54805 at offset 248
Nov 24, 2018 03:57:53.061 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found minf atom of size 54731 at offset 322
Nov 24, 2018 03:57:53.061 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found stbl atom of size 54671 at offset 382
Nov 24, 2018 03:57:53.061 [0x7f288abab7e0] DEBUG - [MI]  * 023aea33697471da498e45e86f3ef554d76edd7b [mp4 /aac] Duration: 301836ms, 0x0 (0.000000) 0.000fps, bitrate=129kbps, audio channels=2, optimizedForStreaming=1 ("/raid/NAS_Public/Music/Concrete Blonde/1990 - Bloodletting/1.10 - Tomorrow, Wendy.m4a")
Nov 24, 2018 03:57:53.064 [0x7f288abab7e0] DEBUG - [MI] Importing 1 media chapters.
Nov 24, 2018 03:57:53.065 [0x7f288abab7e0] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.065 [0x7f288abab7e0] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.070 [0x7f288abab7e0] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.071 [0x7f288abab7e0] DEBUG - Updating metadata item (save) (Tomorrow, Wendy) with ID 50654
Nov 24, 2018 03:57:53.079 [0x7f288abab7e0] DEBUG - [MI] Closing input file: "/raid/NAS_Public/Music/Concrete Blonde/1990 - Bloodletting/1.10 - Tomorrow, Wendy.m4a"
Nov 24, 2018 03:57:53.080 [0x7f288abab7e0] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 0 seeks
Nov 24, 2018 03:57:53.085 [0x7f288abab7e0] DEBUG - Updating media item 47914, size=4892094, metadata_item_id=50654
Nov 24, 2018 03:57:53.121 [0x7f288abab7e0] DEBUG - Updating part with ID=48564 [/raid/NAS_Public/Music/Concrete Blonde/1990 - Bloodletting/1.10 - Tomorrow, Wendy.m4a]
Nov 24, 2018 03:57:53.134 [0x7f288abab7e0] DEBUG - Analyzing media parts for item 50631 (Jenny I Read): 47892
Nov 24, 2018 03:57:53.139 [0x7f288abab7e0] DEBUG - [ID 48542] Media part analysis: /raid/NAS_Public/Music/Concrete Blonde/1993 - Mexican Moon/1.01 - Jenny I Read.m4a
Nov 24, 2018 03:57:53.148 [0x7f288abab7e0] DEBUG - [MI] Opening input file: "/raid/NAS_Public/Music/Concrete Blonde/1993 - Mexican Moon/1.01 - Jenny I Read.m4a"
Nov 24, 2018 03:57:53.149 [0x7f288abab7e0] DEBUG - [FFMPEG] - Opening '/raid/NAS_Public/Music/Concrete Blonde/1993 - Mexican Moon/1.01 - Jenny I Read.m4a' for reading
Nov 24, 2018 03:57:53.149 [0x7f288abab7e0] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Nov 24, 2018 03:57:53.174 [0x7f288abab7e0] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Nov 24, 2018 03:57:53.174 [0x7f288abab7e0] DEBUG - [FFMPEG] - ISO: File Type Major Brand: M4A 
Nov 24, 2018 03:57:53.174 [0x7f288abab7e0] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Nov 24, 2018 03:57:53.178 [0x7f288abab7e0] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 62086 bytes read:65536 seeks:0 nb_streams:1
Nov 24, 2018 03:57:53.179 [0x7f288abab7e0] DEBUG - [FFMPEG] - demuxer injecting skip 2112 / discard 0
Nov 24, 2018 03:57:53.180 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip 2112 / discard 0 samples due to side data
Nov 24, 2018 03:57:53.180 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip whole frame, skip left: 1088
Nov 24, 2018 03:57:53.180 [0x7f288abab7e0] DEBUG - [FFMPEG] - All info found
Nov 24, 2018 03:57:53.180 [0x7f288abab7e0] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 62093 bytes read:65536 seeks:0 frames:1
Nov 24, 2018 03:57:53.180 [0x7f288abab7e0] DEBUG - [MI] Opening "/raid/NAS_Public/Music/Concrete Blonde/1993 - Mexican Moon/1.01 - Jenny I Read.m4a" for MP4 atom parsing
Nov 24, 2018 03:57:53.180 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Nov 24, 2018 03:57:53.181 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found moov atom of size 59722 at offset 32
Nov 24, 2018 03:57:53.181 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found trak atom of size 58689 at offset 148
Nov 24, 2018 03:57:53.181 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found mdia atom of size 58589 at offset 248
Nov 24, 2018 03:57:53.181 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found minf atom of size 58515 at offset 322
Nov 24, 2018 03:57:53.181 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found stbl atom of size 58455 at offset 382
Nov 24, 2018 03:57:53.181 [0x7f288abab7e0] DEBUG - [MI]  * ebb36ea3b85e1fadbf42599ca0ec2c6738e83d03 [mp4 /aac] Duration: 322734ms, 0x0 (0.000000) 0.000fps, bitrate=128kbps, audio channels=2, optimizedForStreaming=1 ("/raid/NAS_Public/Music/Concrete Blonde/1993 - Mexican Moon/1.01 - Jenny I Read.m4a")
Nov 24, 2018 03:57:53.184 [0x7f288abab7e0] DEBUG - [MI] Importing 1 media chapters.
Nov 24, 2018 03:57:53.185 [0x7f288abab7e0] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.186 [0x7f288abab7e0] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.191 [0x7f288abab7e0] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.192 [0x7f288abab7e0] DEBUG - Updating metadata item (save) (Jenny I Read) with ID 50631
Nov 24, 2018 03:57:53.200 [0x7f288abab7e0] DEBUG - [MI] Closing input file: "/raid/NAS_Public/Music/Concrete Blonde/1993 - Mexican Moon/1.01 - Jenny I Read.m4a"
Nov 24, 2018 03:57:53.200 [0x7f288abab7e0] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 0 seeks
Nov 24, 2018 03:57:53.205 [0x7f288abab7e0] DEBUG - Updating media item 47892, size=5200484, metadata_item_id=50631
Nov 24, 2018 03:57:53.206 [0x7f288abab7e0] DEBUG - Updating part with ID=48542 [/raid/NAS_Public/Music/Concrete Blonde/1993 - Mexican Moon/1.01 - Jenny I Read.m4a]
Nov 24, 2018 03:57:53.233 [0x7f288abab7e0] DEBUG - Analyzing media parts for item 50738 (Sex Changes): 47991
Nov 24, 2018 03:57:53.238 [0x7f288abab7e0] DEBUG - [ID 48641] Media part analysis: /raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.01 - Sex Changes.m4a
Nov 24, 2018 03:57:53.260 [0x7f288abab7e0] DEBUG - [MI] Opening input file: "/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.01 - Sex Changes.m4a"
Nov 24, 2018 03:57:53.260 [0x7f288abab7e0] DEBUG - [FFMPEG] - Opening '/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.01 - Sex Changes.m4a' for reading
Nov 24, 2018 03:57:53.260 [0x7f288abab7e0] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Nov 24, 2018 03:57:53.271 [0x7f288abab7e0] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Nov 24, 2018 03:57:53.272 [0x7f288abab7e0] DEBUG - [FFMPEG] - ISO: File Type Major Brand: M4A 
Nov 24, 2018 03:57:53.272 [0x7f288abab7e0] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Nov 24, 2018 03:57:53.274 [0x7f288abab7e0] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 51240 bytes read:65536 seeks:0 nb_streams:1
Nov 24, 2018 03:57:53.276 [0x7f288abab7e0] DEBUG - [FFMPEG] - demuxer injecting skip 2112 / discard 0
Nov 24, 2018 03:57:53.276 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip 2112 / discard 0 samples due to side data
Nov 24, 2018 03:57:53.276 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip whole frame, skip left: 1088
Nov 24, 2018 03:57:53.276 [0x7f288abab7e0] DEBUG - [FFMPEG] - All info found
Nov 24, 2018 03:57:53.277 [0x7f288abab7e0] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 51247 bytes read:65536 seeks:0 frames:1
Nov 24, 2018 03:57:53.277 [0x7f288abab7e0] DEBUG - [MI] Opening "/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.01 - Sex Changes.m4a" for MP4 atom parsing
Nov 24, 2018 03:57:53.277 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Nov 24, 2018 03:57:53.277 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found moov atom of size 46892 at offset 32
Nov 24, 2018 03:57:53.277 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found trak atom of size 45805 at offset 148
Nov 24, 2018 03:57:53.277 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found mdia atom of size 45705 at offset 248
Nov 24, 2018 03:57:53.277 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found minf atom of size 45631 at offset 322
Nov 24, 2018 03:57:53.277 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found stbl atom of size 45571 at offset 382
Nov 24, 2018 03:57:53.278 [0x7f288abab7e0] DEBUG - [MI]  * 7846303c850a5106f39010f6a846a1578317f5a1 [mp4 /aac] Duration: 251333ms, 0x0 (0.000000) 0.000fps, bitrate=129kbps, audio channels=2, optimizedForStreaming=1 ("/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.01 - Sex Changes.m4a")
Nov 24, 2018 03:57:53.280 [0x7f288abab7e0] DEBUG - [MI] Importing 1 media chapters.
Nov 24, 2018 03:57:53.281 [0x7f288abab7e0] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.282 [0x7f288abab7e0] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.287 [0x7f288abab7e0] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.288 [0x7f288abab7e0] DEBUG - Updating metadata item (save) (Sex Changes) with ID 50738
Nov 24, 2018 03:57:53.296 [0x7f288abab7e0] DEBUG - [MI] Closing input file: "/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.01 - Sex Changes.m4a"
Nov 24, 2018 03:57:53.297 [0x7f288abab7e0] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 0 seeks
Nov 24, 2018 03:57:53.302 [0x7f288abab7e0] DEBUG - Updating media item 47991, size=4075340, metadata_item_id=50738
Nov 24, 2018 03:57:53.303 [0x7f288abab7e0] DEBUG - Updating part with ID=48641 [/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.01 - Sex Changes.m4a]
Nov 24, 2018 03:57:53.316 [0x7f288abab7e0] DEBUG - Analyzing media parts for item 50957 (There You Go): 48188
Nov 24, 2018 03:57:53.321 [0x7f288abab7e0] DEBUG - [ID 48855] Media part analysis: /raid/NAS_Public/Music/Johnny Cash/2006 - Golden Legends/1.08 - There You Go.m4a
Nov 24, 2018 03:57:53.365 [0x7f288abab7e0] DEBUG - [MI] Opening input file: "/raid/NAS_Public/Music/Johnny Cash/2006 - Golden Legends/1.08 - There You Go.m4a"
Nov 24, 2018 03:57:53.365 [0x7f288abab7e0] DEBUG - [FFMPEG] - Opening '/raid/NAS_Public/Music/Johnny Cash/2006 - Golden Legends/1.08 - There You Go.m4a' for reading
Nov 24, 2018 03:57:53.365 [0x7f288abab7e0] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Nov 24, 2018 03:57:53.388 [0x7f288abab7e0] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Nov 24, 2018 03:57:53.388 [0x7f288abab7e0] DEBUG - [FFMPEG] - ISO: File Type Major Brand: M4A 
Nov 24, 2018 03:57:53.389 [0x7f288abab7e0] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Nov 24, 2018 03:57:53.390 [0x7f288abab7e0] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 51240 bytes read:65536 seeks:1 nb_streams:1
Nov 24, 2018 03:57:53.392 [0x7f288abab7e0] DEBUG - [FFMPEG] - demuxer injecting skip 2112 / discard 0
Nov 24, 2018 03:57:53.392 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip 2112 / discard 0 samples due to side data
Nov 24, 2018 03:57:53.392 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip whole frame, skip left: 1088
Nov 24, 2018 03:57:53.392 [0x7f288abab7e0] DEBUG - [FFMPEG] - All info found
Nov 24, 2018 03:57:53.392 [0x7f288abab7e0] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 51247 bytes read:65536 seeks:1 frames:1
Nov 24, 2018 03:57:53.393 [0x7f288abab7e0] DEBUG - [MI] Opening "/raid/NAS_Public/Music/Johnny Cash/2006 - Golden Legends/1.08 - There You Go.m4a" for MP4 atom parsing
Nov 24, 2018 03:57:53.393 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Nov 24, 2018 03:57:53.393 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found moov atom of size 26455 at offset 32
Nov 24, 2018 03:57:53.393 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found trak atom of size 25413 at offset 148
Nov 24, 2018 03:57:53.393 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found mdia atom of size 25313 at offset 248
Nov 24, 2018 03:57:53.393 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found minf atom of size 25239 at offset 322
Nov 24, 2018 03:57:53.394 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found stbl atom of size 25179 at offset 382
Nov 24, 2018 03:57:53.394 [0x7f288abab7e0] DEBUG - [MI]  * 805c75b01dfbdca9975bb4061c63de81a8f9b3e4 [mp4 /aac] Duration: 138344ms, 0x0 (0.000000) 0.000fps, bitrate=129kbps, audio channels=2, optimizedForStreaming=1 ("/raid/NAS_Public/Music/Johnny Cash/2006 - Golden Legends/1.08 - There You Go.m4a")
Nov 24, 2018 03:57:53.396 [0x7f288abab7e0] DEBUG - [MI] Importing 1 media chapters.
Nov 24, 2018 03:57:53.398 [0x7f288abab7e0] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.398 [0x7f288abab7e0] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.403 [0x7f288abab7e0] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.404 [0x7f288abab7e0] DEBUG - Updating metadata item (save) (There You Go) with ID 50957
Nov 24, 2018 03:57:53.413 [0x7f288abab7e0] DEBUG - [MI] Closing input file: "/raid/NAS_Public/Music/Johnny Cash/2006 - Golden Legends/1.08 - There You Go.m4a"
Nov 24, 2018 03:57:53.414 [0x7f288abab7e0] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 1 seeks
Nov 24, 2018 03:57:53.419 [0x7f288abab7e0] DEBUG - Updating media item 48188, size=2241523, metadata_item_id=50957
Nov 24, 2018 03:57:53.420 [0x7f288abab7e0] DEBUG - Updating part with ID=48855 [/raid/NAS_Public/Music/Johnny Cash/2006 - Golden Legends/1.08 - There You Go.m4a]
Nov 24, 2018 03:57:53.444 [0x7f288abab7e0] DEBUG - Analyzing media parts for item 50953 (Ballad Of A Teenage Queen): 48184
Nov 24, 2018 03:57:53.448 [0x7f288abab7e0] DEBUG - [ID 48851] Media part analysis: /raid/NAS_Public/Music/Johnny Cash/2006 - Golden Legends/1.04 - Ballad Of A Teenage Queen.m4a
Nov 24, 2018 03:57:53.494 [0x7f288abab7e0] DEBUG - [MI] Opening input file: "/raid/NAS_Public/Music/Johnny Cash/2006 - Golden Legends/1.04 - Ballad Of A Teenage Queen.m4a"
Nov 24, 2018 03:57:53.494 [0x7f288abab7e0] DEBUG - [FFMPEG] - Opening '/raid/NAS_Public/Music/Johnny Cash/2006 - Golden Legends/1.04 - Ballad Of A Teenage Queen.m4a' for reading
Nov 24, 2018 03:57:53.494 [0x7f288abab7e0] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Nov 24, 2018 03:57:53.517 [0x7f288abab7e0] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Nov 24, 2018 03:57:53.517 [0x7f288abab7e0] DEBUG - [FFMPEG] - ISO: File Type Major Brand: M4A 
Nov 24, 2018 03:57:53.518 [0x7f288abab7e0] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Nov 24, 2018 03:57:53.519 [0x7f288abab7e0] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 51240 bytes read:65536 seeks:1 nb_streams:1
Nov 24, 2018 03:57:53.520 [0x7f288abab7e0] DEBUG - [FFMPEG] - demuxer injecting skip 2112 / discard 0
Nov 24, 2018 03:57:53.521 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip 2112 / discard 0 samples due to side data
Nov 24, 2018 03:57:53.521 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip whole frame, skip left: 1088
Nov 24, 2018 03:57:53.521 [0x7f288abab7e0] DEBUG - [FFMPEG] - All info found
Nov 24, 2018 03:57:53.521 [0x7f288abab7e0] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 51247 bytes read:65536 seeks:1 frames:1
Nov 24, 2018 03:57:53.521 [0x7f288abab7e0] DEBUG - [MI] Opening "/raid/NAS_Public/Music/Johnny Cash/2006 - Golden Legends/1.04 - Ballad Of A Teenage Queen.m4a" for MP4 atom parsing
Nov 24, 2018 03:57:53.521 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Nov 24, 2018 03:57:53.521 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found moov atom of size 25221 at offset 32
Nov 24, 2018 03:57:53.521 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found trak atom of size 24153 at offset 148
Nov 24, 2018 03:57:53.522 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found mdia atom of size 24053 at offset 248
Nov 24, 2018 03:57:53.522 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found minf atom of size 23979 at offset 322
Nov 24, 2018 03:57:53.522 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found stbl atom of size 23919 at offset 382
Nov 24, 2018 03:57:53.522 [0x7f288abab7e0] DEBUG - [MI]  * 304b82496c8fa59ec5ac1b2df774b7fba34f50f4 [mp4 /aac] Duration: 131355ms, 0x0 (0.000000) 0.000fps, bitrate=129kbps, audio channels=2, optimizedForStreaming=1 ("/raid/NAS_Public/Music/Johnny Cash/2006 - Golden Legends/1.04 - Ballad Of A Teenage Queen.m4a")
Nov 24, 2018 03:57:53.524 [0x7f288abab7e0] DEBUG - [MI] Importing 1 media chapters.
Nov 24, 2018 03:57:53.526 [0x7f288abab7e0] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.526 [0x7f288abab7e0] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.531 [0x7f288abab7e0] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.532 [0x7f288abab7e0] DEBUG - Updating metadata item (save) (Ballad Of A Teenage Queen) with ID 50953
Nov 24, 2018 03:57:53.540 [0x7f288abab7e0] DEBUG - [MI] Closing input file: "/raid/NAS_Public/Music/Johnny Cash/2006 - Golden Legends/1.04 - Ballad Of A Teenage Queen.m4a"
Nov 24, 2018 03:57:53.541 [0x7f288abab7e0] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 1 seeks
Nov 24, 2018 03:57:53.546 [0x7f288abab7e0] DEBUG - Updating media item 48184, size=2129874, metadata_item_id=50953
Nov 24, 2018 03:57:53.547 [0x7f288abab7e0] DEBUG - Updating part with ID=48851 [/raid/NAS_Public/Music/Johnny Cash/2006 - Golden Legends/1.04 - Ballad Of A Teenage Queen.m4a]
Nov 24, 2018 03:57:53.570 [0x7f288abab7e0] DEBUG - Analyzing media parts for item 50744 (First Orgasm): 47997
Nov 24, 2018 03:57:53.574 [0x7f288abab7e0] DEBUG - [ID 48647] Media part analysis: /raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.07 - First Orgasm.m4a
Nov 24, 2018 03:57:53.575 [0x7f288abab7e0] DEBUG - [MI] Opening input file: "/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.07 - First Orgasm.m4a"
Nov 24, 2018 03:57:53.575 [0x7f288abab7e0] DEBUG - [FFMPEG] - Opening '/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.07 - First Orgasm.m4a' for reading
Nov 24, 2018 03:57:53.576 [0x7f288abab7e0] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Nov 24, 2018 03:57:53.591 [0x7f288abab7e0] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Nov 24, 2018 03:57:53.591 [0x7f288abab7e0] DEBUG - [FFMPEG] - ISO: File Type Major Brand: M4A 
Nov 24, 2018 03:57:53.591 [0x7f288abab7e0] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Nov 24, 2018 03:57:53.594 [0x7f288abab7e0] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 51240 bytes read:65536 seeks:0 nb_streams:1
Nov 24, 2018 03:57:53.595 [0x7f288abab7e0] DEBUG - [FFMPEG] - demuxer injecting skip 2112 / discard 0
Nov 24, 2018 03:57:53.596 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip 2112 / discard 0 samples due to side data
Nov 24, 2018 03:57:53.596 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip whole frame, skip left: 1088
Nov 24, 2018 03:57:53.596 [0x7f288abab7e0] DEBUG - [FFMPEG] - All info found
Nov 24, 2018 03:57:53.596 [0x7f288abab7e0] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 51247 bytes read:65536 seeks:0 frames:1
Nov 24, 2018 03:57:53.596 [0x7f288abab7e0] DEBUG - [MI] Opening "/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.07 - First Orgasm.m4a" for MP4 atom parsing
Nov 24, 2018 03:57:53.597 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Nov 24, 2018 03:57:53.597 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found moov atom of size 42886 at offset 32
Nov 24, 2018 03:57:53.597 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found trak atom of size 41797 at offset 148
Nov 24, 2018 03:57:53.597 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found mdia atom of size 41697 at offset 248
Nov 24, 2018 03:57:53.597 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found minf atom of size 41623 at offset 322
Nov 24, 2018 03:57:53.597 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found stbl atom of size 41563 at offset 382
Nov 24, 2018 03:57:53.598 [0x7f288abab7e0] DEBUG - [MI]  * 65ac36ef1b8338fc7df349b10600ddf28690ec82 [mp4 /aac] Duration: 229135ms, 0x0 (0.000000) 0.000fps, bitrate=129kbps, audio channels=2, optimizedForStreaming=1 ("/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.07 - First Orgasm.m4a")
Nov 24, 2018 03:57:53.600 [0x7f288abab7e0] DEBUG - [MI] Importing 1 media chapters.
Nov 24, 2018 03:57:53.601 [0x7f288abab7e0] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.602 [0x7f288abab7e0] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.607 [0x7f288abab7e0] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.608 [0x7f288abab7e0] DEBUG - Updating metadata item (save) (First Orgasm) with ID 50744
Nov 24, 2018 03:57:53.616 [0x7f288abab7e0] DEBUG - [MI] Closing input file: "/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.07 - First Orgasm.m4a"
Nov 24, 2018 03:57:53.616 [0x7f288abab7e0] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 0 seeks
Nov 24, 2018 03:57:53.621 [0x7f288abab7e0] DEBUG - Updating media item 47997, size=3718998, metadata_item_id=50744
Nov 24, 2018 03:57:53.622 [0x7f288abab7e0] DEBUG - Updating part with ID=48647 [/raid/NAS_Public/Music/The Dresden Dolls/2006 - Yes, Virginia/1.07 - First Orgasm.m4a]
Nov 24, 2018 03:57:53.636 [0x7f288abab7e0] DEBUG - Analyzing media parts for item 50645 (Bloodletting (the Vampire song)): 47905
Nov 24, 2018 03:57:53.640 [0x7f288abab7e0] DEBUG - [ID 48555] Media part analysis: /raid/NAS_Public/Music/Concrete Blonde/1990 - Bloodletting/1.01 - Bloodletting (the Vampire song).m4a
Nov 24, 2018 03:57:53.694 [0x7f288abab7e0] DEBUG - [MI] Opening input file: "/raid/NAS_Public/Music/Concrete Blonde/1990 - Bloodletting/1.01 - Bloodletting (the Vampire song).m4a"
Nov 24, 2018 03:57:53.694 [0x7f288abab7e0] DEBUG - [FFMPEG] - Opening '/raid/NAS_Public/Music/Concrete Blonde/1990 - Bloodletting/1.01 - Bloodletting (the Vampire song).m4a' for reading
Nov 24, 2018 03:57:53.695 [0x7f288abab7e0] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Nov 24, 2018 03:57:53.706 [0x7f288abab7e0] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Nov 24, 2018 03:57:53.706 [0x7f288abab7e0] DEBUG - [FFMPEG] - ISO: File Type Major Brand: M4A 
Nov 24, 2018 03:57:53.706 [0x7f288abab7e0] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Nov 24, 2018 03:57:53.710 [0x7f288abab7e0] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 69374 bytes read:98304 seeks:0 nb_streams:1
Nov 24, 2018 03:57:53.711 [0x7f288abab7e0] DEBUG - [FFMPEG] - demuxer injecting skip 2112 / discard 0
Nov 24, 2018 03:57:53.712 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip 2112 / discard 0 samples due to side data
Nov 24, 2018 03:57:53.712 [0x7f288abab7e0] DEBUG - [FFMPEG] - skip whole frame, skip left: 1088
Nov 24, 2018 03:57:53.712 [0x7f288abab7e0] DEBUG - [FFMPEG] - All info found
Nov 24, 2018 03:57:53.712 [0x7f288abab7e0] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 69381 bytes read:98304 seeks:0 frames:1
Nov 24, 2018 03:57:53.712 [0x7f288abab7e0] DEBUG - [MI] Opening "/raid/NAS_Public/Music/Concrete Blonde/1990 - Bloodletting/1.01 - Bloodletting (the Vampire song).m4a" for MP4 atom parsing
Nov 24, 2018 03:57:53.712 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Nov 24, 2018 03:57:53.712 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found moov atom of size 67052 at offset 32
Nov 24, 2018 03:57:53.713 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found trak atom of size 65981 at offset 148
Nov 24, 2018 03:57:53.713 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found mdia atom of size 65881 at offset 248
Nov 24, 2018 03:57:53.713 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found minf atom of size 65807 at offset 322
Nov 24, 2018 03:57:53.713 [0x7f288abab7e0] DEBUG - Mp4AtomParser: Found stbl atom of size 65747 at offset 382
Nov 24, 2018 03:57:53.713 [0x7f288abab7e0] DEBUG - [MI]  * 40ff7b58c74a94b394b5fa984a94e6c862d224f1 [mp4 /aac] Duration: 363137ms, 0x0 (0.000000) 0.000fps, bitrate=128kbps, audio channels=2, optimizedForStreaming=1 ("/raid/NAS_Public/Music/Concrete Blonde/1990 - Bloodletting/1.01 - Bloodletting (the Vampire song).m4a")
Nov 24, 2018 03:57:53.716 [0x7f288abab7e0] DEBUG - [MI] Importing 1 media chapters.
Nov 24, 2018 03:57:53.717 [0x7f288abab7e0] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.717 [0x7f288abab7e0] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.723 [0x7f288abab7e0] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 24, 2018 03:57:53.723 [0x7f288abab7e0] DEBUG - Updating metadata item (save) (Bloodletting (the Vampire song)) with ID 50645
Nov 24, 2018 03:57:53.930 [0x7f288abab7e0] DEBUG - [MI] Closing input file: "/raid/NAS_Public/Music/Concrete Blonde/1990 - Bloodletting/1.01 - Bloodletting (the Vampire song).m4a"
Nov 24, 2018 03:57:53.930 [0x7f288abab7e0] DEBUG - [FFMPEG] - Statistics: 98304 bytes read, 0 seeks
Nov 24, 2018 03:57:53.935 [0x7f288abab7e0] DEBUG - Updating media item 47905, size=5853263, metadata_item_id=50645
Nov 24, 2018 03:57:53.937 [0x7f288abab7e0] DEBUG - Updating part with ID=48555 [/raid/NAS_Public/Music/Concrete Blonde/1990 - Bloodletting/1.01 - Bloodletting (the Vampire song).m4a]
