FreeSWITCH Pastebin

Calling_card_issue_nil_value_getting_IVR

From Prashant Kumar, 2 Years ago, written in FreeSWITCH.
- go back
URL https://pastebin.freeswitch.org/view/5fc4a354
Embed
Viewing differences between and Calling_card_issue_nil_value_getting_IVR
  1.  
  2.  
  3. 2022-01-05 06:48:45.238230 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [9228553890@192.168.1.114] from ip 192.168.1.59
  4. 2022-01-05 06:49:03.758189 [NOTICE] switch_channel.c:1118 New Channel sofia/default/957484@192.168.1.114:5126 [4ccfe5e8-f376-4691-8d88-f5d7164d425b]
  5. 2022-01-05 06:49:03.758189 [DEBUG] switch_core_state_machine.c:585 (sofia/default/957484@192.168.1.114:5126) Running State Change CS_NEW (Cur 1 Tot 17)
  6. 2022-01-05 06:49:03.758189 [DEBUG] sofia.c:10280 sofia/default/957484@192.168.1.114:5126 receiving invite from 192.168.1.177:45193 version: 1.10.5 -release-17-25569c1631 64bit
  7. 2022-01-05 06:49:03.758189 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 192.168.1.177:0.
  8. 2022-01-05 06:49:03.758189 [WARNING] sofia_reg.c:1794 SIP auth challenge (INVITE) on sofia profile 'default' for [1800@192.168.1.114] from ip 192.168.1.177
  9. 2022-01-05 06:49:03.758189 [DEBUG] switch_core_state_machine.c:604 (sofia/default/957484@192.168.1.114:5126) State NEW
  10. 2022-01-05 06:49:03.758189 [DEBUG] sofia.c:2434 detaching session 4ccfe5e8-f376-4691-8d88-f5d7164d425b
  11. 2022-01-05 06:49:03.818172 [DEBUG] sofia.c:2544 Re-attaching to session 4ccfe5e8-f376-4691-8d88-f5d7164d425b
  12. 2022-01-05 06:49:03.818172 [DEBUG] sofia.c:10280 sofia/default/957484@192.168.1.114:5126 receiving invite from 192.168.1.177:45193 version: 1.10.5 -release-17-25569c1631 64bit
  13. 2022-01-05 06:49:03.818172 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 192.168.1.177:0.
  14. 2022-01-05 06:49:03.898164 [DEBUG] sofia.c:11477 Setting NAT mode based on via port
  15. 2022-01-05 06:49:03.898164 [DEBUG] sofia.c:7326 Channel sofia/default/957484@192.168.1.114:5126 entering state [received][100]
  16. 2022-01-05 06:49:03.898164 [DEBUG] sofia.c:7336 Remote SDP:
  17. v=0
  18. o=Zoiper 1641365344081 1 IN IP4 192.168.1.177
  19. s=Z
  20. c=IN IP4 192.168.1.177
  21. t=0 0
  22. m=audio 33934 RTP/AVP 0 101 8 3
  23. a=rtpmap:101 telephone-event/8000
  24. a=fmtp:101 0-16
  25.  
  26. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  27. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  28. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  29. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
  30. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  31. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  32. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  33. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  34. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  35. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/957484@192.168.1.114:5126 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  36. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_codec.c:111 sofia/default/957484@192.168.1.114:5126 Original read codec set to PCMU:0
  37. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
  38. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5911 sofia/default/957484@192.168.1.114:5126 Set 2833 dtmf send payload to 101 recv payload to 101
  39. 2022-01-05 06:49:03.898164 [DEBUG] sofia.c:7760 (sofia/default/957484@192.168.1.114:5126) State Change CS_NEW -> CS_INIT
  40. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_state_machine.c:585 (sofia/default/957484@192.168.1.114:5126) Running State Change CS_INIT (Cur 1 Tot 17)
  41. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_state_machine.c:628 (sofia/default/957484@192.168.1.114:5126) State INIT
  42. 2022-01-05 06:49:03.898164 [DEBUG] mod_sofia.c:93 sofia/default/957484@192.168.1.114:5126 SOFIA INIT
  43. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_state_machine.c:40 sofia/default/957484@192.168.1.114:5126 Standard INIT
  44. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_state_machine.c:48 (sofia/default/957484@192.168.1.114:5126) State Change CS_INIT -> CS_ROUTING
  45. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_state_machine.c:628 (sofia/default/957484@192.168.1.114:5126) State INIT going to sleep
  46. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_state_machine.c:585 (sofia/default/957484@192.168.1.114:5126) Running State Change CS_ROUTING (Cur 1 Tot 17)
  47. 2022-01-05 06:49:03.898164 [DEBUG] switch_channel.c:2332 (sofia/default/957484@192.168.1.114:5126) Callstate Change DOWN -> RINGING
  48. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_state_machine.c:644 (sofia/default/957484@192.168.1.114:5126) State ROUTING
  49. 2022-01-05 06:49:03.898164 [DEBUG] mod_sofia.c:154 sofia/default/957484@192.168.1.114:5126 SOFIA ROUTING
  50. 2022-01-05 06:49:03.898164 [DEBUG] switch_core_state_machine.c:236 sofia/default/957484@192.168.1.114:5126 Standard ROUTING
  51. 2022-01-05 06:49:03.898164 [INFO] mod_dialplan_xml.c:637 Processing 957484 <957484>->1800 in context default
  52. 2022-01-05 06:49:03.918176 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  53. 2022-01-05 06:49:03.918176 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  54. 2022-01-05 06:49:03.938183 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 1800
  55. 2022-01-05 06:49:03.938183 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '1800' AND status=0 limit 1
  56. 2022-01-05 06:49:03.938183 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Generated XML:
  57. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  58. <document type="freeswitch/xml">
  59. <section name="dialplan" description="ASTPP Dialplan">
  60. <context name="default">
  61. <extension name="1800">
  62. <condition field="destination_number" expression="1800">
  63. <action application="log" data="INFO ASTPP - Calling Card Call"/>
  64. <action application="answer"/>
  65. <action application="sleep" data="2000"/>
  66. <action application="lua" data="astpp-callingcards.lua"/>
  67. </condition>
  68. </extension>
  69. </context>
  70. </section>
  71. </document>
  72. Dialplan: sofia/default/957484@192.168.1.114:5126 parsing [default->1800] continue=false
  73. Dialplan: sofia/default/957484@192.168.1.114:5126 Regex (PASS) [1800] destination_number(1800) =~ /1800/ break=on-false
  74. Dialplan: sofia/default/957484@192.168.1.114:5126 Action log(INFO ASTPP - Calling Card Call)
  75. Dialplan: sofia/default/957484@192.168.1.114:5126 Action answer()
  76. Dialplan: sofia/default/957484@192.168.1.114:5126 Action sleep(2000)
  77. Dialplan: sofia/default/957484@192.168.1.114:5126 Action lua(astpp-callingcards.lua)
  78. 2022-01-05 06:49:03.938183 [DEBUG] switch_core_state_machine.c:287 (sofia/default/957484@192.168.1.114:5126) State Change CS_ROUTING -> CS_EXECUTE
  79. 2022-01-05 06:49:03.938183 [DEBUG] switch_core_state_machine.c:644 (sofia/default/957484@192.168.1.114:5126) State ROUTING going to sleep
  80. 2022-01-05 06:49:03.938183 [DEBUG] switch_core_state_machine.c:585 (sofia/default/957484@192.168.1.114:5126) Running State Change CS_EXECUTE (Cur 1 Tot 17)
  81. 2022-01-05 06:49:03.938183 [DEBUG] switch_core_state_machine.c:651 (sofia/default/957484@192.168.1.114:5126) State EXECUTE
  82. 2022-01-05 06:49:03.938183 [DEBUG] mod_sofia.c:209 sofia/default/957484@192.168.1.114:5126 SOFIA EXECUTE
  83. 2022-01-05 06:49:03.938183 [DEBUG] switch_core_state_machine.c:329 sofia/default/957484@192.168.1.114:5126 Standard EXECUTE
  84. EXECUTE [depth=0] sofia/default/957484@192.168.1.114:5126 log(INFO ASTPP - Calling Card Call)
  85. 2022-01-05 06:49:03.938183 [INFO] mod_dptools.c:1866 ASTPP - Calling Card Call
  86. EXECUTE [depth=0] sofia/default/957484@192.168.1.114:5126 answer()
  87. 2022-01-05 06:49:03.938183 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/957484@192.168.1.114:5126] 192.168.1.114 port 27988 -> 192.168.1.177 port 33934 codec: 0 ms: 20
  88. 2022-01-05 06:49:03.938183 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
  89. 2022-01-05 06:49:03.938183 [DEBUG] switch_core_media.c:8977 sofia/default/957484@192.168.1.114:5126 Set 2833 dtmf send payload to 101
  90. 2022-01-05 06:49:03.938183 [DEBUG] switch_core_media.c:8984 sofia/default/957484@192.168.1.114:5126 Set 2833 dtmf receive payload to 101
  91. 2022-01-05 06:49:03.938183 [DEBUG] switch_core_media.c:9007 sofia/default/957484@192.168.1.114:5126 Set rtp dtmf delay to 40
  92. 2022-01-05 06:49:03.938183 [DEBUG] mod_sofia.c:898 Local SDP sofia/default/957484@192.168.1.114:5126:
  93. v=0
  94. o=FreeSWITCH 1641337355 1641337356 IN IP4 192.168.1.114
  95. s=FreeSWITCH
  96. c=IN IP4 192.168.1.114
  97. t=0 0
  98. m=audio 27988 RTP/AVP 0 101
  99. a=rtpmap:0 PCMU/8000
  100. a=rtpmap:101 telephone-event/8000
  101. a=fmtp:101 0-16
  102. a=ptime:20
  103. a=sendrecv
  104.  
  105. 2022-01-05 06:49:03.938183 [NOTICE] mod_dptools.c:1406 Channel [sofia/default/957484@192.168.1.114:5126] has been answered
  106. 2022-01-05 06:49:03.938183 [DEBUG] switch_channel.c:3865 (sofia/default/957484@192.168.1.114:5126) Callstate Change RINGING -> ACTIVE
  107. 2022-01-05 06:49:03.938183 [DEBUG] sofia.c:7326 Channel sofia/default/957484@192.168.1.114:5126 entering state [completed][200]
  108. EXECUTE [depth=0] sofia/default/957484@192.168.1.114:5126 sleep(2000)
  109. 2022-01-05 06:49:03.958178 [DEBUG] sofia.c:7326 Channel sofia/default/957484@192.168.1.114:5126 entering state [ready][200]
  110. 2022-01-05 06:49:04.338214 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [9228553890@192.168.1.114] from ip 192.168.1.59
  111. 2022-01-05 06:49:04.978176 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
  112. EXECUTE [depth=0] sofia/default/957484@192.168.1.114:5126 lua(astpp-callingcards.lua)
  113. 2022-01-05 06:49:05.938226 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  114. 2022-01-05 06:49:05.958225 [NOTICE] switch_cpp.cpp:1447 [ASTPP] SECTION
  115. 2022-01-05 06:49:05.958225 [DEBUG] switch_core_file.c:405 File /var/www/html/astpp/upload/astpp-welcome.wav sample rate 16000 doesn't match requested rate 8000
  116. 2022-01-05 06:49:05.958225 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms
  117. 2022-01-05 06:49:06.838162 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [9228553890@192.168.1.114] from ip 192.168.1.59
  118. 2022-01-05 06:49:07.738179 [DEBUG] switch_ivr_play_say.c:1933 done playing file /var/www/html/astpp/upload/astpp-welcome.wav
  119. 2022-01-05 06:49:07.738179 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [get_ani] Query :SELECT * FROM ani_map WHERE number = 957484
  120. 2022-01-05 06:49:07.738179 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT *,(select currencyrate from currency where id=currency_id) as currencyrate FROM accounts WHERE (number = "36" OR id="36") AND status=0 AND deleted=0 limit 1
  121. 2022-01-05 06:49:07.738179 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2032-01-03 06:28:50' , '%Y%m%d%H%i%s') AS expiry
  122. 2022-01-05 06:49:07.738179 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2022-01-05 06:49:07' , '%Y%m%d%H%i%s') AS expiry
  123. 2022-01-05 06:49:07.738179 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 3470347082]
  124. 2022-01-05 06:49:07.738179 [ERR] mod_lua.cpp:202 /usr/share/freeswitch/scripts/astpp-callingcards.lua:103: attempt to concatenate field 'access_number_call_flow' (a nil value)
  125. stack traceback:
  126.         /usr/share/freeswitch/scripts/astpp-callingcards.lua:103: in main chunk
  127. 2022-01-05 06:49:07.738179 [DEBUG] switch_cpp.cpp:1191 sofia/default/957484@192.168.1.114:5126 destroy/unlink session from object
  128. 2022-01-05 06:49:07.738179 [NOTICE] switch_core_state_machine.c:386 sofia/default/957484@192.168.1.114:5126 has executed the last dialplan instruction, hanging up.
  129. 2022-01-05 06:49:07.738179 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/default/957484@192.168.1.114:5126 [CS_EXECUTE] [NORMAL_CLEARING]
  130. 2022-01-05 06:49:07.738179 [DEBUG] switch_core_state_machine.c:651 (sofia/default/957484@192.168.1.114:5126) State EXECUTE going to sleep
  131. 2022-01-05 06:49:07.738179 [DEBUG] switch_core_state_machine.c:585 (sofia/default/957484@192.168.1.114:5126) Running State Change CS_HANGUP (Cur 1 Tot 17)
  132. 2022-01-05 06:49:07.738179 [DEBUG] switch_core_state_machine.c:848 (sofia/default/957484@192.168.1.114:5126) Callstate Change ACTIVE -> HANGUP
  133. 2022-01-05 06:49:07.738179 [DEBUG] switch_core_state_machine.c:850 (sofia/default/957484@192.168.1.114:5126) State HANGUP
  134. 2022-01-05 06:49:07.738179 [DEBUG] mod_sofia.c:453 Channel sofia/default/957484@192.168.1.114:5126 hanging up, cause: NORMAL_CLEARING
  135. 2022-01-05 06:49:07.758188 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/default/957484@192.168.1.114:5126
  136. 2022-01-05 06:49:07.758188 [DEBUG] switch_core_state_machine.c:60 sofia/default/957484@192.168.1.114:5126 Standard HANGUP, cause: NORMAL_CLEARING
  137. 2022-01-05 06:49:07.758188 [DEBUG] switch_core_state_machine.c:850 (sofia/default/957484@192.168.1.114:5126) State HANGUP going to sleep
  138. 2022-01-05 06:49:07.758188 [DEBUG] switch_core_state_machine.c:620 (sofia/default/957484@192.168.1.114:5126) State Change CS_HANGUP -> CS_REPORTING
  139. 2022-01-05 06:49:07.758188 [DEBUG] switch_core_state_machine.c:585 (sofia/default/957484@192.168.1.114:5126) Running State Change CS_REPORTING (Cur 1 Tot 17)
  140. 2022-01-05 06:49:07.758188 [DEBUG] switch_core_state_machine.c:936 (sofia/default/957484@192.168.1.114:5126) State REPORTING
  141. 2022-01-05 06:49:07.758188 [INFO] mod_json_cdr.c:271 Process [4ccfe5e8-f376-4691-8d88-f5d7164d425b.cdr.json]
  142. 2022-01-05 06:49:07.778199 [DEBUG] switch_core_state_machine.c:174 sofia/default/957484@192.168.1.114:5126 Standard REPORTING, cause: NORMAL_CLEARING
  143. 2022-01-05 06:49:07.778199 [DEBUG] switch_core_state_machine.c:936 (sofia/default/957484@192.168.1.114:5126) State REPORTING going to sleep
  144. 2022-01-05 06:49:07.778199 [DEBUG] switch_core_state_machine.c:611 (sofia/default/957484@192.168.1.114:5126) State Change CS_REPORTING -> CS_DESTROY
  145. 2022-01-05 06:49:07.778199 [DEBUG] switch_core_session.c:1726 Session 17 (sofia/default/957484@192.168.1.114:5126) Locked, Waiting on external entities
  146. 2022-01-05 06:49:07.778199 [NOTICE] switch_core_session.c:1744 Session 17 (sofia/default/957484@192.168.1.114:5126) Ended
  147. 2022-01-05 06:49:07.778199 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/957484@192.168.1.114:5126 [CS_DESTROY]
  148. 2022-01-05 06:49:07.778199 [DEBUG] switch_core_state_machine.c:739 (sofia/default/957484@192.168.1.114:5126) Running State Change CS_DESTROY (Cur 0 Tot 17)
  149. 2022-01-05 06:49:07.778199 [DEBUG] switch_core_state_machine.c:749 (sofia/default/957484@192.168.1.114:5126) State DESTROY
  150. 2022-01-05 06:49:07.778199 [DEBUG] mod_sofia.c:364 sofia/default/957484@192.168.1.114:5126 SOFIA DESTROY
  151. 2022-01-05 06:49:07.778199 [DEBUG] switch_core_state_machine.c:181 sofia/default/957484@192.168.1.114:5126 Standard DESTROY
  152. 2022-01-05 06:49:07.778199 [DEBUG] switch_core_state_machine.c:749 (sofia/default/957484@192.168.1.114:5126) State DESTROY going to sleep
  153. freeswitch@Devops-jenkins> /exit
  154.  
  155. root@inex-GDhaval:/home/inextrix/Downloads#

Reply to "Calling_card_issue_nil_value_getting_IVR"