060809 20:28:46 InnoDB: Started; log sequence number 0 15717565 060809 20:28:46 [Note] /usr/local/slimserver/Bin/i386-linux/mysqld: ready for connections. Version: '5.0.21-standard' socket: '/home/mjpine/Cache/slimserver-mysql.sock' port: 9092 MySQL Community Edition - Standard (GPL) 2006-08-09 20:28:48.9128 Squeezebox protocol listening on port 3483 2006-08-09 20:28:49.0760 loading conversion config files... 2006-08-09 20:28:49.1026 input: 'mov' output: 'mp3' clienttype: '*': clientid: '*': '[mov123] $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ -b $BITRATE$ -r - -' 2006-08-09 20:28:49.1030 input: 'ogg' output: 'mp3' clienttype: '*': clientid: '*': '[sox] -t ogg $FILE$ -t raw -r 44100 -c 2 -w -s $-x$ - | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ -r - -' 2006-08-09 20:28:49.1032 input: 'wav' output: 'mp3' clienttype: '*': clientid: '*': '[lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ $FILE$ -' 2006-08-09 20:28:49.1035 input: 'aif' output: 'mp3' clienttype: '*': clientid: '*': '[lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ $FILE$ -' 2006-08-09 20:28:49.1037 input: 'shn' output: 'mp3' clienttype: '*': clientid: '*': '[shorten] -x $FILE$ - | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -' 2006-08-09 20:28:49.1040 input: 'flc' output: 'mp3' clienttype: '*': clientid: '*': '[flac] -dcs --skip=$START$ --until=$END$ -- $FILE$ | [lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -' 2006-08-09 20:28:49.1042 input: 'wma' output: 'mp3' clienttype: '*': clientid: '*': '[wmadec] -r 44100 -b 16 -n 2 $FILE$ | [lame] -x --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -' 2006-08-09 20:28:49.1045 input: 'mpc' output: 'mp3' clienttype: '*': clientid: '*': '[mppdec] --raw-le --silent --prev --gain 3 $FILE$ - | [lame] -x --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ - -' 2006-08-09 20:28:49.1048 input: 'ape' output: 'mp3' clienttype: '*': clientid: '*': '[mac] $FILE$ - -d | [lame] --resample 44100 -x --silent -q 9 --abr $BITRATE$ - -' 2006-08-09 20:28:49.1050 input: 'mp3' output: 'mp3' clienttype: '*': clientid: '*': '-' 2006-08-09 20:28:49.1052 input: 'aif' output: 'aif' clienttype: '*': clientid: '*': '-' 2006-08-09 20:28:49.1054 input: 'wav' output: 'wav' clienttype: '*': clientid: '*': '-' 2006-08-09 20:28:49.1057 input: 'shn' output: 'wav' clienttype: '*': clientid: '*': '[shorten] -x $FILE$ -' 2006-08-09 20:28:49.1059 input: 'flc' output: 'wav' clienttype: '*': clientid: '*': '[flac] -dcs --force-raw-format --endian=little --sign=signed --skip=$START$ --until=$END$ -- $FILE$' 2006-08-09 20:28:49.1062 input: 'ogg' output: 'ogg' clienttype: '*': clientid: '*': '-' 2006-08-09 20:28:49.1064 input: 'ogg' output: 'aif' clienttype: '*': clientid: '*': '[sox] -t ogg $FILE$ -t raw -r 44100 -c 2 -w -s $-x$ -' 2006-08-09 20:28:49.1066 input: 'wma' output: 'wav' clienttype: '*': clientid: '*': '[wmadec] -r 44100 -b 16 -n 2 $FILE$' 2006-08-09 20:28:49.1069 input: 'mpc' output: 'wav' clienttype: '*': clientid: '*': '[mppdec] --raw-le --silent --prev --gain 2 $FILE$ -' 2006-08-09 20:28:49.1071 input: 'ape' output: 'wav' clienttype: '*': clientid: '*': '[mac] $FILE$ - -d' 2006-08-09 20:28:49.1074 input: 'mpc' output: 'aif' clienttype: '*': clientid: '*': '[mppdec] --raw-be --silent --prev --gain 2 $FILE$ -' 2006-08-09 20:28:49.1076 input: 'mov' output: 'aif' clienttype: '*': clientid: '*': '[mov123] $FILE$' 2006-08-09 20:28:49.1078 input: 'wma' output: 'wma' clienttype: 'squeezebox2': clientid: '*': '-' 2006-08-09 20:28:49.1080 input: 'flc' output: 'flc' clienttype: '*': clientid: '*': '-' 2006-08-09 20:28:49.1083 input: 'aif' output: 'flc' clienttype: '*': clientid: '*': '[flac] -cs --totally-silent --compression-level-0 --skip=$START$ --until=$END$ -- $FILE$' 2006-08-09 20:28:49.1086 input: 'wav' output: 'flc' clienttype: '*': clientid: '*': '[flac] -cs --totally-silent --compression-level-0 --skip=$START$ --until=$END$ -- $FILE$' 2006-08-09 20:28:49.1088 input: 'shn' output: 'flc' clienttype: '*': clientid: '*': '[shorten] -x $FILE$ - | [flac] -cs --totally-silent --compression-level-0 --endian little --sign signed --channels 2 --bps 16 --sample-rate 44100 -' 2006-08-09 20:28:49.1092 input: 'ogg' output: 'flc' clienttype: '*': clientid: '*': '[sox] -t ogg $FILE$ -t raw -r 44100 -c 2 -w -s $-x$ - | [flac] -cs --compression-level-0 --totally-silent --endian big --channel 2 --bps 16 --sample-rate 44100 --sign signed -' 2006-08-09 20:28:49.1095 input: 'wma' output: 'flc' clienttype: '*': clientid: '*': '[wmadec] -r 44100 -b 16 -n 2 $FILE$ | [flac] -cs --totally-silent --compression-level-0 --endian little --sign signed --channels 2 --bps 16 --sample-rate 44100 -' 2006-08-09 20:28:49.1098 input: 'mpc' output: 'flc' clienttype: '*': clientid: '*': '[mppdec] --silent --prev --gain 2 $FILE$ - | [flac] -cs --totally-silent --compression-level-0 -' 2006-08-09 20:28:49.1103 input: 'ape' output: 'flc' clienttype: '*': clientid: '*': '[mac] $FILE$ - -d | [flac] -cs --totally-silent --compression-level-0 -' 2006-08-09 20:28:49.1106 input: 'mov' output: 'flc' clienttype: '*': clientid: '*': '[mov123] $FILE$ | [flac] -cs --totally-silent --compression-level-0 --endian big --sign signed --channels 2 --bps 16 --sample-rate 44100 -' 2006-08-09 20:28:49.1109 input: 'mp3' output: 'mp3' clienttype: 'transcode': clientid: '*': '[lame] --resample 44100 --silent -q $QUALITY$ --abr $BITRATE$ $FILE$ -' 2006-08-09 20:28:49.1111 input: 'flc' output: 'flc' clienttype: 'transcode': clientid: '*': '[flac] -dcs --skip=$START$ --until=$END$ -- $FILE$ | [flac] -cs --totally-silent --compression-level-0 -' 2006-08-09 20:28:49.1866 input: 'mov' output: 'mp3' clienttype: '*': clientid: '*': '[mplayer] -novideo -nortc -vc dummy -vo null -cache 128 -af volume=0,resample=44100:0:1,channels=2 -ao pcm:file=/dev/fd/4 $FILE$ 4>&1 1>&2 1>/dev/null | [lame] --silent -q $QUALITY$ -b $BITRATE$ -' 2006-08-09 20:28:49.2022 input: 'rtsp' output: 'wav' clienttype: '*': clientid: '*': '[mplayer.sh] -really-quiet -vc null -vo null -bandwidth 10000000 -cache 128 -af volume=0,resample=44100:0:1,channels=2 -ao pcm:nowaveheader:file=/dev/fd/3 $FILE$' 2006-08-09 20:28:49.2025 input: 'rtsp' output: 'mp3' clienttype: '*': clientid: '*': '[mplayer.sh] -really-quiet -vc null -vo null -bandwidth 10000000 -cache 128 -af volume=0,resample=44100:0:1,channels=2 -ao pcm:nowaveheader:file=/dev/fd/3 $FILE$ | [lame] --silent -r -x -q $QUALITY$ -b $BITRATE$ - -' 2006-08-09 20:28:49.2028 input: 'rtsp' output: 'flc' clienttype: '*': clientid: '*': '[mplayer.sh] -really-quiet -vc null -vo null -bandwidth 10000000 -cache 128 -af volume=0,resample=44100:0:1,channels=2 -ao pcm:nowaveheader:file=/dev/fd/3 $FILE$ | [flac] -cs --totally-silent --endian=little --channels=2 --sign=signed --bps=16 --sample-rate=44100 --compression-level-0 -' 2006-08-09 20:28:49.2031 input: 'wma' output: 'wav' clienttype: '*': clientid: '*': '[mplayer.sh] -really-quiet -vc null -vo null -cache 128 -af volume=0,resample=44100:0:1,channels=2 -ao pcm:nowaveheader:file=/dev/fd/3 $FILE$' 2006-08-09 20:28:49.2034 input: 'wma' output: 'mp3' clienttype: '*': clientid: '*': '[mplayer.sh] -really-quiet -vc null -vo null -cache 128 -af volume=0,resample=44100:0:1,channels=2 -ao pcm:nowaveheader:file=/dev/fd/3 $FILE$ | [lame] --silent -r -x -q $QUALITY$ -b $BITRATE$ - -' 2006-08-09 20:28:49.2037 input: 'wma' output: 'flc' clienttype: '*': clientid: '*': '[mplayer.sh] -really-quiet -vc null -vo null -cache 128 -af volume=0,resample=44100:0:1,channels=2 -ao pcm:nowaveheader:file=/dev/fd/3 $FILE$ | [flac] -cs --totally-silent --endian=little --channels=2 --sign=signed --bps=16 --sample-rate=44100 --compression-level-0 -' 2006-08-09 20:28:53.0363 Slimproto accepted connection from: [192.168.2.4:48025] 2006-08-09 20:28:53.0533 Setting timer in 5 seconds to close bogus connection 2006-08-09 20:28:53.0544 _hello_handler: Killing bogus player timer. 2006-08-09 20:28:53.0548 Squeezebox says hello. Deviceid: 4 revision: 59 mac: 00:04:20:06:7f:7f bitmapped: 32768 reconnect: 0 wlan_channellist: 0000 2006-08-09 20:28:53.0550 Squeezebox also says. bytes_received: 3309818 2006-08-09 20:28:53.0553 creating new client, id:00:04:20:06:7f:7f ipport: 192.168.2.4:48025 2006-08-09 20:28:53.3960 Adding song index 0 to song queue 2006-08-09 20:28:53.3962 Clearing out song queue first 2006-08-09 20:28:53.3965 Song queue is now 0 2006-08-09 20:28:53.4004 *************stream called: q paused: format: url: 2006-08-09 20:28:53.4008 Backtrace: frame 0: Slim::Player::Squeezebox::stream (/usr/local/slimserver/Slim/Player/Squeezebox.pm line 202) frame 1: Slim::Player::Squeezebox::stop (/usr/local/slimserver/Slim/Player/Squeezebox2.pm line 203) frame 2: Slim::Player::Squeezebox2::stop (/usr/local/slimserver/Slim/Player/Squeezebox.pm line 87) frame 3: Slim::Player::Squeezebox::reconnect (/usr/local/slimserver/Slim/Networking/Slimproto.pm line 812) frame 4: Slim::Networking::Slimproto::_hello_handler (/usr/local/slimserver/Slim/Networking/Slimproto.pm line 360) frame 5: Slim::Networking::Slimproto::client_readable (/usr/local/slimserver/Slim/Networking/Select.pm line 170) frame 6: Slim::Networking::Select::select (/usr/local/slimserver/slimserver.pl line 493) frame 7: main::idle (/usr/local/slimserver/slimserver.pl line 446) frame 8: main::main (/usr/local/slimserver/slimserver.pl line 1043) 2006-08-09 20:28:53.4011 starting with decoder with format: m autostart: 0 threshold: 255 samplesize: ? samplerate: ? endian: ? channels: ? 2006-08-09 20:28:53.4013 flags: 0 2006-08-09 20:28:53.4015 sending strm frame of length: 24 request string: 2006-08-09 20:28:53.4082 00:04:20:06:7f:7f Squeezebox stream status: event_code: bytes_rec_H 0 bytes_rec_L 3309818 fullness: 2173423 (69%) bufferSize 3145728 fullness 2173423 bytes_received 3309818 jiffies: 355109804 2006-08-09 20:28:53.4085 output size: 3528000 output fullness: 3521408 elapsed seconds: 60 2006-08-09 20:28:53.4094 00:04:20:06:7f:7f Squeezebox stream status: event_code: STMf bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 jiffies: 355110170 2006-08-09 20:28:53.4097 output size: 3528000 output fullness: 3521408 elapsed seconds: 60 2006-08-09 20:28:53.4105 00:04:20:06:7f:7f Squeezebox stream status: event_code: strm bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 jiffies: 355110171 2006-08-09 20:28:53.4107 output size: 3528000 output fullness: 3521408 elapsed seconds: 0 2006-08-09 20:28:53.4115 00:04:20:06:7f:7f Squeezebox stream status: event_code: aude bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 jiffies: 355110176 2006-08-09 20:28:53.4118 output size: 3528000 output fullness: 3521408 elapsed seconds: 0 2006-08-09 20:28:53.4125 00:04:20:06:7f:7f Squeezebox stream status: event_code: audg bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 jiffies: 355110177 2006-08-09 20:28:53.4128 output size: 3528000 output fullness: 3521408 elapsed seconds: 0 2006-08-09 20:28:58.0061 00:04:20:06:7f:7f Squeezebox stream status: event_code: stat bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 jiffies: 355114771 2006-08-09 20:28:58.0064 output size: 3528000 output fullness: 3521408 elapsed seconds: 0 2006-08-09 20:29:01.6908 00:04:20:06:7f:7f: Switching to mode stop from stop 2006-08-09 20:29:01.6911 Already in playmode stop : ignoring mode change 2006-08-09 20:29:01.6914 jumping to 0 2006-08-09 20:29:01.6916 Adding song index 0 to song queue 2006-08-09 20:29:01.6918 Clearing out song queue first 2006-08-09 20:29:01.6920 Song queue is now 0 2006-08-09 20:29:01.6937 00:04:20:06:7f:7f Squeezebox stream status: event_code: aude bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 jiffies: 355118448 2006-08-09 20:29:01.6940 output size: 3528000 output fullness: 3521408 elapsed seconds: 0 2006-08-09 20:29:08.0054 00:04:20:06:7f:7f Squeezebox stream status: event_code: stat bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 jiffies: 355124770 2006-08-09 20:29:08.0057 output size: 3528000 output fullness: 3521408 elapsed seconds: 0 2006-08-09 20:29:13.0058 00:04:20:06:7f:7f Squeezebox stream status: event_code: stat bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 jiffies: 355129769 2006-08-09 20:29:13.0061 output size: 3528000 output fullness: 3521408 elapsed seconds: 0 2006-08-09 20:29:18.0497 00:04:20:06:7f:7f Squeezebox stream status: event_code: stat bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 jiffies: 355134812 2006-08-09 20:29:18.0500 output size: 3528000 output fullness: 3521408 elapsed seconds: 0 2006-08-09 20:29:23.0024 00:04:20:06:7f:7f Squeezebox stream status: event_code: stat bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 jiffies: 355139764 2006-08-09 20:29:23.0027 output size: 3528000 output fullness: 3521408 elapsed seconds: 0 2006-08-09 20:29:26.8629 Adding song index 0 to song queue 2006-08-09 20:29:26.8632 Clearing out song queue first 2006-08-09 20:29:26.8634 Song queue is now 0 2006-08-09 20:29:26.8638 00:04:20:06:7f:7f: Switching to mode stop from stop 2006-08-09 20:29:26.8640 Already in playmode stop : ignoring mode change 2006-08-09 20:29:26.9816 00:04:20:06:7f:7f: Switching to mode stop from stop 2006-08-09 20:29:26.9818 Already in playmode stop : ignoring mode change 2006-08-09 20:29:26.9820 jumping to 0 2006-08-09 20:29:26.9822 Adding song index 0 to song queue 2006-08-09 20:29:26.9824 Clearing out song queue first 2006-08-09 20:29:26.9826 Song queue is now 0 2006-08-09 20:29:26.9830 00:04:20:06:7f:7f: Switching to mode play from stop 2006-08-09 20:29:26.9899 openSong on: file:///home/slim%20music/Bananarama/Greatest%20Hits%20Collection/02%20I%20Heard%20A%20Rumour.m4a 2006-08-09 20:29:26.9914 openSong: duration: [207] size: [3293112] endian [] offset: [0] for file:///home/slim%20music/Bananarama/Greatest%20Hits%20Collection/02%20I%20Heard%20A%20Rumour.m4a 2006-08-09 20:29:26.9918 Setting maxBitRate for squeezhome to: 0 2006-08-09 20:29:26.9922 Setting maxBitRate for squeezhome to: 0 2006-08-09 20:29:26.9931 Setting maxBitRate for squeezhome to: 0 2006-08-09 20:29:26.9934 Setting maxBitRate for squeezhome to: 0 2006-08-09 20:29:26.9936 undermax = 1, type = mov, squeezebox2 = 00:04:20:06:7f:7f 2006-08-09 20:29:26.9939 checking formats for: mov-wma-squeezebox2-00:04:20:06:7f:7f 2006-08-09 20:29:26.9941 checking formats for: mov-wma-*-00:04:20:06:7f:7f 2006-08-09 20:29:26.9942 checking formats for: mov-wma-squeezebox2-* 2006-08-09 20:29:26.9944 checking formats for: mov-wma-*-* 2006-08-09 20:29:26.9946 checking formats for: mov-ogg-squeezebox2-00:04:20:06:7f:7f 2006-08-09 20:29:26.9947 checking formats for: mov-ogg-*-00:04:20:06:7f:7f 2006-08-09 20:29:26.9949 checking formats for: mov-ogg-squeezebox2-* 2006-08-09 20:29:26.9951 checking formats for: mov-ogg-*-* 2006-08-09 20:29:26.9952 checking formats for: mov-flc-squeezebox2-00:04:20:06:7f:7f 2006-08-09 20:29:26.9954 checking formats for: mov-flc-*-00:04:20:06:7f:7f 2006-08-09 20:29:26.9956 checking formats for: mov-flc-squeezebox2-* 2006-08-09 20:29:26.9957 checking formats for: mov-flc-*-* 2006-08-09 20:29:26.9959 Checking to see if mov-flc-*-* is enabled 2006-08-09 20:29:26.9961 There are 1 disabled formats... 2006-08-09 20:29:26.9966 Testing rtsp-wav-*-* vs mov-flc-*-* 2006-08-09 20:29:26.9968 Testing rtsp-flc-*-* vs mov-flc-*-* 2006-08-09 20:29:26.9970 enabled 2006-08-09 20:29:26.9971 Found command: [mov123] $FILE$ | [flac] -cs --totally-silent --compression-level-0 --endian big --sign signed --channels 2 --bps 16 --sample-rate 44100 - 2006-08-09 20:29:27.1897 drat, missing binary mov123 2006-08-09 20:29:27.1900 checking formats for: mov-aif-squeezebox2-00:04:20:06:7f:7f 2006-08-09 20:29:27.1902 checking formats for: mov-aif-*-00:04:20:06:7f:7f 2006-08-09 20:29:27.1904 checking formats for: mov-aif-squeezebox2-* 2006-08-09 20:29:27.1905 checking formats for: mov-aif-*-* 2006-08-09 20:29:27.1907 Checking to see if mov-aif-*-* is enabled 2006-08-09 20:29:27.1909 There are 1 disabled formats... 2006-08-09 20:29:27.1911 Testing rtsp-wav-*-* vs mov-aif-*-* 2006-08-09 20:29:27.1913 Testing rtsp-flc-*-* vs mov-aif-*-* 2006-08-09 20:29:27.1914 enabled 2006-08-09 20:29:27.1916 Found command: [mov123] $FILE$ 2006-08-09 20:29:27.1927 drat, missing binary mov123 2006-08-09 20:29:27.1930 checking formats for: mov-wav-squeezebox2-00:04:20:06:7f:7f 2006-08-09 20:29:27.1931 checking formats for: mov-wav-*-00:04:20:06:7f:7f 2006-08-09 20:29:27.1933 checking formats for: mov-wav-squeezebox2-* 2006-08-09 20:29:27.1935 checking formats for: mov-wav-*-* 2006-08-09 20:29:27.1937 checking formats for: mov-mp3-squeezebox2-00:04:20:06:7f:7f 2006-08-09 20:29:27.1939 checking formats for: mov-mp3-*-00:04:20:06:7f:7f 2006-08-09 20:29:27.1940 checking formats for: mov-mp3-squeezebox2-* 2006-08-09 20:29:27.1942 checking formats for: mov-mp3-*-* 2006-08-09 20:29:27.1944 Checking to see if mov-mp3-*-* is enabled 2006-08-09 20:29:27.1946 There are 1 disabled formats... 2006-08-09 20:29:27.1947 Testing rtsp-wav-*-* vs mov-mp3-*-* 2006-08-09 20:29:27.1949 Testing rtsp-flc-*-* vs mov-mp3-*-* 2006-08-09 20:29:27.1951 enabled 2006-08-09 20:29:27.1953 Found command: [mplayer] -novideo -nortc -vc dummy -vo null -cache 128 -af volume=0,resample=44100:0:1,channels=2 -ao pcm:file=/dev/fd/4 $FILE$ 4>&1 1>&2 1>/dev/null | [lame] --silent -q $QUALITY$ -b $BITRATE$ - 2006-08-09 20:29:27.1972 Setting maxBitRate for squeezhome to: 0 2006-08-09 20:29:27.1976 Setting maxBitRate for squeezhome to: 0 2006-08-09 20:29:27.1978 Matched Format: mp3 Type: mov Command: [mplayer] -novideo -nortc -vc dummy -vo null -cache 128 -af volume=0,resample=44100:0:1,channels=2 -ao pcm:file=/dev/fd/4 $FILE$ 4>&1 1>&2 1>/dev/null | [lame] --silent -q $QUALITY$ -b $BITRATE$ - 2006-08-09 20:29:27.1980 openSong: this is an mov file: file:///home/slim%20music/Bananarama/Greatest%20Hits%20Collection/02%20I%20Heard%20A%20Rumour.m4a 2006-08-09 20:29:27.1982 file type: mov format: mp3 inrate: 124 maxRate: 0 2006-08-09 20:29:27.1984 command: [mplayer] -novideo -nortc -vc dummy -vo null -cache 128 -af volume=0,resample=44100:0:1,channels=2 -ao pcm:file=/dev/fd/4 $FILE$ 4>&1 1>&2 1>/dev/null | [lame] --silent -q $QUALITY$ -b $BITRATE$ - 2006-08-09 20:29:27.1996 Using command for conversion: "/usr/local/bin/mplayer" -novideo -nortc -vc dummy -vo null -cache 128 -af volume=0,resample=44100:0:1,channels=2 -ao pcm:file=/dev/fd/4 "/home/slim music/Bananarama/Greatest Hits Collection/02 I Heard A Rumour.m4a" 4>&1 1>&2 1>/dev/null | "/usr/bin/lame" --silent -q 2 -b 0 - & | 2006-08-09 20:29:27.2059 openSong: Streaming with format: mp3 2006-08-09 20:29:27.2596 00:04:20:06:7f:7f New play mode: play 2006-08-09 20:29:27.6264 *************stream called: s paused: 0 format: mp3 url: file:///home/slim%20music/Bananarama/Greatest%20Hits%20Collection/02%20I%20Heard%20A%20Rumour.m4a 2006-08-09 20:29:27.6272 Backtrace: frame 0: Slim::Player::Squeezebox::stream (/usr/local/slimserver/Slim/Player/Squeezebox.pm line 152) frame 1: Slim::Player::Squeezebox::play (/usr/local/slimserver/Slim/Player/Source.pm line 486) frame 2: Slim::Player::Source::playmode (/usr/local/slimserver/Slim/Player/Source.pm line 856) frame 3: Slim::Player::Source::jumpto (/usr/local/slimserver/Slim/Control/Commands.pm line 579) frame 4: Slim::Control::Commands::playlistJumpCommand (/usr/local/slimserver/Slim/Control/Request.pm line 1405) frame 5: (eval) (/usr/local/slimserver/Slim/Control/Request.pm line 1405) frame 6: Slim::Control::Request::execute (/usr/local/slimserver/Slim/Control/Request.pm line 731) frame 7: Slim::Control::Request::executeRequest (/usr/local/slimserver/Slim/Player/Client.pm line 1015) frame 8: Slim::Player::Client::execute (/usr/local/slimserver/Slim/Buttons/BrowseTree.pm line 129) frame 9: Slim::Buttons::BrowseTree::__ANON__ (/usr/local/slimserver/Slim/Hardware/IR.pm line 737) frame 10: Slim::Hardware::IR::executeButton (/usr/local/slimserver/Slim/Buttons/Input/List.pm line 74) frame 11: Slim::Buttons::Input::List::__ANON__ (/usr/local/slimserver/Slim/Hardware/IR.pm line 737) frame 12: Slim::Hardware::IR::executeButton (/usr/local/slimserver/Slim/Control/Commands.pm line 141) frame 13: Slim::Control::Commands::buttonCommand (/usr/local/slimserver/Slim/Control/Request.pm line 1405) frame 14: (eval) (/usr/local/slimserver/Slim/Control/Request.pm line 1405) frame 15: Slim::Control::Request::execute (/usr/local/slimserver/Slim/Control/Request.pm line 731) frame 16: Slim::Control::Request::executeRequest (/usr/local/slimserver/Slim/Player/Client.pm line 1015) frame 17: Slim::Player::Client::execute (/usr/local/slimserver/Slim/Hardware/IR.pm line 750) frame 18: Slim::Hardware::IR::processCode (/usr/local/slimserver/Slim/Hardware/IR.pm line 613) frame 19: Slim::Hardware::IR::releaseCode (/usr/local/slimserver/Slim/Hardware/IR.pm line 501) frame 20: Slim::Hardware::IR::checkRelease (/usr/local/slimserver/Slim/Utils/Timers.pm line 171) frame 21: Slim::Utils::Timers::checkTimers (/usr/local/slimserver/slimserver.pl line 496) frame 22: main::idle (/usr/local/slimserver/slimserver.pl line 446) frame 23: main::main (/usr/local/slimserver/slimserver.pl line 1043) 2006-08-09 20:29:27.6279 starting with decoder with format: m autostart: 1 threshold: 255 samplesize: ? samplerate: ? endian: ? channels: ? 2006-08-09 20:29:27.6281 flags: 0 2006-08-09 20:29:27.6284 sending strm frame of length: 76 request string: GET /stream.mp3?player=00:04:20:06:7f:7f HTTP/1.0 2006-08-09 20:29:27.6300 00:04:20:06:7f:7f: Current playmode: play 2006-08-09 20:29:27.6324 00:04:20:06:7f:7f Squeezebox stream status: event_code: audg bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 jiffies: 355144023 2006-08-09 20:29:27.6327 output size: 3528000 output fullness: 3521408 elapsed seconds: 0 2006-08-09 20:29:28.3320 00:04:20:06:7f:7f Squeezebox stream status: event_code: STMf bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 jiffies: 355144392 2006-08-09 20:29:28.3327 output size: 3528000 output fullness: 3521408 elapsed seconds: 0 2006-08-09 20:29:28.3338 00:04:20:06:7f:7f Squeezebox stream status: event_code: STMc bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 jiffies: 355144393 2006-08-09 20:29:28.3341 output size: 3528000 output fullness: 3521408 elapsed seconds: 0 2006-08-09 20:29:28.3349 00:04:20:06:7f:7f Squeezebox stream status: event_code: strm bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 jiffies: 355144394 2006-08-09 20:29:28.3352 output size: 3528000 output fullness: 3521408 elapsed seconds: 0 2006-08-09 20:29:28.3361 00:04:20:06:7f:7f Squeezebox stream status: event_code: audg bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 jiffies: 355144395 2006-08-09 20:29:28.3363 output size: 3528000 output fullness: 3521408 elapsed seconds: 0 2006-08-09 20:29:28.3376 00:04:20:06:7f:7f Squeezebox stream status: event_code: STMe bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 jiffies: 355144402 2006-08-09 20:29:28.3379 output size: 3528000 output fullness: 3521408 elapsed seconds: 0 2006-08-09 20:29:28.5530 We need to send 0 seconds of silence... 2006-08-09 20:29:28.5533 sending 0 bytes of silence MPlayer interrupted by signal 1 in module: unknown MPlayer interrupted by signal 15 in module: unknown 060809 20:32:38 [Note] /usr/local/slimserver/Bin/i386-linux/mysqld: Normal shutdown 2006-08-09 20:32:39.1119 ERROR: DBI Connection failed: DBI connect('hostname=127.0.0.1;port=9092;database=slimserver:mysql_socket=/home/mjpine/Cache/slimserver-mysql.sock','slimserver',...) failed: Can't connect to MySQL server on '127.0.0.1' (111) at /usr/local/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 624 2006-08-09 20:32:39.1122 ERROR: Backtrace follows: 2006-08-09 20:32:39.1127 Backtrace: frame 0: Slim::Schema::Storage::throw_exception (/usr/local/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 630) frame 1: DBIx::Class::Storage::DBI::_connect (/usr/local/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 588) frame 2: DBIx::Class::Storage::DBI::_populate_dbh (/usr/local/slimserver/Slim/Schema/Storage.pm line 32) frame 3: Slim::Schema::Storage::_populate_dbh (/usr/local/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 515) frame 4: DBIx::Class::Storage::DBI::ensure_connected (/usr/local/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 529) frame 5: DBIx::Class::Storage::DBI::dbh (/usr/local/slimserver/Slim/Schema.pm line 919) frame 6: Slim::Schema::forceCommit (/usr/local/slimserver/slimserver.pl line 1008) frame 7: main::cleanup (/usr/local/slimserver/Slim/bootstrap.pm line 265) frame 8: Slim::bootstrap::sigterm (/usr/lib/perl5/5.8.7/i586-linux-thread-multi/IO/Handle.pm line 436) frame 9: (eval) (/usr/lib/perl5/5.8.7/i586-linux-thread-multi/IO/Handle.pm line 436) frame 10: IO::Handle::sysread (/usr/local/slimserver/Slim/Player/Source.pm line 1746) frame 11: Slim::Player::Source::readNextChunk (/usr/local/slimserver/Slim/Player/Source.pm line 661) frame 12: Slim::Player::Source::nextChunk (/usr/local/slimserver/Slim/Web/HTTP.pm line 1672) frame 13: Slim::Web::HTTP::sendStreamingResponse (/usr/local/slimserver/Slim/Networking/Select.pm line 170) frame 14: Slim::Networking::Select::select (/usr/local/slimserver/slimserver.pl line 493) frame 15: main::idle (/usr/local/slimserver/slimserver.pl line 446) frame 16: main::main (/usr/local/slimserver/slimserver.pl line 1043) Can't use an undefined value as a HASH reference at /usr/local/slimserver/Slim/Schema.pm line 919. 2006-08-09 20:32:39.1615 ERROR: DBI Connection failed: DBI connect('hostname=127.0.0.1;port=9092;database=slimserver:mysql_socket=/home/mjpine/Cache/slimserver-mysql.sock','slimserver',...) failed: Can't connect to MySQL server on '127.0.0.1' (111) at /usr/local/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 624 2006-08-09 20:32:39.1617 ERROR: Backtrace follows: 2006-08-09 20:32:39.1635 Backtrace: frame 0: Slim::Schema::Storage::throw_exception (/usr/local/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 630) frame 1: DBIx::Class::Storage::DBI::_connect (/usr/local/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 588) frame 2: DBIx::Class::Storage::DBI::_populate_dbh (/usr/local/slimserver/Slim/Schema/Storage.pm line 32) frame 3: Slim::Schema::Storage::_populate_dbh (/usr/local/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 515) frame 4: DBIx::Class::Storage::DBI::ensure_connected (/usr/local/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 529) frame 5: DBIx::Class::Storage::DBI::dbh (/usr/local/slimserver/Slim/Schema.pm line 919) frame 6: Slim::Schema::forceCommit (/usr/local/slimserver/slimserver.pl line 1008) frame 7: main::cleanup (/usr/local/slimserver/Slim/bootstrap.pm line 255) frame 8: Slim::bootstrap::sigint (/usr/local/slimserver/Slim/bootstrap.pm line 286) frame 9: Slim::bootstrap::END (/usr/local/slimserver/slimserver.pl line 0) frame 10: (eval) (/usr/local/slimserver/slimserver.pl line 0) Can't use an undefined value as a HASH reference at /usr/local/slimserver/Slim/Schema.pm line 919. END failed--call queue aborted. 060809 20:32:38 InnoDB: Starting shutdown... 060809 20:32:42 InnoDB: Shutdown completed; log sequence number 0 15734914 060809 20:32:42 [Note] /usr/local/slimserver/Bin/i386-linux/mysqld: Shutdown complete