-- Executing Set("SIP/as5300-4217", "LOCALSTATIONID=eWC FAX") in new stack
-- Executing Set("SIP/as5300-4217", "LOCALHEADERINFO=Jalsot teszt") in new stack
-- Executing Set("SIP/as5300-4217", "FAXFILE=/tmp/test3_opbx_rulez.tif") in new stack
-- Executing TxFAX("SIP/as5300-4217", "/tmp/test3_opbx_rulez.tif|caller|ecm") in new stack
Nov 14 22:17:30 DEBUG[1093470560]: app_txfax.c:369 txfax_exec: Enabling ECM mode for app_txfax
Nov 14 22:17:31 DEBUG[1093470560]: chan_sip.c:3349 sip_rtp_write: OUTGOING Fax CNG detected on SIP/as5300-4217
Nov 14 22:17:31 DEBUG[1093470560]: chan_sip.c:5860 t38_get_rate: T38MaxFaxRate 14400 found
Nov 14 22:17:31 DEBUG[1093470560]: timer.c:200 opbx_timer_start: Timer 0x2aaaab50eaf8 set to 0.999000000 repeat 0.0
Nov 14 22:17:31 DEBUG[1093470560]: chan_sip.c:3426 sip_write: T38 state changed to 2 on channel SIP/as5300-4217
Nov 14 22:17:31 DEBUG[1092405600]: chan_sip.c:1546 __sip_ack: Acked pending invite 103
Nov 14 22:17:31 DEBUG[1092405600]: timer.c:200 opbx_timer_start: Timer 0x2aaaab50eaf8 set to 103.96000000 repeat 0.0
Nov 14 22:17:31 DEBUG[1092405600]: chan_sip.c:1570 __sip_ack: Stopping retransmission on '06e47db574d973dd7b3e19113b8f4c30@195.56.65.38' of Request 103: Match Found
Nov 14 22:17:31 DEBUG[1092405600]: chan_sip.c:4545 process_sdp: Got this other party (o = 'CiscoSystemsSIP-GW-UserAgent 2903 1878 IN IP4 217.116.32.206')
Nov 14 22:17:31 DEBUG[1092405600]: chan_sip.c:4607 process_sdp: Activating UDPTL on response 06e47db574d973dd7b3e19113b8f4c30@195.56.65.38 (1)
Nov 14 22:17:31 DEBUG[1092405600]: chan_sip.c:4616 process_sdp: T38 state changed to 4 on channel SIP/as5300-4217
Nov 14 22:17:31 DEBUG[1092405600]: chan_sip.c:4828 process_sdp: FaxVersion: 0
Nov 14 22:17:31 DEBUG[1092405600]: chan_sip.c:4799 process_sdp: T38MaxBitRate: 14400
Nov 14 22:17:31 DEBUG[1092405600]: chan_sip.c:4844 process_sdp: FillBitRemoval: 0
Nov 14 22:17:31 DEBUG[1092405600]: chan_sip.c:4851 process_sdp: Transcoding MMR: 0
Nov 14 22:17:31 DEBUG[1092405600]: chan_sip.c:4858 process_sdp: Transcoding JBIG: 0
Nov 14 22:17:31 DEBUG[1092405600]: chan_sip.c:4865 process_sdp: RateMangement: transferredTCF
Nov 14 22:17:31 DEBUG[1092405600]: chan_sip.c:4794 process_sdp: MaxBufferSize:200
Nov 14 22:17:31 DEBUG[1092405600]: chan_sip.c:4837 process_sdp: FaxMaxDatagram: 72
Nov 14 22:17:31 DEBUG[1092405600]: chan_sip.c:7990 build_route: build_route: Retaining previous route: <sip:89236#3619997424@217.116.32.206:5060>
Nov 14 22:17:31 DEBUG[1092405600]: chan_sip.c:12004 handle_response_invite: Channel Bridge information is non-existant. T38 Termination requested.
*CLI>
*CLI>
*CLI> Nov 14 22:17:54 DEBUG[1093470560]: app_txfax.c:114 phase_e_handler: ==============================================================================
Nov 14 22:17:54 DEBUG[1093470560]: app_txfax.c:116 phase_e_handler: Fax successfully sent.
Nov 14 22:17:54 DEBUG[1093470560]: app_txfax.c:117 phase_e_handler: Remote station id: +3619997424
Nov 14 22:17:54 DEBUG[1093470560]: app_txfax.c:118 phase_e_handler: Local station id: eWC FAX
Nov 14 22:17:54 DEBUG[1093470560]: app_txfax.c:119 phase_e_handler: Pages transferred: 1
Nov 14 22:17:54 DEBUG[1093470560]: app_txfax.c:120 phase_e_handler: Image resolution: 8037 x 3850
Nov 14 22:17:54 DEBUG[1093470560]: app_txfax.c:121 phase_e_handler: Transfer Rate: 14400
Nov 14 22:17:54 DEBUG[1093470560]: app_txfax.c:136 phase_e_handler: ==============================================================================
== Auto fallthrough, channel 'SIP/as5300-4217' status is 'UNKNOWN'
-- Executing NoOp("SIP/as5300-4217", "TX: REMOTESTATIONID is +3619997424") in new stack
-- Executing NoOp("SIP/as5300-4217", "TX: PHASEESTATUS is 0") in new stack
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is '(null)'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is '(null)'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is 's'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is 'faxout'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is 'SIP/as5300-4217'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is '(null)'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is 'NoOp'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is 'TX: PHASEESTATUS is 0'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is '2006-11-14 22:17:30'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is '(null)'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is '2006-11-14 22:17:54'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is '24'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is '0'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is 'ANSWERED'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is '(null)'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is '1163539050.0'
Nov 14 22:17:54 DEBUG[1093470560]: pbx.c:1673 pbx_substitute_variables_helper_full: Function result is '(null)'
Nov 14 22:17:54 DEBUG[1093470560]: cdr_sqlite3.c:167 sqlite_log: CDR SQLITE3 SQL [INSERT INTO cdr (clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata, start,answer,end,duration,billsec,disposition,amaflags, accountcode) VALUES ('', '', 's', 'faxout', 'SIP/as5300-4217', '', 'NoOp', 'TX: PHASEESTATUS is 0', '2006-11-14 22:17:30', '1970-01-01 01:00:00', '2006-11-14 22:17:54', 24, 0, 4, 3, '')]
*** glibc detected *** free(): invalid next size (normal): 0x0000000000513050 ***
Aborted (core dumped)
(gdb) bt full
#0 0x00002b93fddf311d in raise () from /lib/libc.so.6
No symbol table info available.
#1 0x00002b93fddf484e in abort () from /lib/libc.so.6
No symbol table info available.
#2 0x00002b93fde27e41 in __fsetlocking () from /lib/libc.so.6
No symbol table info available.
#3 0x00002b93fde2d90e in malloc_usable_size () from /lib/libc.so.6
No symbol table info available.
#4 0x00002b93fde2dc56 in free () from /lib/libc.so.6
No symbol table info available.
#5 0x00002b93fe6df30f in sqlite3HashClear (pH=0x549200) at src/hash.c:58
elem = (HashElem *) 0x510c20
#6 0x00002b93fe6e2153 in sqlite3_close (db=0x549060) at src/main.c:164
i = (HashElem *) 0x0
j = <value optimized out>
#7 0x00002aaaac6093cb in sqlite_log (cdr=0x54b0c0) at cdr_sqlite3.c:194
res = 0
zErr = 0x0
tm = {tm_sec = 54, tm_min = 17, tm_hour = 22, tm_mday = 14, tm_mon = 10, tm_year = 106, tm_wday = 2, tm_yday = 317, tm_isdst = 0, tm_gmtoff = 3600,
tm_zone = 0x501950 "CET"}
t = 1163539074
startstr = "2006-11-14 22:17:30", '\0' <repeats 60 times>
answerstr = "1970-01-01 01:00:00", '\0' <repeats 60 times>
endstr = "2006-11-14 22:17:54", '\0' <repeats 60 times>
fn = "/opt/icall/log/cdr.db", '\0' <repeats 179 times>, "/s\030ý\223+\000\000\000\000\000\000\000\000\000\000\001\000\000\000\000\000\000\000\230$ý\223+\000\000 +\030ý\223+", '\0' <repeats 14 times>, "u\032i\t\002\000\000\000\000\000\000\000`\220T", '\0' <repeats 13 times>, "\002\000\000\000\000\000\000\000<pÝý\223+\000\000P#Ýý\223+\000\000PiÜý\223+\000\000\024\000\000\000\000\000\000\000\000\236\222ţ\223+\000\000Ŕ\230QŤŞ*\000\000\200ď,A\000\000\000\000\030¤pŹŞ*\000\000\2256Ę\006\000\000\000\000§pŹŞ*\000\000\020\000\000\000\000\000\000\000"...
sql = 0x0
__PRETTY_FUNCTION__ = "sqlite_log"
#8 0x00002b93fd2f109d in post_cdr (cdr=0x54b0c0) at cdr.c:807
chan = <value optimized out>
i = (struct opbx_cdr_beitem *) 0x2aaaab525500
__PRETTY_FUNCTION__ = "post_cdr"
#9 0x00002b93fd2f17ad in opbx_cdr_detach (cdr=0x54b0c0) at cdr.c:980
curr = <value optimized out>
__PRETTY_FUNCTION__ = "opbx_cdr_detach"
#10 0x00002b93fd2bc645 in opbx_hangup (chan=0x548390) at channel.c:1095
res = <value optimized out>
__PRETTY_FUNCTION__ = "opbx_hangup"
#11 0x00002b93fd2da466 in __opbx_pbx_run (c=0x548390) at pbx.c:2626
digit = <value optimized out>
exten = '\0' <repeats 255 times>
pos = 0
waittime = -47043009
---Type <return> to continue, or q <return> to quit---
res = 0
hash = 0
__PRETTY_FUNCTION__ = "__opbx_pbx_run"
#12 0x00002b93fd2dab57 in opbx_pbx_run (c=0x548390) at pbx.c:2715
res = OPBX_PBX_CALL_LIMIT
#13 0x00002b93fd2dc5fd in opbx_pbx_outgoing_exten (type=0x5349f4 "SIP", format=64, data=0x534af4, timeout=30000, context=0x534ef4 "faxout",
exten=0x534df4 "s", priority=1, reason=0x412d01bc, sync=2, cid_num=0x534ff8 "", cid_name=0x5350f8 "", vars=0x530100, channel=0x0) at pbx.c:5196
chan = (struct opbx_channel *) 0x548390
as = <value optimized out>
res = <value optimized out>
cdr_res = <value optimized out>
oh = {context = 0x534ef4 "faxout", exten = 0x534df4 "s", priority = 1, cid_num = 0x534ff8 "", cid_name = 0x5350f8 "", vars = 0x530100}
attr = {
__size = "ôJS\000\000\000\000\000ôMS\000\000\000\000\000ôNS\000\000\000\000\000`ń(A\000\000\000\000ôIS\000\000\000\000\000Ë\\\030ý\223+\000\000\001\000\000\000\000\000\000", __align = 5458676}
__PRETTY_FUNCTION__ = "opbx_pbx_outgoing_exten"
#14 0x00002aaaac0eda2b in attempt_thread (data=<value optimized out>) at pbx_spool.c:266
o = (struct outgoing *) 0x5348e0
res = <value optimized out>
reason = <value optimized out>
__PRETTY_FUNCTION__ = "attempt_thread"
#15 0x00002b93fd8bd0fa in start_thread () from /lib/libpthread.so.0
No symbol table info available.
#16 0x00002b93fde8bce2 in clone () from /lib/libc.so.6
No symbol table info available.
#17 0x0000000000000000 in ?? ()
No symbol table info available.
(gdb)