Post Reply 
 
Thread Rating:
  • 0 Votes - 0 Average
  • 1
  • 2
  • 3
  • 4
  • 5
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
Find all posts by this user
Quote this message in a reply
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.
Find all posts by this user
Quote this message in a reply
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.
Find all posts by this user
Quote this message in a reply
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.


Attached File(s)
.pdf  log_5.pdf (Size: 299.15 KB / Downloads: 2)
Find all posts by this user
Quote this message in a reply
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.
Find all posts by this user
Quote this message in a reply
31-12-2018, 20:10
Post: #6
RE: Song playback interruptions
Great, thanks - I'll give that a try. Thanks again.
Find all posts by this user
Quote this message in a reply
Post Reply 


Forum Jump:


User(s) browsing this thread: 1 Guest(s)