terracecomm (Shawn)

public text v1 · immutable
#250517
·published 2006-11-17 01:15 UTC
Nov 16 18:11:41 VERBOSE[3080054464] logger.c: OpenPBX Event Logger Started /opt/openpbx.org/log/event_log
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/dnsmgr.conf': Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/dnsmgr.conf': Found
Nov 16 18:11:41 VERBOSE[3080054464] logger.c: OpenPBX Dynamic Loader loading preload modules:
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/modules.conf': Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/modules.conf': Found
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/cdr.conf': Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/cdr.conf': Found
Nov 16 18:11:41 NOTICE[3080054464] cdr.c: CDR simple logging enabled.
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Manager registered action Ping
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Manager registered action Events
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Manager registered action Logoff
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Manager registered action Hangup
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Manager registered action Status
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Manager registered action Setvar
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Manager registered action Getvar
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Manager registered action Redirect
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Manager registered action Originate
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Manager registered action Command
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Manager registered action ExtensionState
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Manager registered action AbsoluteTimeout
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Manager registered action MailboxStatus
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Manager registered action MailboxCount
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Manager registered action ListCommands
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/manager.conf': Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/manager.conf': Found
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/rtp.conf': Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/rtp.conf': Found
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == RTP Allocating from port range 10000 -> 20000
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/udptl.conf': Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/udptl.conf': Found
Nov 16 18:11:41 VERBOSE[3080054464] logger.c: OpenPBX Core Initializing
Nov 16 18:11:41 VERBOSE[3080054464] logger.c: Registering builtin applications:
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [AbsoluteTimeout]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'AbsoluteTimeout'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [Answer]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'Answer'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [Background]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'Background'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [Busy]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'Busy'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [Congestion]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'Congestion'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [DigitTimeout]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'DigitTimeout'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [Goto]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'Goto'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [GotoIf]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'GotoIf'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [GotoIfTime]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'GotoIfTime'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [ExecIfTime]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'ExecIfTime'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [Hangup]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'Hangup'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [NoOp]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'NoOp'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [Prefix]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'Prefix'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [Progress]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'Progress'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [ResetCDR]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'ResetCDR'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [ResponseTimeout]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'ResponseTimeout'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [Ringing]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'Ringing'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [SayNumber]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'SayNumber'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [SayDigits]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'SayDigits'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [SayAlpha]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'SayAlpha'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [SayPhonetic]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'SayPhonetic'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [SetAccount]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'SetAccount'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [SetAMAFlags]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'SetAMAFlags'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [SetGlobalVar]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'SetGlobalVar'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [SetLanguage]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'SetLanguage'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [Set]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'Set'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [SetVar]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'SetVar'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [ImportVar]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'ImportVar'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [StripMSD]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'StripMSD'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [Suffix]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'Suffix'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [Wait]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'Wait'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [WaitExten]
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'WaitExten'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c: OpenPBX Dynamic Loader Starting:
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/modules.conf': Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/modules.conf': Found
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [res_musiconhold.so]Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [res_musiconhold.so] => (Music On Hold Resource)
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'MusicOnHold'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'WaitMusicOnHold'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'SetMusicOnHold'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'StartMusicOnHold'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Registered application 'StopMusicOnHold'
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/musiconhold.conf': Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/musiconhold.conf': Found
Nov 16 18:11:41 WARNING[3080054464] res_musiconhold.c: No music on hold classes configured, disabling music on hold.
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [res_crypto.so]Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [res_crypto.so] => (Cryptographic Digital Signatures)
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [res_indications.so]Nov 16 18:11:41 VERBOSE[3080054464] logger.c:  [res_indications.so] => (Indications Configuration)
Nov 16 18:11:41 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/indications.conf': Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/indications.conf': Found
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'at'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'au'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'br'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'be'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'ch'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'cl'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'cn'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'cz'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'de'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'dk'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'ee'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'es'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'fi'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'fr'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'gr'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'hu'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'it'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'lt'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'mx'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'nl'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'no'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'nz'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'pl'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'pt'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'ru'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'se'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'sg'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'uk'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'us'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'us-o'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'tw'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 've'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered indication country 'za'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Setting default indication country to 'us'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'PlayTones'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'StopPlayTones'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [res_ogi.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [res_ogi.so] => (OpenPBX Gateway Interface (OGI))
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'DeadOGI'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'EOGI'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'OGI'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [res_monitor.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [res_monitor.so] => (Call Monitoring Resource)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Monitor'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'StopMonitor'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'ChangeMonitor'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action Monitor
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action StopMonitor
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action ChangeMonitor
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [res_features.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [res_features.so] => (Call Features Resource)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/features.conf': Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/features.conf': Found
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'parkedcalls' (0x1f3fa4c)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '700' priority 1 to parkedcalls
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'ParkedCall'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Park'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action ParkedCalls
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [res_adsi.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [res_adsi.so] => (ADSI Resource)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/adsi.conf': Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/adsi.conf': Found
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [res_noise.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [res_noise.so] => (Noise Generation)
Nov 16 18:11:42 DEBUG[3080054464] res_noise.c: Using 65536 pregenerated signed linear noise samples
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'WhiteNoise'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [chan_iax2.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [chan_iax2.so] => (Inter Asterisk eXchange (Ver 2))
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function IAXPEER
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action IAXpeers
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action IAXnetstats
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/iax.conf': Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/iax.conf': Found
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- doing lookup for '216.207.245.47'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered channel type 'IAX2' (Inter Asterisk eXchange Driver (Ver 2))
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Using TOS bits 16
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Binding IAX2 to address 0.0.0.0:4569
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == IAX Ready and Listening
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [chan_agent.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [chan_agent.so] => (Agent Proxy Channel)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered channel type 'Agent' (Call Agent Proxy Channel)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'AgentLogin'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'AgentCallbackLogin'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'AgentMonitorOutgoing'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action Agents
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action AgentLogoff
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action AgentCallbackLogin
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/agents.conf': Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/agents.conf': Found
Nov 16 18:11:42 DEBUG[3080054464] db.c: SQL [select keys,value from odb where family='/Agents']
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [chan_features.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [chan_features.so] => (Feature Proxy Channel)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered channel type 'Feature' (Feature Proxy Channel Driver)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [chan_local.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [chan_local.so] => (Local Proxy Channel)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered channel type 'Local' (Local Proxy Channel Driver)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [chan_mgcp.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [chan_mgcp.so] => (Media Gateway Control Protocol (MGCP))
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/mgcp.conf': Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/mgcp.conf': Found
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == MGCP Listening on 0.0.0.0:2727
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Using TOS bits 0
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered channel type 'MGCP' (Media Gateway Control Protocol (MGCP))
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [chan_sip.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [chan_sip.so] => (Session Initiation Protocol (SIP))
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/sip.conf': Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/sip.conf': Found
Nov 16 18:11:42 DEBUG[3080054464] db.c: SQL [select value from odb where family='SIP/Registry' and keys='7805771645']
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- SIP Seeding peer from opbxdb: '7805771645' at 7805771645@192.168.60.87:5060 for 300
Nov 16 18:11:42 DEBUG[3080054464] timer.c: Timer 0x8072210 set to 2.624000000 repeat 0.0
Nov 16 18:11:42 DEBUG[3080054464] timer.c: Timer 0x8072210 set to 2.624000000 repeat 0.0
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == SIP Listening on 0.0.0.0:5060
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Using TOS bits 0
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/sip_notify.conf': Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/sip_notify.conf': Found
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered channel type 'SIP' (Session Initiation Protocol (SIP))
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'SipDTMFMode'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'SipAddHeader'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'SipGetHeader'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function SIP_HEADER
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function SIPPEER
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function SIPCHANINFO
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function CHECKSIPDOMAIN
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action SIPpeers
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action SIPshowpeer
Nov 16 18:11:42 DEBUG[3080054464] timer.c: Timer 0x8072210 set to 309.880000000 repeat 0.0
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [skipping chan_woomera.so]
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [pbx_spool.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [pbx_spool.so] => (Outgoing Spool Support)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [pbx_loopback.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [pbx_loopback.so] => (Loopback Switch)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [pbx_config.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [pbx_config.so] => (Text Extension Configuration)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/extensions.conf': Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/extensions.conf': Found
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Setting global variable 'CONSOLE' to 'Console/dsp'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Setting global variable 'IAXINFO' to 'guest'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Setting global variable 'TRUNK' to 'Zap/g2'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Setting global variable 'TRUNKMSD' to '1'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'dundi-e164-canonical' (0x6be33a1c)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'dundi-e164-customers' (0x460e70fd)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'dundi-e164-via-pstn' (0x49c4d1d4)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'dundi-e164-local' (0x719b8f13)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'dundi-e164-canonical' in context 'dundi-e164-local'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'dundi-e164-customers' in context 'dundi-e164-local'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'dundi-e164-via-pstn' in context 'dundi-e164-local'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'dundi-e164-switch' (0x5f6fdfac)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including switch 'DUNDi/e164' in context 'dundi-e164-switch'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'dundi-e164-lookup' (0x37a29c52)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'dundi-e164-local' in context 'dundi-e164-lookup'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'dundi-e164-switch' in context 'dundi-e164-lookup'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'macro-dundi-e164' (0x75f82996)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 1 to macro-dundi-e164
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'dundi-e164-lookup' in context 'macro-dundi-e164'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'iaxtel700' (0x57e6117c)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '_91700XXXXXXX' priority 1 to iaxtel700
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'iaxprovider' (0x2499a811)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'trunkint' (0x3b77bb7b)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '_9011.' priority 1 to trunkint
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '_9011.' priority 2 to trunkint
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'trunkld' (0x294913ec)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '_91NXXNXXXXXX' priority 1 to trunkld
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '_91NXXNXXXXXX' priority 2 to trunkld
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'trunklocal' (0x3429ac77)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '_9NXXXXXX' priority 1 to trunklocal
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'trunktollfree' (0x5c26873b)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '_91800NXXXXXX' priority 1 to trunktollfree
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '_91888NXXXXXX' priority 1 to trunktollfree
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '_91877NXXXXXX' priority 1 to trunktollfree
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '_91866NXXXXXX' priority 1 to trunktollfree
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'international' (0x7e895cae)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'longdistance' in context 'international'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'trunkint' in context 'international'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'longdistance' (0x45b8c4d1)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'local' in context 'longdistance'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'trunkld' in context 'longdistance'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'local' (0x54dae6b)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'default' in context 'local'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'parkedcalls' in context 'local'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'trunklocal' in context 'local'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'iaxtel700' in context 'local'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'trunktollfree' in context 'local'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'iaxprovider' in context 'local'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'macro-stdexten' (0x4cb03e88)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 1 to macro-stdexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 2 to macro-stdexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's-NOANSWER' priority 1 to macro-stdexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's-NOANSWER' priority 2 to macro-stdexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's-BUSY' priority 1 to macro-stdexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's-BUSY' priority 2 to macro-stdexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '_s-.' priority 1 to macro-stdexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 'a' priority 1 to macro-stdexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'macro-stdPrivacyexten' (0x178bbac4)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 1 to macro-stdPrivacyexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 2 to macro-stdPrivacyexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's-NOANSWER' priority 1 to macro-stdPrivacyexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's-NOANSWER' priority 2 to macro-stdPrivacyexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's-BUSY' priority 1 to macro-stdPrivacyexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's-BUSY' priority 2 to macro-stdPrivacyexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's-DONTCALL' priority 1 to macro-stdPrivacyexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's-TORTURE' priority 1 to macro-stdPrivacyexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '_s-.' priority 1 to macro-stdPrivacyexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 'a' priority 1 to macro-stdPrivacyexten
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'demo' (0x5ed2c3c3)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 1 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 2 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 3 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 4 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 5 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 6 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 7 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '2' priority 1 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '2' priority 2 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '3' priority 1 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '3' priority 2 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '1000' priority 1 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '1234' priority 1 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '1234' priority 2 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '1235' priority 1 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '1236' priority 1 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '1236' priority 2 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '#' priority 1 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '#' priority 2 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 't' priority 1 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 'i' priority 1 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '500' priority 1 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '500' priority 2 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '500' priority 3 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '500' priority 4 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '600' priority 1 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '600' priority 2 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '600' priority 3 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '600' priority 4 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '8500' priority 1 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '8500' priority 2 to demo
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'residential.terracecomm.com' (0x4f807147)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension '7809824561' priority 1 to residential.terracecomm.com
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'faxin' (0x6cc35182)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 1 to faxin
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 2 to faxin
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 3 to faxin
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 4 to faxin
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 5 to faxin
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 6 to faxin
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Added extension 's' priority 7 to faxin
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Registered extension context 'default' (0x5393ac01)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:     -- Including context 'demo' in context 'default'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [pbx_dundi.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [pbx_dundi.so] => (Distributed Universal Number Discovery (DUNDi))
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/dundi.conf': Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/dundi.conf': Found
Nov 16 18:11:42 DEBUG[3080054464] pbx_dundi.c: Seeding global EID '00:10:4b:0c:00:26' from 'eth0'
Nov 16 18:11:42 DEBUG[3080054464] db.c: SQL [select value from odb where family='dundi' and keys='secretexpiry']
Nov 16 18:11:42 DEBUG[3080054464] db.c: SQL [select value from odb where family='dundi' and keys='secret']
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Using TOS bits 0
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == DUNDi Ready and Listening on 0.0.0.0 port 4520
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'DUNDiLookup'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function DUNDILOOKUP
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [pbx_realtime.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [pbx_realtime.so] => (Realtime Switch)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_callerid.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_callerid.so] => (Caller ID related dialplan function)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function CALLERID
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_cdr.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_cdr.so] => (CDR related dialplan function)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function CDR
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_db.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_db.so] => (database functions)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function DB_EXISTS
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function DB
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_enum.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_enum.so] => (ENUMLOOKUP allows for general or specific querying of NAPTR records or )
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function ENUMLOOKUP
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function TXTCIDNAME
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_env.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_env.so] => (Get or set environment variables.)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function ENV
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_fileexists.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_fileexists.so] => (file existence dialplan function)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function FILEEXISTS
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_groupcount.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_groupcount.so] => (database functions)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function GROUP_COUNT
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function GROUP_MATCH_COUNT
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function GROUP
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function GROUP_LIST
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_language.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_language.so] => (language functions)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function LANGUAGE
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_logic.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_logic.so] => (logic functions)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function ISNULL
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function SET
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function EXISTS
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function IF
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function IFTIME
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_math.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_math.so] => (math functions)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function MATH
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_md5.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_md5.so] => (math functions)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function MD5
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function CHECK_MD5
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_moh.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_moh.so] => (MOH functions)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function MUSICCLASS
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_strings.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_strings.so] => (string functions)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function FIELDQTY
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function REGEX
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function LEN
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function STRFTIME
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function EVAL
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function CUT
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function SORT
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_timeout.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_timeout.so] => (string functions)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function TIMEOUT
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_uri.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [func_uri.so] => (URI encode/decode functions)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function URIDECODE
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function URIENCODE
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [cdr_csv.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [cdr_csv.so] => (Comma Separated Values CDR Backend)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [cdr_custom.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [cdr_custom.so] => (Customizable Comma Separated Values CDR Backend)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/cdr_custom.conf': Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/cdr_custom.conf': Found
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [cdr_sqlite3.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [cdr_sqlite3.so] => (SQLite CDR Backend)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_conference.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_conference.so] => (Channel Independent Conference Application)
Nov 16 18:11:42 NOTICE[3080054464] app_conference.c: loading app_conference module [ $Revision: 1.22 $ ]
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Conference'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_adsiprog.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_adsiprog.so] => (OpenPBX ADSI Programming Application)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'ADSIProg'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_pipe.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_pipe.so] => (Pipe Raw Audio to and from an External Process)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'PIPE'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_authenticate.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_authenticate.so] => (Authentication Application)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Authenticate'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_cdr.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_cdr.so] => (Make sure openpbx doesn't save CDR for a certain call)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'NoCDR'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_changrab.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_changrab.so] => (Take over an existing channel and bridge to it.)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'ChanGrab'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_chanisavail.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_chanisavail.so] => (Check if channel is available)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'ChanIsAvail'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_chanspy.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_chanspy.so] => (Tap into any type of openpbx channel and listen to audio)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'ChanSpy'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_controlplayback.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_controlplayback.so] => (Control Playback Application)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'ControlPlayback'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_db.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_db.so] => (Database access functions for OpenPBX extension logic)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'DBget'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'DBput'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'DBdel'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'DBdelTree'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_devstate.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_devstate.so] => (Application for sending device state messages)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered channel type 'DS' (Application for sending device state messages)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action Devstate
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'DevState'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_dial.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_dial.so] => (Dialing Application)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Dial'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'RetryDial'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_dictate.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_dictate.so] => (Virtual Dictation Machine)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Dictate'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_directed_pickup.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_directed_pickup.so] => (Directed Call Pickup Application)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Pickup'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_directory.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_directory.so] => (Extension Directory)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Directory'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_disa.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_disa.so] => (DISA (Direct Inward System Access) Application)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'DISA'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_dumpchan.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_dumpchan.so] => (Dump Info About The Calling Channel)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'DumpChan'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_echo.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_echo.so] => (Simple Echo Application)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Echo'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_enumlookup.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_enumlookup.so] => (ENUM Lookup)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'EnumLookup'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/enum.conf': Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/enum.conf': Found
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_eval.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_eval.so] => (Reevaluates strings)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Eval'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_exec.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_exec.so] => (Executes applications)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Exec'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_forkcdr.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_forkcdr.so] => (Fork The CDR into 2 separate entities.)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'ForkCDR'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_getcpeid.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_getcpeid.so] => (Get ADSI CPE ID)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'GetCPEID'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_groupcount.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_groupcount.so] => (Group Management Routines)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'GetGroupCount'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'SetGroup'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'CheckGroup'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'GetGroupMatchCount'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_hasnewvoicemail.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_hasnewvoicemail.so] => (Indicator for whether a voice mailbox has messages in a given folder.[)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function VMCOUNT
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'HasVoicemail'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'HasNewVoicemail'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_lookupblacklist.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_lookupblacklist.so] => (Look up Caller*ID name/number from blacklist database)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'LookupBlacklist'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_lookupcidname.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_lookupcidname.so] => (Look up CallerID Name from local database)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'LookupCIDName'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_macro.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_macro.so] => (Extension Macros)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'MacroExit'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'MacroIf'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Macro'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_proc.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_proc.so] => (Extension Procs)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'ProcExit'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'ProcIf'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Proc'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_milliwatt.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_milliwatt.so] => (Digital Milliwatt (mu-law) Test Application)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Milliwatt'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_muxmon.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_muxmon.so] => (Native Channel Monitoring Module)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'MuxMon'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_osplookup.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_osplookup.so] => (OSP Lookup)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'OSPLookup'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'OSPNext'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'OSPFinish'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_playback.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_playback.so] => (Sound File Playback Application)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Playback'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_privacy.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_privacy.so] => (Require phone number to be entered, if no CallerID sent)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'PrivacyManager'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_queue.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_queue.so] => (True Call Queueing)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Queue'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action Queues
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action QueueStatus
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action QueueAdd
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action QueueRemove
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action QueuePause
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'AddQueueMember'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'RemoveQueueMember'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'PauseQueueMember'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'UnpauseQueueMember'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered custom function QUEUEAGENTCOUNT
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/queues.conf': Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/queues.conf': Found
Nov 16 18:11:42 DEBUG[3080054464] db.c: SQL [select keys,value from odb where family='/Queue/PersistentMembers']
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_random.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_random.so] => (Random goto)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Random'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_read.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_read.so] => (Read Variable Application)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Read'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_record.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_record.so] => (Trivial Record Application)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Record'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_rxfax.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_rxfax.so] => (Trivial FAX Receive Application)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'RxFAX'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_sayunixtime.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_sayunixtime.so] => (Say time)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'SayUnixTime'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'DateTime'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_senddtmf.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_senddtmf.so] => (Send DTMF digits Application)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'SendDTMF'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_sendtext.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_sendtext.so] => (Send Text Applications)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'SendText'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_setcdruserfield.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_setcdruserfield.so] => (CDR user field apps)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'SetCDRUserField'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'AppendCDRUserField'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Manager registered action SetCDRUserField
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_setrdnis.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_setrdnis.so] => (Set RDNIS Number)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'SetRDNIS'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_settransfercapability.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_settransfercapability.so] => (Set ISDN Transfer Capability)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'SetTransferCapability'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_softhangup.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_softhangup.so] => (Hangs up the requested channel)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'SoftHangup'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_stack.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_stack.so] => (Stack Routines)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'StackPop'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Return'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'GosubIf'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Gosub'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_system.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_system.so] => (Generic System() application)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'TrySystem'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'System'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_backgrounddetect.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_backgrounddetect.so] => (Playback with Talk and Fax Detection)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'BackgroundDetect'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_transfer.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_transfer.so] => (Transfer)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Transfer'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_txfax.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_txfax.so] => (Trivial FAX Transmit Application)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'TxFAX'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_userevent.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_userevent.so] => (Custom User Event Application)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'UserEvent'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_verbose.so]Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_verbose.so] => (Send verbose output)
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:   == Registered application 'Verbose'
Nov 16 18:11:42 VERBOSE[3080054464] logger.c:  [app_voicemail.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_voicemail.so] => (Comedian Mail (Voicemail System))
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered application 'VoiceMail'
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered application 'VoiceMailMain'
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered application 'MailboxExists'
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered application 'VMAuthenticate'
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/voicemail.conf': Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/voicemail.conf': Found
Nov 16 18:11:43 DEBUG[3080054464] app_voicemail.c: VM Review Option disabled globally
Nov 16 18:11:43 DEBUG[3080054464] app_voicemail.c: VM Operator break disabled globally
Nov 16 18:11:43 DEBUG[3080054464] app_voicemail.c: VM CID Info before msg disabled globally
Nov 16 18:11:43 DEBUG[3080054464] app_voicemail.c: ENVELOPE before msg enabled globally
Nov 16 18:11:43 DEBUG[3080054464] app_voicemail.c: Duration info before msg enabled globally
Nov 16 18:11:43 DEBUG[3080054464] app_voicemail.c: We are not going to skip to the next msg after save/delete
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_waitforsilence.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_waitforsilence.so] => (Wait For Silence)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered application 'WaitForSilence'
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_waitforring.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_waitforring.so] => (Waits until first ring after time)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered application 'WaitForRing'
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_while.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_while.so] => (While Loops and Conditional Execution)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered application 'While'
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered application 'ExecIf'
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered application 'EndWhile'
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_zapateller.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_zapateller.so] => (Block Telemarketers with Special Information Tone)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered application 'Zapateller'
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_getdevstate.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_getdevstate.so] => (Gets device state (show hints))
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered application 'GetDevState'
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_getextstate.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_getextstate.so] => (Get state for given extension in a context (show hints))
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered application 'GetExtState'
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_sms.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_sms.so] => (Communicates with SMS service centres and SMS capable analogue phones[)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered application 'SMS'
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_v110.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_v110.so] => (v.110 dialin Application)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered application 'V110'
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_faxdetect.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [app_faxdetect.so] => (Fax detection application)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered application 'FaxDetect'
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_lpc10.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_lpc10.so] => (LPC10e/PCM16 (signed linear) Codec Translator)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/codecs.conf': Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/codecs.conf': Found
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- codec_lpc10: using generic PLC
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'lpc10tolin' from format lpc10 to slin, cost 2
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'lintolpc10' from format slin to lpc10, cost 2
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_dvi_adpcm.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_dvi_adpcm.so] => (DVI/IMA/Intel 32kbps ADPCM encoder/decoder)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/codecs.conf': Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/codecs.conf': Found
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- codec_adpcm: using generic PLC
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'dviadpcmtolin' from format adpcm to slin, cost 1
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'lintodviadpcm' from format slin to adpcm, cost 1
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_oki_adpcm.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_oki_adpcm.so] => (Oki 32kbps ADPCM encoder/decoder)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/codecs.conf': Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/codecs.conf': Found
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- codec_adpcm: using generic PLC
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'okiadpcmtolin' from format unknown to slin, cost 1
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'lintookiadpcm' from format slin to unknown, cost 1
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_ulaw.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_ulaw.so] => (Mu-law Coder/Decoder)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/codecs.conf': Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/codecs.conf': Found
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- codec_ulaw: using generic PLC
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'ulawtolin' from format ulaw to slin, cost 1
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'lintoulaw' from format slin to ulaw, cost 1
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_alaw.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_alaw.so] => (A-law Coder/Decoder)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/codecs.conf': Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/codecs.conf': Found
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- codec_alaw: using generic PLC
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'alawtolin' from format alaw to slin, cost 1
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'lintoalaw' from format slin to alaw, cost 1
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_a_mu.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_a_mu.so] => (A-law and Mulaw direct Coder/Decoder)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'alawtoulaw' from format alaw to ulaw, cost 1
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'ulawtoalaw' from format ulaw to alaw, cost 1
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_g726.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_g726.so] => (ITU G.726-32kbps G726 Transcoder)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/codecs.conf': Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/codecs.conf': Found
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- codec_g726: using generic PLC
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'g726tolin' from format g726 to slin, cost 3
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'lintog726' from format slin to g726, cost 3
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_gsm.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_gsm.so] => (GSM06.10/PCM16 (signed linear) Codec Translator)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/codecs.conf': Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/codecs.conf': Found
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- codec_gsm: using generic PLC
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'gsmtolin' from format gsm to slin, cost 1
Nov 16 18:11:43 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 0.999000000 repeat 0.0
Nov 16 18:11:43 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 0.999000000 repeat 0.0
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'lintogsm' from format slin to gsm, cost 2
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_speex.so]Nov 16 18:11:43 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 14.989000000 repeat 0.0
Nov 16 18:11:43 DEBUG[3059674032] chan_sip.c: Stopping retransmission on '333068cd043325d47317fe2626d0e7fb@192.168.60.78' of Request 102: Match Found
Nov 16 18:11:43 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 308.714000000 repeat 0.0
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [codec_speex.so] => (Speex/PCM16 (signed linear) Codec Translator)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/codecs.conf': Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/codecs.conf': Found
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- CODEC SPEEX: Setting Quality to 3
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- CODEC SPEEX: Setting Complexity to 2
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- CODEC SPEEX: Perceptual Enhancement Mode. [on]
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- CODEC SPEEX: VAD Mode. [on]
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- CODEC SPEEX: VBR Mode. [on]
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- CODEC SPEEX: Disabling ABR
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- CODEC SPEEX: Setting VBR Quality to 4.000000
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- CODEC SPEEX: DTX Mode. [off]
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- CODEC SPEEX: Preprocessing. [off]
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- CODEC SPEEX: Preprocessor VAD. [off]
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- CODEC SPEEX: Preprocessor AGC. [off]
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- CODEC SPEEX: Setting preprocessor AGC Level to 8000.000000
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- CODEC SPEEX: Preprocessor Denoise. [off]
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- CODEC SPEEX: Preprocessor Dereverb. [off]
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- CODEC SPEEX: Setting preprocessor Dereverb Decay to 0.400000
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:     -- CODEC SPEEX: Setting preprocessor Dereverb Level to 0.300000
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'speextolin' from format speex to slin, cost 3
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered translator 'lintospeex' from format slin to speex, cost 91
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_au.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_au.so] => (Sun Microsystems AU format (signed linear))
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered file format au, extension(s) au
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_g723_1.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_g723_1.so] => (G.723.1 Simple Timestamp File Format)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered file format g723.1, extension(s) g723.1|g723
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_g726.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_g726.so] => (Raw G.726 (16/24/32/40kbps) data)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered file format g726-40, extension(s) g726-40
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered file format g726-32, extension(s) g726-32
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered file format g726-24, extension(s) g726-24
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered file format g726-16, extension(s) g726-16
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_g729.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_g729.so] => (Raw G729 data)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered file format g729, extension(s) g729
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_gsm.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_gsm.so] => (Raw GSM data)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered file format gsm, extension(s) gsm
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_h263.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_h263.so] => (Raw h263 data)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered file format h263, extension(s) h263
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_pcm.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_pcm.so] => (Raw uLaw 8khz Audio support (PCM))
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered file format pcm, extension(s) pcm|ulaw|ul|mu
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_pcm_alaw.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_pcm_alaw.so] => (Raw aLaw 8khz PCM Audio support)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered file format alaw, extension(s) alaw|al
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_sln.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_sln.so] => (Raw Signed Linear Audio support (SLN))
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered file format sln, extension(s) sln|raw
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_wav.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_wav.so] => (Microsoft WAV format (8000hz Signed Linear))
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered file format wav, extension(s) wav
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_wav_gsm.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_wav_gsm.so] => (Microsoft WAV format (Proprietary GSM))
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered file format wav49, extension(s) WAV|wav49
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_jpeg.so]Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [format_jpeg.so] => (JPEG (Joint Picture Experts Group) Image Format)
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Registered format 'jpg' (JPEG (Joint Picture Experts Group))
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:  [skipping chan_woomera.so]
Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/enum.conf': Nov 16 18:11:43 VERBOSE[3080054464] logger.c:   == Parsing '/opt/openpbx.org/etc/enum.conf': Found
Nov 16 18:11:43 VERBOSE[3080054464] logger.c: OpenPBX Ready.
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: OpenPBX Ready.

