Caller Name not appearing

I have too many variables so this is a gateway post to sift through to the right forum.

In my extensions_custom.conf [from-pstn-custom] context I have the lines:

exten => _X.,n,Wait(1) exten => _X.,n,Set(CALLERID(all)=${CALLERID(all)})

In the asterisk log with the pri debug enabled I see the namePresentationAllowedSimple name appear from chan_dahdi and Q.931 message arrive just prior to the Set statement. Even with that, the name never gets picked up in the dialplan.

I’m seemingly left to presume this is a DAHDI issue, but before going there I’d like to make sure I’m not missing something obvious. Anything?

asterisk 1.6.2.7 (asterisk16-core-1.6.2.7-1_centos5)
dahdi 2.3.0 (dahdi-linux-2.3.0.1-1_centos5)
libpri 1.4.11 (libpri-1.4.11-1_centos5)

[Aug 9 11:58:46] VERBOSE[23950] pbx.c: -- Executing [6125554322@from-pstn:3] Wait("DAHDI/25-1", "1") in new stack [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: TEI: 0 State 7 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: V(S) 76 V(A) 75 V(R) 103 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: K 7, RC 0, l3initiated 0, reject_except 0 ack_pend 0 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: T200 1, N200 3, T203 0 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < [ 00 01 01 98 ] [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < Supervisory frame: [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < SAPI: 00 C/R: 0 EA: 0 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < TEI: 000 EA: 1 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < Zero: 0 S: 0 01: 1 [ RR (receive ready) ] [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < N(R): 076 P/F: 0 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < 0 bytes of data [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: -- ACKing all packets from 75 to (but not including) 76 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: -- ACKing packet 75, new txqueue is -1 (-1 means empty) [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: -- Stopping T200 timer [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: -- Starting T203 timer [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: Handling message for SAPI/TEI=0/0 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: TEI: 0 State 7 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: V(S) 76 V(A) 76 V(R) 103 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: K 7, RC 0, l3initiated 0, reject_except 0 ack_pend 0 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: T200 0, N200 3, T203 1 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < [ 02 01 ce 98 08 02 03 c5 62 1c 1c 9f 8b 01 00 a1 16 02 01 01 02 01 00 80 0e 56 49 53 49 4f 4e 53 48 41 52 45 20 48 51 ] [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < Informational frame: [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < SAPI: 00 C/R: 1 EA: 0 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < TEI: 000 EA: 1 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < N(S): 103 0: 0 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < N(R): 076 P: 0 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < 35 bytes of data [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < Protocol Discriminator: Q.931 (8) len=35 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < TEI=0 Call Ref: len= 2 (reference 965/0x3C5) (Sent from originator) [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < Message Type: FACILITY (98) [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < [1c 1c 9f 8b 01 00 a1 16 02 01 01 02 01 00 80 0e 56 49 53 49 4f 4e 53 48 41 52 45 20 48 51] [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: < Facility (len=30, codeset=0) [ 0x9F, 0x8B, 0x01, 0x00, 0xA1, 0x16, 0x02, 0x01, 0x01, 0x02, 0x01, 0x00, 0x80, 0x0E, 'REALNAME HQ' ] [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: Received message for call 0x2aaac8470710 on 0x2aaae0b1bf70 TEI/SAPI 0/0, call->pri is 0x2aaae0b1bf70 TEI/SAPI 0/0 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: -- Processing IE 28 (cs0, Facility) [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: -- Delayed processing IE 28 (cs0, Facility) [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: ASN.1 dump [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: Context Specific [11 0x0B] <8B> Len:1 <01> [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: <00> - "~" [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: Context Specific/C [1 0x01] <A1> Len:22 <16> [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: Integer(2 0x02) <02> Len:1 <01> [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: <01> - "~" [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: Integer(2 0x02) <02> Len:1 <01> [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: <00> - "~"[Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: Context Specific [0 0x00] <80> Len:14 <0E>[Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: <56 49 53 49 4F 4E 53 48-41 52 45 20 48 51> - "REALNAME HQ" [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: ASN.1 end [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: interpretation Context Specific [11 0x0B] = 0 0x0000 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: INVOKE Component Context Specific/C [1 0x01] [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: invokeId Integer(2 0x02) = 1 0x0001 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: operationValue Integer(2 0x02) = 0 0x0000 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: operationValue = ROSE_QSIG_CallingName [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: callingName Name [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: namePresentationAllowedSimple Context Specific [0 0x00] = [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: <56 49 53 49 4F 4E 53 48-41 52 45 20 48 51> - "REALNAME HQ" [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: -- ACKing all packets from 76 to (but not including) 76 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: -- T200 requested to stop when not started [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: T203 requested to start without stopping first [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: -- Starting T203 timer [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: Sending Receiver Ready (104) [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: TEI: 0 State 7 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: V(S) 76 V(A) 76 V(R) 104 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: K 7, RC 0, l3initiated 0, reject_except 0 ack_pend 0 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: T200 0, N200 3, T203 1 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: > [ 02 01 01 d0 ] [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: > Supervisory frame: [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: > SAPI: 00 C/R: 1 EA: 0 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: > TEI: 000 EA: 1 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: > Zero: 0 S: 0 01: 1 [ RR (receive ready) ] [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: > N(R): 104 P/F: 0 [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: > 0 bytes of data [Aug 9 11:58:46] VERBOSE[16823] chan_dahdi.c: Handling message for SAPI/TEI=0/0 [Aug 9 11:58:47] VERBOSE[23950] pbx.c: -- Executing [6125554322@from-pstn:4] Set("DAHDI/25-1", "CALLERID(all)="" <6125553995>") in new stack

Thank you for the extra eyes!