Song playback interruptions
|
28-12-2018, 23:13
Post: #1
|
|||
|
|||
Song playback interruptions
Hi,
First post here. I have been experiencing frequent interruption in song playback. System is as follows: Synology DS214play running MinimServer connected by ethernet to a Synology RT2600ac router, in turn connected by ethernet to a Hegel h190 integrated amplifier. Playback control is via BubbleUPnP running on an Asus Zenpad 8" Android tablet. Minimwatch is running on a 2018 MacBook Pro. Songs often stop playing within the first 30-60 seconds of starting to play an album, although it can happen pretty much at any time. After approximately 45 seconds it is usually possible to start playback again (from the start of the song). Below is an example of the log during the period when this happens (including successful restarting). I'd appreciate your thoughts on what may be causing this, in particular the line at at the point of playback interruption 13:18:34.329 HTTPConnection: connection closed by client. Does the "client" here refer to Bubbleupnp or the network player module in the Hegel amplifier? The IP addresses referred to in the log are as follows: 101 (Synology NAS); 22 (Hegel amp); 89 (Android tablet). Thanks so much in advance for your assistance. Rockhound -------- 13:16:10.778 Thread-7: HTTPService: accepted incoming connection 13:16:10.779 Thread-7: HTTPService: waiting for incoming connection 13:16:10.779 Thread-6: HTTPService: received request, Socket[addr=/192.168.1.22,port=33230,localport=9790] 13:16:10.779 Thread-6: HTTPService: adding connection org.jminim.lib.HTTPConnection@15710e 13:16:10.780 Thread-89: HTTPConnection: writer thread waiting for request 13:16:10.780 Thread-90: HTTPConnection: reading HTTP request 13:16:10.781 Thread-90: GET /minimserver/*/music/iTunes/Genesis/Selling*20England*20By*20The*20Pound/01*20Dancing*20With*20The*20Moonlight*20Knight.m4a HTTP/1.1 13:16:10.781 Thread-90: Host: 192.168.1.101, Connection: keep-alive, isTimeSeek: 0, User-Agent: stagefright/1.2 (Linux;Android , Accept-Encoding: gzip,deflate 13:16:10.781 Thread-90: HTTPConnection: reading HTTP request 13:16:10.782 Thread-89: HTTPConnection: writer thread processing request 13:16:10.796 Thread-89: HTTP/1.1 200 OK, Accept-Ranges: bytes, Date: Fri, 28 Dec 2018 21:16:10 GMT, Content-Length: 52387645, Content-Type: audio/mp4, Connection: keep-alive, Last-Modified: Sat, 15 Sep 2012 10:38:32 GMT 13:16:10.797 Thread-89: writing data: total=52387645 from file iTunes/Genesis/Selling England By The Pound/01 Dancing With The Moonlight Knight.m4a 13:16:10.797 Thread-89: writing 16384 bytes: total=52387645 13:16:10.797 Thread-89: writing 16384 bytes: total=52387645 13:16:10.798 Thread-89: writing 16384 bytes: total=52387645 13:16:10.798 Thread-89: writing 16384 bytes: total=52387645 13:16:10.799 Thread-89: writing 16384 bytes: total=52387645 13:16:10.799 Thread-89: writing 16384 bytes: total=52387645 13:16:10.800 Thread-89: writing 16384 bytes: total=52387645 13:16:36.372 Thread-89: writing: bytesWritten=5013504 maxRead=11 minRead=0 maxWrite=24973 minWrite=0 numReads=306 numWrites=306 avgWrite=83 13:16:58.306 Thread-89: writing: bytesWritten=7241728 maxRead=1 minRead=0 maxWrite=21636 minWrite=0 numReads=136 numWrites=136 avgWrite=161 13:17:19.850 Thread-89: writing: bytesWritten=9519104 maxRead=1 minRead=0 maxWrite=21151 minWrite=0 numReads=139 numWrites=139 avgWrite=154 13:17:42.590 Thread-89: writing: bytesWritten=11730944 maxRead=1 minRead=0 maxWrite=22421 minWrite=0 numReads=135 numWrites=135 avgWrite=168 13:18:05.547 Thread-89: writing: bytesWritten=13942784 maxRead=0 minRead=0 maxWrite=22656 minWrite=0 numReads=135 numWrites=135 avgWrite=170 13:18:23.780 Thread-89: writing: bytesWritten=16154624 maxRead=0 minRead=0 maxWrite=17930 minWrite=0 numReads=135 numWrites=135 avgWrite=135 13:18:34.329 Thread-90: HTTPConnection: connection closed by client 13:18:34.330 Thread-89: FileResource: close 13:18:34.330 Thread-91: FileResource: waitForEmpty caught InterruptedException 13:18:34.331 Thread-91: FileResource: waitForEmpty returned true 13:18:34.331 Thread-89: ServerResourceBase: close 13:18:34.332 Thread-90: ServerResourceBase: forced socket close: Socket[addr=/192.168.1.22,port=33230,localport=9790] 13:18:34.332 Thread-89: HTTPConnection: SocketException writing HTTP response to Socket[addr=/192.168.1.22,port=33230,localport=9790] 13:18:34.332 Thread-90: HTTPService: removing connection org.jminim.lib.HTTPConnection@15710e 13:19:43.404 Thread-7: HTTPService: accepted incoming connection 13:19:43.405 Thread-7: HTTPService: waiting for incoming connection 13:19:43.405 Thread-6: HTTPService: received request, Socket[addr=/192.168.1.22,port=50274,localport=9790] 13:19:43.405 Thread-6: HTTPService: adding connection org.jminim.lib.HTTPConnection@1b7ada4 13:19:43.406 Thread-92: HTTPConnection: writer thread waiting for request 13:19:43.406 Thread-93: HTTPConnection: reading HTTP request 13:19:43.464 Thread-93: GET /minimserver/*/music/iTunes/Genesis/Selling*20England*20By*20The*20Pound/01*20Dancing*20With*20The*20Moonlight*20Knight.m4a HTTP/1.1 13:19:43.465 Thread-93: Host: 192.168.1.101, Connection: keep-alive, isTimeSeek: 0, User-Agent: stagefright/1.2 (Linux;Android , Accept-Encoding: gzip,deflate 13:19:43.465 Thread-93: HTTPConnection: reading HTTP request 13:19:43.465 Thread-92: HTTPConnection: writer thread processing request 13:19:43.469 Thread-92: HTTP/1.1 200 OK, Accept-Ranges: bytes, Date: Fri, 28 Dec 2018 21:19:43 GMT, Content-Length: 52387645, Content-Type: audio/mp4, Connection: keep-alive, Last-Modified: Sat, 15 Sep 2012 10:38:32 GMT 13:19:43.469 Thread-92: writing data: total=52387645 from file iTunes/Genesis/Selling England By The Pound/01 Dancing With The Moonlight Knight.m4a 13:19:43.469 Thread-92: writing 16384 bytes: total=52387645 13:19:43.470 Thread-92: writing 16384 bytes: total=52387645 13:19:43.470 Thread-92: writing 16384 bytes: total=52387645 13:19:43.471 Thread-92: writing 16384 bytes: total=52387645 13:19:43.471 Thread-92: writing 16384 bytes: total=52387645 13:19:43.471 Thread-92: writing 16384 bytes: total=52387645 13:19:43.472 Thread-92: writing 16384 bytes: total=52387645 13:20:09.070 Thread-92: writing: bytesWritten=5029888 maxRead=1 minRead=0 maxWrite=25031 minWrite=0 numReads=307 numWrites=307 avgWrite=83 13:20:30.989 Thread-92: writing: bytesWritten=7241728 maxRead=1 minRead=0 maxWrite=21590 minWrite=0 numReads=135 numWrites=135 avgWrite=162 13:20:34.882 Thread-21: Log action: GetLogDataLength 13:20:34.882 Thread-21: version=1, adapter=192.168.1.101, uriPrefix=http://192.168.1.101:9791/71e50b8e-98a8-4a1f-ad8c-e26b122f89af/Upnp/resource/, clientEndpoint=192.168.1.60:53936 13:20:34.882 Thread-21: Log response: GetLogDataLength 13:20:34.882 Thread-21: DataLength=788835 13:20:52.538 Thread-92: writing: bytesWritten=9453568 maxRead=1 minRead=0 maxWrite=21269 minWrite=0 numReads=135 numWrites=135 avgWrite=159 13:21:15.309 Thread-92: writing: bytesWritten=11730944 maxRead=1 minRead=0 maxWrite=22410 minWrite=0 numReads=139 numWrites=139 avgWrite=163 13:21:38.219 Thread-92: writing: bytesWritten=13942784 maxRead=0 minRead=0 maxWrite=22640 minWrite=0 numReads=135 numWrites=135 avgWrite=169 13:21:44.233 Thread-9: Log action: GetLogDataLength 13:21:44.233 Thread-9: version=1, adapter=192.168.1.101, uriPrefix=http://192.168.1.101:9791/71e50b8e-98a8-4a1f-ad8c-e26b122f89af/Upnp/resource/, clientEndpoint=192.168.1.60:54000 13:21:44.233 Thread-9: Log response: GetLogDataLength 13:21:44.233 Thread-9: DataLength=789588 13:21:56.529 Thread-92: writing: bytesWritten=16171008 maxRead=0 minRead=0 maxWrite=18039 minWrite=0 numReads=136 numWrites=136 avgWrite=134 13:22:13.958 Thread-92: writing: bytesWritten=18382848 maxRead=1 minRead=0 maxWrite=17177 minWrite=0 numReads=135 numWrites=135 avgWrite=129 13:22:31.865 Thread-92: writing: bytesWritten=20594688 maxRead=1 minRead=0 maxWrite=17625 minWrite=0 numReads=135 numWrites=135 avgWrite=132 13:22:50.715 Thread-92: writing: bytesWritten=22872064 maxRead=1 minRead=0 maxWrite=18199 minWrite=0 numReads=139 numWrites=139 avgWrite=135 13:23:08.545 Thread-92: writing: bytesWritten=25083904 maxRead=1 minRead=0 maxWrite=17465 minWrite=0 numReads=135 numWrites=135 avgWrite=132 13:23:26.058 Thread-92: writing: bytesWritten=27312128 maxRead=1 minRead=0 maxWrite=17228 minWrite=0 numReads=136 numWrites=136 avgWrite=128 13:23:43.257 Thread-92: writing: bytesWritten=29523968 maxRead=1 minRead=0 maxWrite=16957 minWrite=0 numReads=135 numWrites=135 avgWrite=127 13:24:00.577 Thread-92: writing: bytesWritten=31735808 maxRead=1 minRead=0 maxWrite=17027 minWrite=0 numReads=135 numWrites=135 avgWrite=128 13:24:17.815 Thread-92: writing: bytesWritten=33947648 maxRead=1 minRead=0 maxWrite=16965 minWrite=0 numReads=135 numWrites=135 avgWrite=127 13:24:35.326 Thread-92: writing: bytesWritten=36241408 maxRead=0 minRead=0 maxWrite=17226 minWrite=0 numReads=140 numWrites=140 avgWrite=125 13:24:52.658 Thread-92: writing: bytesWritten=38453248 maxRead=0 minRead=0 maxWrite=17038 minWrite=0 numReads=135 numWrites=135 avgWrite=128 13:25:11.071 Thread-92: writing: bytesWritten=40665088 maxRead=1 minRead=0 maxWrite=18081 minWrite=0 numReads=135 numWrites=135 avgWrite=136 13:25:28.809 Thread-92: writing: bytesWritten=42876928 maxRead=1 minRead=0 maxWrite=17429 minWrite=0 numReads=135 numWrites=135 avgWrite=131 13:25:50.271 Thread-92: writing: bytesWritten=45154304 maxRead=0 minRead=0 maxWrite=20795 minWrite=0 numReads=139 numWrites=139 avgWrite=154 13:26:18.194 Thread-92: writing: bytesWritten=47382528 maxRead=0 minRead=0 maxWrite=27644 minWrite=0 numReads=136 numWrites=136 avgWrite=205 13:26:42.220 Thread-92: writing: bytesWritten=49594368 maxRead=1 minRead=0 maxWrite=23710 minWrite=0 numReads=135 numWrites=135 avgWrite=177 13:27:05.635 Thread-92: writing: bytesWritten=51806208 maxRead=1 minRead=0 maxWrite=23095 minWrite=0 numReads=135 numWrites=135 avgWrite=173 13:27:05.687 Thread-94: FileResource: reader thread end of data 13:27:05.701 Thread-92: FileResource: writer thread end of data, bytesWritten=52387645 13:27:05.701 Thread-92: FileResource: close 13:27:05.701 Thread-92: ServerResourceBase: close 13:27:05.702 Thread-92: HTTPConnection: completed request; socket=Socket[addr=/192.168.1.22,port=50274,localport=9790], keepAlive=true 13:27:05.702 Thread-92: HTTPConnection: writer thread waiting for request 13:27:48.703 Thread-93: GET /minimserver/*/music/iTunes/Genesis/Selling*20England*20By*20The*20Pound/02*20I*20Know*20What*20I*20Like*20(In*20Your*20Wardrobe).m4a HTTP/1.1 13:27:48.703 Thread-93: Host: 192.168.1.101, Connection: keep-alive, isTimeSeek: 0, User-Agent: stagefright/1.2 (Linux;Android , Accept-Encoding: gzip,deflate 13:27:48.704 Thread-93: HTTPConnection: reading HTTP request 13:27:48.704 Thread-92: HTTPConnection: writer thread processing request 13:27:48.725 Thread-92: HTTP/1.1 200 OK, Accept-Ranges: bytes, Date: Fri, 28 Dec 2018 21:27:48 GMT, Content-Length: 29317694, Content-Type: audio/mp4, Connection: keep-alive, Last-Modified: Sat, 15 Sep 2012 10:38:53 GMT 13:27:48.726 Thread-92: writing data: total=29317694 from file iTunes/Genesis/Selling England By The Pound/02 I Know What I Like (In Your Wardrobe).m4a 13:27:48.726 Thread-92: writing 16384 bytes: total=29317694 13:27:48.726 Thread-92: writing 16384 bytes: total=29317694 13:27:48.727 Thread-92: writing 16384 bytes: total=29317694 13:27:48.727 Thread-92: writing 16384 bytes: total=29317694 13:27:48.727 Thread-92: writing 16384 bytes: total=29317694 13:27:48.728 Thread-92: writing 16384 bytes: total=29317694 13:27:48.728 Thread-92: writing 16384 bytes: total=29317694 13:28:13.895 Thread-92: writing: bytesWritten=5046272 maxRead=18 minRead=0 maxWrite=24251 minWrite=0 numReads=308 numWrites=308 avgWrite=81 13:28:33.802 Thread-92: writing: bytesWritten=7258112 maxRead=1 minRead=0 maxWrite=19582 minWrite=0 numReads=135 numWrites=135 avgWrite=147 13:28:51.810 Thread-92: writing: bytesWritten=9469952 maxRead=1 minRead=0 maxWrite=17690 minWrite=0 numReads=135 numWrites=135 avgWrite=133 13:29:08.337 Thread-92: writing: bytesWritten=11681792 maxRead=1 minRead=0 maxWrite=16186 minWrite=0 numReads=135 numWrites=135 avgWrite=122 13:29:26.758 Thread-92: writing: bytesWritten=13910016 maxRead=1 minRead=0 maxWrite=18167 minWrite=0 numReads=136 numWrites=136 avgWrite=135 13:29:45.089 Thread-92: writing: bytesWritten=16187392 maxRead=0 minRead=0 maxWrite=17968 minWrite=0 numReads=139 numWrites=139 avgWrite=131 13:30:02.026 Thread-92: writing: bytesWritten=18399232 maxRead=1 minRead=0 maxWrite=16635 minWrite=0 numReads=135 numWrites=135 avgWrite=125 13:30:18.776 Thread-92: writing: bytesWritten=20611072 maxRead=1 minRead=0 maxWrite=16405 minWrite=0 numReads=135 numWrites=135 avgWrite=124 13:30:35.121 Thread-92: writing: bytesWritten=22822912 maxRead=1 minRead=0 maxWrite=16069 minWrite=0 numReads=135 numWrites=135 avgWrite=121 13:30:53.237 Thread-92: writing: bytesWritten=25116672 maxRead=2 minRead=0 maxWrite=17705 minWrite=0 numReads=140 numWrites=140 avgWrite=129 13:31:11.069 Thread-92: writing: bytesWritten=27328512 maxRead=1 minRead=0 maxWrite=17458 minWrite=0 numReads=135 numWrites=135 avgWrite=132 13:31:11.234 Thread-95: FileResource: reader thread end of data 13:31:11.271 Thread-92: FileResource: writer thread end of data, bytesWritten=29317694 13:31:11.271 Thread-92: FileResource: close 13:31:11.271 Thread-92: ServerResourceBase: close 13:31:11.272 Thread-92: HTTPConnection: completed request; socket=Socket[addr=/192.168.1.22,port=50274,localport=9790], keepAlive=true 13:31:11.272 Thread-92: HTTPConnection: writer thread waiting for request 13:32:01.876 Thread-93: GET /minimserver/*/music/iTunes/Genesis/Selling*20England*20By*20The*20Pound/03*20Firth*20Of*20Fifth.m4a HTTP/1.1 13:32:01.876 Thread-93: Host: 192.168.1.101, Connection: keep-alive, isTimeSeek: 0, User-Agent: stagefright/1.2 (Linux;Android , Accept-Encoding: gzip,deflate 13:32:01.877 Thread-93: HTTPConnection: reading HTTP request 13:32:01.877 Thread-92: HTTPConnection: writer thread processing request 13:32:01.888 Thread-92: HTTP/1.1 200 OK, Accept-Ranges: bytes, Date: Fri, 28 Dec 2018 21:32:01 GMT, Content-Length: 63404656, Content-Type: audio/mp4, Connection: keep-alive, Last-Modified: Sat, 15 Sep 2012 10:39:15 GMT 13:32:01.889 Thread-92: writing data: total=63404656 from file iTunes/Genesis/Selling England By The Pound/03 Firth Of Fifth.m4a 13:32:01.889 Thread-92: writing 16384 bytes: total=63404656 13:32:01.889 Thread-92: writing 16384 bytes: total=63404656 13:32:01.889 Thread-92: writing 16384 bytes: total=63404656 13:32:01.890 Thread-92: writing 16384 bytes: total=63404656 13:32:01.890 Thread-92: writing 16384 bytes: total=63404656 13:32:01.890 Thread-92: writing 16384 bytes: total=63404656 13:32:01.890 Thread-92: writing 16384 bytes: total=63404656 13:32:26.863 Thread-92: writing: bytesWritten=5046272 maxRead=9 minRead=0 maxWrite=24412 minWrite=0 numReads=308 numWrites=308 avgWrite=81 13:32:52.801 Thread-92: writing: bytesWritten=7258112 maxRead=1 minRead=0 maxWrite=25690 minWrite=0 numReads=135 numWrites=135 avgWrite=192 13:33:09.364 Thread-7: HTTPService: accepted incoming connection 13:33:09.364 Thread-7: HTTPService: waiting for incoming connection 13:33:09.364 Thread-6: HTTPService: received request, Socket[addr=/192.168.1.89,port=60767,localport=9790] 13:33:09.365 Thread-6: HTTPService: adding connection org.jminim.lib.HTTPConnection@10e7d7b 13:33:09.365 Thread-97: HTTPConnection: writer thread waiting for request 13:33:09.366 Thread-98: HTTPConnection: reading HTTP request 13:33:09.397 Thread-98: GET /minimserver/*/music/iTunes/Genesis/Selling*20England*20By*20The*20Pound/cover.jpg HTTP/1.1 13:33:09.397 Thread-98: User-Agent: BubbleUPnP UPnP/1.1, Host: 192.168.1.101:9790, Connection: Keep-Alive, Accept-Encoding: gzip 13:33:09.398 Thread-98: HTTPConnection: reading HTTP request 13:33:09.398 Thread-97: HTTPConnection: writer thread processing request 13:33:09.412 Thread-97: HTTP/1.1 200 OK, Accept-Ranges: bytes, Date: Fri, 28 Dec 2018 21:33:09 GMT, Content-Length: 84062, Content-Type: image/jpeg, Connection: keep-alive, Last-Modified: Sun, 11 Mar 2018 00:55:53 GMT 13:33:09.412 Thread-97: writing data: total=84062 from file iTunes/Genesis/Selling England By The Pound/cover.jpg 13:33:09.413 Thread-97: writing 16384 bytes: total=84062 13:33:09.413 Thread-97: writing 16384 bytes: total=84062 13:33:09.413 Thread-99: FileResource: reader thread end of data 13:33:09.413 Thread-97: writing 16384 bytes: total=84062 13:33:09.414 Thread-97: writing 16384 bytes: total=84062 13:33:09.416 Thread-97: writing 16384 bytes: total=84062 13:33:09.416 Thread-97: writing 2142 bytes: total=84062 13:33:09.416 Thread-97: FileResource: writer thread end of data, bytesWritten=84062 13:33:09.416 Thread-97: FileResource: close 13:33:09.416 Thread-97: ServerResourceBase: close 13:33:09.417 Thread-97: HTTPConnection: completed request; socket=Socket[addr=/192.168.1.89,port=60767,localport=9790], keepAlive=true 13:33:09.417 Thread-97: HTTPConnection: writer thread waiting for request 13:33:15.141 Thread-92: writing: bytesWritten=9469952 maxRead=1 minRead=0 maxWrite=22070 minWrite=0 numReads=135 numWrites=135 avgWrite=165 13:33:33.748 Thread-92: writing: bytesWritten=11681792 maxRead=1 minRead=0 maxWrite=18327 minWrite=0 numReads=135 numWrites=135 avgWrite=137 13:33:52.269 Thread-92: writing: bytesWritten=13975552 maxRead=2 minRead=0 maxWrite=18148 minWrite=0 numReads=140 numWrites=140 avgWrite=132 13:34:11.507 Thread-92: writing: bytesWritten=16187392 maxRead=1 minRead=0 maxWrite=18905 minWrite=0 numReads=135 numWrites=135 avgWrite=142 13:34:31.941 Thread-92: writing: bytesWritten=18399232 maxRead=1 minRead=0 maxWrite=20100 minWrite=0 numReads=135 numWrites=135 avgWrite=151 13:34:47.734 Thread-10: Log action: GetLogDataLength 13:34:47.734 Thread-10: version=1, adapter=192.168.1.101, uriPrefix=http://192.168.1.101:9791/71e50b8e-98a8-4a1f-ad8c-e26b122f89af/Upnp/resource/, clientEndpoint=192.168.1.60:54524 13:34:47.743 Thread-10: Log response: GetLogDataLength 13:34:47.743 Thread-10: DataLength=800185 13:34:52.783 Thread-92: writing: bytesWritten=20611072 maxRead=1 minRead=0 maxWrite=20542 minWrite=0 numReads=135 numWrites=135 avgWrite=154 13:35:10.682 Thread-92: writing: bytesWritten=22822912 maxRead=1 minRead=0 maxWrite=17581 minWrite=0 numReads=135 numWrites=135 avgWrite=132 13:35:36.457 Thread-92: writing: bytesWritten=25051136 maxRead=1 minRead=0 maxWrite=25486 minWrite=0 numReads=136 numWrites=136 avgWrite=189 13:36:04.336 Thread-92: writing: bytesWritten=27328512 maxRead=1 minRead=0 maxWrite=27564 minWrite=0 numReads=139 numWrites=139 avgWrite=200 13:36:27.766 Thread-92: writing: bytesWritten=29540352 maxRead=1 minRead=0 maxWrite=23065 minWrite=0 numReads=135 numWrites=135 avgWrite=173 13:36:46.321 Thread-92: writing: bytesWritten=31752192 maxRead=1 minRead=0 maxWrite=18279 minWrite=0 numReads=135 numWrites=135 avgWrite=137 13:37:03.455 Thread-92: writing: bytesWritten=33980416 maxRead=1 minRead=0 maxWrite=16853 minWrite=0 numReads=136 numWrites=136 avgWrite=125 13:37:20.362 Thread-92: writing: bytesWritten=36192256 maxRead=1 minRead=0 maxWrite=16580 minWrite=0 numReads=135 numWrites=135 avgWrite=125 13:37:38.006 Thread-92: writing: bytesWritten=38404096 maxRead=1 minRead=0 maxWrite=17374 minWrite=0 numReads=135 numWrites=135 avgWrite=130 13:37:56.344 Thread-92: writing: bytesWritten=40681472 maxRead=1 minRead=0 maxWrite=18032 minWrite=0 numReads=139 numWrites=139 avgWrite=131 13:38:09.502 Thread-98: HTTPConnection: end of input stream data 13:38:09.503 Thread-98: HTTPConnection: closing Socket[addr=/192.168.1.89,port=60767,localport=9790] 13:38:09.503 Thread-98: HTTPService: removing connection org.jminim.lib.HTTPConnection@10e7d7b 13:38:09.504 Thread-97: HTTPConnection: writer thread interrupted 13:38:15.959 Thread-92: writing: bytesWritten=42893312 maxRead=0 minRead=0 maxWrite=19327 minWrite=0 numReads=135 numWrites=135 avgWrite=145 13:38:35.101 Thread-92: writing: bytesWritten=45121536 maxRead=1 minRead=0 maxWrite=18379 minWrite=0 numReads=136 numWrites=136 avgWrite=140 13:38:54.404 Thread-92: writing: bytesWritten=47333376 maxRead=1 minRead=0 maxWrite=19022 minWrite=0 numReads=135 numWrites=135 avgWrite=142 13:39:12.849 Thread-92: writing: bytesWritten=49545216 maxRead=1 minRead=0 maxWrite=18167 minWrite=0 numReads=135 numWrites=135 avgWrite=136 13:39:23.295 Thread-21: Log action: GetLogDataLength 13:39:23.295 Thread-21: version=1, adapter=192.168.1.101, uriPrefix=http://192.168.1.101:9791/71e50b8e-98a8-4a1f-ad8c-e26b122f89af/Upnp/resource/, clientEndpoint=192.168.1.60:54861 13:39:23.295 Thread-21: Log response: GetLogDataLength 13:39:23.295 Thread-21: DataLength=802819 13:39:31.766 Thread-92: writing: bytesWritten=51822592 maxRead=1 minRead=0 maxWrite=18583 minWrite=0 numReads=139 numWrites=139 avgWrite=136 13:39:49.985 Thread-92: writing: bytesWritten=54034432 maxRead=1 minRead=0 maxWrite=17963 minWrite=0 numReads=135 numWrites=135 avgWrite=134 13:40:07.889 Thread-92: writing: bytesWritten=56262656 maxRead=1 minRead=0 maxWrite=17627 minWrite=0 numReads=136 numWrites=136 avgWrite=131 13:40:27.298 Thread-92: writing: bytesWritten=58474496 maxRead=1 minRead=0 maxWrite=19165 minWrite=0 numReads=135 numWrites=135 avgWrite=143 13:40:44.002 Thread-92: writing: bytesWritten=60686336 maxRead=1 minRead=0 maxWrite=16459 minWrite=0 numReads=135 numWrites=135 avgWrite=123 13:41:01.834 Thread-92: writing: bytesWritten=62963712 maxRead=1 minRead=0 maxWrite=17531 minWrite=0 numReads=139 numWrites=139 avgWrite=128 13:41:01.866 Thread-96: FileResource: reader thread end of data 13:41:01.871 Thread-92: FileResource: writer thread end of data, bytesWritten=63404656 13:41:01.871 Thread-92: FileResource: close 13:41:01.871 Thread-92: ServerResourceBase: close 13:41:01.871 Thread-92: HTTPConnection: completed request; socket=Socket[addr=/192.168.1.22,port=50274,localport=9790], keepAlive=true 13:41:01.872 Thread-92: HTTPConnection: writer thread waiting for request 13:41:40.087 Thread-93: GET /minimserver/*/music/iTunes/Genesis/Selling*20England*20By*20The*20Pound/04*20More*20Fool*20Me.m4a HTTP/1.1 13:41:40.088 Thread-93: Host: 192.168.1.101, Connection: keep-alive, isTimeSeek: 0, User-Agent: stagefright/1.2 (Linux;Android , Accept-Encoding: gzip,deflate 13:41:40.088 Thread-93: HTTPConnection: reading HTTP request 13:41:40.088 Thread-92: HTTPConnection: writer thread processing request 13:41:40.102 Thread-92: HTTP/1.1 200 OK, Accept-Ranges: bytes, Date: Fri, 28 Dec 2018 21:41:40 GMT, Content-Length: 18319240, Content-Type: audio/mp4, Connection: keep-alive, Last-Modified: Sat, 15 Sep 2012 10:39:21 GMT 13:41:40.102 Thread-92: writing data: total=18319240 from file iTunes/Genesis/Selling England By The Pound/04 More Fool Me.m4a 13:41:40.102 Thread-92: writing 16384 bytes: total=18319240 13:41:40.103 Thread-92: writing 16384 bytes: total=18319240 13:41:40.103 Thread-92: writing 16384 bytes: total=18319240 13:41:40.104 Thread-92: writing 16384 bytes: total=18319240 13:41:40.104 Thread-92: writing 16384 bytes: total=18319240 13:41:40.104 Thread-92: writing 16384 bytes: total=18319240 13:41:40.105 Thread-92: writing 16384 bytes: total=18319240 13:42:07.363 Thread-92: writing: bytesWritten=5013504 maxRead=11 minRead=0 maxWrite=26700 minWrite=0 numReads=306 numWrites=306 avgWrite=89 13:42:32.731 Thread-92: writing: bytesWritten=7241728 maxRead=1 minRead=0 maxWrite=25089 minWrite=0 numReads=136 numWrites=136 avgWrite=186 13:42:57.494 Thread-92: writing: bytesWritten=9519104 maxRead=0 minRead=0 maxWrite=24115 minWrite=0 numReads=139 numWrites=139 avgWrite=178 13:43:15.716 Thread-92: writing: bytesWritten=11665408 maxRead=0 minRead=0 maxWrite=17934 minWrite=0 numReads=131 numWrites=131 avgWrite=139 13:43:40.763 Thread-92: writing: bytesWritten=13942784 maxRead=1 minRead=0 maxWrite=24721 minWrite=0 numReads=139 numWrites=139 avgWrite=180 13:44:03.281 Thread-92: writing: bytesWritten=16154624 maxRead=0 minRead=0 maxWrite=22229 minWrite=0 numReads=135 numWrites=135 avgWrite=166 13:44:03.543 Thread-100: FileResource: reader thread end of data 13:44:03.623 Thread-92: FileResource: writer thread end of data, bytesWritten=18319240 13:44:03.623 Thread-92: FileResource: close 13:44:03.623 Thread-92: ServerResourceBase: close 13:44:03.624 Thread-92: HTTPConnection: completed request; socket=Socket[addr=/192.168.1.22,port=50274,localport=9790], keepAlive=true 13:44:03.624 Thread-92: HTTPConnection: writer thread waiting for request 13:44:54.560 Thread-93: GET /minimserver/*/music/iTunes/Genesis/Selling*20England*20By*20The*20Pound/05*20The*20Battle*20Of*20Epping*20Forest.m4a HTTP/1.1 13:44:54.560 Thread-93: Host: 192.168.1.101, Connection: keep-alive, isTimeSeek: 0, User-Agent: stagefright/1.2 (Linux;Android , Accept-Encoding: gzip,deflate 13:44:54.560 Thread-93: HTTPConnection: reading HTTP request 13:44:54.561 Thread-92: HTTPConnection: writer thread processing request 13:44:54.565 Thread-92: HTTP/1.1 200 OK, Accept-Ranges: bytes, Date: Fri, 28 Dec 2018 21:44:54 GMT, Content-Length: 83361285, Content-Type: audio/mp4, Connection: keep-alive, Last-Modified: Sat, 15 Sep 2012 10:39:44 GMT 13:44:54.566 Thread-92: writing data: total=83361285 from file iTunes/Genesis/Selling England By The Pound/05 The Battle Of Epping Forest.m4a 13:44:54.567 Thread-92: writing 16384 bytes: total=83361285 13:44:54.567 Thread-92: writing 16384 bytes: total=83361285 13:44:54.568 Thread-92: writing 16384 bytes: total=83361285 13:44:54.568 Thread-92: writing 16384 bytes: total=83361285 13:44:54.568 Thread-92: writing 16384 bytes: total=83361285 13:44:54.569 Thread-92: writing 16384 bytes: total=83361285 13:44:54.569 Thread-92: writing 16384 bytes: total=83361285 13:45:27.720 Thread-92: writing: bytesWritten=5013504 maxRead=1 minRead=0 maxWrite=32627 minWrite=0 numReads=306 numWrites=306 avgWrite=108 13:45:50.259 Thread-92: writing: bytesWritten=7225344 maxRead=1 minRead=0 maxWrite=22266 minWrite=0 numReads=135 numWrites=135 avgWrite=166 13:46:12.384 Thread-92: writing: bytesWritten=9453568 maxRead=0 minRead=0 maxWrite=21871 minWrite=0 numReads=136 numWrites=136 avgWrite=162 13:46:29.905 Thread-92: writing: bytesWritten=11730944 maxRead=1 minRead=0 maxWrite=17152 minWrite=0 numReads=139 numWrites=139 avgWrite=126 13:46:47.248 Thread-92: writing: bytesWritten=13942784 maxRead=0 minRead=0 maxWrite=17045 minWrite=0 numReads=135 numWrites=135 avgWrite=128 13:47:04.575 Thread-92: writing: bytesWritten=16154624 maxRead=1 minRead=0 maxWrite=17032 minWrite=0 numReads=135 numWrites=135 avgWrite=128 13:47:22.099 Thread-92: writing: bytesWritten=18366464 maxRead=0 minRead=0 maxWrite=17226 minWrite=0 numReads=135 numWrites=135 avgWrite=129 13:47:40.135 Thread-92: writing: bytesWritten=20594688 maxRead=0 minRead=0 maxWrite=17722 minWrite=0 numReads=136 numWrites=136 avgWrite=132 13:47:58.768 Thread-92: writing: bytesWritten=22806528 maxRead=1 minRead=0 maxWrite=18365 minWrite=0 numReads=135 numWrites=135 avgWrite=138 13:48:17.311 Thread-92: writing: bytesWritten=25083904 maxRead=3 minRead=0 maxWrite=18208 minWrite=0 numReads=139 numWrites=139 avgWrite=133 13:48:34.270 Thread-92: writing: bytesWritten=27295744 maxRead=1 minRead=0 maxWrite=16667 minWrite=0 numReads=135 numWrites=135 avgWrite=125 13:48:51.873 Thread-92: writing: bytesWritten=29507584 maxRead=1 minRead=0 maxWrite=17360 minWrite=0 numReads=135 numWrites=135 avgWrite=130 13:49:12.007 Thread-92: writing: bytesWritten=31801344 maxRead=1 minRead=0 maxWrite=19764 minWrite=0 numReads=140 numWrites=140 avgWrite=143 13:49:30.125 Thread-92: writing: bytesWritten=34013184 maxRead=0 minRead=0 maxWrite=17792 minWrite=0 numReads=135 numWrites=135 avgWrite=134 13:49:48.084 Thread-92: writing: bytesWritten=36225024 maxRead=1 minRead=0 maxWrite=17641 minWrite=0 numReads=135 numWrites=135 avgWrite=133 13:50:01.742 Thread-21: Log action: GetLogDataLength 13:50:01.742 Thread-21: version=1, adapter=192.168.1.101, uriPrefix=http://192.168.1.101:9791/71e50b8e-98a8-4a1f-ad8c-e26b122f89af/Upnp/resource/, clientEndpoint=192.168.1.60:55305 13:50:01.742 Thread-21: Log response: GetLogDataLength 13:50:01.742 Thread-21: DataLength=810288 13:50:05.502 Thread-92: writing: bytesWritten=38436864 maxRead=1 minRead=0 maxWrite=17129 minWrite=0 numReads=135 numWrites=135 avgWrite=129 |
|||
29-12-2018, 08:47
Post: #2
|
|||
|
|||
RE: Song playback interruptions
It looks like there is a problem with the way the Hegel is reading and buffering the data. The "client" referred to in the message is the Hegel. The log messages show that the Hegel is reading a large chunk of data very quickly and then blocking the connection to MinimServer for a long time (sometimes over 20 seconds) before reading another large chunk. This isn't necessarily a problem if the client is managing its internal buffering correctly but it looks like it might be a factor in triggering the problem you are having with playback interruptions.
As a possible workaround for this client problem, you could try using MinimWatch to set the MinimServer serverOptions property to flowControl=2x (see this section). This forces the client to read the data in smaller chunks and this might prevent the Hegel from closing the connection prematurely and interrupting playback. |
|||
29-12-2018, 09:15
Post: #3
|
|||
|
|||
RE: Song playback interruptions
Thanks for the very quick reply. I'll give this a go tomorrow and report back. Thanks again.
|
|||
31-12-2018, 03:08
Post: #4
|
|||
|
|||
RE: Song playback interruptions
I'd say there had been some improvement. However, I still experienced a couple of random connection drops, although not right at the start of playing an album as has often been the case previously. I've attached a log file where a couple of tracks played just fine, followed by a random connection drop.
Does the log provide any further clue as to what might be happening, or suggest other potential solution(s)? Thanks again for your assistance. |
|||
31-12-2018, 08:07
Post: #5
|
|||
|
|||
RE: Song playback interruptions
It looks like flow control is working as intended until the client closes the connection and stops playback. You could try changing the flowControl setting from 2x to 3x to see if that helps.
|
|||
31-12-2018, 20:10
Post: #6
|
|||
|
|||
RE: Song playback interruptions
Great, thanks - I'll give that a try. Thanks again.
|
|||
« Next Oldest | Next Newest »
|
User(s) browsing this thread: 1 Guest(s)