Attended call transfer from #1 to #3 Failure. +---------+ | LAB |>-3------+ SIP Phone--> |stock- |<-2----+ | |chan_iax2|>-1--+ | | +---------+ | | | v ^ v +---------+ | RVS | |Modified | |Libiax2 | |No-Debug | +---------+ <<<<<<<<<<<<<<<<<<<<<<<<<<<<<< LAB >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 003 Type: IAX Subclass: QUELCH Timestamp: 19761ms SCall: 20953 DCall: 00001 [10.0.0.163:4569] -- Started music on hold, class 'default', on SIP/lab-7960-e428 Tx-Frame Retry[-01] -- OSeqno: 003 ISeqno: 005 Type: IAX Subclass: ACK Timestamp: 19761ms SCall: 00001 DCall: 20953 [10.0.0.163:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW Timestamp: 00003ms SCall: 20954 DCall: 00000 [10.0.0.163:4569] VERSION : 2 CALLING NUMBER : CALLING NAME : CTIax FORMAT : 4 CAPABILITY : 4 USERNAME : strinh-iax CALLED NUMBER : 2000 DNID : 2000 Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ Timestamp: 00019ms SCall: 00002 DCall: 20954 [10.0.0.163:4569] AUTHMETHODS : 3 CHALLENGE : 123706744 USERNAME : strinh-iax Rx-Frame Retry[Yes] -- OSeqno: 004 ISeqno: 003 Type: IAX Subclass: QUELCH Timestamp: 19761ms SCall: 20953 DCall: 00001 [10.0.0.163:4569] Tx-Frame Retry[-01] -- OSeqno: 003 ISeqno: 005 Type: IAX Subclass: ACK Timestamp: 19761ms SCall: 00001 DCall: 20953 [10.0.0.163:4569] Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 005 Type: IAX Subclass: PING Timestamp: 20017ms SCall: 00001 DCall: 20953 [10.0.0.163:4569] Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 005 Type: IAX Subclass: LAGRQ Timestamp: 20020ms SCall: 00001 DCall: 20953 [10.0.0.163:4569] Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW Timestamp: 00003ms SCall: 20954 DCall: 00000 [10.0.0.163:4569] VERSION : 2 CALLING NUMBER : CALLING NAME : CTIax FORMAT : 4 CAPABILITY : 4 USERNAME : strinh-iax CALLED NUMBER : 2000 DNID : 2000 Tx-Frame Retry[-01] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00003ms SCall: 00002 DCall: 20954 [10.0.0.163:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: AUTHREP Timestamp: 00069ms SCall: 20954 DCall: 00002 [10.0.0.163:4569] MD5 RESULT : 374bbed3bc27405afc0a3a6f82163467 -- Accepting AUTHENTICATED call from 10.0.0.163, requested format = 4, actual format = 4 -- Executing Dial("IAX2/strinh-iax@strinh-iax/2", "IAX2/strinh-iax/2000|60|r") in new stack Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT Timestamp: 00071ms SCall: 00002 DCall: 20954 [10.0.0.163:4569] FORMAT : 4 Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 004 Type: IAX Subclass: ACK Timestamp: 20017ms SCall: 20953 DCall: 00001 [10.0.0.163:4569] Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 004 Type: IAX Subclass: PONG Timestamp: 20017ms SCall: 20953 DCall: 00001 [10.0.0.163:4569] Tx-Frame Retry[-01] -- OSeqno: 004 ISeqno: 006 Type: IAX Subclass: ACK Timestamp: 20017ms SCall: 00001 DCall: 20953 [10.0.0.163:4569] Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 005 Type: IAX Subclass: ACK Timestamp: 20020ms SCall: 20953 DCall: 00001 [10.0.0.163:4569] Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK Timestamp: 00071ms SCall: 20954 DCall: 00002 [10.0.0.163:4569] Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 005 Type: IAX Subclass: LAGRP Timestamp: 20020ms SCall: 20953 DCall: 00001 [10.0.0.163:4569] Tx-Frame Retry[-01] -- OSeqno: 005 ISeqno: 007 Type: IAX Subclass: ACK Timestamp: 20020ms SCall: 00001 DCall: 20953 [10.0.0.163:4569] Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW Timestamp: 00003ms SCall: 00003 DCall: 00000 [10.0.0.163:4569] VERSION : 2 CALLED NUMBER : 2000 CALLING NUMBER : 2000 CALLING NAME : Me LANGUAGE : en USERNAME : strinh-iax FORMAT : 4 CAPABILITY : 2147483647 ADSICPE : 0 DATE TIME : 156074532 Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00003ms SCall: 20955 DCall: 00003 [10.0.0.163:4569] -- Called strinh-iax/2000 Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACCEPT Timestamp: 00003ms SCall: 20955 DCall: 00003 [10.0.0.163:4569] -- Call accepted by 10.0.0.163 (format ULAW) -- Format for call is ULAW Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00003ms SCall: 00003 DCall: 20955 [10.0.0.163:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: CONTROL Subclass: RINGING Timestamp: 00003ms SCall: 20955 DCall: 00003 [10.0.0.163:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK Timestamp: 00003ms SCall: 00003 DCall: 20955 [10.0.0.163:4569] Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 002 Type: CONTROL Subclass: RINGING Timestamp: 00074ms SCall: 00002 DCall: 20954 [10.0.0.163:4569] -- IAX2/strinh-iax/3 is ringing Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACCEPT Timestamp: 00003ms SCall: 20955 DCall: 00003 [10.0.0.163:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK Timestamp: 00003ms SCall: 00003 DCall: 20955 [10.0.0.163:4569] Rx-Frame Retry[Yes] -- OSeqno: 001 ISeqno: 001 Type: CONTROL Subclass: RINGING Timestamp: 00003ms SCall: 20955 DCall: 00003 [10.0.0.163:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK Timestamp: 00003ms SCall: 00003 DCall: 20955 [10.0.0.163:4569] Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 00074ms SCall: 20954 DCall: 00002 [10.0.0.163:4569] Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 001 Type: CONTROL Subclass: ANSWER Timestamp: 00783ms SCall: 20955 DCall: 00003 [10.0.0.163:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 00783ms SCall: 00003 DCall: 20955 [10.0.0.163:4569] -- IAX2/strinh-iax/3 answered IAX2/strinh-iax@strinh-iax/2 -- Attempting native bridge of IAX2/strinh-iax@strinh-iax/2 and IAX2/strinh-iax/3 Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 002 Type: CONTROL Subclass: (255?) Timestamp: 00871ms SCall: 00002 DCall: 20954 [10.0.0.163:4569] Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 002 Type: CONTROL Subclass: ANSWER Timestamp: 00874ms SCall: 00002 DCall: 20954 [10.0.0.163:4569] Tx-Frame Retry[000] -- OSeqno: 005 ISeqno: 002 Type: IAX Subclass: TXREQ Timestamp: 00877ms SCall: 00002 DCall: 20954 [10.0.0.163:4569] APPARENT ADDRES : IPV4 10.0.0.163:4569 CALL NUMBER : 20955 TRANSFER ID : 551408278 Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 003 Type: IAX Subclass: TXREQ Timestamp: 00792ms SCall: 00003 DCall: 20955 [10.0.0.163:4569] APPARENT ADDRES : IPV4 10.0.0.163:4569 CALL NUMBER : 20954 TRANSFER ID : 551408278 Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 005 Type: IAX Subclass: ACK Timestamp: 00874ms SCall: 20954 DCall: 00002 [10.0.0.163:4569] Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 002 Type: IAX Subclass: TXREADY Timestamp: 00789ms SCall: 20955 DCall: 00003 [10.0.0.163:4569] CALL NUMBER : 20955 -- Channel 'IAX2/strinh-iax/3' ready to transfer Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 004 Type: IAX Subclass: ACK Timestamp: 00789ms SCall: 00003 DCall: 20955 [10.0.0.163:4569] Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 006 Type: IAX Subclass: TXREADY Timestamp: 00934ms SCall: 20954 DCall: 00002 [10.0.0.163:4569] CALL NUMBER : 20954 -- Channel 'IAX2/strinh-iax@strinh-iax/2' ready to transfer -- Releasing IAX2/strinh-iax@strinh-iax/2 and IAX2/strinh-iax/3 Tx-Frame Retry[000] -- OSeqno: 006 ISeqno: 003 Type: IAX Subclass: TXREL Timestamp: 00935ms SCall: 00002 DCall: 20954 [10.0.0.163:4569] CALL NUMBER : 20955 Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 004 Type: IAX Subclass: TXREL Timestamp: 00855ms SCall: 00003 DCall: 20955 [10.0.0.163:4569] CALL NUMBER : 20954 Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 007 Type: IAX Subclass: ACK Timestamp: 2801991680ms SCall: 20954 DCall: 00002 [10.0.0.163:4569] Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 1459814400ms SCall: 20955 DCall: 00003 [10.0.0.163:4569] Rx-Frame Retry[ No] -- OSeqno: 007 ISeqno: 005 Type: IAX Subclass: TXREQ Timestamp: 23502ms SCall: 20953 DCall: 00001 [10.0.0.163:4569] APPARENT ADDRES : IPV4 10.0.0.163:4569 CALL NUMBER : 20955 TRANSFER ID : 27619 Tx-Frame Retry[-01] -- OSeqno: 005 ISeqno: 008 Type: IAX Subclass: ACK Timestamp: 23502ms SCall: 00001 DCall: 20953 [10.0.0.163:4569] Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: TXCNT Timestamp: 23519ms SCall: 00001 DCall: 20955 [10.0.0.163:4569] TRANSFER ID : 27619 ******************** Next TXCNT overwrites the peer ************************ ******************** following TXREADY goes the the wrong peer ************* Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: TXCNT Timestamp: 00003ms SCall: 20955 DCall: 00001 [10.0.0.163:4569] TRANSFER ID : 27619 Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: TXACC Timestamp: 23521ms SCall: 00001 DCall: 20955 [10.0.0.163:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: TXACC Timestamp: 00002ms SCall: 20955 DCall: 00001 [10.0.0.163:4569] TRANSFER ID : 27619 Tx-Frame Retry[000] -- OSeqno: 005 ISeqno: 008 Type: IAX Subclass: TXREADY Timestamp: 23522ms SCall: 00001 DCall: 20955 [10.0.0.163:4569] CALL NUMBER : 1 Tx-Frame Retry[001] -- OSeqno: 005 ISeqno: 008 Type: IAX Subclass: TXREADY Timestamp: 23522ms SCall: 00001 DCall: 20955 [10.0.0.163:4569] CALL NUMBER : 1 Tx-Frame Retry[002] -- OSeqno: 005 ISeqno: 008 Type: IAX Subclass: TXREADY Timestamp: 23522ms SCall: 00001 DCall: 20955 [10.0.0.163:4569] CALL NUMBER : 1 Tx-Frame Retry[000] -- OSeqno: 006 ISeqno: 008 Type: IAX Subclass: LAGRQ Timestamp: 30017ms SCall: 00001 DCall: 20955 [10.0.0.163:4569] Tx-Frame Retry[001] -- OSeqno: 006 ISeqno: 008 Type: IAX Subclass: LAGRQ Timestamp: 30017ms SCall: 00001 DCall: 20955 [10.0.0.163:4569] Tx-Frame Retry[002] -- OSeqno: 006 ISeqno: 008 Type: IAX Subclass: LAGRQ Timestamp: 30017ms SCall: 00001 DCall: 20955 [10.0.0.163:4569] -- Hungup 'IAX2/strinh-iax/3' == Spawn extension (default, 2000, 1) exited non-zero on 'IAX2/strinh-iax@strinh-iax/2' -- Hungup 'IAX2/strinh-iax@strinh-iax/2' Tx-Frame Retry[003] -- OSeqno: 005 ISeqno: 008 Type: IAX Subclass: TXREADY Timestamp: 23522ms SCall: 00001 DCall: 20955 [10.0.0.163:4569] CALL NUMBER : 1 Tx-Frame Retry[000] -- OSeqno: 007 ISeqno: 008 Type: IAX Subclass: PING Timestamp: 40017ms SCall: 00001 DCall: 20955 [10.0.0.163:4569] Tx-Frame Retry[000] -- OSeqno: 008 ISeqno: 008 Type: IAX Subclass: LAGRQ Timestamp: 40020ms SCall: 00001 DCall: 20955 [10.0.0.163:4569] Tx-Frame Retry[001] -- OSeqno: 007 ISeqno: 008 Type: IAX Subclass: PING Timestamp: 40017ms SCall: 00001 DCall: 20955 [10.0.0.163:4569] Tx-Frame Retry[001] -- OSeqno: 008 ISeqno: 008 Type: IAX Subclass: LAGRQ Timestamp: 40020ms SCall: 00001 DCall: 20955 [10.0.0.163:4569] Tx-Frame Retry[003] -- OSeqno: 006 ISeqno: 008 Type: IAX Subclass: LAGRQ Timestamp: 30017ms SCall: 00001 DCall: 20955 [10.0.0.163:4569] Tx-Frame Retry[002] -- OSeqno: 007 ISeqno: 008 Type: IAX Subclass: PING Timestamp: 40017ms SCall: 00001 DCall: 20955 [10.0.0.163:4569] Tx-Frame Retry[002] -- OSeqno: 008 ISeqno: 008 Type: IAX Subclass: LAGRQ Timestamp: 40020ms SCall: 00001 DCall: 20955 [10.0.0.163:4569] Oct 13 16:17:33 WARNING[-159442000]: chan_iax2.c:1473 attempt_transmit: Max retries exceeded to host 10.0.0.163 on IAX2/strinh-iax/1 (type = 6, subclass = 25, ts=23522, seqno=5) -- Hungup 'IAX2/strinh-iax/1' == Spawn extension (default, 2000, 1) exited non-zero on 'SIP/lab-7960-e428' -- Stopped music on hold on SIP/lab-7960-e428