From Coral Crocodile, 6 Years ago, written in FreeSWITCH.
- go back
Embed
Viewing differences between and ffs Errol
  1. 2018-07-13 11:35:01.884240 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'internal' for [0114620900@sbc.convergedgroup.co.za] from ip 154.118.252.35
  2. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.884240 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/27105914607@sbc.convergedgroup.co.za) State NEW
  3. 2018-07-13 11:35:01.884240 [DEBUG] sofia.c:2334 detaching session 5be1f9f2-3bc4-489a-8a78-622a7a4dc644
  4. c3169a1c-3cd0-440f-894e-6f0993ae8ffe 2018-07-13 11:35:01.884240 [DEBUG] sofia.c:7084 Channel sofia/internal/0102065021@10.192.0.130:5060 entering state [proceeding][183]
  5. c3169a1c-3cd0-440f-894e-6f0993ae8ffe 2018-07-13 11:35:01.884240 [DEBUG] sofia.c:7094 Remote SDP:
  6. c3169a1c-3cd0-440f-894e-6f0993ae8ffe v=0
  7. c3169a1c-3cd0-440f-894e-6f0993ae8ffe o=CvtVoice 49298220 49298220 IN IP4 10.192.0.130
  8. c3169a1c-3cd0-440f-894e-6f0993ae8ffe s=CvtVoice
  9. c3169a1c-3cd0-440f-894e-6f0993ae8ffe c=IN IP4 10.192.0.130
  10. c3169a1c-3cd0-440f-894e-6f0993ae8ffe t=0 0
  11. c3169a1c-3cd0-440f-894e-6f0993ae8ffe m=audio 13936 RTP/AVP 18 101
  12. c3169a1c-3cd0-440f-894e-6f0993ae8ffe a=rtpmap:18 G729/8000
  13. c3169a1c-3cd0-440f-894e-6f0993ae8ffe a=fmtp:18 annexb=no
  14. c3169a1c-3cd0-440f-894e-6f0993ae8ffe a=rtpmap:101 telephone-event/8000
  15. c3169a1c-3cd0-440f-894e-6f0993ae8ffe a=fmtp:101 0-16
  16. c3169a1c-3cd0-440f-894e-6f0993ae8ffe a=ptime:20
  17. c3169a1c-3cd0-440f-894e-6f0993ae8ffe a=maxptime:230
  18. c3169a1c-3cd0-440f-894e-6f0993ae8ffe
  19. c3169a1c-3cd0-440f-894e-6f0993ae8ffe 2018-07-13 11:35:01.884240 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  20. c3169a1c-3cd0-440f-894e-6f0993ae8ffe 2018-07-13 11:35:01.884240 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  21. c3169a1c-3cd0-440f-894e-6f0993ae8ffe 2018-07-13 11:35:01.884240 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  22. c3169a1c-3cd0-440f-894e-6f0993ae8ffe 2018-07-13 11:35:01.884240 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/0102065021@10.192.0.130:5060 G729/8000 20 ms 160 samples 8000 bits 1 channels
  23. c3169a1c-3cd0-440f-894e-6f0993ae8ffe 2018-07-13 11:35:01.884240 [DEBUG] switch_core_codec.c:111 sofia/internal/0102065021@10.192.0.130:5060 Original read codec set to G729:18
  24. c3169a1c-3cd0-440f-894e-6f0993ae8ffe 2018-07-13 11:35:01.884240 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  25. c3169a1c-3cd0-440f-894e-6f0993ae8ffe 2018-07-13 11:35:01.884240 [DEBUG] switch_core_media.c:4767 sofia/internal/0102065021@10.192.0.130:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  26. c3169a1c-3cd0-440f-894e-6f0993ae8ffe 2018-07-13 11:35:01.884240 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/0102065021@10.192.0.130:5060] 154.118.252.36 port 27272 -> 10.192.0.130 port 13936 codec: 18 ms: 20
  27. c3169a1c-3cd0-440f-894e-6f0993ae8ffe 2018-07-13 11:35:01.884240 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
  28. c3169a1c-3cd0-440f-894e-6f0993ae8ffe 2018-07-13 11:35:01.884240 [DEBUG] switch_core_media.c:7179 sofia/internal/0102065021@10.192.0.130:5060 Set 2833 dtmf send payload to 101
  29. c3169a1c-3cd0-440f-894e-6f0993ae8ffe 2018-07-13 11:35:01.884240 [DEBUG] switch_core_media.c:7186 sofia/internal/0102065021@10.192.0.130:5060 Set 2833 dtmf receive payload to 101
  30. c3169a1c-3cd0-440f-894e-6f0993ae8ffe 2018-07-13 11:35:01.884240 [DEBUG] switch_core_media.c:7209 sofia/internal/0102065021@10.192.0.130:5060 Set rtp dtmf delay to 40
  31. c3169a1c-3cd0-440f-894e-6f0993ae8ffe 2018-07-13 11:35:01.884240 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/0102065021@10.192.0.130:5060!
  32. c3169a1c-3cd0-440f-894e-6f0993ae8ffe 2018-07-13 11:35:01.884240 [DEBUG] switch_channel.c:3474 (sofia/internal/0102065021@10.192.0.130:5060) Callstate Change RINGING -> EARLY
  33. 2018-07-13 11:35:01.884240 [DEBUG] sofia.c:2442 Re-attaching to session 5be1f9f2-3bc4-489a-8a78-622a7a4dc644
  34. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.884240 [NOTICE] mod_sofia.c:2273 Ring-Ready sofia/external/+27837225168@154.118.252.35!
  35. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.884240 [DEBUG] sofia.c:7084 Channel sofia/external/+27837225168@154.118.252.35 entering state [early][180]
  36. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.884240 [NOTICE] switch_ivr_originate.c:525 Ring Ready sofia/external/+27837225168@154.118.252.35!
  37. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.884240 [DEBUG] switch_ivr_originate.c:410 Setting codec string on sofia/external/+27837225168@154.118.252.35 to G729@8000h@20i
  38. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.884240 [INFO] switch_ivr_originate.c:3654 Sending early media
  39. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.884240 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  40. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.884240 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  41. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.884240 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  42. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.884240 [DEBUG] switch_core_media.c:3061 Set Codec sofia/external/+27837225168@154.118.252.35 G729/8000 20 ms 160 samples 8000 bits 1 channels
  43. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.884240 [DEBUG] switch_core_codec.c:111 sofia/external/+27837225168@154.118.252.35 Original read codec set to G729:18
  44. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.924239 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  45. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.924239 [DEBUG] switch_core_media.c:4767 sofia/external/+27837225168@154.118.252.35 Set 2833 dtmf send payload to 101 recv payload to 101
  46. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.924239 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/external/+27837225168@154.118.252.35] 154.118.252.36 port 29692 -> 154.118.252.38 port 38932 codec: 18 ms: 20
  47. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.924239 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
  48. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.924239 [DEBUG] switch_core_media.c:7179 sofia/external/+27837225168@154.118.252.35 Set 2833 dtmf send payload to 101
  49. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.924239 [DEBUG] switch_core_media.c:7186 sofia/external/+27837225168@154.118.252.35 Set 2833 dtmf receive payload to 101
  50. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.924239 [DEBUG] switch_core_media.c:7209 sofia/external/+27837225168@154.118.252.35 Set rtp dtmf delay to 40
  51. c866212e-fe16-48bb-bfdf-ea1aa1d56509 2018-07-13 11:35:01.924239 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/+27837225168@154.118.252.35!
  52. --
  53. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.924239 [INFO] mod_dialplan_xml.c:637 Processing Andrew Colin <27105914607>->0114620900 in context cgrtbilling
  54. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za parsing [cgrtbilling->unloop] continue=false
  55. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  56. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  57. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za parsing [cgrtbilling->call_debug] continue=true
  58. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
  59. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za parsing [cgrtbilling->block_account] continue=false
  60. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Regex (FAIL) [block_account] destination_number(0114620900) =~ /^(block_account)$/ break=on-false
  61. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za parsing [cgrtbilling->check_auth] continue=true
  62. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Regex (PASS) [check_auth] ${sip_authorized}(true) =~ /^true$/ break=never
  63. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za parsing [cgrtbilling->calls_from_converged] continue=true
  64. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Regex (PASS) [calls_from_converged] ${sip_req_host}(sbc.convergedgroup.co.za) =~ /^sbc.convergedgroup.co.za$/ break=on-false
  65. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 |--- Dialplan: Processing recursive conditions level:1 [calls_from_converged_recur_1] require-nested=TRUE
  66. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 |--- Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Regex (PASS) [calls_from_converged_recur_1] destination_number(0114620900) =~ /^(.*)$/ break=on-false
  67. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 |--- Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Action translate(${destination_number} sbc.convergedgroup.co.za-za)
  68. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 |--- Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Action set(destination_number=${translated})
  69. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 |--- Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Action set(outside_call=true)
  70. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 |--- Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Action set(lnp_dest_num=${lua(cgrtbilling/sa_lnp_lookup.lua ${destination_number})})
  71. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 |--- Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Action lua(cgrtbilling/cgrtbilling_v2.luac)
  72. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za parsing [cgrtbilling->calls_from_tenants] continue=true
  73. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Regex (PASS) [calls_from_tenants] destination_number(0114620900) =~ /^(.*)$/ break=on-false
  74. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Action translate(${destination_number} sbc.convergedgroup.co.za-za)
  75. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Action set(destination_number=${translated})
  76. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Action set(outside_call=true)
  77. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 Dialplan: sofia/internal/27105914607@sbc.convergedgroup.co.za Action lua(cgrtbilling/cgrtbilling_v2.luac)
  78. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.924239 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/27105914607@sbc.convergedgroup.co.za) State Change CS_ROUTING -> CS_EXECUTE
  79. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.924239 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/27105914607@sbc.convergedgroup.co.za) State ROUTING going to sleep
  80. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.924239 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/27105914607@sbc.convergedgroup.co.za) Running State Change CS_EXECUTE (Cur 622 Tot 1557932)
  81. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.924239 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/27105914607@sbc.convergedgroup.co.za) State EXECUTE
  82. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.924239 [DEBUG] mod_sofia.c:198 sofia/internal/27105914607@sbc.convergedgroup.co.za SOFIA EXECUTE
  83. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.924239 [DEBUG] switch_core_state_machine.c:328 sofia/internal/27105914607@sbc.convergedgroup.co.za Standard EXECUTE
  84. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 EXECUTE sofia/internal/27105914607@sbc.convergedgroup.co.za translate(0114620900 sbc.convergedgroup.co.za-za)
  85. 2018-07-13 11:35:01.924239 [DEBUG] mod_translate.c:128 translating [0114620900] against [sbc.convergedgroup.co.za-za] profile
  86. 2018-07-13 11:35:01.924239 [DEBUG] mod_translate.c:137 0114620900 =~ /^\+(\d+)$/
  87. 2018-07-13 11:35:01.924239 [DEBUG] mod_translate.c:137 0114620900 =~ /^(00\d{9,17})$/
  88. 2018-07-13 11:35:01.924239 [DEBUG] mod_translate.c:137 0114620900 =~ /^0(\d+)$/
  89. 2018-07-13 11:35:01.924239 [NOTICE] mod_translate.c:249 Translated: 27114620900
  90. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 EXECUTE sofia/internal/27105914607@sbc.convergedgroup.co.za set(destination_number=27114620900)
  91. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.924239 [DEBUG] mod_dptools.c:1548 SET sofia/internal/27105914607@sbc.convergedgroup.co.za [destination_number]=[27114620900]
  92. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 EXECUTE sofia/internal/27105914607@sbc.convergedgroup.co.za set(outside_call=true)
  93. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.924239 [DEBUG] mod_dptools.c:1548 SET sofia/internal/27105914607@sbc.convergedgroup.co.za [outside_call]=[true]
  94. 2018-07-13 11:35:01.924239 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fa889421220 Connected.
  95. 2018-07-13 11:35:01.944239 [NOTICE] switch_cpp.cpp:1365 The number was ported to ECN with DCode D001
  96.  
  97. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(lnp_enabled, true)
  98. 2018-07-13 11:35:01.944239 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fa889421220 released.
  99. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:1112 sofia/internal/27105914607@sbc.convergedgroup.co.za destroy/unlink session from object
  100. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 EXECUTE sofia/internal/27105914607@sbc.convergedgroup.co.za set(lnp_dest_num=D00127114620900)
  101. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] mod_dptools.c:1548 SET sofia/internal/27105914607@sbc.convergedgroup.co.za [lnp_dest_num]=[D00127114620900]
  102. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 EXECUTE sofia/internal/27105914607@sbc.convergedgroup.co.za lua(cgrtbilling/cgrtbilling_v2.luac)
  103. 2018-07-13 11:35:01.944239 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fa888e89850 Connected.
  104. 2018-07-13 11:35:01.944239 [INFO] switch_cpp.cpp:1365 Destination_number: 27114620900<-----------
  105.  
  106. 2018-07-13 11:35:01.944239 [NOTICE] switch_cpp.cpp:1365 SELECT mc.id, mc.name, mc.account_type, mc.account_code, b.balance_uuid, b.value as balance, t.name as tenant,
  107.     mc.origination, mc.orig_credit_limit, mc.max_per_minute_rate as max_rate, mc.max_daily_spend, mc.play_audio_messages as play_msgs,
  108.     mc.orig_max_channels as max_channels, mc.orig_max_cps as max_cps, tc.max_call_length as max_duration, mc.enable_calls, mc.enabled
  109.     FROM management_client mc
  110.     INNER JOIN management_tenant t ON t.id = mc.tenant_id
  111.     INNER JOIN management_tenantconfiguration tc ON ( t.id = tc.tenant_id )
  112.     INNER JOIN management_clientbalances b ON mc.id = b.client_id
  113.     WHERE mc.account_code = '4597204894' AND b.balance_type = '*monetary' AND weight = '10'
  114. 2018-07-13 11:35:01.944239 [NOTICE] switch_cpp.cpp:1365 <-------> Enable calls: 1 -----
  115.  
  116. 2018-07-13 11:35:01.944239 [WARNING] switch_cpp.cpp:1365 <------- Getting Account Info for Client: Converged Telecoms with account_code: 4597204894 ------->
  117.  
  118. 2018-07-13 11:35:01.944239 [NOTICE] switch_cpp.cpp:1365 SELECT mc.account_code, s.username, s.password, s.ip_address, s.caller_id_name as cid_name, s.caller_id_number as cid_number,
  119.     s.bypass_media as bymedia, s.pass_thru_caller_id, s.custom_sip_headers, c1.description as c1, c2.description as c2,
  120.     c3.description as c3, c4.description as c4, s.enabled
  121.     FROM management_sipaccount s
  122.     INNER JOIN management_client mc ON s.client_id = mc.id
  123.     LEFT OUTER JOIN configuration_codecpreference c1 ON s.codec1_id = c1.id
  124.     LEFT OUTER JOIN configuration_codecpreference c2 ON s.codec2_id = c2.id
  125.     LEFT OUTER JOIN configuration_codecpreference c3 ON s.codec3_id = c3.id
  126.     LEFT OUTER JOIN configuration_codecpreference c4 ON s.codec4_id = c4.id
  127.     WHERE  mc.id = '1740885166862658' AND s.username = 'convergedjhb'AND s.enabled = 't'
  128. 2018-07-13 11:35:01.944239 [WARNING] switch_cpp.cpp:1365 elapsed time: 0.004478
  129.  
  130. 2018-07-13 11:35:01.944239 [WARNING] switch_cpp.cpp:1365 -------------> Geting Client Tariff Plan info ------------->
  131.  
  132. 2018-07-13 11:35:01.944239 [NOTICE] switch_cpp.cpp:1365 SELECT mt.tech_prefix, mt.tpb_id, t.name as tpb_name, mt.routing_plan_id, r.routing_type, r.allow_negative_profit
  133.       FROM management_tpballocation mt
  134.       INNER JOIN management_client mc ON mt.client_id = mc.id
  135.       INNER JOIN routing_routingplan r ON mt.routing_plan_id = r.id
  136.       INNER JOIN tariff_plans_tpb t ON t.id = mt.tpb_id
  137.       WHERE mc.account_code = '4597204894'
  138. 2018-07-13 11:35:01.944239 [INFO] switch_cpp.cpp:1365 <----------------------------------------------------->
  139. 2018-07-13 11:35:01.944239 [INFO] switch_cpp.cpp:1365 Trying without tech prefix
  140. 2018-07-13 11:35:01.944239 [INFO] switch_cpp.cpp:1365 Dialed number : 27114620900
  141. 2018-07-13 11:35:01.944239 [INFO] switch_cpp.cpp:1365 Tariff Plan Name : SPECIAL_CUST
  142. 2018-07-13 11:35:01.944239 [INFO] switch_cpp.cpp:1365 <----------------------------------------------------->
  143.  
  144. 2018-07-13 11:35:01.944239 [WARNING] switch_cpp.cpp:1365 ------------  Getting Call cost for client  ------------
  145.  
  146. 2018-07-13 11:35:01.944239 [NOTICE] switch_cpp.cpp:1365 SELECT tp.code, tp.destination_name, tp.connect_fee, tp.rate, tp.rate_minimum, tp.rate_increment, tp.effective_date, tp.expiry_date, tp.enabled
  147.     FROM tariff_plans_rates tp
  148.     WHERE code @> '27114620900' AND tpb_id = '4566351673973515' AND tp.effective_date <= tp.expiry_date AND tp.expiry_date >= now()
  149.     ORDER BY CODE ASC LIMIT 1
  150. 2018-07-13 11:35:01.944239 [NOTICE] switch_cpp.cpp:1365 Code: 2711
  151. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 EXECUTE sofia/internal/27105914607@sbc.convergedgroup.co.za export(execute_on_answer=nibblebill adjust -0)
  152. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [execute_on_answer]=[nibblebill adjust -0]
  153. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(connect_fee, 0)
  154. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(nibble_rate, 0.12)
  155. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(nibble_minimum, 0.002)
  156. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(nibble_increment, 1)
  157. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(nibble_account, 9D589CB4-4BC5-4DBF-AC0D-67E9D0C666B3)
  158. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(o_account, 4597204894)
  159. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(client_id, 1740885166862658)
  160. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(o_tenant, sbc.convergedgroup.co.za)
  161. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(nobal_amt, -5000)
  162. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 EXECUTE sofia/internal/27105914607@sbc.convergedgroup.co.za nibblebill(heartbeat 60)
  163. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [WARNING] switch_core_session.c:1605 sofia/internal/27105914607@sbc.convergedgroup.co.za using scheduler due to bypass media or media is not established.
  164. 2018-07-13 11:35:01.944239 [DEBUG] switch_scheduler.c:249 Added task 3750960 switch_core_session_sched_heartbeat (5be1f9f2-3bc4-489a-8a78-622a7a4dc644) to run at 1531474561
  165. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(o_dept,  )
  166. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(o_site,  )
  167. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(o_extension,  )
  168. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(o_custom,  )
  169. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(o_subject,  )
  170. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(o_supplier,  )
  171. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(o_reqtype, POSTPAID)
  172. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(o_category, SPECIAL_CUST)
  173. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(prefix, 2711)
  174. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(destination_name, SA National_2711)
  175. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(destination_number, 27114620900)
  176. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(tor, *voice)
  177. 2018-07-13 11:35:01.944239 [WARNING] switch_cpp.cpp:1365 -------------> Removing unwanted SIP Headers ------------->
  178.  
  179. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 EXECUTE sofia/internal/27105914607@sbc.convergedgroup.co.za unset(sip_h_X-accountcode)
  180. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] mod_dptools.c:1693 UNSET [sip_h_X-accountcode]
  181. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 EXECUTE sofia/internal/27105914607@sbc.convergedgroup.co.za unset(sip_h_X-username)
  182. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] mod_dptools.c:1693 UNSET [sip_h_X-username]
  183. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 EXECUTE sofia/internal/27105914607@sbc.convergedgroup.co.za unset(sip_h_X-extension)
  184. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] mod_dptools.c:1693 UNSET [sip_h_X-extension]
  185. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 EXECUTE sofia/internal/27105914607@sbc.convergedgroup.co.za unset(sip_h_X-dept)
  186. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] mod_dptools.c:1693 UNSET [sip_h_X-dept]
  187. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 EXECUTE sofia/internal/27105914607@sbc.convergedgroup.co.za unset(sip_h_X-site)
  188. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] mod_dptools.c:1693 UNSET [sip_h_X-site]
  189. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 EXECUTE sofia/internal/27105914607@sbc.convergedgroup.co.za unset(sip_h_X-custom)
  190. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.944239 [DEBUG] mod_dptools.c:1693 UNSET [sip_h_X-custom]
  191. 2018-07-13 11:35:01.944239 [NOTICE] switch_cpp.cpp:1365 Checking if 27114620900 is a DID <-------------
  192. 2018-07-13 11:35:01.944239 [NOTICE] switch_cpp.cpp:1365 SELECT status from management_did where did_number = '27114620900' and status != 'ported_out'2018-07-13 11:35:01.964238 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fa888e89850 released.
  193. 2018-07-13 11:35:01.964238 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fa888e89850 Connected.
  194. 2018-07-13 11:35:01.964238 [WARNING] switch_cpp.cpp:1365 ------------  Getting LCR info  ------------
  195.  
  196. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 SELECT *
  197.     FROM (SELECT DISTINCT ON (t.name) t.name as tariff_plan, rt.id AS rt_id, r.code, r.destination_name, r.connect_fee, r.rate, r.rate_minimum, r.rate_increment,
  198.     t.prefix, t.suffix, t.quality, t.reliability, t.client_id AS carrier_id, r.effective_date, r.expiry_date, t.gw1_id, t.gw2_id, t.gw3_id, r.enabled
  199.     FROM tariff_plans_rates r
  200.     INNER JOIN tariff_plans_tpb t ON r.tpb_id = t.id
  201.     INNER JOIN routing_routingplantariff rt ON rt.tariff_plan_id = t.id
  202.     WHERE rt.routing_plan_id = '1947940545902389' AND code @> 'D00127114620900' AND enabled = 't' AND (CURRENT_TIMESTAMP BETWEEN r.effective_date AND r.expiry_date)
  203.     ORDER BY t.name, code ASC, "effective_date" DESC) n
  204.     ORDER BY rate ASC, quality DESC, reliability DESC
  205. 2018-07-13 11:35:01.964238 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fa888e89850 released.
  206. 2018-07-13 11:35:01.964238 [INFO] switch_cpp.cpp:1365 ------------------  No of routes found: 2  ------------------
  207. 2018-07-13 11:35:01.964238 [INFO] switch_cpp.cpp:1365 ------->                   lcr_profile: lc
  208. 2018-07-13 11:35:01.964238 [INFO] switch_cpp.cpp:1365 ------->     lcr_allow_negative_profit: 0.00
  209.  
  210. 2018-07-13 11:35:01.964238 [INFO] switch_cpp.cpp:1365 -----------------------  Route [ 1 of 2 ] -----------------------
  211. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->             lcr_code[1]: D001
  212. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 -------> lcr_destination_name[1]: ECN
  213. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->      lcr_connect_fee[1]: 0.00000
  214. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->             lcr_rate[1]: 0.16000
  215. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->     lcr_rate_minimum[1]: 1
  216. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->   lcr_rate_increment[1]: 1
  217. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->           lcr_prefix[1]:  
  218. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->      lcr_tariff_plan[1]: LIQUIDTEL_NAT
  219. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->       lcr_carrier_id[1]: 937975527397526
  220. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->   lcr_effective_date[1]: 2017-09-24 21:50:39.307041
  221. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->      lcr_expiry_date[1]: 2027-09-24 21:50:40.264676
  222. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->          lcr_enabled[1]: 1
  223. 2018-07-13 11:35:01.964238 [INFO] switch_cpp.cpp:1365 ------------------------------------------------------------------  
  224.  
  225. 2018-07-13 11:35:01.964238 [INFO] switch_cpp.cpp:1365 -----------------------  Route [ 2 of 2 ] -----------------------
  226. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->             lcr_code[2]: D001
  227. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 -------> lcr_destination_name[2]: ECN
  228. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->      lcr_connect_fee[2]: 0.00000
  229. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->             lcr_rate[2]: 0.18000
  230. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->     lcr_rate_minimum[2]: 1
  231. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->   lcr_rate_increment[2]: 1
  232. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->           lcr_prefix[2]:  
  233. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->      lcr_tariff_plan[2]: TELKOM_NAT
  234. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->       lcr_carrier_id[2]: 946479979601305
  235. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->   lcr_effective_date[2]: 2017-09-24 21:50:00
  236. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->      lcr_expiry_date[2]: 2027-09-24 21:50:00
  237. 2018-07-13 11:35:01.964238 [NOTICE] switch_cpp.cpp:1365 ------->          lcr_enabled[2]: 1
  238. 2018-07-13 11:35:01.964238 [INFO] switch_cpp.cpp:1365 ------------------------------------------------------------------  
  239.  
  240. 5be1f9f2-3bc4-489a-8a78-622a7a4dc644 2018-07-13 11:35:01.964238 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(sip_h_X-cid, vCWfuMQ_ORE85u6iuhgu9w..)
  241. 2018-07-13 11:35:01.964238 [WARNING] switch_cpp.cpp:1365 ------->5be1f9f2-3bc4-489a-8a78-622a7a4dc644 - WS CALL Set value :.....: sip_h_X-cid vCWfuMQ_ORE85u6iuhgu9w..<-------------
  242. 2018-07-13 11:35:01.964238 [INFO] switch_cpp.cpp:1365 ----------> Call progressing through the diaplan <----------
  243.  
  244. 2018-07-13 11:35:01.964238 [INFO] switch_cpp.cpp:1365 --------------------- Customer rate: 0.12
  245. 2018-07-13 11:35:01.964238 [INFO] switch_cpp.cpp:1365 ----------- Negative profit allowed: 0.00%
  246. 2018-07-13 11:35:01.964238 [INFO] switch_cpp.cpp:1365 ---- Maximum provider rate fo