search

arut / nginx-rtmp-module

NGINX-based Media Streaming Server
http://nginx-rtmp.blogspot.com
BSD 2-Clause "Simplified" License
13.45k stars 3.52k forks source link

too big message: 1048576 #324

Open ziontrain opened 10 years ago

ziontrain commented 10 years ago

I get this error when installing on a virtual RHEL box located on another network segment. When i install on Centos (local Virtualbox) i dont get this error.

I have tried to increase max_message but it does not work.

I modified the code to print out the actual size and this is what is says about the size too big message: 1048576 XXXX: 1661009920

Any ideas?

arut commented 10 years ago

Increasing max_message should work. If it doesn't then you're facing protocol inconsistency. What's the client? Try changing publish_time_fix / play_time_fix.

ziontrain commented 10 years ago

Im using VLC Media player

I have tried the publish_time_fix / play_time_fix but there is no difference. If i increase it to some very big value i dont get the error message, however nothing else happens. No video and no error message about the size.

Here is what the log says with a big max_message

014/01/20 10:10:35 [debug] 49278#0: timer delta: 3 2014/01/20 10:10:35 [debug] 49278#0: posted events 0000000000000000 2014/01/20 10:10:35 [debug] 49278#0: worker cycle 2014/01/20 10:10:35 [debug] 49278#0: epoll timer: 30000 2014/01/20 10:10:35 [debug] 49278#0: epoll: fd:3 ev:0001 d:000003FFFD3CD250 2014/01/20 10:10:35 [debug] 49278#0: 1 recv: fd:3 146 of 146 2014/01/20 10:10:35 [debug] 49278#0: 1 RTMP bheader fmt=0 csid=3 2014/01/20 10:10:35 [debug] 49278#0: 1 RTMP mheader fmt=0 amf_cmd (20) time=0+0 mlen=-352321536 len=0 msid=0 2014/01/20 10:10:35 [debug] 49278#0: 1 reusing formerly read data: 6 2014/01/20 10:10:35 [debug] 49278#0: 1 RTMP bheader fmt=3 csid=3 2014/01/20 10:10:35 [debug] 49278#0: 1 RTMP mheader fmt=3 amf_cmd (20) time=0+0 mlen=-352321536 len=128 msid=0 2014/01/20 10:10:35 [debug] 49278#0: 1 recv: fd:3 102 of 140 2014/01/20 10:10:35 [debug] 49278#0: 1 recv: fd:3 -1 of 38 2014/01/20 10:10:35 [debug] 49278#0: *1 recv() not ready (11: Resource temporarily unavailable) 2014/01/20 10:10:35 [debug] 49278#0: timer delta: 1 2014/01/20 10:10:35 [debug] 49278#0: posted events 0000000000000000 2014/01/20 10:10:35 [debug] 49278#0: worker cycle 2014/01/20 10:10:35 [debug] 49278#0: epoll timer: 29999

ziontrain commented 10 years ago

Hmm, maybe it have to do with endianess.. Im running RHEL on a big endian system

arut commented 10 years ago

@ziontrain highly probable. big-endian is poorly supported now. master does not work definitely on that. There's a branch big-endian with basic features fixed, but there's still much work to do. I have no big-endian device so testing is not easy.

ziontrain commented 10 years ago

Yes, looks like that is the problem. I tried the big-endian branch and even if video file streaming is not working (all i need) errors looks more "normal" with regards to the development of the branch.

