Open eizedev opened 5 months ago
You will also find another log file from another user in the latest comment in the other issue
if you need further infos please let me know. I currently have not tested other audio formats or content length settings for airupnp.
Can you try 1.7.1 (it's not a release yet, you have to take the zip directly). I might have found an explanation but I'm not 100% sure and there might be side effects to the change I've made
thanks, ok, tested 1.7.1. Speakers are found, can be connected, playback starts but I do not hear anything. After about 1 minute it will switch back to the integrated speakers and the connection to the Sonos speaker is lost.
Tried with two different Sonos Play 1 speakers. Not using my config file, but still using -o
.
debug log in next comment.
Argh... got it thanks, that's the side effect I was referring to but I know what to do now. Will do that tomorrow, it's getting really late here
BTW, what is the airplay controller you are using?
BTW, what is the airplay controller you are using?
At the moment my MacBook Pro for playback. Same problem with iphone and ipad (didn't tested with 1.7.1).
Edit: On my iphone it does not connect at all (1.7.0), i can select the Speaker, it shows that the speaker is connected but it directly plays on my internal iphone speakers. But this could also be a problem on my phone, didn't test it for a while
edit2: ok, on the iphone of my wife i have the same behaviour than on my macbook (starting playback takes about 15 seconds). So on my phone this is another problem, not related to this issue
right... I've decided that it was time to rethink my approach to flush/first packet management (I did that a very long time ago and it was not pretty). I've refactored that in 1.8.0 (there will probably be a few iterations...) but let me know if it works better for you. On my iTunes (Win) and iPhone iOS 17.x it works now. They are very different way to handle this problem and my response was not ideal, to say the least.
grrr... had to do another build b/c I realized that freaking iOS sends every resend requested frame twice and also, contrary to before and to iTunes Windows, when paused, it does a flush and send start sending silence frame. iTunes, and others, and iOS previously, just send a flush and stopped sending anything. At least the good thing is that it forced me to do a broader validation.
Ok, thanks! Sounds good. I'll test the new version after breakfast :-)
Ok, tried 1.8.1. Same behavior as before, about 10-15 seconds delay. Tested on iPad Pro and MacBook Pro
/airupnp-linux-x86_64 -b 192.168.1.10:49159 -l 1000:2000 -o "<NULL>,S1,S3,S5,S9,S12,ZP80,ZP90,S15,ZP100,ZP120,1.0,LibreWireless,Fitzwilliam,2.2.6,AllShare1.0" -d all=debug
First Example:
Second Example:
Edit: Still don't know why airplay is not working for AirConnect connected speakers on my iPhone, "AirPlay2" native speakers are working, must be an issue on my phone. I have tried to reset iPhone network settings, same behavior. I think it is not related to the origin issue, but just to let you know. On my iPhone I only get this after connected to one speaker, but music will be played on local iPhone speakers. Nothing more will be logged (ok, the presence checking stuff for sure)
[10:19:30.454] rtsp_thread:333 got RTSP connection 21
[10:19:30.454] http_parse_simple:1030 sock: 21, received CSeq: 0
[10:19:30.454] http_parse_simple:1030 sock: 21, received DACP-ID: 9B65DD66E1378478
[10:19:30.454] http_parse_simple:1030 sock: 21, received Active-Remote: 3011166224
[10:19:30.455] http_parse_simple:1030 sock: 21, received User-Agent: AirPlay/750.14.1
Okay, there is something else then because it's only a few seconds for me on my iPhone 13 to my old Sonos PLAY:3
The only explanation I can have is a change of buffering strategy from sonos. They want to have a large amount of data before starting to stream
Thanks!
Hmm... strange. I will troubleshoot it a bit more and try to test if I can identify if it is related to the installation location (Synology NAS). Since more people at the Airconnect-Synology repository have the same problem, I do not think that this is a local network related problem (streaming to airplay devices natively supported is working).
Could it have something to do with the bitrate setting in the streaming clients? I always stream at the highest possible bit rate from players such as Apple Music, Spotify etc. (lossless format in the case of Apple Music). I know that airupnp logically converts this itself, but could it be that this is why it takes longer to start?
I have published 1.8.1 in my repo and get back to you after some more testers tried this version
edit; ok, same on any YouTube video when device is connected to play:1 speaker
Feedback from one tester: https://github.com/eizedev/AirConnect-Synology/issues/79#issuecomment-1900616473
I really have no idea then, except again a change of buffering policy from Sonos. As you know, upon devices rightfully like to buffer a serious amount of data before starting to play to absorb network hiccups. Sonos used to have a very low buffering. That does not work very well with AirPlay source because the source cannot send data in advance, as it is real time.
Maybe one test that can be done is to use aac or mp3 encoding and see if it changes something, as Sonos players will treat that stream as a webradio then and should buffer less.
But I'm quasi-sure it is not a networking issue like in router or configuration
I will give it a try. Thanks
updated my other issue with a comment to a workaround that is working now: https://github.com/eizedev/AirConnect-Synology/issues/79#issuecomment-1902082320
Hi @philippe44
This applies to versions from approximately 1.1.x. Last tests with 1.6.9 and 1.7.0.
i have a few users reporting issues in one issue in the AirConnect-Synology project about starting and (previously) stopping music that it takes very long time (10-15 seconds) until the music starts to play. We did a bit of troubleshooting but cannot recognize what's the problem. (Sonos update or airupnp update etc.).
Also something has changed in the last versions (don't know exactly from which version, didn't tried version between 1.2.x and 1.6.x), stopping playback only takes about 1 second, changing volume is instant but starting playback still takes 10-15 seconds.
Could you please check the issue linked above and the following log files from my devices if you can find any problems? I can reproduce the problem on my Synology NAS devices with the following startup command. The problem is the same with or without a config (config.xml) file for airupnp.
/volume1/@appstore/AirConnect/airupnp -b 192.168.1.10:49154 -l 1000:2000 -x "/volume1/@appstore/AirConnect/config.xml" -o "<NULL>,S1,S3,S5,S9,S12,ZP80,ZP90,S15,ZP100,ZP120,1.0,LibreWireless,Fitzwilliam,2.2.6,AllShare1.0" -z -f "/volume1/@appstore/AirConnect/log/airconnect.log" -d all=info
I was playing music on renderer "Flur" for a few seconds, changing volume and stopping playback in the logs below.
Thanks for your help, René
Logfiles
(the log lines without the milliseconds in the timestamp are from my package):
Click to expand - Log `1.7.0` for aircast an airupnp (log level all=info)
```log 2024-01-15T22:44:56+01:00 Magicbox [22:44:55] Starting AirConnect ... 2024-01-15T22:44:56+01:00 Magicbox [22:44:55] Linking airconnect configuration to "/volume1/airconnect/airconnect.conf". 2024-01-15T22:44:56+01:00 Magicbox [22:44:55] Linking airupnp configuration to "/volume1/airconnect/config.xml". 2024-01-15T22:44:56+01:00 Magicbox [22:44:55] Starting airupnp on 192.168.1.10:49154 2024-01-15T22:44:56+01:00 Magicbox [22:44:55.900] main:1386 Starting airupnp version: v1.7.0 (Jan 14 2024 @ 18:10:00) 2024-01-15T22:44:56+01:00 Magicbox [22:44:55] Linking aircast configuration to "/volume1/airconnect/config-cast.xml". 2024-01-15T22:44:56+01:00 Magicbox [22:44:55.903] Start:1097 Binding to iface 192.168.1.10:49154 [ovs_bond0] 2024-01-15T22:44:56+01:00 Magicbox [22:44:55.907] bind_socket:701 socket binding 7 on port 43391 2024-01-15T22:44:56+01:00 Magicbox [22:44:55] Starting aircast on 192.168.1.10 2024-01-15T22:44:56+01:00 Magicbox [22:44:55.923] Start:1136 Starting pico HTTP server on port 43391 2024-01-15T22:44:56+01:00 Magicbox [22:44:55.925] main:948 Starting aircast version: v1.7.0 (Jan 14 2024 @ 18:10:16) 2024-01-15T22:44:56+01:00 Magicbox [22:44:55.925] main:955 no config file, using defaults 2024-01-15T22:44:56+01:00 Magicbox [22:44:55.926] Start:708 Binding to 192.168.1.10 [ovs_bond0] with mask 0xffffff00 2024-01-15T22:44:56+01:00 Magicbox [22:44:55] AirConnect successfully started on ip "192.168.1.10"! 2024-01-15T22:44:56+01:00 Magicbox [22:44:55.932] bind_socket:701 socket binding 3 on port 39185 2024-01-15T22:44:56+01:00 Magicbox [22:44:55.932] Start:734 Starting pico HTTP server on port 39185 2024-01-15T22:44:56+01:00 Magicbox [22:44:55.988] AddMRDevice:1023 [0x7faa3159b010]: adding renderer (Büro) with mac BBBB1553729B 2024-01-15T22:44:56+01:00 Magicbox [22:44:55.988] bind_socket:701 socket binding 11 on port 55960 2024-01-15T22:44:56+01:00 Magicbox [22:44:56.000] MasterHandler:641 [0x7faa3159b010]: subscribe success 2024-01-15T22:44:56+01:00 Magicbox [22:44:56.042] AddMRDevice:1023 [0x7faa3159cfd0]: adding renderer (Küche) with mac BBBB18ECE22D 2024-01-15T22:44:56+01:00 Magicbox [22:44:56.042] bind_socket:701 socket binding 12 on port 35576 2024-01-15T22:44:56+01:00 Magicbox [22:44:56.054] ProcessEvent:450 [0x7faa3159b010]: UPnP Volume local change 7:7 (master) 2024-01-15T22:44:56+01:00 Magicbox [22:44:56.055] MasterHandler:641 [0x7faa3159cfd0]: subscribe success 2024-01-15T22:44:56+01:00 Magicbox [22:44:56.116] ProcessEvent:450 [0x7faa3159cfd0]: UPnP Volume local change 3:3 (master) 2024-01-15T22:44:56+01:00 Magicbox [22:44:56.304] AddCastDevice:658 [0x148bda0]: creating MAC 2024-01-15T22:44:56+01:00 Magicbox [22:44:56.304] AddCastDevice:672 [0x148bda0]: adding renderer (SHIELD - 192.168.1.51:8009) with mac CCCC85116FE6 2024-01-15T22:44:56+01:00 Magicbox [22:44:56.305] bind_socket:701 socket binding 10 on port 33943 2024-01-15T22:44:56+01:00 Magicbox [22:44:56.449] AddMRDevice:1023 [0x7faa3159ef90]: adding renderer (Flur) with mac BBBB30A98145 2024-01-15T22:44:56+01:00 Magicbox [22:44:56.449] bind_socket:701 socket binding 14 on port 60745 2024-01-15T22:44:56+01:00 Magicbox [22:44:56.457] MasterHandler:641 [0x7faa3159ef90]: subscribe success 2024-01-15T22:44:56+01:00 Magicbox [22:44:56.526] ProcessEvent:450 [0x7faa3159ef90]: UPnP Volume local change 54:54 (master) 2024-01-15T22:44:59+01:00 Magicbox [22:44:59.622] AddMRDevice:1023 [0x7faa315a0f50]: adding renderer (Kello_D4567C) with mac BBBB1FE1E692 2024-01-15T22:44:59+01:00 Magicbox [22:44:59.622] bind_socket:701 socket binding 15 on port 53381 2024-01-15T22:44:59+01:00 Magicbox [22:44:59.647] MasterHandler:641 [0x7faa315a0f50]: subscribe success 2024-01-15T22:45:00+01:00 Magicbox [22:45:00.155] ProcessEvent:450 [0x7faa315a0f50]: UPnP Volume local change 50:-1 (master) 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.851] rtsp_thread:333 got RTSP connection 13 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.857] handle_rtsp:380 [0x7faa18022e10]: challenge xzCjzjoGpECVzNMhvjYiWA== 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.868] handle_rtsp:374 [0x7faa18022e10]: received ANNOUNCE 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.878] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:06+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:06+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:06+01:00 Magicbox CSeq: 3 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.881] handle_rtsp:374 [0x7faa18022e10]: received SETUP 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.884] bind_socket:701 socket binding 18 on port 53119 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.884] raopst_init:265 [0x7faa04006c60]: UDP port-0 53119 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.884] bind_socket:701 socket binding 19 on port 51084 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.884] raopst_init:265 [0x7faa04006c60]: UDP port-1 51084 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.884] bind_socket:701 socket binding 20 on port 53260 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.884] raopst_init:265 [0x7faa04006c60]: UDP port-2 53260 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.885] bind_socket:701 socket binding 21 on port 55024 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.885] raopst_init:283 [0x7faa04006c60]: HTTP listening port 55024 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.885] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:06+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:06+01:00 Magicbox Transport: RTP/AVP/UDP;unicast;mode=record;control_port=51084;timing_port=53260;server_port=53119 2024-01-15T22:45:06+01:00 Magicbox Session: DEADBEEF 2024-01-15T22:45:06+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:06+01:00 Magicbox CSeq: 4 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.887] rtp_thread_func:726 [0x7faa04006c60]: 1st NTP packet received 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.888] handle_rtsp:374 [0x7faa18022e10]: received RECORD 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.888] raopst_record:381 [0x7faa04006c60]: record 0 0 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.888] HandleRAOP:288 [0x7faa3159ef90]: Stream 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.888] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:06+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:06+01:00 Magicbox Audio-Latency: 44100 2024-01-15T22:45:06+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:06+01:00 Magicbox CSeq: 5 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.892] rtp_thread_func:632 [0x7faa04006c60]: 1st RTP packet received 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.892] rtp_thread_func:638 [0x7faa04006c60]: 1st sync packet received 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.930] buffer_put_packet:517 [0x7faa04006c60]: fill [level:1] [W:8783 R:8783] 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.930] handle_rtsp:374 [0x7faa18022e10]: received FLUSH 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.931] raopst_flush:346 [0x7faa04006c60]: FLUSH ignored as same as RECORD (8783 - 3207506263) 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.931] raopst_flush:365 [0x7faa04006c60]: flush 8783 3207506263 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.931] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:06+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:06+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:06+01:00 Magicbox CSeq: 6 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.960] HandleRAOP:323 [0x7faa3159ef90]: uPNP setURI http://192.168.1.10:55024/stream-0.flac (cookie (nil)) 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.960] AVTSetURI:69 [0x7faa3159ef90]: uPNP setURI http://192.168.1.10:55024/stream-0.flac (cookie (nil)) 2024-01-15T22:45:06+01:00 Magicbox [22:45:05.960] AVTPlay:123 [0x7faa3159ef90]: uPNP play (cookie 0x1) 2024-01-15T22:45:06+01:00 Magicbox [22:45:06.013] http_thread_func:935 [0x7faa04006c60]: got HTTP connection 22 2024-01-15T22:45:06+01:00 Magicbox [22:45:06.015] handle_http:1076 [0x7faa04006c60]: received GET HTTP/1.1 2024-01-15T22:45:06+01:00 Magicbox CONNECTION: close 2024-01-15T22:45:06+01:00 Magicbox Host: 192.168.1.10:55024 2024-01-15T22:45:06+01:00 Magicbox ACCEPT: */* 2024-01-15T22:45:06+01:00 Magicbox USER-AGENT: Linux UPnP/1.0 Sonos/76.2-47270 (ZPS12) 2024-01-15T22:45:06+01:00 Magicbox X-Sonos-SWGen: 2 2024-01-15T22:45:06+01:00 Magicbox X-Sonos-Firmware: 76.2-47270 2024-01-15T22:45:06+01:00 Magicbox X-Sonos-Muse-Api: 1.37.1 2024-01-15T22:45:06+01:00 Magicbox X-Sonos-Id-Hash: qx3rchOJjALLQNaTnOkij1abkHulgsT0DmTWJ2OgRVs= 2024-01-15T22:45:06+01:00 Magicbox [22:45:06.015] handle_http:1121 [0x7faa04006c60]: responding: HTTP/1.0 200 OK 2024-01-15T22:45:06+01:00 Magicbox Server: HairTunes 2024-01-15T22:45:06+01:00 Magicbox Content-Type: audio/flac 2024-01-15T22:45:06+01:00 Magicbox Connection: close 2024-01-15T22:45:06+01:00 Magicbox [22:45:06.015] http_thread_func:958 [0x7faa04006c60]: sending 239 silence frames 2024-01-15T22:45:06+01:00 Magicbox [22:45:06.024] _buffer_get_frame:887 [0x7faa04006c60]: drain [level:13 gap:890] [W:8796 R:8783] [R:0 S:0 F:0] 2024-01-15T22:45:06+01:00 Magicbox [22:45:06.348] search_remote_cb:631 [0x7faa18022e10]: found ActiveRemote for 56A51C53CB77E71A at 192.168.1.96:62312 2024-01-15T22:45:06+01:00 Magicbox [22:45:06.454] ActionHandler:508 [0x7faa3159ef90]: uPNP transition 2024-01-15T22:45:07+01:00 Magicbox [22:45:07.666] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:07+01:00 Magicbox [22:45:07.666] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:07+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:07+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:07+01:00 Magicbox CSeq: 7 2024-01-15T22:45:07+01:00 Magicbox [22:45:07.672] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:07+01:00 Magicbox [22:45:07.672] handle_rtsp:559 [0x7faa18022e10]: received metadata 2024-01-15T22:45:07+01:00 Magicbox artist: 2024-01-15T22:45:07+01:00 Magicbox album: 2024-01-15T22:45:07+01:00 Magicbox title: 2024-01-15T22:45:07+01:00 Magicbox [22:45:07.672] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:07+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:07+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:07+01:00 Magicbox CSeq: 8 2024-01-15T22:45:14+01:00 Magicbox [22:45:14.090] buffer_put_packet:517 [0x7faa04006c60]: fill [level:3] [W:9807 R:9805] 2024-01-15T22:45:14+01:00 Magicbox [22:45:14.096] _buffer_get_frame:887 [0x7faa04006c60]: drain [level:0 gap:991] [W:9807 R:9807] [R:0 S:0 F:0] 2024-01-15T22:45:18+01:00 Magicbox [22:45:17.960] ActionHandler:517 [0x7faa3159ef90]: uPNP playing 2024-01-15T22:45:22+01:00 Magicbox [22:45:22.280] buffer_put_packet:517 [0x7faa04006c60]: fill [level:1] [W:10831 R:10831] 2024-01-15T22:45:22+01:00 Magicbox [22:45:22.294] _buffer_get_frame:887 [0x7faa04006c60]: drain [level:3 gap:962] [W:10834 R:10831] [R:0 S:0 F:0] 2024-01-15T22:45:26+01:00 Magicbox [22:45:26.225] handle_rtsp:374 [0x7faa18022e10]: received TEARDOWN 2024-01-15T22:45:26+01:00 Magicbox [22:45:26.225] HandleRAOP:293 [0x7faa3159ef90]: Stop 2024-01-15T22:45:26+01:00 Magicbox [22:45:26.225] AVTStop:180 [0x7faa3159ef90]: uPNP stop (cookie 0x1e) 2024-01-15T22:45:26+01:00 Magicbox [22:45:26.227] http_thread_func:1060 [0x7faa04006c60]: terminating 2024-01-15T22:45:26+01:00 Magicbox [22:45:26.261] rtp_thread_func:737 [0x7faa04006c60]: terminating 2024-01-15T22:45:26+01:00 Magicbox [22:45:26.262] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:26+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:26+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:26+01:00 Magicbox CSeq: 19 2024-01-15T22:45:26+01:00 Magicbox [22:45:26.264] http_read_line:1101 disconnected on the other end 13 2024-01-15T22:45:26+01:00 Magicbox [22:45:26.264] rtsp_thread:348 RTSP close 13 2024-01-15T22:45:27+01:00 Magicbox [22:45:26.972] ActionHandler:513 [0x7faa3159ef90]: uPNP stopped 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.191] rtsp_thread:333 got RTSP connection 13 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.198] handle_rtsp:380 [0x7faa18022e10]: challenge f/QOin9GPwvZf7FelGvoew== 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.209] handle_rtsp:374 [0x7faa18022e10]: received ANNOUNCE 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.220] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:36+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:36+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:36+01:00 Magicbox CSeq: 3 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.222] handle_rtsp:374 [0x7faa18022e10]: received SETUP 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.225] bind_socket:701 socket binding 18 on port 49899 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.225] raopst_init:265 [0x7faa04008000]: UDP port-0 49899 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.225] bind_socket:701 socket binding 19 on port 57599 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.225] raopst_init:265 [0x7faa04008000]: UDP port-1 57599 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.225] bind_socket:701 socket binding 20 on port 52656 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.225] raopst_init:265 [0x7faa04008000]: UDP port-2 52656 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.225] bind_socket:701 socket binding 21 on port 52523 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.225] raopst_init:283 [0x7faa04008000]: HTTP listening port 52523 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.225] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:36+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:36+01:00 Magicbox Transport: RTP/AVP/UDP;unicast;mode=record;control_port=57599;timing_port=52656;server_port=49899 2024-01-15T22:45:36+01:00 Magicbox Session: DEADBEEF 2024-01-15T22:45:36+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:36+01:00 Magicbox CSeq: 4 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.229] rtp_thread_func:726 [0x7faa04008000]: 1st NTP packet received 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.230] handle_rtsp:374 [0x7faa18022e10]: received RECORD 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.230] raopst_record:381 [0x7faa04008000]: record 0 0 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.230] HandleRAOP:288 [0x7faa3159ef90]: Stream 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.230] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:36+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:36+01:00 Magicbox Audio-Latency: 44100 2024-01-15T22:45:36+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:36+01:00 Magicbox CSeq: 5 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.233] rtp_thread_func:632 [0x7faa04008000]: 1st RTP packet received 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.233] rtp_thread_func:638 [0x7faa04008000]: 1st sync packet received 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.259] buffer_put_packet:517 [0x7faa04008000]: fill [level:1] [W:37010 R:37010] 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.260] handle_rtsp:374 [0x7faa18022e10]: received FLUSH 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.260] raopst_flush:346 [0x7faa04008000]: FLUSH ignored as same as RECORD (37010 - 1969306920) 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.260] raopst_flush:365 [0x7faa04008000]: flush 37010 1969306920 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.260] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:36+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:36+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:36+01:00 Magicbox CSeq: 6 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.499] HandleRAOP:323 [0x7faa3159ef90]: uPNP setURI http://192.168.1.10:52523/stream-1.flac (cookie 0x21) 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.500] AVTSetURI:69 [0x7faa3159ef90]: uPNP setURI http://192.168.1.10:52523/stream-1.flac (cookie 0x21) 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.517] search_remote_cb:631 [0x7faa18022e10]: found ActiveRemote for 56A51C53CB77E71A at 192.168.1.96:62312 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.541] AVTPlay:123 [0x7faa3159ef90]: uPNP play (cookie 0x22) 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.580] http_thread_func:935 [0x7faa04008000]: got HTTP connection 22 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.581] handle_http:1076 [0x7faa04008000]: received GET HTTP/1.1 2024-01-15T22:45:36+01:00 Magicbox CONNECTION: close 2024-01-15T22:45:36+01:00 Magicbox Host: 192.168.1.10:52523 2024-01-15T22:45:36+01:00 Magicbox ACCEPT: */* 2024-01-15T22:45:36+01:00 Magicbox USER-AGENT: Linux UPnP/1.0 Sonos/76.2-47270 (ZPS12) 2024-01-15T22:45:36+01:00 Magicbox X-Sonos-SWGen: 2 2024-01-15T22:45:36+01:00 Magicbox X-Sonos-Firmware: 76.2-47270 2024-01-15T22:45:36+01:00 Magicbox X-Sonos-Muse-Api: 1.37.1 2024-01-15T22:45:36+01:00 Magicbox X-Sonos-Id-Hash: qx3rchOJjALLQNaTnOkij1abkHulgsT0DmTWJ2OgRVs= 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.581] handle_http:1121 [0x7faa04008000]: responding: HTTP/1.0 200 OK 2024-01-15T22:45:36+01:00 Magicbox Server: HairTunes 2024-01-15T22:45:36+01:00 Magicbox Content-Type: audio/flac 2024-01-15T22:45:36+01:00 Magicbox Connection: close 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.581] http_thread_func:958 [0x7faa04008000]: sending 211 silence frames 2024-01-15T22:45:36+01:00 Magicbox [22:45:36.589] _buffer_get_frame:887 [0x7faa04008000]: drain [level:38 gap:665] [W:37048 R:37010] [R:0 S:0 F:0] 2024-01-15T22:45:37+01:00 Magicbox [22:45:37.475] ActionHandler:508 [0x7faa3159ef90]: uPNP transition 2024-01-15T22:45:38+01:00 Magicbox [22:45:38.090] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:38+01:00 Magicbox [22:45:38.090] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:38+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:38+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:38+01:00 Magicbox CSeq: 7 2024-01-15T22:45:38+01:00 Magicbox [22:45:38.095] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:38+01:00 Magicbox [22:45:38.095] handle_rtsp:559 [0x7faa18022e10]: received metadata 2024-01-15T22:45:38+01:00 Magicbox artist: 2024-01-15T22:45:38+01:00 Magicbox album: 2024-01-15T22:45:38+01:00 Magicbox title: 2024-01-15T22:45:38+01:00 Magicbox [22:45:38.095] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:38+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:38+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:38+01:00 Magicbox CSeq: 8 2024-01-15T22:45:44+01:00 Magicbox [22:45:44.449] buffer_put_packet:517 [0x7faa04008000]: fill [level:1] [W:38034 R:38034] 2024-01-15T22:45:44+01:00 Magicbox [22:45:44.463] _buffer_get_frame:887 [0x7faa04008000]: drain [level:3 gap:964] [W:38037 R:38034] [R:0 S:0 F:0] 2024-01-15T22:45:50+01:00 Magicbox [22:45:49.964] ActionHandler:517 [0x7faa3159ef90]: uPNP playing 2024-01-15T22:45:52+01:00 Magicbox [22:45:52.626] buffer_put_packet:517 [0x7faa04008000]: fill [level:5] [W:39056 R:39052] 2024-01-15T22:45:52+01:00 Magicbox [22:45:52.643] _buffer_get_frame:887 [0x7faa04008000]: drain [level:6 gap:958] [W:39064 R:39058] [R:2 S:0 F:0] 2024-01-15T22:45:53+01:00 Magicbox [22:45:53.661] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:53+01:00 Magicbox [22:45:53.661] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -14.822743 2024-01-15T22:45:53+01:00 Magicbox [22:45:53.661] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 51 (cookie 0x3a) 2024-01-15T22:45:53+01:00 Magicbox [22:45:53.661] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 50:54 2024-01-15T22:45:53+01:00 Magicbox [22:45:53.661] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:53+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:53+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:53+01:00 Magicbox CSeq: 17 2024-01-15T22:45:53+01:00 Magicbox [22:45:53.665] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:53+01:00 Magicbox [22:45:53.665] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -15.759811 2024-01-15T22:45:53+01:00 Magicbox [22:45:53.665] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 48 (cookie 0x3b) 2024-01-15T22:45:53+01:00 Magicbox [22:45:53.665] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 48:50 2024-01-15T22:45:53+01:00 Magicbox [22:45:53.665] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:53+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:53+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:53+01:00 Magicbox CSeq: 18 2024-01-15T22:45:53+01:00 Magicbox [22:45:53.684] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:53+01:00 Magicbox [22:45:53.684] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -16.714682 2024-01-15T22:45:53+01:00 Magicbox [22:45:53.684] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 44 (cookie 0x3c) 2024-01-15T22:45:53+01:00 Magicbox [22:45:53.684] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 44:48 2024-01-15T22:45:53+01:00 Magicbox [22:45:53.684] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:53+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:53+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:53+01:00 Magicbox CSeq: 19 2024-01-15T22:45:54+01:00 Magicbox [22:45:53.766] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:54+01:00 Magicbox [22:45:53.766] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -17.505583 2024-01-15T22:45:54+01:00 Magicbox [22:45:53.766] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 42 (cookie 0x3d) 2024-01-15T22:45:54+01:00 Magicbox [22:45:53.766] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 41:44 2024-01-15T22:45:54+01:00 Magicbox [22:45:53.766] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:54+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:54+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:54+01:00 Magicbox CSeq: 20 2024-01-15T22:45:54+01:00 Magicbox [22:45:54.119] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:54+01:00 Magicbox [22:45:54.119] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -18.074785 2024-01-15T22:45:54+01:00 Magicbox [22:45:54.119] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 41 (cookie 0x3e) 2024-01-15T22:45:54+01:00 Magicbox [22:45:54.119] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 40:41 2024-01-15T22:45:54+01:00 Magicbox [22:45:54.119] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:54+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:54+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:54+01:00 Magicbox CSeq: 21 2024-01-15T22:45:56+01:00 Magicbox [22:45:56.476] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:56+01:00 Magicbox [22:45:56.476] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -17.592163 2024-01-15T22:45:56+01:00 Magicbox [22:45:56.476] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 42 (cookie 0x44) 2024-01-15T22:45:56+01:00 Magicbox [22:45:56.477] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 42:40 2024-01-15T22:45:56+01:00 Magicbox [22:45:56.477] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:56+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:56+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:56+01:00 Magicbox CSeq: 24 2024-01-15T22:45:56+01:00 Magicbox [22:45:56.740] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:56+01:00 Magicbox [22:45:56.740] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -17.063776 2024-01-15T22:45:56+01:00 Magicbox [22:45:56.740] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 43 (cookie 0x45) 2024-01-15T22:45:56+01:00 Magicbox [22:45:56.740] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 43:42 2024-01-15T22:45:56+01:00 Magicbox [22:45:56.741] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:56+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:56+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:56+01:00 Magicbox CSeq: 25 2024-01-15T22:45:56+01:00 Magicbox [22:45:56.743] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:56+01:00 Magicbox [22:45:56.743] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -15.091171 2024-01-15T22:45:56+01:00 Magicbox [22:45:56.743] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 50 (cookie 0x46) 2024-01-15T22:45:56+01:00 Magicbox [22:45:56.743] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 49:43 2024-01-15T22:45:56+01:00 Magicbox [22:45:56.744] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:56+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:56+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:56+01:00 Magicbox CSeq: 26 2024-01-15T22:45:57+01:00 Magicbox [22:45:57.267] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:57+01:00 Magicbox [22:45:57.344] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -13.620427 2024-01-15T22:45:57+01:00 Magicbox [22:45:57.344] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 56 (cookie 0x47) 2024-01-15T22:45:57+01:00 Magicbox [22:45:57.345] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 55:49 2024-01-15T22:45:57+01:00 Magicbox [22:45:57.345] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:57+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:57+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:57+01:00 Magicbox CSeq: 27 2024-01-15T22:45:57+01:00 Magicbox [22:45:57.421] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:57+01:00 Magicbox [22:45:57.421] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -13.230821 2024-01-15T22:45:57+01:00 Magicbox [22:45:57.421] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 56 (cookie 0x48) 2024-01-15T22:45:57+01:00 Magicbox [22:45:57.421] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 56:55 2024-01-15T22:45:57+01:00 Magicbox [22:45:57.421] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:57+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:57+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:57+01:00 Magicbox CSeq: 28 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.789] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.789] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -13.845749 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.789] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 54 (cookie 0x4b) 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.789] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 54:56 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.789] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:58+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:58+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:58+01:00 Magicbox CSeq: 29 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.823] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.823] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -14.703038 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.823] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 51 (cookie 0x4c) 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.824] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 51:54 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.824] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:58+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:58+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:58+01:00 Magicbox CSeq: 30 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.865] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.865] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -15.424780 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.865] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 49 (cookie 0x4d) 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.865] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 48:51 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.865] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:58+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:58+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:58+01:00 Magicbox CSeq: 31 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.942] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.942] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -16.366806 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.942] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 46 (cookie 0x4e) 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.942] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 46:48 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.942] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:58+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:58+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:58+01:00 Magicbox CSeq: 32 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.988] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.989] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -16.978327 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.989] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 44 (cookie 0x4f) 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.989] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 43:46 2024-01-15T22:45:58+01:00 Magicbox [22:45:57.989] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:58+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:58+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:58+01:00 Magicbox CSeq: 33 2024-01-15T22:45:58+01:00 Magicbox [22:45:58.028] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:58+01:00 Magicbox [22:45:58.028] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -17.283873 2024-01-15T22:45:58+01:00 Magicbox [22:45:58.028] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 43 (cookie 0x50) 2024-01-15T22:45:58+01:00 Magicbox [22:45:58.028] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 43:43 2024-01-15T22:45:58+01:00 Magicbox [22:45:58.028] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:58+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:58+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:58+01:00 Magicbox CSeq: 34 2024-01-15T22:45:58+01:00 Magicbox [22:45:58.077] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:58+01:00 Magicbox [22:45:58.077] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -17.723236 2024-01-15T22:45:58+01:00 Magicbox [22:45:58.077] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 41 (cookie 0x51) 2024-01-15T22:45:58+01:00 Magicbox [22:45:58.078] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 40:43 2024-01-15T22:45:58+01:00 Magicbox [22:45:58.078] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:58+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:58+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:58+01:00 Magicbox CSeq: 35 2024-01-15T22:45:58+01:00 Magicbox [22:45:58.314] handle_rtsp:374 [0x7faa18022e10]: received SET_PARAMETER 2024-01-15T22:45:58+01:00 Magicbox [22:45:58.314] handle_rtsp:541 [0x7faa18022e10]: SET PARAMETER volume -18.337454 2024-01-15T22:45:58+01:00 Magicbox [22:45:58.314] CtrlSetVolume:205 [0x7faa3159ef90]: uPNP volume 40 (cookie 0x52) 2024-01-15T22:45:58+01:00 Magicbox [22:45:58.314] HandleRAOP:370 [0x7faa3159ef90]: Volume[0..100] 39:40 2024-01-15T22:45:58+01:00 Magicbox [22:45:58.314] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:45:58+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:45:58+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:45:58+01:00 Magicbox CSeq: 36 2024-01-15T22:46:01+01:00 Magicbox [22:46:00.941] buffer_put_packet:517 [0x7faa04008000]: fill [level:5] [W:40080 R:40076] 2024-01-15T22:46:01+01:00 Magicbox [22:46:00.951] _buffer_get_frame:887 [0x7faa04008000]: drain [level:21 gap:823] [W:40103 R:40082] [R:2 S:0 F:0] 2024-01-15T22:46:03+01:00 Magicbox [22:46:03.032] handle_rtsp:374 [0x7faa18022e10]: received TEARDOWN 2024-01-15T22:46:03+01:00 Magicbox [22:46:03.032] HandleRAOP:293 [0x7faa3159ef90]: Stop 2024-01-15T22:46:03+01:00 Magicbox [22:46:03.032] AVTStop:180 [0x7faa3159ef90]: uPNP stop (cookie 0x5b) 2024-01-15T22:46:03+01:00 Magicbox [22:46:03.033] rtp_thread_func:737 [0x7faa04008000]: terminating 2024-01-15T22:46:03+01:00 Magicbox [22:46:03.033] http_thread_func:1060 [0x7faa04008000]: terminating 2024-01-15T22:46:03+01:00 Magicbox [22:46:03.034] handle_rtsp:587 [0x7faa18022e10]: responding: 2024-01-15T22:46:03+01:00 Magicbox RTSP/1.0 200 OK 2024-01-15T22:46:03+01:00 Magicbox Audio-Jack-Status: connected; type=analog 2024-01-15T22:46:03+01:00 Magicbox CSeq: 40 2024-01-15T22:46:03+01:00 Magicbox [22:46:03.036] http_read_line:1101 disconnected on the other end 13 2024-01-15T22:46:03+01:00 Magicbox [22:46:03.036] rtsp_thread:348 RTSP close 13 2024-01-15T22:46:03+01:00 Magicbox [22:46:03.472] ActionHandler:513 [0x7faa3159ef90]: uPNP stopped ```Next log in next post (max. characters limit 65536...)