<-- SIP read from 192.168.60.87:5060: 
INVITE sip:7809824561@192.168.60.78 SIP/2.0

Via: SIP/2.0/UDP 192.168.60.87:5060;branch=z9hG4bK-9dbedf98

From: 7805771645 <sip:7805771645@192.168.60.78>;tag=c9c5f11114f81c30o0

To: <sip:7809824561@192.168.60.78>

Call-ID: 5ce9a765-9685b9ec@192.168.60.87

CSeq: 101 INVITE

Max-Forwards: 70

Contact: 7805771645 <sip:7805771645@192.168.60.87:5060>

Expires: 240

User-Agent: Linksys/SPA2102-3.3.6

Content-Length: 444

Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER

Supported: x-sipura

Content-Type: application/sdp



v=0

o=- 200590 200590 IN IP4 192.168.60.87

s=-

c=IN IP4 192.168.60.87

t=0 0

m=audio 16402 RTP/AVP 18 0 2 4 8 96 97 98 100 101

a=rtpmap:18 G729a/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:2 G726-32/8000

a=rtpmap:4 G723/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:96 G726-40/8000

a=rtpmap:97 G726-24/8000

a=rtpmap:98 G726-16/8000

a=rtpmap:100 NSE/8000

a=fmtp:100 192-193

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

