[2020-02-13 10:36:19] Asterisk 13.30.0 built by root @ voip-dev1 on a x86_64 running Linux on 2020-01-16 21:56:44 UTC
[2020-02-13 10:36:19] DEBUG[28455] config.c: Parsing /etc/asterisk/logger.conf
[2020-02-13 10:36:19] VERBOSE[28455] logger.c: Asterisk Queue Logger restarted
[2020-02-13 10:36:21] DEBUG[28455] cdr.c: CDR Engine termination request received; waiting on messages...
[2020-02-13 10:36:21] VERBOSE[28455] asterisk.c: Asterisk cleanly ending (0).
[2020-02-13 10:36:21] VERBOSE[28455] asterisk.c: Executing last minute cleanups
[2020-02-13 10:36:21] VERBOSE[28455] res_musiconhold.c: Destroying musiconhold processes
[2020-02-13 10:36:21] DEBUG[28455] res_musiconhold.c: Destroying MOH class 'default'
[2020-02-13 10:36:21] VERBOSE[28455] manager.c: Manager unregistered action DBGet
[2020-02-13 10:36:21] VERBOSE[28455] manager.c: Manager unregistered action DBPut
[2020-02-13 10:36:21] VERBOSE[28455] manager.c: Manager unregistered action DBDel
[2020-02-13 10:36:21] VERBOSE[28455] manager.c: Manager unregistered action DBDelTree
[2020-02-13 10:36:21] DEBUG[28455] asterisk.c: Asterisk ending (0).
[2020-02-13 10:36:21] VERBOSE[28455] asterisk.c: Preparing for Asterisk restart...
[2020-02-13 10:36:21] VERBOSE[28455] asterisk.c: Asterisk is now restarting...
[2020-02-13 10:36:22] Asterisk 13.30.0 built by root @ voip-dev1 on a x86_64 running Linux on 2020-01-16 21:56:44 UTC
[2020-02-13 10:36:22] VERBOSE[24623] message.c: Message handler 'dialplan' registered.
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'MESSAGE'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'MESSAGE_DATA'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'MessageSend'
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action MessageSend
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action DataGet
[2020-02-13 10:36:22] VERBOSE[24623] channel.c: Registered channel type 'Surrogate' (Surrogate channel used to pull channel from an application)
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action BridgeTechnologyList
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action BridgeTechnologySuspend
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action BridgeTechnologyUnsuspend
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Asterisk Dynamic Loader Starting:
[2020-02-13 10:36:22] NOTICE[24623] loader.c: 2 modules will be loaded.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_odbc.so.
[2020-02-13 10:36:22] NOTICE[24623] res_odbc.c: Registered ODBC class 'asterisk' dsn->[voip-connector]
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_odbc.so => (ODBC resource)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_config_odbc.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_config_odbc.so => (Realtime ODBC configuration)
[2020-02-13 10:36:22] ERROR[24623] config_options.c: Unable to load config file 'features.conf'
[2020-02-13 10:36:22] NOTICE[24623] features_config.c: Could not load features config; using defaults
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'FEATURE'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'FEATUREMAP'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'Bridge'
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action Bridge
[2020-02-13 10:36:22] ERROR[24623] config_options.c: Unable to load config file 'acl.conf'
[2020-02-13 10:36:22] VERBOSE[24623] http.c: Bound HTTP server to address 0.0.0.0:0
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'at'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'au'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'bg'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'br'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'be'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'ch'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'cl'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'cn'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'cz'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'de'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'dk'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'ee'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'es'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'fi'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'fr'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'gr'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'hu'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'il'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'in'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'it'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'lt'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'jp'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'mx'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'my'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'nl'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'no'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'nz'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'ph'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'pl'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'pt'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'ru'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'se'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'sg'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'th'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'uk'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'us'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'us-old'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'tw'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 've'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Registered indication country 'za'
[2020-02-13 10:36:22] VERBOSE[24623] indications.c: Setting default indication country to 'us'
[2020-02-13 10:36:22] NOTICE[24623] cdr.c: CDR simple logging enabled.
[2020-02-13 10:36:22] ERROR[24623] config_options.c: Unable to load config file 'udptl.conf'
[2020-02-13 10:36:22] NOTICE[24623] udptl.c: Could not load udptl config; using defaults
[2020-02-13 10:36:22] VERBOSE[24623] pbx.c: Asterisk PBX Core Initializing
[2020-02-13 10:36:22] VERBOSE[24623] pbx.c: Registering builtin functions:
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'EXCEPTION'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'TESTTIME'
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action ShowDialPlan
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action ExtensionStateList
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'Answer'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'BackGround'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'Busy'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'Congestion'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'ExecIfTime'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'Goto'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'GotoIf'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'GotoIfTime'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'ImportVar'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'Hangup'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'Incomplete'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'NoOp'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'Proceeding'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'Progress'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'RaiseException'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'Ringing'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'SayAlpha'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'SayAlphaCase'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'SayDigits'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'SayNumber'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'SayPhonetic'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'SetAMAFlags'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'Wait'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'WaitExten'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'Set'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'MSet'
[2020-02-13 10:36:22] VERBOSE[24623] channel.c: Registered channel type 'Local' (Local Proxy Channel Driver)
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action LocalOptimizeAway
[2020-02-13 10:36:22] ERROR[24623] config_options.c: Unable to load config file 'cel.conf'
[2020-02-13 10:36:22] NOTICE[24623] cel.c: Failed to process CEL configuration; using defaults
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action Ping
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action Events
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action Logoff
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action Login
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action Challenge
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action Hangup
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action Status
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action Setvar
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action Getvar
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action GetConfig
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action GetConfigJSON
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action UpdateConfig
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action CreateConfig
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action ListCategories
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action Redirect
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action Atxfer
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action CancelAtxfer
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action Originate
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action Command
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action ExtensionState
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action PresenceState
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action AbsoluteTimeout
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action MailboxStatus
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action MailboxCount
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action ListCommands
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action SendText
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action UserEvent
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action WaitEvent
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action CoreSettings
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action CoreStatus
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action Reload
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action LoggerRotate
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action CoreShowChannels
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action ModuleLoad
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action ModuleCheck
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action AOCMessage
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action Filter
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action BlindTransfer
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'AMI_CLIENT'
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action BridgeList
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action BridgeInfo
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action BridgeDestroy
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action BridgeKick
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'CallCompletionRequest'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'CallCompletionCancel'
[2020-02-13 10:36:22] WARNING[24623] ccss.c: Could not find valid ccss.conf file. Using cc_max_requests default
[2020-02-13 10:36:22] WARNING[24623] ccss.c: Could not find valid ccss.conf file. Using cc_[state]_devstate defaults
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Asterisk Dynamic Loader Starting:
[2020-02-13 10:36:22] NOTICE[24623] loader.c: 126 modules will be loaded.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_odbc_transaction.so.
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'ODBC_Commit'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'ODBC_Rollback'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'ODBC'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_odbc_transaction.so => (ODBC transaction resource)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_sorcery_memory_cache.so.
[2020-02-13 10:36:22] VERBOSE[24623] sorcery.c: Sorcery registered wizard 'memory_cache'
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action SorceryMemoryCacheExpireObject
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action SorceryMemoryCacheExpire
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action SorceryMemoryCacheStaleObject
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action SorceryMemoryCacheStale
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action SorceryMemoryCachePopulate
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_sorcery_memory_cache.so => (Sorcery Memory Cache Object Wizard)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_sorcery_config.so.
[2020-02-13 10:36:22] VERBOSE[24623] sorcery.c: Sorcery registered wizard 'config'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_sorcery_config.so => (Sorcery Configuration File Object Wizard)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_sorcery_astdb.so.
[2020-02-13 10:36:22] VERBOSE[24623] sorcery.c: Sorcery registered wizard 'astdb'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_sorcery_astdb.so => (Sorcery Astdb Object Wizard)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_format_attr_opus.so.
[2020-02-13 10:36:22] VERBOSE[24623] format.c: Registered format interface for codec 'opus'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_format_attr_opus.so => (Opus Format Attribute Module)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_sorcery_memory.so.
[2020-02-13 10:36:22] VERBOSE[24623] sorcery.c: Sorcery registered wizard 'memory'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_sorcery_memory.so => (Sorcery In-Memory Object Wizard)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_sorcery_realtime.so.
[2020-02-13 10:36:22] VERBOSE[24623] sorcery.c: Sorcery registered wizard 'realtime'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_sorcery_realtime.so => (Sorcery Realtime Object Wizard)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading codec_opus.so.
[2020-02-13 10:36:22] VERBOSE[24623] format_cache.c: Updated cached format with name 'opus'
[2020-02-13 10:36:22] ERROR[24623] res_sorcery_config.c: Unable to load config file 'codecs.conf'
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'lintoopus' from codec slin to opus, table cost, 600000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'opustolin' from codec opus to slin, table cost, 900000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: codec_opus.so => (OPUS Coder/Decoder)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjproject.so.
[2020-02-13 10:36:22] ERROR[24623] res_sorcery_config.c: Unable to load config file 'pjproject.conf'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjproject.so => (PJPROJECT Log and Utility Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip.so.
[2020-02-13 10:36:22] VERBOSE[24623] res_pjsip.c: Local IPv4 address determined to be: 192.168.200.203
[2020-02-13 10:36:22] VERBOSE[24623] res_pjsip.c: Local IPv6 address determined to be: fe80::20c:29ff:febe:42c5
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action PJSIPShowEndpoints
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action PJSIPShowEndpoint
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action PJSIPQualify
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip.so => (Basic SIP resource)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_http_websocket.so.
[2020-02-13 10:36:22] VERBOSE[24623] res_http_websocket.c: WebSocket registered sub-protocol 'echo'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_http_websocket.so => (HTTP WebSocket Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_crypto.so.
[2020-02-13 10:36:22] VERBOSE[24623] res_crypto.c: Loaded PUBLIC key 'voip2'
[2020-02-13 10:36:22] VERBOSE[24623] res_crypto.c: Loaded PRIVATE key 'voip1'
[2020-02-13 10:36:22] VERBOSE[24623] res_crypto.c: Loaded PUBLIC key 'voip1'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_crypto.so => (Cryptographic Digital Signatures)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_pubsub.so.
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action PJSIPShowSubscriptionsInbound
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action PJSIPShowSubscriptionsOutbound
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action PJSIPShowResourceLists
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_pubsub.so => (PJSIP event resource)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_outbound_publish.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_outbound_publish.so => (PJSIP Outbound Publish Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_stun_monitor.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_stun_monitor.so => (STUN Network Monitor)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_rtp_multicast.so.
[2020-02-13 10:36:22] VERBOSE[24623] rtp_engine.c: Registered RTP engine 'multicast'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_rtp_multicast.so => (Multicast RTP Engine)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_stasis.so.
[2020-02-13 10:36:22] VERBOSE[24623] message.c: Message handler 'ari' registered.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_stasis.so => (Stasis application support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_speech.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_speech.so => (Generic Speech Recognition API)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_stasis_recording.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_stasis_recording.so => (Stasis application recording support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_ari.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_ari.so => (Asterisk RESTful Interface)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_session.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_session.so => (PJSIP Session resource)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_ari_model.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_ari_model.so => (ARI Model validators)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_stasis_playback.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_stasis_playback.so => (Stasis application playback support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_stasis_answer.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_stasis_answer.so => (Stasis application answer support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_stasis_device_state.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_stasis_device_state.so => (Stasis application device state support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_stasis_snoop.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_stasis_snoop.so => (Stasis application snoop support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_timing_timerfd.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_timing_timerfd.so => (Timerfd Timing Interface)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_authenticator_digest.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_authenticator_digest.so => (PJSIP authentication resource)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_endpoint_identifier_ip.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_endpoint_identifier_ip.so => (PJSIP IP endpoint identifier)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_endpoint_identifier_user.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_endpoint_identifier_user.so => (PJSIP username endpoint identifier)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_registrar.so.
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action PJSIPShowRegistrationsInbound
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action PJSIPShowRegistrationInboundContactStatuses
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_registrar.so => (PJSIP Registrar Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_rtp_asterisk.so.
[2020-02-13 10:36:22] VERBOSE[24623] rtp_engine.c: Registered RTP engine 'asterisk'
[2020-02-13 10:36:22] VERBOSE[24623] res_rtp_asterisk.c: RTP Allocating from port range 10000 -> 20000
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_rtp_asterisk.so => (Asterisk RTP Stack)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_outbound_authenticator_digest.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_outbound_authenticator_digest.so => (PJSIP authentication resource)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_format_attr_g729.so.
[2020-02-13 10:36:22] VERBOSE[24623] format.c: Registered format interface for codec 'g729'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_format_attr_g729.so => (G.729 Format Attribute Module)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_musiconhold.so.
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'MusicOnHold'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'StartMusicOnHold'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'StopMusicOnHold'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_musiconhold.so => (Music On Hold Resource)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_mwi_body_generator.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_mwi_body_generator.so => (PJSIP MWI resource)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_publish_asterisk.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_publish_asterisk.so => (PJSIP Asterisk Event PUBLISH Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_mwi.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_mwi.so => (PJSIP MWI resource)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_sdp_rtp.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_sdp_rtp.so => (PJSIP SDP RTP/AVP stream handler)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_t38.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_t38.so => (PJSIP T.38 UDPTL Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading chan_rtp.so.
[2020-02-13 10:36:22] VERBOSE[24623] channel.c: Registered channel type 'MulticastRTP' (Multicast RTP Paging Channel Driver)
[2020-02-13 10:36:22] VERBOSE[24623] channel.c: Registered channel type 'UnicastRTP' (Unicast RTP Media Channel Driver)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: chan_rtp.so => (RTP Media Channel)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading chan_iax2.so.
[2020-02-13 10:36:22] VERBOSE[24623] chan_iax2.c: Binding IAX2 to default address 0.0.0.0:4569
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'IAX2Provision'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'IAXPEER'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'IAXVAR'
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action IAXpeers
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action IAXpeerlist
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action IAXnetstats
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action IAXregistry
[2020-02-13 10:36:22] VERBOSE[24623] channel.c: Registered channel type 'IAX2' (Inter Asterisk eXchange Driver (Ver 2))
[2020-02-13 10:36:22] VERBOSE[24623] chan_iax2.c: 10 helper threads started
[2020-02-13 10:36:22] VERBOSE[24623] chan_iax2.c: IAX Ready and Listening
[2020-02-13 10:36:22] NOTICE[24623] iax2/provision.c: No IAX provisioning configuration found, IAX provisioning disabled.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: chan_iax2.so => (Inter Asterisk eXchange (Ver 2))
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading chan_pjsip.so.
[2020-02-13 10:36:22] VERBOSE[24623] rtp_engine.c: Registered RTP glue 'PJSIP'
[2020-02-13 10:36:22] VERBOSE[24623] channel.c: Registered channel type 'PJSIP' (PJSIP Channel Driver)
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'PJSIP_DIAL_CONTACTS'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'PJSIP_PARSE_URI'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'PJSIP_MEDIA_OFFER'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'PJSIP_DTMF_MODE'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'PJSIP_MOH_PASSTHROUGH'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'PJSIP_SEND_SESSION_REFRESH'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: chan_pjsip.so => (PJSIP Channel Driver)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading format_h264.so.
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format h264, extension(s) h264
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: format_h264.so => (Raw H.264 data)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_refer.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_refer.so => (PJSIP Blind and Attended Transfer Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading format_ilbc.so.
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format iLBC, extension(s) ilbc
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: format_ilbc.so => (Raw iLBC data)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_acl.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_acl.so => (PJSIP ACL Resource)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_header_funcs.so.
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'PJSIP_HEADER'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_header_funcs.so => (PJSIP Header Functions)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_outbound_registration.so.
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action PJSIPUnregister
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action PJSIPRegister
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action PJSIPShowRegistrationsOutbound
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_outbound_registration.so => (PJSIP Outbound Registration Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_diversion.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_diversion.so => (PJSIP Add Diversion Header Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_sips_contact.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_sips_contact.so => (UAC SIPS Contact support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_dtmf_info.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_dtmf_info.so => (PJSIP DTMF INFO Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading format_pcm.so.
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format pcm, extension(s) pcm|ulaw|ul|mu|ulw
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format alaw, extension(s) alaw|al|alw
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format au, extension(s) au
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format g722, extension(s) g722
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: format_pcm.so => (Raw/Sun uLaw/ALaw 8KHz (PCM,PCMA,AU), G.722 16Khz)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading format_wav_gsm.so.
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format wav49, extension(s) WAV|wav49
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: format_wav_gsm.so => (Microsoft WAV format (Proprietary GSM))
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading format_g729.so.
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format g729, extension(s) g729
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: format_g729.so => (Raw G.729 data)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_logger.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_logger.so => (PJSIP Packet Logger)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading format_gsm.so.
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format gsm, extension(s) gsm
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: format_gsm.so => (Raw GSM data)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading format_siren14.so.
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format siren14, extension(s) siren14
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: format_siren14.so => (ITU G.722.1 Annex C (Siren14, licensed from Polycom))
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading format_g723.so.
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format g723sf, extension(s) g723|g723sf
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: format_g723.so => (G.723.1 Simple Timestamp File Format)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading format_g726.so.
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format g726-40, extension(s) g726-40
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format g726-32, extension(s) g726-32
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format g726-24, extension(s) g726-24
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format g726-16, extension(s) g726-16
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: format_g726.so => (Raw G.726 (16/24/32/40kbps) data)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_path.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_path.so => (PJSIP Path Header Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_send_to_voicemail.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_send_to_voicemail.so => (PJSIP REFER Send to Voicemail Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading format_siren7.so.
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format siren7, extension(s) siren7
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: format_siren7.so => (ITU G.722.1 (Siren7, licensed from Polycom))
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading format_sln.so.
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format sln, extension(s) sln|raw
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format sln12, extension(s) sln12
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format sln16, extension(s) sln16
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format sln24, extension(s) sln24
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format sln32, extension(s) sln32
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format sln44, extension(s) sln44
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format sln48, extension(s) sln48
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format sln96, extension(s) sln96
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format sln192, extension(s) sln192
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: format_sln.so => (Raw Signed Linear Audio support (SLN) 8khz-192khz)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_empty_info.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_empty_info.so => (PJSIP Empty INFO Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_notify.so.
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action PJSIPNotify
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_notify.so => (CLI/AMI PJSIP NOTIFY Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_rfc3326.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_rfc3326.so => (PJSIP RFC3326 Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_transport_websocket.so.
[2020-02-13 10:36:22] VERBOSE[24623] res_http_websocket.c: WebSocket registered sub-protocol 'sip'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_transport_websocket.so => (PJSIP WebSocket Transport Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_caller_id.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_caller_id.so => (PJSIP Caller ID Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading format_wav.so.
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format wav, extension(s) wav
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format wav16, extension(s) wav16
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: format_wav.so => (Microsoft WAV/WAV16 format (8kHz/16kHz Signed Linear))
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading format_h263.so.
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format h263, extension(s) h263
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: format_h263.so => (Raw H.263 data)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_dlg_options.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_dlg_options.so => (SIP OPTIONS in dialog handler)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading format_g719.so.
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format g719, extension(s) g719
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: format_g719.so => (ITU G.719)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_registrar_expire.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_registrar_expire.so => (OBSOLETE PJSIP Contact Auto-Expiration)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading format_ogg_opus.so.
[2020-02-13 10:36:22] VERBOSE[24623] file.c: Registered file format ogg_opus, extension(s) opus
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: format_ogg_opus.so => (OGG/Opus audio)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_nat.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_nat.so => (PJSIP NAT Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_mwi_devstate.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_mwi_devstate.so => (MWI Device State Subscriptions)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading cdr_adaptive_odbc.so.
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found adaptive CDR table cdr@asterisk.
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found id column with type 4 with len 10, octetlen 10, and numlen (0,10)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found start column with type 93 with len 19, octetlen 19, and numlen (0,10)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found answer column with type 93 with len 19, octetlen 19, and numlen (0,10)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found end column with type 93 with len 19, octetlen 19, and numlen (0,10)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found clid column with type -9 with len 80, octetlen 320, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found src column with type -9 with len 80, octetlen 320, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found dst column with type -9 with len 80, octetlen 320, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found dcontext column with type -9 with len 80, octetlen 320, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found channel column with type -9 with len 80, octetlen 320, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found dstchannel column with type -9 with len 80, octetlen 320, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found lastapp column with type -9 with len 80, octetlen 320, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found lastdata column with type -9 with len 80, octetlen 320, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found duration column with type 4 with len 10, octetlen 10, and numlen (0,10)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found billsec column with type 4 with len 10, octetlen 10, and numlen (0,10)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found disposition column with type -9 with len 45, octetlen 180, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found amaflags column with type 4 with len 10, octetlen 10, and numlen (0,10)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found accountcode column with type -9 with len 20, octetlen 80, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found uniqueid column with type -9 with len 32, octetlen 128, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found userfield column with type -9 with len 191, octetlen 764, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found peeraccount column with type -9 with len 20, octetlen 80, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found linkedid column with type -9 with len 32, octetlen 128, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found sequence column with type 4 with len 10, octetlen 10, and numlen (0,10)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found bridge_tech column with type -9 with len 30, octetlen 120, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found src_codec column with type -9 with len 30, octetlen 120, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found dst_codec column with type -9 with len 30, octetlen 120, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found src_rc column with type -9 with len 100, octetlen 400, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found dst_rc column with type -9 with len 100, octetlen 400, and numlen (0,0)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found rate column with type 3 with len 10, octetlen 10, and numlen (8,10)
[2020-02-13 10:36:22] VERBOSE[24623] cdr_adaptive_odbc.c: Found added_to_invoice column with type -6 with len 3, octetlen 3, and numlen (0,10)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: cdr_adaptive_odbc.so => (Adaptive ODBC CDR backend)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_ari_device_states.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_ari_device_states.so => (RESTful API module - Device state resources)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading codec_g726.so.
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'g726tolin' from codec g726 to slin, table cost, 900000, computational cost 4000
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'lintog726' from codec slin to g726, table cost, 600000, computational cost 8000
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'g726aal2tolin' from codec g726aal2 to slin, table cost, 900000, computational cost 1
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'lintog726aal2' from codec slin to g726aal2, table cost, 600000, computational cost 8000
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: codec_g726.so => (ITU G.726-32kbps G726 Transcoder)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_ari_endpoints.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_ari_endpoints.so => (RESTful API module - Endpoint resources)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading app_cdr.so.
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'NoCDR'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'ResetCDR'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: app_cdr.so => (Tell Asterisk to not maintain a CDR for the current call)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading bridge_builtin_features.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: bridge_builtin_features.so => (Built in bridging features)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading pbx_config.so.
[2020-02-13 10:36:22] VERBOSE[24623] pbx.c: Time to scan old dialplan and merge leftovers back into the new: 0.000002 sec
[2020-02-13 10:36:22] VERBOSE[24623] pbx.c: Time to restore hints and swap in new dialplan: 0.000005 sec
[2020-02-13 10:36:22] VERBOSE[24623] pbx.c: Time to delete the old dialplan: 0.000001 sec
[2020-02-13 10:36:22] VERBOSE[24623] pbx.c: Total time merge_contexts_delete: 0.000008 sec
[2020-02-13 10:36:22] VERBOSE[24623] pbx.c: pbx_config successfully loaded 0 contexts (enable debug for details).
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action DialplanExtensionAdd
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action DialplanExtensionRemove
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: pbx_config.so => (Text Extension Configuration)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading codec_gsm.so.
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'gsmtolin' from codec gsm to slin, table cost, 900000, computational cost 1
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'lintogsm' from codec slin to gsm, table cost, 600000, computational cost 1
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: codec_gsm.so => (GSM Coder/Decoder)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_pjsip_endpoint_identifier_anonymous.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_pjsip_endpoint_identifier_anonymous.so => (PJSIP Anonymous endpoint identifier)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading app_stasis.so.
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'Stasis'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: app_stasis.so => (Stasis dialplan application)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading app_verbose.so.
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'Log'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'Verbose'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: app_verbose.so => (Send verbose output)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading codec_a_mu.so.
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'alawtoulaw' from codec alaw to ulaw, table cost, 915000, computational cost 1
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'ulawtoalaw' from codec ulaw to alaw, table cost, 915000, computational cost 1
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: codec_a_mu.so => (A-law and Mulaw direct Coder/Decoder)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading bridge_simple.so.
[2020-02-13 10:36:22] VERBOSE[24623] bridge.c: Registered bridge technology simple_bridge
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: bridge_simple.so => (Simple two channel bridging module)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_ari_sounds.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_ari_sounds.so => (RESTful API module - Sound resources)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading codec_resample.so.
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 8000khz -> 12000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 8000khz -> 16000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 8000khz -> 24000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 8000khz -> 32000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 8000khz -> 44100khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 8000khz -> 48000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 8000khz -> 96000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 8000khz -> 192000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 12000khz -> 8000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 12000khz -> 16000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 12000khz -> 24000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 12000khz -> 32000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 12000khz -> 44100khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 12000khz -> 48000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 12000khz -> 96000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 12000khz -> 192000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 16000khz -> 8000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 16000khz -> 12000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 16000khz -> 24000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 16000khz -> 32000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 16000khz -> 44100khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 16000khz -> 48000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 16000khz -> 96000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 16000khz -> 192000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 24000khz -> 8000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 24000khz -> 12000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 24000khz -> 16000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 24000khz -> 32000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 24000khz -> 44100khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 24000khz -> 48000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 24000khz -> 96000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 24000khz -> 192000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 32000khz -> 8000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 32000khz -> 12000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 32000khz -> 16000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 32000khz -> 24000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 32000khz -> 44100khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 32000khz -> 48000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 32000khz -> 96000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 32000khz -> 192000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 44100khz -> 8000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 44100khz -> 12000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 44100khz -> 16000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 44100khz -> 24000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 44100khz -> 32000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 44100khz -> 48000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 44100khz -> 96000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 44100khz -> 192000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 48000khz -> 8000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 48000khz -> 12000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 48000khz -> 16000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 48000khz -> 24000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 48000khz -> 32000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 48000khz -> 44100khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 48000khz -> 96000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 48000khz -> 192000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 96000khz -> 8000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 96000khz -> 12000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 96000khz -> 16000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 96000khz -> 24000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 96000khz -> 32000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 96000khz -> 44100khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 96000khz -> 48000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 96000khz -> 192000khz' from codec slin to slin, table cost, 800000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 192000khz -> 8000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 192000khz -> 12000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 192000khz -> 16000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 192000khz -> 24000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 192000khz -> 32000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 192000khz -> 44100khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 192000khz -> 48000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'slin 192000khz -> 96000khz' from codec slin to slin, table cost, 850000, computational cost 999999
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: codec_resample.so => (SLIN Resampling Codec)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_ari_channels.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_ari_channels.so => (RESTful API module - Channel resources)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading bridge_builtin_interval_features.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: bridge_builtin_interval_features.so => (Built in bridging interval features)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_mutestream.so.
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'MUTEAUDIO'
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action MuteAudio
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_mutestream.so => (Mute audio stream resources)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading codec_g722.so.
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'g722tolin' from codec g722 to slin, table cost, 960000, computational cost 4000
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'lintog722' from codec slin to g722, table cost, 825000, computational cost 4000
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'g722tolin16' from codec g722 to slin, table cost, 900000, computational cost 8000
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'lin16tog722' from codec slin to g722, table cost, 600000, computational cost 8000
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: codec_g722.so => (ITU G.722-64kbps G722 Transcoder)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_clioriginate.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_clioriginate.so => (Call origination and redirection from the CLI)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading app_playback.so.
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'Playback'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: app_playback.so => (Sound File Playback Application)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading codec_ulaw.so.
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'ulawtolin' from codec ulaw to slin, table cost, 900000, computational cost 1
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'lintoulaw' from codec slin to ulaw, table cost, 600000, computational cost 1
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'lintotestlaw' from codec slin to testlaw, table cost, 600000, computational cost 1
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'testlawtolin' from codec testlaw to slin, table cost, 900000, computational cost 1
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: codec_ulaw.so => (mu-Law Coder/Decoder)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_convert.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_convert.so => (File format conversion CLI command)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading func_channel.so.
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'CHANNEL'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'CHANNELS'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'MASTER_CHANNEL'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: func_channel.so => (Channel information dialplan functions)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading bridge_holding.so.
[2020-02-13 10:36:22] VERBOSE[24623] bridge.c: Registered bridge technology holding_bridge
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: bridge_holding.so => (Holding bridge module)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading func_cdr.so.
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'CDR'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'CDR_PROP'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: func_cdr.so => (Call Detail Record (CDR) dialplan functions)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading codec_adpcm.so.
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'adpcmtolin' from codec adpcm to slin, table cost, 900000, computational cost 1
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'lintoadpcm' from codec slin to adpcm, table cost, 600000, computational cost 1
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: codec_adpcm.so => (Adaptive Differential PCM Coder/Decoder)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_ari_asterisk.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_ari_asterisk.so => (RESTful API module - Asterisk resources)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_ari_bridges.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_ari_bridges.so => (RESTful API module - Bridge resources)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading codec_lpc10.so.
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'lpc10tolin' from codec lpc10 to slin, table cost, 900000, computational cost 4000
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'lintolpc10' from codec slin to lpc10, table cost, 600000, computational cost 4000
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: codec_lpc10.so => (LPC10 2.4kbps Coder/Decoder)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading app_voicemail.so.
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'VoiceMail'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'VoiceMailMain'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'MailboxExists'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'VMAuthenticate'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'VoiceMailPlayMsg'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'VMSayName'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'MAILBOX_EXISTS'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'VM_INFO'
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action VoicemailUsersList
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action VoicemailRefresh
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: app_voicemail.so => (Comedian Mail (Voicemail System) with ODBC Storage)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_ari_playbacks.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_ari_playbacks.so => (RESTful API module - Playback control resources)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading func_logic.so.
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'ISNULL'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'SET'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'EXISTS'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'IF'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'IFTIME'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'IMPORT'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: func_logic.so => (Logical dialplan functions)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_ari_events.so.
[2020-02-13 10:36:22] VERBOSE[24623] res_http_websocket.c: WebSocket registered sub-protocol 'ari'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_ari_events.so => (RESTful API module - WebSocket resource)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading bridge_softmix.so.
[2020-02-13 10:36:22] VERBOSE[24623] bridge.c: Registered bridge technology softmix
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: bridge_softmix.so => (Multi-party software based channel mixing)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_realtime.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_realtime.so => (Realtime Data Lookup/Rewrite)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading pbx_spool.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: pbx_spool.so => (Outgoing Spool Support)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_ari_recordings.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_ari_recordings.so => (RESTful API module - Recording resources)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_limit.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_limit.so => (Resource limits)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading codec_ilbc.so.
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'ilbctolin' from codec ilbc to slin, table cost, 900000, computational cost 4000
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'lintoilbc' from codec slin to ilbc, table cost, 600000, computational cost 24000
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: codec_ilbc.so => (iLBC Coder/Decoder)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading codec_alaw.so.
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'alawtolin' from codec alaw to slin, table cost, 900000, computational cost 1
[2020-02-13 10:36:22] VERBOSE[24623] translate.c: Registered translator 'lintoalaw' from codec slin to alaw, table cost, 600000, computational cost 1
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: codec_alaw.so => (A-law Coder/Decoder)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading bridge_native_rtp.so.
[2020-02-13 10:36:22] VERBOSE[24623] bridge.c: Registered bridge technology native_rtp
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: bridge_native_rtp.so => (Native RTP bridging module)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_security_log.so.
[2020-02-13 10:36:22] VERBOSE[24623] res_security_log.c: Security Logging Enabled
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_security_log.so => (Security Event Logging)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_ari_applications.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_ari_applications.so => (RESTful API module - Stasis application resources)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading func_callerid.so.
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'CALLERPRES'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'CALLERID'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'CONNECTEDLINE'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'REDIRECTING'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: func_callerid.so => (Party ID related dialplan functions (Caller-ID, Connected-line, Redirecting))
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading pbx_loopback.so.
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: pbx_loopback.so => (Loopback Switch)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading chan_bridge_media.so.
[2020-02-13 10:36:22] VERBOSE[24623] channel.c: Registered channel type 'Announcer' (Bridge Media Announcing Channel Driver)
[2020-02-13 10:36:22] VERBOSE[24623] channel.c: Registered channel type 'Recorder' (Bridge Media Recording Channel Driver)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: chan_bridge_media.so => (Bridge Media Channel Driver)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading func_odbc.so.
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'ODBC_FETCH'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_app.c: Registered application 'ODBCFinish'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'ODBC_MAILBOX_HAS_GREETING'
[2020-02-13 10:36:22] VERBOSE[24623] pbx_functions.c: Registered custom function 'SQL_ESC'
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: func_odbc.so => (ODBC lookups)
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: Loading res_manager_devicestate.so.
[2020-02-13 10:36:22] VERBOSE[24623] manager.c: Manager registered action DeviceStateList
[2020-02-13 10:36:22] VERBOSE[24623] loader.c: res_manager_devicestate.so => (Manager Device State Topic Forwarder)
[2020-02-13 10:36:22] VERBOSE[24623] asterisk.c: Asterisk Ready.
[2020-02-13 10:36:26] VERBOSE[28463] asterisk.c: Remote UNIX connection
[2020-02-13 10:36:35] DEBUG[28487] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=20 (rdata0x285e108)
[2020-02-13 10:36:35] DEBUG[28487] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000027 to use for Request msg REGISTER/cseq=20 (rdata0x285e108)
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: Splitting '192.168.200.250' into...
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: ...host '192.168.200.250' and port ''.
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '00' domain '192.168.200.203'
[2020-02-13 10:36:35] DEBUG[28487] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=20 (rdata0x7feca8009c08)
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '00@192.168.200.203'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:36:35] DEBUG[28487] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002c to use for Request msg REGISTER/cseq=20 (rdata0x7feca8009c08)
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '00'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:36:35] DEBUG[28488] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip_endpoint_identifier_user.c: Identified by From username '00' domain '192.168.200.203'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_auths WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '00'
[2020-02-13 10:36:35] DEBUG[28489] netsock2.c: Splitting '192.168.200.250' into...
[2020-02-13 10:36:35] DEBUG[28489] netsock2.c: ...host '192.168.200.250' and port ''.
[2020-02-13 10:36:35] DEBUG[28489] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[2020-02-13 10:36:35] DEBUG[28489] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '01' domain '192.168.200.203'
[2020-02-13 10:36:35] DEBUG[28489] res_odbc.c: Connecting asterisk(0x7fecbc001bf8)
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:36:35] DEBUG[28489] res_odbc.c: res_odbc: Connected to asterisk [voip-connector (0x7fecbc001bf8)]
[2020-02-13 10:36:35] DEBUG[28489] res_odbc.c: Created ODBC handle 0x7fecbc001bf8 on class 'asterisk', new count is 2
[2020-02-13 10:36:35] DEBUG[28489] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [32] in [0, 4294967295] gives [32](0)
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: Splitting '192.168.200.203' into...
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: ...host '192.168.200.203' and port ''.
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: Splitting '192.168.200.250' into...
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: ...host '192.168.200.250' and port ''.
[2020-02-13 10:36:35] DEBUG[28489] res_config_odbc.c: Parameter 1 ('id') = '01@192.168.200.203'
[2020-02-13 10:36:35] DEBUG[28489] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:36:35] DEBUG[28489] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28489] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28489] res_config_odbc.c: Parameter 1 ('id') = '01'
[2020-02-13 10:36:35] DEBUG[28489] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:36:35] DEBUG[28489] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:36:35] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28489] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:36:35] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28489] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:36:35] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28489] res_pjsip_endpoint_identifier_user.c: Identified by From username '01' domain '192.168.200.203'
[2020-02-13 10:36:35] DEBUG[28489] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28489] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_auths WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28489] res_config_odbc.c: Parameter 1 ('id') = '01'
[2020-02-13 10:36:35] DEBUG[28489] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:36:35] DEBUG[28489] config.c: extract uint from [32] in [0, 4294967295] gives [32](0)
[2020-02-13 10:36:35] DEBUG[28489] netsock2.c: Splitting '192.168.200.203' into...
[2020-02-13 10:36:35] DEBUG[28489] netsock2.c: ...host '192.168.200.203' and port ''.
[2020-02-13 10:36:35] DEBUG[28489] netsock2.c: Splitting '192.168.200.250' into...
[2020-02-13 10:36:35] DEBUG[28489] netsock2.c: ...host '192.168.200.250' and port ''.
[2020-02-13 10:36:35] DEBUG[28487] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=21 (rdata0x7feca8009c08)
[2020-02-13 10:36:35] DEBUG[28487] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000027 to use for Request msg REGISTER/cseq=21 (rdata0x7feca8009c08)
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: Splitting '192.168.200.250' into...
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: ...host '192.168.200.250' and port ''.
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '00' domain '192.168.200.203'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '00@192.168.200.203'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '00'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:36:35] DEBUG[28488] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip_endpoint_identifier_user.c: Identified by From username '00' domain '192.168.200.203'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_auths WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '00'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [32] in [0, 4294967295] gives [32](0)
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip_authenticator_digest.c: Calculated nonce 1581608195/7474f6f6d52e29a5bb52a0cb13983f7c. Actual nonce is 1581608195/7474f6f6d52e29a5bb52a0cb13983f7c
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: Splitting '192.168.200.203' into...
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: ...host '192.168.200.203' and port ''.
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: Splitting '192.168.200.250' into...
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: ...host '192.168.200.250' and port ''.
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip_registrar.c: Matched id '00' to aor '00'
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip_registrar.c: Matched aor '00' by To username
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_aors WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '00'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [7200] in [0, 4294967295] gives [7200](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [3600] in [0, 4294967295] gives [3600](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [60] in [0, 4294967295] gives [60](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [1] in [0, 4294967295] gives [1](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [60] in [0, 86400] gives [60](0)
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_contacts WHERE id LIKE ? ORDER BY id
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id LIKE') = '00;@%'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: INSERT INTO ps_contacts (id, via_addr, qualify_timeout, call_id, reg_server, prune_on_boot, path, endpoint, via_port, authenticate_qualify, uri, qualify_frequency, user_agent, expiration_time, outbound_proxy) VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '00;@724e61f2779fcfca0a52f5ef847cb01c'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 2 ('via_addr') = '192.168.200.250'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 3 ('qualify_timeout') = '3.000000'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 4 ('call_id') = 'p2sLjX3vn7'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 5 ('reg_server') = ''
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 6 ('prune_on_boot') = 'no'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 7 ('path') = ''
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 8 ('endpoint') = '00'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 9 ('via_port') = '5060'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 10 ('authenticate_qualify') = 'yes'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 11 ('uri') = 'sip:00@192.168.200.250:5060'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 12 ('qualify_frequency') = '60'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 13 ('user_agent') = 'Linphone/3.12.0 (belle-sip/1.6.3)'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 14 ('expiration_time') = '1581611795'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 15 ('outbound_proxy') = ''
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:36:35] DEBUG[28488] sorcery.c: Sorcery wizard 'config' does not support creation
[2020-02-13 10:36:35] VERBOSE[28488] res_pjsip_registrar.c: Added contact 'sip:00@192.168.200.250:5060' to AOR '00' with expiration of 3600 seconds
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_aors WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '00'
[2020-02-13 10:36:35] DEBUG[28490] res_pjsip/pjsip_options.c: Starting scheduled callback on AOR '00' for qualifying as there is now a contact on it
[2020-02-13 10:36:35] DEBUG[28490] res_pjsip/pjsip_options.c: Qualifying all contacts on AOR '00'
[2020-02-13 10:36:35] DEBUG[28490] res_pjsip/pjsip_options.c: Qualifying contact '00;@724e61f2779fcfca0a52f5ef847cb01c' on AOR '00'
[2020-02-13 10:36:35] DEBUG[28490] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28490] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28490] res_config_odbc.c: Parameter 1 ('id') = '00'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [7200] in [0, 4294967295] gives [7200](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [3600] in [0, 4294967295] gives [3600](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [60] in [0, 4294967295] gives [60](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [1] in [0, 4294967295] gives [1](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [60] in [0, 86400] gives [60](0)
[2020-02-13 10:36:35] DEBUG[28490] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:36:35] DEBUG[28490] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28490] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_aors WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28490] res_config_odbc.c: Parameter 1 ('id') = '00'
[2020-02-13 10:36:35] DEBUG[28490] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [7200] in [0, 4294967295] gives [7200](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [3600] in [0, 4294967295] gives [3600](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [60] in [0, 4294967295] gives [60](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [1] in [0, 4294967295] gives [1](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [60] in [0, 86400] gives [60](0)
[2020-02-13 10:36:35] DEBUG[28490] res_pjsip.c: 0x7fecb0006f78: Wrapper created
[2020-02-13 10:36:35] DEBUG[28490] res_pjsip.c: 0x7fecb0006f78: Set timer to 3000 msec
[2020-02-13 10:36:35] DEBUG[28490] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 192.168.200.203:5060 (this may be re-written again later)
[2020-02-13 10:36:35] DEBUG[28487] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=21 (rdata0x7feca8009c08)
[2020-02-13 10:36:35] DEBUG[28487] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002c to use for Request msg REGISTER/cseq=21 (rdata0x7feca8009c08)
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: Splitting '192.168.200.250' into...
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: ...host '192.168.200.250' and port ''.
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '01' domain '192.168.200.203'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '01@192.168.200.203'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '01'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:36:35] DEBUG[28488] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip_endpoint_identifier_user.c: Identified by From username '01' domain '192.168.200.203'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_auths WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '01'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [32] in [0, 4294967295] gives [32](0)
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip_authenticator_digest.c: Calculated nonce 1581608195/7474f6f6d52e29a5bb52a0cb13983f7c. Actual nonce is 1581608195/7474f6f6d52e29a5bb52a0cb13983f7c
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: Splitting '192.168.200.203' into...
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: ...host '192.168.200.203' and port ''.
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: Splitting '192.168.200.250' into...
[2020-02-13 10:36:35] DEBUG[28488] netsock2.c: ...host '192.168.200.250' and port ''.
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip_registrar.c: Matched id '01' to aor '01'
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip_registrar.c: Matched aor '01' by To username
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_aors WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '01'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [7200] in [0, 4294967295] gives [7200](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [3600] in [0, 4294967295] gives [3600](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [60] in [0, 4294967295] gives [60](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [1] in [0, 4294967295] gives [1](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [60] in [0, 86400] gives [60](0)
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_contacts WHERE id LIKE ? ORDER BY id
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id LIKE') = '01;@%'
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: INSERT INTO ps_contacts (id, via_addr, qualify_timeout, call_id, reg_server, prune_on_boot, path, endpoint, via_port, authenticate_qualify, uri, qualify_frequency, user_agent, expiration_time, outbound_proxy) VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '01;@049aa1c485c42afb0ea648d3aa73556e'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 2 ('via_addr') = '192.168.200.250'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 3 ('qualify_timeout') = '3.000000'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 4 ('call_id') = 'knv~Ituw50'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 5 ('reg_server') = ''
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 6 ('prune_on_boot') = 'no'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 7 ('path') = ''
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 8 ('endpoint') = '01'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 9 ('via_port') = '5060'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 10 ('authenticate_qualify') = 'yes'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 11 ('uri') = 'sip:01@192.168.200.250:5060'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 12 ('qualify_frequency') = '60'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 13 ('user_agent') = 'Linphone/3.12.0 (belle-sip/1.6.3)'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 14 ('expiration_time') = '1581611795'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 15 ('outbound_proxy') = ''
[2020-02-13 10:36:35] DEBUG[28487] res_pjsip/pjsip_distributor.c: No dialog serializer for Response msg 200/OPTIONS/cseq=5945 (rdata0x7feca8001798).  Using request transaction as basis.
[2020-02-13 10:36:35] DEBUG[28487] res_pjsip/pjsip_distributor.c: Found transaction tsx0x7fecb0009e38 for Response msg 200/OPTIONS/cseq=5945 (rdata0x7feca8001798).
[2020-02-13 10:36:35] DEBUG[28487] res_pjsip/pjsip_distributor.c: Found serializer pjsip/options/00-0000003c on transaction tsx0x7fecb0009e38
[2020-02-13 10:36:35] DEBUG[28489] res_pjsip.c: 0x7fecb0006f78: PJSIP tsx response received
[2020-02-13 10:36:35] DEBUG[28489] res_pjsip.c: 0x7fecb0006f78: Cancelling timer
[2020-02-13 10:36:35] DEBUG[28489] res_pjsip.c: 0x7fecb0006f78: Timer cancelled
[2020-02-13 10:36:35] DEBUG[28489] res_pjsip.c: 0x7fecb0006f78: Callbacks executed
[2020-02-13 10:36:35] DEBUG[28489] res_pjsip.c: 0x7fecb0006f78: wrapper destroyed
[2020-02-13 10:36:35] DEBUG[28489] res_pjsip/pjsip_options.c: Endpoint state compositor '00' is online as AOR '00' is available
[2020-02-13 10:36:35] VERBOSE[28489] res_pjsip/pjsip_configuration.c: Endpoint 00 is now Reachable
[2020-02-13 10:36:35] VERBOSE[28489] res_pjsip/pjsip_options.c: Contact 00/sip:00@192.168.200.250:5060 is now Reachable.  RTT: 147.394 msec
[2020-02-13 10:36:35] DEBUG[28489] res_pjsip/pjsip_options.c: AOR '00' now has 1 available contacts
[2020-02-13 10:36:35] DEBUG[28473] devicestate.c: No provider found, checking channel drivers for PJSIP - 00
[2020-02-13 10:36:35] DEBUG[28473] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28473] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28473] res_config_odbc.c: Parameter 1 ('id') = '00'
[2020-02-13 10:36:35] DEBUG[28473] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:36:35] DEBUG[28473] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] devicestate.c: Changing state for PJSIP/00 - state 1 (Not in use)
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:36:35] DEBUG[28488] sorcery.c: Sorcery wizard 'config' does not support creation
[2020-02-13 10:36:35] DEBUG[28490] res_pjsip/pjsip_options.c: Starting scheduled callback on AOR '01' for qualifying as there is now a contact on it
[2020-02-13 10:36:35] DEBUG[28490] res_pjsip/pjsip_options.c: Qualifying all contacts on AOR '01'
[2020-02-13 10:36:35] DEBUG[28490] res_pjsip/pjsip_options.c: Qualifying contact '01;@049aa1c485c42afb0ea648d3aa73556e' on AOR '01'
[2020-02-13 10:36:35] DEBUG[28490] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28490] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28490] res_config_odbc.c: Parameter 1 ('id') = '01'
[2020-02-13 10:36:35] VERBOSE[28488] res_pjsip_registrar.c: Added contact 'sip:01@192.168.200.250:5060' to AOR '01' with expiration of 3600 seconds
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28490] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:36:35] DEBUG[28490] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28490] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_aors WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28490] res_config_odbc.c: Parameter 1 ('id') = '01'
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_aors WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '01'
[2020-02-13 10:36:35] DEBUG[28490] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [7200] in [0, 4294967295] gives [7200](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [3600] in [0, 4294967295] gives [3600](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [60] in [0, 4294967295] gives [60](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [1] in [0, 4294967295] gives [1](0)
[2020-02-13 10:36:35] DEBUG[28490] config.c: extract uint from [60] in [0, 86400] gives [60](0)
[2020-02-13 10:36:35] DEBUG[28490] res_pjsip.c: 0x7fecb0001108: Wrapper created
[2020-02-13 10:36:35] DEBUG[28490] res_pjsip.c: 0x7fecb0001108: Set timer to 3000 msec
[2020-02-13 10:36:35] DEBUG[28490] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 192.168.200.203:5060 (this may be re-written again later)
[2020-02-13 10:36:35] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [7200] in [0, 4294967295] gives [7200](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [3600] in [0, 4294967295] gives [3600](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [60] in [0, 4294967295] gives [60](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [1] in [0, 4294967295] gives [1](0)
[2020-02-13 10:36:35] DEBUG[28488] config.c: extract uint from [60] in [0, 86400] gives [60](0)
[2020-02-13 10:36:35] DEBUG[28487] res_pjsip/pjsip_distributor.c: No dialog serializer for Response msg 200/OPTIONS/cseq=9544 (rdata0x7feca8001798).  Using request transaction as basis.
[2020-02-13 10:36:35] DEBUG[28487] res_pjsip/pjsip_distributor.c: Found transaction tsx0x7fecb00159c8 for Response msg 200/OPTIONS/cseq=9544 (rdata0x7feca8001798).
[2020-02-13 10:36:35] DEBUG[28487] res_pjsip/pjsip_distributor.c: Found serializer pjsip/options/01-0000003d on transaction tsx0x7fecb00159c8
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip.c: 0x7fecb0001108: PJSIP tsx response received
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip.c: 0x7fecb0001108: Cancelling timer
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip.c: 0x7fecb0001108: Timer cancelled
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip.c: 0x7fecb0001108: Callbacks executed
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip.c: 0x7fecb0001108: wrapper destroyed
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip/pjsip_options.c: Endpoint state compositor '01' is online as AOR '01' is available
[2020-02-13 10:36:35] VERBOSE[28488] res_pjsip/pjsip_configuration.c: Endpoint 01 is now Reachable
[2020-02-13 10:36:35] DEBUG[28473] devicestate.c: No provider found, checking channel drivers for PJSIP - 01
[2020-02-13 10:36:35] VERBOSE[28488] res_pjsip/pjsip_options.c: Contact 01/sip:01@192.168.200.250:5060 is now Reachable.  RTT: 68.806 msec
[2020-02-13 10:36:35] DEBUG[28473] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:36:35] DEBUG[28473] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:36:35] DEBUG[28473] res_config_odbc.c: Parameter 1 ('id') = '01'
[2020-02-13 10:36:35] DEBUG[28488] res_pjsip/pjsip_options.c: AOR '01' now has 1 available contacts
[2020-02-13 10:36:35] DEBUG[28473] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:36:35] DEBUG[28473] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:35] DEBUG[28473] devicestate.c: Changing state for PJSIP/01 - state 1 (Not in use)
[2020-02-13 10:36:42] DEBUG[28468] threadpool.c: Worker thread idle timeout reached. Dying.
[2020-02-13 10:36:42] DEBUG[28469] threadpool.c: Worker thread idle timeout reached. Dying.
[2020-02-13 10:36:42] DEBUG[28461] threadpool.c: Destroying worker thread 3
[2020-02-13 10:36:42] DEBUG[28461] threadpool.c: Destroying worker thread 4
[2020-02-13 10:36:42] DEBUG[28467] threadpool.c: Worker thread idle timeout reached. Dying.
[2020-02-13 10:36:42] DEBUG[28461] threadpool.c: Destroying worker thread 2
[2020-02-13 10:36:42] DEBUG[28465] threadpool.c: Worker thread idle timeout reached. Dying.
[2020-02-13 10:36:42] DEBUG[28466] threadpool.c: Worker thread idle timeout reached. Dying.
[2020-02-13 10:36:42] DEBUG[28461] threadpool.c: Destroying worker thread 0
[2020-02-13 10:36:42] DEBUG[28461] threadpool.c: Destroying worker thread 1
[2020-02-13 10:36:44] DEBUG[28524] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:36:44] DEBUG[28524] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_contacts WHERE id LIKE ? ORDER BY id
[2020-02-13 10:36:44] DEBUG[28524] res_config_odbc.c: Parameter 1 ('id LIKE') = '%'
[2020-02-13 10:36:44] DEBUG[28524] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:36:44] DEBUG[28524] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:36:44] DEBUG[28524] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:44] DEBUG[28524] config.c: extract uint from [60] in [0, 86400] gives [60](0)
[2020-02-13 10:36:44] DEBUG[28524] config.c: extract double from [3] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:36:44] DEBUG[28524] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0)
[2020-02-13 10:36:44] DEBUG[28524] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:36:44] DEBUG[28524] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:36:44] DEBUG[28524] config.c: extract uint from [60] in [0, 86400] gives [60](0)
[2020-02-13 10:36:44] DEBUG[28524] config.c: extract double from [3] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:36:44] DEBUG[28524] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0)
[2020-02-13 10:36:44] DEBUG[28524] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:36:44] DEBUG[28524] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_aors WHERE contact != ? ORDER BY contact
[2020-02-13 10:36:44] DEBUG[28524] res_config_odbc.c: Parameter 1 ('contact !=') = ''
[2020-02-13 10:36:44] DEBUG[28524] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:36:52] DEBUG[28499] res_pjsip_registrar.c: Woke up at 1581608212  Interval: 30
[2020-02-13 10:36:52] DEBUG[28499] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:36:52] DEBUG[28499] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_contacts WHERE expiration_time <= ? ORDER BY expiration_time
[2020-02-13 10:36:52] DEBUG[28499] res_config_odbc.c: Parameter 1 ('expiration_time <=') = '1581608212'
[2020-02-13 10:36:52] DEBUG[28499] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:36:52] DEBUG[28499] res_pjsip_registrar.c: Expiring 0 contacts
[2020-02-13 10:36:55] DEBUG[28515] res_timing_timerfd.c: Expected to acknowledge 1 ticks but got 3 instead
[2020-02-13 10:37:02] DEBUG[28487] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=1 (rdata0x7feca8000f58)
[2020-02-13 10:37:02] DEBUG[28487] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002f to use for Request msg REGISTER/cseq=1 (rdata0x7feca8000f58)
[2020-02-13 10:37:02] DEBUG[28488] netsock2.c: Splitting '192.168.200.250' into...
[2020-02-13 10:37:02] DEBUG[28488] netsock2.c: ...host '192.168.200.250' and port ''.
[2020-02-13 10:37:02] DEBUG[28488] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[2020-02-13 10:37:02] DEBUG[28488] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '02' domain '192.168.200.203'
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '02@192.168.200.203'
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '02'
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:37:02] DEBUG[28488] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] stasis.c: Topic 'endpoint:PJSIP/02': 0x7fecb80096e8 created
[2020-02-13 10:37:02] DEBUG[28488] stasis.c: Topic 'cache:9/endpoint:PJSIP/02': 0x7fecb802bdf8 created
[2020-02-13 10:37:02] DEBUG[28461] threadpool.c: Increasing threadpool stasis/pool's size by 1
[2020-02-13 10:37:02] DEBUG[28488] res_pjsip_endpoint_identifier_user.c: Identified by From username '02' domain '192.168.200.203'
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_auths WHERE id = ?
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '02'
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [32] in [0, 4294967295] gives [32](0)
[2020-02-13 10:37:02] DEBUG[28488] netsock2.c: Splitting '192.168.200.203' into...
[2020-02-13 10:37:02] DEBUG[28488] netsock2.c: ...host '192.168.200.203' and port ''.
[2020-02-13 10:37:02] DEBUG[28488] netsock2.c: Splitting '192.168.200.250' into...
[2020-02-13 10:37:02] DEBUG[28488] netsock2.c: ...host '192.168.200.250' and port ''.
[2020-02-13 10:37:02] DEBUG[28487] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=2 (rdata0x7feca8000f58)
[2020-02-13 10:37:02] DEBUG[28487] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002f to use for Request msg REGISTER/cseq=2 (rdata0x7feca8000f58)
[2020-02-13 10:37:02] DEBUG[28488] netsock2.c: Splitting '192.168.200.250' into...
[2020-02-13 10:37:02] DEBUG[28488] netsock2.c: ...host '192.168.200.250' and port ''.
[2020-02-13 10:37:02] DEBUG[28488] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[2020-02-13 10:37:02] DEBUG[28488] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '02' domain '192.168.200.203'
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '02@192.168.200.203'
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '02'
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:37:02] DEBUG[28488] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] res_pjsip_endpoint_identifier_user.c: Identified by From username '02' domain '192.168.200.203'
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_auths WHERE id = ?
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '02'
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [32] in [0, 4294967295] gives [32](0)
[2020-02-13 10:37:02] DEBUG[28488] res_pjsip_authenticator_digest.c: Calculated nonce 1581608222/c0b20a8c8e6dc7cd84bf76c04088ad67. Actual nonce is 1581608222/c0b20a8c8e6dc7cd84bf76c04088ad67
[2020-02-13 10:37:02] DEBUG[28488] netsock2.c: Splitting '192.168.200.203' into...
[2020-02-13 10:37:02] DEBUG[28488] netsock2.c: ...host '192.168.200.203' and port ''.
[2020-02-13 10:37:02] DEBUG[28488] netsock2.c: Splitting '192.168.200.250' into...
[2020-02-13 10:37:02] DEBUG[28488] netsock2.c: ...host '192.168.200.250' and port ''.
[2020-02-13 10:37:02] DEBUG[28488] res_pjsip_registrar.c: Matched id '02' to aor '02'
[2020-02-13 10:37:02] DEBUG[28488] res_pjsip_registrar.c: Matched aor '02' by To username
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_aors WHERE id = ?
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '02'
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [7200] in [0, 4294967295] gives [7200](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [3600] in [0, 4294967295] gives [3600](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [60] in [0, 4294967295] gives [60](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [1] in [0, 4294967295] gives [1](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [60] in [0, 86400] gives [60](0)
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_contacts WHERE id LIKE ? ORDER BY id
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id LIKE') = '02;@%'
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: INSERT INTO ps_contacts (id, via_addr, qualify_timeout, call_id, reg_server, prune_on_boot, path, endpoint, via_port, authenticate_qualify, uri, qualify_frequency, user_agent, expiration_time, outbound_proxy) VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '02;@37002688476b5f00454fe5bdf9d111cd'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 2 ('via_addr') = '192.168.200.250'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 3 ('qualify_timeout') = '3.000000'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 4 ('call_id') = '0DIGN36Vo04wsoZ7LhsuOw..'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 5 ('reg_server') = ''
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 6 ('prune_on_boot') = 'no'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 7 ('path') = ''
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 8 ('endpoint') = '02'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 9 ('via_port') = '61421'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 10 ('authenticate_qualify') = 'yes'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 11 ('uri') = 'sip:02@192.168.200.250:61421;rinstance=1d0720c42ceaaa2a'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 12 ('qualify_frequency') = '60'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 13 ('user_agent') = 'Z 5.3.7 rv2.9.30-mod'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 14 ('expiration_time') = '1581608282'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 15 ('outbound_proxy') = ''
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:37:02] DEBUG[28488] sorcery.c: Sorcery wizard 'config' does not support creation
[2020-02-13 10:37:02] VERBOSE[28488] res_pjsip_registrar.c: Added contact 'sip:02@192.168.200.250:61421;rinstance=1d0720c42ceaaa2a' to AOR '02' with expiration of 60 seconds
[2020-02-13 10:37:02] DEBUG[28489] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:37:02] DEBUG[28489] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_aors WHERE id = ?
[2020-02-13 10:37:02] DEBUG[28489] res_config_odbc.c: Parameter 1 ('id') = '02'
[2020-02-13 10:37:02] DEBUG[28489] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [7200] in [0, 4294967295] gives [7200](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [3600] in [0, 4294967295] gives [3600](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [60] in [0, 4294967295] gives [60](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [1] in [0, 4294967295] gives [1](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [60] in [0, 86400] gives [60](0)
[2020-02-13 10:37:02] DEBUG[28489] res_pjsip/pjsip_options.c: AOR '02' qualify options have been modified. Synchronize an AOR local state
[2020-02-13 10:37:02] DEBUG[28489] res_pjsip/pjsip_options.c: Configuring AOR '02' with current state of configuration and world
[2020-02-13 10:37:02] DEBUG[28489] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:37:02] DEBUG[28489] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_contacts WHERE id LIKE ? ORDER BY id
[2020-02-13 10:37:02] DEBUG[28489] res_config_odbc.c: Parameter 1 ('id LIKE') = '02;@%'
[2020-02-13 10:37:02] DEBUG[28489] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [60] in [0, 86400] gives [60](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract double from [3] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [61421] in [0, 4294967295] gives [61421](0)
[2020-02-13 10:37:02] DEBUG[28489] res_pjsip/pjsip_options.c: AOR '02' has transitioned from unqualified to qualified, reset available contacts to 0
[2020-02-13 10:37:02] DEBUG[28489] res_pjsip/pjsip_options.c: AOR '02' now has 0 available contacts
[2020-02-13 10:37:02] DEBUG[28489] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:37:02] DEBUG[28489] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id LIKE ? ORDER BY id
[2020-02-13 10:37:02] DEBUG[28489] res_config_odbc.c: Parameter 1 ('id LIKE') = '%'
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_aors WHERE id = ?
[2020-02-13 10:37:02] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '02'
[2020-02-13 10:37:02] DEBUG[28489] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:37:02] DEBUG[28489] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28489] res_pjsip/pjsip_options.c: Synchronizing endpoint '00' with AORs '00'
[2020-02-13 10:37:02] DEBUG[28489] res_pjsip/pjsip_options.c: Filtered AOR '00' on endpoint '00' as we are looking for '02'
[2020-02-13 10:37:02] DEBUG[28489] res_pjsip/pjsip_options.c: Endpoint '00' has no AORs feeding it, setting it to offline state as default
[2020-02-13 10:37:02] VERBOSE[28489] res_pjsip/pjsip_configuration.c: Endpoint 00 is now Unreachable
[2020-02-13 10:37:02] DEBUG[28473] devicestate.c: No provider found, checking channel drivers for PJSIP - 00
[2020-02-13 10:37:02] DEBUG[28489] res_pjsip/pjsip_options.c: Synchronizing endpoint '01' with AORs '01'
[2020-02-13 10:37:02] DEBUG[28489] res_pjsip/pjsip_options.c: Filtered AOR '01' on endpoint '01' as we are looking for '02'
[2020-02-13 10:37:02] DEBUG[28489] res_pjsip/pjsip_options.c: Endpoint '01' has no AORs feeding it, setting it to offline state as default
[2020-02-13 10:37:02] VERBOSE[28489] res_pjsip/pjsip_configuration.c: Endpoint 01 is now Unreachable
[2020-02-13 10:37:02] DEBUG[28473] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:37:02] DEBUG[28473] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:37:02] DEBUG[28473] res_config_odbc.c: Parameter 1 ('id') = '00'
[2020-02-13 10:37:02] DEBUG[28489] res_pjsip/pjsip_options.c: Synchronizing endpoint '02' with AORs '02'
[2020-02-13 10:37:02] DEBUG[28490] res_pjsip/pjsip_options.c: Adding endpoint compositor '02' to AOR '02'
[2020-02-13 10:37:02] DEBUG[28473] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:37:02] DEBUG[28473] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] devicestate.c: Changing state for PJSIP/00 - state 5 (Unavailable)
[2020-02-13 10:37:02] DEBUG[28473] devicestate.c: No provider found, checking channel drivers for PJSIP - 01
[2020-02-13 10:37:02] DEBUG[28473] res_odbc.c: Reusing ODBC handle 0x7fecbc001bf8 from class 'asterisk'
[2020-02-13 10:37:02] DEBUG[28473] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:37:02] DEBUG[28473] res_config_odbc.c: Parameter 1 ('id') = '01'
[2020-02-13 10:37:02] DEBUG[28473] res_odbc.c: Releasing ODBC handle 0x7fecbc001bf8 into pool
[2020-02-13 10:37:02] DEBUG[28473] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28473] devicestate.c: Changing state for PJSIP/01 - state 5 (Unavailable)
[2020-02-13 10:37:02] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [7200] in [0, 4294967295] gives [7200](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [3600] in [0, 4294967295] gives [3600](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [60] in [0, 4294967295] gives [60](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [1] in [0, 4294967295] gives [1](0)
[2020-02-13 10:37:02] DEBUG[28488] config.c: extract uint from [60] in [0, 86400] gives [60](0)
[2020-02-13 10:37:15] VERBOSE[28524] loader.c: Reloading module 'res_pjsip.so' (Basic SIP resource)
[2020-02-13 10:37:15] DEBUG[28488] res_sorcery_config.c: Config file 'pjsip.conf' was unchanged
[2020-02-13 10:37:15] DEBUG[28488] res_sorcery_config.c: Config file 'pjsip.conf' was unchanged
[2020-02-13 10:37:15] DEBUG[28488] res_sorcery_config.c: Config file 'pjsip.conf' was unchanged
[2020-02-13 10:37:15] DEBUG[28488] res_sorcery_config.c: Config file 'pjsip.conf' was unchanged
[2020-02-13 10:37:15] DEBUG[28488] res_sorcery_config.c: Config file 'pjsip.conf' was unchanged
[2020-02-13 10:37:15] DEBUG[28488] res_sorcery_config.c: Config file 'pjsip.conf' was unchanged
[2020-02-13 10:37:15] DEBUG[28488] res_sorcery_config.c: Config file 'pjsip.conf' was unchanged
[2020-02-13 10:37:15] DEBUG[28488] res_sorcery_config.c: Config file 'pjsip.conf' was unchanged
[2020-02-13 10:37:15] DEBUG[28488] res_sorcery_config.c: Config file 'pjsip.conf' was unchanged
[2020-02-13 10:37:15] NOTICE[28488] sorcery.c: Type 'system' is not reloadable, maintaining previous values
[2020-02-13 10:37:15] DEBUG[28488] res_sorcery_config.c: Config file 'pjsip.conf' was unchanged
[2020-02-13 10:37:15] DEBUG[28488] res_sorcery_config.c: Config file 'pjsip.conf' was unchanged
[2020-02-13 10:37:15] DEBUG[28488] res_sorcery_config.c: Config file 'pjsip.conf' was unchanged
[2020-02-13 10:37:15] DEBUG[28488] res_sorcery_config.c: Config file 'pjsip.conf' was unchanged
[2020-02-13 10:37:15] DEBUG[28488] res_sorcery_config.c: Config file 'pjsip.conf' was unchanged
[2020-02-13 10:37:15] DEBUG[28462] threadpool.c: Increasing threadpool sorcery/pool's size by 1
[2020-02-13 10:37:15] DEBUG[28462] threadpool.c: Increasing threadpool sorcery/pool's size by 1
[2020-02-13 10:37:15] DEBUG[28462] threadpool.c: Increasing threadpool sorcery/pool's size by 1
[2020-02-13 10:37:15] DEBUG[28462] threadpool.c: Increasing threadpool sorcery/pool's size by 1
[2020-02-13 10:37:15] DEBUG[28493] res_pjsip_outbound_registration.c: Auths updated. Checking for any outbound registrations that are in permanent rejected state so they can be retried
[2020-02-13 10:37:15] DEBUG[28489] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:37:15] DEBUG[28489] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_aors WHERE id LIKE ? ORDER BY id
[2020-02-13 10:37:15] DEBUG[28489] res_config_odbc.c: Parameter 1 ('id LIKE') = '%'
[2020-02-13 10:37:15] DEBUG[28489] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [7200] in [0, 4294967295] gives [7200](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [3600] in [0, 4294967295] gives [3600](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [60] in [0, 4294967295] gives [60](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [1] in [0, 4294967295] gives [1](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [60] in [0, 86400] gives [60](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [7200] in [0, 4294967295] gives [7200](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [3600] in [0, 4294967295] gives [3600](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [60] in [0, 4294967295] gives [60](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [1] in [0, 4294967295] gives [1](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [60] in [0, 86400] gives [60](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [7200] in [0, 4294967295] gives [7200](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [3600] in [0, 4294967295] gives [3600](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [60] in [0, 4294967295] gives [60](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [1] in [0, 4294967295] gives [1](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [60] in [0, 86400] gives [60](0)
[2020-02-13 10:37:15] DEBUG[28490] res_pjsip/pjsip_options.c: Synchronizing AOR '00' with current state of configuration and world
[2020-02-13 10:37:15] DEBUG[28490] res_pjsip/pjsip_options.c: Configuring AOR '00' with current state of configuration and world
[2020-02-13 10:37:15] DEBUG[28490] res_pjsip/pjsip_options.c: AOR '00' now has 1 available contacts
[2020-02-13 10:37:15] DEBUG[28490] res_pjsip/pjsip_options.c: Synchronizing AOR '01' with current state of configuration and world
[2020-02-13 10:37:15] DEBUG[28490] res_pjsip/pjsip_options.c: Configuring AOR '01' with current state of configuration and world
[2020-02-13 10:37:15] DEBUG[28490] res_pjsip/pjsip_options.c: AOR '01' now has 1 available contacts
[2020-02-13 10:37:15] DEBUG[28490] res_pjsip/pjsip_options.c: Synchronizing AOR '02' with current state of configuration and world
[2020-02-13 10:37:15] DEBUG[28490] res_pjsip/pjsip_options.c: Configuring AOR '02' with current state of configuration and world
[2020-02-13 10:37:15] DEBUG[28490] res_pjsip/pjsip_options.c: AOR '02' now has 0 available contacts
[2020-02-13 10:37:15] DEBUG[28489] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:37:15] DEBUG[28489] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id LIKE ? ORDER BY id
[2020-02-13 10:37:15] DEBUG[28489] res_config_odbc.c: Parameter 1 ('id LIKE') = '%'
[2020-02-13 10:37:15] DEBUG[28489] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:37:15] DEBUG[28489] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28489] res_pjsip/pjsip_options.c: Synchronizing endpoint '00' with AORs '00'
[2020-02-13 10:37:15] DEBUG[28490] res_pjsip/pjsip_options.c: Adding endpoint compositor '00' to AOR '00'
[2020-02-13 10:37:15] VERBOSE[28489] res_pjsip/pjsip_configuration.c: Endpoint 00 is now Reachable
[2020-02-13 10:37:15] DEBUG[28473] devicestate.c: No provider found, checking channel drivers for PJSIP - 00
[2020-02-13 10:37:15] DEBUG[28473] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:37:15] DEBUG[28473] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:37:15] DEBUG[28473] res_config_odbc.c: Parameter 1 ('id') = '00'
[2020-02-13 10:37:15] DEBUG[28489] res_pjsip/pjsip_options.c: Synchronizing endpoint '01' with AORs '01'
[2020-02-13 10:37:15] DEBUG[28490] res_pjsip/pjsip_options.c: Adding endpoint compositor '01' to AOR '01'
[2020-02-13 10:37:15] DEBUG[28473] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:37:15] DEBUG[28473] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] devicestate.c: Changing state for PJSIP/00 - state 1 (Not in use)
[2020-02-13 10:37:15] VERBOSE[28489] res_pjsip/pjsip_configuration.c: Endpoint 01 is now Reachable
[2020-02-13 10:37:15] DEBUG[28473] devicestate.c: No provider found, checking channel drivers for PJSIP - 01
[2020-02-13 10:37:15] DEBUG[28489] res_pjsip/pjsip_options.c: Synchronizing endpoint '02' with AORs '02'
[2020-02-13 10:37:15] DEBUG[28490] res_pjsip/pjsip_options.c: Adding endpoint compositor '02' to AOR '02'
[2020-02-13 10:37:15] DEBUG[28473] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:37:15] DEBUG[28473] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:37:15] DEBUG[28473] res_config_odbc.c: Parameter 1 ('id') = '01'
[2020-02-13 10:37:15] DEBUG[28473] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:37:15] DEBUG[28473] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:15] DEBUG[28473] devicestate.c: Changing state for PJSIP/01 - state 1 (Not in use)
[2020-02-13 10:37:18] DEBUG[28488] res_pjsip/pjsip_options.c: Qualifying all contacts on AOR '02'
[2020-02-13 10:37:18] DEBUG[28488] res_pjsip/pjsip_options.c: Qualifying contact '02;@37002688476b5f00454fe5bdf9d111cd' on AOR '02'
[2020-02-13 10:37:18] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:37:18] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:37:18] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '02'
[2020-02-13 10:37:18] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:37:18] DEBUG[28488] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28488] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:37:18] DEBUG[28488] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_aors WHERE id = ?
[2020-02-13 10:37:18] DEBUG[28488] res_config_odbc.c: Parameter 1 ('id') = '02'
[2020-02-13 10:37:18] DEBUG[28488] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0)
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [7200] in [0, 4294967295] gives [7200](0)
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [3600] in [0, 4294967295] gives [3600](0)
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [60] in [0, 4294967295] gives [60](0)
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [1] in [0, 4294967295] gives [1](0)
[2020-02-13 10:37:18] DEBUG[28488] config.c: extract uint from [60] in [0, 86400] gives [60](0)
[2020-02-13 10:37:18] DEBUG[28488] res_pjsip.c: 0x7fecb8006ae8: Wrapper created
[2020-02-13 10:37:18] DEBUG[28488] res_pjsip.c: 0x7fecb8006ae8: Set timer to 3000 msec
[2020-02-13 10:37:18] DEBUG[28488] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 192.168.200.203:5060 (this may be re-written again later)
[2020-02-13 10:37:18] DEBUG[28487] res_pjsip/pjsip_distributor.c: No dialog serializer for Response msg 200/OPTIONS/cseq=3698 (rdata0x7feca8000f58).  Using request transaction as basis.
[2020-02-13 10:37:18] DEBUG[28487] res_pjsip/pjsip_distributor.c: Found transaction tsx0x7fecb8029618 for Response msg 200/OPTIONS/cseq=3698 (rdata0x7feca8000f58).
[2020-02-13 10:37:18] DEBUG[28487] res_pjsip/pjsip_distributor.c: Found serializer pjsip/options/02-00000057 on transaction tsx0x7fecb8029618
[2020-02-13 10:37:18] DEBUG[28488] res_pjsip.c: 0x7fecb8006ae8: PJSIP tsx response received
[2020-02-13 10:37:18] DEBUG[28488] res_pjsip.c: 0x7fecb8006ae8: Cancelling timer
[2020-02-13 10:37:18] DEBUG[28488] res_pjsip.c: 0x7fecb8006ae8: Timer cancelled
[2020-02-13 10:37:18] DEBUG[28488] res_pjsip.c: 0x7fecb8006ae8: Callbacks executed
[2020-02-13 10:37:18] DEBUG[28488] res_pjsip.c: 0x7fecb8006ae8: wrapper destroyed
[2020-02-13 10:37:18] DEBUG[28488] res_pjsip/pjsip_options.c: Endpoint state compositor '02' is online as AOR '02' is available
[2020-02-13 10:37:18] VERBOSE[28488] res_pjsip/pjsip_configuration.c: Endpoint 02 is now Reachable
[2020-02-13 10:37:18] DEBUG[28473] devicestate.c: No provider found, checking channel drivers for PJSIP - 02
[2020-02-13 10:37:18] VERBOSE[28488] res_pjsip/pjsip_options.c: Contact 02/sip:02@192.168.200.250:61421;rinstance=1d0720c42ceaaa2a is now Reachable.  RTT: 18.657 msec
[2020-02-13 10:37:18] DEBUG[28473] res_odbc.c: Reusing ODBC handle 0x22b9c58 from class 'asterisk'
[2020-02-13 10:37:18] DEBUG[28473] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2020-02-13 10:37:18] DEBUG[28473] res_config_odbc.c: Parameter 1 ('id') = '02'
[2020-02-13 10:37:18] DEBUG[28488] res_pjsip/pjsip_options.c: AOR '02' now has 1 available contacts
[2020-02-13 10:37:18] DEBUG[28473] res_odbc.c: Releasing ODBC handle 0x22b9c58 into pool
[2020-02-13 10:37:18] DEBUG[28473] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval
[2020-02-13 10:37:18] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28473] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2020-02-13 10:37:18] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28473] config.c: extract uint from [90] in [0, 4294967295] gives [90](0)
[2020-02-13 10:37:18] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28473] config.c: extract uint from [0] in [0, 4294967295] gives [0](0)
[2020-02-13 10:37:18] DEBUG[28473] devicestate.c: Changing state for PJSIP/02 - state 1 (Not in use)
[2020-02-13 10:37:18] DEBUG[28473] stasis.c: Topic 'devicestate:all/PJSIP/02': 0x7fecb802bce8 created