Aug 11 10:40:20 DEBUG[1111694256]: pbx.c:1175 pbx_substitute_variables_helper: Expression is '0'
    -- Executing GotoIf("SIP/phone08a-c71d", "0?3:5") in new stack
    -- Goto (macro-wasatch-voicemail-extension,s,5)
    -- Executing Dial("SIP/phone08a-c71d", "SIP/phone04a|14") in new stack
Aug 11 10:40:20 DEBUG[1111694256]: chan_sip.c:1265 create_addr: Setting NAT on RTP to 0
Aug 11 10:40:20 DEBUG[1111694256]: chan_sip.c:1500 sip_call: Outgoing Call for phone04a
Aug 11 10:40:20 DEBUG[1111694256]: chan_sip.c:1633 update_user_counter: Call from user 'phone04a' is 1 out of 0
    -- Called phone04a
Aug 11 10:40:20 DEBUG[1087077296]: chan_sip.c:840 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '35ad76b42d74cb587c1dc6da2cc5c4e3@192.168.0.2' Request 102: Found
Aug 11 10:40:20 DEBUG[1087077296]: chan_sip.c:840 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '35ad76b42d74cb587c1dc6da2cc5c4e3@192.168.0.2' Request 102: Found
    -- SIP/phone04a-ed88 is ringing                                                                                                                          
Aug 11 10:40:24 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '6384b3b4662392bd21c021b210fff707@192.168.0.2' of Request 102: Found 
Aug 11 10:40:24 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '641b83da3bd1547130508c5011904f32@192.168.0.2' of Request 102: Found 
Aug 11 10:40:24 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '0c2c5cfb47afb1a743f30a546fb110af@192.168.0.2' of Request 102: Found 
Aug 11 10:40:24 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '797219ca25323232624ce3c65d8d9da4@192.168.0.2' of Request 102: Found 
Aug 11 10:40:25 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '522ce41508c1be885c5335995e594110@192.168.0.2' of Request 102: Found 
Aug 11 10:40:25 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '3885aa67671476da59698d4a31f7c431@192.168.0.2' of Request 102: Found 
Aug 11 10:40:25 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '639e28313120d5576e5d8a6e35cb0c47@192.168.0.2' of Request 102: Found 
Aug 11 10:40:25 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '4b60cd8b1c6c17de57f07b8203e677f2@192.168.0.2' of Request 102: Found 
Aug 11 10:40:25 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '63bfa8f63a1216ec5cbd472c2f207681@192.168.0.2' of Request 102: Found 
Aug 11 10:40:25 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '796bfd78789c64e34ec9f2a05d0a25aa@192.168.0.2' of Request 102: Found 
Aug 11 10:40:25 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '0e7ca05e724fb5366a2595d607e89dd6@192.168.0.2' of Request 102: Found 
Aug 11 10:40:25 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '70be7fa627e993195cc97f60547e289c@192.168.0.2' of Request 102: Found 
Aug 11 10:40:25 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '27549a0f4dd380ba6315e85f35d13a6d@192.168.0.2' of Request 102: Found 
Aug 11 10:40:25 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '7a314ecc393605ef1e3ebb606aefce72@192.168.0.2' of Request 102: Found 
Aug 11 10:40:25 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '76ffdf40193cdbe80a4c3dd41e54794f@192.168.0.2' of Request 102: Found 
Aug 11 10:40:25 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '3d0ab7464f00b8f949fd4dc4373c0612@192.168.0.2' of Request 102: Found 
Aug 11 10:40:25 DEBUG[1111694256]: chan_sip.c:1729 sip_hangup: update_user_counter(phone04a) - decrement outUse counter                                      
Aug 11 10:40:25 DEBUG[1111694256]: app_dial.c:974 dial_exec: Exiting with DIALSTATUS=CANCEL.                                                                 
  == Spawn extension (macro-wasatch-voicemail-extension, s, 5) exited non-zero on 'SIP/phone08a-c71d' in macro 'wasatch-voicemail-extension'
  == Spawn extension (wasatch-trusted, 116, 1) exited non-zero on 'SIP/phone08a-c71d'
Aug 11 10:40:25 DEBUG[1111694256]: cdr_addon_mysql.c:178 mysql_log: cdr_mysql: inserting a CDR record.                                                       
Aug 11 10:40:25 DEBUG[1111694256]: cdr_addon_mysql.c:197 mysql_log: cdr_mysql: SQL command as follows:  INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode) VALUES ('2004-08-11 10:40:20','\"Person 8\" <110>','110','116','wasatch-trusted', 'SIP/phone08a-c71d','SIP/phone04a-ed88','Dial','SIP/phone04a|14',5,0,'NO ANSWER',3,'')
Aug 11 10:40:25 DEBUG[1111694256]: chan_sip.c:1732 sip_hangup: update_user_counter(phone08a) - decrement inUse counter                                       
Aug 11 10:40:25 DEBUG[1087077296]: chan_sip.c:799 __sip_ack: Acked pending invite 102                                                                        
Aug 11 10:40:25 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '35ad76b42d74cb587c1dc6da2cc5c4e3@192.168.0.2' of Request 102: Found
Aug 11 10:40:25 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '35ad76b42d74cb587c1dc6da2cc5c4e3@192.168.0.2' of Request 102: Found 
    -- Stopped music on hold on Zap/8-1                                                                                                                      
Aug 11 10:40:34 DEBUG[1113795504]: rtp.c:1086 ast_rtp_raw_write: Difference is 187344, ms is 23438                                                           
Aug 11 10:40:35 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '6d99a31d635060ef6272c2b575632619@192.168.0.2' of Response 102: Found
Aug 11 10:40:36 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '29291c9b6a7c8fcb2a17ba326633d3e1@192.168.0.2' of Request 102: Found 
Aug 11 10:40:36 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '3a96cfbd1e58857f361469153196aefd@192.168.0.2' of Request 102: Found 
Aug 11 10:40:36 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '57689a3645dbdbcb7f82b17f11ff69f3@192.168.0.2' of Request 102: Found 
Aug 11 10:40:36 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '0c1b00216d82854e1865049335441cbc@192.168.0.2' of Request 102: Found 
    -- Started music on hold, class 'default', on Zap/8-1                                                                                                    
Aug 11 10:40:45 DEBUG[1087077296]: chan_sip.c:817 __sip_ack: Stopping retransmission on '6d99a31d635060ef6272c2b575632619@192.168.0.2' of Response 103: Found
Aug 11 10:40:56 DEBUG[1087077296]: chan_sip.c:748 __sip_autodestruct: Auto destroying call '4fc5c4fb-781839f3@192.168.0.9'                                   
Aug 11 10:40: