search

karlheyes / icecast-kh

KH branch of icecast
GNU General Public License v2.0
298 stars 105 forks source link

kh-16: The server does not respond after reloading the config file #378

Open DroidU opened 1 year ago

DroidU commented 1 year ago

If the following settings are included in the config file, it works perfectly after startup. However, if I reload the config file, the port remains open, but Icecast is not responding. Unfortunately, if the loglevel is set to level 4, when the error appears, it does not contain anything unusual.

Config file: https://pastebin.com/bFxzrzfH

karlheyes commented 1 year ago

can you send me the error log level 4 to me, privately if need be

karl

gunsar commented 1 year ago

can you send me the error log level 4 to me, privately if need be

karl

Please wait... i will reinstall back to icecat-kh master version

gunsar commented 1 year ago

[2023-01-13 21:21:14] INFO slave/start_relay_stream source /rockradio is ready to start [2023-01-13 21:21:14] INFO mpeg/check_for_aac Detected AAC MPEG-4, 22050Hz 2 channel(s) 64 kbps on xxx.xxx.xxx.xxx [2023-01-13 21:21:14] INFO source/source_set_override no override for /stream set [2023-01-13 21:21:14] INFO source/source_read listener count on /rockradio now 0 [2023-01-13 21:21:15] INFO mpeg/check_for_aac Detected AAC MPEG-4, 22050Hz 2 channel(s) 64 kbps on xxx.xxx.xxx.xxx [2023-01-13 21:21:15] INFO source/source_set_override no override for /rockradio set [2023-01-13 21:21:15] WARN format-mp3/validate_mpeg source /rockradio, len 1400, unprocessed 1400 [2023-01-13 21:21:15] INFO mpeg/check_for_aac Detected AAC MPEG-4, 22050Hz 2 channel(s) 64 kbps on xxx.xxx.xxx.xxx [2023-01-13 21:21:15] INFO util/cached_file_recheck 1 entries read from file "blockip" [2023-01-13 21:21:15] INFO util/cached_file_recheck 3 entries read from file "blockagent" [2023-01-13 21:21:15] INFO source/source_add_listener max on /stream is -1 (cur 0) [2023-01-13 21:21:15] INFO source/source_read listener count on /stream now 1 [2023-01-13 21:21:19] INFO source/source_add_listener max on /rockradio is -1 (cur 0) [2023-01-13 21:21:19] INFO source/source_add_listener max on /stream is -1 (cur 1) [2023-01-13 21:21:19] INFO source/source_read listener count on /rockradio now 1 [2023-01-13 21:21:19] INFO source/source_read listener count on /stream now 2 [2023-01-13 21:21:27] INFO source/source_add_listener max on /stream is -1 (cur 2) [2023-01-13 21:21:27] INFO source/source_read listener count on /stream now 3 [2023-01-13 21:21:27] INFO source/source_read listener count on /stream now 2 [2023-01-13 21:21:34] INFO source/source_read listener count on /stream now 1 [2023-01-13 21:21:37] WARN format-mp3/validate_mpeg source /rockradio, len 1400, unprocessed 1400 [2023-01-13 21:21:37] WARN format-mp3/validate_mpeg source /rockradio, len 1400, unprocessed 1400 [2023-01-13 21:21:37] WARN format-mp3/validate_mpeg source /rockradio, len 1400, unprocessed 2400 [2023-01-13 21:21:37] WARN format-mp3/validate_mpeg source /rockradio, len 1400, unprocessed 3400 [2023-01-13 21:21:37] WARN format-mp3/validate_mpeg source /rockradio, len 1400, unprocessed 4400 [2023-01-13 21:21:37] WARN format-mp3/validate_mpeg source /rockradio, len 1400, unprocessed 5400 [2023-01-13 21:21:38] INFO mpeg/check_for_aac Detected AAC MPEG-4, 22050Hz 2 channel(s) 64 kbps on xxx.xxx.xxx.xxx [2023-01-13 21:21:44] INFO source/source_add_listener max on /stream is -1 (cur 1) [2023-01-13 21:21:44] INFO source/source_read listener count on /stream now 2 [2023-01-13 21:21:49] WARN format-mp3/validate_mpeg source /rockradio, len 1400, unprocessed 1400 [2023-01-13 21:21:49] WARN format-mp3/validate_mpeg source /rockradio, len 1400, unprocessed 2400 [2023-01-13 21:21:49] WARN format-mp3/validate_mpeg source /rockradio, len 1400, unprocessed 2340 [2023-01-13 21:21:49] WARN format-mp3/validate_mpeg source /rockradio, len 1400, unprocessed 3340 [2023-01-13 21:21:49] WARN format-mp3/validate_mpeg source /rockradio, len 1400, unprocessed 4340 [2023-01-13 21:21:49] WARN format-mp3/validate_mpeg source /rockradio, len 1400, unprocessed 4095 [2023-01-13 21:21:49] WARN format-mp3/validate_mpeg source /rockradio, len 1400, unp

<relay> <server>xxx.xx.x.xx</server> <port>8000</port> <mount>/stream</mount> <local-mount>/stream</local-mount> <username>source</username> <password>xxxxxxx</password> <relay-shoutcast-metadata>1</relay-shoutcast-metadata> </relay>

<relay> <server>xxx.xx.x.xx</server> <port>8000</port> <mount>/stream</mount> <local-mount>/rockradio</local-mount> <username>source</username> <password>xxxxxxx</password> <relay-shoutcast-metadata>1</relay-shoutcast-metadata> </relay>