2014/01/20 12:02:51 [debug] 64780#0: epoll: fd:7 ev:0001 d:000003FFFD13F0D0 2014/01/20 12:02:51 [debug] 64780#0: accept on 0.0.0.0:1935, ready: 0 2014/01/20 12:02:51 [debug] 64780#0: posix_memalign: 00000000B7A00C80:4096 @16 2014/01/20 12:02:51 [debug] 64780#0: 1 accept: 192.168.13.45 fd:3 2014/01/20 12:02:51 [info] 64780#0: 1 client connected '192.168.13.45' 2014/01/20 12:02:51 [debug] 64780#0: 1 posix_memalign: 00000000B79F9FA0:4096 @16 2014/01/20 12:02:51 [debug] 64780#0: 1 setting chunk_size=128 2014/01/20 12:02:51 [debug] 64780#0: 1 posix_memalign: 00000000B79FAFB0:4096 @16 2014/01/20 12:02:51 [debug] 64780#0: 1 handshake: start server handshake 2014/01/20 12:02:51 [debug] 64780#0: 1 handshake: allocating buffer 2014/01/20 12:02:51 [debug] 64780#0: 1 recv: fd:3 -1 of 1537 2014/01/20 12:02:51 [debug] 64780#0: 1 recv() not ready (11: Resource temporarily unavailable) 2014/01/20 12:02:51 [debug] 64780#0: 1 event timer add: 3: 60000:1390215831274 2014/01/20 12:02:51 [debug] 64780#0: 1 epoll add event: fd:3 op:1 ev:80000001 2014/01/20 12:02:51 [debug] 64780#0: timer delta: 11913 2014/01/20 12:02:51 [debug] 64780#0: posted events 0000000000000000 2014/01/20 12:02:51 [debug] 64780#0: worker cycle 2014/01/20 12:02:51 [debug] 64780#0: epoll timer: 60000 2014/01/20 12:02:51 [debug] 64780#0: epoll: fd:3 ev:0001 d:000003FFFD13F250 2014/01/20 12:02:51 [debug] 64780#0: 1 event timer del: 3: 1390215831274 2014/01/20 12:02:51 [debug] 64780#0: 1 recv: fd:3 1537 of 1537 2014/01/20 12:02:51 [debug] 64780#0: 1 epoll del event: fd:3 op:2 ev:00000000 2014/01/20 12:02:51 [debug] 64780#0: 1 handshake: stage 2 2014/01/20 12:02:51 [debug] 64780#0: 1 handshake: peer version=2.124.0.9 epoch=0 2014/01/20 12:02:51 [debug] 64780#0: 1 handshake: digest found at pos=494 2014/01/20 12:02:51 [debug] 64780#0: 1 send: fd:3 1537 of 1537 2014/01/20 12:02:51 [debug] 64780#0: 1 handshake: stage 3 2014/01/20 12:02:51 [debug] 64780#0: 1 send: fd:3 1536 of 1536 2014/01/20 12:02:51 [debug] 64780#0: 1 handshake: stage 4 2014/01/20 12:02:51 [debug] 64780#0: 1 recv: fd:3 -1 of 1536 2014/01/20 12:02:51 [debug] 64780#0: 1 recv() not ready (11: Resource temporarily unavailable) 2014/01/20 12:02:51 [debug] 64780#0: 1 event timer add: 3: 60000:1390215831274 2014/01/20 12:02:51 [debug] 64780#0: 1 epoll add event: fd:3 op:1 ev:80000001 2014/01/20 12:02:51 [debug] 64780#0: timer delta: 0 2014/01/20 12:02:51 [debug] 64780#0: posted events 0000000000000000 2014/01/20 12:02:51 [debug] 64780#0: worker cycle 2014/01/20 12:02:51 [debug] 64780#0: epoll timer: 60000 2014/01/20 12:02:51 [debug] 64780#0: epoll: fd:3 ev:0001 d:000003FFFD13F250 2014/01/20 12:02:51 [debug] 64780#0: 1 event timer del: 3: 1390215831274 2014/01/20 12:02:51 [debug] 64780#0: 1 recv: fd:3 1536 of 1536 2014/01/20 12:02:51 [debug] 64780#0: 1 epoll del event: fd:3 op:2 ev:00000000 2014/01/20 12:02:51 [debug] 64780#0: 1 handshake: stage 5 2014/01/20 12:02:51 [debug] 64780#0: 1 handshake: done 2014/01/20 12:02:51 [debug] 64780#0: 1 event timer add: 3: 30000:1390215801277 2014/01/20 12:02:51 [debug] 64780#0: 1 ping: wait 30000ms 2014/01/20 12:02:51 [debug] 64780#0: 1 recv: fd:3 -1 of 146 2014/01/20 12:02:51 [debug] 64780#0: 1 recv() not ready (11: Resource temporarily unavailable) 2014/01/20 12:02:51 [debug] 64780#0: 1 epoll add event: fd:3 op:1 ev:80000001 2014/01/20 12:02:51 [debug] 64780#0: timer delta: 3 2014/01/20 12:02:51 [debug] 64780#0: posted events 0000000000000000 2014/01/20 12:02:51 [debug] 64780#0: worker cycle 2014/01/20 12:02:51 [debug] 64780#0: epoll timer: 30000 2014/01/20 12:02:51 [debug] 64780#0: epoll: fd:3 ev:0001 d:000003FFFD13F250 2014/01/20 12:02:51 [debug] 64780#0: 1 recv: fd:3 146 of 146 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP bheader fmt=0 csid=3 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP mheader fmt=0 amf_cmd (20) time=0+0 mlen=195 len=0 msid=0 2014/01/20 12:02:51 [debug] 64780#0: 1 reusing formerly read data: 6 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP bheader fmt=3 csid=3 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP mheader fmt=3 amf_cmd (20) time=0+0 mlen=195 len=128 msid=0 2014/01/20 12:02:51 [debug] 64780#0: 1 recv: fd:3 62 of 140 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP recv amf_cmd (20) csid=3 timestamp=0 mlen=195 msid=0 nbufs=2 2014/01/20 12:02:51 [debug] 64780#0: 1 nhandlers: 1 2014/01/20 12:02:51 [debug] 64780#0: 1 calling handler 0 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (2) 00 07 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (7) 63 6F 6E 6E 65 63 74 'connect' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF func 'connect' passed to handler 0/1 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 00 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (8) 3F F0 00 00 00 00 00 00 '????????' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 03 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (2) 00 03 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (3) 61 70 70 'app' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (2) 00 04 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (4) 76 6F 64 32 'vod2' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (2) 00 08 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (8) 66 6C 61 73 68 56 65 72 'flashVer' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (2) 00 0D '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (13) 4C 4E 58 20 39 2C 30 2C 31 32 34 2C 32 'LNX 9,0,124,2' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (2) 00 05 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (5) 74 63 55 72 6C 'tcUrl' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (2) 00 19 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (25) 72 74 6D 70 3A 2F 2F 7A 6C 69 6E 31 30 38 38 3A 'rtmp://rtmpserver:' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (2) 00 04 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (4) 66 70 61 64 'fpad' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 01 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) XX '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (2) 00 0C '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (12) 63 61 70 61 62 69 6C 69 74 69 65 73 'capabilities' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 00 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (8) 40 2E 00 00 00 00 00 00 '@.??????' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (2) 00 0B '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (11) 61 75 64 69 6F 43 6F 64 65 63 73 'audioCodecs' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 00 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (8) 40 AF CE 00 00 00 00 00 '@???????' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (2) 00 0B '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (11) 76 69 64 65 6F 43 6F 64 65 63 73 'videoCodecs' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 00 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (8) 40 6F 80 00 00 00 00 00 '@o??????' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (2) 00 0D '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (13) 76 69 64 65 6F 46 75 6E 63 74 69 6F 6E 'videoFunction' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 00 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (8) 3F F0 00 00 00 00 00 00 '????????' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (2) 00 00 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 09 '?' 2014/01/20 12:02:51 [info] 64780#0: 1 connect: app='vod2' args='' flashver='LNX 9,0,124,2' swf_url='' tc_url='rtmp://rtmpserver:1935/vod2' page_url='' acodecs=4071 vcodecs=252 object_encoding=0, client: 192.168.13.45, server: 0.0.0.0:1935 2014/01/20 12:02:51 [debug] 64780#0: 1 create: ack_size=5000000 2014/01/20 12:02:51 [debug] 64780#0: malloc: 00000000B79FBFC0:4214 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP prep ack_size (5) fmt=0 csid=2 timestamp=0 mlen=4 msid=0 nbufs=1 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP send nmsg=1, priority=0 #1 2014/01/20 12:02:51 [debug] 64780#0: 1 send: fd:3 16 of 16 2014/01/20 12:02:51 [debug] 64780#0: posted event 0000000000000000 2014/01/20 12:02:51 [debug] 64780#0: 1 create: bandwidth ack_size=5000000 limit=2 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP prep bandwidth (6) fmt=0 csid=2 timestamp=0 mlen=5 msid=0 nbufs=1 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP send nmsg=1, priority=0 #2 2014/01/20 12:02:51 [debug] 64780#0: 1 send: fd:3 17 of 17 2014/01/20 12:02:51 [debug] 64780#0: posted event 0000000000000000 2014/01/20 12:02:51 [debug] 64780#0: 1 chunk_size=4096 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP prep chunk_size (1) fmt=0 csid=2 timestamp=0 mlen=4 msid=0 nbufs=1 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP send nmsg=1, priority=0 #3 2014/01/20 12:02:51 [debug] 64780#0: 1 send: fd:3 16 of 16 2014/01/20 12:02:51 [debug] 64780#0: posted event 0000000000000000 2014/01/20 12:02:51 [debug] 64780#0: 1 create: amf nelts=4 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 07 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (7) 5F 72 65 73 75 6C 74 '_result' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 00 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (8) 3F F0 00 00 00 00 00 00 '????????' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 03 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 06 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (6) 66 6D 73 56 65 72 'fmsVer' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 0D '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (13) 46 4D 53 2F 33 2C 30 2C 31 2C 31 32 33 'FMS/3,0,1,123' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 0C '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (12) 63 61 70 61 62 69 6C 69 74 69 65 73 'capabilities' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 00 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (8) 40 3F 00 00 00 00 00 00 '@???????' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 00 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 09 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 03 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 05 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (5) 6C 65 76 65 6C 'level' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 06 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (6) 73 74 61 74 75 73 'status' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 04 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (4) 63 6F 64 65 'code' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 1D '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (29) 4E 65 74 43 6F 6E 6E 65 63 74 69 6F 6E 2E 43 6F 'NetConnection.Co' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 0B '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (11) 64 65 73 63 72 69 70 74 69 6F 6E 'description' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 15 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (21) 43 6F 6E 6E 65 63 74 69 6F 6E 20 73 75 63 63 65 'Connection succe' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 0E '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (14) 6F 62 6A 65 63 74 45 6E 63 6F 64 69 6E 67 'objectEncoding' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 00 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (8) 00 00 00 00 00 00 00 00 '????????' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 00 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 09 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP prep amf_cmd (20) fmt=0 csid=3 timestamp=0 mlen=190 msid=0 nbufs=1 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP send nmsg=1, priority=0 #4 2014/01/20 12:02:51 [debug] 64780#0: 1 send: fd:3 202 of 202 2014/01/20 12:02:51 [debug] 64780#0: posted event 0000000000000000 2014/01/20 12:02:51 [debug] 64780#0: 1 recv: fd:3 -1 of 146 2014/01/20 12:02:51 [debug] 64780#0: 1 recv() not ready (11: Resource temporarily unavailable) 2014/01/20 12:02:51 [debug] 64780#0: timer delta: 1 2014/01/20 12:02:51 [debug] 64780#0: posted events 0000000000000000 2014/01/20 12:02:51 [debug] 64780#0: worker cycle 2014/01/20 12:02:51 [debug] 64780#0: epoll timer: 29999 2014/01/20 12:02:51 [debug] 64780#0: epoll: fd:3 ev:0001 d:000003FFFD13F250 2014/01/20 12:02:51 [debug] 64780#0: 1 recv: fd:3 12 of 146 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP bheader fmt=0 csid=2 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP mheader fmt=0 ack_size (5) time=0+0 mlen=4 len=0 msid=0 2014/01/20 12:02:51 [debug] 64780#0: 1 recv: fd:3 -1 of 134 2014/01/20 12:02:51 [debug] 64780#0: 1 recv() not ready (11: Resource temporarily unavailable) 2014/01/20 12:02:51 [debug] 64780#0: timer delta: 205 2014/01/20 12:02:51 [debug] 64780#0: posted events 0000000000000000 2014/01/20 12:02:51 [debug] 64780#0: worker cycle 2014/01/20 12:02:51 [debug] 64780#0: epoll timer: 29794 2014/01/20 12:02:51 [debug] 64780#0: epoll: fd:3 ev:0001 d:000003FFFD13F250 2014/01/20 12:02:51 [debug] 64780#0: 1 recv: fd:3 37 of 134 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP recv ack_size (5) csid=2 timestamp=0 mlen=4 msid=0 nbufs=1 2014/01/20 12:02:51 [debug] 64780#0: 1 nhandlers: 1 2014/01/20 12:02:51 [debug] 64780#0: 1 calling handler 0 2014/01/20 12:02:51 [debug] 64780#0: 1 receive ack_size=1078676480 2014/01/20 12:02:51 [debug] 64780#0: 1 reusing formerly read data: 33 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP bheader fmt=1 csid=3 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP mheader fmt=1 amf_cmd (20) time=0+0 mlen=25 len=0 msid=0 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP recv amf_cmd (20) csid=3 timestamp=0 mlen=25 msid=0 nbufs=1 2014/01/20 12:02:51 [debug] 64780#0: 1 nhandlers: 1 2014/01/20 12:02:51 [debug] 64780#0: 1 calling handler 0 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (2) 00 0C '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (12) 63 72 65 61 74 65 53 74 72 65 61 6D 'createStream' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF func 'createstream' passed to handler 0/1 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 00 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (8) 40 00 00 00 00 00 00 00 '@???????' 2014/01/20 12:02:51 [info] 64780#0: 1 createStream, client: 192.168.13.45, server: 0.0.0.0:1935 2014/01/20 12:02:51 [debug] 64780#0: 1 create: amf nelts=4 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 07 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (7) 5F 72 65 73 75 6C 74 '_result' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 00 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (8) 40 00 00 00 00 00 00 00 '@???????' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 05 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 00 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (8) 3F F0 00 00 00 00 00 00 '????????' 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP prep amf_cmd (20) fmt=0 csid=3 timestamp=0 mlen=29 msid=0 nbufs=1 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP send nmsg=1, priority=0 #5 2014/01/20 12:02:51 [debug] 64780#0: 1 send: fd:3 41 of 41 2014/01/20 12:02:51 [debug] 64780#0: posted event 0000000000000000 2014/01/20 12:02:51 [debug] 64780#0: 1 recv: fd:3 -1 of 146 2014/01/20 12:02:51 [debug] 64780#0: 1 recv() not ready (11: Resource temporarily unavailable) 2014/01/20 12:02:51 [debug] 64780#0: timer delta: 35 2014/01/20 12:02:51 [debug] 64780#0: posted events 0000000000000000 2014/01/20 12:02:51 [debug] 64780#0: worker cycle 2014/01/20 12:02:51 [debug] 64780#0: epoll timer: 29759 2014/01/20 12:02:51 [debug] 64780#0: epoll: fd:3 ev:0001 d:000003FFFD13F250 2014/01/20 12:02:51 [debug] 64780#0: 1 recv: fd:3 12 of 146 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP bheader fmt=0 csid=8 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP mheader fmt=0 amf_cmd (20) time=0+0 mlen=43 len=0 msid=1 2014/01/20 12:02:51 [debug] 64780#0: 1 recv: fd:3 -1 of 134 2014/01/20 12:02:51 [debug] 64780#0: 1 recv() not ready (11: Resource temporarily unavailable) 2014/01/20 12:02:51 [debug] 64780#0: timer delta: 2 2014/01/20 12:02:51 [debug] 64780#0: posted events 0000000000000000 2014/01/20 12:02:51 [debug] 64780#0: worker cycle 2014/01/20 12:02:51 [debug] 64780#0: epoll timer: 29757 2014/01/20 12:02:51 [debug] 64780#0: epoll: fd:3 ev:0001 d:000003FFFD13F250 2014/01/20 12:02:51 [debug] 64780#0: 1 recv: fd:3 61 of 134 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP recv amf_cmd (20) csid=8 timestamp=0 mlen=43 msid=1 nbufs=1 2014/01/20 12:02:51 [debug] 64780#0: 1 nhandlers: 1 2014/01/20 12:02:51 [debug] 64780#0: 1 calling handler 0 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (2) 00 04 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (4) 70 6C 61 79 'play' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF func 'play' passed to handler 0/1 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 00 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (8) 40 08 00 00 00 00 00 00 '@???????' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 05 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (2) 00 0E '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (14) 6D 70 34 3A 73 61 6D 70 6C 65 2E 6D 70 34 'mp4:sample.mp4' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (1) 00 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read (8) C0 9F 40 00 00 00 00 00 '??@?????' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF read eof (1) 2014/01/20 12:02:51 [info] 64780#0: 1 play: name='mp4:sample.mp4' args='' start=-2000 duration=0 reset=0 silent=0, client: 192.168.13.45, server: 0.0.0.0:1935 2014/01/20 12:02:51 [info] 64780#0: 1 play: play name='mp4:sample.mp4' timestamp=-2000, client: 192.168.13.45, server: 0.0.0.0:1935 2014/01/20 12:02:51 [debug] 64780#0: 1 play: fmt=mp4-format 2014/01/20 12:02:51 [debug] 64780#0: 1 play: trying local entry 1/1 '/home/video/mp4' 2014/01/20 12:02:51 [debug] 64780#0: 1 play: open local file '/home/video/mp4/sample.mp4' 2014/01/20 12:02:51 [debug] 64780#0: 1 create: stream_begin msid=1 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP prep user (4) fmt=0 csid=2 timestamp=0 mlen=6 msid=0 nbufs=1 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP send nmsg=1, priority=0 #6 2014/01/20 12:02:51 [debug] 64780#0: 1 send: fd:3 18 of 18 2014/01/20 12:02:51 [debug] 64780#0: posted event 0000000000000000 2014/01/20 12:02:51 [debug] 64780#0: 1 create: status code='NetStream.Play.Start' level='status' desc='Start video on demand' 2014/01/20 12:02:51 [debug] 64780#0: 1 create: amf nelts=4 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 08 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (8) 6F 6E 53 74 61 74 75 73 'onStatus' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 00 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (8) 00 00 00 00 00 00 00 00 '????????' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 05 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 03 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 05 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (5) 6C 65 76 65 6C 'level' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 06 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (6) 73 74 61 74 75 73 'status' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 04 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (4) 63 6F 64 65 'code' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 14 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (20) 4E 65 74 53 74 72 65 61 6D 2E 50 6C 61 79 2E 53 'NetStream.Play.S' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 0B '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (11) 64 65 73 63 72 69 70 74 69 6F 6E 'description' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 15 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (21) 53 74 61 72 74 20 76 69 64 65 6F 20 6F 6E 20 64 'Start video on d' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 00 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 09 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP prep amf_cmd (20) fmt=0 csid=5 timestamp=0 mlen=107 msid=1 nbufs=1 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP send nmsg=1, priority=0 #7 2014/01/20 12:02:51 [debug] 64780#0: 1 send: fd:3 119 of 119 2014/01/20 12:02:51 [debug] 64780#0: posted event 0000000000000000 2014/01/20 12:02:51 [debug] 64780#0: 1 play: join 2014/01/20 12:02:51 [debug] 64780#0: 1 create: recorded msid=1 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP prep user (4) fmt=0 csid=2 timestamp=0 mlen=6 msid=0 nbufs=1 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP send nmsg=1, priority=0 #8 2014/01/20 12:02:51 [debug] 64780#0: 1 send: fd:3 18 of 18 2014/01/20 12:02:51 [debug] 64780#0: posted event 0000000000000000 2014/01/20 12:02:51 [debug] 64780#0: 1 read: 11, 000003FFFFEA63D0, 8, 0 2014/01/20 12:02:51 [debug] 64780#0: 1 mp4: skipping box 'ftyp' 2014/01/20 12:02:51 [debug] 64780#0: 1 read: 11, 000003FFFFEA63D0, 8, 20 2014/01/20 12:02:51 [debug] 64780#0: 1 mp4: skipping box 'wide' 2014/01/20 12:02:51 [debug] 64780#0: 1 read: 11, 000003FFFFEA63D0, 8, 28 2014/01/20 12:02:51 [debug] 64780#0: 1 mp4: skipping box 'mdat' 2014/01/20 12:02:51 [debug] 64780#0: 1 read: 11, 000003FFFFEA63D0, 8, 38774924 2014/01/20 12:02:51 [debug] 64780#0: 1 mp4: skipping box 'moov' 2014/01/20 12:02:51 [debug] 64780#0: 1 read: 11, 000003FFFFEA63D0, 8, 39115156 2014/01/20 12:02:51 [error] 64780#0: 1 mp4: error reading file at offset=39115156 while searching for moov box (11: Resource temporarily unavailable), client: 192.168.13.45, server: 0.0.0.0:1935 2014/01/20 12:02:51 [debug] 64780#0: 1 handler 0 failed 2014/01/20 12:02:51 [debug] 64780#0: 1 finalize session 2014/01/20 12:02:51 [debug] 64780#0: 1 post event 00000000B7A00D28 2014/01/20 12:02:51 [debug] 64780#0: timer delta: 38 2014/01/20 12:02:51 [debug] 64780#0: posted events 00000000B7A00D28 2014/01/20 12:02:51 [debug] 64780#0: posted event 00000000B7A00D28 2014/01/20 12:02:51 [debug] 64780#0: 1 delete posted event 00000000B7A00D28 2014/01/20 12:02:51 [debug] 64780#0: 1 close session 2014/01/20 12:02:51 [info] 64780#0: 1 disconnect, client: 192.168.13.45, server: 0.0.0.0:1935 2014/01/20 12:02:51 [info] 64780#0: 1 deleteStream, client: 192.168.13.45, server: 0.0.0.0:1935 2014/01/20 12:02:51 [debug] 64780#0: 1 play: close_stream 2014/01/20 12:02:51 [debug] 64780#0: 1 play: stop 2014/01/20 12:02:51 [debug] 64780#0: 1 mp4: stop timestamp=2941334950 2014/01/20 12:02:51 [debug] 64780#0: 1 create: stream_end msid=1 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP prep user (4) fmt=0 csid=2 timestamp=0 mlen=6 msid=0 nbufs=1 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP send nmsg=1, priority=0 #9 2014/01/20 12:02:51 [debug] 64780#0: 1 create: status code='NetStream.Play.Stop' level='status' desc='Stop video on demand' 2014/01/20 12:02:51 [debug] 64780#0: 1 create: amf nelts=4 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: malloc: 00000000B7A76610:4214 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 08 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (8) 6F 6E 53 74 61 74 75 73 'onStatus' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 00 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (8) 00 00 00 00 00 00 00 00 '????????' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 05 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 03 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 05 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (5) 6C 65 76 65 6C 'level' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 06 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (6) 73 74 61 74 75 73 'status' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 04 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (4) 63 6F 64 65 'code' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 13 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (19) 4E 65 74 53 74 72 65 61 6D 2E 50 6C 61 79 2E 53 'NetStream.Play.S' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 0B '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (11) 64 65 73 63 72 69 70 74 69 6F 6E 'description' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 02 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 14 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (20) 53 74 6F 70 20 76 69 64 65 6F 20 6F 6E 20 64 65 'Stop video on de' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (2) 00 00 '??' 2014/01/20 12:02:51 [debug] 64780#0: 1 AMF write (1) 09 '?' 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP prep amf_cmd (20) fmt=0 csid=5 timestamp=0 mlen=105 msid=1 nbufs=1 2014/01/20 12:02:51 [debug] 64780#0: 1 RTMP send nmsg=2, priority=0 #10 2014/01/20 12:02:51 [debug] 64780#0: 1 play: leave 2014/01/20 12:02:51 [debug] 64780#0: 1 record: close_stream 2014/01/20 12:02:51 [debug] 64780#0: 1 event timer del: 3: 1390215801277 2014/01/20 12:02:51 [debug] 64780#0: 1 free: 00000000B79FAFB0, unused: 3518 2014/01/20 12:02:51 [debug] 64780#0: 1 close connection 2014/01/20 12:02:51 [debug] 64780#0: 1 reusable connection: 0 2014/01/20 12:02:51 [debug] 64780#0: 1 free: 00000000B7A00C80, unused: 524 2014/01/20 12:02:51 [debug] 64780#0: 1 free: 00000000B79F9FA0, unused: 793 2014/01/20 12:02:51 [debug] 64780#0: posted event 0000000000000000 2014/01/20 12:02:51 [debug] 64780#0: worker cycle 2014/01/20 12:02:51 [debug] 64780#0: epoll timer: -1

