Incorrect header for PES ADTS stream

[ash.madden]

Hi,

I could not see where it says what version of MinimWatch I was running so I deleted it and reinstalled the latest version. As you correctly said, it then showed MinimStreamer greyed out in the packages. I then signed up for the trial license on the Melco and have installed MinimStreamer. I'll try the radio station later when I get the chance.

Meanwhile, I was just looking back at the Melco EX upgrade (which I paid for) and was under the impression it should have included the full version of MinimServer. Certainly that's what is showing on the comparison chart that Alan @ Melco sent me. I've pinged him for confirmation.

Your help is much appreciated,



Ash

[ash.madden]

Hi,

Turns out that I was mistaken. The EX upgrade included a full version of SongKong but not MinimServer. At least that's what Alan says and who am I to argue?

Anyway, I'll give the MininStreamer a spin later and of course, I'll buy a license if it works. It's a small price to pay for a great product.

Keep up the great work,



Ash

[simoncn]

It will work (or I will keep fixing it until it does).

[ash.madden]

Hi Simon,

Apologies for the slow response; I've had mixed results with the MinimStreamer but haven't had enough time to test or document the issues properly.

The gist is that I've tried five different radio stations with two different streamers. Results are as follows with the debug logs at the bottom. Typically, my favourite radio station was the least successful (Sun).

Your comments are most welcome,

1. FIP

#EXTM3U
#EXTINF:-1,[+FIP] FIP
https://stream.radiofrance.fr/fip/fip_hifi.m3u8

QNAP:
1.1: QNAP: [FIP] FIP: Good.
1.2: QNAP: FIP: Does not play.
1.3: Melco: [FIP] FIP: Good but something odd happens in the debug log after stopping it.
1.4: Melco: FIP: Does not play.

2. FIPjazz

#EXTM3U
#EXTINF:-1,[+FIPjazz] FIPjazz
https://stream.radiofrance.fr/fipjazz/fipjazz_hifi.m3u8

2.1: QNAP: [FIPjazz] FIPjazz: Good.
2.2: QNAP: FIPjazz: Does not play.
2.3: Melco: [FIPjazz] FIPjazz: Good
2.4: Melco: FIPjazz: Does not play.

3. Linn Classical

#EXTM3U
#EXTINF:-1,[+Linn] Linn Radio Classical
http://radio.linnrecords.com/cast/tunein...aylist.pls

3.1: QNAP: [Linn] Linn Radio Classical: Good
3.2: QNAP: Linn Radio Classical: Good
3.3: Melco: [Linn] Linn Radio Classical: Good
3.4: Melco: Linn Radio Classical: Good

4. RTE Lyric

#EXTM3U
#EXTINF:-1,[+RTE] RTE Lyric
https://www.rte.ie/manifests/lyric.m3u8

4.1: QNAP: [RTE] RTE Lyric: Good
4.2: QNAP: RTE Lyric: Started OK but stopped after a couple of minutes and would not restart.
4.3: Melco: [RTE] RTE Lyric: Good
4.4: Melco: RTE Lyric: Started OK but stopped after a couple of minutes. Nothing in debug log.

5. Sun

#EXTM3U
#EXTINF:-1,[+Sun] Le Son Unique a Nantes
http://diffusion.lafrap.fr:8000/sun.flac

5.1: QNAP: [Sun] Le Son Unique a Nantes: Started OK but stopped again.
5.2: QNAP: Le Son Unique a Nantes: As for 5.1
5.3: Melco: [Sun] Le Son Unique a Nantes: As for 5.1
5.4: Melco: Le Son Unique a Nantes: As for 5.1

Debug Logs

1.2 Debug log

18:35:49.353 Thread-11: Log action: SetLogStart
18:35:49.353 Thread-11: version=1, adapter=192.168.1.220, uriPrefix=http://192.168.1.220:9791/39b8e7f9-5251-4e01-a272-a9118c68d16a/Upnp/resource/, clientEndpoint=192.168.1.152:49434
18:35:49.353 Thread-11: StartPosition=84739171
18:35:49.362 Thread-11: Log action complete: SetLogStart
18:35:49.513 Thread-9: Log action: GetLogStart
18:35:49.513 Thread-9: version=1, adapter=192.168.1.220, uriPrefix=http://192.168.1.220:9791/39b8e7f9-5251-4e01-a272-a9118c68d16a/Upnp/resource/, clientEndpoint=192.168.1.152:49437
18:35:49.514 Thread-9: Log response: GetLogStart
18:35:49.514 Thread-9: StartPosition=84739171

