From sahil, 3 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. t                                                                                                                                            
  2. 2021-03-03 08:58:09.049677 [DEBUG] switch_ivr_originate.c:2212 Parsing global variables
  3. 2021-03-03 08:58:09.049677 [NOTICE] switch_channel.c:1118 New Channel sofia/default/3302@192.168.0.108:5060 [404b71b4-cea5-4276-93c4-26312dc4ea76]                                                                                                                                          
  4. 2021-03-03 08:58:09.049677 [DEBUG] mod_sofia.c:5089 (sofia/default/3302@192.168.0.108:5060) State Change CS_NEW -> CS_INIT
  5. 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3302@192.168.0.108:5060) Running State Change CS_INIT (Cur 7 Tot 235075)                                                                                                                                  
  6. 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:628 (sofia/default/3302@192.168.0.108:5060) State INIT
  7. 2021-03-03 08:58:09.049677 [DEBUG] mod_sofia.c:93 sofia/default/3302@192.168.0.108:5060 SOFIA INIT
  8. 2021-03-03 08:58:09.049677 [DEBUG] sofia_glue.c:1587 sip:3302@212.139.8.138:3580 Setting proxy route to sofia/default/3302@192.168.0.108:5060
  9. 2021-03-03 08:58:09.049677 [DEBUG] sofia_glue.c:1618 sofia/default/3302@192.168.0.108:5060 sending invite version: 1.10.2 -release-14-f7bdd3845a 64bit                                                                                                                                      
  10. Local SDP:                                                                                                                                    
  11. v=0                                                                                                                                          
  12. o=FreeSWITCH 1614733081 1614733082 IN IP4 178.62.70.78                                                                                        
  13. s=FreeSWITCH                                                                                                                                  
  14. c=IN IP4 178.62.70.78                                                                                                                        
  15. t=0 0                                                                                                                                        
  16. m=audio 28808 RTP/AVP 8 0 101                                                                                                                
  17. a=rtpmap:8 PCMA/8000                                                                                                                          
  18. a=rtpmap:0 PCMU/8000                                                                                                                          
  19. a=rtpmap:101 telephone-event/8000                                                                                                            
  20. a=fmtp:101 0-16                                                                                                                              
  21. a=ptime:20                                                                                                                                    
  22. a=sendrecv                                                                                                                                    
  23.  
  24. 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:40 sofia/default/3302@192.168.0.108:5060 Standard INIT
  25. 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:48 (sofia/default/3302@192.168.0.108:5060) State Change CS_INIT -> CS_ROUTING
  26. 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:628 (sofia/default/3302@192.168.0.108:5060) State INIT going to sleep
  27. 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3302@192.168.0.108:5060) Running State Change CS_ROUTING (Cur 7 Tot 235075)                                                                                                                              
  28. 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:644 (sofia/default/3302@192.168.0.108:5060) State ROUTING
  29. 2021-03-03 08:58:09.049677 [DEBUG] mod_sofia.c:154 sofia/default/3302@192.168.0.108:5060 SOFIA ROUTING
  30. 2021-03-03 08:58:09.049677 [DEBUG] switch_ivr_originate.c:67 (sofia/default/3302@192.168.0.108:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA                                                                                                                                            
  31. 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:644 (sofia/default/3302@192.168.0.108:5060) State ROUTING going to sleep
  32. 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3302@192.168.0.108:5060) Running State Change CS_CONSUME_MEDIA (Cur 7 Tot 235075)                                                                                                                        
  33. 2021-03-03 08:58:09.049677 [DEBUG] sofia.c:7301 Channel sofia/default/3302@192.168.0.108:5060 entering state [calling][0]
  34. 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:663 (sofia/default/3302@192.168.0.108:5060) State CONSUME_MEDIA
  35. 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:663 (sofia/default/3302@192.168.0.108:5060) State CONSUME_MEDIA going to sleep
  36. 2021-03-03 08:58:09.069757 [DEBUG] sofia.c:7301 Channel sofia/default/3301@192.168.0.100:5060 entering state [proceeding][180]
  37. 2021-03-03 08:58:09.069757 [NOTICE] sofia.c:7412 Ring-Ready sofia/default/3301@192.168.0.100:5060!
  38. 2021-03-03 08:58:09.069757 [DEBUG] switch_channel.c:3437 (sofia/default/3301@192.168.0.100:5060) Callstate Change DOWN -> RINGING
  39. 2021-03-03 08:58:09.089796 [NOTICE] mod_sofia.c:2498 Ring-Ready sofia/default/447903334940@simwood.com!
  40. 2021-03-03 08:58:09.089796 [DEBUG] sofia.c:7301 Channel sofia/default/447903334940@simwood.com entering state [early][180]
  41. 2021-03-03 08:58:09.089796 [NOTICE] switch_ivr_originate.c:553 Ring Ready sofia/default/447903334940@simwood.com!
  42. 2021-03-03 08:58:09.249763 [DEBUG] sofia.c:7301 Channel sofia/default/3307@154.113.86.204:61749 entering state [proceeding][180]
  43. 2021-03-03 08:58:09.249763 [NOTICE] sofia.c:7412 Ring-Ready sofia/default/3307@154.113.86.204:61749!
  44. 2021-03-03 08:58:09.249763 [DEBUG] switch_channel.c:3437 (sofia/default/3307@154.113.86.204:61749) Callstate Change DOWN -> RINGING
  45. 2021-03-03 08:58:12.849683 [WARNING] sofia_reg.c:1793 SIP auth challenge (REGISTER) on sofia profile 'default' for [7706@Rajni179.sipp.switchboard.co.uk] from ip 188.240.171.6                                                                                                            
  46. 2021-03-03 08:58:15.429801 [WARNING] switch_core_state_machine.c:688 1f3b2245-9e37-431e-9876-226d4e9a2f0e sofia/default/56001@178.62.70.78 Abandoned                                                                                                                                        
  47. 2021-03-03 08:58:15.429801 [NOTICE] switch_core_state_machine.c:691 Hangup sofia/default/56001@178.62.70.78 [CS_NEW] [WRONG_CALL_STATE]
  48. 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:585 (sofia/default/56001@178.62.70.78) Running State Change CS_HANGUP (Cur 7 Tot 235075)                                                                                                                                    
  49. 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:848 (sofia/default/56001@178.62.70.78) Callstate Change DOWN -> HANGUP
  50. 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:850 (sofia/default/56001@178.62.70.78) State HANGUP
  51. 2021-03-03 08:58:15.429801 [DEBUG] mod_sofia.c:453 Channel sofia/default/56001@178.62.70.78 hanging up, cause: WRONG_CALL_STATE
  52. 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:60 sofia/default/56001@178.62.70.78 Standard HANGUP, cause: WRONG_CALL_STATE
  53. 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:850 (sofia/default/56001@178.62.70.78) State HANGUP going to sleep
  54. 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:620 (sofia/default/56001@178.62.70.78) State Change CS_HANGUP -> CS_REPORTING
  55. 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:585 (sofia/default/56001@178.62.70.78) Running State Change CS_REPORTING (Cur 7 Tot 235075)                                                                                                                                  
  56. 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:936 (sofia/default/56001@178.62.70.78) State REPORTING
  57. 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:174 sofia/default/56001@178.62.70.78 Standard REPORTING, cause: WRONG_CALL_STATE                                                                                                                                            
  58. 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:936 (sofia/default/56001@178.62.70.78) State REPORTING going to sleep
  59. 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:611 (sofia/default/56001@178.62.70.78) State Change CS_REPORTING -> CS_DESTROY
  60. 2021-03-03 08:58:15.429801 [DEBUG] switch_core_session.c:1726 Session 235069 (sofia/default/56001@178.62.70.78) Locked, Waiting on external entities                                                                                                                                        
  61. 2021-03-03 08:58:15.429801 [NOTICE] switch_core_session.c:1744 Session 235069 (sofia/default/56001@178.62.70.78) Ended
  62. 2021-03-03 08:58:15.429801 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/56001@178.62.70.78 [CS_DESTROY]
  63. 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:739 (sofia/default/56001@178.62.70.78) Running State Change CS_DESTROY (Cur 6 Tot 235075)                                                                                                                                    
  64. 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:749 (sofia/default/56001@178.62.70.78) State DESTROY
  65. 2021-03-03 08:58:15.429801 [DEBUG] mod_sofia.c:364 sofia/default/56001@178.62.70.78 SOFIA DESTROY
  66. 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:181 sofia/default/56001@178.62.70.78 Standard DESTROY
  67. 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:749 (sofia/default/56001@178.62.70.78) State DESTROY going to sleep
  68. 2021-03-03 08:58:23.649744 [DEBUG] sofia.c:7301 Channel sofia/default/3307@154.113.86.204:61749 entering state [completing][200]
  69. 2021-03-03 08:58:23.649744 [DEBUG] sofia.c:7311 Remote SDP:
  70. v=0                                                                                                                                          
  71. o=3cxVCE 48281295 52157625 IN IP4 154.113.86.204                                                                                              
  72. s=3cxVCE Audio Call                                                                                                                          
  73. c=IN IP4 154.113.86.204                                                                                                                      
  74. t=0 0                                                                                                                                        
  75. m=audio 40006 RTP/AVP 8 0 101                                                                                                                
  76. a=rtpmap:8 PCMA/8000                                                                                                                          
  77. a=rtpmap:0 PCMU/8000                                                                                                                          
  78. a=rtpmap:101 telephone-event/8000                                                                                                            
  79. a=fmtp:101 0-16                                                                                                                              
  80.  
  81. 2021-03-03 08:58:23.649744 [DEBUG] sofia.c:7301 Channel sofia/default/3307@154.113.86.204:61749 entering state [ready][200]
  82. 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  83. 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  84. 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  85. 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  86. 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  87. 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  88. 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000
  89. 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:3837 Set Codec sofia/default/3307@154.113.86.204:61749 PCMA/8000 20 ms 160 samples 64000 bits 1 channels                                                                                                                            
  90. 2021-03-03 08:58:23.649744 [DEBUG] switch_core_codec.c:111 sofia/default/3307@154.113.86.204:61749 Original read codec set to PCMA:8
  91. 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000
  92. 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5909 sofia/default/3307@154.113.86.204:61749 Set 2833 dtmf send payload to 101 recv payload to 101                                                                                                                                  
  93. 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/3307@154.113.86.204:61749] 178.62.70.78 port 17120 -> 154.113.86.204 port 40006 codec: 8 ms: 20                                                                                                        
  94. 2021-03-03 08:58:23.649744 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms
  95. 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:8973 sofia/default/3307@154.113.86.204:61749 Set 2833 dtmf send payload to 101
  96. 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:8980 sofia/default/3307@154.113.86.204:61749 Set 2833 dtmf receive payload to 101
  97. 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:9003 sofia/default/3307@154.113.86.204:61749 Set rtp dtmf delay to 40
  98. 2021-03-03 08:58:23.649744 [NOTICE] sofia.c:8479 Channel [sofia/default/3307@154.113.86.204:61749] has been answered
  99. 2021-03-03 08:58:23.649744 [DEBUG] switch_channel.c:3865 (sofia/default/3307@154.113.86.204:61749) Callstate Change RINGING -> ACTIVE
  100. EXECUTE [depth=0] sofia/default/3307@154.113.86.204:61749 lua(/usr/share/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua)
  101. 2021-03-03 08:58:23.669723 [ERR] mod_lua.cpp:202 cannot open /usr/share/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua: No such file or directory                                                                                                                                      
  102. 2021-03-03 08:58:23.669723 [DEBUG] switch_cpp.cpp:1187 sofia/default/3307@154.113.86.204:61749 destroy/unlink session from object
  103. 2021-03-03 08:58:23.689697 [NOTICE] switch_ivr_originate.c:3716 Hangup sofia/default/3301@192.168.0.100:5060 [CS_CONSUME_MEDIA] [LOSE_RACE]
  104. 2021-03-03 08:58:23.689697 [NOTICE] switch_ivr_originate.c:3716 Hangup sofia/default/3303@192.168.0.106:5074 [CS_CONSUME_MEDIA] [LOSE_RACE]
  105. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3301@192.168.0.100:5060) Running State Change CS_HANGUP (Cur 6 Tot 235075)                                                                                                                                
  106. 2021-03-03 08:58:23.689697 [NOTICE] switch_ivr_originate.c:3716 Hangup sofia/default/3304@192.168.0.110:5060 [CS_CONSUME_MEDIA] [LOSE_RACE]
  107. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:848 (sofia/default/3301@192.168.0.100:5060) Callstate Change RINGING -> HANGUP
  108. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3303@192.168.0.106:5074) Running State Change CS_HANGUP (Cur 6 Tot 235075)                                                                                                                                
  109. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3301@192.168.0.100:5060) State HANGUP
  110. 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:453 Channel sofia/default/3301@192.168.0.100:5060 hanging up, cause: LOSE_RACE
  111. 2021-03-03 08:58:23.689697 [NOTICE] switch_ivr_originate.c:3716 Hangup sofia/default/3302@192.168.0.108:5060 [CS_CONSUME_MEDIA] [LOSE_RACE]
  112. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3304@192.168.0.110:5060) Running State Change CS_HANGUP (Cur 6 Tot 235075)                                                                                                                                
  113. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:848 (sofia/default/3303@192.168.0.106:5074) Callstate Change DOWN -> HANGUP
  114. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3302@192.168.0.108:5060) Running State Change CS_HANGUP (Cur 6 Tot 235075)                                                                                                                                
  115. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3303@192.168.0.106:5074) State HANGUP
  116. 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:453 Channel sofia/default/3303@192.168.0.106:5074 hanging up, cause: LOSE_RACE
  117. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:848 (sofia/default/3304@192.168.0.110:5060) Callstate Change DOWN -> HANGUP
  118. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:848 (sofia/default/3302@192.168.0.108:5060) Callstate Change DOWN -> HANGUP
  119. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3304@192.168.0.110:5060) State HANGUP
  120. 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:453 Channel sofia/default/3304@192.168.0.110:5060 hanging up, cause: LOSE_RACE
  121. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3302@192.168.0.108:5060) State HANGUP
  122. 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:453 Channel sofia/default/3302@192.168.0.108:5060 hanging up, cause: LOSE_RACE
  123. 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:521 Sending CANCEL to sofia/default/3301@192.168.0.100:5060
  124. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:60 sofia/default/3301@192.168.0.100:5060 Standard HANGUP, cause: LOSE_RACE
  125. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3301@192.168.0.100:5060) State HANGUP going to sleep
  126. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:620 (sofia/default/3301@192.168.0.100:5060) State Change CS_HANGUP -> CS_REPORTING                                                                                                                                          
  127. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3301@192.168.0.100:5060) Running State Change CS_REPORTING (Cur 6 Tot 235075)                                                                                                                            
  128. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3301@192.168.0.100:5060) State REPORTING
  129. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/447903334940@simwood.com] 178.62.70.78 port 27396 -> 178.22.139.45 port 17370 codec: 8 ms: 20                                                                                                          
  130. 2021-03-03 08:58:23.689697 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms
  131. 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:521 Sending CANCEL to sofia/default/3303@192.168.0.106:5074
  132. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:60 sofia/default/3303@192.168.0.106:5074 Standard HANGUP, cause: LOSE_RACE
  133. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3303@192.168.0.106:5074) State HANGUP going to sleep
  134. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:620 (sofia/default/3303@192.168.0.106:5074) State Change CS_HANGUP -> CS_REPORTING                                                                                                                                          
  135. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3303@192.168.0.106:5074) Running State Change CS_REPORTING (Cur 6 Tot 235075)                                                                                                                            
  136. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3303@192.168.0.106:5074) State REPORTING
  137. 2021-03-03 08:58:23.689697 [INFO] mod_json_cdr.c:271 Process [5f0629f5-2d26-4eac-a26c-702e8cf26429.cdr.json]
  138. 2021-03-03 08:58:23.689697 [INFO] mod_json_cdr.c:271 Process [5ac3dffa-1cda-4966-ad56-a58779541e1b.cdr.json]
  139. 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:521 Sending CANCEL to sofia/default/3302@192.168.0.108:5060
  140. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:60 sofia/default/3302@192.168.0.108:5060 Standard HANGUP, cause: LOSE_RACE
  141. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3302@192.168.0.108:5060) State HANGUP going to sleep
  142. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:620 (sofia/default/3302@192.168.0.108:5060) State Change CS_HANGUP -> CS_REPORTING                                                                                                                                          
  143. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3302@192.168.0.108:5060) Running State Change CS_REPORTING (Cur 6 Tot 235075)                                                                                                                            
  144. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3302@192.168.0.108:5060) State REPORTING
  145. 2021-03-03 08:58:23.689697 [INFO] mod_json_cdr.c:271 Process [404b71b4-cea5-4276-93c4-26312dc4ea76.cdr.json]
  146. 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:521 Sending CANCEL to sofia/default/3304@192.168.0.110:5060
  147. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:60 sofia/default/3304@192.168.0.110:5060 Standard HANGUP, cause: LOSE_RACE
  148. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3304@192.168.0.110:5060) State HANGUP going to sleep
  149. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:620 (sofia/default/3304@192.168.0.110:5060) State Change CS_HANGUP -> CS_REPORTING                                                                                                                                          
  150. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3304@192.168.0.110:5060) Running State Change CS_REPORTING (Cur 6 Tot 235075)                                                                                                                            
  151. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3304@192.168.0.110:5060) State REPORTING
  152. 2021-03-03 08:58:23.689697 [INFO] mod_json_cdr.c:271 Process [b37cb6a9-c9f4-441f-af99-7f48216f734f.cdr.json]
  153. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_media.c:8881 Activating RTCP PORT 17371
  154. 2021-03-03 08:58:23.689697 [DEBUG] switch_rtp.c:4806 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 17371
  155. 2021-03-03 08:58:23.689697 [DEBUG] switch_rtp.c:2627 Setting RTCP remote addr to 178.22.139.45:17371 2
  156. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_media.c:8973 sofia/default/447903334940@simwood.com Set 2833 dtmf send payload to 100
  157. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_media.c:8980 sofia/default/447903334940@simwood.com Set 2833 dtmf receive payload to 100
  158. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_media.c:9003 sofia/default/447903334940@simwood.com Set rtp dtmf delay to 40
  159. 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:898 Local SDP sofia/default/447903334940@simwood.com:
  160. v=0                                                                                                                                          
  161. o=FreeSWITCH 1614734507 1614734508 IN IP4 178.62.70.78                                                                                        
  162. s=FreeSWITCH                                                                                                                                  
  163. c=IN IP4 178.62.70.78                                                                                                                        
  164. t=0 0                                                                                                                                        
  165. m=audio 27396 RTP/AVP 8 100                                                                                                                  
  166. a=rtpmap:8 PCMA/8000                                                                                                                          
  167. a=rtpmap:100 telephone-event/8000                                                                                                            
  168. a=fmtp:100 0-16                                                                                                                              
  169. a=ptime:20                                                                                                                                    
  170. a=sendrecv                                                                                                                                    
  171. a=rtcp:27397 IN IP4 178.62.70.78                                                                                                              
  172.  
  173. 2021-03-03 08:58:23.689697 [NOTICE] switch_ivr_originate.c:3751 Channel [sofia/default/447903334940@simwood.com] has been answered
  174. 2021-03-03 08:58:23.689697 [DEBUG] switch_channel.c:3865 (sofia/default/447903334940@simwood.com) Callstate Change RINGING -> ACTIVE
  175. 2021-03-03 08:58:23.689697 [DEBUG] sofia.c:7301 Channel sofia/default/447903334940@simwood.com entering state [completed][200]
  176. 2021-03-03 08:58:23.689697 [DEBUG] switch_ivr_originate.c:3809 Originate Resulted in Success: [sofia/default/3307@154.113.86.204:61749]
  177. 2021-03-03 08:58:23.689697 [DEBUG] switch_ivr_bridge.c:1796 (sofia/default/3307@154.113.86.204:61749) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA                                                                                                                                    
  178. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3307@154.113.86.204:61749) Running State Change CS_EXCHANGE_MEDIA (Cur 6 Tot 235075)                                                                                                                      
  179. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:654 (sofia/default/3307@154.113.86.204:61749) State EXCHANGE_MEDIA
  180. 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
  181. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:174 sofia/default/3301@192.168.0.100:5060 Standard REPORTING, cause: LOSE_RACE
  182. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3301@192.168.0.100:5060) State REPORTING going to sleep
  183. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:611 (sofia/default/3301@192.168.0.100:5060) State Change CS_REPORTING -> CS_DESTROY                                                                                                                                          
  184. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_session.c:1726 Session 235072 (sofia/default/3301@192.168.0.100:5060) Locked, Waiting on external entities                                                                                                                                  
  185. 2021-03-03 08:58:23.689697 [NOTICE] switch_core_session.c:1744 Session 235072 (sofia/default/3301@192.168.0.100:5060) Ended
  186. 2021-03-03 08:58:23.689697 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/3301@192.168.0.100:5060 [CS_DESTROY]
  187. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:739 (sofia/default/3301@192.168.0.100:5060) Running State Change CS_DESTROY (Cur 5 Tot 235075)                                                                                                                              
  188. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3301@192.168.0.100:5060) State DESTROY
  189. 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:364 sofia/default/3301@192.168.0.100:5060 SOFIA DESTROY
  190. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:181 sofia/default/3301@192.168.0.100:5060 Standard DESTROY
  191. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3301@192.168.0.100:5060) State DESTROY going to sleep
  192. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:174 sofia/default/3304@192.168.0.110:5060 Standard REPORTING, cause: LOSE_RACE
  193. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:174 sofia/default/3303@192.168.0.106:5074 Standard REPORTING, cause: LOSE_RACE
  194. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3304@192.168.0.110:5060) State REPORTING going to sleep
  195. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3303@192.168.0.106:5074) State REPORTING going to sleep
  196. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:611 (sofia/default/3304@192.168.0.110:5060) State Change CS_REPORTING -> CS_DESTROY                                                                                                                                          
  197. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_session.c:1726 Session 235074 (sofia/default/3304@192.168.0.110:5060) Locked, Waiting on external entities                                                                                                                                  
  198. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:611 (sofia/default/3303@192.168.0.106:5074) State Change CS_REPORTING -> CS_DESTROY                                                                                                                                          
  199. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_session.c:1726 Session 235073 (sofia/default/3303@192.168.0.106:5074) Locked, Waiting on external entities                                                                                                                                  
  200. 2021-03-03 08:58:23.689697 [NOTICE] switch_core_session.c:1744 Session 235074 (sofia/default/3304@192.168.0.110:5060) Ended
  201. 2021-03-03 08:58:23.689697 [NOTICE] switch_core_session.c:1744 Session 235073 (sofia/default/3303@192.168.0.106:5074) Ended
  202. 2021-03-03 08:58:23.689697 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/3304@192.168.0.110:5060 [CS_DESTROY]
  203. 2021-03-03 08:58:23.689697 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/3303@192.168.0.106:5074 [CS_DESTROY]
  204. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:739 (sofia/default/3304@192.168.0.110:5060) Running State Change CS_DESTROY (Cur 3 Tot 235075)                                                                                                                              
  205. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:739 (sofia/default/3303@192.168.0.106:5074) Running State Change CS_DESTROY (Cur 3 Tot 235075)                                                                                                                              
  206. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3303@192.168.0.106:5074) State DESTROY
  207. 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:364 sofia/default/3303@192.168.0.106:5074 SOFIA DESTROY
  208. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:181 sofia/default/3303@192.168.0.106:5074 Standard DESTROY
  209. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3303@192.168.0.106:5074) State DESTROY going to sleep
  210. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3304@192.168.0.110:5060) State DESTROY
  211. 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:364 sofia/default/3304@192.168.0.110:5060 SOFIA DESTROY
  212. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:181 sofia/default/3304@192.168.0.110:5060 Standard DESTROY
  213. 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3304@192.168.0.110:5060) State DESTROY going to sleep
  214. 2021-03-03 08:58:23.709748 [DEBUG] switch_core_state_machine.c:174 sofia/default/3302@192.168.0.108:5060 Standard REPORTING, cause: LOSE_RACE
  215. 2021-03-03 08:58:23.709748 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3302@192.168.0.108:5060) State REPORTING going to sleep
  216. 2021-03-03 08:58:23.709748 [DEBUG] switch_core_state_machine.c:611 (sofia/default/3302@192.168.0.108:5060) State Change CS_REPORTING -> CS_DESTROY                                                                                                                                          
  217. 2021-03-03 08:58:23.709748 [DEBUG] switch_core_session.c:1726 Session 235075 (sofia/default/3302@192.168.0.108:5060) Locked, Waiting on external entities                                                                                                                                  
  218. 2021-03-03 08:58:23.709748 [NOTICE] switch_core_session.c:1744 Session 235075 (sofia/default/3302@192.168.0.108:5060) Ended
  219. 2021-03-03 08:58:23.709748 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/3302@192.168.0.108:5060 [CS_DESTROY]
  220. 2021-03-03 08:58:23.709748 [DEBUG] switch_core_state_machine.c:739 (sofia/default/3302@192.168.0.108:5060) Running State Change CS_DESTROY (Cur 2 Tot 235075)                                                                                                                              
  221. 2021-03-03 08:58:23.709748 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3302@192.168.0.108:5060) State DESTROY
  222. 2021-03-03 08:58:23.709748 [DEBUG] mod_sofia.c:364 sofia/default/3302@192.168.0.108:5060 SOFIA DESTROY
  223. 2021-03-03 08:58:23.709748 [DEBUG] switch_core_state_machine.c:181 sofia/default/3302@192.168.0.108:5060 Standard DESTROY
  224. 2021-03-03 08:58:23.709748 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3302@192.168.0.108:5060) State DESTROY going to sleep
  225. 2021-03-03 08:58:23.869757 [DEBUG] switch_ivr_bridge.c:658 sofia/default/3307@154.113.86.204:61749 Bridge execute app sched_hangup(+6000 normal_clearing)                                                                                                                                  
  226. 2021-03-03 08:58:23.889748 [DEBUG] switch_ivr.c:632 sofia/default/3307@154.113.86.204:61749 Command Execute [depth=0] sched_hangup(+6000 normal_clearing)                                                                                                                                  
  227. EXECUTE [depth=0] sofia/default/3307@154.113.86.204:61749 sched_hangup(+6000 normal_clearing)
  228. 2021-03-03 08:58:23.889748 [DEBUG] switch_scheduler.c:252 Added task 406 switch_ivr_schedule_hangup (e632e3bf-a87f-4a41-9b7e-65ec265c437d) to run at 1614767903                                                                                                                            
  229. 2021-03-03 08:58:23.929752 [DEBUG] switch_rtp.c:7720 Correct audio ip/port confirmed.
  230. 2021-03-03 08:58:24.209760 [DEBUG] switch_rtp.c:7720 Correct audio ip/port confirmed.
  231. 2021-03-03 08:58:24.229751 [DEBUG] switch_rtp.c:1912 rtcp_stats_init: audio ssrc[233912089] base_seq[64275]
  232. 2021-03-03 08:58:24.529749 [DEBUG] switch_rtp.c:7118 Correct audio RTCP ip/port confirmed.
  233. 2021-03-03 08:58:24.709757 [DEBUG] sofia.c:7301 Channel sofia/default/447903334940@simwood.com entering state [ready][200]
  234. 2021-03-03 08:58:25.269761 [NOTICE] switch_channel.c:1118 New Channel sofia/default/9142@178.62.70.78 [e7f0bad7-312a-4ea6-b62c-264ce4447f70]
  235. 2021-03-03 08:58:25.269761 [DEBUG] switch_core_state_machine.c:585 (sofia/default/9142@178.62.70.78) Running State Change CS_NEW (Cur 3 Tot 235076)                                                                                                                                        
  236. 2021-03-03 08:58:25.269761 [DEBUG] sofia.c:10255 sofia/default/9142@178.62.70.78 receiving invite from 193.107.216.2:57622 version: 1.10.2 -release-14-f7bdd3845a 64bit                                                                                                                    
  237. 2021-03-03 08:58:25.269761 [DEBUG] sofia.c:10349 verifying acl "default" for ip/port 193.107.216.2:0.
  238. 2021-03-03 08:58:25.269761 [WARNING] sofia_reg.c:1793 SIP auth challenge (INVITE) on sofia profile 'default' for [700441769772003@178.62.70.78] from ip 193.107.216.2                                                                                                                      
  239. 2021-03-03 08:58:25.269761 [DEBUG] switch_core_state_machine.c:604 (sofia/default/9142@178.62.70.78) State NEW
  240. 2021-03-03 08:58:25.269761 [DEBUG] sofia.c:2434 detaching session e7f0bad7-312a-4ea6-b62c-264ce4447f70
  241. 2021-03-03 08:58:35.349765 [WARNING] switch_core_state_machine.c:688 e7f0bad7-312a-4ea6-b62c-264ce4447f70 sofia/default/9142@178.62.70.78 Abandoned                                                                                                                                        
  242. 2021-03-03 08:58:35.349765 [NOTICE] switch_core_state_machine.c:691 Hangup sofia/default/9142@178.62.70.78 [CS_NEW] [WRONG_CALL_STATE]
  243. 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:585 (sofia/default/9142@178.62.70.78) Running State Change CS_HANGUP (Cur 3 Tot 235076)                                                                                                                                      
  244. 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:848 (sofia/default/9142@178.62.70.78) Callstate Change DOWN -> HANGUP
  245. 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:850 (sofia/default/9142@178.62.70.78) State HANGUP
  246. 2021-03-03 08:58:35.349765 [DEBUG] mod_sofia.c:453 Channel sofia/default/9142@178.62.70.78 hanging up, cause: WRONG_CALL_STATE
  247. 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:60 sofia/default/9142@178.62.70.78 Standard HANGUP, cause: WRONG_CALL_STATE
  248. 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:850 (sofia/default/9142@178.62.70.78) State HANGUP going to sleep
  249. 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:620 (sofia/default/9142@178.62.70.78) State Change CS_HANGUP -> CS_REPORTING
  250. 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:585 (sofia/default/9142@178.62.70.78) Running State Change CS_REPORTING (Cur 3 Tot 235076)                                                                                                                                  
  251. 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:936 (sofia/default/9142@178.62.70.78) State REPORTING
  252. 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:174 sofia/default/9142@178.62.70.78 Standard REPORTING, cause: WRONG_CALL_STATE
  253. 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:936 (sofia/default/9142@178.62.70.78) State REPORTING going to sleep
  254. 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:611 (sofia/default/9142@178.62.70.78) State Change CS_REPORTING -> CS_DESTROY
  255. 2021-03-03 08:58:35.349765 [DEBUG] switch_core_session.c:1726 Session 235076 (sofia/default/9142@178.62.70.78) Locked, Waiting on external entities                                                                                                                                        
  256. 2021-03-03 08:58:35.349765 [NOTICE] switch_core_session.c:1744 Session 235076 (sofia/default/9142@178.62.70.78) Ended
  257. 2021-03-03 08:58:35.349765 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/9142@178.62.70.78 [CS_DESTROY]
  258. 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:739 (sofia/default/9142@178.62.70.78) Running State Change CS_DESTROY (Cur 2 Tot 235076)                                                                                                                                    
  259. 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:749 (sofia/default/9142@178.62.70.78) State DESTROY
  260. 2021-03-03 08:58:35.349765 [DEBUG] mod_sofia.c:364 sofia/default/9142@178.62.70.78 SOFIA DESTROY
  261. 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:181 sofia/default/9142@178.62.70.78 Standard DESTROY
  262. 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:749 (sofia/default/9142@178.62.70.78) State DESTROY going to sleep
  263.