a=ptime:20

a=sendrecv


Nov 16 18:12:32 VERBOSE[3059674032] logger.c: --- (14 headers 20 lines) ---
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Using INVITE request as basis request - 5ce9a765-9685b9ec@192.168.60.87
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Sending to 192.168.60.87 : 5060 (non-NAT)
Nov 16 18:12:32 DEBUG[3059674032] chan_sip.c: Setting NAT on RTP to 0
Nov 16 18:12:32 DEBUG[3059674032] chan_sip.c: Setting NAT on UDPTL to 0
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Reliably Transmitting (no NAT) to 192.168.60.87:5060:
SIP/2.0 407 Proxy Authentication Required

Via: SIP/2.0/UDP 192.168.60.87:5060;branch=z9hG4bK-9dbedf98;received=192.168.60.87

From: 7805771645 <sip:7805771645@192.168.60.78>;tag=c9c5f11114f81c30o0

To: <sip:7809824561@192.168.60.78>;tag=as28ed5a70

Call-ID: 5ce9a765-9685b9ec@192.168.60.87

CSeq: 101 INVITE

User-Agent: OpenPBX

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Max-Forwards: 70

Proxy-Authenticate: Digest algorithm=MD5, realm="openpbx.org", nonce="16a702e7"

Content-Length: 0




---
Nov 16 18:12:32 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 0.999000000 repeat 0.0
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Scheduling destruction of call '5ce9a765-9685b9ec@192.168.60.87' in 15000 ms
Nov 16 18:12:32 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 0.999000000 repeat 0.0
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found user '7805771645'
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: 
<-- SIP read from 192.168.60.87:5060: 
ACK sip:7809824561@192.168.60.78 SIP/2.0

Via: SIP/2.0/UDP 192.168.60.87:5060;branch=z9hG4bK-9dbedf98

From: 7805771645 <sip:7805771645@192.168.60.78>;tag=c9c5f11114f81c30o0

To: <sip:7809824561@192.168.60.78>;tag=as28ed5a70

Call-ID: 5ce9a765-9685b9ec@192.168.60.87

CSeq: 101 ACK

Max-Forwards: 70

Contact: 7805771645 <sip:7805771645@192.168.60.87:5060>

User-Agent: Linksys/SPA2102-3.3.6

Content-Length: 0




Nov 16 18:12:32 VERBOSE[3059674032] logger.c: --- (10 headers 0 lines) ---
Nov 16 18:12:32 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 14.993000000 repeat 0.0
Nov 16 18:12:32 DEBUG[3059674032] chan_sip.c: Stopping retransmission on '5ce9a765-9685b9ec@192.168.60.87' of Response 101: Match Found
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: 
<-- SIP read from 192.168.60.87:5060: 
INVITE sip:7809824561@192.168.60.78 SIP/2.0

Via: SIP/2.0/UDP 192.168.60.87:5060;branch=z9hG4bK-21a652b0

From: 7805771645 <sip:7805771645@192.168.60.78>;tag=c9c5f11114f81c30o0

To: <sip:7809824561@192.168.60.78>

Call-ID: 5ce9a765-9685b9ec@192.168.60.87

CSeq: 102 INVITE

Max-Forwards: 70

Proxy-Authorization: Digest username="7805771645",realm="openpbx.org",nonce="16a702e7",uri="sip:7809824561@192.168.60.78",algorithm=MD5,response="e24315e9b74673831a5b7a318aebc994"

Contact: 7805771645 <sip:7805771645@192.168.60.87:5060>

Expires: 240

User-Agent: Linksys/SPA2102-3.3.6

Content-Length: 444

Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER

Supported: x-sipura

Content-Type: application/sdp



v=0

o=- 200590 200590 IN IP4 192.168.60.87

s=-

c=IN IP4 192.168.60.87

t=0 0

m=audio 16402 RTP/AVP 18 0 2 4 8 96 97 98 100 101

a=rtpmap:18 G729a/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:2 G726-32/8000

a=rtpmap:4 G723/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:96 G726-40/8000

a=rtpmap:97 G726-24/8000

a=rtpmap:98 G726-16/8000

a=rtpmap:100 NSE/8000

a=fmtp:100 192-193

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

a=ptime:20

a=sendrecv


Nov 16 18:12:32 VERBOSE[3059674032] logger.c: --- (15 headers 20 lines) ---
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Using INVITE request as basis request - 5ce9a765-9685b9ec@192.168.60.87
Nov 16 18:12:32 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 259.160000000 repeat 0.0
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Sending to 192.168.60.87 : 5060 (non-NAT)
Nov 16 18:12:32 DEBUG[3059674032] chan_sip.c: Setting NAT on RTP to 0
Nov 16 18:12:32 DEBUG[3059674032] chan_sip.c: Setting NAT on UDPTL to 0
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found user '7805771645'
Nov 16 18:12:32 DEBUG[3059674032] chan_sip.c: Got this other party (o = '- 200590 200590 IN IP4 192.168.60.87')
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found RTP audio format 18
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found RTP audio format 0
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found RTP audio format 2
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found RTP audio format 4
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found RTP audio format 8
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found RTP audio format 96
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found RTP audio format 97
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found RTP audio format 98
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found RTP audio format 100
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found RTP audio format 101
Nov 16 18:12:32 DEBUG[3059674032] chan_sip.c: Activating RTP on response 5ce9a765-9685b9ec@192.168.60.87 (1)
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Peer audio RTP is at port 192.168.60.87:16402
Nov 16 18:12:32 DEBUG[3059674032] chan_sip.c: Peer audio RTP is at port 192.168.60.87:16402
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found description format G729a
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found description format PCMU
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found description format G726-32
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found description format G723
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found description format PCMA
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found description format G726-40
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found description format G726-24
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found description format G726-16
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found description format NSE
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Found description format telephone-event
Nov 16 18:12:32 DEBUG[3059674032] chan_sip.c: T38 state changed to 0 on channel <none>
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Capabilities: us - 0x4 (ulaw), peer - audio=0x51d (g723|ulaw|alaw|g726|g729|ilbc)/video=0x0 (nothing), combined - 0x4 (ulaw)
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event)
Nov 16 18:12:32 DEBUG[3059674032] chan_sip.c: Checking SIP call limits for device 7805771645
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Looking for 7809824561 in residential.terracecomm.com (domain 192.168.60.78)
Nov 16 18:12:32 DEBUG[3059674032] chan_sip.c: build_route: Contact hop: 7805771645 <sip:7805771645@192.168.60.87:5060>
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: list_route: hop: <sip:7805771645@192.168.60.87:5060>
Nov 16 18:12:32 VERBOSE[3059674032] logger.c: Transmitting (no NAT) to 192.168.60.87:5060:
SIP/2.0 100 Trying

