10:37 == call time

*CLI> iax2 debug
IAX2 Debugging Enabled
*CLI> iax2 reload
Mar 22 10:37:03 DEBUG[16702]: config.c:595 config_text_file_load: Parsing /etc/asterisk/iax.conf
    -- doing lookup for 'switch-1.nufone.net'
Mar 22 10:37:03 DEBUG[16702]: chan_iax2.c:7743 iax2_do_register: Sending registration request for 'cr4z3d'
Mar 22 10:37:03 DEBUG[16702]: chan_iax2.c:7746 iax2_do_register: Allocate call number
Mar 22 10:37:03 DEBUG[16702]: chan_iax2.c:1006 update_max_nontrunk: New max nontrunk callno is 4
Mar 22 10:37:03 DEBUG[16702]: chan_iax2.c:1105 find_callno: Creating new call structure 3
Mar 22 10:37:03 DEBUG[16702]: chan_iax2.c:7752 iax2_do_register: Registration created on call 3
Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: REGREQ 
   Timestamp: 00013ms  SCall: 00003  DCall: 00000 [66.225.202.72:4569]
   USERNAME        : cr4z3d
   REFRESH         : 60

Mar 22 10:37:03 DEBUG[16702]: config.c:595 config_text_file_load: Parsing /etc/asterisk/iaxprov.conf
    -- Loaded provisioning template 'default'
Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: REGAUTH
   Timestamp: 00003ms  SCall: 00185  DCall: 00003 [66.225.202.72:4569]
   AUTHMETHODS     : 3
   CHALLENGE       : 173867033
   USERNAME        : cr4z3d

Mar 22 10:37:03 DEBUG[16721]: chan_iax2.c:6623 socket_read: Received packet 0, (6, 14)
Mar 22 10:37:03 DEBUG[16721]: chan_iax2.c:6720 socket_read: Cancelling transmission of packet 0
Mar 22 10:37:03 DEBUG[16721]: chan_iax2.c:6817 socket_read: IAX subclass 14 received
Mar 22 10:37:03 DEBUG[16721]: chan_iax2.c:6826 socket_read: For call=3, set last=3
Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: REGREQ 
   Timestamp: 00069ms  SCall: 00003  DCall: 00185 [66.225.202.72:4569]
   USERNAME        : cr4z3d
   REFRESH         : 60
   MD5 RESULT      : 3d0afc81baf53084f9287254750f58b3

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: REGACK 
   Timestamp: 00059ms  SCall: 00185  DCall: 00003 [66.225.202.72:4569]
   USERNAME        : cr4z3d
   DATE TIME       : 2007-03-22  12:37:04
   REFRESH         : 60
   APPARENT ADDRES : IPV4 70.162.117.14:4569

Mar 22 10:37:03 DEBUG[16721]: chan_iax2.c:6623 socket_read: Received packet 1, (6, 15)
Mar 22 10:37:03 DEBUG[16721]: chan_iax2.c:6720 socket_read: Cancelling transmission of packet 1
Mar 22 10:37:03 DEBUG[16721]: chan_iax2.c:6817 socket_read: IAX subclass 15 received
Mar 22 10:37:03 DEBUG[16721]: chan_iax2.c:6826 socket_read: For call=3, set last=59
    -- Registered IAX2 to '66.225.202.72', who sees us as 70.162.117.14:4569 with no messages waiting

Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK    
   Timestamp: 00059ms  SCall: 00003  DCall: 00185 [66.225.202.72:4569]