1.3 Debug log

missing HLS media segment 182502
missing HLS media segment 182503
missing HLS media segment 182504
missing HLS media segment 182505
missing HLS media segment 182506
missing HLS media segment 182507
missing HLS media segment 182508
…
missing HLS media segment 197555
missing HLS media segment 197556
missing HLS media segment 197557
missing HLS media segment 197558
missing HLS media segment 197559
missing HLS media segment 197560
missing HLS media segment 197561
missing HLS media segment 197562
19:09:42.017 Thread-9929: adding media segment 197563
19:09:42.017 Thread-9929: adding media segment 197564
19:09:42.017 Thread-9929: adding media segment 197565
19:09:42.019 Thread-9929: adding media segment 197566
19:09:42.019 Thread-9929: adding media segment 197567
19:09:42.019 Thread-9929: adding media segment 197568
19:09:42.020 Thread-9929: adding media segment 197569
19:09:42.020 Thread-9929: adding media segment 197570
19:09:42.020 Thread-9929: adding media segment 197571
Chain: error starting chain
java.lang.StringIndexOutOfBoundsException: index 0, length 0
at java.base/java.lang.String.checkIndex(String.java:3715)
at java.base/java.lang.StringUTF16.checkIndex(StringUTF16.java:1587)
at java.base/java.lang.StringUTF16.charAt(StringUTF16.java:1384)
at java.base/java.lang.String.charAt(String.java:713)
at com.minimstreamer.NetworkSource$HLSLoaderThread.loadMediaPlaylist(NetworkSource.​java:2469)
at com.minimstreamer.NetworkSource$HLSLoaderThread.init(NetworkSource.java:2275)
at com.minimstreamer.NetworkSource$HLSReaderThread.initHLS(NetworkSource.java:1750)​
at com.minimstreamer.NetworkSource.doStart(NetworkSource.java:306)
at com.minimstreamer.NetworkSource.start(NetworkSource.java:150)
at org.jstreamer.core.ChainSource.start(ChainSource.java:47)
at org.jstreamer.core.ChainImpl.startLinks(ChainImpl.java:79)
at org.jstreamer.core.ChainImpl.start(ChainImpl.java:55)
at com.minimstreamer.Streamer$NetworkRequestHandler.processRequest(Streamer.java:31​16)
at com.minimserver.Server$StreamerRequestHandler.processRequest(Server.java:25868)
at org.jminim.lib.HTTPConnection$WriterThread.runWriterThread(HTTPConnection.java:4​75)
at org.jminim.lib.HTTPConnection$WriterThread.run(HTTPConnection.java:430)
19:09:42.025 Thread-9929: ChainImpl: stop org.jstreamer.core.ChainImpl@1339d41
19:09:42.025 Thread-9929: ChainImpl: stopping chain org.jstreamer.core.ChainImpl@1339d41
19:09:42.026 Thread-9929: Buffer: closing buffer: org.jstreamer.core.Buffer@178a9b6
19:09:42.026 Thread-9929: ChainSource: stopping source
19:09:42.026 Thread-9929: NetworkSource: terminating reader thread
19:09:42.027 Thread-9929: NetworkSource.ReaderThread: thread terminated
19:09:42.028 Thread-9929: NetworkSource.HLSReaderThread: terminating loader thread
19:09:42.028 Thread-9929: NetworkSource.HLSLoaderThread: thread terminated
19:09:42.029 Thread-9929: NetworkSource: closing outgoing socket Socket[addr=stream.radiofrance.fr/90.244.156.138,port=443,localport=35135]
19:09:42.029 Thread-9929: ChainSource: source stopped
19:09:42.029 Thread-9929: TargetChain: stop
19:09:42.029 Thread-9929: TargetChain: stopping subchain
19:09:42.030 Thread-9929: ChainImpl: stop org.jstreamer.core.ChainImpl@15d34ed
19:09:42.030 Thread-9929: ChainImpl: removing target from org.jstreamer.core.TargetChain@18a08b6
19:09:42.031 Thread-9929: ChainImpl: stop org.jstreamer.core.ChainImpl@1339d41
19:09:42.032 Thread-9929: ChainImpl: stopping chain org.jstreamer.core.ChainImpl@15d34ed
19:09:42.032 Thread-9929: ChainTarget: stop
19:09:42.032 Thread-9929: ChainTarget: stopping target
19:09:42.033 Thread-9929: HTTPRequestTarget: stop this=com.minimstreamer.HTTPRequestTarget@1a43c0b outStr=com.minimstreamer.HTTPRequestTarget$ProxyOutputStream@6e8b68
19:09:42.033 Thread-9929: ChainImpl: getting property request.responseMessage
19:09:42.033 Thread-9929: ChainImpl: property request.responseMessage has initial value '$/source.responseMessage'
19:09:42.034 Thread-9929: ChainImpl: property source.responseMessage has null value
19:09:42.034 Thread-9929: ChainImpl: substitution property /source.responseMessage has value ''
19:09:42.034 Thread-9929: ChainImpl: property request.responseMessage has final value ''
19:09:42.035 Thread-9929: ChainImpl: getting property request.status
19:09:42.035 Thread-9929: ChainImpl: property request.status has initial value '$/source.status'
19:09:42.035 Thread-9929: ChainImpl: property source.status has literal value 'HTTP/1.1 200 OK
'
19:09:42.036 Thread-9929: ChainImpl: substitution property /source.status has value 'HTTP/1.1 200 OK
'
19:09:42.038 Thread-9929: ChainImpl: property request.status has final value 'HTTP/1.1 200 OK
'
19:09:42.039 Thread-9929: ChainImpl: property obj request.@http is reference to .@http
19:09:42.039 Thread-9929: ChainImpl: property object request.@http has value org.jminim.lib.HTTPContext@1f8d9b4
19:09:42.040 Thread-9929: HTTP/1.1 500 Internal Server Error, Date: Sun, 07 Mar 2021 19:09:42 GMT, Connection: close, Content-Length: 0, Content-Type: text/plain; charset=utf-8
19:09:42.041 Thread-9929: HTTPConnection: stopping connection
19:09:42.041 Thread-9929: Streamer: getting request chain for org.jminim.lib.HTTPConnection$Request@e3b273
19:09:42.041 Thread-9929: Streamer: stopping chain org.jstreamer.core.ChainImpl@15d34ed
19:09:42.042 Thread-9929: ChainImpl: stop org.jstreamer.core.ChainImpl@15d34ed
19:09:42.042 Thread-9929: Streamer: closing Socket[addr=/192.168.1.205,port=57137,localport=9790]
19:09:42.043 Thread-9929: HTTPService: removing connection org.jminim.lib.HTTPConnection@1981061
19:09:42.046 Thread-9929: HTTPRequestTarget: closeSocket
19:09:42.046 Thread-9929: HTTPRequestTarget: closing Socket[addr=/192.168.1.205,port=57137,localport=9790]
19:09:42.046 Thread-9929: HTTPRequestTarget: notifyStopped com.minimstreamer.Streamer$NotifyStopped@fbe361
19:09:42.047 Thread-9929: Streamer.NotifyStopped: run
19:09:42.047 Thread-9929: ChainTarget: target stopped
19:09:42.047 Thread-9929: ChainImpl: stopped chain org.jstreamer.core.ChainImpl@15d34ed
19:09:42.048 Thread-9929: TargetChain: subchain stopped
19:09:42.049 Thread-9929: ChainImpl: stopped chain org.jstreamer.core.ChainImpl@1339d41
19:09:42.050 Thread-9929: Streamer: removing request chain for org.jminim.lib.HTTPConnection$Request@e3b273
19:09:42.050 Thread-9929: Streamer: completed network request
19:09:42.050 Thread-9929: HTTPConnection: completed request; socket=Socket[addr=/192.168.1.205,port=57137,localport=9790], keepAlive=false
19:09:42.051 Thread-9929: HTTPConnection: writer thread interrupted
19:09:42.051 Thread-9929: HTTPConnection: stopping connection

