WITH diff.chan_sip_devstate1.4debug APPLIED:
============================================

    -- Registered SIP 'snom' at 192.168.0.15 port 5060 expires 120
[Nov 10 17:29:32] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[snom]=NOT_INUSE
[Nov 10 17:29:32] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[snom]=NOT_INUSE
[Nov 10 17:29:32] NOTICE[13985]: chan_sip.c:11841 handle_response_peerpoke: Peer 'snom' is now Reachable. (2ms / 2000ms)
[Nov 10 17:29:32] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[snom]=NOT_INUSE
[Nov 10 17:29:32] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[snom]=NOT_INUSE
    -- Registered SIP 'xlite' at 192.168.0.15 port 53410 expires 3600
[Nov 10 17:29:39] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[xlite]=NOT_INUSE
[Nov 10 17:29:39] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[xlite]=NOT_INUSE
[Nov 10 17:29:39] NOTICE[13985]: chan_sip.c:11841 handle_response_peerpoke: Peer 'xlite' is now Reachable. (7ms / 2000ms)
[Nov 10 17:29:39] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[xlite]=NOT_INUSE
[Nov 10 17:29:39] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[xlite]=NOT_INUSE

gw-matriz*CLI> show queues
fila         has 0 calls (max unlimited) in '<unknown>' strategy (0s holdtime), W:0, C:0, A:0, SL:0.0% within 0s
   Members: I>
      SIP/xlite (dynamic) (Not in use) has taken no calls yet
      SIP/snom (dynamic) (Not in use) has taken no calls yet
   No Callers>

gw-matriz*CLI>
[Nov 10 17:30:03] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[xlite]=NOT_INUSE
[Nov 10 17:30:03] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[xlite]=NOT_INUSE
    -- Executing [1111@default:1] Queue("SIP/xlite-0a1bb780", "fila") in new stack
[Nov 10 17:30:03] DEBUG[14030]: channel.c:2476 ast_prod: Prodding channel 'SIP/xlite-0a1bb780'
    -- Started music on hold, class 'default', on SIP/xlite-0a1bb780
[Nov 10 17:30:03] DEBUG[14030]: channel.c:1908 ast_settimeout: Scheduling timer at 160 sample intervals
[Nov 10 17:30:03] WARNING[14030]: app_queue.c:2276 calc_metric: Can't calculate metric for unknown strategy 7
[Nov 10 17:30:03] WARNING[14030]: app_queue.c:2276 calc_metric: Can't calculate metric for unknown strategy 7
[Nov 10 17:30:03] DEBUG[14030]: channel.c:1908 ast_settimeout: Scheduling timer at 0 sample intervals
    -- SIP/snom-0a1c0c40 is ringing
[Nov 10 17:30:03] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[snom]=NOT_INUSE
[Nov 10 17:30:03] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[snom]=NOT_INUSE

gw-matriz*CLI> show queues
fila         has 1 calls (max unlimited) in '<unknown>' strategy (0s holdtime), W:0, C:0, A:0, SL:0.0% within 0s
   Members: I>
      SIP/xlite (dynamic) (Not in use) has taken no calls yet
      SIP/snom (dynamic) (Not in use) has taken no calls yet
   Callers:
      1. SIP/xlite-0a1bb780 (wait: 0:05, prio: 0)

[Nov 10 17:30:09] NOTICE[14030]: rtp.c:902 ast_rtcp_read: Unknown RTCP packet (pt=207) received from 192.168.0.15:19417
[Nov 10 17:30:15] NOTICE[14030]: rtp.c:902 ast_rtcp_read: Unknown RTCP packet (pt=207) received from 192.168.0.15:19417
    -- Nobody picked up in 15000 ms
[Nov 10 17:30:18] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[snom]=NOT_INUSE
[Nov 10 17:30:18] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[snom]=NOT_INUSE
[Nov 10 17:30:21] NOTICE[14030]: rtp.c:902 ast_rtcp_read: Unknown RTCP packet (pt=207) received from 192.168.0.15:19417
[Nov 10 17:30:23] WARNING[14030]: app_queue.c:2276 calc_metric: Can't calculate metric for unknown strategy 7
[Nov 10 17:30:23] WARNING[14030]: app_queue.c:2276 calc_metric: Can't calculate metric for unknown strategy 7
    -- SIP/snom-0a1c6ec0 is ringing
[Nov 10 17:30:24] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[snom]=NOT_INUSE
[Nov 10 17:30:24] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[snom]=NOT_INUSE
[Nov 10 17:30:27] NOTICE[14030]: rtp.c:902 ast_rtcp_read: Unknown RTCP packet (pt=207) received from 192.168.0.15:19417

gw-matriz*CLI> show queues
fila         has 1 calls (max unlimited) in '<unknown>' strategy (0s holdtime), W:0, C:0, A:0, SL:0.0% within 0s
   Members:
      SIP/xlite (dynamic) (Not in use) has taken no calls yet
      SIP/snom (dynamic) (Not in use) has taken no calls yet
   Callers:
      1. SIP/xlite-0a1bb780 (wait: 0:27, prio: 0)

