D110E randomly dropping calls

We're currently in the process of replacing our cisco system (which works fine on our UK ISDN30 PRI) with a Trixbox 2.6.2 (HP ML110, sata, D110E).

All works fine using a voiptalk siptrunk but when calls go via the D110E they keep randomly dropping (sometimes minutes, sometimes in seconds).

looks like the card is sharing an interrupt with the usb bus (which has nothing attached) but zttool shows no irq misses.

Looks to me like the D channel is dropping and causing the B channels to restart.

Any help would be much appreciated as this is the only thing stopping us going live.


output from cat /proc/interrupts


0: 6102892 IO-APIC-edge timer
1: 2 IO-APIC-edge i8042
7: 0 IO-APIC-edge parport0
8: 1 IO-APIC-edge rtc
9: 1 IO-APIC-level acpi
12: 4 IO-APIC-edge i8042
14: 54230 IO-APIC-edge ide0
169: 6055513 IO-APIC-level uhci_hcd:usb5, wcte11xp
209: 0 IO-APIC-level uhci_hcd:usb4
217: 0 IO-APIC-level ehci_hcd:usb1, uhci_hcd:usb2
225: 11998 IO-APIC-level uhci_hcd:usb3, ahci
233: 44193 IO-APIC-level eth0
NMI: 0
LOC: 6103046
ERR: 0
MIS: 0

**********************
zaptel.conf

span=1,1,0,ccs,hdb3
bchan=1-15,17-31
dchan=16
# Global data
loadzone = uk
defaultzone = uk

******************************
zapata.conf

prilocaldialplan=unknown
group=0
context=zap-in
callerid=asreceived
channel => 1-15,17-31
resetinterval=100000000

;faxdetect=both
;faxdetect=incoming
;faxdetect=outgoing
;faxdetect=no
;Include genzaptelconf configs
#include zapata-auto.conf

;Include AMP configs
#include zapata_additional.conf

**************************************************

debugs from pri