2.2 Debug log

18:41:16.853 Thread-1690: HTTPConnection: connection closed by client
18:41:16.854 Thread-1693: BufferReader: writeStream caught IOException: buffer=org.jstreamer.core.Buffer@4ea0f40f
18:41:16.854 Thread-1690: HTTPConnection: requestStop
18:41:16.854 Thread-1690: HTTPConnection: stopping connection
18:41:16.854 Thread-1690: Streamer: getting request chain for org.jminim.lib.HTTPConnection$Request@30217ee4
18:41:16.854 Thread-1690: Streamer: stopping chain org.jstreamer.core.ChainImpl@2f53efd4
18:41:16.854 Thread-1690: ChainImpl: stop org.jstreamer.core.ChainImpl@2f53efd4
18:41:16.854 Thread-1690: ChainImpl: removing target from org.jstreamer.core.TargetChain@7ebd8943
18:41:16.854 Thread-1693: ChainTarget: socket connection ended
18:41:16.854 Thread-1693: ChainImpl: stop org.jstreamer.core.ChainImpl@2f53efd4
18:41:16.854 Thread-1690: ChainImpl: stop org.jstreamer.core.ChainImpl@1e422d92
18:41:16.854 Thread-1690: ChainImpl: stopping chain org.jstreamer.core.ChainImpl@1e422d92
18:41:16.854 Thread-1690: ChainSource: stopping reader thread
18:41:16.854 Thread-1690: ChainSource: stopping source
18:41:16.854 Thread-1690: NetworkSource: terminating reader thread
18:41:16.854 Thread-1691: NetworkSource.ReaderThread has been interrupted
18:41:16.855 Thread-1691: NetworkSource: closing piped output stream
18:41:16.855 Thread-1694: Buffer: closing buffer: org.jstreamer.core.Buffer@27b528a4
18:41:16.855 Thread-1693: BufferReader: fillBuffer returned -1: buffer=org.jstreamer.core.Buffer@27b528a4
18:41:16.855 Thread-1693: ChainTarget: end of data from input stream
18:41:16.855 Thread-1693: ChainImpl: stop org.jstreamer.core.ChainImpl@2f53efd4
18:41:16.855 Thread-1693: BufferPoolReader: close
18:41:16.855 Thread-1693: BufferReader: close: buffer=org.jstreamer.core.Buffer@4ea0f40f
18:41:16.855 Thread-1693: Buffer: removeReader: org.jstreamer.core.Buffer@4ea0f40f
18:41:16.855 Thread-1693: Buffer: removeReaders calling notifyAll: org.jstreamer.core.Buffer@4ea0f40f
18:41:16.855 Thread-1693: BufferReader: close: buffer=org.jstreamer.core.Buffer@27b528a4
18:41:16.855 Thread-1693: Buffer: removeReader: org.jstreamer.core.Buffer@27b528a4
18:41:16.855 Thread-1693: Buffer: removeReaders calling notifyAll: org.jstreamer.core.Buffer@27b528a4
18:41:16.855 Thread-1690: NetworkSource.ReaderThread: thread terminated
18:41:16.855 Thread-1690: NetworkSource.HLSReaderThread: terminating loader thread
18:41:16.856 Thread-1692: SegmentBuffer.waitLimit caught InterruptedException
18:41:16.857 Thread-1692: NetworkSource.HLSLoaderThread has been interrupted
18:41:16.859 Thread-1690: NetworkSource.HLSLoaderThread: thread terminated
18:41:16.859 Thread-1690: NetworkSource: closing outgoing socket 47f45ce5[TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256: Socket[addr=stream.radiofrance.fr/90.244.156.152,port=443,localport=51788]]
18:41:16.859 Thread-1690: ChainSource: source stopped
18:41:16.859 Thread-1690: TargetChain: stop
18:41:16.859 Thread-1690: TargetChain: stopping subchain
18:41:16.859 Thread-1690: ChainImpl: stop org.jstreamer.core.ChainImpl@2f53efd4
18:41:16.859 Thread-1690: TargetChain: subchain stopped
18:41:16.859 Thread-1690: ChainImpl: stopped chain org.jstreamer.core.ChainImpl@1e422d92
18:41:16.859 Thread-1690: ChainImpl: stopping chain org.jstreamer.core.ChainImpl@2f53efd4
18:41:16.859 Thread-1690: ChainTarget: stop
18:41:16.859 Thread-1690: ChainTarget: stopping target
18:41:16.859 Thread-1690: HTTPRequestTarget: stop this=com.minimstreamer.HTTPRequestTarget@65de2aff outStr=com.minimstreamer.HTTPRequestTarget$ProxyOutputStream@e3dd1a1
18:41:16.860 Thread-1690: HTTPRequestTarget: closeSocket
18:41:16.860 Thread-1690: HTTPRequestTarget: closing Socket[addr=/192.168.1.205,port=55835,localport=9790]
18:41:16.860 Thread-1690: HTTPRequestTarget: notifyStopped com.minimstreamer.Streamer$NotifyStopped@7d3289c6
18:41:16.860 Thread-1690: Streamer.NotifyStopped: run
18:41:16.860 Thread-1690: ChainTarget: target stopped
18:41:16.860 Thread-1690: ChainImpl: stopped chain org.jstreamer.core.ChainImpl@2f53efd4
18:41:16.860 Thread-1690: HTTPService: removing connection org.jminim.lib.HTTPConnection@4b946fdf
18:41:16.860 Thread-1689: NetworkRequestHandler: thread interrupted
18:41:16.860 Thread-1689: Streamer: getting request chain for org.jminim.lib.HTTPConnection$Request@30217ee4
18:41:16.860 Thread-1689: ChainImpl: stop org.jstreamer.core.ChainImpl@2f53efd4
18:41:16.860 Thread-1689: Streamer: removing request chain for org.jminim.lib.HTTPConnection$Request@30217ee4
18:41:16.860 Thread-1689: Streamer: completed network request
18:41:16.860 Thread-1689: HTTPConnection: completed request; socket=Socket[addr=/192.168.1.205,port=55835,localport=9790], keepAlive=false
18:41:16.860 Thread-1689: HTTPConnection: writer thread interrupted
18:41:16.860 Thread-1689: HTTPConnection: stopping connection