Via: SIP/2.0/UDP 192.168.60.87:5060;branch=z9hG4bK-21a652b0;received=192.168.60.87

From: 7805771645 <sip:7805771645@192.168.60.78>;tag=c9c5f11114f81c30o0

To: <sip:7809824561@192.168.60.78>

Call-ID: 5ce9a765-9685b9ec@192.168.60.87

CSeq: 102 INVITE

User-Agent: OpenPBX

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Max-Forwards: 70

Contact: <sip:7809824561@192.168.60.78>

Content-Length: 0




---
Nov 16 18:12:32 VERBOSE[3056651184] logger.c:     -- Executing Goto("SIP/7805771645-cfae", "faxin|s|1") in new stack
Nov 16 18:12:32 VERBOSE[3056651184] logger.c:     -- Goto (faxin,s,1)
Nov 16 18:12:32 VERBOSE[3056651184] logger.c:     -- Executing Set("SIP/7805771645-cfae", "LOCALSTATIONID=Virtutone FAX-Forward") in new stack
Nov 16 18:12:32 VERBOSE[3056651184] logger.c:     -- Executing Set("SIP/7805771645-cfae", "LOCALHEADERINFO=FAX-Forward Satellite Faxing Solution") in new stack
Nov 16 18:12:32 VERBOSE[3056651184] logger.c:     -- Executing Set("SIP/7805771645-cfae", "FAXFILE=/tmp/1163725952.0.tif") in new stack
Nov 16 18:12:32 VERBOSE[3056651184] logger.c:     -- Executing Set("SIP/7805771645-cfae", "FAXFILENOEXT=/tmp/1163725952.0") in new stack
Nov 16 18:12:32 VERBOSE[3056651184] logger.c:     -- Executing RxFAX("SIP/7805771645-cfae", "/tmp/1163725952.0.tif|debug") in new stack
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: We're at 192.168.60.78 port 19224
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Adding codec 0x4 (ulaw) to SDP
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Adding non-codec 0x1 (telephone-event) to SDP
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Reliably Transmitting (no NAT) to 192.168.60.87:5060:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 192.168.60.87:5060;branch=z9hG4bK-21a652b0;received=192.168.60.87

From: 7805771645 <sip:7805771645@192.168.60.78>;tag=c9c5f11114f81c30o0

To: <sip:7809824561@192.168.60.78>;tag=as5e2a367c

Call-ID: 5ce9a765-9685b9ec@192.168.60.87

CSeq: 102 INVITE

User-Agent: OpenPBX

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Max-Forwards: 70

Contact: <sip:7809824561@192.168.60.78>

Content-Type: application/sdp

Content-Length: 216



v=0

o=root 6438 6438 IN IP4 192.168.60.78

s=session

c=IN IP4 192.168.60.78

t=0 0

m=audio 19224 RTP/AVP 0 101

a=rtpmap:0 PCMU/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -


---
Nov 16 18:12:33 DEBUG[3056651184] timer.c: Timer 0x8072210 set to 0.999000000 repeat 0.0
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 0, len 8)
Nov 16 18:12:33 VERBOSE[3059674032] logger.c: 
<-- SIP read from 192.168.60.87:5060: 
ACK sip:7809824561@192.168.60.78 SIP/2.0

Via: SIP/2.0/UDP 192.168.60.87:5060;branch=z9hG4bK-6abe8f30

From: 7805771645 <sip:7805771645@192.168.60.78>;tag=c9c5f11114f81c30o0

To: <sip:7809824561@192.168.60.78>;tag=as5e2a367c

Call-ID: 5ce9a765-9685b9ec@192.168.60.87

CSeq: 102 ACK

Max-Forwards: 70

Proxy-Authorization: Digest username="7805771645",realm="openpbx.org",nonce="16a702e7",uri="sip:7809824561@192.168.60.78",algorithm=MD5,response="6fbad82b5c82dd30176f5155b1033d47"

Contact: 7805771645 <sip:7805771645@192.168.60.87:5060>

User-Agent: Linksys/SPA2102-3.3.6

Content-Length: 0




Nov 16 18:12:33 VERBOSE[3059674032] logger.c: --- (11 headers 0 lines) ---
Nov 16 18:12:33 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 258.984000000 repeat 0.0
Nov 16 18:12:33 DEBUG[3059674032] chan_sip.c: Stopping retransmission on '5ce9a765-9685b9ec@192.168.60.87' of Response 102: Match Found
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15124, ts 83895113, len 160)
Nov 16 18:12:33 DEBUG[3056651184] rtp.c: Had to change frame MS from 0 to 20
Nov 16 18:12:33 DEBUG[3056651184] rtp.c: Able to create smoother :) ms: 20 len 160
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11848, ts 160, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15125, ts 83895273, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11849, ts 320, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15126, ts 83895433, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11850, ts 480, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15127, ts 83895593, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11851, ts 640, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15128, ts 83895753, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11852, ts 800, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15129, ts 83895913, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11853, ts 960, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15130, ts 83896073, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11854, ts 1120, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15131, ts 83896233, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11855, ts 1280, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15132, ts 83896393, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11856, ts 1440, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15133, ts 83896553, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11857, ts 1600, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15134, ts 83896713, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11858, ts 1760, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15135, ts 83896873, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11859, ts 1920, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15136, ts 83897033, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11860, ts 2080, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15137, ts 83897193, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11861, ts 2240, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15138, ts 83897353, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11862, ts 2400, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15139, ts 83897513, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11863, ts 2560, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15140, ts 83897673, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11864, ts 2720, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15141, ts 83897833, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11865, ts 2880, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15142, ts 83897993, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11866, ts 3040, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15143, ts 83898153, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11867, ts 3200, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15144, ts 83898313, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11868, ts 3360, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15145, ts 83898473, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11869, ts 3520, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15146, ts 83898633, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11870, ts 3680, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15147, ts 83898793, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11871, ts 3840, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15148, ts 83898953, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11872, ts 4000, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15149, ts 83899113, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11873, ts 4160, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15150, ts 83899273, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11874, ts 4320, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15151, ts 83899433, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11875, ts 4480, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15152, ts 83899593, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11876, ts 4640, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15153, ts 83899753, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11877, ts 4800, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15154, ts 83899913, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11878, ts 4960, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15155, ts 83900073, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11879, ts 5120, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15156, ts 83900233, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11880, ts 5280, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15157, ts 83900393, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11881, ts 5440, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15158, ts 83900553, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11882, ts 5600, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15159, ts 83900713, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11883, ts 5760, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15160, ts 83900873, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11884, ts 5920, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15161, ts 83901033, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11885, ts 6080, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15162, ts 83901193, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11886, ts 6240, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15163, ts 83901353, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11887, ts 6400, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Got RTP packet from 192.168.60.87:16402 (type 0, seq 15164, ts 83901513, len 160)
Nov 16 18:12:33 VERBOSE[3056651184] logger.c: Sent RTP packet to 192.168.60.87:16402 (type 0, seq 11888, ts 6560, len 160)
Nov 16 18:12:34 VERBOSE[3059674032] logger.c: 
<-- SIP read from 192.168.60.87:5060: 
INVITE sip:7809824561@192.168.60.78 SIP/2.0

Via: SIP/2.0/UDP 192.168.60.87:5060;branch=z9hG4bK-1ef7d06

From: 7805771645 <sip:7805771645@192.168.60.78>;tag=c9c5f11114f81c30o0

To: <sip:7809824561@192.168.60.78>;tag=as5e2a367c

Call-ID: 5ce9a765-9685b9ec@192.168.60.87

CSeq: 103 INVITE

Max-Forwards: 70

Proxy-Authorization: Digest username="7805771645",realm="openpbx.org",nonce="16a702e7",uri="sip:7809824561@192.168.60.78",algorithm=MD5,response="e24315e9b74673831a5b7a318aebc994"

Contact: 7805771645 <sip:7805771645@192.168.60.87:5060>

Expires: 30

User-Agent: Linksys/SPA2102-3.3.6

Content-Length: 267

Content-Type: application/sdp



v=0

o=- 200695 200695 IN IP4 192.168.60.87

s=-

c=IN IP4 192.168.60.87

t=0 0

m=image 16402 udptl t38

a=T38FaxVersion:0

a=T38MaxBitRate:14400

a=T38FaxRateManagement:transferredTCF

a=T38FaxMaxBuffer:200

a=T38FaxMaxDatagram:200

a=T38FaxUdpEC:t38UDPRedundancy


Nov 16 18:12:34 VERBOSE[3059674032] logger.c: --- (13 headers 12 lines) ---
Nov 16 18:12:34 VERBOSE[3059674032] logger.c: Using INVITE request as basis request - 5ce9a765-9685b9ec@192.168.60.87
Nov 16 18:12:34 VERBOSE[3059674032] logger.c: Sending to 192.168.60.87 : 5060 (non-NAT)
Nov 16 18:12:34 DEBUG[3059674032] chan_sip.c: Got this other party (o = '- 200695 200695 IN IP4 192.168.60.87')
Nov 16 18:12:34 VERBOSE[3059674032] logger.c: Got T.38 offer in SDP
Nov 16 18:12:34 DEBUG[3059674032] chan_sip.c: Activating UDPTL on response 5ce9a765-9685b9ec@192.168.60.87 (1)
Nov 16 18:12:34 DEBUG[3059674032] chan_sip.c: T38 state changed to 4 on channel SIP/7805771645-cfae
Nov 16 18:12:34 VERBOSE[3059674032] logger.c: Peer T.38 UDPTL is at port 192.168.60.87:16402
Nov 16 18:12:34 DEBUG[3059674032] chan_sip.c: Peer T.38 UDPTL is at port 192.168.60.87:16402
Nov 16 18:12:34 DEBUG[3059674032] chan_sip.c: FaxVersion: 0
Nov 16 18:12:34 DEBUG[3059674032] chan_sip.c: T38MaxBitRate: 14400
Nov 16 18:12:34 DEBUG[3059674032] chan_sip.c: RateMangement: transferredTCF
Nov 16 18:12:34 DEBUG[3059674032] chan_sip.c: MaxBufferSize:200
Nov 16 18:12:34 DEBUG[3059674032] chan_sip.c: FaxMaxDatagram: 200
Nov 16 18:12:34 DEBUG[3059674032] chan_sip.c: UDP EC: t38UDPRedundancy
Nov 16 18:12:34 DEBUG[3059674032] chan_sip.c: Our T38 capability = (12048), peer T38 capability (16160), joint T38 capability (12064)
Nov 16 18:12:34 VERBOSE[3059674032] logger.c: Capabilities: us - 0x4 (ulaw), peer - audio=0x51d (g723|ulaw|alaw|g726|g729|ilbc)/video=0x0 (nothing), combined - 0x4 (ulaw)
Nov 16 18:12:34 VERBOSE[3059674032] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event)
Nov 16 18:12:34 VERBOSE[3059674032] logger.c: T.38 UDPTL is at port 192.168.60.78:19224
Nov 16 18:12:34 DEBUG[3059674032] chan_sip.c: Our T38 capability (12048), peer T38 capability (16160), joint capability (12064)
Nov 16 18:12:34 DEBUG[3059674032] chan_sip.c: T38MaxFaxRate 14400 found
Nov 16 18:12:34 VERBOSE[3059674032] logger.c: Reliably Transmitting (no NAT) to 192.168.60.87:5060:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 192.168.60.87:5060;branch=z9hG4bK-1ef7d06;received=192.168.60.87

