2018-03-13 11:45:13.715390 [NOTICE] mod_logfile.c:192 New log started. ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.015389 [NOTICE] sofia.c:1079 Hangup sofia/internal/1006@65.15.69.32 [CS_EXECUTE] [NORMAL_CLEARING] ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.015389 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/internal/1006@65.15.69.32] 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_ivr_bridge.c:825 sofia/internal/1006@65.15.69.32 ending bridge by request from write function 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/internal/1007@192.168.151.148:5060] 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [NOTICE] switch_ivr_bridge.c:1034 Hangup sofia/internal/1007@192.168.151.148:5060 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/1007@192.168.151.148:5060) State EXCHANGE_MEDIA going to sleep 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1007@192.168.151.148:5060) Running State Change CS_HANGUP (Cur 2 Tot 88) 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1007@192.168.151.148:5060) Callstate Change ACTIVE -> HANGUP 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1007@192.168.151.148:5060) State HANGUP 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] mod_sofia.c:443 sofia/internal/1007@192.168.151.148:5060 Overriding SIP cause 480 with 200 from the other leg 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] mod_sofia.c:449 Channel sofia/internal/1007@192.168.151.148:5060 hanging up, cause: NORMAL_CLEARING 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_ivr_bridge.c:1843 sofia/internal/1007@192.168.151.148:5060 skip receive message [TRANSFER] (channel is hungup already) ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_ivr_bridge.c:1846 sofia/internal/1006@65.15.69.32 skip receive message [TRANSFER] (channel is hungup already) ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_session.c:2885 sofia/internal/1006@65.15.69.32 skip receive message [PHONE_EVENT] (channel is hungup already) ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1006@65.15.69.32) State EXECUTE going to sleep 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] mod_sofia.c:502 Sending BYE to sofia/internal/1007@192.168.151.148:5060 ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1006@65.15.69.32) Running State Change CS_HANGUP (Cur 2 Tot 88) 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1007@192.168.151.148:5060 Standard HANGUP, cause: NORMAL_CLEARING 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1007@192.168.151.148:5060) State HANGUP going to sleep 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1007@192.168.151.148:5060) State Change CS_HANGUP -> CS_REPORTING 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1007@192.168.151.148:5060) Running State Change CS_REPORTING (Cur 2 Tot 88) ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1006@65.15.69.32) Callstate Change ACTIVE -> HANGUP 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1007@192.168.151.148:5060) State REPORTING ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1006@65.15.69.32) State HANGUP ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] mod_sofia.c:449 Channel sofia/internal/1006@65.15.69.32 hanging up, cause: NORMAL_CLEARING 2018-03-13 11:45:19.035364 [DEBUG] mod_cdr_sqlite.c:102 Writing SQL to DB: INSERT INTO cdr VALUES ("HTek","1006","1007","default","2018-03-13 11:43:23","2018-03-13 11:43:25","2018-03-13 11:45:19",116,114,"NORMAL_CLEARING","48a61d20-2b13-465f-aca6-e0e0c055bb76","","1007") ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1006@65.15.69.32 Standard HANGUP, cause: NORMAL_CLEARING ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1006@65.15.69.32) State HANGUP going to sleep ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1006@65.15.69.32) State Change CS_HANGUP -> CS_REPORTING 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1007@192.168.151.148:5060 Standard REPORTING, cause: NORMAL_CLEARING 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1007@192.168.151.148:5060) State REPORTING going to sleep ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1006@65.15.69.32) Running State Change CS_REPORTING (Cur 2 Tot 88) ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1006@65.15.69.32) State REPORTING 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1007@192.168.151.148:5060) State Change CS_REPORTING -> CS_DESTROY 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_session.c:1713 Session 88 (sofia/internal/1007@192.168.151.148:5060) Locked, Waiting on external entities 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [NOTICE] switch_core_session.c:1731 Session 88 (sofia/internal/1007@192.168.151.148:5060) Ended 2018-03-13 11:45:19.035364 [DEBUG] mod_cdr_sqlite.c:102 Writing SQL to DB: INSERT INTO cdr VALUES ("HTek","1006","1007","default","2018-03-13 11:43:23","2018-03-13 11:43:25","2018-03-13 11:45:19",116,114,"NORMAL_CLEARING","ff630981-1b19-4ac8-8b06-41a54fd9411c","48a61d20-2b13-465f-aca6-e0e0c055bb76","1006") 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/1007@192.168.151.148:5060 [CS_DESTROY] ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1006@65.15.69.32 Standard REPORTING, cause: NORMAL_CLEARING ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1006@65.15.69.32) State REPORTING going to sleep 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1007@192.168.151.148:5060) Running State Change CS_DESTROY (Cur 1 Tot 88) 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1007@192.168.151.148:5060) State DESTROY 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] mod_sofia.c:354 sofia/internal/1007@192.168.151.148:5060 SOFIA DESTROY 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1007@192.168.151.148:5060 Standard DESTROY 48a61d20-2b13-465f-aca6-e0e0c055bb76 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1007@192.168.151.148:5060) State DESTROY going to sleep ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1006@65.15.69.32) State Change CS_REPORTING -> CS_DESTROY ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_session.c:1713 Session 87 (sofia/internal/1006@65.15.69.32) Locked, Waiting on external entities ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [NOTICE] switch_core_session.c:1731 Session 87 (sofia/internal/1006@65.15.69.32) Ended ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/1006@65.15.69.32 [CS_DESTROY] ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1006@65.15.69.32) Running State Change CS_DESTROY (Cur 0 Tot 88) ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1006@65.15.69.32) State DESTROY ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] mod_sofia.c:354 sofia/internal/1006@65.15.69.32 SOFIA DESTROY ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1006@65.15.69.32 Standard DESTROY ff630981-1b19-4ac8-8b06-41a54fd9411c 2018-03-13 11:45:19.035364 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1006@65.15.69.32) State DESTROY going to sleep