Calls dropped after 30 seconds, yes I searched these forums

Hi everyone,

I have a weird one. Originally my asterisk server was configured with NAT. Things have been working pretty well! Today when I came in (nothing changed) I found that every call was getting dropped at around 30 seconds. Research on these forums shows that this is generally related to having your NAT settings configured properly. As far as I know, mine were, but just to experiment, I disabled NATing and found that the server functions fine, except it is still dropping calls at around 30 seconds. Can anyone please be kind enough to point in the right research direction to resolve this? Here is a log file for your consideration as well, and much thanks ahead of time! SkyingOH, don’t be mad that I am asking a dumb question ;).

[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*72’ priority 3 to app-cf-on (0x959d720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*72’ priority 2 to app-cf-on (0x959d720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*72’ priority 1 to app-cf-on (0x959d720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Registered extension context ‘app-cf-off-any’ (0x9560e18) in local table 0xb7c5fe00; registrar: pbx_config
[May 18 12:23:39] VERBOSE[9817] pbx.c: – merging incls/swits/igpats from old(app-cf-off-any) to new(app-cf-off-any) context, registrar = pbx_ael
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Including context ‘app-cf-off-any-custom’ in context ‘app-cf-off-any’
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 9 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 8 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 7 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 6 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 5 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 4 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 3 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 2 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 1 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*74’ priority 13 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*74’ priority 12 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*74’ priority 11 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*74’ priority 10 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*74’ priority 9 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*74’ priority 8 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*74’ priority 7 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*74’ priority 6 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*74’ priority 5 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*74’ priority 4 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*74’ priority 3 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*74’ priority 2 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*74’ priority 1 to app-cf-off-any (0x9560e18)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Registered extension context ‘app-cf-off’ (0x97ea7d8) in local table 0xb7c5fe00; registrar: pbx_config
[May 18 12:23:39] VERBOSE[9817] pbx.c: – merging incls/swits/igpats from old(app-cf-off) to new(app-cf-off) context, registrar = pbx_ael
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Including context ‘app-cf-off-custom’ in context ‘app-cf-off’
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 9 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 8 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 7 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 6 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 5 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 4 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 3 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 2 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘sstate’ priority 1 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*73.’ priority 10 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*73.’ priority 9 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*73.’ priority 8 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*73.’ priority 7 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*73.’ priority 6 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*73.’ priority 5 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*73.’ priority 4 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*73.’ priority 3 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*73.’ priority 2 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*73.’ priority 1 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*73’ priority 9 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*73’ priority 8 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*73’ priority 7 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*73’ priority 6 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*73’ priority 5 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*73’ priority 4 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*73’ priority 3 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*73’ priority 2 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*73’ priority 1 to app-cf-off (0x97ea7d8)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Registered extension context ‘app-cf-busy-on’ (0x9513d80) in local table 0xb7c5fe00; registrar: pbx_config
[May 18 12:23:39] VERBOSE[9817] pbx.c: – merging incls/swits/igpats from old(app-cf-busy-on) to new(app-cf-busy-on) context, registrar = pbx_ael
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Including context ‘app-cf-busy-on-custom’ in context ‘app-cf-busy-on’
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90.’ priority 11 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*90.’ priority 10 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90.’ priority 9 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*90.’ priority 8 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90.’ priority 7 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*90.’ priority 6 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90.’ priority 5 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*90.’ priority 4 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90.’ priority 3 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*90.’ priority 2 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90.’ priority 1 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90’ priority 15 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90’ priority 14 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90’ priority 13 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90’ priority 12 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90’ priority 11 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90’ priority 10 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90’ priority 9 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90’ priority 8 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90’ priority 7 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90’ priority 6 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90’ priority 5 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90’ priority 4 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90’ priority 3 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90’ priority 2 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*90’ priority 1 to app-cf-busy-on (0x9513d80)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Registered extension context ‘app-cf-busy-off-any’ (0x956b260) in local table 0xb7c5fe00; registrar: pbx_config
[May 18 12:23:39] VERBOSE[9817] pbx.c: – merging incls/swits/igpats from old(app-cf-busy-off-any) to new(app-cf-busy-off-any) context, registrar = pbx_ael
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Including context ‘app-cf-busy-off-any-custom’ in context ‘app-cf-busy-off-any’
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*92’ priority 11 to app-cf-busy-off-any (0x956b260)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*92’ priority 10 to app-cf-busy-off-any (0x956b260)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*92’ priority 9 to app-cf-busy-off-any (0x956b260)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*92’ priority 8 to app-cf-busy-off-any (0x956b260)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*92’ priority 7 to app-cf-busy-off-any (0x956b260)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*92’ priority 6 to app-cf-busy-off-any (0x956b260)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*92’ priority 5 to app-cf-busy-off-any (0x956b260)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*92’ priority 4 to app-cf-busy-off-any (0x956b260)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*92’ priority 3 to app-cf-busy-off-any (0x956b260)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*92’ priority 2 to app-cf-busy-off-any (0x956b260)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*92’ priority 1 to app-cf-busy-off-any (0x956b260)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Registered extension context ‘app-cf-busy-off’ (0x9511720) in local table 0xb7c5fe00; registrar: pbx_config
[May 18 12:23:39] VERBOSE[9817] pbx.c: – merging incls/swits/igpats from old(app-cf-busy-off) to new(app-cf-busy-off) context, registrar = pbx_ael
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Including context ‘app-cf-busy-off-custom’ in context ‘app-cf-busy-off’
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*91.’ priority 8 to app-cf-busy-off (0x9511720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*91.’ priority 7 to app-cf-busy-off (0x9511720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*91.’ priority 6 to app-cf-busy-off (0x9511720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*91.’ priority 5 to app-cf-busy-off (0x9511720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*91.’ priority 4 to app-cf-busy-off (0x9511720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*91.’ priority 3 to app-cf-busy-off (0x9511720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*91.’ priority 2 to app-cf-busy-off (0x9511720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*91.’ priority 1 to app-cf-busy-off (0x9511720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*91’ priority 7 to app-cf-busy-off (0x9511720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*91’ priority 6 to app-cf-busy-off (0x9511720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*91’ priority 5 to app-cf-busy-off (0x9511720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*91’ priority 4 to app-cf-busy-off (0x9511720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*91’ priority 3 to app-cf-busy-off (0x9511720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*91’ priority 2 to app-cf-busy-off (0x9511720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*91’ priority 1 to app-cf-busy-off (0x9511720)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Registered extension context ‘app-speeddial-set’ (0x9723328) in local table 0xb7c5fe00; registrar: pbx_config
[May 18 12:23:39] VERBOSE[9817] pbx.c: – merging incls/swits/igpats from old(app-speeddial-set) to new(app-speeddial-set) context, registrar = pbx_ael
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Including context ‘app-speeddial-set-custom’ in context ‘app-speeddial-set’
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘t’ priority 1 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘3’ priority 1 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘2’ priority 1 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 5 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 4 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 3 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 2 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 1 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 16 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 15 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 14 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 13 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 12 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 11 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 10 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 9 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 8 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 7 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 6 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 5 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 4 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 3 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 2 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 1 to app-speeddial-set (0x9723328)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Registered extension context ‘app-speeddial’ (0x96cd040) in local table 0xb7c5fe00; registrar: pbx_config
[May 18 12:23:39] VERBOSE[9817] pbx.c: – merging incls/swits/igpats from old(app-speeddial) to new(app-speeddial) context, registrar = pbx_ael
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Including context ‘app-speeddial-custom’ in context ‘app-speeddial’
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*75’ priority 1 to app-speeddial (0x96cd040)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*0.’ priority 105 to app-speeddial (0x96cd040)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*0.’ priority 104 to app-speeddial (0x96cd040)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*0.’ priority 5 to app-speeddial (0x96cd040)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*0.’ priority 4 to app-speeddial (0x96cd040)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*0.’ priority 3 to app-speeddial (0x96cd040)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*0.’ priority 2 to app-speeddial (0x96cd040)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension '
*0.’ priority 1 to app-speeddial (0x96cd040)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Registered extension context ‘macro-speeddial-lookup’ (0x9626c08) in local table 0xb7c5fe00; registrar: pbx_config
[May 18 12:23:39] VERBOSE[9817] pbx.c: – merging incls/swits/igpats from old(macro-speeddial-lookup) to new(macro-speeddial-lookup) context, registrar = pbx_ael
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Including context ‘macro-speeddial-lookup-custom’ in context ‘macro-speeddial-lookup’
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 206 to macro-speeddial-lookup (0x9626c08)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 205 to macro-speeddial-lookup (0x9626c08)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 107 to macro-speeddial-lookup (0x9626c08)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 106 to macro-speeddial-lookup (0x9626c08)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 105 to macro-speeddial-lookup (0x9626c08)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 104 to macro-speeddial-lookup (0x9626c08)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 6 to macro-speeddial-lookup (0x9626c08)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 5 to macro-speeddial-lookup (0x9626c08)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 4 to macro-speeddial-lookup (0x9626c08)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 3 to macro-speeddial-lookup (0x9626c08)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 2 to macro-speeddial-lookup (0x9626c08)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 1 to macro-speeddial-lookup (0x9626c08)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Registered extension context ‘app-blacklist-remove’ (0x94f9710) in local table 0xb7c5fe00; registrar: pbx_config
[May 18 12:23:39] VERBOSE[9817] pbx.c: – merging incls/swits/igpats from old(app-blacklist-remove) to new(app-blacklist-remove) context, registrar = pbx_ael
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Including context ‘app-blacklist-remove-custom’ in context ‘app-blacklist-remove’
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 4 to app-blacklist-remove (0x94f9710)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 3 to app-blacklist-remove (0x94f9710)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 2 to app-blacklist-remove (0x94f9710)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 1 to app-blacklist-remove (0x94f9710)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 11 to app-blacklist-remove (0x94f9710)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 10 to app-blacklist-remove (0x94f9710)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 9 to app-blacklist-remove (0x94f9710)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 8 to app-blacklist-remove (0x94f9710)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 7 to app-blacklist-remove (0x94f9710)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 6 to app-blacklist-remove (0x94f9710)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 5 to app-blacklist-remove (0x94f9710)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 4 to app-blacklist-remove (0x94f9710)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 3 to app-blacklist-remove (0x94f9710)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 2 to app-blacklist-remove (0x94f9710)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 1 to app-blacklist-remove (0x94f9710)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Registered extension context ‘app-blacklist-last’ (0x96ea780) in local table 0xb7c5fe00; registrar: pbx_config
[May 18 12:23:39] VERBOSE[9817] pbx.c: – merging incls/swits/igpats from old(app-blacklist-last) to new(app-blacklist-last) context, registrar = pbx_ael
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Including context ‘app-blacklist-last-custom’ in context ‘app-blacklist-last’
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 5 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 4 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 3 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 2 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 1 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 15 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 14 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 13 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 12 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 11 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 10 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 9 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 8 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 7 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 6 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 5 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 4 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 3 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 2 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 1 to app-blacklist-last (0x96ea780)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Registered extension context ‘app-blacklist-add-invalid’ (0x9516340) in local table 0xb7c5fe00; registrar: pbx_config
[May 18 12:23:39] VERBOSE[9817] pbx.c: – merging incls/swits/igpats from old(app-blacklist-add-invalid) to new(app-blacklist-add-invalid) context, registrar = pbx_ael
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Including context ‘app-blacklist-add-invalid-custom’ in context ‘app-blacklist-add-invalid’
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 5 to app-blacklist-add-invalid (0x9516340)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 4 to app-blacklist-add-invalid (0x9516340)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 3 to app-blacklist-add-invalid (0x9516340)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 2 to app-blacklist-add-invalid (0x9516340)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 1 to app-blacklist-add-invalid (0x9516340)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Registered extension context ‘app-blacklist-add’ (0x9564a20) in local table 0xb7c5fe00; registrar: pbx_config
[May 18 12:23:39] VERBOSE[9817] pbx.c: – merging incls/swits/igpats from old(app-blacklist-add) to new(app-blacklist-add) context, registrar = pbx_ael
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Including context ‘app-blacklist-add-custom’ in context ‘app-blacklist-add’
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 5 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 4 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 3 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 2 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘1’ priority 1 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 12 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 11 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 10 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 9 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 8 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 7 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 6 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 5 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 4 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 3 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 2 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 1 to app-blacklist-add (0x9564a20)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Registered extension context ‘app-blacklist’ (0x9812b60) in local table 0xb7c5fe00; registrar: pbx_config
[May 18 12:23:39] VERBOSE[9817] pbx.c: – merging incls/swits/igpats from old(app-blacklist) to new(app-blacklist) context, registrar = pbx_ael
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Including context ‘app-blacklist-custom’ in context ‘app-blacklist’
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*31’ priority 1 to app-blacklist (0x9812b60)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*32’ priority 1 to app-blacklist (0x9812b60)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘*30’ priority 1 to app-blacklist (0x9812b60)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Registered extension context ‘app-blacklist-check’ (0x95fb680) in local table 0xb7c5fe00; registrar: pbx_config
[May 18 12:23:39] VERBOSE[9817] pbx.c: – merging incls/swits/igpats from old(app-blacklist-check) to new(app-blacklist-check) context, registrar = pbx_ael
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Including context ‘app-blacklist-check-custom’ in context ‘app-blacklist-check’
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 8 to app-blacklist-check (0x95fb680)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 7 to app-blacklist-check (0x95fb680)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 6 to app-blacklist-check (0x95fb680)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 5 to app-blacklist-check (0x95fb680)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 4 to app-blacklist-check (0x95fb680)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 3 to app-blacklist-check (0x95fb680)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 2 to app-blacklist-check (0x95fb680)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 1 to app-blacklist-check (0x95fb680)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Registered extension context ‘macro-hangupcall’ (0x95ba878) in local table 0xb7c5fe00; registrar: pbx_config
[May 18 12:23:39] VERBOSE[9817] pbx.c: – merging incls/swits/igpats from old(macro-hangupcall) to new(macro-hangupcall) context, registrar = pbx_ael
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Including context ‘macro-hangupcall-custom’ in context ‘macro-hangupcall’
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 12 to macro-hangupcall (0x95ba878)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 11 to macro-hangupcall (0x95ba878)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 10 to macro-hangupcall (0x95ba878)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 9 to macro-hangupcall (0x95ba878)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 8 to macro-hangupcall (0x95ba878)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 7 to macro-hangupcall (0x95ba878)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 6 to macro-hangupcall (0x95ba878)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 5 to macro-hangupcall (0x95ba878)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 4 to macro-hangupcall (0x95ba878)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 3 to macro-hangupcall (0x95ba878)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 2 to macro-hangupcall (0x95ba878)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 1 to macro-hangupcall (0x95ba878)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Registered extension context ‘macro-record-enable’ (0x95c4cd0) in local table 0xb7c5fe00; registrar: pbx_config
[May 18 12:23:39] VERBOSE[9817] pbx.c: – merging incls/swits/igpats from old(macro-record-enable) to new(macro-record-enable) context, registrar = pbx_ael
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Including context ‘macro-record-enable-custom’ in context ‘macro-record-enable’
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 25 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 24 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 23 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 22 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 21 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 20 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 19 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 18 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 17 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 16 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 15 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 14 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 13 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 12 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 11 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 10 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 9 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 8 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 7 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 6 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 5 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 4 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 3 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 2 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Added extension ‘s’ priority 1 to macro-record-enable (0x95c4cd0)
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Time to scan old dialplan and merge leftovers back into the new: 0.033291 sec
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Time to restore hints and swap in new dialplan: 0.000006 sec
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Time to delete the old dialplan: 0.001290 sec
[May 18 12:23:39] VERBOSE[9817] pbx.c: – Total time merge_contexts_delete: 0.034587 sec

I don’t get mad! You didn’t need to post all tnat dial plan log. It is meaningless. It’s not a dial-plan issue, it’s NAT. You did not provide any of your NAT details so it’s hard to be specific.

I would check if your IP address has changed and/or DNS broken.

Thanks Skying.

I am running FreePBX 2.9.0.7 on a custom built box, but mem and cpu loads are all good.

I have internet service with AT&T and my DID is through VOIP.ms.
Freepbx is configured with NAT, Static IP. Local networks 192.168.1.0 / 255.255.255.0

I noticed shortly after my original post that VOIP.ms was having issues with the server I was routed through. However, these issues have been reported as resolved and I am still having the same problem. I even switched my routing through a different VOIP.ms server.

I have been having weird issues like intermittently, but they seem to have massively escalated today.

Additionally, FreePBX reports that my SSH server is not running, but localhost is setup to point to 127.0.0.1 in my hosts file and I have killed my firewall. I am also able to SSH from a terminal. I don’t know if this is useful information or not, just thought I would let you know.

What other information would it be helpful for me to provide?

Thanks!

If your localnet matches that’s half the battle. You need to make sure your externip/externhost is working.

You should be able to do a debug RTP and determine where Asterisk is trying to send the packets and timing out (the 30 seconds show that Asterisk can’t validate the reverse path on the media).

Hi, hope this isn’t too dense. I’m not exactly sure what I am looking for in the debugging stuff, but I just dropped a call and tried to copy what was available. Does this tell you anything? If not I will keep looking. Anything I should be looking for specifically, am running the proper debugger?

<------------>
– Executing [h@macro-dial:1] Macro(“SIP/voipms-0000004f”, “hangupcall”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“SIP/voipms-0000004f”, “1?noautomon”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [s@macro-hangupcall:3] NoOp(“SIP/voipms-0000004f”, “TOUCH_MONITOR_OUTPUT=”) in new stack
– Executing [s@macro-hangupcall:4] GotoIf(“SIP/voipms-0000004f”, “1?skiprg”) in new stack
– Goto (macro-hangupcall,s,7)
– Executing [s@macro-hangupcall:7] GotoIf(“SIP/voipms-0000004f”, “1?skipblkvm”) in new stack
– Goto (macro-hangupcall,s,10)
– Executing [s@macro-hangupcall:10] GotoIf(“SIP/voipms-0000004f”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,12)
– Executing [s@macro-hangupcall:12] Hangup(“SIP/voipms-0000004f”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 12) exited non-zero on ‘SIP/voipms-0000004f’ in macro 'hangupcall’
Scheduling destruction of SIP dialog ‘[email protected]’ in 6400 ms (Method: INVITE)
set_destination: Parsing sip:[email protected]:5060 for address/port to send to
set_destination: set destination to 192.168.1.91, port 5060
Reliably Transmitting (NAT) to 192.168.1.91:5060:
BYE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.75:5060;branch=z9hG4bK5d1b2a77;rport
Max-Forwards: 70
From: “8479898002” sip:[email protected];tag=as44f05b3b
To: sip:[email protected]:5060;tag=becd6b8f7503c33ai0
Call-ID: [email protected]
CSeq: 103 BYE
User-Agent: FPBX-2.9.0(1.6.2.13)
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0


== Spawn extension (macro-dial, s, 7) exited non-zero on ‘SIP/voipms-0000004f’ in macro ‘dial’
== Spawn extension (ext-group, 100, 12) exited non-zero on ‘SIP/voipms-0000004f’

<— SIP read from UDP:192.168.1.91:5060 —>
SIP/2.0 200 OK
To: sip:[email protected]:5060;tag=becd6b8f7503c33ai0
From: “8479898002” sip:[email protected];tag=as44f05b3b
Call-ID: [email protected]
CSeq: 103 BYE
Via: SIP/2.0/UDP 192.168.1.75:5060;branch=z9hG4bK5d1b2a77
Server: Cisco/SPA303-7.4.5
Content-Length: 0

<------------->
— (8 headers 0 lines) —
Really destroying SIP dialog ‘[email protected]’ Method: BYE
Really destroying SIP dialog ‘[email protected]’ Method: INVITE

Do a

tail -f /var/log/asterisk/full

If you’re using Putty, make sure to set the screen buffer to 1000 to copy out more text otherwise, it will be right on top and gone with newer entries.

Then make it break, then copy over the logs, that one above doesn’t seem to say why it hungup but the hangup (h) channel has already kicked in …

Correction: I’ll see what I can identify from the tail. Thanks!

<------------->
[May 20 13:17:29] VERBOSE[5836] chan_sip.c: — (8 headers 0 lines) —
[May 20 13:17:29] VERBOSE[5836] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: INVITE
[May 20 13:17:45] VERBOSE[5836] chan_sip.c: Reliably Transmitting (NAT) to 192.168.1.93:5060:
OPTIONS sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.75:5060;branch=z9hG4bK4d2f3cfe;rport
Max-Forwards: 70
From: “Unknown” sip:[email protected];tag=as6bf1ae45
To: sip:[email protected]:5060
Contact: sip:[email protected]
Call-ID: [email protected]
CSeq: 102 OPTIONS
User-Agent: FPBX-2.9.0(1.6.2.13)
Date: Sun, 20 May 2012 18:17:45 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Length: 0


[May 20 13:17:45] VERBOSE[5836] chan_sip.c:
<— SIP read from UDP:192.168.1.93:5060 —>
SIP/2.0 200 OK
To: sip:[email protected]:5060;tag=4974e9aad5e05116i0
From: “Unknown” sip:[email protected];tag=as6bf1ae45
Call-ID: [email protected]
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 192.168.1.75:5060;branch=z9hG4bK4d2f3cfe
Server: Cisco/SPA303-7.4.5
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces

<------------->
[May 20 13:17:45] VERBOSE[5836] chan_sip.c: — (10 headers 0 lines) —
[May 20 13:17:45] VERBOSE[5836] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: OPTIONS
[May 20 13:17:45] VERBOSE[5836] chan_sip.c: Reliably Transmitting (NAT) to 192.168.1.91:5060:
OPTIONS sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.75:5060;branch=z9hG4bK02f21b7d;rport
Max-Forwards: 70
From: “Unknown” sip:[email protected];tag=as2d67d7d7
To: sip:[email protected]:5060
Contact: sip:[email protected]
Call-ID: [email protected]
CSeq: 102 OPTIONS
User-Agent: FPBX-2.9.0(1.6.2.13)
Date: Sun, 20 May 2012 18:17:45 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Length: 0


[May 20 13:17:45] VERBOSE[5836] chan_sip.c:
<— SIP read from UDP:192.168.1.91:5060 —>
SIP/2.0 200 OK
To: sip:[email protected]:5060;tag=8677e7ccaa539847i0
From: “Unknown” sip:[email protected];tag=as2d67d7d7
Call-ID: [email protected]
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 192.168.1.75:5060;branch=z9hG4bK02f21b7d
Server: Cisco/SPA303-7.4.5
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE, MESSAGE
Supported: replaces

<------------->
[May 20 13:17:45] VERBOSE[5836] chan_sip.c: — (10 headers 0 lines) —
[May 20 13:17:45] VERBOSE[5836] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: OPTIONS
[May 20 13:17:47] VERBOSE[5836] chan_sip.c: Reliably Transmitting (NAT) to 64.120.22.242:5060:
OPTIONS sip:64.120.22.242 SIP/2.0
Via: SIP/2.0/UDP 99.42.189.74:5060;branch=z9hG4bK633f47ac;rport
Max-Forwards: 70
From: “Unknown” sip:[email protected];tag=as50d2947b
To: sip:64.120.22.242
Contact: sip:[email protected]
Call-ID: [email protected]
CSeq: 102 OPTIONS
User-Agent: FPBX-2.9.0(1.6.2.13)
Date: Sun, 20 May 2012 18:17:47 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Length: 0


[May 20 13:17:48] VERBOSE[5836] chan_sip.c:
<— SIP read from UDP:64.120.22.242:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.75:5060;branch=z9hG4bK633f47ac;received=192.168.1.75;rport=5060
From: “Unknown” sip:[email protected]:5060;tag=as50d2947b
To: sip:64.120.22.242;tag=as4c67d702
Call-ID: [email protected]
CSeq: 102 OPTIONS
User-Agent: VoIPMS/SERAST
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces
Contact: sip:64.120.22.242
Accept: application/sdp
Content-Length: 0

<------------->
[May 20 13:17:48] VERBOSE[5836] chan_sip.c: — (12 headers 0 lines) —
[May 20 13:17:48] VERBOSE[5836] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: OPTIONS
[May 20 13:17:50] VERBOSE[5836] chan_sip.c:
<— SIP read from UDP:64.120.22.242:5060 —>
BYE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 64.120.22.242:5060;branch=z9hG4bK34848621;rport
From: “7732552539” sip:[email protected];tag=as6e017016
To: sip:[email protected]:5060;tag=as4763e1fe
Call-ID: [email protected]
CSeq: 103 BYE
User-Agent: VoIPMS/SERAST
Max-Forwards: 70
Remote-Party-ID: “7732552539” sip:[email protected];privacy=off;screen=no
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0

<------------->
[May 20 13:17:50] VERBOSE[5836] chan_sip.c: — (12 headers 0 lines) —
[May 20 13:17:50] VERBOSE[5836] chan_sip.c: Sending to 64.120.22.242 : 5060 (NAT)
[May 20 13:17:50] VERBOSE[5836] chan_sip.c:
<— Transmitting (NAT) to 64.120.22.242:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 64.120.22.242:5060;branch=z9hG4bK34848621;received=64.120.22.242;rport=5060
From: “7732552539” sip:[email protected];tag=as6e017016
To: sip:[email protected]:5060;tag=as4763e1fe
Call-ID: [email protected]
CSeq: 103 BYE
Server: FPBX-2.9.0(1.6.2.13)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Length: 0

<------------>
[May 20 13:17:50] VERBOSE[8389] pbx.c: – Executing [h@macro-dial:1] Macro(“SIP/voipms-0000001e”, “hangupcall”) in new stack
[May 20 13:17:50] VERBOSE[8389] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/voipms-0000001e”, “1?noautomon”) in new stack
[May 20 13:17:50] VERBOSE[8389] pbx.c: – Goto (macro-hangupcall,s,3)
[May 20 13:17:50] VERBOSE[8389] pbx.c: – Executing [s@macro-hangupcall:3] NoOp(“SIP/voipms-0000001e”, “TOUCH_MONITOR_OUTPUT=”) in new stack
[May 20 13:17:50] VERBOSE[8389] pbx.c: – Executing [s@macro-hangupcall:4] GotoIf(“SIP/voipms-0000001e”, “1?skiprg”) in new stack
[May 20 13:17:50] VERBOSE[8389] pbx.c: – Goto (macro-hangupcall,s,7)
[May 20 13:17:50] VERBOSE[8389] pbx.c: – Executing [s@macro-hangupcall:7] GotoIf(“SIP/voipms-0000001e”, “1?skipblkvm”) in new stack
[May 20 13:17:50] VERBOSE[8389] pbx.c: – Goto (macro-hangupcall,s,10)
[May 20 13:17:50] VERBOSE[8389] pbx.c: – Executing [s@macro-hangupcall:10] GotoIf(“SIP/voipms-0000001e”, “1?theend”) in new stack
[May 20 13:17:50] VERBOSE[8389] pbx.c: – Goto (macro-hangupcall,s,12)
[May 20 13:17:50] VERBOSE[8389] pbx.c: – Executing [s@macro-hangupcall:12] Hangup(“SIP/voipms-0000001e”, “”) in new stack
[May 20 13:17:50] VERBOSE[8389] app_macro.c: == Spawn extension (macro-hangupcall, s, 12) exited non-zero on ‘SIP/voipms-0000001e’ in macro ‘hangupcall’
[May 20 13:17:50] VERBOSE[8389] chan_sip.c: Scheduling destruction of SIP dialog ‘[email protected]’ in 6400 ms (Method: INVITE)
[May 20 13:17:50] VERBOSE[8389] chan_sip.c: set_destination: Parsing sip:[email protected]:5060 for address/port to send to
[May 20 13:17:50] VERBOSE[8389] chan_sip.c: set_destination: set destination to 192.168.1.91, port 5060
[May 20 13:17:50] VERBOSE[8389] chan_sip.c: Reliably Transmitting (NAT) to 192.168.1.91:5060:
BYE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.75:5060;branch=z9hG4bK72b22aa5;rport
Max-Forwards: 70
From: “7732552539” sip:[email protected];tag=as628d1d52
To: sip:[email protected]:5060;tag=495079bcbccd106ai0
Call-ID: [email protected]
CSeq: 103 BYE
User-Agent: FPBX-2.9.0(1.6.2.13)
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0


[May 20 13:17:50] VERBOSE[8389] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on ‘SIP/voipms-0000001e’ in macro ‘dial’
[May 20 13:17:50] VERBOSE[8389] pbx.c: == Spawn extension (ext-group, 100, 12) exited non-zero on ‘SIP/voipms-0000001e’
[May 20 13:17:50] VERBOSE[5836] chan_sip.c:
<— SIP read from UDP:192.168.1.91:5060 —>
SIP/2.0 200 OK
To: sip:[email protected]:5060;tag=495079bcbccd106ai0
From: “7732552539” sip:[email protected];tag=as628d1d52
Call-ID: [email protected]
CSeq: 103 BYE
Via: SIP/2.0/UDP 192.168.1.75:5060;branch=z9hG4bK72b22aa5
Server: Cisco/SPA303-7.4.5
Content-Length: 0

<------------->
[May 20 13:17:50] VERBOSE[5836] chan_sip.c: — (8 headers 0 lines) —
[May 20 13:17:50] VERBOSE[5836] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: BYE
[May 20 13:17:50] VERBOSE[5836] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: INVITE

That flow looks pretty mangled. You sure you don’t have a SIP ALG or ‘helper’ turned on in the firewall?

ya, I’m not sure about the SIP ALG. I have a Motorola NVG510. I tried “whitelisting” the server’s MAC address, but if anything that made it worse. Would it help if I disabled NAT in FreePBX and “whitelisted” the MAC?

When I try to add a custom “Hosted Application” to the router for SIP ports UDP 5060 - 5061, it tells me “Pinhole Conflict Detected XXX.” Does this tell you anything? Seems this router may well be the source of the problem.

If nothing else, if you can give me an idea of how the router / server combination should ideally be configured for an aDSL connection with AT&T, that’d be great. The weird thing is that things were working well enough before, maybe there was a firmware update or something.

Thanks!

logs from dropped call after setting a rule in the firewall to pass UDP traffic on port 5060… still looks mangled:


[May 20 14:37:53] VERBOSE[9053] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on ‘SIP/voipms-00000037’ in macro ‘dial’
[May 20 14:37:53] VERBOSE[9053] pbx.c: == Spawn extension (ext-group, 100, 12) exited non-zero on ‘SIP/voipms-00000037’
[May 20 14:37:53] VERBOSE[5836] chan_sip.c:
<— SIP read from UDP:192.168.1.91:5060 —>
SIP/2.0 200 OK
To: sip:[email protected]:5060;tag=5e0aa753f34ebdd3i0
From: “9416856257” sip:[email protected];tag=as3eb0e02d
Call-ID: [email protected]
CSeq: 103 BYE
Via: SIP/2.0/UDP 192.168.1.75:5060;branch=z9hG4bK7119b025
Server: Cisco/SPA303-7.4.5
Content-Length: 0

<------------->
[May 20 14:37:53] VERBOSE[5836] chan_sip.c: — (8 headers 0 lines) —
[May 20 14:37:53] VERBOSE[5836] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: BYE
[May 20 14:37:53] VERBOSE[5836] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: INVITE
[May 20 14:37:57] VERBOSE[5836] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: REGISTER
[May 20 14:38:12] VERBOSE[5836] chan_sip.c: Reliably Transmitting (NAT) to 192.168.1.87:5060:
OPTIONS sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.75:5060;branch=z9hG4bK1f5412a9;rport
Max-Forwards: 70
From: “Unknown” sip:[email protected];tag=as67fcf2c2
To: sip:[email protected]:5060
Contact: sip:[email protected]
Call-ID: [email protected]
CSeq: 102 OPTIONS
User-Agent: FPBX-2.9.0(1.6.2.13)
Date: Sun, 20 May 2012 19:38:12 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Length: 0


[May 20 14:38:12] VERBOSE[5836] chan_sip.c:
<— SIP read from UDP:192.168.1.87:5060 —>
SIP/2.0 200 OK
To: sip:[email protected]:5060;tag=5abc68772779918i0
From: “Unknown” sip:[email protected];tag=as67fcf2c2
Call-ID: [email protected]
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 192.168.1.75:5060;branch=z9hG4bK1f5412a9
Server: Cisco/SPA303-7.4.5
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces

<------------->
[May 20 14:38:12] VERBOSE[5836] chan_sip.c: — (10 headers 0 lines) —
[May 20 14:38:12] VERBOSE[5836] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: OPTIONS

Look for any mention of the word SIP, VoIP or voice in the router and turn it off.

You should not need to do any port forwards. If you want to for testing you need to do 5060 UDP and 10,000 say 10,400 UDP.

Make sure to change RTP end in /etc/asterisk/rtp.conf to match the port range and do an amportal restart.

Hi Everyone, couple more details:

Does this tell you anything:
[May 21 10:50:11] NOTICE[22754] rtp.c: Comfort noise support incomplete in Asterisk (RFC 3389). Please turn off on client if possible. Client IP: 64.120.22.242

The IP refers to 64.120.22.242.ubiquityservers.com - maybe a caller is using a softphone? I looked for ways to disable VAD on our devices, but I don’t think CISCO303s have it.

In noticed that there is an option to disable or enable NAT on my VOIP.ms control panel. It is enabled, is this correct if I have NAT enabled in Asterisk?

I have also disabled as much of the Firewall as possible, along with IPv6 on the Motorolla NVG510, but still dropping calls.

One last anomaly, I have NEVER had a call from my cell phone dropped… weird. One thing I did not mention is how the PBX is actually contacted. We have a T-Mobile cell phone that forwards all calls to the VOIP.ms number. Could this be the issue? I knwo this is weird to say the least, but we have our reasons.

FINALLY, I am in Chicago and the NATO summit is in full swing. Could this have something to do with things?

Thanks again! -J

Have you tried turning off the re-invite behavior in SIP settings?

I can’t seem to figure out positively who’s sending the initial BYE, looks like the remote end.

So, i think it could be that you’re not transmitting any “RTP audio” so the other side initiates a timeout and sends you a BYE.

So question 1:

When making a call, do both parties hear each other ok, despite it getting dropped at 30 secs?

Question 2:

If above is yes (all parties are hearing properly), you gotta check why is the remote side is sending you BYEs…

This is just from seeing a log, probably the next step is to tcpdump for SIP and sending the file over to some shared location to confirm this initial BYE.

Dump using

tcpdump -i eth0 -n -s 0 port 5060 -vvv -w /root/dumpdropcall.pcap

make sure eth0 is the eth that you are using.

Start before making that call and end capturing (ctrl+c) after a drop happens. Zip up the file and share it somewhere. Be sure to speak both sides :slight_smile:

Here is the dump, not sure if it will be helpful =/

I ended the sump right after the call ended. Let me know if it was premature and I will redo the dump. Nothing jumped out at me, but I’m the dumb one with the failed-PBX ;).

http://www.2shared.com/file/pv1Ho0Ad/dump.html

Do internal, i.e. extension to extension calls, drop?

Have you tried with another router? Have you power cycled your router? Have you factory-defaulted your router?

If everything was working okay one day and the next day it stopped working, and nothing else changed in your configuration on the day it started, then it is almost certainly your VOIP provider or your router.

Try another VOIP provider, i.e. Callcentric, and see if that affects anything. If it does, open a ticket with VOIP.ms.

You’re never dumb for asking for sure. The capture looks like its incomplete, you may wish to try again, make sure that

  1. Start capturing before you pickup your phone even
  2. Stop capturing after you hangup your phone

Anyway, from this file, it is clear that the remote side sends you a bye first.
21 42.565742 64.120.22.242 192.168.1.75 SIP 570 Request: BYE sip:[email protected]:5060

64.120.22.242 is sending a BYE to 192.168.1.75.

Looks like the other side is also using Asterisk based on some X header.

Hi everyone,

Here is a nightmare newb story for you all. I tried everything you all mentioned. Then I formatted my PBX, and rebuilt from the ground up. Still dropping calls. Then I called AT&T, upped my bandwidth, purchased a block of static IPs, redesigned my network so that there was a separate publicly addressable router handling all of the SIP traffic (all under the advise of AT&T Tier 2 support). Still dropping calls.

So, I cast my hands in the air, buried my pride, and called Nextiva to outsource the whole damn thing. The Nextiva sales lady, a lovely woman name Dana, informed me that they could not reliably offer service to an AT&T Uverse account, and that I might be best off figuring out the network issues before signing up with them. She put me in touch with their top level support. Miss tech support lady told me this: “Change the port you are routing your SIP traffic through to 5061 or 5062, not 5060. Newer AT&T equipment scrambles SIP traffic on port 5060.”

WTF! I say! I have opened the port on the router, made custom assignments to open port 5060 to UDP and TCP packets, and STILL, they scramble SIP traffic on port 5060? She says “I’m not sure, try it.”

Voila!

I ask, “why would they do such a thing…”

She is silent.

“I bet those jags are trying to force me to purchase VOIP service through them!”

She quietly responds, "maybe that’s it… "

SUMMARY: If you are using AT&T, make sure to route your traffic through a port other than 5060.