From: 7805771645 <sip:7805771645@192.168.60.78>;tag=c9c5f11114f81c30o0

To: <sip:7809824561@192.168.60.78>;tag=as5e2a367c

Call-ID: 5ce9a765-9685b9ec@192.168.60.87

CSeq: 103 INVITE

User-Agent: OpenPBX

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Max-Forwards: 70

Contact: <sip:7809824561@192.168.60.78>

Content-Type: application/sdp

Content-Length: 351



v=0

o=root 6438 6439 IN IP4 192.168.60.78

s=session

c=IN IP4 192.168.60.78

t=0 0

m=image 19224 udptl t38

a=T38FaxVersion:0

a=T38MaxBitRate:14400

a=T38FaxFillBitRemoval:0

a=T38FaxTranscodingMMR:0

a=T38FaxTranscodingJBIG:0

a=T38FaxRateManagement:transferredTCF

a=T38FaxMaxBuffer:200

a=T38FaxMaxDatagram:200

a=T38FaxUdpEC:t38UDPRedundancy


---
Nov 16 18:12:34 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 0.999000000 repeat 0.0
Nov 16 18:12:34 DEBUG[3059674032] chan_sip.c: T38 state changed to 5 on channel SIP/7805771645-cfae
Nov 16 18:12:34 DEBUG[3059674032] chan_sip.c: T38mode enabled for channel SIP/7805771645-cfae
Nov 16 18:12:34 DEBUG[3056651184] app_rxfax.c: T38 switchover detected
Nov 16 18:12:34 VERBOSE[3056651184] logger.c: Got UDPTL packet from 192.168.60.87:16402 (len 172)
Nov 16 18:12:34 DEBUG[3056651184] app_rxfax.c:  Unknown pkt received: frametype: 0 subclass: 0 t38_mode: 1
Nov 16 18:12:34 VERBOSE[3056651184] logger.c: Got UDPTL packet from 192.168.60.87:16402 (len 172)
Nov 16 18:12:34 DEBUG[3056651184] app_rxfax.c:  Unknown pkt received: frametype: 0 subclass: 0 t38_mode: 1
Nov 16 18:12:34 VERBOSE[3056651184] logger.c: Got UDPTL packet from 192.168.60.87:16402 (len 172)
Nov 16 18:12:34 DEBUG[3056651184] app_rxfax.c:  Unknown pkt received: frametype: 0 subclass: 0 t38_mode: 1
Nov 16 18:12:34 VERBOSE[3056651184] logger.c: Got UDPTL packet from 192.168.60.87:16402 (len 172)
Nov 16 18:12:34 DEBUG[3056651184] app_rxfax.c:  Unknown pkt received: frametype: 0 subclass: 0 t38_mode: 1
Nov 16 18:12:34 VERBOSE[3056651184] logger.c: Got UDPTL packet from 192.168.60.87:16402 (len 172)
Nov 16 18:12:34 DEBUG[3056651184] app_rxfax.c:  Unknown pkt received: frametype: 0 subclass: 0 t38_mode: 1
Nov 16 18:12:34 VERBOSE[3056651184] logger.c: Got UDPTL packet from 192.168.60.87:16402 (len 172)
Nov 16 18:12:34 DEBUG[3056651184] app_rxfax.c:  Unknown pkt received: frametype: 0 subclass: 0 t38_mode: 1
Nov 16 18:12:34 VERBOSE[3056651184] logger.c: Got UDPTL packet from 192.168.60.87:16402 (len 172)
Nov 16 18:12:34 DEBUG[3056651184] app_rxfax.c:  Unknown pkt received: frametype: 0 subclass: 0 t38_mode: 1
Nov 16 18:12:34 VERBOSE[3056651184] logger.c: Got UDPTL packet from 192.168.60.87:16402 (len 172)
Nov 16 18:12:34 DEBUG[3056651184] app_rxfax.c:  Unknown pkt received: frametype: 0 subclass: 0 t38_mode: 1
Nov 16 18:12:34 VERBOSE[3056651184] logger.c: Got UDPTL packet from 192.168.60.87:16402 (len 172)
Nov 16 18:12:34 DEBUG[3056651184] app_rxfax.c:  Unknown pkt received: frametype: 0 subclass: 0 t38_mode: 1
Nov 16 18:12:34 VERBOSE[3059674032] logger.c: 
<-- SIP read from 192.168.60.87:5060: 
ACK sip:7809824561@192.168.60.78 SIP/2.0

Via: SIP/2.0/UDP 192.168.60.87:5060;branch=z9hG4bK-c19c793a

From: 7805771645 <sip:7805771645@192.168.60.78>;tag=c9c5f11114f81c30o0

To: <sip:7809824561@192.168.60.78>;tag=as5e2a367c

Call-ID: 5ce9a765-9685b9ec@192.168.60.87

CSeq: 103 ACK

Max-Forwards: 70

Proxy-Authorization: Digest username="7805771645",realm="openpbx.org",nonce="16a702e7",uri="sip:7809824561@192.168.60.78",algorithm=MD5,response="6fbad82b5c82dd30176f5155b1033d47"

Contact: 7805771645 <sip:7805771645@192.168.60.87:5060>

User-Agent: Linksys/SPA2102-3.3.6

Content-Length: 0