4.2 Debug Log

18:52:03.818 Thread-12: Log action: SetLogStart
18:52:03.818 Thread-12: version=1, adapter=192.168.1.220, uriPrefix=http://192.168.1.220:9791/39b8e7f9-5251-4e01-a272-a9118c68d16a/Upnp/resource/, clientEndpoint=192.168.1.152:50524
18:52:03.818 Thread-12: StartPosition=85252583
18:52:03.818 Thread-12: Log action complete: SetLogStart
18:52:03.865 Thread-11: Log action: GetLogStart
18:52:03.865 Thread-11: version=1, adapter=192.168.1.220, uriPrefix=http://192.168.1.220:9791/39b8e7f9-5251-4e01-a272-a9118c68d16a/Upnp/resource/, clientEndpoint=192.168.1.152:50526
18:52:03.865 Thread-11: Log response: GetLogStart
18:52:03.865 Thread-11: StartPosition=85252583
18:53:49.599 Thread-10: Log action: GetLogDataLength
18:53:49.599 Thread-10: version=1, adapter=192.168.1.220, uriPrefix=http://192.168.1.220:9791/39b8e7f9-5251-4e01-a272-a9118c68d16a/Upnp/resource/, clientEndpoint=192.168.1.152:50583
18:53:49.600 Thread-10: Log response: GetLogDataLength
18:53:49.600 Thread-10: DataLength=85253484