Mar 22 10:37:53 DEBUG[16721]: chan_iax2.c:7743 iax2_do_register: Sending registration request for 'cr4z3d'
Mar 22 10:37:53 DEBUG[16721]: chan_iax2.c:7746 iax2_do_register: Allocate call number
Mar 22 10:37:53 DEBUG[16721]: chan_iax2.c:1006 update_max_nontrunk: New max nontrunk callno is 2
Mar 22 10:37:53 DEBUG[16721]: chan_iax2.c:1105 find_callno: Creating new call structure 1
Mar 22 10:37:53 DEBUG[16721]: chan_iax2.c:7752 iax2_do_register: Registration created on call 1
Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: REGREQ 
   Timestamp: 00006ms  SCall: 00001  DCall: 00000 [66.225.202.72:4569]
   USERNAME        : cr4z3d
   REFRESH         : 60

Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: REGAUTH
   Timestamp: 00018ms  SCall: 00215  DCall: 00001 [66.225.202.72:4569]
   AUTHMETHODS     : 3
   CHALLENGE       : 764687202
   USERNAME        : cr4z3d

Mar 22 10:37:53 DEBUG[16721]: chan_iax2.c:6623 socket_read: Received packet 0, (6, 14)
Mar 22 10:37:53 DEBUG[16721]: chan_iax2.c:6720 socket_read: Cancelling transmission of packet 0
Mar 22 10:37:53 DEBUG[16721]: chan_iax2.c:6817 socket_read: IAX subclass 14 received
Mar 22 10:37:53 DEBUG[16721]: chan_iax2.c:6826 socket_read: For call=1, set last=18
Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: REGREQ 
   Timestamp: 00063ms  SCall: 00001  DCall: 00215 [66.225.202.72:4569]
   USERNAME        : cr4z3d
   REFRESH         : 60
   MD5 RESULT      : 82f5b503511029991d6f600f92a75bc3

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: REGACK 
   Timestamp: 00074ms  SCall: 00215  DCall: 00001 [66.225.202.72:4569]
   USERNAME        : cr4z3d
   DATE TIME       : 2007-03-22  12:37:54
   REFRESH         : 60
   APPARENT ADDRES : IPV4 70.162.117.14:4569

Mar 22 10:37:53 DEBUG[16721]: chan_iax2.c:6623 socket_read: Received packet 1, (6, 15)
Mar 22 10:37:53 DEBUG[16721]: chan_iax2.c:6720 socket_read: Cancelling transmission of packet 1
Mar 22 10:37:53 DEBUG[16721]: chan_iax2.c:6817 socket_read: IAX subclass 15 received
Mar 22 10:37:53 DEBUG[16721]: chan_iax2.c:6826 socket_read: For call=1, set last=74
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK    
   Timestamp: 00074ms  SCall: 00001  DCall: 00215 [66.225.202.72:4569]
Mar 22 10:38:43 DEBUG[16721]: chan_iax2.c:7743 iax2_do_register: Sending registration request for 'cr4z3d'
Mar 22 10:38:43 DEBUG[16721]: chan_iax2.c:7746 iax2_do_register: Allocate call number
Mar 22 10:38:43 DEBUG[16721]: chan_iax2.c:1006 update_max_nontrunk: New max nontrunk callno is 3
Mar 22 10:38:43 DEBUG[16721]: chan_iax2.c:1105 find_callno: Creating new call structure 2
Mar 22 10:38:43 DEBUG[16721]: chan_iax2.c:7752 iax2_do_register: Registration created on call 2
Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: REGREQ 
   Timestamp: 00002ms  SCall: 00002  DCall: 00000 [66.225.202.72:4569]
   USERNAME        : cr4z3d
   REFRESH         : 60

Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: REGAUTH
   Timestamp: 00016ms  SCall: 00088  DCall: 00002 [66.225.202.72:4569]
   AUTHMETHODS     : 3
   CHALLENGE       : 185129575
   USERNAME        : cr4z3d