Nov 16 18:12:34 VERBOSE[3059674032] logger.c: --- (11 headers 0 lines) ---
Nov 16 18:12:34 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 257.944000000 repeat 0.0
Nov 16 18:12:34 DEBUG[3059674032] chan_sip.c: Stopping retransmission on '5ce9a765-9685b9ec@192.168.60.87' of Response 103: Match Found
Nov 16 18:12:34 VERBOSE[3056651184] logger.c: Got UDPTL packet from 192.168.60.87:16402 (len 6)
Nov 16 18:12:34 DEBUG[3056651184] app_rxfax.c:  Unknown pkt received: frametype: 0 subclass: 0 t38_mode: 1
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Send complete in phase T30_PHASE_A_CED, state 1
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Starting answer mode
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Changing from phase T30_PHASE_A_CED to T30_PHASE_B_TX
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set rx type 0
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set tx type 4
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Changing from state 1 to 15
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30 DIS:
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Store and forward Internet fax (T.37): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Real-time Internet fax (T.38): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= 3G mobile network: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= V.8 capabilities: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Preferred octets: 256 octets
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Ready to transmit a fax document (polling): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..1.= Can receive fax: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= 2-D coding: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..00= Recording width: 215 mm +- 1%
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 10..= Recording length: Unlimited
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .111 ....= Receiver's minimum scan line time: 0 ms at 3.85 l/mm: T7.7 = T3.85
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Compressed/uncompressed mode: Compressed
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Error correction mode (ECM): Non-ECM
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= T.6 coding: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= "Field not valid" supported: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Multiple selective polling: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Polled subaddress: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= T.43 coding: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Plane interleave: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Reserved for the use of extended voice coding set: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...1= R8x15.4lines/mm: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= 300x300pels/25.4mm: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= R16x15.4lines/mm and/or 400x400pels/25.4 mm: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 1...= Inch-based resolution preferred: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Metric-based resolution preferred: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Selective polling: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Subaddressing: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Password: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Ready to transmit a data file (polling): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Binary file transfer (BFT): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Document transfer mode (DTM): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Electronic data interchange (EDI): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Basic transfer mode (BTM): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Ready to transfer a character or mixed mode document (polling): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= Character mode: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Mixed mode (Annex E/T.4): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Processable mode 26 (Rec. T.505): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Digital network capability: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Duplex capability: Half only
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= JPEG coding: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Full colour mode: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= 12bits/pel component: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= No subsampling (1:1:1): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Custom illuminant: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Custom gamut range: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 1...= North American Letter (215.9mm x 279.4mm): Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...1 ....= North American Legal (215.9mm x 355.6mm): Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Single-progression sequential coding (Rec. T.85) basic: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= HKM key management: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= RSA key management: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Override: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= HFX40 cipher: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Alternative cipher number 2: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Alternative cipher number 3: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= HFX40-I hashing: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Alternative hashing system 2: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Alternative hashing system 3: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Reserved for future security features: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..00 0...= T.44 (Mixed Raster Content): 0
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Page length maximum stripe size for T.44 (Mixed Raster Content): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Colour/gray-scale 300pels/25.4mm x 300lines/25.4mm or 400pels/25.4mm x 400lines/25.4mm resolution: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= 100pels/25.4mm x 100lines/25.4mm for colour/gray scale: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Simple phase C BFT negotiations: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= Extended BFT Negotiations capable: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Internet Selective Polling address (ISP): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Internet Routing Address (IRA): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= 600pels/25.4mm x 600lines/25.4mm: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= 1200pels/25.4mm x 1200lines/25.4mm: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= 300pels/25.4mm x 600lines/25.4mm: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= 400pels/25.4mm x 800lines/25.4mm: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= 600pels/25.4mm x 1200lines/25.4mm: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Colour/gray scale 600pels/25.4mm x 600lines/25.4mm: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Colour/gray scale 1200pels/25.4mm x 1200lines/25.4mm: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Double sided printing capability (alternate mode): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Double sided printing capability (continuous mode): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= T.45 (run length colour encoded): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..00 ....= Shared memory: Not available
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= T.44 colour space: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Flow control capability for T.38 communication: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= K>4: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Internet aware T.38 mode fax (not affected by data signal rate bits): Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..00 0...= T.89 (Application profiles for ITU-T Rec T.8): Not used
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= sYCC-JPEG coding: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30   0... ....= Extension indicator: Not set
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Tx:  DIS with final frame tag
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Tx:  ff 13 80 00 ee f8 80 80 89 80 80 80 98 80 80 80 80 80 00
Nov 16 18:12:36 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     1: indicator T38_IND_V21_PREAMBLE
Nov 16 18:12:36 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 1, len 8)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     2: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     2: c0 01 80 00 00 ff
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 2, len 15)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     3: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     3: c0 01 80 00 00 c8
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 3, len 22)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     4: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     4: c0 01 80 00 00 01
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 4, len 27)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     5: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     5: c0 01 80 00 00 00
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 5, len 32)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     6: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     6: c0 01 80 00 00 77
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 6, len 32)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     7: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     7: c0 01 80 00 00 1f
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 7, len 32)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     8: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     8: c0 01 80 00 00 01
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 8, len 32)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     9: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx     9: c0 01 80 00 00 01
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 9, len 32)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    10: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    10: c0 01 80 00 00 91
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 10, len 32)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    11: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    11: c0 01 80 00 00 01
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 11, len 32)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    12: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    12: c0 01 80 00 00 01
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 12, len 32)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    13: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    13: c0 01 80 00 00 01
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 13, len 32)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    14: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    14: c0 01 80 00 00 19
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 14, len 32)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    15: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    15: c0 01 80 00 00 01
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 15, len 32)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    16: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    16: c0 01 80 00 00 01
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 16, len 32)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    17: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    17: c0 01 80 00 00 01
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 17, len 32)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    18: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    18: c0 01 80 00 00 01
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 18, len 32)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    19: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    19: c0 01 80 00 00 01
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 19, len 32)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    20: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    20: c0 01 80 00 00 00
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 20, len 32)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 15
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    21: data type T38_DATA_V21/T38_FIELD_HDLC_FCS_OK_SIG_END + 0 byte(s)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    21: c0 01 40
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 21, len 29)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    22: indicator T38_IND_NO_SIGNAL
Nov 16 18:12:37 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 22, len 24)
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 15
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Changing from phase T30_PHASE_B_TX to T30_PHASE_B_RX
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set rx type 4
Nov 16 18:12:37 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set tx type 0
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30 T4 timeout in phase T30_PHASE_B_RX, state 15
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Changing from phase T30_PHASE_B_RX to T30_PHASE_B_TX
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set rx type 0
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set tx type 4
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30 DIS:
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Store and forward Internet fax (T.37): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Real-time Internet fax (T.38): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= 3G mobile network: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= V.8 capabilities: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Preferred octets: 256 octets
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Ready to transmit a fax document (polling): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..1.= Can receive fax: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= 2-D coding: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..00= Recording width: 215 mm +- 1%
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 10..= Recording length: Unlimited
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .111 ....= Receiver's minimum scan line time: 0 ms at 3.85 l/mm: T7.7 = T3.85
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Compressed/uncompressed mode: Compressed
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Error correction mode (ECM): Non-ECM
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= T.6 coding: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= "Field not valid" supported: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Multiple selective polling: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Polled subaddress: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= T.43 coding: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Plane interleave: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Reserved for the use of extended voice coding set: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...1= R8x15.4lines/mm: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= 300x300pels/25.4mm: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= R16x15.4lines/mm and/or 400x400pels/25.4 mm: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 1...= Inch-based resolution preferred: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Metric-based resolution preferred: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Selective polling: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Subaddressing: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Password: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Ready to transmit a data file (polling): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Binary file transfer (BFT): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Document transfer mode (DTM): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Electronic data interchange (EDI): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Basic transfer mode (BTM): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Ready to transfer a character or mixed mode document (polling): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= Character mode: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Mixed mode (Annex E/T.4): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Processable mode 26 (Rec. T.505): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Digital network capability: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Duplex capability: Half only
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= JPEG coding: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Full colour mode: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= 12bits/pel component: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= No subsampling (1:1:1): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Custom illuminant: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Custom gamut range: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 1...= North American Letter (215.9mm x 279.4mm): Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...1 ....= North American Legal (215.9mm x 355.6mm): Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Single-progression sequential coding (Rec. T.85) basic: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= HKM key management: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= RSA key management: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Override: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= HFX40 cipher: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Alternative cipher number 2: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Alternative cipher number 3: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= HFX40-I hashing: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Alternative hashing system 2: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Alternative hashing system 3: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Reserved for future security features: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..00 0...= T.44 (Mixed Raster Content): 0
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Page length maximum stripe size for T.44 (Mixed Raster Content): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Colour/gray-scale 300pels/25.4mm x 300lines/25.4mm or 400pels/25.4mm x 400lines/25.4mm resolution: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= 100pels/25.4mm x 100lines/25.4mm for colour/gray scale: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Simple phase C BFT negotiations: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= Extended BFT Negotiations capable: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Internet Selective Polling address (ISP): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Internet Routing Address (IRA): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= 600pels/25.4mm x 600lines/25.4mm: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= 1200pels/25.4mm x 1200lines/25.4mm: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= 300pels/25.4mm x 600lines/25.4mm: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= 400pels/25.4mm x 800lines/25.4mm: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= 600pels/25.4mm x 1200lines/25.4mm: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Colour/gray scale 600pels/25.4mm x 600lines/25.4mm: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Colour/gray scale 1200pels/25.4mm x 1200lines/25.4mm: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Double sided printing capability (alternate mode): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Double sided printing capability (continuous mode): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= T.45 (run length colour encoded): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..00 ....= Shared memory: Not available
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= T.44 colour space: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Flow control capability for T.38 communication: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= K>4: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Internet aware T.38 mode fax (not affected by data signal rate bits): Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..00 0...= T.89 (Application profiles for ITU-T Rec T.8): Not used
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= sYCC-JPEG coding: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30   0... ....= Extension indicator: Not set
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Tx:  DIS with final frame tag
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Tx:  ff 13 80 00 ee f8 80 80 89 80 80 80 98 80 80 80 80 80 00
Nov 16 18:12:41 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    23: indicator T38_IND_V21_PREAMBLE
Nov 16 18:12:41 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 23, len 19)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    24: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    24: c0 01 80 00 00 ff
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 24, len 19)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    25: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    25: c0 01 80 00 00 c8
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 25, len 22)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    26: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    26: c0 01 80 00 00 01
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 26, len 27)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    27: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    27: c0 01 80 00 00 00
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 27, len 32)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    28: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    28: c0 01 80 00 00 77
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 28, len 32)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    29: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    29: c0 01 80 00 00 1f
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 29, len 32)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    30: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    30: c0 01 80 00 00 01
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 30, len 32)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    31: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    31: c0 01 80 00 00 01
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 31, len 32)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    32: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    32: c0 01 80 00 00 91
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 32, len 32)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    33: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    33: c0 01 80 00 00 01
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 33, len 32)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    34: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    34: c0 01 80 00 00 01
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 34, len 32)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    35: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    35: c0 01 80 00 00 01
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 35, len 32)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    36: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    36: c0 01 80 00 00 19
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 36, len 32)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    37: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    37: c0 01 80 00 00 01
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 37, len 32)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    38: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    38: c0 01 80 00 00 01
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 38, len 32)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    39: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    39: c0 01 80 00 00 01
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 39, len 32)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    40: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    40: c0 01 80 00 00 01
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 40, len 32)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    41: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    41: c0 01 80 00 00 01
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 41, len 32)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    42: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:42 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    42: c0 01 80 00 00 00
Nov 16 18:12:42 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 42, len 32)
Nov 16 18:12:43 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 15
Nov 16 18:12:43 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    43: data type T38_DATA_V21/T38_FIELD_HDLC_FCS_OK_SIG_END + 0 byte(s)
Nov 16 18:12:43 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    43: c0 01 40
Nov 16 18:12:43 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 43, len 29)
Nov 16 18:12:43 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    44: indicator T38_IND_NO_SIGNAL
Nov 16 18:12:43 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 44, len 24)
Nov 16 18:12:43 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 15
Nov 16 18:12:43 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Changing from phase T30_PHASE_B_TX to T30_PHASE_B_RX
Nov 16 18:12:43 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set rx type 4
Nov 16 18:12:43 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set tx type 0
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30 T4 timeout in phase T30_PHASE_B_RX, state 15
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Changing from phase T30_PHASE_B_RX to T30_PHASE_B_TX
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set rx type 0
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set tx type 4
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30 DIS:
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Store and forward Internet fax (T.37): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Real-time Internet fax (T.38): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= 3G mobile network: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= V.8 capabilities: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Preferred octets: 256 octets
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Ready to transmit a fax document (polling): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..1.= Can receive fax: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= 2-D coding: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..00= Recording width: 215 mm +- 1%
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 10..= Recording length: Unlimited
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .111 ....= Receiver's minimum scan line time: 0 ms at 3.85 l/mm: T7.7 = T3.85
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Compressed/uncompressed mode: Compressed
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Error correction mode (ECM): Non-ECM
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= T.6 coding: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= "Field not valid" supported: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Multiple selective polling: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Polled subaddress: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= T.43 coding: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Plane interleave: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Reserved for the use of extended voice coding set: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...1= R8x15.4lines/mm: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= 300x300pels/25.4mm: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= R16x15.4lines/mm and/or 400x400pels/25.4 mm: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 1...= Inch-based resolution preferred: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Metric-based resolution preferred: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Selective polling: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Subaddressing: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Password: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Ready to transmit a data file (polling): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Binary file transfer (BFT): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Document transfer mode (DTM): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Electronic data interchange (EDI): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Basic transfer mode (BTM): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Ready to transfer a character or mixed mode document (polling): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= Character mode: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Mixed mode (Annex E/T.4): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Processable mode 26 (Rec. T.505): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Digital network capability: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Duplex capability: Half only
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= JPEG coding: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Full colour mode: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= 12bits/pel component: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= No subsampling (1:1:1): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Custom illuminant: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Custom gamut range: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 1...= North American Letter (215.9mm x 279.4mm): Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...1 ....= North American Legal (215.9mm x 355.6mm): Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Single-progression sequential coding (Rec. T.85) basic: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= HKM key management: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= RSA key management: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Override: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= HFX40 cipher: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Alternative cipher number 2: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Alternative cipher number 3: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= HFX40-I hashing: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Alternative hashing system 2: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Alternative hashing system 3: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Reserved for future security features: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..00 0...= T.44 (Mixed Raster Content): 0
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Page length maximum stripe size for T.44 (Mixed Raster Content): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Colour/gray-scale 300pels/25.4mm x 300lines/25.4mm or 400pels/25.4mm x 400lines/25.4mm resolution: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= 100pels/25.4mm x 100lines/25.4mm for colour/gray scale: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Simple phase C BFT negotiations: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= Extended BFT Negotiations capable: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Internet Selective Polling address (ISP): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Internet Routing Address (IRA): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= 600pels/25.4mm x 600lines/25.4mm: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= 1200pels/25.4mm x 1200lines/25.4mm: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= 300pels/25.4mm x 600lines/25.4mm: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= 400pels/25.4mm x 800lines/25.4mm: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= 600pels/25.4mm x 1200lines/25.4mm: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Colour/gray scale 600pels/25.4mm x 600lines/25.4mm: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Colour/gray scale 1200pels/25.4mm x 1200lines/25.4mm: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Double sided printing capability (alternate mode): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Double sided printing capability (continuous mode): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= T.45 (run length colour encoded): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..00 ....= Shared memory: Not available
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= T.44 colour space: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Flow control capability for T.38 communication: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= K>4: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Internet aware T.38 mode fax (not affected by data signal rate bits): Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..00 0...= T.89 (Application profiles for ITU-T Rec T.8): Not used
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= sYCC-JPEG coding: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30   0... ....= Extension indicator: Not set
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Tx:  DIS with final frame tag
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Tx:  ff 13 80 00 ee f8 80 80 89 80 80 80 98 80 80 80 80 80 00
Nov 16 18:12:46 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    45: indicator T38_IND_V21_PREAMBLE
Nov 16 18:12:46 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 45, len 19)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    46: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    46: c0 01 80 00 00 ff
Nov 16 18:12:47 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 46, len 19)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    47: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    47: c0 01 80 00 00 c8
Nov 16 18:12:47 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 47, len 22)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    48: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    48: c0 01 80 00 00 01
Nov 16 18:12:47 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 48, len 27)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    49: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    49: c0 01 80 00 00 00
Nov 16 18:12:47 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 49, len 32)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    50: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    50: c0 01 80 00 00 77
Nov 16 18:12:47 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 50, len 32)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    51: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    51: c0 01 80 00 00 1f
Nov 16 18:12:47 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 51, len 32)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    52: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    52: c0 01 80 00 00 01
Nov 16 18:12:47 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 52, len 32)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    53: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    53: c0 01 80 00 00 01
Nov 16 18:12:47 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 53, len 32)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    54: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    54: c0 01 80 00 00 91
Nov 16 18:12:47 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 54, len 32)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    55: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    55: c0 01 80 00 00 01
Nov 16 18:12:47 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 55, len 32)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    56: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    56: c0 01 80 00 00 01
Nov 16 18:12:47 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 56, len 32)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    57: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    57: c0 01 80 00 00 01
Nov 16 18:12:47 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 57, len 32)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    58: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    58: c0 01 80 00 00 19
Nov 16 18:12:47 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 58, len 32)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    59: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:47 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    59: c0 01 80 00 00 01
Nov 16 18:12:47 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 59, len 32)
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    60: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    60: c0 01 80 00 00 01
Nov 16 18:12:48 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 60, len 32)
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    61: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    61: c0 01 80 00 00 01
Nov 16 18:12:48 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 61, len 32)
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    62: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    62: c0 01 80 00 00 01
Nov 16 18:12:48 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 62, len 32)
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    63: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    63: c0 01 80 00 00 01
Nov 16 18:12:48 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 63, len 32)
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    64: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    64: c0 01 80 00 00 00
Nov 16 18:12:48 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 64, len 32)
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 15
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    65: data type T38_DATA_V21/T38_FIELD_HDLC_FCS_OK_SIG_END + 0 byte(s)
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    65: c0 01 40
Nov 16 18:12:48 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 65, len 29)
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    66: indicator T38_IND_NO_SIGNAL
Nov 16 18:12:48 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 66, len 24)
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 15
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Changing from phase T30_PHASE_B_TX to T30_PHASE_B_RX
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set rx type 4
Nov 16 18:12:48 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set tx type 0
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30 T4 timeout in phase T30_PHASE_B_RX, state 15
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Changing from phase T30_PHASE_B_RX to T30_PHASE_B_TX
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set rx type 0
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set tx type 4
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30 DIS:
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Store and forward Internet fax (T.37): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Real-time Internet fax (T.38): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= 3G mobile network: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= V.8 capabilities: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Preferred octets: 256 octets
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Ready to transmit a fax document (polling): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..1.= Can receive fax: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= 2-D coding: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..00= Recording width: 215 mm +- 1%
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 10..= Recording length: Unlimited
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .111 ....= Receiver's minimum scan line time: 0 ms at 3.85 l/mm: T7.7 = T3.85
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Compressed/uncompressed mode: Compressed
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Error correction mode (ECM): Non-ECM
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= T.6 coding: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= "Field not valid" supported: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Multiple selective polling: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Polled subaddress: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= T.43 coding: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Plane interleave: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Reserved for the use of extended voice coding set: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...1= R8x15.4lines/mm: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= 300x300pels/25.4mm: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= R16x15.4lines/mm and/or 400x400pels/25.4 mm: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 1...= Inch-based resolution preferred: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Metric-based resolution preferred: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Selective polling: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Subaddressing: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Password: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Ready to transmit a data file (polling): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Binary file transfer (BFT): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Document transfer mode (DTM): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Electronic data interchange (EDI): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Basic transfer mode (BTM): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Ready to transfer a character or mixed mode document (polling): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= Character mode: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Mixed mode (Annex E/T.4): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Processable mode 26 (Rec. T.505): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Digital network capability: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Duplex capability: Half only
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= JPEG coding: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Full colour mode: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= 12bits/pel component: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= No subsampling (1:1:1): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Custom illuminant: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Custom gamut range: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 1...= North American Letter (215.9mm x 279.4mm): Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...1 ....= North American Legal (215.9mm x 355.6mm): Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Single-progression sequential coding (Rec. T.85) basic: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= HKM key management: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= RSA key management: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Override: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= HFX40 cipher: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Alternative cipher number 2: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Alternative cipher number 3: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= HFX40-I hashing: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Alternative hashing system 2: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Alternative hashing system 3: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Reserved for future security features: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..00 0...= T.44 (Mixed Raster Content): 0
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Page length maximum stripe size for T.44 (Mixed Raster Content): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Colour/gray-scale 300pels/25.4mm x 300lines/25.4mm or 400pels/25.4mm x 400lines/25.4mm resolution: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= 100pels/25.4mm x 100lines/25.4mm for colour/gray scale: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Simple phase C BFT negotiations: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= Extended BFT Negotiations capable: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= Internet Selective Polling address (ISP): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Internet Routing Address (IRA): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= 600pels/25.4mm x 600lines/25.4mm: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= 1200pels/25.4mm x 1200lines/25.4mm: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= 300pels/25.4mm x 600lines/25.4mm: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= 400pels/25.4mm x 800lines/25.4mm: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ...0 ....= 600pels/25.4mm x 1200lines/25.4mm: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..0. ....= Colour/gray scale 600pels/25.4mm x 600lines/25.4mm: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= Colour/gray scale 1200pels/25.4mm x 1200lines/25.4mm: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Double sided printing capability (alternate mode): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= Double sided printing capability (continuous mode): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... 0...= T.45 (run length colour encoded): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..00 ....= Shared memory: Not available
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= T.44 colour space: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   1... ....= Extension indicator: Set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ...0= Flow control capability for T.38 communication: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... ..0.= K>4: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .... .0..= Internet aware T.38 mode fax (not affected by data signal rate bits): Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   ..00 0...= T.89 (Application profiles for ITU-T Rec T.8): Not used
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   .0.. ....= sYCC-JPEG coding: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30   0... ....= Extension indicator: Not set
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Tx:  DIS with final frame tag
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Tx:  ff 13 80 00 ee f8 80 80 89 80 80 80 98 80 80 80 80 80 00
Nov 16 18:12:51 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    67: indicator T38_IND_V21_PREAMBLE
Nov 16 18:12:51 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 67, len 19)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    68: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    68: c0 01 80 00 00 ff
Nov 16 18:12:52 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 68, len 19)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    69: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    69: c0 01 80 00 00 c8
Nov 16 18:12:52 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 69, len 22)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    70: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    70: c0 01 80 00 00 01
Nov 16 18:12:52 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 70, len 27)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    71: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    71: c0 01 80 00 00 00
Nov 16 18:12:52 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 71, len 32)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    72: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    72: c0 01 80 00 00 77
Nov 16 18:12:52 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 72, len 32)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    73: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    73: c0 01 80 00 00 1f
Nov 16 18:12:52 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 73, len 32)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    74: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    74: c0 01 80 00 00 01
Nov 16 18:12:52 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 74, len 32)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    75: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    75: c0 01 80 00 00 01
Nov 16 18:12:52 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 75, len 32)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    76: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:52 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    76: c0 01 80 00 00 91
Nov 16 18:12:52 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 76, len 32)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    77: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    77: c0 01 80 00 00 01
Nov 16 18:12:53 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 77, len 32)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    78: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    78: c0 01 80 00 00 01
Nov 16 18:12:53 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 78, len 32)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    79: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    79: c0 01 80 00 00 01
Nov 16 18:12:53 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 79, len 32)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    80: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    80: c0 01 80 00 00 19
Nov 16 18:12:53 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 80, len 32)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    81: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    81: c0 01 80 00 00 01
Nov 16 18:12:53 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 81, len 32)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    82: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    82: c0 01 80 00 00 01
Nov 16 18:12:53 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 82, len 32)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    83: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    83: c0 01 80 00 00 01
Nov 16 18:12:53 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 83, len 32)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    84: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    84: c0 01 80 00 00 01
Nov 16 18:12:53 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 84, len 32)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    85: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    85: c0 01 80 00 00 01
Nov 16 18:12:53 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 85, len 32)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    86: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    86: c0 01 80 00 00 00
Nov 16 18:12:53 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 86, len 32)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 15
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    87: data type T38_DATA_V21/T38_FIELD_HDLC_FCS_OK_SIG_END + 0 byte(s)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    87: c0 01 40
Nov 16 18:12:53 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 87, len 29)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    88: indicator T38_IND_NO_SIGNAL
Nov 16 18:12:53 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 88, len 24)
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 15
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Changing from phase T30_PHASE_B_TX to T30_PHASE_B_RX
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set rx type 4
Nov 16 18:12:53 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set tx type 0
Nov 16 18:12:56 DEBUG[3056651184] app_rxfax.c: FLOW T.30 T4 timeout in phase T30_PHASE_B_RX, state 15
Nov 16 18:12:56 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Changing from phase T30_PHASE_B_RX to T30_PHASE_D_TX
Nov 16 18:12:56 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set rx type 0
Nov 16 18:12:56 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set tx type 4
Nov 16 18:12:56 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Changing from state 15 to 3
Nov 16 18:12:56 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Tx:  DCN with final frame tag
Nov 16 18:12:56 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Tx:  ff 13 fa
Nov 16 18:12:56 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    89: indicator T38_IND_V21_PREAMBLE
Nov 16 18:12:56 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 89, len 19)
Nov 16 18:12:57 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    90: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:57 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    90: c0 01 80 00 00 ff
Nov 16 18:12:57 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 90, len 19)
Nov 16 18:12:57 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    91: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:57 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    91: c0 01 80 00 00 c8
Nov 16 18:12:57 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 91, len 22)
Nov 16 18:12:57 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    92: data type T38_DATA_V21/T38_FIELD_HDLC_DATA + 1 byte(s)
Nov 16 18:12:57 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    92: c0 01 80 00 00 5f
Nov 16 18:12:57 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 92, len 27)
Nov 16 18:12:57 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Send complete in phase T30_PHASE_D_TX, state 3
Nov 16 18:12:57 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    93: data type T38_DATA_V21/T38_FIELD_HDLC_FCS_OK_SIG_END + 0 byte(s)
Nov 16 18:12:57 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    93: c0 01 40
Nov 16 18:12:57 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 93, len 29)
Nov 16 18:12:57 DEBUG[3056651184] app_rxfax.c: FLOW T.38 Tx    94: indicator T38_IND_NO_SIGNAL
Nov 16 18:12:57 VERBOSE[3056651184] logger.c: Sent UDPTL packet to 192.168.60.87:16402 (seq 94, len 24)
Nov 16 18:12:57 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Send complete in phase T30_PHASE_D_TX, state 3
Nov 16 18:12:57 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Disconnecting
Nov 16 18:12:57 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Changing from phase T30_PHASE_D_TX to T30_PHASE_E
Nov 16 18:12:57 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set rx type 0
Nov 16 18:12:57 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set tx type 1
Nov 16 18:12:57 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Changing from state 3 to 2
Nov 16 18:12:58 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Send complete in phase T30_PHASE_E, state 2
Nov 16 18:12:58 DEBUG[3056651184] app_rxfax.c: ==============================================================================
Nov 16 18:12:58 DEBUG[3056651184] app_rxfax.c: Fax receive not successful - result (60) Disconnected after permitted retries.
Nov 16 18:12:58 DEBUG[3056651184] app_rxfax.c: ==============================================================================
Nov 16 18:12:58 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Changing from state 2 to 30
Nov 16 18:12:58 DEBUG[3056651184] app_rxfax.c: FLOW T.30 Changing from phase T30_PHASE_E to T30_PHASE_CALL_FINISHED
Nov 16 18:12:58 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set rx type 13
Nov 16 18:12:58 DEBUG[3056651184] app_rxfax.c: FLOW T.38T Set tx type 13
Nov 16 18:12:58 DEBUG[3056651184] app_rxfax.c: FLOW T.38T FAX exchange complete
Nov 16 18:12:58 VERBOSE[3056651184] logger.c:     -- Executing NoOp("SIP/7805771645-cfae", "RX: REMOTESTATIONID is ") in new stack
Nov 16 18:12:58 VERBOSE[3056651184] logger.c:     -- Executing NoOp("SIP/7805771645-cfae", "RX: PHASEESTATUS is 60") in new stack
Nov 16 18:12:58 VERBOSE[3056651184] logger.c:   == Auto fallthrough, channel 'SIP/7805771645-cfae' status is 'UNKNOWN'
Nov 16 18:12:58 DEBUG[3056651184] cdr_sqlite3.c: CDR SQLITE3 SQL [INSERT INTO cdr (clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata, start,answer,end,duration,billsec,disposition,amaflags, accountcode) VALUES ('"Virtutone" <7805771645>', '7805771645', 's', 'faxin', 'SIP/7805771645-cfae', '', 'NoOp', 'RX: PHASEESTATUS is 60', '2006-11-16 18:12:32', '2006-11-16 18:12:33', '2006-11-16 18:12:58', 26, 25, 4, 3, '')]
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is '"Virtutone" <7805771645>'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is '7805771645'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is 's'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is 'faxin'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is 'SIP/7805771645-cfae'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is '(null)'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is 'NoOp'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is 'RX: PHASEESTATUS is 60'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is '2006-11-16 18:12:32'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is '2006-11-16 18:12:33'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is '2006-11-16 18:12:58'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is '26'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is '25'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is 'ANSWERED'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is 'DOCUMENTATION'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is '(null)'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is '1163725952.0'
Nov 16 18:12:58 DEBUG[3056651184] pbx.c: Function result is '(null)'
Nov 16 18:12:58 DEBUG[3056651184] chan_sip.c: update_call_counter(7805771645) - decrement call limit counter
Nov 16 18:12:58 VERBOSE[3056651184] logger.c: set_destination: Parsing <sip:7805771645@192.168.60.87:5060> for address/port to send to
Nov 16 18:12:58 VERBOSE[3056651184] logger.c: set_destination: set destination to 192.168.60.87, port 5060
Nov 16 18:12:58 VERBOSE[3056651184] logger.c: Reliably Transmitting (no NAT) to 192.168.60.87:5060:
BYE sip:7805771645@192.168.60.87:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.60.78:5060;branch=z9hG4bK4d3d4b92;rport

