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 ;tag=c9c5f11114f81c30o0 To: Call-ID: 5ce9a765-9685b9ec@192.168.60.87 CSeq: 101 INVITE Max-Forwards: 70 Contact: 7805771645 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 ;tag=c9c5f11114f81c30o0 To: ;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 ;tag=c9c5f11114f81c30o0 To: ;tag=as28ed5a70 Call-ID: 5ce9a765-9685b9ec@192.168.60.87 CSeq: 101 ACK Max-Forwards: 70 Contact: 7805771645 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 ;tag=c9c5f11114f81c30o0 To: 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 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 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 Nov 16 18:12:32 VERBOSE[3059674032] logger.c: list_route: hop: 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 ;tag=c9c5f11114f81c30o0 To: 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: 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 ;tag=c9c5f11114f81c30o0 To: ;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: 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 ;tag=c9c5f11114f81c30o0 To: ;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 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 ;tag=c9c5f11114f81c30o0 To: ;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 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 ;tag=c9c5f11114f81c30o0 To: ;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: 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 ;tag=c9c5f11114f81c30o0 To: ;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 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 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: ;tag=as5e2a367c To: 7805771645 ;tag=c9c5f11114f81c30o0 Contact: 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 ;tag=c9c5f11114f81c30o0 From: ;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 ;tag=eebb97d84b6ec0f2o0 To: 7805771645 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 ;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 ;tag=eebb97d84b6ec0f2o0 To: 7805771645 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: 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 ;tag=eebb97d84b6ec0f2o0 To: 7805771645 ;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 ;tag=eebb97d84b6ec0f2o0 To: 7805771645 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 ;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 ;tag=eebb97d84b6ec0f2o0 To: 7805771645 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: 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 ;tag=eebb97d84b6ec0f2o0 To: 7805771645 ;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: ;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" ;tag=as5a8051d0 To: Contact: 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: ;tag=89199650a5e814dai0 From: "openpbx" ;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