[Nov 10 17:30:33] NOTICE[14030]: rtp.c:902 ast_rtcp_read: Unknown RTCP packet (pt=207) received from 192.168.0.15:19417
    -- SIP/snom-0a1c6ec0 answered SIP/xlite-0a1bb780
    -- Stopped music on hold on SIP/xlite-0a1bb780
[Nov 10 17:30:33] DEBUG[14030]: channel.c:1908 ast_settimeout: Scheduling timer at 0 sample intervals
    -- Packet2Packet bridging SIP/xlite-0a1bb780 and SIP/snom-0a1c6ec0
[Nov 10 17:30:33] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[snom]=NOT_INUSE
[Nov 10 17:30:33] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[snom]=NOT_INUSE
[Nov 10 17:30:33] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[xlite]=NOT_INUSE
[Nov 10 17:30:33] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[xlite]=NOT_INUSE

gw-matriz*CLI> show queues
fila         has 0 calls (max unlimited) in '<unknown>' strategy (7s holdtime), W:0, C:0, A:0, SL:0.0% within 0s
   Members: I>
      SIP/xlite (dynamic) (Not in use) has taken no calls yet
      SIP/snom (dynamic) (Not in use) has taken no calls yet
   No Callers>

gw-matriz*CLI>
[Nov 10 17:30:39] DEBUG[14030]: channel.c:3918 ast_channel_bridge: Returning from native bridge, channels: SIP/xlite-0a1bb780, SIP/snom-0a1c6ec0
  == Spawn extension (default, 1111, 1) exited non-zero on 'SIP/xlite-0a1bb780'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '"Jane Smith" <1234>'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '1234'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '1111'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'default'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'SIP/xlite-0a1bb780'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'SIP/snom-0a1c6ec0'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'Queue'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'fila'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '2006-11-10 17:30:03'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '2006-11-10 17:30:33'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '2006-11-10 17:30:39'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '36'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '6'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'ANSWERED'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is ''
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '1163190603.0'
[Nov 10 17:30:39] DEBUG[14030]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is ''
[Nov 10 17:30:39] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[snom]=NOT_INUSE
[Nov 10 17:30:39] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[snom]=NOT_INUSE
[Nov 10 17:30:39] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[xlite]=NOT_INUSE
[Nov 10 17:30:39] NOTICE[13966]: chan_sip.c:14895 sip_devicestate: devicestate[xlite]=NOT_INUSE



WITH diff.chan_sip_devstate1.2debug APPLIED:
============================================

gw-matriz*CLI> queue add member SIP/xlite to fila penalty 0
Added interface 'SIP/xlite' to queue 'fila'
[Nov 10 17:03:48] NOTICE[3065]: chan_sip.c:14892 sip_devicestate: devicestate[xlite] UNKNOWN (call_limit)

gw-matriz*CLI> queue add member SIP/snom to fila penalty 0
Added interface 'SIP/snom' to queue 'fila'
[Nov 10 17:03:51] NOTICE[3065]: chan_sip.c:14892 sip_devicestate: devicestate[snom] UNKNOWN (call_limit)

gw-matriz*CLI> show queues
fila         has 0 calls (max unlimited) in '<unknown>' strategy (0s holdtime), W:0, C:0, A:0, SL:0.0% within 0s
   Members: I>
      SIP/snom (dynamic) (Not in use) has taken no calls yet
      SIP/xlite (dynamic) (Not in use) has taken no calls yet
   No Callers>

gw-matriz*CLI>
    -- Executing [1111@default:1] Queue("SIP/xlite-09f6aa60", "fila") in new stack
[Nov 10 17:06:29] DEBUG[3100]: channel.c:2476 ast_prod: Prodding channel 'SIP/xlite-09f6aa60'
    -- Started music on hold, class 'default', on SIP/xlite-09f6aa60
[Nov 10 17:06:29] DEBUG[3100]: channel.c:1908 ast_settimeout: Scheduling timer at 160 sample intervals
[Nov 10 17:06:29] WARNING[3100]: app_queue.c:2276 calc_metric: Can't calculate metric for unknown strategy 7
[Nov 10 17:06:29] WARNING[3100]: app_queue.c:2276 calc_metric: Can't calculate metric for unknown strategy 7
[Nov 10 17:06:30] DEBUG[3100]: channel.c:1908 ast_settimeout: Scheduling timer at 0 sample intervals
[Nov 10 17:06:30] NOTICE[2644]: chan_sip.c:14892 sip_devicestate: devicestate[xlite] UNKNOWN (call_limit)
[Nov 10 17:06:30] NOTICE[2644]: chan_sip.c:14892 sip_devicestate: devicestate[xlite] UNKNOWN (call_limit)
    -- SIP/xlite-09f674d8 is ringing