From: <sip:7809824561@192.168.60.78>;tag=as5e2a367c

To: 7805771645 <sip:7805771645@192.168.60.78>;tag=c9c5f11114f81c30o0

Contact: <sip:7809824561@192.168.60.78>

Call-ID: 5ce9a765-9685b9ec@192.168.60.87

CSeq: 102 BYE

User-Agent: OpenPBX

Max-Forwards: 70

Content-Length: 0




---
Nov 16 18:12:58 DEBUG[3056651184] timer.c: Timer 0x8072210 set to 0.999000000 repeat 0.0
Nov 16 18:12:58 DEBUG[3056651184] generator.c: Trying to deactivate generator in SIP/7805771645-cfae
Nov 16 18:12:58 DEBUG[3056651184] generator.c: Generator on SIP/7805771645-cfae stopped after 0 iterations
Nov 16 18:12:58 VERBOSE[3059674032] logger.c: 
<-- SIP read from 192.168.60.87:5060: 
SIP/2.0 200 OK

To: 7805771645 <sip:7805771645@192.168.60.78>;tag=c9c5f11114f81c30o0

From: <sip:7809824561@192.168.60.78>;tag=as5e2a367c

Call-ID: 5ce9a765-9685b9ec@192.168.60.87

CSeq: 102 BYE