q921.c:782 q921_reset: q921_state now is Q921_LINK_CONNECTION_RELEASED
q921.c:733 q921_dchannel_up: q921_state now is Q921_LINK_CONNECTION_ESTABLISHED
q921.c:782 q921_reset: q921_state now is Q921_LINK_CONNECTION_RELEASED
Sending Set Asynchronous Balanced Mode Extended
q921.c:211 q921_send_sabme: q921_state now is Q921_AWAITING_ESTABLISH
-- Got UA from network peer Link up.
q921.c:782 q921_reset: q921_state now is Q921_LINK_CONNECTION_RELEASED
q921.c:733 q921_dchannel_up: q921_state now is Q921_LINK_CONNECTION_ESTABLISHED
== Primary D-Channel on span 1 up
-- Executing [9123@from-internal:1] Macro("SIP/1518-b7c02190", "user-callerid|SKIPTTL|") in new stack
-- Executing [s@macro-user-callerid:1] Set("SIP/1518-b7c02190", "AMPUSER=1518") in new stack
-- Executing [s@macro-user-callerid:2] GotoIf("SIP/1518-b7c02190", "0?report") in new stack
-- Executing [s@macro-user-callerid:3] ExecIf("SIP/1518-b7c02190", "1|Set|REALCALLERIDNUM=1518") in new stack
-- Executing [s@macro-user-callerid:4] Set("SIP/1518-b7c02190", "AMPUSER=1518") in new stack
-- Executing [s@macro-user-callerid:5] Set("SIP/1518-b7c02190", "AMPUSERCIDNAME=Stuart Clarkson") in new stack
-- Executing [s@macro-user-callerid:6] GotoIf("SIP/1518-b7c02190", "0?report") in new stack
-- Executing [s@macro-user-callerid:7] Set("SIP/1518-b7c02190", "AMPUSERCID=1518") in new stack
-- Executing [s@macro-user-callerid:8] Set("SIP/1518-b7c02190", "CALLERID(all)="Stuart Clarkson" <1518>") in new stack
-- Executing [s@macro-user-callerid:9] Set("SIP/1518-b7c02190", "REALCALLERIDNUM=1518") in new stack
-- Executing [s@macro-user-callerid:10] ExecIf("SIP/1518-b7c02190", "0|Set|CHANNEL(language)=") in new stack
-- Executing [s@macro-user-callerid:11] GotoIf("SIP/1518-b7c02190", "1?continue") in new stack
-- Goto (macro-user-callerid,s,20)
-- Executing [s@macro-user-callerid:20] NoOp("SIP/1518-b7c02190", "Using CallerID "Stuart Clarkson" <1518>") in new stack
-- Executing [9123@from-internal:2] Set("SIP/1518-b7c02190", "_NODEST=") in new stack
-- Executing [9123@from-internal:3] Macro("SIP/1518-b7c02190", "record-enable|1518|OUT|") in new stack
-- Executing [s@macro-record-enable:1] GotoIf("SIP/1518-b7c02190", "1?check") in new stack
-- Goto (macro-record-enable,s,4)
-- Executing [s@macro-record-enable:4] AGI("SIP/1518-b7c02190", "recordingcheck|20091117-181736|1258481856.0") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
recordingcheck|20091117-181736|1258481856.0: Outbound recording not enabled
-- AGI Script recordingcheck completed, returning 0
-- Executing [s@macro-record-enable:5] MacroExit("SIP/1518-b7c02190", "") in new stack
-- Executing [9123@from-internal:4] Macro("SIP/1518-b7c02190", "dialout-trunk|1|123|") in new stack
-- Executing [s@macro-dialout-trunk:1] Set("SIP/1518-b7c02190", "DIAL_TRUNK=1") in new stack
-- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/1518-b7c02190", "0?sub-pincheck|s|1") in new stack
-- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/1518-b7c02190", "0?disabletrunk|1") in new stack
-- Executing [s@macro-dialout-trunk:4] Set("SIP/1518-b7c02190", "DIAL_NUMBER=123") in new stack
-- Executing [s@macro-dialout-trunk:5] Set("SIP/1518-b7c02190", "DIAL_TRUNK_OPTIONS=tr") in new stack
-- Executing [s@macro-dialout-trunk:6] Set("SIP/1518-b7c02190", "OUTBOUND_GROUP=OUT_1") in new stack
-- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/1518-b7c02190", "1?nomax") in new stack
-- Goto (macro-dialout-trunk,s,9)
-- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/1518-b7c02190", "0?skipoutcid") in new stack
-- Executing [s@macro-dialout-trunk:10] Set("SIP/1518-b7c02190", "DIAL_TRUNK_OPTIONS=") in new stack
-- Executing [s@macro-dialout-trunk:11] Macro("SIP/1518-b7c02190", "outbound-callerid|1") in new stack
-- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/1518-b7c02190", "0|SetCallerPres|") in new stack
-- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/1518-b7c02190", "0|Set|REALCALLERIDNUM=1518") in new stack
-- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/1518-b7c02190", "1?normcid") in new stack
-- Goto (macro-outbound-callerid,s,6)
-- Executing [s@macro-outbound-callerid:6] Set("SIP/1518-b7c02190", "USEROUTCID=") in new stack
-- Executing [s@macro-outbound-callerid:7] Set("SIP/1518-b7c02190", "EMERGENCYCID=") in new stack
-- Executing [s@macro-outbound-callerid:8] Set("SIP/1518-b7c02190", "TRUNKOUTCID=") in new stack
-- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/1518-b7c02190", "1?trunkcid") in new stack
-- Goto (macro-outbound-callerid,s,12)
-- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/1518-b7c02190", "0|Set|CALLERID(all)=") in new stack
-- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/1518-b7c02190", "0|Set|CALLERID(all)=") in new stack
-- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/1518-b7c02190", "0|SetCallerPres|prohib_passed_screen") in new stack
-- Executing [s@macro-dialout-trunk:12] ExecIf("SIP/1518-b7c02190", "0|AGI|fixlocalprefix") in new stack
-- Executing [s@macro-dialout-trunk:13] Set("SIP/1518-b7c02190", "OUTNUM=123") in new stack
-- Executing [s@macro-dialout-trunk:14] Set("SIP/1518-b7c02190", "custom=ZAP/g0") in new stack
-- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/1518-b7c02190", "0|Set|DIAL_TRUNK_OPTIONS=M(setmusic^)") in new stack
-- Executing [s@macro-dialout-trunk:16] Macro("SIP/1518-b7c02190", "dialout-trunk-predial-hook|") in new stack
-- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/1518-b7c02190", "") in new stack
-- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/1518-b7c02190", "0?bypass|1") in new stack
-- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/1518-b7c02190", "0?customtrunk") in new stack
-- Executing [s@macro-dialout-trunk:19] Dial("SIP/1518-b7c02190", "ZAP/g0/123|300|") in new stack
-- Making new call for cr 32770
-- Requested transfer capability: 0x00 - SPEECH
> Protocol Discriminator: Q.931 ( len=29
> Call Ref: len= 2 (reference 2/0x2) (Originator)
> Message type: SETUP (5)
> [04 03 80 90 a3]
> Bearer Capability (len= 5) [ Ext: 1 Q.931 Std: 0 Info transfer capability: Speech (0)
> Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
> User information layer 1: A-Law (35)
> [18 03 a9 83 83]
> Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0
> ChanSel: As indicated in following octets
> Ext: 1 Coding: 0 Number Specified Channel Type: 3
> Ext: 1 Channel: 3 ]
> [6c 06 00 81 31 35 31 38]
> Calling Number (len= [ Ext: 0 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0)
> Presentation: Presentation permitted, user number passed network screening (1) '1518' ]
> [70 04 80 31 32 33]
> Called Number (len= 6) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '123' ]
q931.c:3092 q931_setup: call 32770 on channel 3 enters state 1 (Call Initiated)
-- Called g0/123
< Protocol Discriminator: Q.931 ( len=10
< Call Ref: len= 2 (reference 2/0x2) (Terminator)
< Message type: CALL PROCEEDING (2)
< [18 03 a9 83 83]
< Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0
< ChanSel: As indicated in following octets
< Ext: 1 Coding: 0 Number Specified Channel Type: 3
< Ext: 1 Channel: 3 ]
-- Processing IE 24 (cs0, Channel Identification)
q931.c:3641 q931_receive: call 32770 on channel 3 enters state 3 (Outgoing call Proceeding)
-- Zap/3-1 is proceeding passing it to SIP/1518-b7c02190
< Protocol Discriminator: Q.931 ( len=5
< Call Ref: len= 2 (reference 2/0x2) (Terminator)
< Message type: ALERTING (1)
q931.c:3554 q931_receive: call 32770 on channel 3 enters state 4 (Call Delivered)
-- Zap/3-1 is ringing
< Protocol Discriminator: Q.931 ( len=5
< Call Ref: len= 2 (reference 2/0x2) (Terminator)
< Message type: CONNECT (7)
q931.c:3584 q931_receive: call 32770 on channel 3 enters state 10 (Active)
> Protocol Discriminator: Q.931 ( len=5
> Call Ref: len= 2 (reference 2/0x2) (Originator)
> Message type: CONNECT ACKNOWLEDGE (15)
-- Zap/3-1 answered SIP/1518-b7c02190
> Protocol Discriminator: Q.931 ( len=13
> Call Ref: len= 2 (reference 0/0x0) (Originator)
> Message type: RESTART (70)
> [18 03 a9 83 84]
> Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0
> ChanSel: As indicated in following octets
> Ext: 1 Coding: 0 Number Specified Channel Type: 3
> Ext: 1 Channel: 4 ]
> [79 01 80]
> Restart Indentifier (len= 3) [ Ext: 1 Spare: 0 Resetting Indicated Channel (0) ]
< Protocol Discriminator: Q.931 ( len=13
< Call Ref: len= 2 (reference 0/0x0) (Terminator)
< Message type: RESTART ACKNOWLEDGE (78)
< [18 03 a9 83 84]
< Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0
< ChanSel: As indicated in following octets
< Ext: 1 Coding: 0 Number Specified Channel Type: 3
< Ext: 1 Channel: 4 ]
< [79 01 80]
< Restart Indentifier (len= 3) [ Ext: 1 Spare: 0 Resetting Indicated Channel (0) ]
-- Processing IE 24 (cs0, Channel Identification)
-- Processing IE 121 (cs0, Restart Indicator)
q931.c:3808 q931_receive: call 32768 on channel 4 enters state 0 (Null)
-- B-channel 0/4 successfully restarted on span 1
q931.c:2964 q931_restart: call 32768 on channel 5 enters state 62 (Restart)
> Protocol Discriminator: Q.931 ( len=13
> Call Ref: len= 2 (reference 0/0x0) (Originator)
> Message type: RESTART (70)
> [18 03 a9 83 85]
> Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0
> ChanSel: As indicated in following octets
> Ext: 1 Coding: 0 Number Specified Channel Type: 3
> Ext: 1 Channel: 5 ]
> [79 01 80]
> Restart Indentifier (len= 3) [ Ext: 1 Spare: 0 Resetting Indicated Channel (0) ]
< Protocol Discriminator: Q.931 ( len=13
< Call Ref: len= 2 (reference 0/0x0) (Terminator)
< Message type: RESTART ACKNOWLEDGE (78)
< [18 03 a9 83 85]
< Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0
< ChanSel: As indicated in following octets
< Ext: 1 Coding: 0 Number Specified Channel Type: 3
< Ext: 1 Channel: 5 ]
< [79 01 80]
< Restart Indentifier (len= 3) [ Ext: 1 Spare: 0 Resetting Indicated Channel (0) ]
-- Processing IE 24 (cs0, Channel Identification)
-- Processing IE 121 (cs0, Restart Indicator)
q931.c:3808 q931_receive: call 32768 on channel 5 enters state 0 (Null)
-- B-channel 0/5 successfully restarted on span 1
q931.c:2964 q931_restart: call 32768 on channel 6 enters state 62 (Restart)
> Protocol Discriminator: Q.931 ( len=13
> Call Ref: len= 2 (reference 0/0x0) (Originator)
> Message type: RESTART (70)
> [18 03 a9 83 86]
> Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0
> ChanSel: As indicated in following octets
> Ext: 1 Coding: 0 Number Specified Channel Type: 3
> Ext: 1 Channel: 6 ]
> [79 01 80]
> Restart Indentifier (len= 3) [ Ext: 1 Spare: 0 Resetting Indicated Channel (0) ]
< Protocol Discriminator: Q.931 ( len=13
< Call Ref: len= 2 (reference 0/0x0) (Terminator)
< Message type: RESTART ACKNOWLEDGE (78)
< [18 03 a9 83 86]
< Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0
< ChanSel: As indicated in following octets
< Ext: 1 Coding: 0 Number Specified Channel Type: 3
< Ext: 1 Channel: 6 ]
< [79 01 80]
< Restart Indentifier (len= 3) [ Ext: 1 Spare: 0 Resetting Indicated Channel (0) ]
-- Processing IE 24 (cs0, Channel Identification)
-- Processing IE 121 (cs0, Restart Indicator)
q931.c:3808 q931_receive: call 32768 on channel 6 enters state 0 (Null)
-- B-channel 0/6 successfully restarted on span 1
q931.c:2964 q931_restart: call 32768 on channel 7 enters state 62 (Restart)
> Protocol Discriminator: Q.931 ( len=13
> Call Ref: len= 2 (reference 0/0x0) (Originator)
> Message type: RESTART (70)
> [18 03 a9 83 87]
> Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0
> ChanSel: As indicated in following octets
> Ext: 1 Coding: 0 Number Specified Channel Type: 3
> Ext: 1 Channel: 7 ]
> [79 01 80]
> Restart Indentifier (len= 3) [ Ext: 1 Spare: 0 Resetting Indicated Channel (0) ]
< Protocol Discriminator: Q.931 ( len=13
< Call Ref: len= 2 (reference 0/0x0) (Terminator)
< Message type: RESTART ACKNOWLEDGE (78)
< [18 03 a9 83 87]
< Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0
< ChanSel: As indicated in following octets
< Ext: 1 Coding: 0 Number Specified Channel Type: 3
< Ext: 1 Channel: 7 ]
< [79 01 80]
< Restart Indentifier (len= 3) [ Ext: 1 Spare: 0 Resetting Indicated Channel (0) ]
-- Processing IE 24 (cs0, Channel Identification)
-- Processing IE 121 (cs0, Restart Indicator)
q931.c:3808 q931_receive: call 32768 on channel 7 enters state 0 (Null)
-- B-channel 0/7 successfully restarted on span 1
q931.c:2964 q931_restart: call 32768 on channel 8 enters state 62 (Restart)
> Protocol Discriminator: Q.931 ( len=13
> Call Ref: len= 2 (reference 0/0x0) (Originator)
> Message type: RESTART (70)
> [18 03 a9 83 88]
> Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0
> ChanSel: As indicated in following octets
> Ext: 1 Coding: 0 Number Specified Channel Type: 3
> Ext: 1 Channel: 8 ]
> [79 01 80]
> Restart Indentifier (len= 3) [ Ext: 1 Spare: 0 Resetting Indicated Channel (0) ]
< Protocol Discriminator: Q.931 ( len=13
< Call Ref: len= 2 (reference 0/0x0) (Terminator)
< Message type: RESTART ACKNOWLEDGE (78)
< [18 03 a9 83 88]
< Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0
< ChanSel: As indicated in following octets
< Ext: 1 Coding: 0 Number Specified Channel Type: 3
< Ext: 1 Channel: 8 ]
< [79 01 80]
< Restart Indentifier (len= 3) [ Ext: 1 Spare: 0 Resetting Indicated Channel (0) ]
-- Processing IE 24 (cs0, Channel Identification)
-- Processing IE 121 (cs0, Restart Indicator)
q931.c:3808 q931_receive: call 32768 on channel 8 enters state 0 (Null)
-- B-channel 0/8 successfully restarted on span 1
q931.c:2964 q931_restart: call 32768 on channel 11 enters state 62 (Restart)
> Protocol Discriminator: Q.931 ( len=13
> Call Ref: len= 2 (reference 0/0x0) (Originator)
> Message type: RESTART (70)
> [18 03 a9 83 8b]
> Channel ID (len= 5) [ Ext: 1 IntID: Implicit PRI Spare: 0 Exclusive Dchan: 0
> ChanSel: As indicated in following octets
> Ext: 1 Coding: 0 Number Specified Channel Type: 3
> Ext: 1 Channel: 11 ]
> [79 01 80]
> Restart Indentifier (len= 3) [ Ext: 1 Spare: 0 Resetting Indicated Channel (0) ]
< Protocol Discriminator: Q.931 ( len=12
< Call Ref: len= 2 (reference 0/0x0) (Terminator)
< Message type: STATUS (125)
< [08 02 82 d2]
< Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Public network serving the local user (2)
< Ext: 1 Cause: Identified channel does not exist (82), class = Invalid message (e.g. parameter out of range) (5) ]
< [14 01 00]
< Call State (len= 3) [ Ext: 0 Coding: CCITT (ITU) standard (0) Call state: Null (0)
-- Processing IE 8 (cs0, Cause)
-- Processing IE 20 (cs0, Call State)
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Active, peerstate Connect Request
q931.c:2973 q931_disconnect: call 32770 on channel 3 enters state 11 (Disconnect Request)
> Protocol Discriminator: Q.931 ( len=9
> Call Ref: len= 2 (reference 2/0x2) (Originator)
> Message type: DISCONNECT (69)
> [08 02 81 90]
> Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
> Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]
NEW_HANGUP DEBUG: Destroying the call, ourstate Disconnect Request, peerstate Disconnect Indication
-- Hungup 'Zap/3-1'
== Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/1518-b7c02190' in macro 'dialout-trunk'
== Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/1518-b7c02190'
-- Executing [h@macro-dialout-trunk:1] Macro("SIP/1518-b7c02190", "hangupcall|") in new stack
-- Executing [s@macro-hangupcall:1] ResetCDR("SIP/1518-b7c02190", "vw") in new stack
q921.c:782 q921_reset: q921_state now is Q921_LINK_CONNECTION_RELEASED
Sending Set Asynchronous Balanced Mode Extended
q921.c:211 q921_send_sabme: q921_state now is Q921_AWAITING_ESTABLISH
-- Executing [s@macro-hangupcall:2] NoCDR("SIP/1518-b7c02190", "") in new stack
-- Executing [s@macro-hangupcall:3] GotoIf("SIP/1518-b7c02190", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,6)
-- Executing [s@macro-hangupcall:6] GotoIf("SIP/1518-b7c02190", "1?skipblkvm") in new stack
-- Goto (macro-hangupcall,s,9)
-- Executing [s@macro-hangupcall:9] GotoIf("SIP/1518-b7c02190", "1?theend") in new stack
-- Goto (macro-hangupcall,s,11)
-- Executing [s@macro-hangupcall:11] Hangup("SIP/1518-b7c02190", "") in new stack
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/1518-b7c02190' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/1518-b7c02190'
Sending Set Asynchronous Balanced Mode Extended
-- Got UA from network peer Link up.
q921.c:782 q921_reset: q921_state now is Q921_LINK_CONNECTION_RELEASED
q921.c:733 q921_dchannel_up: q921_state now is Q921_LINK_CONNECTION_ESTABLISHED
== Primary D-Channel on span 1 up
-- Got SABME from network peer.
Sending Unnumbered Acknowledgement
q921.c:782 q921_reset: q921_state now is Q921_LINK_CONNECTION_RELEASED
q921.c:733 q921_dchannel_up: q921_state now is Q921_LINK_CONNECTION_ESTABLISHED
< Protocol Discriminator: Q.931 ( len=5
< Call Ref: len= 2 (reference 2/0x2) (Terminator)
< Message type: STATUS ENQUIRY (117)
-- Making new call for cr 32770
> Protocol Discriminator: Q.931 ( len=9
> Call Ref: len= 2 (reference 2/0x2) (Originator)
> Message type: RELEASE COMPLETE (90)
> [08 02 81 d1]
> Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
> Ext: 1 Cause: Invalid call reference value (81), class = Invalid message (e.g. parameter out of range) (5) ]
NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null
NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null

Some more output from the asterisk log

Nov 18 18:39:48] VERBOSE[2676] logger.c: -- Executing [s@macro-dialout-trunk:12] ExecIf("SIP/1518-09523f58", "0|AGI|fixlocalprefix") in new stack
[Nov 18 18:39:48] DEBUG[2676] app_macro.c: Executed application: ExecIf
[Nov 18 18:39:48] VERBOSE[2676] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/1518-09523f58", "OUTNUM=123") in new stack
[Nov 18 18:39:48] DEBUG[2676] app_macro.c: Executed application: Set
[Nov 18 18:39:48] VERBOSE[2676] logger.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/1518-09523f58", "custom=ZAP/g0") in new stack
[Nov 18 18:39:48] DEBUG[2676] app_macro.c: Executed application: Set
[Nov 18 18:39:48] VERBOSE[2676] logger.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/1518-09523f58", "0|Set|DIAL_TRUNK_OPTIONS=M(setmusic^)") in new stack
[Nov 18 18:39:48] DEBUG[2676] app_macro.c: Executed application: ExecIf
[Nov 18 18:39:48] VERBOSE[2676] logger.c: -- Executing [s@macro-dialout-trunk:16] Macro("SIP/1518-09523f58", "dialout-trunk-predial-hook|") in new stack
[Nov 18 18:39:48] VERBOSE[2676] logger.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/1518-09523f58", "") in new stack
[Nov 18 18:39:48] DEBUG[2676] app_macro.c: Executed application: Macro
[Nov 18 18:39:48] VERBOSE[2676] logger.c: -- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/1518-09523f58", "0?bypass|1") in new stack
[Nov 18 18:39:48] DEBUG[2676] app_macro.c: Executed application: GotoIf
[Nov 18 18:39:48] VERBOSE[2676] logger.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/1518-09523f58", "0?customtrunk") in new stack
[Nov 18 18:39:48] DEBUG[2676] app_macro.c: Executed application: GotoIf
[Nov 18 18:39:48] VERBOSE[2676] logger.c: -- Executing [s@macro-dialout-trunk:19] Dial("SIP/1518-09523f58", "ZAP/g0/123|300|") in new stack
[Nov 18 18:39:48] VERBOSE[2676] logger.c: -- Requested transfer capability: 0x00 - SPEECH
[Nov 18 18:39:48] VERBOSE[2676] logger.c: -- Called g0/123
[Nov 18 18:39:48] VERBOSE[2629] logger.c: -- B-channel 0/5 successfully restarted on span 1
[Nov 18 18:39:48] VERBOSE[2629] logger.c: -- B-channel 0/6 successfully restarted on span 1
[Nov 18 18:39:48] VERBOSE[2629] logger.c: -- B-channel 0/7 successfully restarted on span 1
[Nov 18 18:39:48] DEBUG[2629] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/1 span 1
[Nov 18 18:39:48] VERBOSE[2676] logger.c: -- Zap/1-1 is proceeding passing it to SIP/1518-09523f58
[Nov 18 18:39:48] VERBOSE[2629] logger.c: -- B-channel 0/8 successfully restarted on span 1
[Nov 18 18:39:49] VERBOSE[2676] logger.c: -- Zap/1-1 is ringing
[Nov 18 18:39:49] VERBOSE[2676] logger.c: -- Zap/1-1 answered SIP/1518-09523f58
[Nov 18 18:39:52] NOTICE[2629] chan_zap.c: PRI got event: HDLC Bad FCS ( on Primary D-channel of span 1
[Nov 18 18:40:03] NOTICE[2629] chan_zap.c: PRI got event: HDLC Bad FCS ( on Primary D-channel of span 1
[Nov 18 18:40:14] NOTICE[2629] chan_zap.c: PRI got event: HDLC Bad FCS ( on Primary D-channel of span 1
[Nov 18 18:40:19] NOTICE[2629] chan_zap.c: PRI got event: HDLC Bad FCS ( on Primary D-channel of span 1
[Nov 18 18:40:19] ERROR[2676] chan_zap.c: Write to 30 failed: Unknown error 500
[Nov 18 18:40:19] ERROR[2676] chan_zap.c: Short write: 0/15 (Unknown error 500)
[Nov 18 18:40:19] WARNING[2676] chan_zap.c: Detected alarm on channel 1: Yellow Alarm
[Nov 18 18:40:19] DEBUG[2676] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/1-1
[Nov 18 18:40:19] DEBUG[2676] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/1-1
[Nov 18 18:40:19] VERBOSE[2676] logger.c: -- Hungup 'Zap/1-1'
[Nov 18 18:40:19] VERBOSE[2676] logger.c: == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/1518-09523f58' in macro 'dialout-trunk'
[Nov 18 18:40:19] VERBOSE[2676] logger.c: == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/1518-09523f58'
[Nov 18 18:40:19] VERBOSE[2676] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/1518-09523f58", "hangupcall|") in new stack
[Nov 18 18:40:19] VERBOSE[2676] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/1518-09523f58", "vw") in new stack
[Nov 18 18:40:19] WARNING[2630] chan_zap.c: Detected alarm on channel 2: Yellow Alarm
[Nov 18 18:40:19] WARNING[2630] chan_zap.c: Detected alarm on channel 3: Yellow Alarm
[Nov 18 18:40:19] WARNING[2630] chan_zap.c: Detected alarm on channel 4: Yellow Alarm
[Nov 18 18:40:19] WARNING[2630] chan_zap.c: Detected alarm on channel 5: Yellow Alarm
[Nov 18 18:40:19] WARNING[2630] chan_zap.c: Detected alarm on channel 6: Yellow Alarm
[Nov 18 18:40:19] WARNING[2630] chan_zap.c: Detected alarm on channel 7: Yellow Alarm
[Nov 18 18:40:19] WARNING[2630] chan_zap.c: Detected alarm on channel 8: Yellow Alarm
[Nov 18 18:40:19] WARNING[2630] chan_zap.c: Detected alarm on channel 9: Yellow Alarm
[Nov 18 18:40:19] WARNING[2630] chan_zap.c: Detected alarm on channel 10: Yellow Alarm
[Nov 18 18:40:19] WARNING[2630] chan_zap.c: Detected alarm on channel 11: Yellow Alarm
[Nov 18 18:40:19] WARNING[2630] chan_zap.c: Detected alarm on channel 12: Yellow Alarm
[Nov 18 18:40:19] WARNING[2630] chan_zap.c: Detected alarm on channel 13: Yellow Alarm
[Nov 18 18:40:19] WARNING[2630] chan_zap.c: Detected alarm on channel 14: Yellow Alarm
[Nov 18 18:40:19] WARNING[2630] chan_zap.c: Detected alarm on channel 15: Yellow Alarm
[Nov 18 18:40:19] NOTICE[2629] chan_zap.c: PRI got event: Alarm (4) on Primary D-channel of span 1
[Nov 18 18:40:19] WARNING[2629] chan_zap.c: No D-channels available! Using Primary channel 16 as D-channel anyway!
[Nov 18 18:40:19] NOTICE[2630] chan_zap.c: Alarm cleared on channel 1
[Nov 18 18:40:19] NOTICE[2630] chan_zap.c: Alarm cleared on channel 2
[Nov 18 18:40:19] NOTICE[2630] chan_zap.c: Alarm cleared on channel 3
[Nov 18 18:40:19] NOTICE[2630] chan_zap.c: Alarm cleared on channel 4
[Nov 18 18:40:19] NOTICE[2630] chan_zap.c: Alarm cleared on channel 5
[Nov 18 18:40:19] NOTICE[2630] chan_zap.c: Alarm cleared on channel 6
[Nov 18 18:40:19] NOTICE[2630] chan_zap.c: Alarm cleared on channel 7
[Nov 18 18:40:19] NOTICE[2630] chan_zap.c: Alarm cleared on channel 8
[Nov 18 18:40:19] NOTICE[2630] chan_zap.c: Alarm cleared on channel 9
[Nov 18 18:40:19] NOTICE[2630] chan_zap.c: Alarm cleared on channel 10
[Nov 18 18:40:19] NOTICE[2630] chan_zap.c: Alarm cleared on channel 11
[Nov 18 18:40:19] NOTICE[2630] chan_zap.c: Alarm cleared on channel 12
[Nov 18 18:40:19] NOTICE[2630] chan_zap.c: Alarm cleared on channel 13
[Nov 18 18:40:19] NOTICE[2630] chan_zap.c: Alarm cleared on channel 14
[Nov 18 18:40:19] NOTICE[2630] chan_zap.c: Alarm cleared on channel 15
[Nov 18 18:40:19] NOTICE[2629] chan_zap.c: PRI got event: No more alarm (5) on Primary D-channel of span 1
[Nov 18 18:40:19] DEBUG[2676] app_macro.c: Executed application: ResetCDR
[Nov 18 18:40:19] VERBOSE[2676] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("SIP/1518-09523f58", "") in new stack
[Nov 18 18:40:19] DEBUG[2676] app_macro.c: Executed application: NoCDR
[Nov 18 18:40:19] VERBOSE[2676] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/1518-09523f58", "1?skiprg") in new stack
[Nov 18 18:40:19] VERBOSE[2676] logger.c: -- Goto (macro-hangupcall,s,6)
[Nov 18 18:40:19] DEBUG[2676] app_macro.c: Executed application: GotoIf
[Nov 18 18:40:19] VERBOSE[2676] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("SIP/1518-09523f58", "1?skipblkvm") in new stack
[Nov 18 18:40:19] VERBOSE[2676] logger.c: -- Goto (macro-hangupcall,s,9)
[Nov 18 18:40:19] DEBUG[2676] app_macro.c: Executed application: GotoIf
[Nov 18 18:40:19] VERBOSE[2676] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("SIP/1518-09523f58", "1?theend") in new stack
[Nov 18 18:40:19] VERBOSE[2676] logger.c: -- Goto (macro-hangupcall,s,11)
[Nov 18 18:40:19] DEBUG[2676] app_macro.c: Executed application: GotoIf
[Nov 18 18:40:19] VERBOSE[2676] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("SIP/1518-09523f58", "") in new stack
[Nov 18 18:40:19] VERBOSE[2676] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/1518-09523f58' in macro 'hangupcall'
[Nov 18 18:40:19] VERBOSE[2676] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/1518-09523f58'
[Nov 18 18:40:19] VERBOSE[2629] logger.c: == Primary D-Channel on span 1 up
[Nov 18 18:40:29] VERBOSE[2675] logger.c: -- Remote UNIX connection disconnected
[Nov 18 18:40:30] VERBOSE[2626] logger.c: -- Registered SIP '1525' at 10.10.10.132 port 51682 expires 3600
[Nov 18 18:40:30] VERBOSE[2626] logger.c: -- Saved useragent "X-Lite release 1103k stamp 53621" for peer 1525
[Nov 18 18:40:30] VERBOSE[2629] logger.c: -- B-channel 0/1 successfully restarted on span 1
[Nov 18 18:40:30] VERBOSE[2629] logger.c: -- B-channel 0/2 successfully restarted on span 1
[Nov 18 18:40:30] VERBOSE[2629] logger.c: -- B-channel 0/3 successfully restarted on span 1
[Nov 18 18:40:30] VERBOSE[2629] logger.c: -- B-channel 0/4 successfully restarted on span 1
[Nov 18 18:40:30] VERBOSE[2629] logger.c: -- B-channel 0/5 successfully restarted on span 1
[Nov 18 18:40:30] VERBOSE[2629] logger.c: -- B-channel 0/6 successfully restarted on span 1
[Nov 18 18:40:31] VERBOSE[2629] logger.c: -- B-channel 0/7 successfully restarted on span 1
[Nov 18 18:40:31] VERBOSE[2629] logger.c: -- B-channel 0/8 successfully restarted on span 1

Solved!.

Looks like the usb irq sharing was the issue or maybe just a general motherboard incompatabilty issue.
I cloned the config to a new ML150 and an abit motherboarded PC and they both had no issues.