karlheyes commented 1 year ago

not level 4 but I don't see resync type messages. The unprocessed messages are annoying though, could do with checking that. Is the stream public?

jjkbssn commented 1 year ago

Running icecast-2.4.0-kh17-pre3_win64_setup.exe

Something is wrong with the response headers. Content-Lenght is limited to 4000 and every page is truncated.

image

gunsar commented 1 year ago

@karlheyes

<mount> <mount-name>/stream</mount-name> <charset>UTF-8</charset> <stream-name>xxxxxxxxx</stream-name> <stream-description>xxxxxxx</stream-description> <stream-url>https://xxxxxxxxx</stream-url> <genre>Pop</genre> <public>0</public> <bitrate>64</bitrate> </mount>

<mount> <mount-name>/rockradio</mount-name> <charset>UTF-8</charset> <stream-name>xxxxxxxxx</stream-name> <stream-description>xxxxxxx</stream-description> <stream-url>https://xxxxxxxxx</stream-url> <genre>Pop</genre> <public>0</public> <bitrate>64</bitrate> </mount>

[2023-01-13 22:03:34] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-13 22:03:34] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 143 (1207) [2023-01-13 22:03:34] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-13 22:03:35] DBUG stats/stats_global_calc update global outgoing_kbitrate (53) [2023-01-13 22:03:35] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 1 (1400) [2023-01-13 22:03:35] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1399) [2023-01-13 22:03:35] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 8 (1400) [2023-01-13 22:03:35] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-13 22:03:35] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1393) [2023-01-13 22:03:36] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-13 22:03:36] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 244 (891) [2023-01-13 22:03:37] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-13 22:03:37] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 1 (1400) [2023-01-13 22:03:37] DBUG stats/stats_global_calc update global outgoing_kbitrate (54) [2023-01-13 22:03:37] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-13 22:03:37] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-13 22:03:38] DBUG stats/modify_node_event update "/rockradio" outgoing_kbitrate (0) [2023-01-13 22:03:38] DBUG stats/modify_node_event update "/rockradio" incoming_bitrate (65200) [2023-01-13 22:03:38] DBUG stats/modify_node_event update "/rockradio" total_bytes_read (2341645) [2023-01-13 22:03:38] DBUG stats/modify_node_event update "/rockradio" total_bytes_sent (0) [2023-01-13 22:03:38] DBUG stats/modify_node_event update "/rockradio" total_mbytes_sent (0) [2023-01-13 22:03:38] DBUG stats/modify_node_event update "/rockradio" queue_size (72332) [2023-01-13 22:03:38] DBUG stats/modify_node_event update "/rockradio" connected (285) [2023-01-13 22:03:38] DBUG stats/modify_node_event update "/stream" outgoing_kbitrate (56) [2023-01-13 22:03:38] DBUG stats/modify_node_event update "/stream" incoming_bitrate (65520) [2023-01-13 22:03:38] DBUG stats/modify_node_event update "/stream" total_bytes_read (2347953) [2023-01-13 22:03:38] DBUG stats/modify_node_event update "/stream" total_bytes_sent (1564649) [2023-01-13 22:03:38] DBUG stats/modify_node_event update "/stream" total_mbytes_sent (1) [2023-01-13 22:03:38] DBUG stats/modify_node_event update "/stream" queue_size (114046) [2023-01-13 22:03:38] DBUG stats/modify_node_event update "/stream" connected (285) [2023-01-13 22:03:38] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-13 22:03:38] DBUG stats/stats_global_calc update global outgoing_kbitrate (55) [2023-01-13 22:03:38] DBUG stats/stats_global_calc update global stream_kbytes_read (3391) [2023-01-13 22:03:38] DBUG stats/stats_global_calc update global stream_kbytes_sent (1504) [2023-01-13 22:03:38] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-13 22:03:38] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 8 (1400) [2023-01-13 22:03:38] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 204 (1400) [2023-01-13 22:03:38] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-13 22:03:38] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-13 22:03:39] DBUG stats/stats_global_calc update global clients (3) [2023-01-13 22:03:39] DBUG stats/stats_global_calc update global outgoing_kbitrate (54) [2023-01-13 22:03:39] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-13 22:03:39] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 1 (1393) [2023-01-13 22:03:39] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-13 22:03:39] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-13 22:03:40] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-13 22:03:40] DBUG stats/stats_global_calc update global outgoing_kbitrate (55)

i didn't find this problem in icecast-2.4.0-kh17-pre4. its a problem in icecast-kh-master version

karlheyes commented 1 year ago

you have a link I can try to relay?

gunsar commented 1 year ago

you have a link I can try to relay?

gunsar.ddns.net:8000/stream