[Nov 10 17:06:36] NOTICE[3100]: rtp.c:902 ast_rtcp_read: Unknown RTCP packet (pt=207) received from 192.168.0.15:64049

gw-matriz*CLI> show queues
fila         has 1 calls (max unlimited) in '<unknown>' strategy (0s holdtime), W:0, C:0, A:0, SL:0.0% within 0s
   Members: I>
      SIP/snom (dynamic) (Not in use) has taken no calls yet
      SIP/xlite (dynamic) (Ringing) has taken no calls yet
   Callers:
      1. SIP/xlite-09f6aa60 (wait: 0:11, prio: 0)

[Nov 10 17:06:42] NOTICE[3100]: rtp.c:902 ast_rtcp_read: Unknown RTCP packet (pt=207) received from 192.168.0.15:64049
    -- Nobody picked up in 15000 ms
[Nov 10 17:06:45] NOTICE[2644]: chan_sip.c:14892 sip_devicestate: devicestate[xlite] UNKNOWN (call_limit)
[Nov 10 17:06:45] NOTICE[2644]: chan_sip.c:14892 sip_devicestate: devicestate[xlite] UNKNOWN (call_limit)
[Nov 10 17:06:48] NOTICE[3100]: rtp.c:902 ast_rtcp_read: Unknown RTCP packet (pt=207) received from 192.168.0.15:64049
[Nov 10 17:06:50] WARNING[3100]: app_queue.c:2276 calc_metric: Can't calculate metric for unknown strategy 7
[Nov 10 17:06:50] WARNING[3100]: app_queue.c:2276 calc_metric: Can't calculate metric for unknown strategy 7
    -- SIP/snom-09f674d8 is ringing
[Nov 10 17:06:50] NOTICE[2644]: chan_sip.c:14892 sip_devicestate: devicestate[snom] UNKNOWN (call_limit)
[Nov 10 17:06:50] NOTICE[2644]: chan_sip.c:14892 sip_devicestate: devicestate[snom] UNKNOWN (call_limit)
[Nov 10 17:06:54] NOTICE[3100]: rtp.c:902 ast_rtcp_read: Unknown RTCP packet (pt=207) received from 192.168.0.15:64049

gw-matriz*CLI> show queues
fila         has 1 calls (max unlimited) in '<unknown>' strategy (0s holdtime), W:0, C:0, A:0, SL:0.0% within 0s
   Members:
      SIP/snom (dynamic) (Ringing) has taken no calls yet
      SIP/xlite (dynamic) (In use) has taken no calls yet
   Callers:
      1. SIP/xlite-09f6aa60 (wait: 0:25, prio: 0)

[Nov 10 17:07:00] NOTICE[3100]: rtp.c:902 ast_rtcp_read: Unknown RTCP packet (pt=207) received from 192.168.0.15:64049
    -- Stopped music on hold on SIP/xlite-09f6aa60
[Nov 10 17:07:00] DEBUG[3100]: channel.c:1908 ast_settimeout: Scheduling timer at 0 sample intervals
  == Spawn extension (default, 1111, 1) exited non-zero on 'SIP/xlite-09f6aa60'
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '"Jane Smith" <1234>'
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '1234'
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '1111'
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'default'
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'SIP/xlite-09f6aa60'
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is ''
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'Queue'
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'fila'
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '2006-11-10 17:06:29'
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is ''
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '2006-11-10 17:07:00'
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '31'
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '0'
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'BUSY'
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is ''
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '1163189189.8'
[Nov 10 17:07:00] DEBUG[3100]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is ''
[Nov 10 17:07:00] NOTICE[2644]: chan_sip.c:14892 sip_devicestate: devicestate[snom] UNKNOWN (call_limit)
[Nov 10 17:07:00] NOTICE[2644]: chan_sip.c:14892 sip_devicestate: devicestate[snom] UNKNOWN (call_limit)
[Nov 10 17:07:00] NOTICE[2644]: chan_sip.c:14892 sip_devicestate: devicestate[xlite] UNKNOWN (call_limit)
[Nov 10 17:07:00] NOTICE[2644]: chan_sip.c:14892 sip_devicestate: devicestate[xlite] UNKNOWN (call_limit)
[Nov 10 17:07:22] NOTICE[2644]: chan_sip.c:14892 sip_devicestate: devicestate[snom] UNKNOWN (call_limit)
[Nov 10 17:07:22] NOTICE[2644]: chan_sip.c:14892 sip_devicestate: devicestate[snom] UNKNOWN (call_limit)
[Nov 10 17:09:22] NOTICE[2644]: chan_sip.c:14892 sip_devicestate: devicestate[snom] UNKNOWN (call_limit)
[Nov 10 17:09:22] NOTICE[2644]: chan_sip.c:14892 sip_devicestate: devicestate[snom] UNKNOWN (call_limit)