Zulu 3.3 client can call but can not answer PSTN routed calls

configuration
Tags: #<Tag:0x00007fafc6439b58>

(Simont) #1

Hi,
Been on a voyage of discovery with Zulu 3 and have it mostly working, which is great!
I am running PBX version 12.7.6-1910-1.sng7 with latest updates
zulu is behind a firewall, of which i have opened 8002, 5060, 5061, 5160 and also 1194 for VPN
However when receiving an external call I am unable to answer on the Zulu 3.30 softphone on iphone IOS
This may well be a port issue/config issue but i am unsure where to trace this problem in the logs, calls made from the softphone to external numbers works ok (mostly)
Any pointers on this issue?
Many thanks in advance


(Dave Burgess) #2

We’re gonna need logs for this one, I think.


(Simont) #3

will this do?
[2019-11-28 15:42:12] VERBOSE[28925][C-0000019b] pbx.c: Executing [9012@zulu-mobile-phone-registered:1] Stasis(“Local/9012@zulu-mobile-phone-registered-00000075;2”, “zulu-mobile-call-registered-processing,10,90,”) in new stack
[2019-11-28 15:42:12] VERBOSE[28923][C-0000019b] app_dial.c: Called Local/9012@zulu-desktop-phone
[2019-11-28 15:42:12] VERBOSE[28923][C-0000019b] app_dial.c: Called Local/9012@zulu-mobile-phone-push
[2019-11-28 15:42:12] VERBOSE[28054][C-0000019b] app_dial.c: Local/9012@zulu-call-00000073;1 is ringing
[2019-11-28 15:42:12] VERBOSE[28924][C-0000019b] pbx.c: Executing [9012@zulu-mobile-phone-wait:1] Stasis(“Local/9012@zulu-mobile-phone-wait-00000074;2”, “zulu-mobile-call-wait-processing,10,90,”) in new stack
[2019-11-28 15:42:12] VERBOSE[28927][C-0000019b] pbx.c: Executing [9012@zulu-mobile-phone-push:1] Stasis(“Local/9012@zulu-mobile-phone-push-00000077;2”, “zulu-mobile-call-push-processing,1574948532 ,90,”) in new stack
[2019-11-28 15:42:12] VERBOSE[28926][C-0000019b] pbx.c: Executing [9012@zulu-desktop-phone:1] Stasis(“Local/9012@zulu-desktop-phone-00000076;2”, “zulu-desktop-call-processing,10,90,”) in new stack
[2019-11-28 15:42:14] VERBOSE[28925][C-0000019b] pbx.c: Executing [9012@zulu-mobile-phone-registered:2] GotoIf(“Local/9012@zulu-mobile-phone-registered-00000075;2”, “0?connected”) in new stack
[2019-11-28 15:42:14] VERBOSE[28925][C-0000019b] pbx.c: Executing [9012@zulu-mobile-phone-registered:3] Hangup(“Local/9012@zulu-mobile-phone-registered-00000075;2”, “”) in new stack
[2019-11-28 15:42:14] VERBOSE[28925][C-0000019b] pbx.c: Spawn extension (zulu-mobile-phone-registered, 9012, 3) exited non-zero on ‘Local/9012@zulu-mobile-phone-registered-00000075;2’
[2019-11-28 15:42:14] VERBOSE[28926][C-0000019b] pbx.c: Executing [9012@zulu-desktop-phone:2] GotoIf(“Local/9012@zulu-desktop-phone-00000076;2”, “0?connected”) in new stack
[2019-11-28 15:42:14] VERBOSE[28926][C-0000019b] pbx.c: Executing [9012@zulu-desktop-phone:3] Hangup(“Local/9012@zulu-desktop-phone-00000076;2”, “”) in new stack
[2019-11-28 15:42:14] VERBOSE[28926][C-0000019b] pbx.c: Spawn extension (zulu-desktop-phone, 9012, 3) exited non-zero on ‘Local/9012@zulu-desktop-phone-00000076;2’
[2019-11-28 15:42:14] VERBOSE[28924][C-0000019b] pbx.c: Executing [9012@zulu-mobile-phone-wait:2] GotoIf(“Local/9012@zulu-mobile-phone-wait-00000074;2”, “0?connected”) in new stack
[2019-11-28 15:42:14] VERBOSE[28924][C-0000019b] pbx.c: Executing [9012@zulu-mobile-phone-wait:3] Hangup(“Local/9012@zulu-mobile-phone-wait-00000074;2”, “”) in new stack
[2019-11-28 15:42:14] VERBOSE[28924][C-0000019b] pbx.c: Spawn extension (zulu-mobile-phone-wait, 9012, 3) exited non-zero on ‘Local/9012@zulu-mobile-phone-wait-00000074;2’
[2019-11-28 15:42:15] NOTICE[11610][C-000001a0] chan_sip.c: Failed to authenticate device sip:7000@89.238.246.118;tag=1908836820
[2019-11-28 15:42:18] VERBOSE[28054][C-0000019b] app_dial.c: SIP/12-00000028 connected line has changed. Saving it until answer for SIP/fpbx-1-Fm0DPWFNXrPN-00000020
[2019-11-28 15:42:18] VERBOSE[28054][C-0000019b] app_dial.c: SIP/12-00000028 answered SIP/fpbx-1-Fm0DPWFNXrPN-00000020
[2019-11-28 15:42:18] VERBOSE[28923][C-0000019b] pbx.c: Spawn extension (zulu-call, 9012, 5) exited non-zero on ‘Local/9012@zulu-call-00000073;2’
[2019-11-28 15:42:18] VERBOSE[28054][C-0000019b] pbx.c: Executing [s@macro-auto-blkvm:1] Set(“SIP/12-00000028”, “__MACRO_RESULT=”) in new stack
[2019-11-28 15:42:18] VERBOSE[28054][C-0000019b] pbx.c: Executing [s@macro-auto-blkvm:2] Set(“SIP/12-00000028”, “CFIGNORE=”) in new stack
[2019-11-28 15:42:18] VERBOSE[28054][C-0000019b] pbx.c: Executing [s@macro-auto-blkvm:3] Set(“SIP/12-00000028”, “MASTER_CHANNEL(CFIGNORE)=”) in new stack
[2019-11-28 15:42:18] VERBOSE[28054][C-0000019b] pbx.c: Executing [s@macro-auto-blkvm:4] Set(“SIP/12-00000028”, “FORWARD_CONTEXT=from-internal”) in new stack
[2019-11-28 15:42:18] VERBOSE[28054][C-0000019b] pbx.c: Executing [s@macro-auto-blkvm:5] Set(“SIP/12-00000028”, “MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal”) in new stack
[2019-11-28 15:42:18] VERBOSE[28054][C-0000019b] pbx.c: Executing [s@macro-auto-blkvm:6] Macro(“SIP/12-00000028”, “blkvm-clr,”) in new stack
[2019-11-28 15:42:18] VERBOSE[28054][C-0000019b] pbx.c: Executing [s@macro-blkvm-clr:1] Set(“SIP/12-00000028”, “SHARED(BLKVM,SIP/fpbx-1-Fm0DPWFNXrPN-00000020)=”) in new stack
[2019-11-28 15:42:18] VERBOSE[28054][C-0000019b] pbx.c: Executing [s@macro-blkvm-clr:2] Set(“SIP/12-00000028”, “GOSUB_RETVAL=”) in new stack
[2019-11-28 15:42:18] VERBOSE[28054][C-0000019b] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit(“SIP/12-00000028”, “”) in new stack
[2019-11-28 15:42:18] VERBOSE[28054][C-0000019b] pbx.c: Executing [s@macro-auto-blkvm:7] ExecIf(“SIP/12-00000028”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=12)”) in new stack
[2019-11-28 15:42:18] VERBOSE[28054][C-0000019b] pbx.c: Executing [s@macro-auto-blkvm:8] ExecIf(“SIP/12-00000028”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=Support Desk)”) in new stack
[2019-11-28 15:42:18] VERBOSE[28975][C-0000019b] bridge_channel.c: Channel SIP/12-00000028 joined ‘simple_bridge’ basic-bridge
[2019-11-28 15:42:18] VERBOSE[28054][C-0000019b] bridge_channel.c: Channel SIP/fpbx-1-Fm0DPWFNXrPN-00000020 joined ‘simple_bridge’ basic-bridge
[2019-11-28 15:42:18] WARNING[28975][C-0000019b] chan_iax2.c: Resyncing the jb. last_delay 0, this delay -284553135, threshold 1000, new offset 284553135
[2019-11-28 15:42:18] WARNING[28054][C-0000019b] chan_iax2.c: Resyncing the jb. last_delay 0, this delay -284549594, threshold 1000, new offset 284549594
[2019-11-28 15:42:18] VERBOSE[28026][C-0000019a] app_dial.c: SIP/fpbx-1-Fm0DPWFNXrPN-0000001f requested media update control 26, passing it to SIP/21-0000001e
[2019-11-28 15:42:18] VERBOSE[28026][C-0000019a] app_dial.c: SIP/fpbx-1-Fm0DPWFNXrPN-0000001f answered SIP/21-0000001e
[2019-11-28 15:42:18] VERBOSE[28983][C-0000019a] bridge_channel.c: Channel SIP/fpbx-1-Fm0DPWFNXrPN-0000001f joined ‘simple_bridge’ basic-bridge <46100f0e-b963-4bdf-8686-b69c5cc36b9a>
[2019-11-28 15:42:18] VERBOSE[28026][C-0000019a] bridge_channel.c: Channel SIP/21-0000001e joined ‘simple_bridge’ basic-bridge <46100f0e-b963-4bdf-8686-b69c5cc36b9a>
[2019-11-28 15:42:18] WARNING[28983][C-0000019a] chan_iax2.c: Resyncing the jb. last_delay 0, this delay -284553246, threshold 1000, new offset 284553246
[2019-11-28 15:42:18] WARNING[28026][C-0000019a] chan_iax2.c: Resyncing the jb. last_delay 0, this delay -284550075, threshold 1000, new offset 284550075
[2019-11-28 15:42:21] WARNING[11610] chan_sip.c: Timeout on 349884661-558786933-1918428532 on non-critical invite transaction.
[2019-11-28 15:42:21] VERBOSE[28975][C-0000019b] bridge_channel.c: Channel SIP/12-00000028 left ‘simple_bridge’ basic-bridge
[2019-11-28 15:42:21] VERBOSE[28054][C-0000019b] bridge_channel.c: Channel SIP/fpbx-1-Fm0DPWFNXrPN-00000020 left ‘simple_bridge’ basic-bridge
[2019-11-28 15:42:21] VERBOSE[28054][C-0000019b] app_macro.c: Spawn extension (macro-dial, s, 65) exited non-zero on ‘SIP/fpbx-1-Fm0DPWFNXrPN-00000020’ in macro ‘dial’
[2019-11-28 15:42:21] VERBOSE[28054][C-0000019b] pbx.c: Spawn extension (ext-group, 84051, 23) exited non-zero on ‘SIP/fpbx-1-Fm0DPWFNXrPN-00000020’
[2019-11-28 15:42:21] VERBOSE[28054][C-0000019b] pbx.c: Executing [h@ext-group:1] Macro(“SIP/fpbx-1-Fm0DPWFNXrPN-00000020”, “hangupcall,”) in new stack
[2019-11-28 15:42:21] VERBOSE[28054][C-0000019b] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/fpbx-1-Fm0DPWFNXrPN-00000020”, “1?theend”) in new stack
[2019-11-28 15:42:21] VERBOSE[28054][C-0000019b] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-11-28 15:42:21] VERBOSE[28054][C-0000019b] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/fpbx-1-Fm0DPWFNXrPN-00000020”, “0?Set(CDR(recordingfile)=)”) in new stack
[2019-11-28 15:42:21] VERBOSE[28054][C-0000019b] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“SIP/fpbx-1-Fm0DPWFNXrPN-00000020”, "Local/9012@zulu-call-00000073;1 montior file= ") in new stack
[2019-11-28 15:42:21] VERBOSE[28054][C-0000019b] pbx.c: Executing [s@macro-hangupcall:5] GotoIf(“SIP/fpbx-1-Fm0DPWFNXrPN-00000020”, “1?skipagi”) in new stack
[2019-11-28 15:42:21] VERBOSE[28054][C-0000019b] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2019-11-28 15:42:21] VERBOSE[28054][C-0000019b] pbx.c: Executing [s@macro-hangupcall:7] Hangup(“SIP/fpbx-1-Fm0DPWFNXrPN-00000020”, “”) in new stack
[2019-11-28 15:42:21] VERBOSE[28054][C-0000019b] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘SIP/fpbx-1-Fm0DPWFNXrPN-00000020’ in macro ‘hangupcall’
[2019-11-28 15:42:21] VERBOSE[28054][C-0000019b] pbx.c: Spawn extension (ext-group, h, 1) exited non-zero on ‘SIP/fpbx-1-Fm0DPWFNXrPN-00000020’
[2019-11-28 15:42:21] VERBOSE[28983][C-0000019a] bridge_channel.c: Channel SIP/fpbx-1-Fm0DPWFNXrPN-0000001f left ‘simple_bridge’ basic-bridge <46100f0e-b963-4bdf-8686-b69c5cc36b9a>
[2019-11-28 15:42:21] VERBOSE[28026][C-0000019a] bridge_channel.c: Channel SIP/21-0000001e left ‘simple_bridge’ basic-bridge <46100f0e-b963-4bdf-8686-b69c5cc36b9a>
[2019-11-28 15:42:21] VERBOSE[28026][C-0000019a] app_macro.c: Spawn extension (macro-dialout-trunk, s, 27) exited non-zero on ‘SIP/21-0000001e’ in macro ‘dialout-trunk’
[2019-11-28 15:42:21] VERBOSE[28026][C-0000019a] pbx.c: Spawn extension (restrictedroute-d40450b7032d3352c91eff77325939fa, 8572066014 , 6) exited non-zero on ‘SIP/21-0000001e’
[2019-11-28 15:42:21] VERBOSE[28026][C-0000019a] pbx.c: Executing [h@restrictedroute-d40450b7032d3352c91eff77325939fa:1] Hangup(“SIP/21-0000001e”, “”) in new stack
[2019-11-28 15:42:21] VERBOSE[28026][C-0000019a] pbx.c: Spawn extension (restrictedroute-d40450b7032d3352c91eff77325939fa, h, 1) exited non-zero on ‘SIP/21-0000001e’
[2019-11-28 15:42:28] WARNING[11610] chan_sip.c: Retransmission timeout reached on transmission 415ab9690faf8e4352a1d64a07e2122f@127.0.0.1 for seqno 205 (Critical Request) – See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 32001ms with no response