next check debug : [2023-01-13 23:05:38] DBUG stats/stats_global_calc update global stream_kbytes_read (54444) [2023-01-13 23:05:38] DBUG stats/stats_global_calc update global stream_kbytes_sent (27706) [2023-01-13 23:05:39] DBUG stats/stats_global_calc update global clients (3) [2023-01-13 23:05:39] DBUG stats/stats_global_calc update global outgoing_kbitrate (55) [2023-01-13 23:05:40] DBUG stats/stats_global_calc update global listeners (1) [2023-01-13 23:05:40] DBUG stats/stats_global_calc update global outgoing_kbitrate (56) [2023-01-13 23:05:40] INFO format-mp3/parse_icy_metadata extra meta for /stream ( W b ] 9 A ';) [2023-01-13 23:05:40] DBUG stats/modify_node_event update "/stream" title () [2023-01-13 23:05:40] INFO format-mp3/metadata_setup icy metadata on /stream as (3) StreamTitle=''; W b ] 9 A ';... [2023-01-13 23:05:40] DBUG stats/modify_node_event update "/stream" yp_currently_playing () [2023-01-13 23:05:40] DBUG flv/flv_meta_increase 7 array elements [2023-01-13 23:05:40] DBUG stats/modify_node_event update "/stream" metadata_updated (13/Jan/2023:23:05:40 +0700) [2023-01-13 23:05:40] DBUG yp/yp_pending_update running through YP changes [2023-01-13 23:05:40] DBUG yp/yp_pending_update Processed 1 changes [2023-01-13 23:05:41] DBUG stats/stats_global_calc update global outgoing_kbitrate (54) [2023-01-13 23:05:41] DBUG client/worker_add_pending_clients Added 1 pending clients to 0x56246ea90720 [2023-01-13 23:05:41] DBUG connection/_handle_get_request start with /stream [2023-01-13 23:05:41] INFO source/source_add_listener max on /stream is -1 (cur 1) [2023-01-13 23:05:41] DBUG source/listener_change_worker dest count is 3, 1000000, move 1 [2023-01-13 23:05:41] DBUG source/listener_change_worker moving listener 172 on /stream from 0x56246ea90720 to 0x56246ea909d0 [2023-01-13 23:05:41] DBUG client/worker_add_pending_clients Added 1 pending clients to 0x56246ea909d0 [2023-01-13 23:05:41] DBUG stats/modify_node_event update "/stream" listener_connections (13) [2023-01-13 23:05:41] DBUG source/locate_start_on_queue xx.xx.xx.xx Joining queue on /stream (27419967, 27354891) [2023-01-13 23:05:41] DBUG client/worker 0x56246ea909d0 now has 4 clients [2023-01-13 23:05:41] INFO source/source_read listener count on /stream now 2 [2023-01-13 23:05:41] DBUG stats/modify_node_event update "/stream" listeners (2) [2023-01-13 23:05:42] DBUG stats/stats_global_calc update global banned_IPs (1) [2023-01-13 23:05:42] DBUG stats/stats_global_calc update global client_connections (170) [2023-01-13 23:05:42] DBUG stats/stats_global_calc update global clients (4) [2023-01-13 23:05:42] DBUG stats/stats_global_calc update global connections (171) [2023-01-13 23:05:42] DBUG stats/stats_global_calc update global listener_connections (20) [2023-01-13 23:05:42] DBUG stats/stats_global_calc update global listeners (2) [2023-01-13 23:05:43] DBUG stats/modify_node_event update "/stream" outgoing_kbitrate (120) [2023-01-13 23:05:43] DBUG stats/modify_node_event update "/stream" incoming_bitrate (65352) [2023-01-13 23:05:43] DBUG stats/modify_node_event update "/stream" total_bytes_read (32250397) [2023-01-13 23:05:43] DBUG stats/modify_node_event update "/stream" total_bytes_sent (27535382) [2023-01-13 23:05:43] DBUG stats/modify_node_event update "/stream" total_mbytes_sent (26) [2023-01-13 23:05:43] DBUG stats/modify_node_event update "/stream" queue_size (103801) [2023-01-13 23:05:43] DBUG stats/modify_node_event update "/stream" connected (4010) [2023-01-13 23:05:43] DBUG stats/stats_global_calc update global outgoing_kbitrate (842) [2023-01-13 23:05:43] DBUG

i didn't find this problem in icecast-2.4.0-kh17-pre4. its a problem in icecast-kh-master & icecast-2.4.0-kh17-http

karlheyes commented 1 year ago

ok, I've updated the master build. you should be good to go.

karl

gunsar commented 1 year ago

I updated and re-installed from icecast-kh-master. problem still the same. audio builds up and whirrs, then stops. playback with the sound of songs piling and whirring, then stopping

karlheyes commented 1 year ago

first thing to check is the error log. I'm getting a clean relay here from the link you provided whereas I did not before. lets see what your log is showing first

gunsar commented 1 year ago

