this was being caused by the branch of astmanproxy that we are using returning duplicate Server: fields on Response and Event message such as this:
127.0.0.1 <- Server: localhost
127.0.0.1 <- Server: 0
to fix it i had to patch astmanproxy to not add the header, but you might want to fix your script to not crash on multiple server fields.
edited debug below, let me know if you want or need more info.
1) first originate
- * MAIN AMI event received...
- * MAIN Processing command received from flash clients...
209.XXX.XXX.XXX <= <msg data="3|originate|4|0f26945d6535b67424e49bdbefa0XXXX" />
-- PROCESS_FLASH_COMMAND origen 3 accion originate destino 4 password 0f26945d6535b67424e49bdbefa0XXXX
VALIDAR USUARIO 256@TRAINING
VALIDAR USUARIO 256 OK con clave regular (209.XXXX.XXXX.XXXX)
Validation ok, have all permissions for all buttons (0) 4
GET SERVER para SIP/256 = 0
ORIGINATE:
Action: Originate
Channel: SIP/256
Exten: 129
Context: outbound
Priority: 1
CallerID: Steve Test: 256 <256>
Async: True
2) extension state change
127.0.0.1 <- Event: Newstate
127.0.0.1 <- Privilege: call,all
127.0.0.1 <- Channel: SIP/256-00000004
127.0.0.1 <- ChannelState: 6
127.0.0.1 <- ChannelStateDesc: Up
127.0.0.1 <- CallerIDNum: 256
127.0.0.1 <- CallerIDName: Steve Test: 256
127.0.0.1 <- ConnectedLineNum: 256
127.0.0.1 <- ConnectedLineName: Steve Test: 256
127.0.0.1 <- Uniqueid: 1343249677.4
127.0.0.1 <- Server: localhost
127.0.0.1 <- Server: 0
- * MAIN AMI event received...
- * MAIN There's an 'Event' in the event block
- * MAIN Event detected block_count = 1 (Event: Newstate)
- * MAIN There are 1 blocks for processing
- * MAIN Answer block cleared
** DIGEST_EVENT: start
- * PROCESA_BLOQUE 0
- * PROCESA_BLOQUE NEWSTATE ARRAY(0xbb578f4)
It's blessed into class Extension
State UP para canal SIP/256-00000004 en slot 1
GETSTATE para SIP/256-00000004 estaba definido y devuelve UP
GET CALL SLOT para SIP/256-00000004 definido, devuelvo 1
SET SECONDS SIP/256-00000004 = 0
GET SECONDS devuelve 0
SET SERVER para SIP/256 = ARRAY(0xbb578f4)
3) 2nd originate
- * MAIN AMI event received...
- * MAIN Processing command received from flash clients...
209.242.9.134 <= <msg data="3|originate|4|0f26945d6535b67424e49bdbefa0XXXX" />
-- PROCESS_FLASH_COMMAND origen 3 accion originate destino 4 password 0f26945d6535b67424e49bdbefa0XXXX
VALIDAR USUARIO 256@TRAINING
VALIDAR USUARIO 256 OK con clave regular (209.XXX.XXX.XXXX )
Validation ok, have all permissions for all buttons (0) 4
GET SERVER para SIP/256 = ARRAY(0xbb578f4)
ORIGINATE:
Action: Originate
Channel: SIP/256
Exten: 129
Context: outbound
Priority: 1
CallerID: Steve Test: 256 <256>
Async: True
Use of reference "ARRAY(0xbb578f4)" as array index at script/fop2_server.pl line 8246.
Use of uninitialized value in hash element at script/fop2_server.pl line 12129.
Use of uninitialized value in concatenation (.) or string at script/fop2_server.pl line 12133.
Cannot send command to (unauthenticated or connection failed)
Exiting...