5.1 Debug Log

20:11:27.385 Thread-1815: writing: bytesWritten=28426406
20:11:29.519 Thread-1815: writing: bytesWritten=28834633
20:11:31.568 Thread-1815: writing: bytesWritten=29228325
20:11:33.706 Thread-1815: writing: bytesWritten=29637075
20:11:33.956 Thread-10: Log action: GetLogDataLength
20:11:33.956 Thread-10: version=1, adapter=192.168.1.220, uriPrefix=http://192.168.1.220:9791/39b8e7f9-5251-4e01-a272-a9118c68d16a/Upnp/resource/, clientEndpoint=192.168.1.152:56600
20:11:33.957 Thread-10: Log response: GetLogDataLength
20:11:33.957 Thread-10: DataLength=105721491
20:11:35.752 Thread-1815: writing: bytesWritten=30033404
20:11:37.881 Thread-1815: writing: bytesWritten=30440698

5.2 Debug log

Nothing in log.

5.3 Debug log

20:27:34.308 Thread-10118: writing: bytesWritten=20886063
20:27:36.310 Thread-10118: writing: bytesWritten=21185283
20:27:38.574 Thread-10118: writing: bytesWritten=21464767
20:27:40.621 Thread-10118: writing: bytesWritten=21756605
20:27:42.672 Thread-10118: writing: bytesWritten=22053756
20:27:44.113 Thread-501: Log action: GetLogDataLength
20:27:44.113 Thread-501: version=1, adapter=192.168.1.222, uriPrefix=http://192.168.1.222:9791/caa8c74f-1a74-4d78-a1df-adb993fcd9e6/Upnp/resource/, clientEndpoint=192.168.1.152:57411
20:27:44.114 Thread-501: Log response: GetLogDataLength
20:27:44.114 Thread-501: DataLength=411813444
20:27:44.718 Thread-10118: writing: bytesWritten=22362569
20:27:46.765 Thread-10118: writing: bytesWritten=22663765
20:27:48.815 Thread-10118: writing: bytesWritten=22991921

