541393966-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setIdentifier:8] Return("PJSIP/10001-K6IEGJEVY-00000860", "") in new stack
541394113-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] func_odbc.c: Found no rows [SELECT 1 FROM call_channels_shared_vars WHERE uuid = 'osl1-voip-cluster01-asterisk04-1510646692.1120' AND `key` = 'CallerType']
541394317-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setSharedVar:4] Set("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(Check)=") in new stack
541394473-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setSharedVar:5] GotoIf("PJSIP/10001-K6IEGJEVY-00000860", "0?6:8") in new stack
541394624-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx_builtins.c: Goto (setSharedVar,~~s~~,8)
541394716-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setSharedVar:8] Gosub("PJSIP/10001-K6IEGJEVY-00000860", "writeOrUpdateODBC,~~s~~,1(ODBC_SET_SHARED_VARIABLE(osl1-voip-cluster01-asterisk04-1510646692.1120,CallerType)=ENDPOINT)") in new stack
541394980-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(expression)=ODBC_SET_SHARED_VARIABLE(osl1-voip-cluster01-asterisk04-1510646692.1120,CallerType)=ENDPOINT") in new stack
541395239-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(TransID)=4179c33974d7d838e149a002bba13dbc") in new stack
541395434-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10001-K6IEGJEVY-00000860", "ODBC(transaction,asterisk2)=4179c33974d7d838e149a002bba13dbc") in new stack
541395642-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10001-K6IEGJEVY-00000860", "ODBC_SET_SHARED_VARIABLE(osl1-voip-cluster01-asterisk04-1510646692.1120,CallerType)=ENDPOINT") in new stack
541395882-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10001-K6IEGJEVY-00000860", "4179c33974d7d838e149a002bba13dbc") in new stack
541396070-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10001-K6IEGJEVY-00000860", "") in new stack
541396221-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setSharedVar:9] NoOp("PJSIP/10001-K6IEGJEVY-00000860", "Finish if_setSharedVar_164") in new stack
541396391-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setSharedVar:10] Set("PJSIP/10001-K6IEGJEVY-00000860", "CallerType=ENDPOINT") in new stack
541396554-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setSharedVar:11] Return("PJSIP/10001-K6IEGJEVY-00000860", "") in new stack
541396701-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setIAXVars:14] Set("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(ServerRestarts)=519") in new stack
541396868-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setIAXVars:15] Gosub("PJSIP/10001-K6IEGJEVY-00000860", "setSharedVar,~~s~~,1(CallerIdentifier,0884b88007753d61cb389c87864c6ff0)") in new stack
541397083-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setSharedVar:1] MSet("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(key)=CallerIdentifier") in new stack
541397254-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setSharedVar:2] MSet("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(value)=0884b88007753d61cb389c87864c6ff0") in new stack
541397443-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setSharedVar:3] Gosub("PJSIP/10001-K6IEGJEVY-00000860", "setIdentifier,~~s~~,1") in new stack
541397609-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setIdentifier:1] Set("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(TempIdentifier)=osl1-voip-cluster01-asterisk04-1510646692.1120") in new stack
541397821-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setIdentifier:2] GotoIf("PJSIP/10001-K6IEGJEVY-00000860", "0?3:4") in new stack
541397973-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx_builtins.c: Goto (setIdentifier,~~s~~,4)
541398066-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setIdentifier:4] NoOp("PJSIP/10001-K6IEGJEVY-00000860", "Finish if_setIdentifier_160") in new stack
541398238-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setIdentifier:5] GotoIf("PJSIP/10001-K6IEGJEVY-00000860", "1?6:7") in new stack
541398390-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx_builtins.c: Goto (setIdentifier,~~s~~,6)
541398483-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setIdentifier:6] Set("PJSIP/10001-K6IEGJEVY-00000860", "identifier=osl1-voip-cluster01-asterisk04-1510646692.1120") in new stack
541398684-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setIdentifier:7] NoOp("PJSIP/10001-K6IEGJEVY-00000860", "Finish if_setIdentifier_161") in new stack
541398856-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setIdentifier:8] Return("PJSIP/10001-K6IEGJEVY-00000860", "") in new stack
541399003-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] func_odbc.c: Found no rows [SELECT 1 FROM call_channels_shared_vars WHERE uuid = 'osl1-voip-cluster01-asterisk04-1510646692.1120' AND `key` = 'CallerIdentifier']
541399213-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setSharedVar:4] Set("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(Check)=") in new stack
541399369-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setSharedVar:5] GotoIf("PJSIP/10001-K6IEGJEVY-00000860", "0?6:8") in new stack
541399520-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx_builtins.c: Goto (setSharedVar,~~s~~,8)
541399612-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setSharedVar:8] Gosub("PJSIP/10001-K6IEGJEVY-00000860", "writeOrUpdateODBC,~~s~~,1(ODBC_SET_SHARED_VARIABLE(osl1-voip-cluster01-asterisk04-1510646692.1120,CallerIdentifier)=0884b88007753d61cb389c87864c6ff0)") in new stack
541399906-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(expression)=ODBC_SET_SHARED_VARIABLE(osl1-voip-cluster01-asterisk04-1510646692.1120,CallerIdentifier)=0884b88007753d61cb389c87864c6ff0") in new stack
541400195-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(TransID)=d517f31c25429a438700803f5bb64228") in new stack
541400390-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10001-K6IEGJEVY-00000860", "ODBC(transaction,asterisk2)=d517f31c25429a438700803f5bb64228") in new stack
541400598-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10001-K6IEGJEVY-00000860", "ODBC_SET_SHARED_VARIABLE(osl1-voip-cluster01-asterisk04-1510646692.1120,CallerIdentifier)=0884b88007753d61cb389c87864c6ff0") in new stack
541400868-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10001-K6IEGJEVY-00000860", "d517f31c25429a438700803f5bb64228") in new stack
541401056-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10001-K6IEGJEVY-00000860", "") in new stack
541401207-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setSharedVar:9] NoOp("PJSIP/10001-K6IEGJEVY-00000860", "Finish if_setSharedVar_164") in new stack
541401377-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setSharedVar:10] Set("PJSIP/10001-K6IEGJEVY-00000860", "CallerIdentifier=0884b88007753d61cb389c87864c6ff0") in new stack
541401570-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setSharedVar:11] Return("PJSIP/10001-K6IEGJEVY-00000860", "") in new stack
541401717-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setIAXVars:16] Gosub("PJSIP/10001-K6IEGJEVY-00000860", "startRecording,~~s~~,1") in new stack
541401883-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@startRecording:1] Set("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(Record)=no") in new stack
541402044-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@startRecording:2] GotoIf("PJSIP/10001-K6IEGJEVY-00000860", "1?3:15") in new stack
541402198-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx_builtins.c: Goto (startRecording,~~s~~,3)
541402292-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@startRecording:3] GotoIf("PJSIP/10001-K6IEGJEVY-00000860", "1?4:8") in new stack
541402445-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx_builtins.c: Goto (startRecording,~~s~~,4)
541402539-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@startRecording:4] Set("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(EndpointRecord)=no") in new stack
541402708-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@startRecording:5] GotoIf("PJSIP/10001-K6IEGJEVY-00000860", "0?6:7") in new stack
541402861-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx_builtins.c: Goto (startRecording,~~s~~,7)
541402955-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@startRecording:7] NoOp("PJSIP/10001-K6IEGJEVY-00000860", "Finish if_if_if_startRecording_61_62_63") in new stack
541403140-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@startRecording:8] NoOp("PJSIP/10001-K6IEGJEVY-00000860", "Finish if_if_startRecording_61_62") in new stack
541403319-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@startRecording:9] GotoIf("PJSIP/10001-K6IEGJEVY-00000860", "0?10:14") in new stack
541403474-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx_builtins.c: Goto (startRecording,~~s~~,14)
541403569-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@startRecording:14] NoOp("PJSIP/10001-K6IEGJEVY-00000860", "Finish if_if_startRecording_61_64") in new stack
541403749-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@startRecording:15] NoOp("PJSIP/10001-K6IEGJEVY-00000860", "Finish if_startRecording_61") in new stack
541403923-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@startRecording:16] GotoIf("PJSIP/10001-K6IEGJEVY-00000860", "0?17:30") in new stack
541404079-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx_builtins.c: Goto (startRecording,~~s~~,30)
541404174-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@startRecording:30] NoOp("PJSIP/10001-K6IEGJEVY-00000860", "Finish if_startRecording_66") in new stack
541404348-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@startRecording:31] Return("PJSIP/10001-K6IEGJEVY-00000860", "") in new stack
541404497-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setIAXVars:17] Set("PJSIP/10001-K6IEGJEVY-00000860", "CHANNEL(hangup_handler_push)=onDisconnect,~~s~~,1") in new stack
541404688-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setIAXVars:18] NoOp("PJSIP/10001-K6IEGJEVY-00000860", "Finish if_setIAXVars_21") in new stack
541404854-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setIAXVars:19] Return("PJSIP/10001-K6IEGJEVY-00000860", "") in new stack
541404999-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [004790695826@internal:3] Gosub("PJSIP/10001-K6IEGJEVY-00000860", "dialExternal,~~s~~,1(004790695826)") in new stack
541405181-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialExternal:1] MSet("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(number)=004790695826") in new stack
541405351-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialExternal:2] Set("PJSIP/10001-K6IEGJEVY-00000860", "HASH(ProviderInfo)=trunk0047telenor,+47,0047,osl1-voip-cluster01-upstream03") in new stack
541405569-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialExternal:3] Set("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(TrunkName)=trunk0047telenor") in new stack
541405745-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialExternal:4] Set("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(UpstreamServer)=osl1-voip-cluster01-upstream03") in new stack
541405940-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] func_odbc.c: Found no rows [SELECT 1 FROM ps_endpoints_has_asterisk_nodes INNER JOIN asterisk_nodes ON asterisk_nodes.name = ps_endpoints_has_asterisk_nodes.asterisk_nodes_name WHERE asterisk_nodes.name = 'osl1-voip-cluster01-asterisk04' AND ps_endpoints_has_asterisk_nodes.ps_endpoints_id = 'trunk0047telenor']
541406300-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialExternal:5] Set("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(HasAccess)=") in new stack
541406460-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialExternal:6] GotoIf("PJSIP/10001-K6IEGJEVY-00000860", "0?7:8") in new stack
541406611-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx_builtins.c: Goto (dialExternal,~~s~~,8)
541406703-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialExternal:8] NoOp("PJSIP/10001-K6IEGJEVY-00000860", "Finish if_dialExternal_33") in new stack
541406872-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialExternal:9] GotoIf("PJSIP/10001-K6IEGJEVY-00000860", "0?10:53") in new stack
541407025-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx_builtins.c: Goto (dialExternal,~~s~~,53)
541407118-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialExternal:53] Gosub("PJSIP/10001-K6IEGJEVY-00000860", "dialServer,~~s~~,1(004790695826,osl1-voip-cluster01-upstream03)") in new stack
541407327-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialServer:1] MSet("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(extension)=004790695826") in new stack
541407498-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialServer:2] MSet("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(server)=osl1-voip-cluster01-upstream03") in new stack
541407684-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialServer:3] Set("PJSIP/10001-K6IEGJEVY-00000860", "LOCAL(IdentifierHeader)=osl1-voip-cluster01-asterisk04-1510646692.1120") in new stack
541407895-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialServer:4] GotoIf("PJSIP/10001-K6IEGJEVY-00000860", "0?5:6") in new stack
541408044-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx_builtins.c: Goto (dialServer,~~s~~,6)
541408134-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialServer:6] NoOp("PJSIP/10001-K6IEGJEVY-00000860", "Finish if_dialServer_44") in new stack
541408299-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialServer:7] GotoIf("PJSIP/10001-K6IEGJEVY-00000860", "0?8:9") in new stack
541408448-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx_builtins.c: Goto (dialServer,~~s~~,9)
541408538-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialServer:9] NoOp("PJSIP/10001-K6IEGJEVY-00000860", "Finish if_dialServer_45") in new stack
541408703-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@dialServer:10] Dial("PJSIP/10001-K6IEGJEVY-00000860", "PJSIP/004790695826@osl1-voip-cluster01-upstream03,3600,oarb(setHeader,~~s~~,1(x-identifier,osl1-voip-cluster01-asterisk04-1510646692.1120))") in new stack
541408985-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] app_stack.c: PJSIP/osl1-voip-cluster01-upstream03-00000861 Internal Gosub(setHeader,~~s~~,1(x-identifier,osl1-voip-cluster01-asterisk04-1510646692.1120)) start
541409193-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setHeader:1] MSet("PJSIP/osl1-voip-cluster01-upstream03-00000861", "LOCAL(name)=x-identifier") in new stack
541409373-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setHeader:2] MSet("PJSIP/osl1-voip-cluster01-upstream03-00000861", "LOCAL(value)=osl1-voip-cluster01-asterisk04-1510646692.1120") in new stack
541409588-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setHeader:3] Set("PJSIP/osl1-voip-cluster01-upstream03-00000861", "PJSIP_HEADER(add,x-identifier)=osl1-voip-cluster01-asterisk04-1510646692.1120") in new stack
541409820-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] pbx.c: Executing [~~s~~@setHeader:4] Return("PJSIP/osl1-voip-cluster01-upstream03-00000861", "") in new stack
541409978-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] app_stack.c: Spawn extension (fromupstream, ~~s~~, 1) exited non-zero on 'PJSIP/osl1-voip-cluster01-upstream03-00000861'
541410147-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] app_stack.c: PJSIP/osl1-voip-cluster01-upstream03-00000861 Internal Gosub(setHeader,~~s~~,1(x-identifier,osl1-voip-cluster01-asterisk04-1510646692.1120)) complete GOSUB_RETVAL=
541410372-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] app_dial.c: Called PJSIP/004790695826@osl1-voip-cluster01-upstream03
541410489-[2017-11-14 09:04:52] VERBOSE[14792] netsock2.c: Using SIP RTP Audio TOS bits 40
541410570-[2017-11-14 09:04:52] VERBOSE[14792] netsock2.c: Using SIP RTP Audio CoS mark 5
541410650-[2017-11-14 09:04:52] VERBOSE[9999][C-00000232] app_dial.c: PJSIP/osl1-voip-cluster01-upstream03-00000861 is ringing
541410767-[2017-11-14 09:04:53] ERROR[4821] res_pjsip.c: Error 171060 'Unsupported transport (PJSIP_EUNSUPTRANSPORT)' sending OPTIONS request to endpoint 10016-AV8V6SSI5
541410927:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] bridge_channel.c: Channel PJSIP/10008-4JUACEMUK-0000082d left 'simple_bridge' basic-bridge <875379ef-9de1-44ee-ae05-dc7d9f19939f>
541411105:[2017-11-14 09:04:54] VERBOSE[9092][C-00000219] bridge_channel.c: Channel PJSIP/osl1-voip-cluster01-queue01-00000834 left 'simple_bridge' basic-bridge <875379ef-9de1-44ee-ae05-dc7d9f19939f>
541411295:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Spawn extension (dialServer, ~~s~~, 10) exited non-zero on 'PJSIP/10008-4JUACEMUK-0000082d'
541411442:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [h@dialServer:1] Goto("PJSIP/10008-4JUACEMUK-0000082d", "9991") in new stack
541411584:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx_builtins.c: Goto (dialServer,h,9991)
541411673:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [h@dialServer:9991] Set("PJSIP/10008-4JUACEMUK-0000082d", "~~parentcxt~~=unparkCall") in new stack
541411837:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [h@dialServer:9992] GotoIf("PJSIP/10008-4JUACEMUK-0000082d", "0?9996") in new stack
541411986:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [h@dialServer:9993] GotoIf("PJSIP/10008-4JUACEMUK-0000082d", "1?9994:9996") in new stack
541412140:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx_builtins.c: Goto (dialServer,h,9994)
541412229:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [h@dialServer:9994] StackPop("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541412374:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [h@dialServer:9995] Goto("PJSIP/10008-4JUACEMUK-0000082d", "unparkCall,h,1") in new stack
541412529:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx_builtins.c: Goto (unparkCall,h,1)
541412615:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [h@unparkCall:1] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "releaseParkinglotLock,~~s~~,1") in new stack
541412783:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@releaseParkinglotLock:1] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(ParkinglotHost)=osl1-voip-cluster01-queue01") in new stack
541412984:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@releaseParkinglotLock:2] GotoIf("PJSIP/10008-4JUACEMUK-0000082d", "0?3:4") in new stack
541413144:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx_builtins.c: Goto (releaseParkinglotLock,~~s~~,4)
541413245:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@releaseParkinglotLock:4] NoOp("PJSIP/10008-4JUACEMUK-0000082d", "Finish if_releaseParkinglotLock_127") in new stack
541413433:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@releaseParkinglotLock:5] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541413588:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [h@unparkCall:2] GotoIf("PJSIP/10008-4JUACEMUK-0000082d", "1?3:4") in new stack
541413733:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx_builtins.c: Goto (unparkCall,h,3)
541413819:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [h@unparkCall:3] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "writeOrUpdateODBC,~~s~~,1(ODBC_WRITE_ENDPOINT_STATE(10008-4JUACEMUK)=online)") in new stack
541414034:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(expression)=ODBC_WRITE_ENDPOINT_STATE(10008-4JUACEMUK)=online") in new stack
541414250:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(TransID)=796b93816ba3e10059ccc463c1201736") in new stack
541414445:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC(transaction,asterisk2)=796b93816ba3e10059ccc463c1201736") in new stack
541414653:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC_WRITE_ENDPOINT_STATE(10008-4JUACEMUK)=online") in new stack
541414850:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10008-4JUACEMUK-0000082d", "796b93816ba3e10059ccc463c1201736") in new stack
541415038:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541415189:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [h@unparkCall:4] NoOp("PJSIP/10008-4JUACEMUK-0000082d", "Finish if_catch_h_124_125") in new stack
541415352:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] app_stack.c: PJSIP/10008-4JUACEMUK-0000082d Internal Gosub(onDisconnect,~~s~~,1) start
541415487:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@onDisconnect:1] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "deleteCallChannelByChannel,~~s~~,1(osl1-voip-cluster01-asterisk04-1510646589.1069)") in new stack
541415714:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:1] MSet("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(channel)=osl1-voip-cluster01-asterisk04-1510646589.1069") in new stack
541415933:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:2] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(CallChannelId)=2824058") in new stack
541416118:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:3] GotoIf("PJSIP/10008-4JUACEMUK-0000082d", "1?4:6") in new stack
541416283:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx_builtins.c: Goto (deleteCallChannelByChannel,~~s~~,4)
541416389:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:4] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "preDeleteCleanup,~~s~~,1(2824058)") in new stack
541416581:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:1] MSet("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(callchannelid)=2824058") in new stack
541416757:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:2] Set("PJSIP/10008-4JUACEMUK-0000082d", "HASH(CallChannelInfo)=451dadec-c912-11e7-80ae-0050568bcea9,551,ENDPOINT,EXTERNAL,+4797961214,10008-4JUACEMUK") in new stack
541417012:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:3] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(UniqueIdentifier)=451dadec-c912-11e7-80ae-0050568bcea9") in new stack
541417219:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:4] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(CallerType)=ENDPOINT") in new stack
541417392:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:5] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(CalleeType)=EXTERNAL") in new stack
541417565:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:6] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(CallerId)=10008-4JUACEMUK") in new stack
541417743:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:7] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(CalleeId)=+4797961214") in new stack
541417917:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:8] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(CalleeTenantId)=551") in new stack
541418089:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:9] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "writeOrUpdateODBC,~~s~~,1(ODBC_UPDATE_CALL_HISTORY_END_TIME(451dadec-c912-11e7-80ae-0050568bcea9)=)") in new stack
541418337:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(expression)=ODBC_UPDATE_CALL_HISTORY_END_TIME(451dadec-c912-11e7-80ae-0050568bcea9)=") in new stack
541418576:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(TransID)=440ab1ae99c77874b7892bf4a363f3e9") in new stack
541418771:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC(transaction,asterisk2)=440ab1ae99c77874b7892bf4a363f3e9") in new stack
541418979:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC_UPDATE_CALL_HISTORY_END_TIME(451dadec-c912-11e7-80ae-0050568bcea9)=") in new stack
541419199:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10008-4JUACEMUK-0000082d", "440ab1ae99c77874b7892bf4a363f3e9") in new stack
541419387:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541419538:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:10] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "writeOrUpdateODBC,~~s~~,1(ODBC_UPDATE_CALL_HISTORY_CALLEE_IDENTIFIER(451dadec-c912-11e7-80ae-0050568bcea9)=)") in new stack
541419796:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(expression)=ODBC_UPDATE_CALL_HISTORY_CALLEE_IDENTIFIER(451dadec-c912-11e7-80ae-0050568bcea9)=") in new stack
541420044:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(TransID)=555d1d05dfeea8d3f3041c96316ee4c4") in new stack
541420239:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC(transaction,asterisk2)=555d1d05dfeea8d3f3041c96316ee4c4") in new stack
541420447:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC_UPDATE_CALL_HISTORY_CALLEE_IDENTIFIER(451dadec-c912-11e7-80ae-0050568bcea9)=") in new stack
541420676:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10008-4JUACEMUK-0000082d", "555d1d05dfeea8d3f3041c96316ee4c4") in new stack
541420864:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541421015:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:11] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "writeOrUpdateODBC,~~s~~,1(ODBC_CLEANUP_DIAL_INCOMING(2824058)=)") in new stack
541421228:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(expression)=ODBC_CLEANUP_DIAL_INCOMING(2824058)=") in new stack
541421431:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(TransID)=f151833b712417b1e353c28f86d8e10d") in new stack
541421626:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC(transaction,asterisk2)=f151833b712417b1e353c28f86d8e10d") in new stack
541421834:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC_CLEANUP_DIAL_INCOMING(2824058)=") in new stack
541422018:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10008-4JUACEMUK-0000082d", "f151833b712417b1e353c28f86d8e10d") in new stack
541422206:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541422357:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:12] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "writeOrUpdateODBC,~~s~~,1(ODBC_CLEANUP_PARK_META(2824058)=)") in new stack
541422566:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(expression)=ODBC_CLEANUP_PARK_META(2824058)=") in new stack
541422765:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(TransID)=5899dbcd30f433ae6bb880083a3b54a0") in new stack
541422960:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC(transaction,asterisk2)=5899dbcd30f433ae6bb880083a3b54a0") in new stack
541423168:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC_CLEANUP_PARK_META(2824058)=") in new stack
541423348:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10008-4JUACEMUK-0000082d", "5899dbcd30f433ae6bb880083a3b54a0") in new stack
541423536:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541423687:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:13] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "writeOrUpdateODBC,~~s~~,1(ODBC_CLEANUP_SOFT_TRANSFERS(2824058)=)") in new stack
541423901:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(expression)=ODBC_CLEANUP_SOFT_TRANSFERS(2824058)=") in new stack
541424105:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(TransID)=7d0b9580756bb3cb2f76c3a885ffbbd1") in new stack
541424300:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC(transaction,asterisk2)=7d0b9580756bb3cb2f76c3a885ffbbd1") in new stack
541424508:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC_CLEANUP_SOFT_TRANSFERS(2824058)=") in new stack
541424693:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10008-4JUACEMUK-0000082d", "7d0b9580756bb3cb2f76c3a885ffbbd1") in new stack
541424881:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541425032:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:14] GotoIf("PJSIP/10008-4JUACEMUK-0000082d", "0?15:17") in new stack
541425190:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx_builtins.c: Goto (preDeleteCleanup,~~s~~,17)
541425287:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:17] NoOp("PJSIP/10008-4JUACEMUK-0000082d", "Finish if_preDeleteCleanup_158") in new stack
541425466:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:18] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "writeOrUpdateODBC,~~s~~,1(ODBC_POST_CALL_ENDPOINT_STATE_ONLINE(10008-4JUACEMUK,+4797961214)=)") in new stack
541425709:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(expression)=ODBC_POST_CALL_ENDPOINT_STATE_ONLINE(10008-4JUACEMUK,+4797961214)=") in new stack
541425942:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(TransID)=6432b442be5d29dc5824e684704402aa") in new stack
541426137:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC(transaction,asterisk2)=6432b442be5d29dc5824e684704402aa") in new stack
541426345:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC_POST_CALL_ENDPOINT_STATE_ONLINE(10008-4JUACEMUK,+4797961214)=") in new stack
541426559:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10008-4JUACEMUK-0000082d", "6432b442be5d29dc5824e684704402aa") in new stack
541426747:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541426898:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:19] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "writeOrUpdateODBC,~~s~~,1(ODBC_POST_CALL_ENDPOINT_STATE_OFFLINE(10008-4JUACEMUK,+4797961214)=)") in new stack
541427142:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(expression)=ODBC_POST_CALL_ENDPOINT_STATE_OFFLINE(10008-4JUACEMUK,+4797961214)=") in new stack
541427376:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(TransID)=05e158742ea60c17395b4aa07a057423") in new stack
541427571:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC(transaction,asterisk2)=05e158742ea60c17395b4aa07a057423") in new stack
541427779:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC_POST_CALL_ENDPOINT_STATE_OFFLINE(10008-4JUACEMUK,+4797961214)=") in new stack
541427994:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10008-4JUACEMUK-0000082d", "05e158742ea60c17395b4aa07a057423") in new stack
541428182:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541428333:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@preDeleteCleanup:20] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541428484:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:5] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "writeOrUpdateODBC,~~s~~,1(ODBC_DELETE_CALL_CHANNEL_INFO_BY_CALLER_CHANNEL(osl1-voip-cluster01-asterisk04-1510646589.1069)=)") in new stack
541428766:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(expression)=ODBC_DELETE_CALL_CHANNEL_INFO_BY_CALLER_CHANNEL(osl1-voip-cluster01-asterisk04-1510646589.1069)=") in new stack
541429029:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(TransID)=e9826580c2bf0c44da7c99865ab2f0dd") in new stack
541429224:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC(transaction,asterisk2)=e9826580c2bf0c44da7c99865ab2f0dd") in new stack
541429432:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC_DELETE_CALL_CHANNEL_INFO_BY_CALLER_CHANNEL(osl1-voip-cluster01-asterisk04-1510646589.1069)=") in new stack
541429676:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10008-4JUACEMUK-0000082d", "e9826580c2bf0c44da7c99865ab2f0dd") in new stack
541429864:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541430015:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:6] NoOp("PJSIP/10008-4JUACEMUK-0000082d", "Finish if_deleteCallChannelByChannel_155") in new stack
541430213:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] func_odbc.c: Found no rows [SELECT id FROM call_channels WHERE callee_channel = 'osl1-voip-cluster01-asterisk04-1510646589.1069']
541430391:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:7] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(CallChannelId)=") in new stack
541430569:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:8] GotoIf("PJSIP/10008-4JUACEMUK-0000082d", "0?9:11") in new stack
541430735:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx_builtins.c: Goto (deleteCallChannelByChannel,~~s~~,11)
541430842:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:11] NoOp("PJSIP/10008-4JUACEMUK-0000082d", "Finish if_deleteCallChannelByChannel_156") in new stack
541431041:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:12] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541431202:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@onDisconnect:2] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "writeOrUpdateODBC,~~s~~,1(ODBC_DELETE_QUEUES_JOINED(osl1-voip-cluster01-asterisk04-1510646589.1069)=)") in new stack
541431448:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(expression)=ODBC_DELETE_QUEUES_JOINED(osl1-voip-cluster01-asterisk04-1510646589.1069)=") in new stack
541431689:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(TransID)=554a6f34a313210dd991c5ce801d5ad0") in new stack
541431884:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC(transaction,asterisk2)=554a6f34a313210dd991c5ce801d5ad0") in new stack
541432092:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC_DELETE_QUEUES_JOINED(osl1-voip-cluster01-asterisk04-1510646589.1069)=") in new stack
541432314:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10008-4JUACEMUK-0000082d", "554a6f34a313210dd991c5ce801d5ad0") in new stack
541432502:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541432653:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@onDisconnect:3] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "writeOrUpdateODBC,~~s~~,1(ODBC_DELETE_DTMF_DATA(osl1-voip-cluster01-asterisk04-1510646589.1069)=)") in new stack
541432895:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(expression)=ODBC_DELETE_DTMF_DATA(osl1-voip-cluster01-asterisk04-1510646589.1069)=") in new stack
541433132:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(TransID)=ee1bf83d7ec912fee9e54027f7e03901") in new stack
541433327:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC(transaction,asterisk2)=ee1bf83d7ec912fee9e54027f7e03901") in new stack
541433535:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC_DELETE_DTMF_DATA(osl1-voip-cluster01-asterisk04-1510646589.1069)=") in new stack
541433753:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10008-4JUACEMUK-0000082d", "ee1bf83d7ec912fee9e54027f7e03901") in new stack
541433941:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541434092:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@onDisconnect:4] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541434238:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] app_stack.c: Spawn extension (unparkCall, h, 5) exited non-zero on 'PJSIP/10008-4JUACEMUK-0000082d'
541434386:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] app_stack.c: PJSIP/10008-4JUACEMUK-0000082d Internal Gosub(onDisconnect,~~s~~,1) complete GOSUB_RETVAL=
541434538:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] app_stack.c: PJSIP/10008-4JUACEMUK-0000082d Internal Gosub(removeSharedVarLock,~~s~~,1) start
541434680:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@removeSharedVarLock:1] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "setIdentifier,~~s~~,1") in new stack
541434853:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@setIdentifier:1] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(TempIdentifier)=osl1-voip-cluster01-asterisk05-1510646588.1071") in new stack
541435065:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@setIdentifier:2] GotoIf("PJSIP/10008-4JUACEMUK-0000082d", "0?3:4") in new stack
541435217:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx_builtins.c: Goto (setIdentifier,~~s~~,4)
541435310:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@setIdentifier:4] NoOp("PJSIP/10008-4JUACEMUK-0000082d", "Finish if_setIdentifier_160") in new stack
541435482:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@setIdentifier:5] GotoIf("PJSIP/10008-4JUACEMUK-0000082d", "1?6:7") in new stack
541435634:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx_builtins.c: Goto (setIdentifier,~~s~~,6)
541435727:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@setIdentifier:6] Set("PJSIP/10008-4JUACEMUK-0000082d", "identifier=osl1-voip-cluster01-asterisk05-1510646588.1071") in new stack
541435928:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@setIdentifier:7] NoOp("PJSIP/10008-4JUACEMUK-0000082d", "Finish if_setIdentifier_161") in new stack
541436100:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@setIdentifier:8] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541436247:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@removeSharedVarLock:2] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "writeOrUpdateODBC,~~s~~,1(ODBC_REMOVE_SHARED_VARIABLE_LOCK(osl1-voip-cluster01-asterisk05-1510646588.1071,osl1-voip-cluster01-asterisk04-1510646589.1069)=)") in new stack
541436554:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(expression)=ODBC_REMOVE_SHARED_VARIABLE_LOCK(osl1-voip-cluster01-asterisk05-1510646588.1071,osl1-voip-cluster01-asterisk04-1510646589.1069)=") in new stack
541436849:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(TransID)=2ffc1356523b3e101a37c3047d599e53") in new stack
541437044:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC(transaction,asterisk2)=2ffc1356523b3e101a37c3047d599e53") in new stack
541437252:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC_REMOVE_SHARED_VARIABLE_LOCK(osl1-voip-cluster01-asterisk05-1510646588.1071,osl1-voip-cluster01-asterisk04-1510646589.1069)=") in new stack
541437528:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10008-4JUACEMUK-0000082d", "2ffc1356523b3e101a37c3047d599e53") in new stack
541437716:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541437867:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@removeSharedVarLock:3] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "cleanupSharedVars,~~s~~,1") in new stack
541438044:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@cleanupSharedVars:1] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "setIdentifier,~~s~~,1") in new stack
541438215:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@setIdentifier:1] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(TempIdentifier)=osl1-voip-cluster01-asterisk05-1510646588.1071") in new stack
541438427:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@setIdentifier:2] GotoIf("PJSIP/10008-4JUACEMUK-0000082d", "0?3:4") in new stack
541438579:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx_builtins.c: Goto (setIdentifier,~~s~~,4)
541438672:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@setIdentifier:4] NoOp("PJSIP/10008-4JUACEMUK-0000082d", "Finish if_setIdentifier_160") in new stack
541438844:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@setIdentifier:5] GotoIf("PJSIP/10008-4JUACEMUK-0000082d", "1?6:7") in new stack
541438996:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx_builtins.c: Goto (setIdentifier,~~s~~,6)
541439089:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@setIdentifier:6] Set("PJSIP/10008-4JUACEMUK-0000082d", "identifier=osl1-voip-cluster01-asterisk05-1510646588.1071") in new stack
541439290:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@setIdentifier:7] NoOp("PJSIP/10008-4JUACEMUK-0000082d", "Finish if_setIdentifier_161") in new stack
541439462:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@setIdentifier:8] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541439609:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@cleanupSharedVars:2] GotoIf("PJSIP/10008-4JUACEMUK-0000082d", "1?3:7") in new stack
541439765:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx_builtins.c: Goto (cleanupSharedVars,~~s~~,3)
541439862:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@cleanupSharedVars:3] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(SharedVarLockCheck)=0") in new stack
541440037:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@cleanupSharedVars:4] GotoIf("PJSIP/10008-4JUACEMUK-0000082d", "1?5:6") in new stack
541440193:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx_builtins.c: Goto (cleanupSharedVars,~~s~~,5)
541440290:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@cleanupSharedVars:5] Gosub("PJSIP/10008-4JUACEMUK-0000082d", "writeOrUpdateODBC,~~s~~,1(ODBC_DELETE_SHARED_VARIABLES(osl1-voip-cluster01-asterisk05-1510646588.1071)=)") in new stack
541440544:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(expression)=ODBC_DELETE_SHARED_VARIABLES(osl1-voip-cluster01-asterisk05-1510646588.1071)=") in new stack
541440788:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10008-4JUACEMUK-0000082d", "LOCAL(TransID)=720554c38ce92615a9d316f25379cc00") in new stack
541440983:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC(transaction,asterisk2)=720554c38ce92615a9d316f25379cc00") in new stack
541441191:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10008-4JUACEMUK-0000082d", "ODBC_DELETE_SHARED_VARIABLES(osl1-voip-cluster01-asterisk05-1510646588.1071)=") in new stack
541441416:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10008-4JUACEMUK-0000082d", "720554c38ce92615a9d316f25379cc00") in new stack
541441604:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541441755:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@cleanupSharedVars:6] NoOp("PJSIP/10008-4JUACEMUK-0000082d", "Finish if_if_cleanupSharedVars_166_167") in new stack
541441942:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@cleanupSharedVars:7] NoOp("PJSIP/10008-4JUACEMUK-0000082d", "Finish if_cleanupSharedVars_166") in new stack
541442122:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@cleanupSharedVars:8] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541442273:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] pbx.c: Executing [~~s~~@removeSharedVarLock:4] Return("PJSIP/10008-4JUACEMUK-0000082d", "") in new stack
541442426:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] app_stack.c: Spawn extension (unparkCall, h, 5) exited non-zero on 'PJSIP/10008-4JUACEMUK-0000082d'
541442574:[2017-11-14 09:04:54] VERBOSE[9087][C-00000219] app_stack.c: PJSIP/10008-4JUACEMUK-0000082d Internal Gosub(removeSharedVarLock,~~s~~,1) complete GOSUB_RETVAL=
541442733:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] bridge_channel.c: Channel PJSIP/10004-U9A8Y9QL5-00000820 left 'simple_bridge' basic-bridge <14266455-0363-497b-b5fe-2481681af969>
541442911:[2017-11-14 09:04:54] VERBOSE[8758][C-00000213] bridge_channel.c: Channel PJSIP/osl1-voip-cluster01-upstream03-00000821 left 'simple_bridge' basic-bridge <14266455-0363-497b-b5fe-2481681af969>
541443104:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Spawn extension (dialServer, ~~s~~, 10) exited non-zero on 'PJSIP/10004-U9A8Y9QL5-00000820'
541443251:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [h@dialServer:1] Goto("PJSIP/10004-U9A8Y9QL5-00000820", "9991") in new stack
541443393:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx_builtins.c: Goto (dialServer,h,9991)
541443482:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [h@dialServer:9991] Set("PJSIP/10004-U9A8Y9QL5-00000820", "~~parentcxt~~=pluckCallerFromCallId") in new stack
541443657:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [h@dialServer:9992] GotoIf("PJSIP/10004-U9A8Y9QL5-00000820", "0?9996") in new stack
541443806:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [h@dialServer:9993] GotoIf("PJSIP/10004-U9A8Y9QL5-00000820", "1?9994:9996") in new stack
541443960:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx_builtins.c: Goto (dialServer,h,9994)
541444049:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [h@dialServer:9994] StackPop("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541444194:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [h@dialServer:9995] Goto("PJSIP/10004-U9A8Y9QL5-00000820", "pluckCallerFromCallId,h,1") in new stack
541444360:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx_builtins.c: Goto (pluckCallerFromCallId,h,1)
541444457:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [h@pluckCallerFromCallId:1] GotoIf("PJSIP/10004-U9A8Y9QL5-00000820", "0?2:3") in new stack
541444613:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx_builtins.c: Goto (pluckCallerFromCallId,h,3)
541444710:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [h@pluckCallerFromCallId:3] NoOp("PJSIP/10004-U9A8Y9QL5-00000820", "Finish if_catch_h_103_104") in new stack
541444884:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] app_stack.c: PJSIP/10004-U9A8Y9QL5-00000820 Internal Gosub(onDisconnect,~~s~~,1) start
541445019:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@onDisconnect:1] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "deleteCallChannelByChannel,~~s~~,1(osl1-voip-cluster01-asterisk04-1510646575.1056)") in new stack
541445246:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:1] MSet("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(channel)=osl1-voip-cluster01-asterisk04-1510646575.1056") in new stack
541445465:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] func_odbc.c: Found no rows [SELECT id FROM call_channels WHERE caller_channel = 'osl1-voip-cluster01-asterisk04-1510646575.1056']
541445643:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:2] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(CallChannelId)=") in new stack
541445821:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:3] GotoIf("PJSIP/10004-U9A8Y9QL5-00000820", "0?4:6") in new stack
541445986:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx_builtins.c: Goto (deleteCallChannelByChannel,~~s~~,6)
541446092:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:6] NoOp("PJSIP/10004-U9A8Y9QL5-00000820", "Finish if_deleteCallChannelByChannel_155") in new stack
541446290:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:7] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(CallChannelId)=2823929") in new stack
541446475:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:8] GotoIf("PJSIP/10004-U9A8Y9QL5-00000820", "1?9:11") in new stack
541446641:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx_builtins.c: Goto (deleteCallChannelByChannel,~~s~~,9)
541446747:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:9] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "preDeleteCleanup,~~s~~,1(2823929)") in new stack
541446939:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:1] MSet("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(callchannelid)=2823929") in new stack
541447115:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:2] Set("PJSIP/10004-U9A8Y9QL5-00000820", "HASH(CallChannelInfo)=390b82d6-c912-11e7-80ae-0050568bcea9,316,EXTERNAL,ENDPOINT,10004-U9A8Y9QL5,+4797062352") in new stack
541447370:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:3] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(UniqueIdentifier)=390b82d6-c912-11e7-80ae-0050568bcea9") in new stack
541447577:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:4] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(CallerType)=EXTERNAL") in new stack
541447750:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:5] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(CalleeType)=ENDPOINT") in new stack
541447923:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:6] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(CallerId)=+4797062352") in new stack
541448097:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:7] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(CalleeId)=10004-U9A8Y9QL5") in new stack
541448275:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:8] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(CalleeTenantId)=316") in new stack
541448447:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:9] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "writeOrUpdateODBC,~~s~~,1(ODBC_UPDATE_CALL_HISTORY_END_TIME(390b82d6-c912-11e7-80ae-0050568bcea9)=)") in new stack
541448695:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(expression)=ODBC_UPDATE_CALL_HISTORY_END_TIME(390b82d6-c912-11e7-80ae-0050568bcea9)=") in new stack
541448934:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(TransID)=a979ab79b8ae48bf12a55f494dd0b3cf") in new stack
541449129:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC(transaction,asterisk2)=a979ab79b8ae48bf12a55f494dd0b3cf") in new stack
541449337:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC_UPDATE_CALL_HISTORY_END_TIME(390b82d6-c912-11e7-80ae-0050568bcea9)=") in new stack
541449557:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10004-U9A8Y9QL5-00000820", "a979ab79b8ae48bf12a55f494dd0b3cf") in new stack
541449745:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541449896:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:10] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "writeOrUpdateODBC,~~s~~,1(ODBC_UPDATE_CALL_HISTORY_CALLEE_IDENTIFIER(390b82d6-c912-11e7-80ae-0050568bcea9)=)") in new stack
541450154:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(expression)=ODBC_UPDATE_CALL_HISTORY_CALLEE_IDENTIFIER(390b82d6-c912-11e7-80ae-0050568bcea9)=") in new stack
541450402:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(TransID)=662358ff8bc4660f629cd50ad48e686b") in new stack
541450597:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC(transaction,asterisk2)=662358ff8bc4660f629cd50ad48e686b") in new stack
541450805:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC_UPDATE_CALL_HISTORY_CALLEE_IDENTIFIER(390b82d6-c912-11e7-80ae-0050568bcea9)=") in new stack
541451034:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10004-U9A8Y9QL5-00000820", "662358ff8bc4660f629cd50ad48e686b") in new stack
541451222:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541451373:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:11] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "writeOrUpdateODBC,~~s~~,1(ODBC_CLEANUP_DIAL_INCOMING(2823929)=)") in new stack
541451586:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(expression)=ODBC_CLEANUP_DIAL_INCOMING(2823929)=") in new stack
541451789:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(TransID)=b5f60d1439d4adec173b87b5a5204dae") in new stack
541451984:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC(transaction,asterisk2)=b5f60d1439d4adec173b87b5a5204dae") in new stack
541452192:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC_CLEANUP_DIAL_INCOMING(2823929)=") in new stack
541452376:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10004-U9A8Y9QL5-00000820", "b5f60d1439d4adec173b87b5a5204dae") in new stack
541452564:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541452715:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:12] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "writeOrUpdateODBC,~~s~~,1(ODBC_CLEANUP_PARK_META(2823929)=)") in new stack
541452924:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(expression)=ODBC_CLEANUP_PARK_META(2823929)=") in new stack
541453123:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(TransID)=49c24ddb38b038f80552e3b5af4023bf") in new stack
541453318:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC(transaction,asterisk2)=49c24ddb38b038f80552e3b5af4023bf") in new stack
541453526:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC_CLEANUP_PARK_META(2823929)=") in new stack
541453706:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10004-U9A8Y9QL5-00000820", "49c24ddb38b038f80552e3b5af4023bf") in new stack
541453894:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541454045:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:13] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "writeOrUpdateODBC,~~s~~,1(ODBC_CLEANUP_SOFT_TRANSFERS(2823929)=)") in new stack
541454259:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(expression)=ODBC_CLEANUP_SOFT_TRANSFERS(2823929)=") in new stack
541454463:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(TransID)=2dd5e8ce88d91ccabcfded4b1dcf45df") in new stack
541454658:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC(transaction,asterisk2)=2dd5e8ce88d91ccabcfded4b1dcf45df") in new stack
541454866:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC_CLEANUP_SOFT_TRANSFERS(2823929)=") in new stack
541455051:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10004-U9A8Y9QL5-00000820", "2dd5e8ce88d91ccabcfded4b1dcf45df") in new stack
541455239:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541455390:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:14] GotoIf("PJSIP/10004-U9A8Y9QL5-00000820", "0?15:17") in new stack
541455548:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx_builtins.c: Goto (preDeleteCleanup,~~s~~,17)
541455645:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:17] NoOp("PJSIP/10004-U9A8Y9QL5-00000820", "Finish if_preDeleteCleanup_158") in new stack
541455824:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:18] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "writeOrUpdateODBC,~~s~~,1(ODBC_POST_CALL_ENDPOINT_STATE_ONLINE(+4797062352,10004-U9A8Y9QL5)=)") in new stack
541456067:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(expression)=ODBC_POST_CALL_ENDPOINT_STATE_ONLINE(+4797062352,10004-U9A8Y9QL5)=") in new stack
541456300:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(TransID)=35addcb85e1d0890b725372901d3a2a1") in new stack
541456495:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC(transaction,asterisk2)=35addcb85e1d0890b725372901d3a2a1") in new stack
541456703:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC_POST_CALL_ENDPOINT_STATE_ONLINE(+4797062352,10004-U9A8Y9QL5)=") in new stack
541456917:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10004-U9A8Y9QL5-00000820", "35addcb85e1d0890b725372901d3a2a1") in new stack
541457105:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541457256:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:19] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "writeOrUpdateODBC,~~s~~,1(ODBC_POST_CALL_ENDPOINT_STATE_OFFLINE(+4797062352,10004-U9A8Y9QL5)=)") in new stack
541457500:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(expression)=ODBC_POST_CALL_ENDPOINT_STATE_OFFLINE(+4797062352,10004-U9A8Y9QL5)=") in new stack
541457734:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(TransID)=eac63e340be1dfacb597294ac0d8eea6") in new stack
541457929:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC(transaction,asterisk2)=eac63e340be1dfacb597294ac0d8eea6") in new stack
541458137:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC_POST_CALL_ENDPOINT_STATE_OFFLINE(+4797062352,10004-U9A8Y9QL5)=") in new stack
541458352:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10004-U9A8Y9QL5-00000820", "eac63e340be1dfacb597294ac0d8eea6") in new stack
541458540:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541458691:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@preDeleteCleanup:20] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541458842:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:10] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "writeOrUpdateODBC,~~s~~,1(ODBC_DELETE_CALL_CHANNEL_INFO_BY_CALLEE_CHANNEL(osl1-voip-cluster01-asterisk04-1510646575.1056)=)") in new stack
541459125:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(expression)=ODBC_DELETE_CALL_CHANNEL_INFO_BY_CALLEE_CHANNEL(osl1-voip-cluster01-asterisk04-1510646575.1056)=") in new stack
541459388:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(TransID)=499f142a9462cf2e46ea274a61ce122d") in new stack
541459583:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC(transaction,asterisk2)=499f142a9462cf2e46ea274a61ce122d") in new stack
541459791:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC_DELETE_CALL_CHANNEL_INFO_BY_CALLEE_CHANNEL(osl1-voip-cluster01-asterisk04-1510646575.1056)=") in new stack
541460035:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10004-U9A8Y9QL5-00000820", "499f142a9462cf2e46ea274a61ce122d") in new stack
541460223:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541460374:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:11] NoOp("PJSIP/10004-U9A8Y9QL5-00000820", "Finish if_deleteCallChannelByChannel_156") in new stack
541460573:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@deleteCallChannelByChannel:12] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541460734:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@onDisconnect:2] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "writeOrUpdateODBC,~~s~~,1(ODBC_DELETE_QUEUES_JOINED(osl1-voip-cluster01-asterisk04-1510646575.1056)=)") in new stack
541460980:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(expression)=ODBC_DELETE_QUEUES_JOINED(osl1-voip-cluster01-asterisk04-1510646575.1056)=") in new stack
541461221:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(TransID)=8e29d154871b9d0ec1904ca027237c0d") in new stack
541461416:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC(transaction,asterisk2)=8e29d154871b9d0ec1904ca027237c0d") in new stack
541461624:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC_DELETE_QUEUES_JOINED(osl1-voip-cluster01-asterisk04-1510646575.1056)=") in new stack
541461846:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10004-U9A8Y9QL5-00000820", "8e29d154871b9d0ec1904ca027237c0d") in new stack
541462034:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541462185:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@onDisconnect:3] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "writeOrUpdateODBC,~~s~~,1(ODBC_DELETE_DTMF_DATA(osl1-voip-cluster01-asterisk04-1510646575.1056)=)") in new stack
541462427:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(expression)=ODBC_DELETE_DTMF_DATA(osl1-voip-cluster01-asterisk04-1510646575.1056)=") in new stack
541462664:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(TransID)=f01fa9877f06519e91dd9e303d9d94f9") in new stack
541462859:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC(transaction,asterisk2)=f01fa9877f06519e91dd9e303d9d94f9") in new stack
541463067:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC_DELETE_DTMF_DATA(osl1-voip-cluster01-asterisk04-1510646575.1056)=") in new stack
541463285:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10004-U9A8Y9QL5-00000820", "f01fa9877f06519e91dd9e303d9d94f9") in new stack
541463473:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541463624:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@onDisconnect:4] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541463770:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] app_stack.c: Spawn extension (pluckCallerFromCallId, h, 4) exited non-zero on 'PJSIP/10004-U9A8Y9QL5-00000820'
541463929:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] app_stack.c: PJSIP/10004-U9A8Y9QL5-00000820 Internal Gosub(onDisconnect,~~s~~,1) complete GOSUB_RETVAL=
541464081:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] app_stack.c: PJSIP/10004-U9A8Y9QL5-00000820 Internal Gosub(removeSharedVarLock,~~s~~,1) start
541464223:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@removeSharedVarLock:1] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "setIdentifier,~~s~~,1") in new stack
541464396:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@setIdentifier:1] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(TempIdentifier)=osl1-voip-cluster01-asterisk04-1510646575.1056") in new stack
541464608:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@setIdentifier:2] GotoIf("PJSIP/10004-U9A8Y9QL5-00000820", "0?3:4") in new stack
541464760:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx_builtins.c: Goto (setIdentifier,~~s~~,4)
541464853:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@setIdentifier:4] NoOp("PJSIP/10004-U9A8Y9QL5-00000820", "Finish if_setIdentifier_160") in new stack
541465025:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@setIdentifier:5] GotoIf("PJSIP/10004-U9A8Y9QL5-00000820", "1?6:7") in new stack
541465177:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx_builtins.c: Goto (setIdentifier,~~s~~,6)
541465270:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@setIdentifier:6] Set("PJSIP/10004-U9A8Y9QL5-00000820", "identifier=osl1-voip-cluster01-asterisk04-1510646575.1056") in new stack
541465471:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@setIdentifier:7] NoOp("PJSIP/10004-U9A8Y9QL5-00000820", "Finish if_setIdentifier_161") in new stack
541465643:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@setIdentifier:8] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541465790:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@removeSharedVarLock:2] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "writeOrUpdateODBC,~~s~~,1(ODBC_REMOVE_SHARED_VARIABLE_LOCK(osl1-voip-cluster01-asterisk04-1510646575.1056,osl1-voip-cluster01-asterisk04-1510646575.1056)=)") in new stack
541466097:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(expression)=ODBC_REMOVE_SHARED_VARIABLE_LOCK(osl1-voip-cluster01-asterisk04-1510646575.1056,osl1-voip-cluster01-asterisk04-1510646575.1056)=") in new stack
541466392:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(TransID)=90eb48cff5caffbe3f1bbb7a8c6b7381") in new stack
541466587:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC(transaction,asterisk2)=90eb48cff5caffbe3f1bbb7a8c6b7381") in new stack
541466795:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC_REMOVE_SHARED_VARIABLE_LOCK(osl1-voip-cluster01-asterisk04-1510646575.1056,osl1-voip-cluster01-asterisk04-1510646575.1056)=") in new stack
541467071:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10004-U9A8Y9QL5-00000820", "90eb48cff5caffbe3f1bbb7a8c6b7381") in new stack
541467259:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541467410:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@removeSharedVarLock:3] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "cleanupSharedVars,~~s~~,1") in new stack
541467587:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@cleanupSharedVars:1] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "setIdentifier,~~s~~,1") in new stack
541467758:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@setIdentifier:1] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(TempIdentifier)=osl1-voip-cluster01-asterisk04-1510646575.1056") in new stack
541467970:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@setIdentifier:2] GotoIf("PJSIP/10004-U9A8Y9QL5-00000820", "0?3:4") in new stack
541468122:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx_builtins.c: Goto (setIdentifier,~~s~~,4)
541468215:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@setIdentifier:4] NoOp("PJSIP/10004-U9A8Y9QL5-00000820", "Finish if_setIdentifier_160") in new stack
541468387:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@setIdentifier:5] GotoIf("PJSIP/10004-U9A8Y9QL5-00000820", "1?6:7") in new stack
541468539:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx_builtins.c: Goto (setIdentifier,~~s~~,6)
541468632:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@setIdentifier:6] Set("PJSIP/10004-U9A8Y9QL5-00000820", "identifier=osl1-voip-cluster01-asterisk04-1510646575.1056") in new stack
541468833:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@setIdentifier:7] NoOp("PJSIP/10004-U9A8Y9QL5-00000820", "Finish if_setIdentifier_161") in new stack
541469005:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@setIdentifier:8] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541469152:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@cleanupSharedVars:2] GotoIf("PJSIP/10004-U9A8Y9QL5-00000820", "1?3:7") in new stack
541469308:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx_builtins.c: Goto (cleanupSharedVars,~~s~~,3)
541469405:[2017-11-14 09:04:54] VERBOSE[14792] pbx_variables.c: Setting global variable 'SIPDOMAIN' to 'patientsky.com'
541469515:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@cleanupSharedVars:3] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(SharedVarLockCheck)=0") in new stack
541469690:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@cleanupSharedVars:4] GotoIf("PJSIP/10004-U9A8Y9QL5-00000820", "1?5:6") in new stack
541469846:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx_builtins.c: Goto (cleanupSharedVars,~~s~~,5)
541469943:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@cleanupSharedVars:5] Gosub("PJSIP/10004-U9A8Y9QL5-00000820", "writeOrUpdateODBC,~~s~~,1(ODBC_DELETE_SHARED_VARIABLES(osl1-voip-cluster01-asterisk04-1510646575.1056)=)") in new stack
541470197:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:1] MSet("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(expression)=ODBC_DELETE_SHARED_VARIABLES(osl1-voip-cluster01-asterisk04-1510646575.1056)=") in new stack
541470441:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:2] Set("PJSIP/10004-U9A8Y9QL5-00000820", "LOCAL(TransID)=a5351d795b9cc68bd646d0c8819cc14b") in new stack
541470636:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:3] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC(transaction,asterisk2)=a5351d795b9cc68bd646d0c8819cc14b") in new stack
541470844:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:4] Set("PJSIP/10004-U9A8Y9QL5-00000820", "ODBC_DELETE_SHARED_VARIABLES(osl1-voip-cluster01-asterisk04-1510646575.1056)=") in new stack
541471069:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:5] ODBC_Commit("PJSIP/10004-U9A8Y9QL5-00000820", "a5351d795b9cc68bd646d0c8819cc14b") in new stack
541471257:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@writeOrUpdateODBC:6] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541471408:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@cleanupSharedVars:6] NoOp("PJSIP/10004-U9A8Y9QL5-00000820", "Finish if_if_cleanupSharedVars_166_167") in new stack
541471595:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@cleanupSharedVars:7] NoOp("PJSIP/10004-U9A8Y9QL5-00000820", "Finish if_cleanupSharedVars_166") in new stack
541471775:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@cleanupSharedVars:8] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541471926:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] pbx.c: Executing [~~s~~@removeSharedVarLock:4] Return("PJSIP/10004-U9A8Y9QL5-00000820", "") in new stack
541472079:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] app_stack.c: Spawn extension (pluckCallerFromCallId, h, 4) exited non-zero on 'PJSIP/10004-U9A8Y9QL5-00000820'
541472238:[2017-11-14 09:04:54] VERBOSE[8757][C-00000213] app_stack.c: PJSIP/10004-U9A8Y9QL5-00000820 Internal Gosub(removeSharedVarLock,~~s~~,1) complete GOSUB_RETVAL=
541472397:[2017-11-14 09:04:54] VERBOSE[14792] netsock2.c: Using SIP RTP Audio TOS bits 40
541472478:[2017-11-14 09:04:54] VERBOSE[14792] netsock2.c: Using SIP RTP Audio CoS mark 5
541472558-[2017-11-14 09:05:26] Asterisk 14.6.2 built by root @ osl1-voip-cluster01-asterisk04 on a x86_64 running Linux on 2017-10-27 19:21:14 UTC
541472696-[Nov 14 09:05:26] VERBOSE[10339] config.c: Parsing '/etc/asterisk/logger.conf': Found
541472782-[2017-11-14 09:05:26] VERBOSE[10339] config.c: Parsing '/etc/asterisk/pjproject.conf': Found
541472875-[2017-11-14 09:05:26] VERBOSE[10339] message.c: Message handler 'dialplan' registered.
541472962-[2017-11-14 09:05:26] VERBOSE[10339] pbx_functions.c: Registered custom function 'MESSAGE'
541473053-[2017-11-14 09:05:26] VERBOSE[10339] pbx_functions.c: Registered custom function 'MESSAGE_DATA'
541473149-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'MessageSend'
541473234-[2017-11-14 09:05:26] VERBOSE[10339] manager.c: Manager registered action MessageSend
541473320-[2017-11-14 09:05:26] VERBOSE[10339] manager.c: Manager registered action DataGet
541473402-[2017-11-14 09:05:26] VERBOSE[10339] channel.c: Registered channel type 'Surrogate' (Surrogate channel used to pull channel from an application)
541473547-[2017-11-14 09:05:26] VERBOSE[10339] config.c: Parsing '/etc/asterisk/codecs.conf': Found
541473637-[2017-11-14 09:05:26] VERBOSE[10339] manager.c: Manager registered action BridgeTechnologyList
541473732-[2017-11-14 09:05:26] VERBOSE[10339] manager.c: Manager registered action BridgeTechnologySuspend
541473830-[2017-11-14 09:05:26] VERBOSE[10339] manager.c: Manager registered action BridgeTechnologyUnsuspend
541473930-[2017-11-14 09:05:26] VERBOSE[10339] dns_core.c: Registered DNS resolver 'system' with priority '2147483647'
541474039-[2017-11-14 09:05:26] VERBOSE[10339] loader.c: Asterisk Dynamic Loader Starting:
541474120-[2017-11-14 09:05:26] VERBOSE[10339] config.c: Parsing '/etc/asterisk/modules.conf': Found
541474211-[2017-11-14 09:05:26] VERBOSE[10339] loader.c: Loading res_odbc.so.
541474279-[2017-11-14 09:05:26] VERBOSE[10339] config.c: Parsing '/etc/asterisk/res_odbc.conf': Found
541474371-[2017-11-14 09:05:26] VERBOSE[10339] loader.c: res_odbc.so => (ODBC resource)
541474449-[2017-11-14 09:05:26] VERBOSE[10339] loader.c: Loading res_config_odbc.so.
541474524-[2017-11-14 09:05:26] VERBOSE[10339] loader.c: res_config_odbc.so => (Realtime ODBC configuration)
541474623-[2017-11-14 09:05:26] VERBOSE[10339] loader.c: Loading res_timing_timerfd.so.
541474701-[2017-11-14 09:05:26] VERBOSE[10339] loader.c: res_timing_timerfd.so => (Timerfd Timing Interface)
541474800-[2017-11-14 09:05:26] VERBOSE[10339] config.c: Parsing '/etc/asterisk/features.conf': Found
541474892-[2017-11-14 09:05:26] VERBOSE[10339] pbx_functions.c: Registered custom function 'FEATURE'
541474983-[2017-11-14 09:05:26] VERBOSE[10339] pbx_functions.c: Registered custom function 'FEATUREMAP'
541475077-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'Bridge'
541475157-[2017-11-14 09:05:26] VERBOSE[10339] manager.c: Manager registered action Bridge
541475238-[2017-11-14 09:05:26] VERBOSE[10339] config.c: Parsing '/etc/asterisk/acl.conf': Found
541475325-[2017-11-14 09:05:26] VERBOSE[10339] config.c: Parsing '/etc/asterisk/http.conf': Found
541475413-[2017-11-14 09:05:26] VERBOSE[10339] tcptls.c: TLS/SSL ECDH initialized (automatic), faster PFS ciphers enabled
541475525-[2017-11-14 09:05:26] VERBOSE[10339] tcptls.c: TLS/SSL certificate ok
541475595-[2017-11-14 09:05:26] VERBOSE[10339] config.c: Parsing '/etc/asterisk/indications.conf': Found
541475690-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'at'
541475777-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'au'
541475864-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'bg'
541475951-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'br'
541476038-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'be'
541476125-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'ch'
541476212-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'cl'
541476299-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'cn'
541476386-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'cz'
541476473-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'de'
541476560-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'dk'
541476647-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'ee'
541476734-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'es'
541476821-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'fi'
541476908-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'fr'
541476995-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'gr'
541477082-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'hu'
541477169-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'il'
541477256-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'in'
541477343-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'it'
541477430-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'lt'
541477517-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'jp'
541477604-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'mx'
541477691-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'my'
541477778-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'nl'
541477865-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'no'
541477952-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'nz'
541478039-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'ph'
541478126-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'pl'
541478213-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'pt'
541478300-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'ru'
541478387-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'se'
541478474-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'sg'
541478561-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'th'
541478648-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'uk'
541478735-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'us'
541478822-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'us-old'
541478913-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'tw'
541479000-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 've'
541479087-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Registered indication country 'za'
541479174-[2017-11-14 09:05:26] VERBOSE[10339] indications.c: Setting default indication country to 'no'
541479269-[2017-11-14 09:05:26] VERBOSE[10339] config.c: Parsing '/etc/asterisk/cdr.conf': Found
541479356-[2017-11-14 09:05:26] VERBOSE[10339] config.c: Parsing '/etc/asterisk/udptl.conf': Found
541479445-[2017-11-14 09:05:26] VERBOSE[10339] pbx.c: Asterisk PBX Core Initializing
541479520-[2017-11-14 09:05:26] VERBOSE[10339] pbx.c: Registering builtin functions:
541479595-[2017-11-14 09:05:26] VERBOSE[10339] pbx_functions.c: Registered custom function 'EXCEPTION'
541479688-[2017-11-14 09:05:26] VERBOSE[10339] pbx_functions.c: Registered custom function 'TESTTIME'
541479780-[2017-11-14 09:05:26] VERBOSE[10339] manager.c: Manager registered action ShowDialPlan
541479867-[2017-11-14 09:05:26] VERBOSE[10339] manager.c: Manager registered action ExtensionStateList
541479960-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'Answer'
541480040-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'BackGround'
541480124-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'Busy'
541480202-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'Congestion'
541480286-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'ExecIfTime'
541480370-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'Goto'
541480448-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'GotoIf'
541480528-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'GotoIfTime'
541480612-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'ImportVar'
541480695-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'Hangup'
541480775-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'Incomplete'
541480859-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'NoOp'
541480937-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'Proceeding'
541481021-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'Progress'
541481103-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'RaiseException'
541481191-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'Ringing'
541481272-[2017-11-14 09:05:26] VERBOSE[10339] pbx_app.c: Registered application 'SayAlpha'