Skip to content

Instantly share code, notes, and snippets.

@dkovacevic

dkovacevic/cali.log

Last active March 14, 2018 14:17
Show Gist options

  • Select an option

    Learn more about clone URLs
  • Save dkovacevic/6d9842994329900edca6976fa1e9623c to your computer and use it in GitHub Desktop.

Select an option

Learn more about clone URLs
Save dkovacevic/6d9842994329900edca6976fa1e9623c to your computer and use it in GitHub Desktop.
Download ZIP
Raw
cali.log
The push refers to a repository [docker.io/dejankovacevic/cali-bot]
0149d369bba7: Preparing
bce23cc362cc: Pushed
233ad7a44f1d: Pushed
6b1bb7186397: Layer already exists
4e8b059ee721: Layer already exists
374dd0935303: Layer already exists
53b2942b089b: Layer already exists
4956e830da27: Layer already exists
0738910e0455: Layer already exists
21df36b5c775: Layer already exists
315fe8388056: Layer already exists
7f4734de8e3d: Layer already exists
latest: digest: sha256:c702b1fd5ef4a6c5f84a5e6679cae97314c976dc4793f79a506f419ae610bd9c size: 2829
pod "cali-759986369-frnj1" deleted
NAME READY STATUS RESTARTS AGE
cali-759986369-73dhk 0/1 ContainerCreating 0 1s
cali-759986369-frnj1 1/1 Terminating 1 41m
DK:cali-bot dejankovacevic$ kubectl logs -f cali-759986369-73dhk -n prod
OpenJDK 64-Bit Server VM warning: You have loaded library /opt/cali/libblender.so which might have disabled stack guard. The VM will try to fix the stack guard now.
It's highly recommended that you fix the library with 'execstack -c <libfile>', or link it with '-z noexecstack'.
INFO [2018-03-14 14:11:58,880] org.eclipse.jetty.util.log: Logging initialized @3227ms to org.eclipse.jetty.util.log.Slf4jLog
INFO [2018-03-14 14:11:59,141] io.dropwizard.server.DefaultServerFactory: Registering jersey handler with root path prefix: /
INFO [2018-03-14 14:11:59,143] io.dropwizard.server.DefaultServerFactory: Registering admin handler with root path prefix: /
INFO [2018-03-14 14:12:00,662] io.dropwizard.server.DefaultServerFactory: Registering jersey handler with root path prefix: /
INFO [2018-03-14 14:12:00,663] io.dropwizard.server.DefaultServerFactory: Registering admin handler with root path prefix: /
INFO [2018-03-14 14:12:00,668] io.dropwizard.server.ServerFactory: Starting Service
INFO [2018-03-14 14:12:01,135] org.eclipse.jetty.setuid.SetUIDListener: Opened application@4a8a0099{HTTP/1.1,[http/1.1]}{0.0.0.0:8080}
INFO [2018-03-14 14:12:01,139] org.eclipse.jetty.setuid.SetUIDListener: Opened admin@7c1447b5{HTTP/1.1,[http/1.1]}{0.0.0.0:8081}
INFO [2018-03-14 14:12:01,142] org.eclipse.jetty.server.Server: jetty-9.4.z-SNAPSHOT
INFO [2018-03-14 14:12:02,220] io.dropwizard.jersey.DropwizardResourceConfig: The following paths were found for the configured resources:
POST /bots (com.wire.bots.sdk.server.resources.BotsResource)
GET /bots/status (com.wire.bots.sdk.server.resources.StatusResource)
POST /bots/{bot}/messages (com.wire.bots.sdk.server.resources.MessageResource)
GET /user/auth/google_oauth2/callback (com.wire.bots.cali.AuthResource)
INFO [2018-03-14 14:12:02,223] org.eclipse.jetty.server.handler.ContextHandler: Started i.d.j.MutableServletContextHandler@12a2585b{/,null,AVAILABLE}
INFO [2018-03-14 14:12:02,240] io.dropwizard.setup.AdminEnvironment: tasks =
POST /tasks/prekeys (com.wire.bots.sdk.server.tasks.AvailablePrekeysTask)
POST /tasks/log-level (io.dropwizard.servlets.tasks.LogConfigurationTask)
POST /tasks/gc (io.dropwizard.servlets.tasks.GarbageCollectionTask)
POST /tasks/conversation (com.wire.bots.sdk.server.tasks.ConversationTask)
POST /tasks/broadcast (com.wire.bots.sdk.server.tasks.BroadcastAllTask)
INFO [2018-03-14 14:12:02,255] org.eclipse.jetty.server.handler.ContextHandler: Started i.d.j.MutableServletContextHandler@33d60b7e{/,null,AVAILABLE}
INFO [2018-03-14 14:12:02,301] org.eclipse.jetty.server.AbstractConnector: Started application@4a8a0099{HTTP/1.1,[http/1.1]}{0.0.0.0:8080}
INFO [2018-03-14 14:12:02,303] org.eclipse.jetty.server.AbstractConnector: Started admin@7c1447b5{HTTP/1.1,[http/1.1]}{0.0.0.0:8081}
INFO [2018-03-14 14:12:02,304] org.eclipse.jetty.server.Server: Started @6656ms
mixer_service: module loaded
wcall: init
service_alloc: name=mixer userid=1ec91a08-2b3d-49ee-abb9-33925e8879f7 clientid=d101d3af25c106b local_addr=35.189.223.29(32768-32867)
mixer_service: alloc: userid=1ec91a08-2b3d-49ee-abb9-33925e8879f7 clientid=d101d3af25c106b
mixer_service: creating wcall: userid=1ec91a08-2b3d-49ee-abb9-33925e8879f7 clientid=d101d3af25c106b
WAPI wcall: create userid=1ec91a08-2b3d-49ee-abb9-33925e8879f7 clientid=d101d3af25c106b
flowmgr: setting 21 ciphers for DTLS
msystem: generating ECDSA certificate
flowmgr: generate certificate took 1 ms
msystem: initializing for msys: extcodec
aucodec: opus/48000/2 (pt=96)
extcodec_audio_init: registering opus(48000) ch=2
msystem: successfully initialized
dce_init: inited=0
config(0x7f600c96ed98): sending request
wcall: creating async config polling thread
WAPI wcall: create return inst=0x7f600c2e3b18
mixer_service: calling_handler: remote = cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed
WAPI wcall(0x7f600c970dc8): added for convid=1ec91a08-2b3d-49ee-abb9-33925e8879f7.cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed
inst=0x7f600c2e3b18
wcall(0x7f600c970dc8): no turn servers
wcall(0x7f600c970dc8): start: convid=1ec91a08-2b3d-49ee-abb9-33925e8879f7.cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed
video=no audio_cbr=no
wcall(0x7f600c970dc8): set_state: none->outgoing
ecall(0x7f600c091888): set_video_send_active false econn (nil)
ecall(0x7f600c091888): start
econn: State changed: `Idle' --> `Pending-Outgoing'
ecall: alloc_mediaflow: ecall=0x7f600c091888
ecall(0x7f600c091888): alloc_mediaflow:: local IPv4 addr 10.0.7.146
[0x7f6020017128] mediaflow: local SSRC is 3506702692
kase: generated keypair (32,32 bytes)
dce: alloc: binding: 0x7f6020022228:5000
dce: init done
[0x7f6020017128] mediaflow: created new mediaflow with local port 9 and 1 audio-codecs
[0x7f6020017128] mediaflow: adding video-codecs (0)
[0x7f6020017128] mediaflow_add_data: adding data channel
ecall(0x7f600c091888): adding local host interface: eth0:10.0.7.146
[0x7f6020017128] mediaflow: add_local_host_cand: eth0:10.0.7.146 (lpref=0x0302 prio=0x7e0302ff)
ecall(0x7f600c091888): generate_offer
[0x7f6020017128] mediaflow: is_gathered: turnconnl=0 stun=0/0
[0x7f6020017128] ---------- generate SDP offer ---------
[0x7f6020017128] v=0
o=- 2230057660 375165415 IN IP4 10.0.7.146
s=-
c=IN IP4 10.0.7.146
t=0 0
a=tool:avs 4.1.snapshot (x86_64/linux)
a=ice-options:trickle
a=x-OFFER
a=group:BUNDLE audio video data
m=audio 9 UDP/TLS/RTP/SAVPF 96
b=AS:50
a=rtpmap:96 opus/48000/2
a=fmtp:96 stereo=0;sprop-stereo=0
a=rtcp:9
a=sendrecv
a=mid:audio
a=ssrc:3506702692 cname:iaEt1uPyC5pY7St
a=rtcp-mux
a=ice-ufrag:TlJiFnpoz2WGJoz
a=ice-pwd:cmKFYXFbIa2Dcif6k5LrOiMUOETZPVS
a=fingerprint:sha-256 5B:85:DF:CC:10:95:15:FE:40:CD:94:BA:07:DC:3F:06:EA:A1:5F:E8:56:74:AB:D0:CA:E1:FD:17:16:CB:95:73
a=x-KASEv1:ohq0FetJAsbJvUK89LHiK+t/VqT8hG995dKfbnUJjRQ=
a=setup:actpass
a=candidate:0a000792 1 UDP 2114126591 35.189.223.29 32773 typ host
m=video 0 UDP/TLS/RTP/SAVPF 0
m=application 9 DTLS/SCTP 5000
a=sendrecv
a=mid:data
a=ice-ufrag:TlJiFnpoz2WGJoz
a=ice-pwd:cmKFYXFbIa2Dcif6k5LrOiMUOETZPVS
a=fingerprint:sha-256 5B:85:DF:CC:10:95:15:FE:40:CD:94:BA:07:DC:3F:06:EA:A1:5F:E8:56:74:AB:D0:CA:E1:FD:17:16:CB:95:73
a=setup:actpass
a=sctpmap:5000 webrtc-datachannel 16
[0x7f6020017128] ---------------------------------------
wcall(0x7f600c970dc8): c3_message_send: convid=1ec91a08-2b3d-49ee-abb9-33925e8879f7.cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed
from=1ec91a08-2b3d-49ee-abb9-33925e8879f7.d101d3af25c106b to=ALL.ALL msg=SETUP | Request | sessid_sender=H494 ctx=0x7f602002b4e8
send_calling_handler: jblender=0x7f600c314b38 convid=1ec91a08-2b3d-49ee-abb9-33925e8879f7 userid_self=1ec91a08-2b3d-49ee-abb9-33925e8879f7 clientid_self=d101d3af25c106b userid_peer= clientid_peer=
wcall(0x7f600c2e3b18): starting config wait
14/03/2018 14:16:11 - [INFO] - id: 1ec91a08-2b3d-49ee-abb9-33925e8879f7, user: (1ec91a08-2b3d-49ee-abb9-33925e8879f7-d101d3af25c106b), peer: (null-null), content: {"version":"3.0","type":"GROUPSETUP","sessid":"H494","dest_userid":"cd284e19-c30e-44c6-8049-75070a0e0c08","dest_clientid":"a07e079b3ff0dfed","resp":false,"sdp":"v=0\r\no=- 2230057660 375165415 IN IP4 10.0.7.146\r\ns=-\r\nc=IN IP4 10.0.7.146\r\nt=0 0\r\na=tool:avs 4.1.snapshot (x86_64\/linux)\r\na=ice-options:trickle\r\na=x-OFFER\r\na=group:BUNDLE audio video data\r\nm=audio 9 UDP\/TLS\/RTP\/SAVPF 96\r\nb=AS:50\r\na=rtpmap:96 opus\/48000\/2\r\na=fmtp:96 stereo=0;sprop-stereo=0\r\na=rtcp:9\r\na=sendrecv\r\na=mid:audio\r\na=ssrc:3506702692 cname:iaEt1uPyC5pY7St\r\na=rtcp-mux\r\na=ice-ufrag:TlJiFnpoz2WGJoz\r\na=ice-pwd:cmKFYXFbIa2Dcif6k5LrOiMUOETZPVS\r\na=fingerprint:sha-256 5B:85:DF:CC:10:95:15:FE:40:CD:94:BA:07:DC:3F:06:EA:A1:5F:E8:56:74:AB:D0:CA:E1:FD:17:16:CB:95:73\r\na=x-KASEv1:ohq0FetJAsbJvUK89LHiK+t\/VqT8hG995dKfbnUJjRQ=\r\na=setup:actpass\r\na=candidate:0a000792 1 UDP 2114126591 35.189.223.29 32773 typ host\r\nm=video 0 UDP\/TLS\/RTP\/SAVPF 0\r\nm=application 9 DTLS\/SCTP 5000\r\na=sendrecv\r\na=mid:data\r\na=ice-ufrag:TlJiFnpoz2WGJoz\r\na=ice-pwd:cmKFYXFbIa2Dcif6k5LrOiMUOETZPVS\r\na=fingerprint:sha-256 5B:85:DF:CC:10:95:15:FE:40:CD:94:BA:07:DC:3F:06:EA:A1:5F:E8:56:74:AB:D0:CA:E1:FD:17:16:CB:95:73\r\na=setup:actpass\r\na=sctpmap:5000 webrtc-datachannel 16\r\n","props":{"videosend":"false","audiocbr":"false"}}, transient: false
wcall(0x7f600c970dc8): resp: status=200 reason=[] ctx=0x7f602002b4e8
mixer_service: calling_handler: remote = cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed
wcall(0x7f600c970dc8): c3_message_recv: convid=1ec91a08-2b3d-49ee-abb9-33925e8879f7.cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed
from=cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed to=1ec91a08-2b3d-49ee-abb9-33925e8879f7.d101d3af25c106b msg=SETUP | Response | sessid_sender=UO3p age=0 seconds inst=0x7f600c2e3b18
ecall(0x7f600c091888): msg_recv: SETUP | Response | sessid_sender=UO3p
econn: State changed: `Pending-Outgoing' --> `Answered'
ecall(0x7f600c091888): [ 1ec91a08-2b3d-49ee-abb9-33925e8879f7.d101d3af25c106b ] ecall: answered (reset=0, sdp=0x7f601000a5f8)
[0x7f6020017128] ---------- recv SDP answer ----------
[0x7f6020017128] v=0
o=- 866572620 952916029 IN IP4 192.168.13.98
s=-
c=IN IP4 192.168.13.98
t=0 0
a=tool:avs 4.2.snapshot (x86_64/darwin)
a=ice-options:trickle
a=group:BUNDLE audio video data
a=x-ANSWER
m=audio 9 UDP/TLS/RTP/SAVPF 96
b=AS:50
a=rtpmap:96 opus/48000/2
a=fmtp:96 stereo=0;sprop-stereo=0;useinbandfec=1
a=rtcp:9
a=sendrecv
a=ssrc:3602309130 cname:RPTrKFHZ1bAsMIS
a=rtcp-mux
a=ice-ufrag:X9CFNUH56c0QPjI
a=ice-pwd:iswBGbIrA0V1zZashKq03CIecSpcGpX
a=fingerprint:sha-256 0D:CA:FC:FE:C5:B2:C0:7B:27:ED:CC:7C:7B:95:DD:76:9D:1D:4D:82:19:92:D1:9D:58:FC:82:AA:AB:32:49:A6
a=x-KASEv1:cRIa3nunLd+ld8R2cB4xvIH/GPPzz2wx4bA0V+KeVyQ=
a=ptime:60
a=candidate:c0a80d62 1 UDP 2114126591 192.168.13.98 62442 typ host
a=candidate:0af20202 1 UDP 2113995519 10.242.2.2 54697 typ host
a=mid:audio
a=setup:active
a=candidate:2eb6cd25 1 UDP 1677722623 46.182.205.36 60840 typ srflx raddr 192.168.13.98 rport 9
a=candidate:365da96d 1 UDP 1023 54.93.169.110 38608 typ relay raddr 46.182.205.36 rport 60840
a=end-of-candidates
m=video 0 UDP/TLS/RTP/SAVPF 0
m=application 9 DTLS/SCTP 5000
a=sendrecv
a=ice-ufrag:X9CFNUH56c0QPjI
a=ice-pwd:iswBGbIrA0V1zZashKq03CIecSpcGpX
a=fingerprint:sha-256 0D:CA:FC:FE:C5:B2:C0:7B:27:ED:CC:7C:7B:95:DD:76:9D:1D:4D:82:19:92:D1:9D:58:FC:82:AA:AB:32:49:A6
a=sctpmap:5000 webrtc-datachannel 16
a=mid:data
a=setup:active
[0x7f6020017128] ------------------------------------
[0x7f6020017128] mediaflow: updating mid-value to 'audio'
[0x7f6020017128] mediaflow: updating data mid-value to 'data'
[0x7f6020017128] mediaflow: negotiated crypto = ???
[0x7f6020017128] mediaflow: negotiated both cryptos, fallback to 'KASE'
[0x7f6020017128] mediaflow: remote_setup=active
[0x7f6020017128] mediaflow: local_setup=actpass
[0x7f6020017128] mediaflow: local_setup: `actpass' --> `passive'
[0x7f6020017128] mediaflow: using crypto KASE
[0x7f6020017128] mediaflow: kase: remote public key: 71121ade7ba72ddfa577c476701e31bc81ff18f3f3cf6c31e1b03457e29e5724
kase: channel binding: "d101d3af25c106b" "a07e079b3ff0dfed" -> d6a8704fd0098d9f
[0x7f6020017128] mediaflow: KASE established
[0x7f6020017128] mediaflow: starting audio codecs (opus/48000/2)
opusmix: enc_alloc: allocating codec:opus(96) srate=48000 ch=2
opus: encoder alloc (srate = 48000, ch = 2)
opus: encode bw=full bitrate=120000 forcech=auto vbr=1 fec=0 dtx=0 complex=10
opusmix: dec_alloc: allocating codec:opus(96)
opus: decoder alloc (srate = 48000, ch = 2)
[0x7f6020017128] mediaflow: video is disabled
[0x7f6020017128] mediaflow: SDP has data channel
[0x7f6020017128] mediaflow: start_ice: starting ICE checklist with 7 remote candidates
WAPI wcall(0x7f600c970dc8): answerh(0x7f601ecf3bff) convid=1ec91a08-2b3d-49ee-abb9-33925e8879f7.cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed
echo: call 1ec91a08-2b3d-49ee-abb9-33925e8879f7.cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed
answered
WAPI wcall(0x7f600c970dc8): answerh took 0 ms
wcall(0x7f600c970dc8): set_state: outgoing->answered
[0x7f6020017128] mediaflow: ice pair established {comp=1} Succeeded {VNE } host|UDP|35.189.223.29:32773 <---> prflx|UDP|10.0.7.1:38608
[0x7f6020017128] mediaflow: trice: setting peer to prflx.10.0.7.1:38608 [avs 4.2.snapshot (x86_64/darwin)]
[0x7f6020017128] mediaflow: ICE-transport established [got_sdp=1] (peer = prflx.10.0.7.1:38608)
[0x7f6020017128] mediaflow: dtls_peer: setting to Socket|10.0.7.1:38608
[0x7f6020017128] mediaflow: dtls_estab_handler: has_data=1 active=0
[0x7f6020017128] mediaflow: dce: connecting.. (0x7f6020022228)
dce: Connecting on dce=0x7f6020022228 to port=5000
[0x7f6020017128] mediaflow(0x7f6020017128): sending DCE packet: 104
[0x7f6020017128] mediaflow: ICE+DTLS established
ecall(0x7f600c091888): mediaflow established (crypto=KASE)
wcall(0x7f600c970dc8): media established(video=0): convid=1ec91a08-2b3d-49ee-abb9-33925e8879f7.cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed
peer_userid=cd284e19-c30e-44c6-8049-75070a0e0c08 update=0
wcall(0x7f600c970dc8): set_state: answered->media-established
mixer_service: cannot find member: cd284e19-c30e-44c6-8049-75070a0e0c08
ecall: media start ecall=0x7f600c091888
[0x7f6020017128] mediaflow: local SSRC is 793183150
opusmix_ctx_alloc: codec=opus
[0x7f6020017128] mediaflow: enc_start: err=Success
opusmix: audec_start: ads=0x7f6020053028 mctxp=0x7f602001c4f0
[0x7f6020017128] mediaflow: dec_start: err=Success
ecall(0x7f600c091888): media started on ecall:0x7f600c091888
[0x7f6020017128] mediaflow: first RTP packet received (85 bytes)
[0x7f6020017128] mediaflow: ice pair established {comp=1} Succeeded {VNE } host|UDP|35.189.223.29:32773 <---> prflx|UDP|10.132.0.5:60840
dce: recv_pkt: dce=0x7f6020022228 len=424
[0x7f6020017128] mediaflow(0x7f6020017128): sending DCE packet: 332
[0x7f6020017128] mediaflow: ice pair established {comp=1} Succeeded {VNE } host|UDP|35.189.223.29:32773 <---> prflx|UDP|10.0.7.1:62442
dce: recv_pkt: dce=0x7f6020022228 len=16
sock=0x7f6020022e90 dce=0x7f6020022228 dce->pc=0x7f6020022230
dce: association change: SCTP_COMM_UP
dce: association change: streams (in/out) = (5/5)
, supports PR AUTH ASCONF MULTIBUF RE-CONFIG.
dce: mqueue_handler: id=0 <dce=0x7f6020022228>
[0x7f6020017128] mediaflow(0x7f6020017128): dce established (132 ms)
dce: mqueue_handler: id=1 <dce=0x7f6020022228>
ecall(0x7f600c091888): [ 1ec91a08-2b3d-49ee-abb9-33925e8879f7 ] datachan estab.
[0x7f6020017128] mediaflow(0x7f6020017128): sending DCE packet: 52
dce: channel id 1 created.
dce: recv_pkt: dce=0x7f6020022228 len=32
sock=0x7f6020022e90 dce=0x7f6020022228 dce->pc=0x7f6020022230
[0x7f6020017128] mediaflow(0x7f6020017128): sending DCE packet: 28
dce: recv_pkt: dce=0x7f6020022228 len=28
sock=0x7f6020022e90 dce=0x7f6020022228 dce->pc=0x7f6020022230
dce: mqueue_handler: id=2 <dce=0x7f6020022228>
ecall(0x7f600c091888): data channel opened with label calling-3.0
ecall(0x7f600c091888): data channel established
econn: State changed: `Answered' --> `DatachanEstablished'
[0x7f6020017128] mediaflow(0x7f6020017128): sending DCE packet: 232
wcall(0x7f600c970dc8): data channel established for conversation 1ec91a08-2b3d-49ee-abb9-33925e8879f7.cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed
update=0
WAPI wcall(0x7f600c970dc8): dcestabh((nil)) conv=1ec91a08-2b3d-49ee-abb9-33925e8879f7.cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed
peer_userid=cd284e19-c30e-44c6-8049-75070a0e0c08 update=0
dce: recv_pkt: dce=0x7f6020022228 len=228
sock=0x7f6020022e90 dce=0x7f6020022228 dce->pc=0x7f6020022230
dce: message received of length 200 on chan: 0
dce: mqueue_handler: id=4 <dce=0x7f6020022228>
ecall(0x7f600c091888): channel: [DatachanEstablished] receive message type 'PROPSYNC'
[0x7f6020017128] mediaflow(0x7f6020017128): sending DCE packet: 244
ecall(0x7f600c091888): propsync_handler, current recv_state false
dce: recv_pkt: dce=0x7f6020022228 len=28
sock=0x7f6020022e90 dce=0x7f6020022228 dce->pc=0x7f6020022230
dce: recv_pkt: dce=0x7f6020022228 len=228
sock=0x7f6020022e90 dce=0x7f6020022228 dce->pc=0x7f6020022230
dce: message received of length 199 on chan: 0
dce: mqueue_handler: id=4 <dce=0x7f6020022228>
ecall(0x7f600c091888): channel: [DatachanEstablished] receive message type 'PROPSYNC'
ecall(0x7f600c091888): propsync_handler, current recv_state false
[0x7f6020017128] mediaflow(0x7f6020017128): sending DCE packet: 28
opusmix: receive RTCP 40 bytes
opusmix: receive RTCP 40 bytes
opusmix: receive RTCP 40 bytes
opusmix: receive RTCP 40 bytes
dce: recv_pkt: dce=0x7f6020022228 len=180
sock=0x7f6020022e90 dce=0x7f6020022228 dce->pc=0x7f6020022230
dce: message received of length 149 on chan: 0
dce: mqueue_handler: id=4 <dce=0x7f6020022228>
ecall(0x7f600c091888): channel: [DatachanEstablished] receive message type 'HANGUP'
econn: State changed: `DatachanEstablished' --> `HangupRecv'
[0x7f6020017128] mediaflow(0x7f6020017128): sending DCE packet: 196
econn: State changed: `HangupRecv' --> `Terminating'
econn: connection closed (normal)
ecall(0x7f600c091888): econn closed (normal)
ecall(0x7f600c091888): closed (normal)
[0x7f6020017128] mediaflow: mediaflow 0x7f6020017128 destroyed (Success) got_sdp=1
[0x7f6020017128] ------------- mediaflow summary -------------
tag: 1ec91a08-2b3d-49ee-abb9-33925e8
clientid_local: d101d3af25c106b
clientid_remote: a07e079b3ff0dfed
sdp: state=3, got_sdp=1, sent_sdp=1
remote_tool=avs 4.2.snapshot (x86_64/darwin)
nat: (ready=1)
remote candidates:
----- ICE Media <0x7f602001e7c8> -----
local_role=Controlling
local_ufrag="TlJiFnpoz2WGJoz" local_pwd="cmKFYXFbIa2Dcif6k5LrOiMUOETZPVS"
Local Candidates: (1)
{1} [tx= 52, rx=1015] fnd=0a000792 prio=7e0302ff eth0:host|UDP|35.189.223.29:32773
Remote Candidates: (7)
{1} fnd=444 prio=6e0000ff prflx|UDP|10.0.7.1:62442
{1} fnd=444 prio=6e0000ff prflx|UDP|10.132.0.5:60840
{1} fnd=444 prio=6e0000ff prflx|UDP|10.0.7.1:38608
{1} fnd=c0a80d62 prio=7e0302ff host|UDP|192.168.13.98:62442
{1} fnd=0af20202 prio=7e0102ff host|UDP|10.242.2.2:54697
{1} fnd=2eb6cd25 prio=640003ff srflx|UDP|46.182.205.36:60840
{1} fnd=365da96d prio=000003ff relay|UDP|54.93.169.110:38608
Check list: (4)
{comp=1} InProgress { } host|UDP|35.189.223.29:32773 <---> host|UDP|192.168.13.98:62442
{comp=1} InProgress { } host|UDP|35.189.223.29:32773 <---> host|UDP|10.242.2.2:54697
{comp=1} InProgress { } host|UDP|35.189.223.29:32773 <---> srflx|UDP|46.182.205.36:60840
{comp=1} InProgress { } host|UDP|35.189.223.29:32773 <---> relay|UDP|54.93.169.110:38608
Valid list: (3)
{comp=1} Succeeded {VNE } host|UDP|35.189.223.29:32773 <---> prflx|UDP|10.0.7.1:62442
{comp=1} Succeeded {VNE } host|UDP|35.189.223.29:32773 <---> prflx|UDP|10.132.0.5:60840
{comp=1} Succeeded {VNE } host|UDP|35.189.223.29:32773 <---> prflx|UDP|10.0.7.1:38608
Buffered STUN Requests: (0)
Checklist: Running, interval=50ms
Pending connchecks: 4
...proto=UDP stun=0x7f602005aab8 use_cand=1 state=InProgress
...proto=UDP stun=0x7f602005b678 use_cand=1 state=InProgress
...proto=UDP stun=0x7f602005cce8 use_cand=1 state=InProgress
...proto=UDP stun=0x7f602005e518 use_cand=1 state=InProgress
STUN debug:
STUN client transactions: (4)
method=Binding tid=1bb54c49ef5d58aa3b9b2dab rto=150ms tmr=1380 n=8 interval=2400
method=Binding tid=93749bf20e8457864b5bc781 rto=150ms tmr=1415 n=8 interval=2400
method=Binding tid=ef0bef5cfa1f01e16a763832 rto=150ms tmr=1611 n=8 interval=2400
method=Binding tid=55d53f628fb320983b421126 rto=150ms tmr=1664 n=8 interval=2400
TCP Connections: (0)
selected local candidate: host|UDP|35.189.223.29:32773
selected remote candidate: prflx|UDP|10.0.7.1:38608
peer_software: avs 4.2.snapshot (x86_64/darwin)
eoc: local=0, remote=1
crypto: local = DTLS-SRTP KASE
remote = DTLS-SRTP KASE
common = KASE
ready=1
RTP packets:
bytes sent: 0 (0.0 bit/s) for 0.00 sec
bytes recv: 80226 (32203.1 bit/s) for 19.92 sec
SDP recvd: 1
SRTP dropped: 7
SRTP errors: 0
audio_active: 1
video_media: 0
TURN Clients: (0)
Interfaces: (1)
...*..eth0|10.0.7.146
-----------------------------------------------
[0x7f6020017128] ----------- mediaflow RTP summary ------------
Audio TX:
Level (dB) 0.0 0.0 0.0
Bit rate (kbps) -1.0 -1.0 -1.0
Packet rate (1/s) -1.0 -1.0 -1.0
Loss rate (pct) -1.0 -1.0 -1.0
Audio RX:
Level (dB) 0.0 0.0 0.0
Bit rate (kbps) 33.0 33.0 33.0
Packet rate (1/s) 50.0 50.0 50.0
Loss rate (pct) 0.0 0.0 0.0
Mean burst length 1.0 1.0 1.0
JB size (ms) 0.0 0.0 0.0
RTT (ms) 0.0 0.0 0.0
Packet dropouts (#) 0
-----------------------------------------------
dce: destructor: 0x7f6020022228
dce: send: dce(0x7f6020022228) not active
WAPI wcall(0x7f600c970dc8): closeh(0x7f601ecf3c14) group=no state=media-established reason=Normal
wcall(0x7f600c970dc8): set_state: remotely terminated->none
mixer_service: call closed 1ec91a08-2b3d-49ee-abb9-33925e8879f7.cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed
(Normal)
WAPI wcall(0x7f600c970dc8): closeh took 0 ms
WAPI wcall(0x7f600c970dc8): metricsh((nil)) json=0x7f60200655c8
WAPI wcall(0x7f600c970dc8): end convid=1ec91a08-2b3d-49ee-abb9-33925e8879f7.cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed
wcall(0x7f600c970dc8): end
wcall(0x7f600c970dc8): set_state: none->locally terminated
ecall(0x7f600c091888): [self=1ec91a08-2b3d-49ee-abb9-33925e8879f7] end
econn: end (state=Terminating)
wcall(0x7f600c970dc8): dtor -- started
--------------------------------------
ECALL SUMMARY 0x7f600c091888:
crypto: KASE
convid: 1ec91a08-2b3d-49ee-abb9-33925e8879f7.cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed
userid_self: 1ec91a08-2b3d-49ee-abb9-33925e8879f7
clientid: d101d3af25c106b
async_sdp: None
answered: Yes
estab_time: 170 ms
audio_setup_time: -1 ms
mediaflow: None
props_local: { videosend:false audiocbr:false }
props_remote: { videosend:false audiocbr:false }
~~~~~ econn <0x7f6020016b68> ~~~~~
state: Terminating (Outgoing)
clientid_remote: a07e079b3ff0dfed
session: H494|UO3p
timer_local: (not running)
conflict: None
userid_peer: cd284e19-c30e-44c6-8049-75070a0e0c08
Ecall message trace (8 messages):
* 0.001s send ---> via Backend SETUP Request
* 2.385s recv <--- via Backend SETUP Response age=0sec
* 2.728s send ---> via Direct PROPSYNC Request
* 2.729s recv <--- via Direct PROPSYNC Request
* 2.729s send ---> via Direct PROPSYNC Response
* 2.790s recv <--- via Direct PROPSYNC Response
* 22.486s recv <--- via Direct HANGUP Request
* 22.486s send ---> via Direct HANGUP Response
--------------------------------------
wcall(0x7f600c970dc8): dtor -- done
mixer_service: calling_handler: remote = cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed
wcall((nil)): c3_message_recv: convid=1ec91a08-2b3d-49ee-abb9-33925e8879f7.cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed
from=cd284e19-c30e-44c6-8049-75070a0e0c08.a07e079b3ff0dfed to=ALL.ALL msg=GROUPLEAVE | Request | sessid_sender= age=0 seconds inst=0x7f600c2e3b18
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment