Now lets look at the squeezelite logs:
Code:
--------------------
[01:16:08.861] stream_thread:428 streambuf read 3132 bytes
[01:16:08.961] stream_thread:428 streambuf read 1566 bytes
[01:16:09.026] sendSTAT:166 ms_played: 328110 (frames_played: 14470092
device_frames: 441)
[01:16:09.026] sendSTAT:195 STAT: STMt
[01:16:09.026] sendSTAT:200 received bytesL: 12661166 streambuf: 2093499
outputbuf: 3325264 calc elapsed: 328110 real elapsed: 328203 (diff: -93)
device: 10 delay: 0 <-- first STMt after LMS closed connection
[01:16:09.026] process:528 strm
[01:16:09.026] process_strm:280 strm command t
[01:16:09.026] sendSTAT:166 ms_played: 328110 (frames_played: 14470092
device_frames: 441)
[01:16:09.026] sendSTAT:195 STAT: STMt
[01:16:09.026] sendSTAT:200 received bytesL: 12661166 streambuf: 2093499
outputbuf: 3325264 calc elapsed: 328110 real elapsed: 328203 (diff: -93)
device: 10 delay: 0
[01:16:09.062] stream_thread:428 streambuf read 3652 bytes
... reading from the buffer continues
[01:18:31.890] stream_thread:428 streambuf read 3340 bytes
<-- frame 17677 with TCP FIN arrives
[01:18:31.991] stream_thread:428 streambuf read 3131 bytes
[01:18:32.021] process:528 strm
[01:18:32.021] process_strm:280 strm command t
[01:18:32.021] sendSTAT:166 ms_played: 471080 (frames_played: 20775069
device_frames: 441)
[01:18:32.021] sendSTAT:195 STAT: STMt
[01:18:32.021] sendSTAT:200 received bytesL: 16819726 streambuf: 2097151
outputbuf: 3306184 calc elapsed: 471080 real elapsed: 471203 (diff: -123)
device: 10 delay: 0
[01:18:32.092] stream_thread:428 streambuf read 3445 bytes
[01:18:32.193] stream_thread:428 streambuf read 1253 bytes
... reading from the buffer continues
[01:19:40.919] stream_thread:428 streambuf read 4280 bytes
[01:19:41.019] stream_thread:428 streambuf read 3236 bytes
[01:19:41.021] sendSTAT:166 ms_played: 540080 (frames_played: 23817969
device_frames: 441)
[01:19:41.021] sendSTAT:195 STAT: STMt
[01:19:41.021] sendSTAT:200 received bytesL: 18902856 streambuf: 2097151
outputbuf: 3320872 calc elapsed: 540080 real elapsed: 540203 (diff: -123)
device: 10 delay: 0
[01:19:41.120] stream_thread:428 streambuf read 2296 bytes
[01:19:41.220] stream_thread:428 streambuf read 3654 bytes
[01:19:41.321] stream_thread:428 streambuf read 2818 bytes
[01:19:41.422] stream_thread:428 streambuf read 3237 bytes
[01:19:41.522] stream_thread:428 streambuf read 3445 bytes
<-- last read from the stream buffer
[01:19:43.021] sendSTAT:166 ms_played: 542080 (frames_played: 23906169
device_frames: 441)
[01:19:43.021] sendSTAT:195 STAT: STMt
[01:19:43.021] sendSTAT:200 received bytesL: 18918306 streambuf: 2049237
outputbuf: 3334120 calc elapsed: 542080 real elapsed: 542203 (diff: -123)
device: 10 delay: 0
[01:19:44.019] process:528 strm
[01:19:44.019] process_strm:280 strm command t
[01:19:44.019] sendSTAT:166 ms_played: 543080 (frames_played: 23950269
device_frames: 441)
[01:19:44.019] sendSTAT:195 STAT: STMt
[01:19:44.019] sendSTAT:200 received bytesL: 18918306 streambuf: 2020947
outputbuf: 3322312 calc elapsed: 543080 real elapsed: 543203 (diff: -123)
device: 10 delay: 0
[01:19:45.020] sendSTAT:166 ms_played: 544080 (frames_played: 23994369
device_frames: 441)
[01:19:45.020] sendSTAT:195 STAT: STMt
[01:19:45.020] sendSTAT:200 received bytesL: 18918306 streambuf: 1994641
outputbuf: 3301288 calc elapsed: 544080 real elapsed: 544203 (diff: -123)
device: 10 delay: 0
[01:19:47.021] sendSTAT:166 ms_played: 546096 (frames_played: 24082569
device_frames: 441)
[01:19:47.021] sendSTAT:195 STAT: STMt
[01:19:47.021] sendSTAT:200 received bytesL: 18918306 streambuf: 1940677
outputbuf: 3314536 calc elapsed: 546096 real elapsed: 546203 (diff: -107)
device: 10 delay: 16
...
[01:20:59.017] sendSTAT:200 received bytesL: 18918306 streambuf: 2019
outputbuf: 209176 calc elapsed: 618036 real elapsed: 618203 (diff: -167)
device: 10 delay: 16
[01:21:00.018] sendSTAT:166 ms_played: 618638 (frames_played: 27281711
device_frames: 441)
[01:21:00.018] sendSTAT:195 STAT: STMo
[01:21:00.018] sendSTAT:200 received bytesL: 18918306 streambuf: 2019
outputbuf: 0 calc elapsed: 618638 real elapsed: 619203 (diff: -565) device: 10
delay: 16
[01:21:00.018] process:528 strm
[01:21:00.018] process_strm:280 strm command t
[01:21:00.018] sendSTAT:166 ms_played: 618638 (frames_played: 27281711
device_frames: 441)
[01:21:00.018] sendSTAT:195 STAT: STMt
[01:21:00.018] sendSTAT:200 received bytesL: 18918306 streambuf: 2019
outputbuf: 0 calc elapsed: 618638 real elapsed: 619203 (diff: -565) device: 10
delay: 16
[01:21:00.028] process:528 strm
[01:21:00.028] process_strm:280 strm command p
[01:21:00.028] sendSTAT:166 ms_played: 618654 (frames_played: 27281711
device_frames: 441)
[01:21:00.028] sendSTAT:195 STAT: STMp
[01:21:00.028] sendSTAT:200 received bytesL: 18918306 streambuf: 2019
outputbuf: 0 calc elapsed: 618654 real elapsed: 619219 (diff: -565) device: 10
delay: 32
[01:21:00.028] process_strm:316 pause interval: 0
[01:21:00.160] process:528 strm
[01:21:00.160] process_strm:280 strm command t
--------------------
squeezelite performs last successfull read from the stream buffer at
01:19:41.522 which matches with the arrival of RST packet from the
server. Once Windows receives RST packet it destroys TCP connection and
associated buffers so squeezelite has nothing to read from. Once
squeezelite buffer empties it pauses music and sends STMo to the sever,
20-30 seconds later music playback resumes.
------------------------------------------------------------------------
lngxa's Profile: http://forums.slimdevices.com/member.php?userid=71826
View this thread: http://forums.slimdevices.com/showthread.php?t=114661
_______________________________________________
Squeezecenter mailing list
[email protected]
http://lists.slimdevices.com/mailman/listinfo/squeezecenter