5.4 Debug log

20:35:18.633 Thread-72: Log action: GetLogDataLength
20:35:18.633 Thread-72: version=1, adapter=192.168.1.222, uriPrefix=http://192.168.1.222:9791/caa8c74f-1a74-4d78-a1df-adb993fcd9e6/Upnp/resource/, clientEndpoint=192.168.1.152:57957
20:35:18.634 Thread-72: Log response: GetLogDataLength
20:35:18.634 Thread-72: DataLength=411922885
20:36:24.290 Thread-72: Log action: GetLogDataLength
20:36:24.290 Thread-72: version=1, adapter=192.168.1.222, uriPrefix=http://192.168.1.222:9791/caa8c74f-1a74-4d78-a1df-adb993fcd9e6/Upnp/resource/, clientEndpoint=192.168.1.152:57993
20:36:24.291 Thread-72: Log response: GetLogDataLength
20:36:24.291 Thread-72: DataLength=411923222
20:36:48.161 Thread-74: Log action: GetLogDataLength
20:36:48.161 Thread-74: version=1, adapter=192.168.1.222, uriPrefix=http://192.168.1.222:9791/caa8c74f-1a74-4d78-a1df-adb993fcd9e6/Upnp/resource/, clientEndpoint=192.168.1.152:58003
20:36:48.162 Thread-74: Log response: GetLogDataLength
20:36:48.162 Thread-74: DataLength=411923559
20:37:29.723 Thread-73: Log action: GetLogDataLength
20:37:29.723 Thread-73: version=1, adapter=192.168.1.222, uriPrefix=http://192.168.1.222:9791/caa8c74f-1a74-4d78-a1df-adb993fcd9e6/Upnp/resource/, clientEndpoint=192.168.1.152:58061
20:37:29.724 Thread-73: Log response: GetLogDataLength
20:37:29.724 Thread-73: DataLength=411923896

[simoncn]

Thanks for this.

The problems with 1.2, 1.4, 2.2, 2.4, 4.2 and 4.4 are caused by not routing the stream through MinimStreamer. In almost all cases, it is not useful to use '+' to create a second stream. This can occasionally be useful for debugging if the primary stream (routed through MinimStreamer) is not working correctly.

1.3 is caused by a bug in MinimStreamer. It will be fixed in the next MinimStreamer update.

I will look at 5.1 and post back here when I have more information.

[simoncn]

I tried 5.1 and the stream stopped playing after a few minutes with a network timeout error in the log (no response from the server within 3 seconds).

I tried again a few minutes later and the stream played for a long time without stopping.

The timeout could be caused by network delays or an overloaded stream server. Is it better if you try at a time when not many users are likely to be active?

[simoncn]

(09-03-2021 20:58)simoncn Wrote:  1.3 is caused by a bug in MinimStreamer. It will be fixed in the next MinimStreamer update.

This fix is now available in MinimStreamer 2.0.7.