Lotroj commented 9 years ago

Facing same error, when trying run rtmp on my router with openwrt.

Router is from project Turris https://www.turris.cz/en/ Nginx 1.4.4-1 with actual rtmp compiled under debian with sdk. Streming with OBS/XSplit doesnt make difference server just doesnt accept conection.

nginx cfg:

rtmp { server { listen 192.168.1.1:1935; max_message 10M; application myapp { live on; } } }

Log with debug: 2015/01/27 11:03:42 [debug] 17288#0: bind() 192.168.1.1:3095 #6 2015/01/27 11:03:42 [debug] 17288#0: bind() 192.168.1.1:1935 #7 2015/01/27 11:03:42 [notice] 17288#0: using the "epoll" event method 2015/01/27 11:03:42 [debug] 17288#0: counter: 48009080, 1 2015/01/27 11:03:42 [notice] 17288#0: nginx/1.4.4 2015/01/27 11:03:42 [notice] 17288#0: built by gcc 4.8.3 (OpenWrt/Linaro GCC 4.8-2014.04 r44153) 2015/01/27 11:03:42 [notice] 17288#0: OS: Linux 3.10.18-3a38986a4e5e869c1bdacc2a0ad7aa0f 2015/01/27 11:03:42 [notice] 17288#0: getrlimit(RLIMIT_NOFILE): 1024:4096 2015/01/27 11:03:42 [debug] 17289#0: write: 8, BFED5888, 6, 0 2015/01/27 11:03:42 [debug] 17289#0: setproctitle: "nginx: master process nginx" 2015/01/27 11:03:42 [notice] 17289#0: start worker processes 2015/01/27 11:03:42 [debug] 17289#0: channel 3:8 2015/01/27 11:03:42 [notice] 17289#0: start worker process 17290 2015/01/27 11:03:42 [debug] 17289#0: sigsuspend 2015/01/27 11:03:42 [debug] 17290#0: malloc: 10991298:8192 2015/01/27 11:03:42 [debug] 17290#0: malloc: 109A5390:106496 2015/01/27 11:03:42 [debug] 17290#0: malloc: 109BF398:53248 2015/01/27 11:03:42 [debug] 17290#0: malloc: 109CC3A0:53248 2015/01/27 11:03:42 [debug] 17290#0: epoll add event: fd:6 op:1 ev:00000001 2015/01/27 11:03:42 [debug] 17290#0: epoll add event: fd:7 op:1 ev:00000001 2015/01/27 11:03:42 [debug] 17290#0: posted event 00000000 2015/01/27 11:03:42 [debug] 17290#0: epoll add event: fd:8 op:1 ev:00000001 2015/01/27 11:03:42 [debug] 17290#0: setproctitle: "nginx: worker process" 2015/01/27 11:03:42 [debug] 17290#0: worker cycle 2015/01/27 11:03:42 [debug] 17290#0: epoll timer: -1 2015/01/27 11:03:59 [debug] 17290#0: epoll: fd:7 ev:0001 d:109A53F8 2015/01/27 11:03:59 [debug] 17290#0: accept on 192.168.1.1:1935, ready: 0 2015/01/27 11:03:59 [debug] 17290#0: posix_memalign: 10989820:4096 @16 2015/01/27 11:03:59 [debug] 17290#0: 1 accept: 192.168.1.200 fd:3 2015/01/27 11:03:59 [info] 17290#0: 1 client connected '192.168.1.200' 2015/01/27 11:03:59 [debug] 17290#0: 1 setting chunk_size=128 2015/01/27 11:03:59 [debug] 17290#0: 1 posix_memalign: 109932A0:4096 @16 2015/01/27 11:03:59 [debug] 17290#0: 1 handshake: start server handshake 2015/01/27 11:03:59 [debug] 17290#0: 1 handshake: allocating buffer 2015/01/27 11:03:59 [debug] 17290#0: 1 recv: fd:3 -1 of 1537 2015/01/27 11:03:59 [debug] 17290#0: 1 recv() not ready (11: Resource temporarily unavailable) 2015/01/27 11:03:59 [debug] 17290#0: 1 event timer add: 3: 60000:718924112 2015/01/27 11:03:59 [debug] 17290#0: 1 epoll add event: fd:3 op:1 ev:80000001 2015/01/27 11:03:59 [debug] 17290#0: timer delta: 16599 2015/01/27 11:03:59 [debug] 17290#0: posted events 00000000 2015/01/27 11:03:59 [debug] 17290#0: worker cycle 2015/01/27 11:03:59 [debug] 17290#0: epoll timer: 60000 2015/01/27 11:03:59 [debug] 17290#0: epoll: fd:3 ev:0001 d:109A54C8 2015/01/27 11:03:59 [debug] 17290#0: 1 event timer del: 3: 718924112 2015/01/27 11:03:59 [debug] 17290#0: 1 recv: fd:3 1537 of 1537 2015/01/27 11:03:59 [debug] 17290#0: 1 epoll del event: fd:3 op:2 ev:00000000 2015/01/27 11:03:59 [debug] 17290#0: 1 handshake: stage 2 2015/01/27 11:03:59 [debug] 17290#0: 1 handshake: peer version=0.0.0.0 epoch=587022850 2015/01/27 11:03:59 [debug] 17290#0: 1 handshake: old-style challenge 2015/01/27 11:03:59 [debug] 17290#0: 1 send: fd:3 1537 of 1537 2015/01/27 11:03:59 [debug] 17290#0: 1 handshake: stage 3 2015/01/27 11:03:59 [debug] 17290#0: 1 handshake: old-style response 2015/01/27 11:03:59 [debug] 17290#0: 1 send: fd:3 1536 of 1536 2015/01/27 11:03:59 [debug] 17290#0: 1 handshake: stage 4 2015/01/27 11:03:59 [debug] 17290#0: 1 recv: fd:3 -1 of 1536 2015/01/27 11:03:59 [debug] 17290#0: 1 recv() not ready (11: Resource temporarily unavailable) 2015/01/27 11:03:59 [debug] 17290#0: 1 event timer add: 3: 60000:718924112 2015/01/27 11:03:59 [debug] 17290#0: 1 epoll add event: fd:3 op:1 ev:80000001 2015/01/27 11:03:59 [debug] 17290#0: timer delta: 0 2015/01/27 11:03:59 [debug] 17290#0: posted events 00000000 2015/01/27 11:03:59 [debug] 17290#0: worker cycle 2015/01/27 11:03:59 [debug] 17290#0: epoll timer: 60000 2015/01/27 11:03:59 [debug] 17290#0: epoll: fd:3 ev:0001 d:109A54C8 2015/01/27 11:03:59 [debug] 17290#0: 1 event timer del: 3: 718924112 2015/01/27 11:03:59 [debug] 17290#0: 1 recv: fd:3 1536 of 1536 2015/01/27 11:03:59 [debug] 17290#0: 1 epoll del event: fd:3 op:2 ev:00000000 2015/01/27 11:03:59 [debug] 17290#0: 1 handshake: stage 5 2015/01/27 11:03:59 [debug] 17290#0: 1 handshake: done 2015/01/27 11:03:59 [debug] 17290#0: 1 event timer add: 3: 60000:718924113 2015/01/27 11:03:59 [debug] 17290#0: 1 ping: wait 60000ms 2015/01/27 11:03:59 [debug] 17290#0: 1 recv: fd:3 -1 of 146 2015/01/27 11:03:59 [debug] 17290#0: 1 recv() not ready (11: Resource temporarily unavailable) 2015/01/27 11:03:59 [debug] 17290#0: 1 epoll add event: fd:3 op:1 ev:80000001 2015/01/27 11:03:59 [debug] 17290#0: timer delta: 1 2015/01/27 11:03:59 [debug] 17290#0: posted events 00000000 2015/01/27 11:03:59 [debug] 17290#0: worker cycle 2015/01/27 11:03:59 [debug] 17290#0: epoll timer: 60000 2015/01/27 11:03:59 [debug] 17290#0: epoll: fd:3 ev:0001 d:109A54C8 2015/01/27 11:03:59 [debug] 17290#0: 1 recv: fd:3 146 of 146 2015/01/27 11:03:59 [debug] 17290#0: 1 RTMP bheader fmt=0 csid=2 2015/01/27 11:03:59 [debug] 17290#0: 1 RTMP mheader fmt=0 chunk_size (1) time=0+0 mlen=67108864 len=0 msid=0 2015/01/27 11:03:59 [info] 17290#0: 1 too big message: 10485760, client: 192.168.1.200, server: 192.168.1.1:1935 2015/01/27 11:03:59 [debug] 17290#0: 1 finalize session 2015/01/27 11:03:59 [debug] 17290#0: 1 post event 10989894 2015/01/27 11:03:59 [debug] 17290#0: timer delta: 0 2015/01/27 11:03:59 [debug] 17290#0: posted events 10989894 2015/01/27 11:03:59 [debug] 17290#0: posted event 10989894 2015/01/27 11:03:59 [debug] 17290#0: 1 delete posted event 10989894 2015/01/27 11:03:59 [debug] 17290#0: 1 close session 2015/01/27 11:03:59 [info] 17290#0: 1 disconnect, client: 192.168.1.200, server: 192.168.1.1:1935 2015/01/27 11:03:59 [info] 17290#0: 1 deleteStream, client: 192.168.1.200, server: 192.168.1.1:1935 2015/01/27 11:03:59 [debug] 17290#0: 1 record: close_stream 2015/01/27 11:03:59 [debug] 17290#0: 1 event timer del: 3: 718924113 2015/01/27 11:03:59 [debug] 17290#0: 1 free: 109932A0, unused: 3822 2015/01/27 11:03:59 [debug] 17290#0: 1 close connection 2015/01/27 11:03:59 [debug] 17290#0: 1 reusable connection: 0 2015/01/27 11:03:59 [debug] 17290#0: *1 free: 10989820, unused: 1392 2015/01/27 11:03:59 [debug] 17290#0: posted event 00000000 2015/01/27 11:03:59 [debug] 17290#0: worker cycle 2015/01/27 11:03:59 [debug] 17290#0: epoll timer: -1 2015/01/27 11:04:17 [notice] 17289#0: signal 15 (SIGTERM) received, exiting 2015/01/27 11:04:17 [debug] 17289#0: wake up, sigio 0 2015/01/27 11:04:17 [debug] 17289#0: child: 0 17290 e:0 t:0 d:0 r:1 j:0 2015/01/27 11:04:17 [debug] 17289#0: termination cycle: 50 2015/01/27 11:04:17 [debug] 17289#0: sigsuspend 2015/01/27 11:04:17 [debug] 17290#0: epoll: fd:8 ev:0001 d:109A5460 2015/01/27 11:04:17 [debug] 17290#0: channel handler 2015/01/27 11:04:17 [debug] 17290#0: channel: 16 2015/01/27 11:04:17 [debug] 17290#0: channel command: 4 2015/01/27 11:04:17 [debug] 17290#0: channel: -2 2015/01/27 11:04:17 [debug] 17290#0: timer delta: 18047 2015/01/27 11:04:17 [debug] 17290#0: posted events 00000000 2015/01/27 11:04:17 [notice] 17290#0: exiting 2015/01/27 11:04:17 [debug] 17290#0: flush files 2015/01/27 11:04:17 [debug] 17290#0: run cleanup: 10999E10 2015/01/27 11:04:17 [debug] 17290#0: cleanup resolver 2015/01/27 11:04:17 [debug] 17290#0: free: 109A4388 2015/01/27 11:04:17 [debug] 17290#0: free: 109A3380 2015/01/27 11:04:17 [debug] 17290#0: free: 109A2378 2015/01/27 11:04:17 [debug] 17290#0: free: 109A0368 2015/01/27 11:04:17 [debug] 17290#0: free: 1098D290, unused: 0 2015/01/27 11:04:17 [debug] 17290#0: free: 109962D0, unused: 0 2015/01/27 11:04:17 [debug] 17290#0: free: 1099A2F0, unused: 2748 2015/01/27 11:04:17 [notice] 17290#0: exit 2015/01/27 11:04:17 [notice] 17289#0: signal 17 (SIGCHLD) received 2015/01/27 11:04:17 [notice] 17289#0: worker process 17290 exited with code 0 2015/01/27 11:04:17 [debug] 17289#0: shmtx forced unlock 2015/01/27 11:04:17 [debug] 17289#0: wake up, sigio 3 2015/01/27 11:04:17 [debug] 17289#0: reap children 2015/01/27 11:04:17 [debug] 17289#0: child: 0 17290 e:1 t:1 d:0 r:1 j:0 2015/01/27 11:04:17 [notice] 17289#0: exit 2015/01/27 11:04:17 [debug] 17289#0: close listening 192.168.1.1:3095 #6 2015/01/27 11:04:17 [debug] 17289#0: close listening 192.168.1.1:1935 #7 2015/01/27 11:04:17 [debug] 17289#0: run cleanup: 10999E10 2015/01/27 11:04:17 [debug] 17289#0: cleanup resolver 2015/01/27 11:04:17 [debug] 17289#0: free: 109A4388 2015/01/27 11:04:17 [debug] 17289#0: free: 109A3380 2015/01/27 11:04:17 [debug] 17289#0: free: 109A2378 2015/01/27 11:04:17 [debug] 17289#0: free: 109A0368 2015/01/27 11:04:17 [debug] 17289#0: free: 1098D290, unused: 0 2015/01/27 11:04:17 [debug] 17289#0: free: 109962D0, unused: 0 2015/01/27 11:04:17 [debug] 17289#0: free: 1099A2F0, unused: 2753

Thank you

Edit: omg now i see this is one year old thread (

jbubik commented 4 years ago

To anyone reading this in the future:

The original code is heavily little-endian "minded". ARUT's big-endian branch fixes some but not all issues. I tried to fix it properly. My PR is in repository @ut0mt8/nginx-rtmp-module - that is where OpenWrt currently takes sources from. Should get its way to OpenWrt packages eventually.