Via: SIP/2.0/UDP 192.168.60.78:5060;branch=z9hG4bK4d3d4b92

Server: Linksys/SPA2102-3.3.6

Content-Length: 0




Nov 16 18:12:58 VERBOSE[3059674032] logger.c: --- (8 headers 0 lines) ---
Nov 16 18:12:58 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 233.817000000 repeat 0.0
Nov 16 18:12:58 DEBUG[3059674032] chan_sip.c: Stopping retransmission on '5ce9a765-9685b9ec@192.168.60.87' of Request 102: Match Found
Nov 16 18:12:58 VERBOSE[3059674032] logger.c: Destroying call '5ce9a765-9685b9ec@192.168.60.87'
Nov 16 18:15:21 VERBOSE[3059674032] logger.c: 
<-- SIP read from 192.168.60.87:5060: 
REGISTER sip:192.168.60.78 SIP/2.0

Via: SIP/2.0/UDP 192.168.60.87:5060;branch=z9hG4bK-ce4ecc00

From: 7805771645 <sip:7805771645@192.168.60.78>;tag=eebb97d84b6ec0f2o0

To: 7805771645 <sip:7805771645@192.168.60.78>

Call-ID: 3513cc44-60383332@192.168.60.87

CSeq: 18734 REGISTER

Max-Forwards: 70

Authorization: Digest username="7805771645",realm="openpbx.org",nonce="14b4161e",uri="sip:192.168.60.78",algorithm=MD5,response="59b28f5353e372cf9cedeabc304b3ba8"

Contact: 7805771645 <sip:7805771645@192.168.60.87:5060>;expires=300

User-Agent: Linksys/SPA2102-3.3.6

Content-Length: 0

Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER

Supported: x-sipura




Nov 16 18:15:21 VERBOSE[3059674032] logger.c: --- (13 headers 0 lines) ---
Nov 16 18:15:21 VERBOSE[3059674032] logger.c: Using latest REGISTER request as basis request
Nov 16 18:15:21 VERBOSE[3059674032] logger.c: Sending to 192.168.60.87 : 5060 (non-NAT)
Nov 16 18:15:21 VERBOSE[3059674032] logger.c: Transmitting (no NAT) to 192.168.60.87:5060:
SIP/2.0 100 Trying

Via: SIP/2.0/UDP 192.168.60.87:5060;branch=z9hG4bK-ce4ecc00;received=192.168.60.87

From: 7805771645 <sip:7805771645@192.168.60.78>;tag=eebb97d84b6ec0f2o0

To: 7805771645 <sip:7805771645@192.168.60.78>

Call-ID: 3513cc44-60383332@192.168.60.87

CSeq: 18734 REGISTER

User-Agent: OpenPBX

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Max-Forwards: 70

Contact: <sip:7805771645@192.168.60.78>

Content-Length: 0




---
Nov 16 18:15:21 VERBOSE[3059674032] logger.c: Transmitting (no NAT) to 192.168.60.87:5060:
SIP/2.0 401 Unauthorized

Via: SIP/2.0/UDP 192.168.60.87:5060;branch=z9hG4bK-ce4ecc00;received=192.168.60.87

From: 7805771645 <sip:7805771645@192.168.60.78>;tag=eebb97d84b6ec0f2o0

To: 7805771645 <sip:7805771645@192.168.60.78>;tag=as051e80f5

Call-ID: 3513cc44-60383332@192.168.60.87

CSeq: 18734 REGISTER

User-Agent: OpenPBX

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Max-Forwards: 70

WWW-Authenticate: Digest algorithm=MD5, realm="openpbx.org", nonce="21aa3bda"

Content-Length: 0




---
Nov 16 18:15:21 VERBOSE[3059674032] logger.c: Scheduling destruction of call '3513cc44-60383332@192.168.60.87' in 15000 ms
Nov 16 18:15:21 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 14.999000000 repeat 0.0
Nov 16 18:15:21 VERBOSE[3059674032] logger.c: 
<-- SIP read from 192.168.60.87:5060: 
REGISTER sip:192.168.60.78 SIP/2.0

Via: SIP/2.0/UDP 192.168.60.87:5060;branch=z9hG4bK-7d07c49d

From: 7805771645 <sip:7805771645@192.168.60.78>;tag=eebb97d84b6ec0f2o0

To: 7805771645 <sip:7805771645@192.168.60.78>

Call-ID: 3513cc44-60383332@192.168.60.87

CSeq: 18735 REGISTER

Max-Forwards: 70

Authorization: Digest username="7805771645",realm="openpbx.org",nonce="21aa3bda",uri="sip:192.168.60.78",algorithm=MD5,response="74f8e29ef320e40deafe3526fbd81f4c"

Contact: 7805771645 <sip:7805771645@192.168.60.87:5060>;expires=300

User-Agent: Linksys/SPA2102-3.3.6

Content-Length: 0

Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER

Supported: x-sipura




Nov 16 18:15:21 VERBOSE[3059674032] logger.c: --- (13 headers 0 lines) ---
Nov 16 18:15:21 VERBOSE[3059674032] logger.c: Using latest REGISTER request as basis request
Nov 16 18:15:21 VERBOSE[3059674032] logger.c: Sending to 192.168.60.87 : 5060 (non-NAT)
Nov 16 18:15:21 VERBOSE[3059674032] logger.c: Transmitting (no NAT) to 192.168.60.87:5060:
SIP/2.0 100 Trying

Via: SIP/2.0/UDP 192.168.60.87:5060;branch=z9hG4bK-7d07c49d;received=192.168.60.87

From: 7805771645 <sip:7805771645@192.168.60.78>;tag=eebb97d84b6ec0f2o0

To: 7805771645 <sip:7805771645@192.168.60.78>

Call-ID: 3513cc44-60383332@192.168.60.87

CSeq: 18735 REGISTER

User-Agent: OpenPBX

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Max-Forwards: 70

Contact: <sip:7805771645@192.168.60.78>

Content-Length: 0




---
Nov 16 18:15:21 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 90.468000000 repeat 0.0
Nov 16 18:15:21 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 309.999000000 repeat 0.0
Nov 16 18:15:21 DEBUG[3059674032] db.c: SQL [delete from odb where family = 'SIP/Registry' and keys = '7805771645']
Nov 16 18:15:21 DEBUG[3059674032] db.c: SQL [insert into odb values('SIP/Registry','7805771645','192.168.60.87:5060:300:7805771645:sip:7805771645@192.168.60.87:5060')]
Nov 16 18:15:21 VERBOSE[3059674032] logger.c:     -- Saved useragent "Linksys/SPA2102-3.3.6" for peer 7805771645
Nov 16 18:15:21 VERBOSE[3059674032] logger.c: Transmitting (no NAT) to 192.168.60.87:5060:
SIP/2.0 200 OK

Via: SIP/2.0/UDP 192.168.60.87:5060;branch=z9hG4bK-7d07c49d;received=192.168.60.87

From: 7805771645 <sip:7805771645@192.168.60.78>;tag=eebb97d84b6ec0f2o0

To: 7805771645 <sip:7805771645@192.168.60.78>;tag=as051e80f5

Call-ID: 3513cc44-60383332@192.168.60.87

CSeq: 18735 REGISTER

User-Agent: OpenPBX

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Max-Forwards: 70

Expires: 300

Contact: <sip:7805771645@192.168.60.87:5060>;expires=300

Date: Fri, 17 Nov 2006 01:15:21 GMT

Content-Length: 0




---
Nov 16 18:15:21 VERBOSE[3059674032] logger.c: Scheduling destruction of call '3513cc44-60383332@192.168.60.87' in 15000 ms
Nov 16 18:15:21 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 14.999000000 repeat 0.0
Nov 16 18:15:23 VERBOSE[3059674032] logger.c: 12 headers, 5 lines
Nov 16 18:15:23 VERBOSE[3059674032] logger.c: Reliably Transmitting (no NAT) to 192.168.60.87:5060:
NOTIFY sip:7805771645@192.168.60.87:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.60.78:5060;branch=z9hG4bK25a5c613;rport

From: "openpbx" <sip:openpbx@192.168.60.78>;tag=as5a8051d0

To: <sip:7805771645@192.168.60.87:5060>

Contact: <sip:openpbx@192.168.60.78>

Call-ID: 513c2f3564791b7c523989c4402d80cd@192.168.60.78

CSeq: 102 NOTIFY

User-Agent: OpenPBX

Max-Forwards: 70

Event: message-summary

Content-Type: application/simple-message-summary

Content-Length: 83



Messages-Waiting: no

Message-Account: sip:openpbx@



Voice-Message: 0/0 (0/0)




---
Nov 16 18:15:23 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 0.999000000 repeat 0.0
Nov 16 18:15:23 VERBOSE[3059674032] logger.c: Scheduling destruction of call '513c2f3564791b7c523989c4402d80cd@192.168.60.78' in 15000 ms
Nov 16 18:15:23 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 0.999000000 repeat 0.0
Nov 16 18:15:23 VERBOSE[3059674032] logger.c: 
<-- SIP read from 192.168.60.87:5060: 
SIP/2.0 200 OK

To: <sip:7805771645@192.168.60.87:5060>;tag=89199650a5e814dai0

From: "openpbx" <sip:openpbx@192.168.60.78>;tag=as5a8051d0

Call-ID: 513c2f3564791b7c523989c4402d80cd@192.168.60.78

CSeq: 102 NOTIFY

Via: SIP/2.0/UDP 192.168.60.78:5060;branch=z9hG4bK25a5c613

Server: Linksys/SPA2102-3.3.6

Content-Length: 0




Nov 16 18:15:23 VERBOSE[3059674032] logger.c: --- (8 headers 0 lines) ---
Nov 16 18:15:23 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 12.979000000 repeat 0.0
Nov 16 18:15:23 DEBUG[3059674032] chan_sip.c: Stopping retransmission on '513c2f3564791b7c523989c4402d80cd@192.168.60.78' of Request 102: Match Found
Nov 16 18:15:23 VERBOSE[3059674032] logger.c: Destroying call '513c2f3564791b7c523989c4402d80cd@192.168.60.78'
Nov 16 18:15:23 DEBUG[3059674032] timer.c: Timer 0x8072210 set to 12.979000000 repeat 0.0
Nov 16 18:15:36 DEBUG[3071011760] chan_sip.c: Auto destroying call '3513cc44-60383332@192.168.60.87'
Nov 16 18:15:36 VERBOSE[3071011760] logger.c: Destroying call '3513cc44-60383332@192.168.60.87'
Nov 16 18:15:36 DEBUG[3071011760] timer.c: Timer 0x8072210 set to 294.883000000 repeat 0.0