(Simont) #4

also this is in the zulu error log
2019-11-28 14:48 +02:00: e[31m[2019-11-28 14:48:09.630] [ERROR] console - e[39mError starting mobile call (registered) {“message”:"{“message”:“Channel not found”}",“stack”:“Error: {“message”:“Channel not found”}\n at SwaggerRequest.swaggerError [as errorCallback] (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/lib/client.js:246:23)\n at Object.error (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/lib/swagger.js:1077:24)\n at EventEmitter.error (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/lib/swagger.js:1296:19)\n at emitOne (events.js:116:13)\n at EventEmitter.emit (events.js:211:7)\n at emit (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/node_modules/shred/lib/shred/request.js:454:21)\n at /var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/node_modules/shred/lib/shred/request.js:473:9\n at setBodyAndFinish (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/node_modules/shred/lib/shred/response.js:103:7)\n at IncomingMessage.<anonymous> (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/node_modules/shred/lib/shred/response.js:120:7)\n at emitNone (events.js:111:20)\n at IncomingMessage.emit (events.js:208:7)\n at endReadableNT (_stream_readable.js:1064:12)\n at _combinedTickCallback (internal/process/next_tick.js:138:11)\n at process._tickDomainCallback (internal/process/next_tick.js:218:9)”,“isOperational”:true,"__error_callsites":[{},{},{},{},{},{},{},{},{},{},{},{},{},{}]}
2019-11-28 14:48 +02:00: e[31m[2019-11-28 14:48:10.158] [ERROR] console - e[39mError starting mobile call (push) {“message”:“Not Found”,“stack”:“Error: Not Found\n at Request._callback (/var/www/html/admin/modules/zulu/node/index.js:1:155155)\n at Request.self.callback (/var/www/html/admin/modules/zulu/node/node_modules/request/request.js:185:22)\n at emitTwo (events.js:126:13)\n at Request.emit (events.js:214:7)\n at Request.<anonymous> (/var/www/html/admin/modules/zulu/node/node_modules/request/request.js:1161:10)\n at emitOne (events.js:116:13)\n at Request.emit (events.js:211:7)\n at IncomingMessage.<anonymous> (/var/www/html/admin/modules/zulu/node/node_modules/request/request.js:1083:12)\n at Object.onceWrapper (events.js:313:30)\n at emitNone (events.js:111:20)\n at IncomingMessage.emit (events.js:208:7)\n at endReadableNT (_stream_readable.js:1064:12)\n at _combinedTickCallback (internal/process/next_tick.js:138:11)\n at process._tickDomainCallback (internal/process/next_tick.js:218:9)”,"__error_callsites":[{},{},{},{},{},{},{},{},{},{},{},{},{},{}]}
2019-11-28 15:04 +02:00: e[31m[2019-11-28 15:04:06.352] [ERROR] console - e[39mThere is no bridgeMap for bridge id: 5d4f319d-0f15-4c82-b989-41d34aa65e37
2019-11-28 15:41 +02:00: e[31m[2019-11-28 15:41:55.281] [ERROR] console - e[39mError adding 1574948513 .248 to 7fb8b15b-d3d0-4172-9599-bb0101660c87 {“message”:"{“message”:“Channel not found”}",“stack”:“Error: {“message”:“Channel not found”}\n at SwaggerRequest.swaggerError [as errorCallback] (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/lib/client.js:246:23)\n at Object.error (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/lib/swagger.js:1077:24)\n at EventEmitter.error (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/lib/swagger.js:1296:19)\n at emitOne (events.js:116:13)\n at EventEmitter.emit (events.js:211:7)\n at emit (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/node_modules/shred/lib/shred/request.js:454:21)\n at /var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/node_modules/shred/lib/shred/request.js:473:9\n at setBodyAndFinish (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/node_modules/shred/lib/shred/response.js:103:7)\n at IncomingMessage.<anonymous> (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/node_modules/shred/lib/shred/response.js:120:7)\n at emitNone (events.js:111:20)\n at IncomingMessage.emit (events.js:208:7)\n at endReadableNT (_stream_readable.js:1064:12)\n at _combinedTickCallback (internal/process/next_tick.js:138:11)\n at process._tickDomainCallback (internal/process/next_tick.js:218:9)”,“isOperational”:true,"__error_callsites":[{},{},{},{},{},{},{},{},{},{},{},{},{},{}]}
2019-11-28 15:41 +02:00: e[31m[2019-11-28 15:41:55.467] [ERROR] console - e[39mError handling mobile call (answer) {“message”:"{“message”:“Channel not found”}",“stack”:“Error: {“message”:“Channel not found”}\n at SwaggerRequest.swaggerError [as errorCallback] (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/lib/client.js:246:23)\n at Object.error (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/lib/swagger.js:1077:24)\n at EventEmitter.error (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/lib/swagger.js:1296:19)\n at emitOne (events.js:116:13)\n at EventEmitter.emit (events.js:211:7)\n at emit (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/node_modules/shred/lib/shred/request.js:454:21)\n at /var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/node_modules/shred/lib/shred/request.js:473:9\n at setBodyAndFinish (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/node_modules/shred/lib/shred/response.js:103:7)\n at IncomingMessage.<anonymous> (/var/www/html/admin/modules/zulu/node/node_modules/ari-client/node_modules/swagger-client/node_modules/shred/lib/shred/response.js:120:7)\n at emitNone (events.js:111:20)\n at IncomingMessage.emit (events.js:208:7)\n at endReadableNT (_stream_readable.js:1064:12)\n at _combinedTickCallback (internal/process/next_tick.js:138:11)\n at process._tickDomainCallback (internal/process/next_tick.js:218:9)”,“isOperational”:true,"__error_callsites":[{},{},{},{},{},{},{},{},{},{},{},{},{},{}]}


(Simont) #5

also from zulu out log
2019-11-28 15:41 +02:00: e[33m[2019-11-28 15:41:42.626] [WARN] console - e[39mNo notification sent for call to 12 as there are no sessions set with that default extension
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:43.074] [INFO] console - e[39mSending Call event message ‘inbound’ to 20 [d00MWFgW6AKfdo9fIahjVA==]
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:43.075] [INFO] console - e[39mSending Call event message ‘inbound’ to 20 [c6zgh5U1C4/zVAO4Mj2bqA==]
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:43.326] [INFO] console - e[39mGetting notify token of 20 for voip token dbbad57e9f436e95d2f1c763396e6dfdc27fff68726595e847e7614ff764274c
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:43.615] [INFO] console - e[39mSending call push notification to 20
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:50.067] [INFO] console - e[39mSending callCanceled push notification to 20
2019-11-28 15:41 +02:00: e[33m[2019-11-28 15:41:51.205] [WARN] console - e[39mRejecting call notification to client as previous notification already sent
2019-11-28 15:41 +02:00: e[33m[2019-11-28 15:41:51.397] [WARN] console - e[39mRejecting call notification to client as no variables were set for the notification to 14
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:53.146] [INFO] console - e[39mGetting mobile tokens of 20
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:53.152] [INFO] console - e[39mGetting mobile tokens of 14
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:53.252] [INFO] console - e[39mGetting mobile tokens of 12
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:53.682] [INFO] console - e[39mGetting mobile tokens of 20
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:53.839] [INFO] console - e[39mGetting mobile tokens of 14
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:54.031] [INFO] console - e[39mGetting mobile tokens of 12
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:54.086] [INFO] console - e[39mCall Event of ‘answered’ from BOSTON MA <18572066014> to 20
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:54.088] [INFO] console - e[39mSending Call event message ‘answered’ to 20 [d00MWFgW6AKfdo9fIahjVA==]
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:54.090] [INFO] console - e[39mSending Call event message ‘answered’ to 20 [c6zgh5U1C4/zVAO4Mj2bqA==]
2019-11-28 15:41 +02:00: e[33m[2019-11-28 15:41:54.444] [WARN] console - e[39mUnable to find call 1574948513 .248
2019-11-28 15:41 +02:00: e[33m[2019-11-28 15:41:54.447] [WARN] console - e[39mRejecting call notification to client as previous notification already sent
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:54.780] [INFO] console - e[39mCall Event of ‘inbound’ from BOSTON MA <18572066014> to 14
2019-11-28 15:41 +02:00: e[33m[2019-11-28 15:41:54.782] [WARN] console - e[39mRejecting call notification to client as previous notification already sent
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:55.001] [INFO] console - e[39mSending callCanceled push notification to 20
2019-11-28 15:41 +02:00: e[33m[2019-11-28 15:41:55.231] [WARN] console - e[39mFailed to hangup last channel in bridge: {“message”:“Channel not found”}
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:55.804] [INFO] console - e[39mSending Call event message ‘inbound’ to 14 [wb/NxWFfPaytnPQvwMDi8w==]
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:56.041] [INFO] console - e[39mSending call push notification to 14
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:56.078] [INFO] console - e[39mGetting notify token of 20 for voip token dbbad57e9f436e95d2f1c763396e6dfdc27fff68726595e847e7614ff764274c
2019-11-28 15:41 +02:00: e[32m[2019-11-28 15:41:56.382] [INFO] console - e[39mSending call push notification to 20
2019-11-28 15:42 +02:00: e[33m[2019-11-28 15:42:00.155] [WARN] console - e[39mRejecting call notification to client as previous notification already sent
2019-11-28 15:42 +02:00: e[32m[2019-11-28 15:42:00.339] [INFO] console - e[39mSending callCanceled push notification to 20
2019-11-28 15:42 +02:00: e[32m[2019-11-28 15:42:00.356] [INFO] console - e[39mSending callCanceled push notification to 14
2019-11-28 15:42 +02:00: e[33m[2019-11-28 15:42:00.688] [WARN] console - e[39mUnable to find call 1574948520 .252
2019-11-28 15:42 +02:00: e[33m[2019-11-28 15:42:02.090] [WARN] console - e[39mRejecting call notification to client as previous notification already sent
2019-11-28 15:42 +02:00: e[33m[2019-11-28 15:42:02.318] [WARN] console - e[39mRejecting call notification to client as previous notification already sent
2019-11-28 15:42 +02:00: e[33m[2019-11-28 15:42:02.321] [WARN] console - e[39mRejecting call notification to client as no variables were set for the notification to 10
2019-11-28 15:42 +02:00: e[32m[2019-11-28 15:42:04.922] [INFO] console - e[39mSending callCanceled push notification to 20