Stream doesn't recover if there is network outage for more then 60s
Hello, i noticed that there is a problem that stream doesn't recover if there is network outage for more then 60s.
To reproduce i use:
./ristsender -S 0 -i udp://239.255.255.1:1234 -o rist://127.0.0.1:2000
./ristreceiver -S 0 -i rist://@127.0.0.1:2000 -o udp://127.0.0.1:3000
sudo iptables -I INPUT -d 127.0.0.1 -p udp --dport 2000 -j DROP && sleep 61 && sudo iptables -D INPUT -d 127.0.0.1 -p udp --dport 2000 -j DROP
Log:
1605868028.312141|0.0|[INFO] Starting ristsender version: 4.0.0.2 1605868028.312179|0.0|[INFO] Assigning stream-id 0 to this input 1605868028.312198|0.0|[INFO] Starting in Main Profile Mode 1605868028.312213|0.0|[INFO] RIST Sender Library 4fd2b3ea 1605868028.312250|0.0|[INFO] Link configured with maxrate=100000 bufmin=1000 bufmax=1000 reorder=25 rttmin=50 rttmax=500 congestion_control=1 min_retries=6 max_retries=20 1605868028.312258|0.140271878823952|[INFO] Encryption is disabled for this peer 1605868028.312266|0.140271878823952|[INFO] URL parsed successfully: Host 127.0.0.1, Port 2000 1605868028.312291|0.140271878823952|[INFO] Starting in URL connect mode (3) 1605868028.312302|0.140271878823952|[INFO] Starting master sender loop at 5 ms max jitter 1605868028.312305|0.140271878823952|[INFO] Configured the starting socket receive buffer size to 419430 Bytes. 1605868028.312326|0.140271878823952|[INFO] Configured the starting socket send buffer size to 419430 Bytes. 1605868028.312342|0.140271878823952|[INFO] Peer cname is cup 1605868028.312346|0.140271878823952|[INFO] Setting max nacks per cycle to 44 1605868028.312350|0.140271878823952|[INFO] Setting buffer size to 2000ms 1605868028.312354|0.140271878823952|[INFO] Advertising flow_id 3027558244 and peer_id 1, 32768/1969 1605868028.312360|0.140271878823952|[INFO] Initialized Sender Peer, connecting to receiver ... 1605868028.312365|0.140271878823952|[INFO] Active Peer Information, IP:Port => 127.0.0.1:2000 (0), id: 1, ports: 32768->1969 1605868028.312370|0.140271878823952|[INFO] Enabling keepalive for peer 1 1605868028.312459|0.140271878823952|[INFO] Successfully Authenticated peer 1 1605868028.312466|0.0|[INFO] URL parsed successfully: Host 239.255.255.1, Port 1234 1605868028.312480|0.0|[INFO] Joining multicast address: 239.255.255.1 from IP 0.0.0.0 1605868028.312495|0.0|[INFO] Input socket is open and bound 239.255.255.1:1234 1605868035.265338|0.140271878823952|[INFO] Peer 1 receiver name is now: cup@127.0.0.1:2000 1605868035.266747|0.0|[INFO] Out-of-band data received: auth,127.0.0.1:14126,127.0.0.1:2000 1605868109.313134|0.140271878823952|[WARNING] Peer with id 1 is dead, stopping stream ...
1605868035.224421|0.0|[INFO] Starting ristreceiver version: 4.0.0.2 1605868035.224477|0.0|[INFO] Starting in Main Profile Mode 1605868035.224491|0.0|[INFO] RIST Receiver Library version:4fd2b3ea 1605868035.224498|140142248382480.0|[INFO] Starting in receiver mode 1605868035.224523|0.0|[INFO] Link configured with maxrate=100000 bufmin=1000 bufmax=1000 reorder=25 rttmin=50 rttmax=500 congestion_control=1 min_retries=6 max_retries=20 1605868035.224532|140142248382480.0|[INFO] Encryption is disabled for this peer 1605868035.224547|140142248382480.0|[INFO] URL parsed successfully: Host 127.0.0.1, Port 2000 1605868035.224586|140142248382480.0|[INFO] Starting in URL listening mode (socket# 3) 1605868035.224608|140142248382480.0|[INFO] Configured the starting socket receive buffer size to 419430 Bytes. 1605868035.224624|140142248382480.0|[INFO] Configured the starting socket send buffer size to 419430 Bytes. 1605868035.224646|140142248382480.0|[INFO] Peer cname is cup@127.0.0.1:2000 1605868035.224657|140142248382480.0|[INFO] New peer with id #1 (closed) was configured with maxrate=100000/0 bufmin=1000 bufmax=1000 reorder=25 rttmin=50 rttmax=500 congestion_control=1 min_retries=6 max_retries=20 1605868035.224666|140142248382480.0|[INFO] Initialized Receiver Peer, listening mode ... 1605868035.224677|140142248382480.0|[INFO] Active Peer Information, IP:Port => 127.0.0.1:2000 (1), id: 1, ports: 2000->1969 1605868035.224690|0.0|[INFO] URL parsed successfully: Host 127.0.0.1, Port 3000 1605868035.224715|0.0|[INFO] Output socket is open and bound 127.0.0.1:3000 1605868035.224822|140142248382480.0|[INFO] Starting receiver protocol loop with 5 ms timer 1605868035.264074|140142248382480.0|[INFO] New peer with id #2 was configured with maxrate=100000/0 bufmin=1000 bufmax=1000 reorder=25 rttmin=50 rttmax=500 congestion_control=1 min_retries=6 max_retries=20 1605868035.264095|140142248382480.0|[INFO] New RTCP peer connecting, flow_id 3027558244, peer_id 2, ports 1969 <- 32768 1605868035.264107|0.0|[INFO] Peer has been authenticated, sending auth message: auth,127.0.0.1:14126,127.0.0.1:2000 1605868035.264127|140142248382480.0|[INFO] Enabling keepalive for peer 2 1605868035.264192|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868035.265406|140142248382480.0|[INFO] Peer 2 receiver name is now: cup 1605868035.265448|140142248382480.0|[INFO] FLOW #3027558244 created (short=1) 1605868035.265462|140142248382480.0|[INFO] Peer with id #2 associated with flow #3027558244 1605868035.265480|140142248382480.0|[INFO] Flow #3027558244 has now 1 peers. 1605868035.265537|140142248382480.0|[INFO] Successfully Authenticated peer 2 1605868035.265546|140142248382480.0|[INFO] Authenticated RTCP peer 2 and flow 3027558244 for connection with cname: cup 1605868035.265564|140142248382480.0|[INFO] Authenticated RTP peer 2 and ssrc 3027558244 for connection with flowid 3027558244 1605868035.265576|140142248382480.0|[INFO] Storing first packet seq 5257, idx 5257, 204735660539114, offset -2079368769504 ms, output_idx 5257 1605868035.265635|140142248382480.0|[WARNING] Failed to set data output thread to RR scheduler with prio of 99 1605868035.265652|140142248382480.0|[INFO] Starting data output thread with 5 ms max output jitter 1605868049.268873|140142248382480.0|[WARNING] Flow with id 3027558244 is dead, age is 1620ms 1605868108.269763|140142248382480.0|[INFO] ************** Session Timeout after 60s of no data, deleting flow with id 3027558244 *************** 1605868108.269777|140142248382480.0|[INFO] Shutting down peer #2 1605868108.269780|140142248382480.0|[INFO] Removing peer handshake/ping timer 1605868108.269782|140142248382480.0|[INFO] Triggering data output thread termination 1605868108.269784|140142248382480.0|[INFO] Waiting for data output thread to exit 1605868108.274867|140142248382480.0|[INFO] Free flow peer list 1605868108.274893|140142248382480.0|[INFO] Deleting missing queue elements 1605868108.274899|140142248382480.0|[INFO] Deleting output buffer data 1605868108.274903|140142248382480.0|[INFO] Freeing data fifo queue 1605868108.275070|140142248382480.0|[INFO] Deleting flow 1605868108.662780|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.664312|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.665553|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.666721|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.668106|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.669384|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.670840|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.672099|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.673117|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.674803|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.676146|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.677501|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.678646|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.679917|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.681147|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.682461|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.683908|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.685145|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.686639|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.687897|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.688961|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.690370|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.691860|140142248382480.0|[WARNING] Received data packet (3027558244) but handshake is still pending (waiting for an RTCP packet with SDES on it), ignoring ... 1605868108.693984|140142248382480.0|[INFO] FLOW #3027558244 created (short=1) 1605868108.694003|140142248382480.0|[INFO] Peer with id #2 associated with flow #3027558244 1605868108.694011|140142248382480.0|[INFO] Flow #3027558244 has now 1 peers. 1605868108.694060|140142248382480.0|[INFO] Successfully Authenticated peer 2 1605868108.694069|140142248382480.0|[INFO] Authenticated RTCP peer 2 and flow 3027558244 for connection with cname: cup 1605868108.694099|140142248382480.0|[INFO] Storing first packet seq 61387, idx 61387, 87208308786, offset -2079321047642 ms, output_idx 61387 1605868108.694154|140142248382480.0|[WARNING] Failed to set data output thread to RR scheduler with prio of 99 1605868108.694171|140142248382480.0|[INFO] Starting data output thread with 5 ms max output jitter 1605868110.698300|140142248382480.0|[WARNING] Flow with id 3027558244 is dead, age is 1386ms 1605868169.694868|140142248382480.0|[INFO] ************** Session Timeout after 60s of no data, deleting flow with id 3027558244 *************** 1605868169.694882|140142248382480.0|[INFO] Shutting down peer #2 1605868169.694886|140142248382480.0|[INFO] Triggering data output thread termination 1605868169.694889|140142248382480.0|[INFO] Waiting for data output thread to exit 1605868169.699949|140142248382480.0|[INFO] Free flow peer list 1605868169.699958|140142248382480.0|[INFO] Deleting missing queue elements 1605868169.699961|140142248382480.0|[INFO] Deleting output buffer data 1605868169.699964|140142248382480.0|[INFO] Freeing data fifo queue 1605868169.700069|140142248382480.0|[INFO] Deleting flow 1605868169.736226|140142248382480.0|[INFO] FLOW #3027558244 created (short=1) 1605868169.736242|140142248382480.0|[INFO] Peer with id #2 associated with flow #3027558244 1605868169.736247|140142248382480.0|[INFO] Flow #3027558244 has now 1 peers. 1605868169.736274|140142248382480.0|[INFO] Successfully Authenticated peer 2 1605868169.736281|140142248382480.0|[INFO] Authenticated RTCP peer 2 and flow 3027558244 for connection with cname: cup 1605868169.736301|140142248382480.0|[WARNING] Failed to set data output thread to RR scheduler with prio of 99 1605868169.736315|140142248382480.0|[INFO] Starting data output thread with 5 ms max output jitter