2007-01-30 14:33:50.3168 SlimServer strings init... 2007-01-30 14:33:50.5429 SlimServer Setup init... 2007-01-30 14:33:50.5557 SlimServer setting language... 2007-01-30 14:33:50.5558 SlimServer MySQL init... 2007-01-30 14:33:51.6553 Firmware init... 2007-01-30 14:33:51.6579 SlimServer Info init... 2007-01-30 14:33:52.6757 SlimServer IR init... 2007-01-30 14:33:52.6842 SlimServer Request init... 2007-01-30 14:33:52.6908 SlimServer Buttons init... 2007-01-30 14:33:52.6953 SlimServer Graphic Fonts init... 2007-01-30 14:33:52.7510 UDP init... 2007-01-30 14:33:52.7529 Slimproto Init... 2007-01-30 14:33:52.7538 mDNS init... 2007-01-30 14:33:52.7540 Async Networking init... 2007-01-30 14:33:52.9558 Cache init... 2007-01-30 14:33:53.0317 UPnP init... 2007-01-30 14:33:53.0335 SlimServer HTTP init... 2007-01-30 14:33:53.0444 Source conversion init.. 2007-01-30 14:33:53.0466 SlimServer Plugins init... 2007-01-30 14:33:53.5098 mDNS startAdvertising... 2007-01-30 14:33:53.5679 SlimServer checkDataSource... 2007-01-30 14:33:54.0028 SlimServer persist playlists... 2007-01-30 14:33:54.0033 SlimServer done init... 2007-01-30 14:34:03.0242 Cache purge: FileCache - skipping, purged recently 2007-01-30 14:34:03.1017 Cache purge: Artwork - skipping, purged recently 2007-01-30 14:35:32.4945 00:04:20:06:33:43: Switching to mode stop from stop 2007-01-30 14:35:32.4946 Already in playmode stop : ignoring mode change 2007-01-30 14:35:32.4948 Adding song index 0 to song queue 2007-01-30 14:35:32.4949 Clearing out song queue first 2007-01-30 14:35:32.4949 Song queue is now 0 2007-01-30 14:35:32.4959 scanPathOrURL: Finding valid files in: /Users/qatest/Music/iTunes/ogg 2007-01-30 14:35:32.4963 About to look for files in /Users/qatest/Music/iTunes/ogg 2007-01-30 14:35:32.4982 For files with extensions in: [(?i-xsm:\.(?:ape|wma|asf|wax|asx|lnk|wpl|shn|wav|wave|mp3|mp2|m3u|pls|xspf|flc|flac|m4a|mov|m4b|mp4|cue|mp+|mpc|ogg|aif|aiff)$)] 2007-01-30 14:35:32.5031 Found 10 files in /Users/qatest/Music/iTunes/ogg 2007-01-30 14:35:32.5414 Reshuffling, current song index: -1, preserve song? no 2007-01-30 14:35:32.5418 00:04:20:10:02:0b: Switching to mode stop from stop 2007-01-30 14:35:32.5419 Already in playmode stop : ignoring mode change 2007-01-30 14:35:32.5420 jumping to 0 2007-01-30 14:35:32.5420 Adding song index 0 to song queue 2007-01-30 14:35:32.5421 Clearing out song queue first 2007-01-30 14:35:32.5421 Song queue is now 0 2007-01-30 14:35:32.5423 00:04:20:10:02:0b: Switching to mode play from stop 2007-01-30 14:35:32.5462 openSong on: file:///Users/qatest/Music/iTunes/ogg/01%20Born%20to%20Be%20Wild.ogg 2007-01-30 14:35:32.5470 openSong: duration: [252] size: [3676230] endian [] offset: [0] for file:///Users/qatest/Music/iTunes/ogg/01%20Born%20to%20Be%20Wild.ogg 2007-01-30 14:35:32.5476 undermax = 1, type = ogg, transporter = 00:04:20:10:02:0b 2007-01-30 14:35:32.5478 checking formats for: ogg-wma-transporter-00:04:20:10:02:0b 2007-01-30 14:35:32.5479 checking formats for: ogg-wma-*-00:04:20:10:02:0b 2007-01-30 14:35:32.5479 checking formats for: ogg-wma-transporter-* 2007-01-30 14:35:32.5480 checking formats for: ogg-wma-squeezebox2-* 2007-01-30 14:35:32.5480 checking formats for: ogg-wma-*-* 2007-01-30 14:35:32.5481 checking formats for: ogg-ogg-transporter-00:04:20:10:02:0b 2007-01-30 14:35:32.5481 checking formats for: ogg-ogg-*-00:04:20:10:02:0b 2007-01-30 14:35:32.5482 checking formats for: ogg-ogg-transporter-* 2007-01-30 14:35:32.5482 checking formats for: ogg-ogg-squeezebox2-* 2007-01-30 14:35:32.5483 checking formats for: ogg-ogg-*-* 2007-01-30 14:35:32.5484 Checking to see if ogg-ogg-*-* is enabled 2007-01-30 14:35:32.5485 enabled 2007-01-30 14:35:32.5486 Found command: - 2007-01-30 14:35:32.5487 Matched Format: ogg Type: ogg Command: - 2007-01-30 14:35:32.5488 openSong: this is an ogg file: file:///Users/qatest/Music/iTunes/ogg/01%20Born%20to%20Be%20Wild.ogg 2007-01-30 14:35:32.5489 file type: ogg format: ogg inrate: 112 maxRate: 0 2007-01-30 14:35:32.5489 command: - 2007-01-30 14:35:32.5490 openSong: opening file /Users/qatest/Music/iTunes/ogg/01 Born to Be Wild.ogg 2007-01-30 14:35:32.5492 openSong: seeking in 0 into /Users/qatest/Music/iTunes/ogg/01 Born to Be Wild.ogg 2007-01-30 14:35:32.5494 openSong: Streaming with format: ogg 2007-01-30 14:35:32.5572 00:04:20:10:02:0b New play mode: play 2007-01-30 14:35:32.5581 Transporter::play - setting DigitalInput to 0 for [file:///Users/qatest/Music/iTunes/ogg/01%20Born%20to%20Be%20Wild.ogg] 2007-01-30 14:35:32.5589 00:04:20:06:33:43 New play mode: play 2007-01-30 14:35:32.5597 00:04:20:10:02:0b: Current playmode: play 2007-01-30 14:35:32.5620 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:35:32.5626 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:35:32.5632 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:35:32.5638 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:35:32.5643 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:35:32.5650 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:35:32.5656 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:35:32.5662 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:35:32.5668 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:35:32.5674 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:35:32.5679 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:35:32.6621 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:35:32.6627 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:35:32.7469 modifyPlaylistCallback: savecurrsong is 1 2007-01-30 14:35:32.7472 modifyPlaylistCallback: finding client playlist for: [00:04:20:10:02:0b] 2007-01-30 14:35:32.7543 modifyPlaylistCallback: calling setTracks() 2007-01-30 14:35:32.7791 Playlist: Jumping to song index: 0 2007-01-30 14:35:32.7792 new playlistindex: 0 2007-01-30 14:35:32.7979 modifyPlaylistCallback: finding client playlist for: [00:04:20:06:33:43] 2007-01-30 14:35:32.8039 modifyPlaylistCallback: calling setTracks() 2007-01-30 14:35:32.8154 Playlist: Jumping to song index: 0 2007-01-30 14:35:32.8155 new playlistindex: 0 2007-01-30 14:35:32.8400 modifyPlaylistCallback: savecurrsong is 1 2007-01-30 14:35:32.8479 modifyPlaylistCallback: savecurrsong is 1 2007-01-30 14:35:32.8482 modifyPlaylistCallback: finding client playlist for: [00:04:20:10:02:0b] 2007-01-30 14:35:32.8592 modifyPlaylistCallback: calling setTracks() 2007-01-30 14:35:32.8678 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:35:32.9049 modifyPlaylistCallback: finding client playlist for: [00:04:20:06:33:43] 2007-01-30 14:35:32.9102 modifyPlaylistCallback: calling setTracks() 2007-01-30 14:35:32.9145 00:04:20:06:33:43 checking buffer fullness: 131400 (threshold: 128) 2007-01-30 14:35:32.9146 00:04:20:06:33:43 is ready to sync 1170196532.91466 2007-01-30 14:35:32.9521 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:35:32.9785 00:04:20:10:02:0b checking buffer fullness: 131400 (threshold: 128) 2007-01-30 14:35:32.9786 00:04:20:10:02:0b is ready to sync 1170196532.97866 2007-01-30 14:35:32.9787 all clients ready to sync now. unpausing them. 2007-01-30 14:35:33.0370 End playlist build. 2007-01-30 14:35:33.2059 Caching playlist as html. 2007-01-30 14:35:34.1637 Got a track starting event 2007-01-30 14:35:34.1638 Song 0 has now started playing 2007-01-30 14:35:34.1641 Playlist: Jumping to song index: 0 2007-01-30 14:35:34.1642 new playlistindex: 0 2007-01-30 14:35:34.1643 Playlist: Jumping to song index: 0 2007-01-30 14:35:34.1644 new playlistindex: 0 2007-01-30 14:35:34.1646 Song queue is now 0 2007-01-30 14:35:34.1653 modifyPlaylistCallback: savecurrsong is 0 2007-01-30 14:35:34.2818 Got a track starting event 2007-01-30 14:35:34.2819 Song 0 has now started playing 2007-01-30 14:35:34.2821 Playlist: Jumping to song index: 0 2007-01-30 14:35:34.2822 new playlistindex: 0 2007-01-30 14:35:34.2824 Playlist: Jumping to song index: 0 2007-01-30 14:35:34.2824 new playlistindex: 0 2007-01-30 14:35:34.2826 Song queue is now 0 2007-01-30 14:35:34.2833 modifyPlaylistCallback: savecurrsong is 0 2007-01-30 14:35:57.4342 Reduced chunksize to 6214 at end of file (3676230 - 3670016) 2007-01-30 14:35:57.7960 Reduced chunksize to 0 at end of file (3676230 - 3676230) 2007-01-30 14:35:57.7963 end of file or error on socket, opening next song, (song pos: 3676230(tell says: . 3676230), totalbytes: 3676230) 2007-01-30 14:35:57.7964 opening next song... 2007-01-30 14:35:57.7966 the next song is number 1, was 0 2007-01-30 14:35:57.7970 undermax = 1, type = ogg, transporter = 00:04:20:10:02:0b 2007-01-30 14:35:57.7971 checking formats for: ogg-wma-transporter-00:04:20:10:02:0b 2007-01-30 14:35:57.7972 checking formats for: ogg-wma-*-00:04:20:10:02:0b 2007-01-30 14:35:57.7973 checking formats for: ogg-wma-transporter-* 2007-01-30 14:35:57.7973 checking formats for: ogg-wma-squeezebox2-* 2007-01-30 14:35:57.7974 checking formats for: ogg-wma-*-* 2007-01-30 14:35:57.7974 checking formats for: ogg-ogg-transporter-00:04:20:10:02:0b 2007-01-30 14:35:57.7975 checking formats for: ogg-ogg-*-00:04:20:10:02:0b 2007-01-30 14:35:57.7975 checking formats for: ogg-ogg-transporter-* 2007-01-30 14:35:57.7976 checking formats for: ogg-ogg-squeezebox2-* 2007-01-30 14:35:57.7977 checking formats for: ogg-ogg-*-* 2007-01-30 14:35:57.7977 Checking to see if ogg-ogg-*-* is enabled 2007-01-30 14:35:57.7978 enabled 2007-01-30 14:35:57.7978 Found command: - 2007-01-30 14:35:57.7980 Matched Format: ogg Type: ogg Command: - 2007-01-30 14:35:57.7980 playing out before starting next song. (old format: ogg, new: ogg) 2007-01-30 14:35:57.7981 00:04:20:10:02:0b: Switching to mode playout-play from play 2007-01-30 14:35:57.7983 00:04:20:10:02:0b New play mode: playout-play 2007-01-30 14:35:57.7989 Playlist: Jumping to song index: 0 2007-01-30 14:35:57.7989 new playlistindex: 0 2007-01-30 14:35:57.7991 Playlist: Jumping to song index: 0 2007-01-30 14:35:57.7991 new playlistindex: 0 2007-01-30 14:35:57.7992 00:04:20:06:33:43 New play mode: playout-play 2007-01-30 14:35:57.7997 Playlist: Jumping to song index: 0 2007-01-30 14:35:57.7997 new playlistindex: 0 2007-01-30 14:35:57.7999 Playlist: Jumping to song index: 0 2007-01-30 14:35:57.7999 new playlistindex: 0 2007-01-30 14:35:57.8000 00:04:20:10:02:0b: Current playmode: playout-play 2007-01-30 14:35:57.8001 No pending chunks - we're dropping the streaming connection 2007-01-30 14:35:57.8005 00:04:20:10:02:0b: Can't opennext, returning no chunk. 2007-01-30 14:35:57.9207 Found an empty chunk on the queue - this means we should drop the streaming connection. 2007-01-30 14:36:03.0948 currentPlaylistChangeTime : Tue Jan 30 14:35:34 2007 2007-01-30 14:36:03.0949 currentPlaylistRender : Tue Jan 30 14:35:33 2007 2007-01-30 14:36:03.0949 currentPlaylistRenderSkin : 2007-01-30 14:36:03.0950 currentPlaylistRenderStart: 0 2007-01-30 14:36:03.0951 skinOverride: 2007-01-30 14:36:03.0951 start: 0 2007-01-30 14:36:03.1478 End playlist build. 2007-01-30 14:36:03.1881 Caching playlist as html. 2007-01-30 14:36:28.5247 currentPlaylistChangeTime : Tue Jan 30 14:35:34 2007 2007-01-30 14:36:28.5248 currentPlaylistRender : Tue Jan 30 14:36:03 2007 2007-01-30 14:36:28.5248 currentPlaylistRenderSkin : 2007-01-30 14:36:28.5249 currentPlaylistRenderStart: 0 2007-01-30 14:36:28.5249 skinOverride: 2007-01-30 14:36:28.5250 start: 0 2007-01-30 14:36:28.5251 Returning cached playlist html - not modified. 2007-01-30 14:36:58.7409 currentPlaylistChangeTime : Tue Jan 30 14:35:34 2007 2007-01-30 14:36:58.7410 currentPlaylistRender : Tue Jan 30 14:36:03 2007 2007-01-30 14:36:58.7410 currentPlaylistRenderSkin : 2007-01-30 14:36:58.7411 currentPlaylistRenderStart: 0 2007-01-30 14:36:58.7412 skinOverride: 2007-01-30 14:36:58.7412 start: 0 2007-01-30 14:36:58.7413 Returning cached playlist html - not modified. 2007-01-30 14:37:28.9809 currentPlaylistChangeTime : Tue Jan 30 14:35:34 2007 2007-01-30 14:37:28.9810 currentPlaylistRender : Tue Jan 30 14:36:03 2007 2007-01-30 14:37:28.9811 currentPlaylistRenderSkin : 2007-01-30 14:37:28.9812 currentPlaylistRenderStart: 0 2007-01-30 14:37:28.9812 skinOverride: 2007-01-30 14:37:28.9813 start: 0 2007-01-30 14:37:28.9814 Returning cached playlist html - not modified. 2007-01-30 14:37:59.0876 currentPlaylistChangeTime : Tue Jan 30 14:35:34 2007 2007-01-30 14:37:59.0877 currentPlaylistRender : Tue Jan 30 14:36:03 2007 2007-01-30 14:37:59.0878 currentPlaylistRenderSkin : 2007-01-30 14:37:59.0879 currentPlaylistRenderStart: 0 2007-01-30 14:37:59.0880 skinOverride: 2007-01-30 14:37:59.0880 start: 0 2007-01-30 14:37:59.0881 Returning cached playlist html - not modified. 2007-01-30 14:38:13.7369 Playlist: Jumping to song index: 0 2007-01-30 14:38:13.7370 new playlistindex: 0 2007-01-30 14:38:20.1662 Playlist: Jumping to song index: 0 2007-01-30 14:38:20.1663 new playlistindex: 0 2007-01-30 14:38:20.1743 Playlist: Jumping to song index: 0 2007-01-30 14:38:20.1744 new playlistindex: 0 2007-01-30 14:38:20.2735 Playlist: Jumping to song index: 0 2007-01-30 14:38:20.2736 new playlistindex: 0 2007-01-30 14:38:20.2756 Playlist: Jumping to song index: 0 2007-01-30 14:38:20.2756 new playlistindex: 0 2007-01-30 14:38:20.3813 Playlist: Jumping to song index: 0 2007-01-30 14:38:20.3814 new playlistindex: 0 2007-01-30 14:38:20.3832 Playlist: Jumping to song index: 0 2007-01-30 14:38:20.3833 new playlistindex: 0 2007-01-30 14:38:20.4892 Playlist: Jumping to song index: 0 2007-01-30 14:38:20.4893 new playlistindex: 0 2007-01-30 14:38:20.4913 Playlist: Jumping to song index: 0 2007-01-30 14:38:20.4914 new playlistindex: 0 2007-01-30 14:38:20.5973 Playlist: Jumping to song index: 0 2007-01-30 14:38:20.5973 new playlistindex: 0 2007-01-30 14:38:20.5992 Playlist: Jumping to song index: 0 2007-01-30 14:38:20.5992 new playlistindex: 0 2007-01-30 14:38:20.7053 Playlist: Jumping to song index: 0 2007-01-30 14:38:20.7053 new playlistindex: 0 2007-01-30 14:38:20.7099 Playlist: Jumping to song index: 0 2007-01-30 14:38:20.7100 new playlistindex: 0 2007-01-30 14:38:20.8138 Playlist: Jumping to song index: 0 2007-01-30 14:38:20.8139 new playlistindex: 0 2007-01-30 14:38:20.8157 Playlist: Jumping to song index: 0 2007-01-30 14:38:20.8158 new playlistindex: 0 2007-01-30 14:38:20.9218 Playlist: Jumping to song index: 0 2007-01-30 14:38:20.9220 new playlistindex: 0 2007-01-30 14:38:20.9272 Playlist: Jumping to song index: 0 2007-01-30 14:38:20.9273 new playlistindex: 0 2007-01-30 14:38:29.6207 currentPlaylistChangeTime : Tue Jan 30 14:35:34 2007 2007-01-30 14:38:29.6208 currentPlaylistRender : Tue Jan 30 14:36:03 2007 2007-01-30 14:38:29.6209 currentPlaylistRenderSkin : 2007-01-30 14:38:29.6209 currentPlaylistRenderStart: 0 2007-01-30 14:38:29.6210 skinOverride: 2007-01-30 14:38:29.6210 start: 0 2007-01-30 14:38:29.6211 Returning cached playlist html - not modified. 2007-01-30 14:39:00.1040 currentPlaylistChangeTime : Tue Jan 30 14:35:34 2007 2007-01-30 14:39:00.1042 currentPlaylistRender : Tue Jan 30 14:36:03 2007 2007-01-30 14:39:00.1044 currentPlaylistRenderSkin : 2007-01-30 14:39:00.1044 currentPlaylistRenderStart: 0 2007-01-30 14:39:00.1045 skinOverride: 2007-01-30 14:39:00.1045 start: 0 2007-01-30 14:39:00.1046 Returning cached playlist html - not modified. 2007-01-30 14:39:30.5368 currentPlaylistChangeTime : Tue Jan 30 14:35:34 2007 2007-01-30 14:39:30.5369 currentPlaylistRender : Tue Jan 30 14:36:03 2007 2007-01-30 14:39:30.5369 currentPlaylistRenderSkin : 2007-01-30 14:39:30.5371 currentPlaylistRenderStart: 0 2007-01-30 14:39:30.5371 skinOverride: 2007-01-30 14:39:30.5372 start: 0 2007-01-30 14:39:30.5372 Returning cached playlist html - not modified. 2007-01-30 14:39:40.0925 00:04:20:10:02:0b: Decoder underrun while this mode: playout-play 2007-01-30 14:39:40.0926 the next song is number 1, was 0 2007-01-30 14:39:40.2155 00:04:20:06:33:43: Decoder underrun while this mode: playout-play 2007-01-30 14:39:40.2156 the next song is number 1, was 0 2007-01-30 14:39:47.6554 currentPlaylistChangeTime : Tue Jan 30 14:35:34 2007 2007-01-30 14:39:47.6557 currentPlaylistRender : Tue Jan 30 14:36:03 2007 2007-01-30 14:39:47.6557 currentPlaylistRenderSkin : 2007-01-30 14:39:47.6558 currentPlaylistRenderStart: 0 2007-01-30 14:39:47.6558 skinOverride: 2007-01-30 14:39:47.6559 start: 0 2007-01-30 14:39:47.6560 Returning cached playlist html - not modified. 2007-01-30 14:39:50.0773 00:04:20:10:02:0b: Underrun while this mode: playout-play 2007-01-30 14:39:50.0798 00:04:20:10:02:0b has run out of data, checking to see if we can push on... 2007-01-30 14:39:50.0803 00:04:20:10:02:0b has run out of data, checking to see if we can push on... 2007-01-30 14:39:50.2054 00:04:20:06:33:43: Underrun while this mode: playout-play 2007-01-30 14:39:50.2071 00:04:20:06:33:43 has run out of data, checking to see if we can push on... 2007-01-30 14:39:50.2072 everybody's run out of data. Let's start them up... 2007-01-30 14:39:50.2072 **skipahead: stopping 2007-01-30 14:39:50.2073 00:04:20:06:33:43: Switching to mode stop from playout-play 2007-01-30 14:39:50.2076 00:04:20:06:33:43 New play mode: stop 2007-01-30 14:39:50.2077 Stopping and clearing out old chunks for client 00:04:20:06:33:43 2007-01-30 14:39:50.2082 Resetting song queue 2007-01-30 14:39:50.2083 Song queue is now 0 2007-01-30 14:39:50.2086 newSongPlaylistCallback() writeCurTrackForM3U() 2007-01-30 14:39:50.2089 Playlist: Jumping to song index: 0 2007-01-30 14:39:50.2089 new playlistindex: 0 2007-01-30 14:39:50.2091 Playlist: Jumping to song index: 0 2007-01-30 14:39:50.2092 new playlistindex: 0 2007-01-30 14:39:50.2092 00:04:20:10:02:0b New play mode: stop 2007-01-30 14:39:50.2093 Stopping and clearing out old chunks for client 00:04:20:10:02:0b 2007-01-30 14:39:50.2098 Resetting song queue 2007-01-30 14:39:50.2098 Song queue is now 0 2007-01-30 14:39:50.2100 newSongPlaylistCallback() writeCurTrackForM3U() 2007-01-30 14:39:50.2102 Playlist: Jumping to song index: 0 2007-01-30 14:39:50.2103 new playlistindex: 0 2007-01-30 14:39:50.2104 Playlist: Jumping to song index: 0 2007-01-30 14:39:50.2105 new playlistindex: 0 2007-01-30 14:39:50.2106 00:04:20:06:33:43: Current playmode: stop 2007-01-30 14:39:50.2107 **skipahead: opening next song 2007-01-30 14:39:50.2107 opening next song... 2007-01-30 14:39:50.2109 the next song is number 1, was 0 2007-01-30 14:39:50.2113 undermax = 1, type = ogg, squeezebox2 = 00:04:20:06:33:43 2007-01-30 14:39:50.2114 checking formats for: ogg-wma-squeezebox2-00:04:20:06:33:43 2007-01-30 14:39:50.2115 checking formats for: ogg-wma-*-00:04:20:06:33:43 2007-01-30 14:39:50.2115 checking formats for: ogg-wma-squeezebox2-* 2007-01-30 14:39:50.2116 checking formats for: ogg-wma-*-* 2007-01-30 14:39:50.2117 checking formats for: ogg-ogg-squeezebox2-00:04:20:06:33:43 2007-01-30 14:39:50.2117 checking formats for: ogg-ogg-*-00:04:20:06:33:43 2007-01-30 14:39:50.2118 checking formats for: ogg-ogg-squeezebox2-* 2007-01-30 14:39:50.2119 checking formats for: ogg-ogg-*-* 2007-01-30 14:39:50.2119 Checking to see if ogg-ogg-*-* is enabled 2007-01-30 14:39:50.2120 enabled 2007-01-30 14:39:50.2120 Found command: - 2007-01-30 14:39:50.2122 Matched Format: ogg Type: ogg Command: - 2007-01-30 14:39:50.2123 opening next song (old format: , new: ogg) current playmode: stop 2007-01-30 14:39:50.2123 Adding song index 1 to song queue 2007-01-30 14:39:50.2124 Clearing out song queue first 2007-01-30 14:39:50.2125 Song queue is now 1 2007-01-30 14:39:50.2125 **skipahead: restarting 2007-01-30 14:39:50.2126 00:04:20:06:33:43: Switching to mode play from stop 2007-01-30 14:39:50.2164 openSong on: file:///Users/qatest/Music/iTunes/ogg/02%20Cat%20Scratch%20Fever.ogg 2007-01-30 14:39:50.2171 openSong: duration: [182] size: [2595744] endian [] offset: [0] for file:///Users/qatest/Music/iTunes/ogg/02%20Cat%20Scratch%20Fever.ogg 2007-01-30 14:39:50.2176 undermax = 1, type = ogg, transporter = 00:04:20:10:02:0b 2007-01-30 14:39:50.2178 checking formats for: ogg-wma-transporter-00:04:20:10:02:0b 2007-01-30 14:39:50.2178 checking formats for: ogg-wma-*-00:04:20:10:02:0b 2007-01-30 14:39:50.2181 checking formats for: ogg-wma-transporter-* 2007-01-30 14:39:50.2182 checking formats for: ogg-wma-squeezebox2-* 2007-01-30 14:39:50.2182 checking formats for: ogg-wma-*-* 2007-01-30 14:39:50.2183 checking formats for: ogg-ogg-transporter-00:04:20:10:02:0b 2007-01-30 14:39:50.2184 checking formats for: ogg-ogg-*-00:04:20:10:02:0b 2007-01-30 14:39:50.2184 checking formats for: ogg-ogg-transporter-* 2007-01-30 14:39:50.2185 checking formats for: ogg-ogg-squeezebox2-* 2007-01-30 14:39:50.2185 checking formats for: ogg-ogg-*-* 2007-01-30 14:39:50.2186 Checking to see if ogg-ogg-*-* is enabled 2007-01-30 14:39:50.2187 enabled 2007-01-30 14:39:50.2187 Found command: - 2007-01-30 14:39:50.2189 Matched Format: ogg Type: ogg Command: - 2007-01-30 14:39:50.2189 openSong: this is an ogg file: file:///Users/qatest/Music/iTunes/ogg/02%20Cat%20Scratch%20Fever.ogg 2007-01-30 14:39:50.2190 file type: ogg format: ogg inrate: 112 maxRate: 0 2007-01-30 14:39:50.2191 command: - 2007-01-30 14:39:50.2192 openSong: opening file /Users/qatest/Music/iTunes/ogg/02 Cat Scratch Fever.ogg 2007-01-30 14:39:50.2193 openSong: seeking in 0 into /Users/qatest/Music/iTunes/ogg/02 Cat Scratch Fever.ogg 2007-01-30 14:39:50.2194 openSong: Streaming with format: ogg 2007-01-30 14:39:50.2228 00:04:20:06:33:43 New play mode: play 2007-01-30 14:39:50.2238 Playlist: Jumping to song index: 1 2007-01-30 14:39:50.2239 new playlistindex: 1 2007-01-30 14:39:50.2241 Playlist: Jumping to song index: 1 2007-01-30 14:39:50.2241 new playlistindex: 1 2007-01-30 14:39:50.2242 00:04:20:10:02:0b New play mode: play 2007-01-30 14:39:50.2248 Transporter::play - setting DigitalInput to 0 for [file:///Users/qatest/Music/iTunes/ogg/02%20Cat%20Scratch%20Fever.ogg] 2007-01-30 14:39:50.2254 Playlist: Jumping to song index: 1 2007-01-30 14:39:50.2255 new playlistindex: 1 2007-01-30 14:39:50.2256 Playlist: Jumping to song index: 1 2007-01-30 14:39:50.2257 new playlistindex: 1 2007-01-30 14:39:50.2258 00:04:20:06:33:43: Current playmode: play 2007-01-30 14:39:50.2269 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2275 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2281 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2287 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2293 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2299 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2305 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2310 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2317 modifyPlaylistCallback: savecurrsong is 1 2007-01-30 14:39:50.2329 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2335 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2341 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2346 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2352 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2358 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2364 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2370 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2376 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2663 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2672 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:39:50.2904 00:04:20:06:33:43 checking buffer fullness: 131400 (threshold: 128) 2007-01-30 14:39:50.2905 00:04:20:06:33:43 is ready to sync 1170196790.29053 2007-01-30 14:39:50.2914 00:04:20:10:02:0b checking buffer fullness: 131400 (threshold: 128) 2007-01-30 14:39:50.2915 00:04:20:10:02:0b is ready to sync 1170196790.29152 2007-01-30 14:39:50.2917 all clients ready to sync now. unpausing them. 2007-01-30 14:39:50.8113 Reduced chunksize to 7072 at end of file (2595744 - 2588672) 2007-01-30 14:39:50.8126 Reduced chunksize to 0 at end of file (2595744 - 2595744) 2007-01-30 14:39:50.8128 end of file or error on socket, opening next song, (song pos: 2595744(tell says: . 2595744), totalbytes: 2595744) 2007-01-30 14:39:50.8130 opening next song... 2007-01-30 14:39:50.8133 the next song is number 2, was 1 2007-01-30 14:39:50.8137 undermax = 1, type = ogg, transporter = 00:04:20:10:02:0b 2007-01-30 14:39:50.8138 checking formats for: ogg-wma-transporter-00:04:20:10:02:0b 2007-01-30 14:39:50.8139 checking formats for: ogg-wma-*-00:04:20:10:02:0b 2007-01-30 14:39:50.8140 checking formats for: ogg-wma-transporter-* 2007-01-30 14:39:50.8140 checking formats for: ogg-wma-squeezebox2-* 2007-01-30 14:39:50.8141 checking formats for: ogg-wma-*-* 2007-01-30 14:39:50.8142 checking formats for: ogg-ogg-transporter-00:04:20:10:02:0b 2007-01-30 14:39:50.8142 checking formats for: ogg-ogg-*-00:04:20:10:02:0b 2007-01-30 14:39:50.8143 checking formats for: ogg-ogg-transporter-* 2007-01-30 14:39:50.8144 checking formats for: ogg-ogg-squeezebox2-* 2007-01-30 14:39:50.8144 checking formats for: ogg-ogg-*-* 2007-01-30 14:39:50.8145 Checking to see if ogg-ogg-*-* is enabled 2007-01-30 14:39:50.8146 enabled 2007-01-30 14:39:50.8146 Found command: - 2007-01-30 14:39:50.8147 Matched Format: ogg Type: ogg Command: - 2007-01-30 14:39:50.8148 playing out before starting next song. (old format: ogg, new: ogg) 2007-01-30 14:39:50.8149 00:04:20:10:02:0b: Switching to mode playout-play from play 2007-01-30 14:39:50.8151 00:04:20:10:02:0b New play mode: playout-play 2007-01-30 14:39:50.8157 Playlist: Jumping to song index: 1 2007-01-30 14:39:50.8157 new playlistindex: 1 2007-01-30 14:39:50.8159 Playlist: Jumping to song index: 1 2007-01-30 14:39:50.8160 new playlistindex: 1 2007-01-30 14:39:50.8161 00:04:20:06:33:43 New play mode: playout-play 2007-01-30 14:39:50.8165 Playlist: Jumping to song index: 1 2007-01-30 14:39:50.8166 new playlistindex: 1 2007-01-30 14:39:50.8167 Playlist: Jumping to song index: 1 2007-01-30 14:39:50.8168 new playlistindex: 1 2007-01-30 14:39:50.8169 00:04:20:10:02:0b: Current playmode: playout-play 2007-01-30 14:39:50.8170 No pending chunks - we're dropping the streaming connection 2007-01-30 14:39:50.8173 00:04:20:10:02:0b: Can't opennext, returning no chunk. 2007-01-30 14:39:50.8666 Found an empty chunk on the queue - this means we should drop the streaming connection. 2007-01-30 14:39:51.4784 Got a track starting event 2007-01-30 14:39:51.4785 Song 1 has now started playing 2007-01-30 14:39:51.4787 Playlist: Jumping to song index: 1 2007-01-30 14:39:51.4788 new playlistindex: 1 2007-01-30 14:39:51.4789 Playlist: Jumping to song index: 1 2007-01-30 14:39:51.4790 new playlistindex: 1 2007-01-30 14:39:51.4792 Song queue is now 1 2007-01-30 14:39:51.4800 modifyPlaylistCallback: savecurrsong is 0 2007-01-30 14:39:51.6124 Got a track starting event 2007-01-30 14:39:51.6125 Song 1 has now started playing 2007-01-30 14:39:51.6127 Playlist: Jumping to song index: 1 2007-01-30 14:39:51.6127 new playlistindex: 1 2007-01-30 14:39:51.6129 Playlist: Jumping to song index: 1 2007-01-30 14:39:51.6130 new playlistindex: 1 2007-01-30 14:39:51.6132 Song queue is now 1 2007-01-30 14:39:51.6139 modifyPlaylistCallback: savecurrsong is 0 2007-01-30 14:39:52.7786 currentPlaylistChangeTime : Tue Jan 30 14:39:51 2007 2007-01-30 14:39:52.7788 currentPlaylistRender : Tue Jan 30 14:36:03 2007 2007-01-30 14:39:52.7789 currentPlaylistRenderSkin : 2007-01-30 14:39:52.7789 currentPlaylistRenderStart: 0 2007-01-30 14:39:52.7790 skinOverride: 2007-01-30 14:39:52.7790 start: 0 2007-01-30 14:39:52.8497 End playlist build. 2007-01-30 14:39:52.8893 Caching playlist as html. 2007-01-30 14:40:22.8871 currentPlaylistChangeTime : Tue Jan 30 14:39:51 2007 2007-01-30 14:40:22.8872 currentPlaylistRender : Tue Jan 30 14:39:52 2007 2007-01-30 14:40:22.8873 currentPlaylistRenderSkin : 2007-01-30 14:40:22.8874 currentPlaylistRenderStart: 0 2007-01-30 14:40:22.8875 skinOverride: 2007-01-30 14:40:22.8875 start: 0 2007-01-30 14:40:22.8876 Returning cached playlist html - not modified. 2007-01-30 14:40:53.0100 currentPlaylistChangeTime : Tue Jan 30 14:39:51 2007 2007-01-30 14:40:53.0101 currentPlaylistRender : Tue Jan 30 14:39:52 2007 2007-01-30 14:40:53.0102 currentPlaylistRenderSkin : 2007-01-30 14:40:53.0102 currentPlaylistRenderStart: 0 2007-01-30 14:40:53.0103 skinOverride: 2007-01-30 14:40:53.0104 start: 0 2007-01-30 14:40:53.0104 Returning cached playlist html - not modified. 2007-01-30 14:41:23.5118 currentPlaylistChangeTime : Tue Jan 30 14:39:51 2007 2007-01-30 14:41:23.5119 currentPlaylistRender : Tue Jan 30 14:39:52 2007 2007-01-30 14:41:23.5120 currentPlaylistRenderSkin : 2007-01-30 14:41:23.5120 currentPlaylistRenderStart: 0 2007-01-30 14:41:23.5121 skinOverride: 2007-01-30 14:41:23.5122 start: 0 2007-01-30 14:41:23.5122 Returning cached playlist html - not modified. 2007-01-30 14:41:54.0606 currentPlaylistChangeTime : Tue Jan 30 14:39:51 2007 2007-01-30 14:41:54.0607 currentPlaylistRender : Tue Jan 30 14:39:52 2007 2007-01-30 14:41:54.0608 currentPlaylistRenderSkin : 2007-01-30 14:41:54.0608 currentPlaylistRenderStart: 0 2007-01-30 14:41:54.0609 skinOverride: 2007-01-30 14:41:54.0609 start: 0 2007-01-30 14:41:54.0610 Returning cached playlist html - not modified. 2007-01-30 14:42:24.5434 currentPlaylistChangeTime : Tue Jan 30 14:39:51 2007 2007-01-30 14:42:24.5435 currentPlaylistRender : Tue Jan 30 14:39:52 2007 2007-01-30 14:42:24.5436 currentPlaylistRenderSkin : 2007-01-30 14:42:24.5437 currentPlaylistRenderStart: 0 2007-01-30 14:42:24.5437 skinOverride: 2007-01-30 14:42:24.5438 start: 0 2007-01-30 14:42:24.5439 Returning cached playlist html - not modified. 2007-01-30 14:42:47.2560 00:04:20:10:02:0b: Decoder underrun while this mode: playout-play 2007-01-30 14:42:47.2562 the next song is number 2, was 1 2007-01-30 14:42:47.3931 00:04:20:06:33:43: Decoder underrun while this mode: playout-play 2007-01-30 14:42:47.3933 the next song is number 2, was 1 2007-01-30 14:42:55.0265 currentPlaylistChangeTime : Tue Jan 30 14:39:51 2007 2007-01-30 14:42:55.0266 currentPlaylistRender : Tue Jan 30 14:39:52 2007 2007-01-30 14:42:55.0267 currentPlaylistRenderSkin : 2007-01-30 14:42:55.0268 currentPlaylistRenderStart: 0 2007-01-30 14:42:55.0268 skinOverride: 2007-01-30 14:42:55.0269 start: 0 2007-01-30 14:42:55.0270 Returning cached playlist html - not modified. 2007-01-30 14:42:57.2510 00:04:20:10:02:0b: Underrun while this mode: playout-play 2007-01-30 14:42:57.2534 00:04:20:10:02:0b has run out of data, checking to see if we can push on... 2007-01-30 14:42:57.2539 00:04:20:10:02:0b has run out of data, checking to see if we can push on... 2007-01-30 14:42:57.3828 00:04:20:06:33:43: Underrun while this mode: playout-play 2007-01-30 14:42:57.3845 00:04:20:06:33:43 has run out of data, checking to see if we can push on... 2007-01-30 14:42:57.3846 everybody's run out of data. Let's start them up... 2007-01-30 14:42:57.3847 **skipahead: stopping 2007-01-30 14:42:57.3848 00:04:20:06:33:43: Switching to mode stop from playout-play 2007-01-30 14:42:57.3850 00:04:20:06:33:43 New play mode: stop 2007-01-30 14:42:57.3851 Stopping and clearing out old chunks for client 00:04:20:06:33:43 2007-01-30 14:42:57.3856 Resetting song queue 2007-01-30 14:42:57.3856 Song queue is now 1 2007-01-30 14:42:57.3859 newSongPlaylistCallback() writeCurTrackForM3U() 2007-01-30 14:42:57.3862 Playlist: Jumping to song index: 1 2007-01-30 14:42:57.3863 new playlistindex: 1 2007-01-30 14:42:57.3864 Playlist: Jumping to song index: 1 2007-01-30 14:42:57.3865 new playlistindex: 1 2007-01-30 14:42:57.3866 00:04:20:10:02:0b New play mode: stop 2007-01-30 14:42:57.3867 Stopping and clearing out old chunks for client 00:04:20:10:02:0b 2007-01-30 14:42:57.3871 Resetting song queue 2007-01-30 14:42:57.3872 Song queue is now 1 2007-01-30 14:42:57.3874 newSongPlaylistCallback() writeCurTrackForM3U() 2007-01-30 14:42:57.3877 Playlist: Jumping to song index: 1 2007-01-30 14:42:57.3878 new playlistindex: 1 2007-01-30 14:42:57.3879 Playlist: Jumping to song index: 1 2007-01-30 14:42:57.3880 new playlistindex: 1 2007-01-30 14:42:57.3881 00:04:20:06:33:43: Current playmode: stop 2007-01-30 14:42:57.3882 **skipahead: opening next song 2007-01-30 14:42:57.3882 opening next song... 2007-01-30 14:42:57.3884 the next song is number 2, was 1 2007-01-30 14:42:57.3889 undermax = 1, type = ogg, squeezebox2 = 00:04:20:06:33:43 2007-01-30 14:42:57.3890 checking formats for: ogg-wma-squeezebox2-00:04:20:06:33:43 2007-01-30 14:42:57.3891 checking formats for: ogg-wma-*-00:04:20:06:33:43 2007-01-30 14:42:57.3892 checking formats for: ogg-wma-squeezebox2-* 2007-01-30 14:42:57.3892 checking formats for: ogg-wma-*-* 2007-01-30 14:42:57.3893 checking formats for: ogg-ogg-squeezebox2-00:04:20:06:33:43 2007-01-30 14:42:57.3894 checking formats for: ogg-ogg-*-00:04:20:06:33:43 2007-01-30 14:42:57.3894 checking formats for: ogg-ogg-squeezebox2-* 2007-01-30 14:42:57.3895 checking formats for: ogg-ogg-*-* 2007-01-30 14:42:57.3896 Checking to see if ogg-ogg-*-* is enabled 2007-01-30 14:42:57.3896 enabled 2007-01-30 14:42:57.3897 Found command: - 2007-01-30 14:42:57.3898 Matched Format: ogg Type: ogg Command: - 2007-01-30 14:42:57.3899 opening next song (old format: , new: ogg) current playmode: stop 2007-01-30 14:42:57.3900 Adding song index 2 to song queue 2007-01-30 14:42:57.3901 Clearing out song queue first 2007-01-30 14:42:57.3902 Song queue is now 2 2007-01-30 14:42:57.3902 **skipahead: restarting 2007-01-30 14:42:57.3903 00:04:20:06:33:43: Switching to mode play from stop 2007-01-30 14:42:57.3940 openSong on: file:///Users/qatest/Music/iTunes/ogg/03%20Sweet%20Home%20Alabama.ogg 2007-01-30 14:42:57.3948 openSong: duration: [269] size: [3946018] endian [] offset: [0] for file:///Users/qatest/Music/iTunes/ogg/03%20Sweet%20Home%20Alabama.ogg 2007-01-30 14:42:57.3953 undermax = 1, type = ogg, transporter = 00:04:20:10:02:0b 2007-01-30 14:42:57.3954 checking formats for: ogg-wma-transporter-00:04:20:10:02:0b 2007-01-30 14:42:57.3955 checking formats for: ogg-wma-*-00:04:20:10:02:0b 2007-01-30 14:42:57.3955 checking formats for: ogg-wma-transporter-* 2007-01-30 14:42:57.3956 checking formats for: ogg-wma-squeezebox2-* 2007-01-30 14:42:57.3957 checking formats for: ogg-wma-*-* 2007-01-30 14:42:57.3958 checking formats for: ogg-ogg-transporter-00:04:20:10:02:0b 2007-01-30 14:42:57.3958 checking formats for: ogg-ogg-*-00:04:20:10:02:0b 2007-01-30 14:42:57.3959 checking formats for: ogg-ogg-transporter-* 2007-01-30 14:42:57.3960 checking formats for: ogg-ogg-squeezebox2-* 2007-01-30 14:42:57.3960 checking formats for: ogg-ogg-*-* 2007-01-30 14:42:57.3961 Checking to see if ogg-ogg-*-* is enabled 2007-01-30 14:42:57.3962 enabled 2007-01-30 14:42:57.3962 Found command: - 2007-01-30 14:42:57.3964 Matched Format: ogg Type: ogg Command: - 2007-01-30 14:42:57.3964 openSong: this is an ogg file: file:///Users/qatest/Music/iTunes/ogg/03%20Sweet%20Home%20Alabama.ogg 2007-01-30 14:42:57.3965 file type: ogg format: ogg inrate: 112 maxRate: 0 2007-01-30 14:42:57.3966 command: - 2007-01-30 14:42:57.3967 openSong: opening file /Users/qatest/Music/iTunes/ogg/03 Sweet Home Alabama.ogg 2007-01-30 14:42:57.3968 openSong: seeking in 0 into /Users/qatest/Music/iTunes/ogg/03 Sweet Home Alabama.ogg 2007-01-30 14:42:57.3969 openSong: Streaming with format: ogg 2007-01-30 14:42:57.4003 00:04:20:06:33:43 New play mode: play 2007-01-30 14:42:57.4013 Playlist: Jumping to song index: 2 2007-01-30 14:42:57.4014 new playlistindex: 2 2007-01-30 14:42:57.4016 Playlist: Jumping to song index: 2 2007-01-30 14:42:57.4017 new playlistindex: 2 2007-01-30 14:42:57.4018 00:04:20:10:02:0b New play mode: play 2007-01-30 14:42:57.4023 Transporter::play - setting DigitalInput to 0 for [file:///Users/qatest/Music/iTunes/ogg/03%20Sweet%20Home%20Alabama.ogg] 2007-01-30 14:42:57.4029 Playlist: Jumping to song index: 2 2007-01-30 14:42:57.4030 new playlistindex: 2 2007-01-30 14:42:57.4033 Playlist: Jumping to song index: 2 2007-01-30 14:42:57.4034 new playlistindex: 2 2007-01-30 14:42:57.4035 00:04:20:06:33:43: Current playmode: play 2007-01-30 14:42:57.4046 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4052 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4058 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4064 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4070 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4076 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4082 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4088 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4094 modifyPlaylistCallback: savecurrsong is 1 2007-01-30 14:42:57.4106 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4112 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4118 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4124 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4130 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4136 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4142 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4148 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4154 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4256 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4280 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:42:57.4529 00:04:20:06:33:43 checking buffer fullness: 131400 (threshold: 128) 2007-01-30 14:42:57.4531 00:04:20:06:33:43 is ready to sync 1170196977.4531 2007-01-30 14:42:57.4547 00:04:20:10:02:0b checking buffer fullness: 131400 (threshold: 128) 2007-01-30 14:42:57.4548 00:04:20:10:02:0b is ready to sync 1170196977.4548 2007-01-30 14:42:57.4548 all clients ready to sync now. unpausing them. 2007-01-30 14:42:58.6841 Got a track starting event 2007-01-30 14:42:58.6842 Song 2 has now started playing 2007-01-30 14:42:58.6844 Playlist: Jumping to song index: 2 2007-01-30 14:42:58.6845 new playlistindex: 2 2007-01-30 14:42:58.6846 Playlist: Jumping to song index: 2 2007-01-30 14:42:58.6847 new playlistindex: 2 2007-01-30 14:42:58.6850 Song queue is now 2 2007-01-30 14:42:58.6856 modifyPlaylistCallback: savecurrsong is 0 2007-01-30 14:42:58.6939 Got a track starting event 2007-01-30 14:42:58.6941 Song 2 has now started playing 2007-01-30 14:42:58.6943 Playlist: Jumping to song index: 2 2007-01-30 14:42:58.6943 new playlistindex: 2 2007-01-30 14:42:58.6945 Playlist: Jumping to song index: 2 2007-01-30 14:42:58.6946 new playlistindex: 2 2007-01-30 14:42:58.6948 Song queue is now 2 2007-01-30 14:42:58.6955 modifyPlaylistCallback: savecurrsong is 0 2007-01-30 14:42:58.8342 Got a track starting event 2007-01-30 14:42:58.8344 Song 2 has now started playing 2007-01-30 14:42:58.8346 Playlist: Jumping to song index: 2 2007-01-30 14:42:58.8347 new playlistindex: 2 2007-01-30 14:42:58.8349 Playlist: Jumping to song index: 2 2007-01-30 14:42:58.8349 new playlistindex: 2 2007-01-30 14:42:58.8352 Song queue is now 2 2007-01-30 14:42:58.8361 modifyPlaylistCallback: savecurrsong is 0 2007-01-30 14:43:00.1317 currentPlaylistChangeTime : Tue Jan 30 14:42:58 2007 2007-01-30 14:43:00.1319 currentPlaylistRender : Tue Jan 30 14:39:52 2007 2007-01-30 14:43:00.1319 currentPlaylistRenderSkin : 2007-01-30 14:43:00.1320 currentPlaylistRenderStart: 0 2007-01-30 14:43:00.1321 skinOverride: 2007-01-30 14:43:00.1322 start: 0 2007-01-30 14:43:00.1843 End playlist build. 2007-01-30 14:43:00.2242 Caching playlist as html. 2007-01-30 14:43:30.2502 currentPlaylistChangeTime : Tue Jan 30 14:42:58 2007 2007-01-30 14:43:30.2504 currentPlaylistRender : Tue Jan 30 14:43:00 2007 2007-01-30 14:43:30.2505 currentPlaylistRenderSkin : 2007-01-30 14:43:30.2505 currentPlaylistRenderStart: 0 2007-01-30 14:43:30.2506 skinOverride: 2007-01-30 14:43:30.2507 start: 0 2007-01-30 14:43:30.2508 Returning cached playlist html - not modified. 2007-01-30 14:43:36.3276 Reduced chunksize to 13858 at end of file (3946018 - 3932160) 2007-01-30 14:43:37.2527 Reduced chunksize to 0 at end of file (3946018 - 3946018) 2007-01-30 14:43:37.2529 end of file or error on socket, opening next song, (song pos: 3946018(tell says: . 3946018), totalbytes: 3946018) 2007-01-30 14:43:37.2530 opening next song... 2007-01-30 14:43:37.2532 the next song is number 3, was 2 2007-01-30 14:43:37.2537 undermax = 1, type = ogg, transporter = 00:04:20:10:02:0b 2007-01-30 14:43:37.2539 checking formats for: ogg-wma-transporter-00:04:20:10:02:0b 2007-01-30 14:43:37.2539 checking formats for: ogg-wma-*-00:04:20:10:02:0b 2007-01-30 14:43:37.2540 checking formats for: ogg-wma-transporter-* 2007-01-30 14:43:37.2541 checking formats for: ogg-wma-squeezebox2-* 2007-01-30 14:43:37.2541 checking formats for: ogg-wma-*-* 2007-01-30 14:43:37.2542 checking formats for: ogg-ogg-transporter-00:04:20:10:02:0b 2007-01-30 14:43:37.2543 checking formats for: ogg-ogg-*-00:04:20:10:02:0b 2007-01-30 14:43:37.2544 checking formats for: ogg-ogg-transporter-* 2007-01-30 14:43:37.2544 checking formats for: ogg-ogg-squeezebox2-* 2007-01-30 14:43:37.2545 checking formats for: ogg-ogg-*-* 2007-01-30 14:43:37.2546 Checking to see if ogg-ogg-*-* is enabled 2007-01-30 14:43:37.2546 enabled 2007-01-30 14:43:37.2547 Found command: - 2007-01-30 14:43:37.2549 Matched Format: ogg Type: ogg Command: - 2007-01-30 14:43:37.2549 playing out before starting next song. (old format: ogg, new: ogg) 2007-01-30 14:43:37.2550 00:04:20:10:02:0b: Switching to mode playout-play from play 2007-01-30 14:43:37.2552 00:04:20:10:02:0b New play mode: playout-play 2007-01-30 14:43:37.2558 Playlist: Jumping to song index: 2 2007-01-30 14:43:37.2559 new playlistindex: 2 2007-01-30 14:43:37.2561 Playlist: Jumping to song index: 2 2007-01-30 14:43:37.2562 new playlistindex: 2 2007-01-30 14:43:37.2563 00:04:20:06:33:43 New play mode: playout-play 2007-01-30 14:43:37.2567 Playlist: Jumping to song index: 2 2007-01-30 14:43:37.2568 new playlistindex: 2 2007-01-30 14:43:37.2570 Playlist: Jumping to song index: 2 2007-01-30 14:43:37.2570 new playlistindex: 2 2007-01-30 14:43:37.2571 00:04:20:10:02:0b: Current playmode: playout-play 2007-01-30 14:43:37.2572 No pending chunks - we're dropping the streaming connection 2007-01-30 14:43:37.2576 00:04:20:10:02:0b: Can't opennext, returning no chunk. 2007-01-30 14:43:37.3893 Found an empty chunk on the queue - this means we should drop the streaming connection. 2007-01-30 14:44:04.3825 currentPlaylistChangeTime : Tue Jan 30 14:42:58 2007 2007-01-30 14:44:04.3826 currentPlaylistRender : Tue Jan 30 14:43:00 2007 2007-01-30 14:44:04.3827 currentPlaylistRenderSkin : 2007-01-30 14:44:04.3827 currentPlaylistRenderStart: 0 2007-01-30 14:44:04.3828 skinOverride: 2007-01-30 14:44:04.3829 start: 0 2007-01-30 14:44:04.3829 Returning cached playlist html - not modified. 2007-01-30 14:44:34.9134 currentPlaylistChangeTime : Tue Jan 30 14:42:58 2007 2007-01-30 14:44:34.9136 currentPlaylistRender : Tue Jan 30 14:43:00 2007 2007-01-30 14:44:34.9137 currentPlaylistRenderSkin : 2007-01-30 14:44:34.9137 currentPlaylistRenderStart: 0 2007-01-30 14:44:34.9139 skinOverride: 2007-01-30 14:44:34.9139 start: 0 2007-01-30 14:44:34.9140 Returning cached playlist html - not modified. 2007-01-30 14:45:05.4329 currentPlaylistChangeTime : Tue Jan 30 14:42:58 2007 2007-01-30 14:45:05.4330 currentPlaylistRender : Tue Jan 30 14:43:00 2007 2007-01-30 14:45:05.4331 currentPlaylistRenderSkin : 2007-01-30 14:45:05.4332 currentPlaylistRenderStart: 0 2007-01-30 14:45:05.4332 skinOverride: 2007-01-30 14:45:05.4333 start: 0 2007-01-30 14:45:05.4334 Returning cached playlist html - not modified. 2007-01-30 14:45:35.8494 currentPlaylistChangeTime : Tue Jan 30 14:42:58 2007 2007-01-30 14:45:35.8495 currentPlaylistRender : Tue Jan 30 14:43:00 2007 2007-01-30 14:45:35.8496 currentPlaylistRenderSkin : 2007-01-30 14:45:35.8497 currentPlaylistRenderStart: 0 2007-01-30 14:45:35.8498 skinOverride: 2007-01-30 14:45:35.8498 start: 0 2007-01-30 14:45:35.8499 Returning cached playlist html - not modified. 2007-01-30 14:46:06.3695 currentPlaylistChangeTime : Tue Jan 30 14:42:58 2007 2007-01-30 14:46:06.3696 currentPlaylistRender : Tue Jan 30 14:43:00 2007 2007-01-30 14:46:06.3697 currentPlaylistRenderSkin : 2007-01-30 14:46:06.3698 currentPlaylistRenderStart: 0 2007-01-30 14:46:06.3698 skinOverride: 2007-01-30 14:46:06.3699 start: 0 2007-01-30 14:46:06.3700 Returning cached playlist html - not modified. 2007-01-30 14:46:36.9025 currentPlaylistChangeTime : Tue Jan 30 14:42:58 2007 2007-01-30 14:46:36.9026 currentPlaylistRender : Tue Jan 30 14:43:00 2007 2007-01-30 14:46:36.9027 currentPlaylistRenderSkin : 2007-01-30 14:46:36.9028 currentPlaylistRenderStart: 0 2007-01-30 14:46:36.9028 skinOverride: 2007-01-30 14:46:36.9029 start: 0 2007-01-30 14:46:36.9030 Returning cached playlist html - not modified. 2007-01-30 14:47:07.5368 currentPlaylistChangeTime : Tue Jan 30 14:42:58 2007 2007-01-30 14:47:07.5369 currentPlaylistRender : Tue Jan 30 14:43:00 2007 2007-01-30 14:47:07.5370 currentPlaylistRenderSkin : 2007-01-30 14:47:07.5371 currentPlaylistRenderStart: 0 2007-01-30 14:47:07.5371 skinOverride: 2007-01-30 14:47:07.5372 start: 0 2007-01-30 14:47:07.5373 Returning cached playlist html - not modified. 2007-01-30 14:47:18.3388 00:04:20:10:02:0b: Decoder underrun while this mode: playout-play 2007-01-30 14:47:18.3390 the next song is number 3, was 2 2007-01-30 14:47:18.4732 00:04:20:06:33:43: Decoder underrun while this mode: playout-play 2007-01-30 14:47:18.4733 the next song is number 3, was 2 2007-01-30 14:47:28.3236 00:04:20:10:02:0b: Underrun while this mode: playout-play 2007-01-30 14:47:28.3272 00:04:20:10:02:0b has run out of data, checking to see if we can push on... 2007-01-30 14:47:28.3277 00:04:20:10:02:0b has run out of data, checking to see if we can push on... 2007-01-30 14:47:28.4630 00:04:20:06:33:43: Underrun while this mode: playout-play 2007-01-30 14:47:28.4647 00:04:20:06:33:43 has run out of data, checking to see if we can push on... 2007-01-30 14:47:28.4648 everybody's run out of data. Let's start them up... 2007-01-30 14:47:28.4649 **skipahead: stopping 2007-01-30 14:47:28.4650 00:04:20:06:33:43: Switching to mode stop from playout-play 2007-01-30 14:47:28.4652 00:04:20:06:33:43 New play mode: stop 2007-01-30 14:47:28.4653 Stopping and clearing out old chunks for client 00:04:20:06:33:43 2007-01-30 14:47:28.4658 Resetting song queue 2007-01-30 14:47:28.4658 Song queue is now 2 2007-01-30 14:47:28.4661 newSongPlaylistCallback() writeCurTrackForM3U() 2007-01-30 14:47:28.4663 Playlist: Jumping to song index: 2 2007-01-30 14:47:28.4664 new playlistindex: 2 2007-01-30 14:47:28.4666 Playlist: Jumping to song index: 2 2007-01-30 14:47:28.4666 new playlistindex: 2 2007-01-30 14:47:28.4667 00:04:20:10:02:0b New play mode: stop 2007-01-30 14:47:28.4668 Stopping and clearing out old chunks for client 00:04:20:10:02:0b 2007-01-30 14:47:28.4673 Resetting song queue 2007-01-30 14:47:28.4674 Song queue is now 2 2007-01-30 14:47:28.4675 newSongPlaylistCallback() writeCurTrackForM3U() 2007-01-30 14:47:28.4678 Playlist: Jumping to song index: 2 2007-01-30 14:47:28.4681 new playlistindex: 2 2007-01-30 14:47:28.4683 Playlist: Jumping to song index: 2 2007-01-30 14:47:28.4683 new playlistindex: 2 2007-01-30 14:47:28.4684 00:04:20:06:33:43: Current playmode: stop 2007-01-30 14:47:28.4685 **skipahead: opening next song 2007-01-30 14:47:28.4686 opening next song... 2007-01-30 14:47:28.4687 the next song is number 3, was 2 2007-01-30 14:47:28.4692 undermax = 1, type = ogg, squeezebox2 = 00:04:20:06:33:43 2007-01-30 14:47:28.4693 checking formats for: ogg-wma-squeezebox2-00:04:20:06:33:43 2007-01-30 14:47:28.4695 checking formats for: ogg-wma-*-00:04:20:06:33:43 2007-01-30 14:47:28.4695 checking formats for: ogg-wma-squeezebox2-* 2007-01-30 14:47:28.4696 checking formats for: ogg-wma-*-* 2007-01-30 14:47:28.4697 checking formats for: ogg-ogg-squeezebox2-00:04:20:06:33:43 2007-01-30 14:47:28.4697 checking formats for: ogg-ogg-*-00:04:20:06:33:43 2007-01-30 14:47:28.4698 checking formats for: ogg-ogg-squeezebox2-* 2007-01-30 14:47:28.4699 checking formats for: ogg-ogg-*-* 2007-01-30 14:47:28.4699 Checking to see if ogg-ogg-*-* is enabled 2007-01-30 14:47:28.4700 enabled 2007-01-30 14:47:28.4701 Found command: - 2007-01-30 14:47:28.4702 Matched Format: ogg Type: ogg Command: - 2007-01-30 14:47:28.4703 opening next song (old format: , new: ogg) current playmode: stop 2007-01-30 14:47:28.4704 Adding song index 3 to song queue 2007-01-30 14:47:28.4705 Clearing out song queue first 2007-01-30 14:47:28.4705 Song queue is now 3 2007-01-30 14:47:28.4706 **skipahead: restarting 2007-01-30 14:47:28.4707 00:04:20:06:33:43: Switching to mode play from stop 2007-01-30 14:47:28.4745 openSong on: file:///Users/qatest/Music/iTunes/ogg/04%20More%20Than%20a%20Feeling.ogg 2007-01-30 14:47:28.4752 openSong: duration: [245] size: [3644723] endian [] offset: [0] for file:///Users/qatest/Music/iTunes/ogg/04%20More%20Than%20a%20Feeling.ogg 2007-01-30 14:47:28.4757 undermax = 1, type = ogg, transporter = 00:04:20:10:02:0b 2007-01-30 14:47:28.4759 checking formats for: ogg-wma-transporter-00:04:20:10:02:0b 2007-01-30 14:47:28.4759 checking formats for: ogg-wma-*-00:04:20:10:02:0b 2007-01-30 14:47:28.4760 checking formats for: ogg-wma-transporter-* 2007-01-30 14:47:28.4761 checking formats for: ogg-wma-squeezebox2-* 2007-01-30 14:47:28.4761 checking formats for: ogg-wma-*-* 2007-01-30 14:47:28.4762 checking formats for: ogg-ogg-transporter-00:04:20:10:02:0b 2007-01-30 14:47:28.4763 checking formats for: ogg-ogg-*-00:04:20:10:02:0b 2007-01-30 14:47:28.4764 checking formats for: ogg-ogg-transporter-* 2007-01-30 14:47:28.4764 checking formats for: ogg-ogg-squeezebox2-* 2007-01-30 14:47:28.4765 checking formats for: ogg-ogg-*-* 2007-01-30 14:47:28.4766 Checking to see if ogg-ogg-*-* is enabled 2007-01-30 14:47:28.4766 enabled 2007-01-30 14:47:28.4767 Found command: - 2007-01-30 14:47:28.4768 Matched Format: ogg Type: ogg Command: - 2007-01-30 14:47:28.4769 openSong: this is an ogg file: file:///Users/qatest/Music/iTunes/ogg/04%20More%20Than%20a%20Feeling.ogg 2007-01-30 14:47:28.4770 file type: ogg format: ogg inrate: 112 maxRate: 0 2007-01-30 14:47:28.4771 command: - 2007-01-30 14:47:28.4772 openSong: opening file /Users/qatest/Music/iTunes/ogg/04 More Than a Feeling.ogg 2007-01-30 14:47:28.4773 openSong: seeking in 0 into /Users/qatest/Music/iTunes/ogg/04 More Than a Feeling.ogg 2007-01-30 14:47:28.4774 openSong: Streaming with format: ogg 2007-01-30 14:47:28.4934 00:04:20:06:33:43 New play mode: play 2007-01-30 14:47:28.4944 Playlist: Jumping to song index: 3 2007-01-30 14:47:28.4945 new playlistindex: 3 2007-01-30 14:47:28.4946 Playlist: Jumping to song index: 3 2007-01-30 14:47:28.4947 new playlistindex: 3 2007-01-30 14:47:28.4948 00:04:20:10:02:0b New play mode: play 2007-01-30 14:47:28.4954 Transporter::play - setting DigitalInput to 0 for [file:///Users/qatest/Music/iTunes/ogg/04%20More%20Than%20a%20Feeling.ogg] 2007-01-30 14:47:28.4960 Playlist: Jumping to song index: 3 2007-01-30 14:47:28.4961 new playlistindex: 3 2007-01-30 14:47:28.4963 Playlist: Jumping to song index: 3 2007-01-30 14:47:28.4964 new playlistindex: 3 2007-01-30 14:47:28.4966 00:04:20:06:33:43: Current playmode: play 2007-01-30 14:47:28.4977 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.4983 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.4989 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.4995 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.5001 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.5008 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.5014 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.5020 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.5028 modifyPlaylistCallback: savecurrsong is 1 2007-01-30 14:47:28.5041 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.5047 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.5053 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.5059 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.5065 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.5071 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.5077 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.5083 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.5089 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.5302 00:04:20:10:02:0b checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.5312 00:04:20:06:33:43 checking buffer fullness: 0 (threshold: 128) 2007-01-30 14:47:28.5546 00:04:20:10:02:0b checking buffer fullness: 131400 (threshold: 128) 2007-01-30 14:47:28.5547 00:04:20:10:02:0b is ready to sync 1170197248.55477 2007-01-30 14:47:28.5556 00:04:20:06:33:43 checking buffer fullness: 131400 (threshold: 128) 2007-01-30 14:47:28.5556 00:04:20:06:33:43 is ready to sync 1170197248.55568 2007-01-30 14:47:28.5557 all clients ready to sync now. unpausing them. 2007-01-30 14:47:29.6871 currentPlaylistChangeTime : Tue Jan 30 14:47:28 2007 2007-01-30 14:47:29.6872 currentPlaylistRender : Tue Jan 30 14:43:00 2007 2007-01-30 14:47:29.6873 currentPlaylistRenderSkin : 2007-01-30 14:47:29.6874 currentPlaylistRenderStart: 0 2007-01-30 14:47:29.6874 skinOverride: 2007-01-30 14:47:29.6875 start: 0 2007-01-30 14:47:29.7461 End playlist build. 2007-01-30 14:47:29.7873 Caching playlist as html. 2007-01-30 14:47:29.7912 Got a track starting event 2007-01-30 14:47:29.7913 Song 3 has now started playing 2007-01-30 14:47:29.7915 Playlist: Jumping to song index: 3 2007-01-30 14:47:29.7916 new playlistindex: 3 2007-01-30 14:47:29.7918 Playlist: Jumping to song index: 3 2007-01-30 14:47:29.7918 new playlistindex: 3 2007-01-30 14:47:29.7921 Song queue is now 3 2007-01-30 14:47:29.7935 modifyPlaylistCallback: savecurrsong is 0 2007-01-30 14:47:29.9254 Got a track starting event 2007-01-30 14:47:29.9256 Song 3 has now started playing 2007-01-30 14:47:29.9258 Playlist: Jumping to song index: 3 2007-01-30 14:47:29.9259 new playlistindex: 3 2007-01-30 14:47:29.9261 Playlist: Jumping to song index: 3 2007-01-30 14:47:29.9261 new playlistindex: 3 2007-01-30 14:47:29.9264 Song queue is now 3 2007-01-30 14:47:29.9271 modifyPlaylistCallback: savecurrsong is 0