Mar 22 10:38:43 DEBUG[16721]: chan_iax2.c:6623 socket_read: Received packet 0, (6, 14)
Mar 22 10:38:43 DEBUG[16721]: chan_iax2.c:6720 socket_read: Cancelling transmission of packet 0
Mar 22 10:38:43 DEBUG[16721]: chan_iax2.c:6817 socket_read: IAX subclass 14 received
Mar 22 10:38:43 DEBUG[16721]: chan_iax2.c:6826 socket_read: For call=2, set last=16
Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: REGREQ 
   Timestamp: 00441ms  SCall: 00002  DCall: 00088 [66.225.202.72:4569]
   USERNAME        : cr4z3d
   REFRESH         : 60
   MD5 RESULT      : 970dec3e15e05650535a121365020b4c

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: REGACK 
   Timestamp: 00074ms  SCall: 00088  DCall: 00002 [66.225.202.72:4569]
   USERNAME        : cr4z3d
   DATE TIME       : 2007-03-22  12:38:44
   REFRESH         : 60
   APPARENT ADDRES : IPV4 70.162.117.14:4569

Mar 22 10:38:44 DEBUG[16721]: chan_iax2.c:6623 socket_read: Received packet 1, (6, 15)
Mar 22 10:38:44 DEBUG[16721]: chan_iax2.c:6720 socket_read: Cancelling transmission of packet 1
Mar 22 10:38:44 DEBUG[16721]: chan_iax2.c:6817 socket_read: IAX subclass 15 received
Mar 22 10:38:44 DEBUG[16721]: chan_iax2.c:6826 socket_read: For call=2, set last=74
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK    
   Timestamp: 00074ms  SCall: 00002  DCall: 00088 [66.225.202.72:4569]



cr4z3d@cr4z3d-laptop:~$ sudo tcpdump -i eth1 port 4569
tcpdump: verbose output suppressed, use -v or -vv for full protocol decode
listening on eth1, link-type EN10MB (Ethernet), capture size 96 bytes
10:36:50.877042 IP 192.168.1.109.iax > unknown.ord.scnet.net.iax: UDP, length 24
10:36:50.943201 IP unknown.ord.scnet.net.iax > 192.168.1.109.iax: UDP, length 35
10:36:50.945044 IP 192.168.1.109.iax > unknown.ord.scnet.net.iax: UDP, length 58
10:36:51.000985 IP unknown.ord.scnet.net.iax > 192.168.1.109.iax: UDP, length 48
10:36:51.001463 IP 192.168.1.109.iax > unknown.ord.scnet.net.iax: UDP, length 12
10:37:03.293768 IP 192.168.1.109.iax > unknown.ord.scnet.net.iax: UDP, length 24
10:37:03.348707 IP unknown.ord.scnet.net.iax > 192.168.1.109.iax: UDP, length 35
10:37:03.349968 IP 192.168.1.109.iax > unknown.ord.scnet.net.iax: UDP, length 58
10:37:03.404778 IP unknown.ord.scnet.net.iax > 192.168.1.109.iax: UDP, length 48
10:37:03.405968 IP 192.168.1.109.iax > unknown.ord.scnet.net.iax: UDP, length 12
10:37:53.407197 IP 192.168.1.109.iax > unknown.ord.scnet.net.iax: UDP, length 24
10:37:53.462884 IP unknown.ord.scnet.net.iax > 192.168.1.109.iax: UDP, length 35
10:37:53.464084 IP 192.168.1.109.iax > unknown.ord.scnet.net.iax: UDP, length 58
10:37:53.518348 IP unknown.ord.scnet.net.iax > 192.168.1.109.iax: UDP, length 48
10:37:53.520164 IP 192.168.1.109.iax > unknown.ord.scnet.net.iax: UDP, length 12
10:38:43.522304 IP 192.168.1.109.iax > unknown.ord.scnet.net.iax: UDP, length 24
10:38:43.960307 IP unknown.ord.scnet.net.iax > 192.168.1.109.iax: UDP, length 35
10:38:43.961617 IP 192.168.1.109.iax > unknown.ord.scnet.net.iax: UDP, length 58
10:38:44.017761 IP unknown.ord.scnet.net.iax > 192.168.1.109.iax: UDP, length 48
10:38:44.018853 IP 192.168.1.109.iax > unknown.ord.scnet.net.iax: UDP, length 12