[2023-01-14 01:35:45] INFO main/server_process Icecast 2.4.0-kh17-pre4 server started [2023-01-14 01:35:45] WARN slave/slave_startup process has 10240 max file descriptor limit [2023-01-14 01:35:45] INFO client/workers_adjust requested worker count 1 [2023-01-14 01:35:45] INFO client/log_commit_thread started [2023-01-14 01:35:45] INFO client/worker_start starting incoming worker thread 0x556df6b06720 [2023-01-14 01:35:45] DBUG client/worker 0x556df6b06720 now has 0 clients [2023-01-14 01:35:45] DBUG yp/yp_recheck_config Updating YP configuration [2023-01-14 01:35:45] DBUG client/worker 0x556df6b069d0 now has 0 clients [2023-01-14 01:35:45] DBUG slave/relay_copy copy relay /rockradio at 0x556df6b06d00 [2023-01-14 01:35:45] DBUG slave/relay_installed adding relay client for /rockradio (0x556df6b06d00) [2023-01-14 01:35:45] DBUG client/worker_add_pending_clients Added 1 pending clients to 0x556df6b069d0 [2023-01-14 01:35:45] DBUG slave/relay_copy copy relay /stream at 0x556df6b06ed0 [2023-01-14 01:35:45] DBUG slave/relay_installed adding relay client for /stream (0x556df6b06ed0) [2023-01-14 01:35:45] WARN slave/relay_initialise relay for "/rockradio" cannot get started, mountpoint in use, waiting [2023-01-14 01:35:45] DBUG client/worker 0x556df6b069d0 now has 1 clients [2023-01-14 01:35:45] DBUG client/worker_add_pending_clients Added 1 pending clients to 0x556df6b069d0 [2023-01-14 01:35:45] DBUG source/source_format_init no parser found for /stream [2023-01-14 01:35:45] DBUG client/worker 0x556df6b069d0 now has 2 clients [2023-01-14 01:35:45] DBUG stats/stats_global_calc update global banned_IPs (0) [2023-01-14 01:35:45] DBUG stats/stats_global_calc update global client_connections (0) [2023-01-14 01:35:45] DBUG stats/stats_global_calc update global clients (2) [2023-01-14 01:35:45] DBUG stats/stats_global_calc update global connections (0) [2023-01-14 01:35:45] DBUG stats/stats_global_calc update global listener_connections (0) [2023-01-14 01:35:45] DBUG stats/stats_global_calc update global listeners (0) [2023-01-14 01:35:45] DBUG stats/stats_global_calc update global outgoing_kbitrate (0) [2023-01-14 01:35:45] DBUG stats/stats_global_calc update global source_client_connections (0) [2023-01-14 01:35:45] DBUG stats/stats_global_calc update global source_relay_connections (0) [2023-01-14 01:35:45] DBUG stats/stats_global_calc update global source_total_connections (0) [2023-01-14 01:35:45] DBUG stats/stats_global_calc update global sources (0) [2023-01-14 01:35:45] DBUG stats/stats_global_calc update global stats (0) [2023-01-14 01:35:45] DBUG stats/stats_global_calc update global stats_connections (0) [2023-01-14 01:35:45] DBUG stats/stats_global_calc update global stream_kbytes_read (0) [2023-01-14 01:35:45] DBUG stats/stats_global_calc update global stream_kbytes_sent (0) [2023-01-14 01:35:45] INFO slave/start_relay_stream Starting relayed source at mountpoint "/stream" [2023-01-14 01:35:45] INFO slave/open_relay_connection using username source for /stream [2023-01-14 01:35:45] INFO slave/open_relay_connection connecting /stream to xx.xx.xx.xx:8000 [2023-01-14 01:35:45] INFO connection/connection_thread connection thread started [2023-01-14 01:35:45] DBUG slave/relay_get_response found 3515 bytes of data after header, queueing [2023-01-14 01:35:45] DBUG source/source_format_init parser found for /stream [2023-01-14 01:35:45] INFO format-mp3/format_mp3_get_plugin Created format details for /stream [2023-01-14 01:35:45] INFO format-mp3/mpeg_apply_client icy metadata format expected on /stream, interval 16000 [2023-01-14 01:35:45] DBUG stats/stats_handle new source stat /stream [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "slow_listeners" (0) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "server_type" (audio/aacp) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "listener_peak" (0) [2023-01-14 01:35:45] DBUG stats/modify_node_event update "/stream" listener_peak (0) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "listener_connections" (0) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "stream_start" (14/Jan/2023:01:35:45 +0700) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "total_mbytes_sent" (0) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "total_bytes_sent" (0) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "total_bytes_read" (0) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "outgoing_kbitrate" (0) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "incoming_bitrate" (0) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "queue_size" (0) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "connected" (0) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "source_ip" (xx.xx.xx.xx) [2023-01-14 01:35:45] INFO source/source_init Source /stream initialised [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "listenurl" (http://localhost:8000/stream) [2023-01-14 01:35:45] INFO source/source_apply_mount Applying mount information for "/stream" [2023-01-14 01:35:45] DBUG stats/modify_node_event update "/stream" listener_peak (0) [2023-01-14 01:35:45] DBUG format-mp3/format_mp3_apply_settings sending metadata interval 16000 [2023-01-14 01:35:45] DBUG format-mp3/format_mp3_apply_settings charset UTF-8 [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "public" (0) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "server_name" (Rock Radio Test) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "server_description" (Rock Radio Test) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "server_url" (https://www.sample.com) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "genre" (Pop) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "bitrate" (64) [2023-01-14 01:35:45] DBUG stats/modify_node_event update "/stream" server_type (audio/aacp) [2023-01-14 01:35:45] DBUG source/source_update_settings max listeners to -1 [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "max_listeners" (-1) [2023-01-14 01:35:45] DBUG source/source_update_settings public set to 0 [2023-01-14 01:35:45] DBUG source/source_update_settings source timeout to 10 [2023-01-14 01:35:45] INFO slave/start_relay_stream source /stream is ready to start [2023-01-14 01:35:45] INFO source/source_read listener count on /stream now 0 [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "listeners" (0) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 5 (8000) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 334 (7995) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 27 (7661) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 22 (7634) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 148 (7612) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 1 (7464) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 175 (7463) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 120 (7288) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 210 (7168) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 115 (6958) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 57 (6843) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 172 (6786) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 100 (6614) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 24 (6514) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 261 (6490) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 101 (6229) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 61 (6128) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 138 (6067) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 159 (5929) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 1 (8400) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 26 (8399) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 72 (8373) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 14 (8301) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 99 (8287) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 36 (8188) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 66 (8152) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 50 (8086) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 143 (8036) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 98 (7893) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 24 (7795) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 166 (7771) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 22 (7605) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 156 (7583) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 71 (7427) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 43 (7356) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 92 (8400) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 278 (8308) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 58 (8030) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 122 (7972) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 5 (7850) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 170 (7845) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 41 (7675) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 28 (7634) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 54 (7606) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 92 (7552) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 114 (7460) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 30 (7346) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 375 (7316) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 90 (6941) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 3 (8400) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 184 (8397) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 40 (8213) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 28 (8173) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 53 (8145) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 326 (8092) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 38 (7766) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 110 (7728) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 149 (7618) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 125 (7469) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 50 (7344) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 2 (7294) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 209 (7292) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 20 (7083) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 20 (7063) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 45 (7043) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 81 (6998) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 136 (6917) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 131 (6781) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 53 (6650) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 150 (6597) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 94 (6447) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 51 (8400) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 217 (8349) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 58 (8132) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 35 (8074) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 124 (8039) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 383 (7915) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 18 (7532) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 31 (7514) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 21 (7483) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 6 (7462) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 66 (7456) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 11 (7390) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 240 (7379) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 363 (7139) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 67 (6776) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 318 (6709) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 69 (6391) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 92 (6322) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 24 (6230) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 73 (6206) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 5 (6133) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 93 (6128) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 123 (6035) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 189 (5912) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 10 (5723) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 210 (5713) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 44 (5503) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 37 (5459) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 118 (5422) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 18 (5304) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 148 (5286) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 37 (5138) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 310 (5101) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 328 (4791) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 1 (4463) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 72 (4462) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 3 (4390) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 184 (4387) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 59 (4203) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 68 (4144) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 51 (4076) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 241 (4025) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 318 (3784) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 79 (3466) [2023-01-14 01:35:45] INFO mpeg/check_for_aac Detected AAC MPEG-4, 22050Hz 2 channel(s) 64 kbps on xx.xx.xx.xx [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "audio_codecid" (10) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "mpeg_samplerate" (22050) [2023-01-14 01:35:45] DBUG stats/process_source_stat new node on /stream "mpeg_channels" (2) [2023-01-14 01:35:45] INFO source/source_set_override no override for /stream set [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 8 (4959) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 118 (4951) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 8 (4833) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 8 (8400) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 8 (8392) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 9 (6719) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 373 (6710) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 8 (8400) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 8 (8392) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 1 (7551) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 8 (7550) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 8 (7542) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 269 (7534) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 8 (8400) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 8 (8392) [2023-01-14 01:35:45] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 1 (8384) [2023-01-14 01:35:46] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (5716) [2023-01-14 01:35:46] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (5709) [2023-01-14 01:35:46] DBUG stats/stats_global_calc update global source_relay_connections (1) [2023-01-14 01:35:46] DBUG stats/stats_global_calc update global source_total_connections (1) [2023-01-14 01:35:46] DBUG stats/stats_global_calc update global sources (1) [2023-01-14 01:35:47] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 359 (8243) [2023-01-14 01:35:47] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (7884) [2023-01-14 01:35:47] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (7877) [2023-01-14 01:35:47] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 1 (7870) [2023-01-14 01:35:48] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (8348) [2023-01-14 01:35:48] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (8341) [2023-01-14 01:35:48] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (8334) [2023-01-14 01:35:48] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (8327) [2023-01-14 01:35:49] DBUG stats/modify_node_event update "/stream" outgoing_kbitrate (0) [2023-01-14 01:35:49] DBUG stats/modify_node_event update "/stream" incoming_bitrate (245824) [2023-01-14 01:35:49] DBUG stats/modify_node_event update "/stream" total_bytes_read (92185) [2023-01-14 01:35:49] DBUG stats/modify_node_event update "/stream" total_bytes_sent (0) [2023-01-14 01:35:49] DBUG stats/modify_node_event update "/stream" total_mbytes_sent (0) [2023-01-14 01:35:49] DBUG stats/modify_node_event update "/stream" queue_size (66651) [2023-01-14 01:35:49] DBUG stats/modify_node_event update "/stream" connected (4) [2023-01-14 01:35:49] DBUG source/update_source_stats /stream queue size set to 524288 [2023-01-14 01:35:49] DBUG source/update_source_stats /stream min queue size set to 65536 [2023-01-14 01:35:49] DBUG source/update_source_stats /stream burst size set to 65535 [2023-01-14 01:35:49] DBUG stats/stats_global_calc update global stream_kbytes_read (65) [2023-01-14 01:35:49] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 34 (4543) [2023-01-14 01:35:49] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (4509) [2023-01-14 01:35:49] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 1 (4502) [2023-01-14 01:35:50] DBUG stats/modify_node_event update "/stream" outgoing_kbitrate (0) [2023-01-14 01:35:50] DBUG stats/modify_node_event update "/stream" incoming_bitrate (198696) [2023-01-14 01:35:50] DBUG stats/modify_node_event update "/stream" total_bytes_read (99349) [2023-01-14 01:35:50] DBUG stats/modify_node_event update "/stream" total_bytes_sent (0) [2023-01-14 01:35:50] DBUG stats/modify_node_event update "/stream" total_mbytes_sent (0) [2023-01-14 01:35:50] DBUG stats/modify_node_event update "/stream" queue_size (71038) [2023-01-14 01:35:50] DBUG stats/modify_node_event update "/stream" connected (5) [2023-01-14 01:35:50] DBUG stats/stats_global_calc update global stream_kbytes_read (69) [2023-01-14 01:35:50] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (8400) [2023-01-14 01:35:50] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (8393) [2023-01-14 01:35:50] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (8386) [2023-01-14 01:35:50] DBUG connection/add_banned_ip Adding literal entry "167.71.204.xxx" [2023-01-14 01:35:50] INFO util/cached_file_recheck 1 entries read from file "blockip" [2023-01-14 01:35:50] DBUG client/worker_add_pending_clients Added 1 pending clients to 0x556df6b06720 [2023-01-14 01:35:50] DBUG client/worker 0x556df6b06720 now has 1 clients [2023-01-14 01:35:50] DBUG util/add_generic_text Adding wildcard entry "phyton" [2023-01-14 01:35:50] DBUG util/add_generic_text Adding wildcard entry "python" [2023-01-14 01:35:50] DBUG util/add_generic_text Adding wildcard entry "apache" [2023-01-14 01:35:50] INFO util/cached_file_recheck 3 entries read from file "blockagent" [2023-01-14 01:35:50] DBUG connection/_handle_get_request start with /stream [2023-01-14 01:35:50] INFO source/source_add_listener max on /stream is -1 (cur 0) [2023-01-14 01:35:50] DBUG source/listener_change_worker dest count is 2, 1000000, move 1 [2023-01-14 01:35:50] DBUG source/listener_change_worker moving listener 1 on /stream from 0x556df6b06720 to 0x556df6b069d0 [2023-01-14 01:35:50] DBUG client/worker 0x556df6b06720 now has 0 clients [2023-01-14 01:35:50] DBUG client/worker_add_pending_clients Added 1 pending clients to 0x556df6b069d0 [2023-01-14 01:35:50] DBUG stats/modify_node_event update "/stream" listener_connections (1) [2023-01-14 01:35:50] DBUG source/locate_start_on_queue 136.171.168.xx Joining queue on /stream (79297, 16127) [2023-01-14 01:35:50] DBUG client/worker 0x556df6b069d0 now has 3 clients [2023-01-14 01:35:50] INFO source/source_read listener count on /stream now 1 [2023-01-14 01:35:50] DBUG stats/modify_node_event update "/stream" listeners (1) [2023-01-14 01:35:50] DBUG stats/modify_node_event update "/stream" listener_peak (1) [2023-01-14 01:35:50] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:50] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1233) [2023-01-14 01:35:51] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 39 (389) [2023-01-14 01:35:51] DBUG stats/stats_global_calc update global banned_IPs (1) [2023-01-14 01:35:51] DBUG stats/stats_global_calc update global client_connections (1) [2023-01-14 01:35:51] DBUG stats/stats_global_calc update global clients (3) [2023-01-14 01:35:51] DBUG stats/stats_global_calc update global connections (1) [2023-01-14 01:35:51] DBUG stats/stats_global_calc update global listener_connections (1) [2023-01-14 01:35:51] DBUG stats/stats_global_calc update global listeners (1) [2023-01-14 01:35:51] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 1 (1400) [2023-01-14 01:35:51] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1399) [2023-01-14 01:35:52] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:52] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:52] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:52] DBUG stats/stats_global_calc update global outgoing_kbitrate (559) [2023-01-14 01:35:52] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:52] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:53] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:53] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:53] DBUG stats/stats_global_calc update global outgoing_kbitrate (298) [2023-01-14 01:35:53] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 102 (1492) [2023-01-14 01:35:53] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1390) [2023-01-14 01:35:53] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:53] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:54] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:54] DBUG stats/stats_global_calc update global outgoing_kbitrate (220) [2023-01-14 01:35:54] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:54] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:55] DBUG stats/modify_node_event update "/stream" outgoing_kbitrate (161) [2023-01-14 01:35:55] DBUG stats/modify_node_event update "/stream" incoming_bitrate (125024) [2023-01-14 01:35:55] DBUG stats/modify_node_event update "/stream" total_bytes_read (140658) [2023-01-14 01:35:55] DBUG stats/modify_node_event update "/stream" total_bytes_sent (94379) [2023-01-14 01:35:55] DBUG stats/modify_node_event update "/stream" total_mbytes_sent (0) [2023-01-14 01:35:55] DBUG stats/modify_node_event update "/stream" queue_size (79391) [2023-01-14 01:35:55] DBUG stats/modify_node_event update "/stream" connected (10) [2023-01-14 01:35:55] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:55] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:55] DBUG stats/stats_global_calc update global outgoing_kbitrate (178) [2023-01-14 01:35:55] DBUG stats/stats_global_calc update global source_client_connections (0) [2023-01-14 01:35:55] DBUG stats/stats_global_calc update global stats (0) [2023-01-14 01:35:55] DBUG stats/stats_global_calc update global stats_connections (0) [2023-01-14 01:35:55] DBUG stats/stats_global_calc update global stream_kbytes_read (107) [2023-01-14 01:35:55] DBUG stats/stats_global_calc update global stream_kbytes_sent (92) [2023-01-14 01:35:55] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1048) [2023-01-14 01:35:55] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 239 (1544) [2023-01-14 01:35:55] WARN format-mp3/validate_mpeg source /stream, len 1400, unprocessed 1400 [2023-01-14 01:35:55] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 166 (2400) [2023-01-14 01:35:55] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 123 (2234) [2023-01-14 01:35:55] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 52 (2111) [2023-01-14 01:35:55] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 189 (2059) [2023-01-14 01:35:55] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 270 (1870) [2023-01-14 01:35:55] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 34 (1600) [2023-01-14 01:35:55] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 358 (1566) [2023-01-14 01:35:55] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 182 (1208) [2023-01-14 01:35:55] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 15 (1026) [2023-01-14 01:35:55] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 48 (1011) [2023-01-14 01:35:55] WARN format-mp3/validate_mpeg source /stream, len 1400, unprocessed 1400 [2023-01-14 01:35:55] WARN format-mp3/validate_mpeg source /stream, len 1400, unprocessed 2400 [2023-01-14 01:35:56] WARN format-mp3/validate_mpeg source /stream, len 1400, unprocessed 3400 [2023-01-14 01:35:56] WARN format-mp3/validate_mpeg source /stream, len 1400, unprocessed 4400 [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 113 (5400) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 98 (5287) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 84 (5189) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 2 (5105) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 150 (5103) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 144 (4953) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 238 (4809) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 356 (4571) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 64 (4215) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 78 (4151) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 191 (4073) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 120 (3882) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 28 (3762) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 27 (3734) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 165 (3707) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 169 (3542) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 232 (3373) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 17 (3141) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 219 (3124) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 116 (2905) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 22 (2789) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 63 (2767) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 118 (2704) [2023-01-14 01:35:56] WARN format-mp3/validate_mpeg source /stream, len 1400, unprocessed 2586 [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 35 (3586) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 112 (3551) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 30 (3439) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 98 (3409) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 19 (3311) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 50 (3292) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 186 (3242) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 55 (3056) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 375 (3001) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 37 (2626) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 36 (2589) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 208 (2553) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 9 (2345) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 33 (2336) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 26 (2303) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 13 (2277) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 39 (2264) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 35 (2225) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 208 (2190) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 36 (1982) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 8 (1946) [2023-01-14 01:35:56] INFO mpeg/check_for_aac Detected AAC MPEG-4, 22050Hz 2 channel(s) 64 kbps on xx.xx.xx.xx [2023-01-14 01:35:56] DBUG stats/modify_node_event update "/stream" audio_codecid (10) [2023-01-14 01:35:56] DBUG stats/modify_node_event update "/stream" mpeg_samplerate (22050) [2023-01-14 01:35:56] DBUG stats/modify_node_event update "/stream" mpeg_channels (2) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:56] DBUG stats/stats_global_calc update global outgoing_kbitrate (154) [2023-01-14 01:35:56] DBUG stats/stats_global_calc update global source_relay_connections (1) [2023-01-14 01:35:56] DBUG stats/stats_global_calc update global source_total_connections (1) [2023-01-14 01:35:56] DBUG stats/stats_global_calc update global sources (1) [2023-01-14 01:35:56] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:57] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:57] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:57] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 1 (1383) [2023-01-14 01:35:57] DBUG stats/stats_global_calc update global outgoing_kbitrate (132) [2023-01-14 01:35:57] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 324 (1400) [2023-01-14 01:35:57] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1076) [2023-01-14 01:35:58] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:58] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:58] DBUG stats/stats_global_calc update global outgoing_kbitrate (121) [2023-01-14 01:35:58] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:58] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:59] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:59] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 7 (1400) [2023-01-14 01:35:59] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 1 (1041) [2023-01-14 01:35:59] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 84 (440) [2023-01-14 01:35:59] DBUG stats/stats_global_calc update global outgoing_kbitrate (112) [2023-01-14 01:35:59] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 169 (1400) [2023-01-14 01:35:59] WARN format-mp3/validate_mpeg source /stream, len 1400, unprocessed 1400 [2023-01-14 01:36:00] DBUG stats/modify_node_event update "/stream" outgoing_kbitrate (49) [2023-01-14 01:36:00] DBUG stats/modify_node_event update "/stream" incoming_bitrate (103624) [2023-01-14 01:36:00] DBUG stats/modify_node_event update "/stream" total_bytes_read (181355) [2023-01-14 01:36:00] DBUG stats/modify_node_event update "/stream" total_bytes_sent (123734) [2023-01-14 01:36:00] DBUG stats/modify_node_event update "/stream" total_mbytes_sent (0) [2023-01-14 01:36:00] DBUG stats/modify_node_event update "/stream" queue_size (72485) [2023-01-14 01:36:00] DBUG stats/modify_node_event update "/stream" connected (15) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 95 (2400) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 2 (2305) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 253 (2303) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 146 (2050) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 172 (1904) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 47 (1732) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 225 (1685) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 112 (1460) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 115 (1348) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 32 (1233) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 4 (1201) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 400 (1197) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 61 (797) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 49 (736) [2023-01-14 01:36:00] WARN format-mp3/validate_mpeg source /stream, len 1400, unprocessed 1400 [2023-01-14 01:36:00] WARN format-mp3/validate_mpeg source /stream, len 1400, unprocessed 2400 [2023-01-14 01:36:00] WARN format-mp3/validate_mpeg source /stream, len 1400, unprocessed 3400 [2023-01-14 01:36:00] DBUG stats/stats_global_calc update global outgoing_kbitrate (108) [2023-01-14 01:36:00] DBUG stats/stats_global_calc update global stream_kbytes_read (135) [2023-01-14 01:36:00] DBUG stats/stats_global_calc update global stream_kbytes_sent (120) [2023-01-14 01:36:00] WARN format-mp3/validate_mpeg source /stream, len 1400, unprocessed 4400 [2023-01-14 01:36:00] WARN format-mp3/validate_mpeg source /stream, len 1400, unprocessed 5400 [2023-01-14 01:36:00] WARN format-mp3/validate_mpeg source /stream, len 1400, unprocessed 6400 [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 175 (7400) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 2 (7225) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 73 (7223) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 37 (7150) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 44 (7113) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 72 (7069) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 205 (6997) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 56 (6792) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 295 (6736) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 17 (6441) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 54 (6424) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 8 (6370) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 22 (6362) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 314 (6340) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 87 (6026) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 33 (5939) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 16 (5906) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 349 (5890) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 148 (5541) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 203 (5393) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 69 (5190) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 232 (5121) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 65 (4889) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 63 (4824) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 293 (4761) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 344 (4468) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 53 (4124) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 184 (4071) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 125 (3887) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 30 (3762) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 228 (3732) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 1 (3504) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 60 (3503) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 18 (3443) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 58 (3425) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 34 (3367) [2023-01-14 01:36:00] DBUG mpeg/mpeg_complete_frames_cb no frame sync on xx.xx.xx.xx, re-checking after skipping 183 (3333) [2023-01-14 01:36:00] WARN format-mp3/validate_mpeg source /stream, len 1400, unprocessed 3150

karlheyes commented 1 year ago

the changes since pre4 are in the relay start up.The above has all the indications of some data added or removed from the stream which is what yours was reporting with

[2023-01-14 01:35:45] DBUG slave/relay_get_response found 3515 bytes of data after header, queueing

That was causing the issue when I tried it here (not that figure for me) but was causing the misalignment with all the skipping lines.

I noticed the stream was off so not able to duplicate it, but I've put up a modification to see if more info can be attained when you are next on.

karlheyes commented 1 year ago

ok, so what did you actually remove? input header data?

gunsar commented 1 year ago

earlier I was curious to reinstall the vps and there was only 1 standalone icecast-kh debian, and a relay from another vps server.

back to the initial trial settings like yesterday: debian 1 VPS 1 IP icecast host 1 : docker azuracast icecast host 2 : standalone icecast-kh experiment with your updates on icecast host 2, relay from icecast host 1 everything works normally relay up to version kh17-pre3

when using version kh17-pre4 and having problems with audio

I can conclude that when using the kh17-pre4 version, on 1 VPS 1 IP, even though the port is different, there will be problems with audio such as wheezing and finally the audio stops

karlheyes commented 1 year ago

well the pre-4 update had more relay changes. I just need to pinpoint what differences are between our setups

gunsar commented 1 year ago

I now understand the big change in the relay, because the relay from 1 server and 1 vps is the same as audio problems such as stacking and finally stopping, including in the alias command with muti port settings on icecast-kh stand alone like this:

<alias source="/test" port="8000" dest="/stream"/> <alias source="/test" port="8800" dest="/rockradio"/>

the audio becomes like a heap of whirring and finally stops whereas in the previous version of the kh17-pre3 the audio relay was normal.

it's up to you how to do it, but I think this is a note that there will be problems for users with 1 vps 1 ip but a different hostname or virtual host

karlheyes commented 1 year ago

I doubt that the alias plays any part, the pre-3 changed a bunch of xml reading but the alias table still looks intact and only really applies a change to the mount lookup. That aspect was not changed. If you modify the icy-metaint header then it can get messed up but I doubt you did that.

gunsar commented 1 year ago

but is it possible for you to return to the initial pre-3 settings for the relay settings in the next version? I hope so, because my experiment is very comfortable relaying in 1 vps 1 ip, but a different hostname/vhost.

karlheyes commented 1 year ago

it's not just settings, the switchover code is fairly sizeable but should have near 0 effect on your setup. I wondering if you are just falling foul of an initialising bug (or lack of) relating to the client part

gunsar commented 1 year ago

personal experiment. I have a lot of radio friends who use only 1 server to relay from the same server, as I remember the vhost, and I have a Centova panel, and just tried it for new master-icecast-kh, and it turned out that the audio problem was stacked and finally it stopped with the relay system from 1 same servers.

karlheyes commented 1 year ago

I still need to replicate the problem though if I'm to flush out the issue. However the setup is organised. From the description you have mentioned it's an alignment bug

gunsar commented 1 year ago

well, looks like a sync bug for your version in the latest icecast-kh-master, I tried again, with 2 versions, and in conclusion: 1 vps 1 ip (azuracast, icecast-kh standalone and relay from azuracast)

1 vps 1 ip (azuracast, centova vhost and relay from azuracast)

it's my experiment. the only problem is using the newest icecast-kh-master if the relay source is from the same server

if the relay source is from a different server (from another VPS) everything will work normally

karlheyes commented 1 year ago

could it be prior to the fix I did earlier? I'm trying a single icecast relaying and external stream and have another relay on the same icecast and that all seems ok so far

gunsar commented 1 year ago

still the problem is synchronization relay because in my experiment icecast-kh stands alone on one server with centova and azuracat. back to the beginning. the problem with the voice relay getting choked up and finally stopping.

i tried again: 1 server 1 ip (azuracast v17.6 with their icecast version still kh15-ac + icecast-kh standalone v17-pre4) running normally relay and header

karlheyes commented 1 year ago

just to keep things in sync with others, I've tagged pre5. Can you verify that you have a problem and if so what you have to show the problem up. I have tried pulling an external relay (aac) and relaying that on the same icecast and that was ok. Sometimes problems can occur in specific cases so I'm all for collecting info.

gunsar commented 1 year ago

thanks for giving me another try. i tried to install using wget v-pre5. running normally, no problems with the relay anymore (1 vps 1 ip : icecast-kh standalone relay from azuracast) it's nice that the audio is running normally without stuttering and jitter. for 15 minutes it was still running normally, there was no debug error report

DroidU commented 1 year ago

Unfortunately, the problem described in the title is present again with kh-18.1.

gunsar commented 1 year ago

I also have a problem with this title for relay since version kh17, I have to restart the icecast-kh service, then the song title/metadata appears.