0.002: open(/dev/ttyAMA5) begin 0.003: open(/dev/ttyAMA5) result=8 0.003: uart: THREAD_create() 0.003: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.003: uart: TX Msg (start) [reset-cmd] 0.003: reset-cmd msg(0000) nbytes=6 len=1 [ 0xfe 0x01 0x41 0x00 0x01 0x41] 0.003: uart: TX 6 bytes 0.003: 00000000: fe 01 41 00 01 41 - |..A..A | 0.003: uart: rx-msg looking for start 0.003: uart-wr: write(6 bytes) = 6 0.003: uart: TX Msg (Complete) r=6 [reset-cmd] 0.003: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.017: uart-rd: read(5 bytes) = 5 0.017: uart: nbytes-avail: 5 0.018: 00000000: fe 06 41 80 00 - |..A.. | 0.018: uart-rd: read(6 bytes) = 6 0.018: uart: nbytes-avail: 11 0.018: 00000000: fe 06 41 80 00 03 01 02-02 00 c5 |..A........ | 0.018: rx areq 0.018: incoming-msg msg(0001) nbytes=11 len=6 [ 0xfe 0x06 0x41 0x80 0x00 0x03 0x01 0x02] 0.018: uart: MUTEX_lock(mi-lock) success (recursion=1) 0.018: uart: MUTEX_unlock(mi-lock) unlocked 0.018: not-a-thread: MUTEX_lock(mi-lock) success (recursion=1) 0.018: not-a-thread: MUTEX_unlock(mi-lock) unlocked 0.018: Reset Indication: CoP init 0.018: incoming-msg msg(0001) nbytes=11 len=6 [ 0xfe 0x06 0x41 0x80 0x00 0x03 0x01 0x02] 0.018: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.018: uart: TX Msg (start) [get-version] 0.018: get-version msg(0003) nbytes=5 len=0 [ 0xfe 0x00 0x21 0x02 0x23] 0.018: uart: TX 5 bytes 0.018: 00000000: fe 00 21 02 23 - |..!.# | 0.018: uart: rx-msg looking for start 0.018: uart-wr: write(5 bytes) = 5 0.018: uart: TX Msg (Complete) r=5 [get-version] 0.020: uart-rd: read(5 bytes) = 5 0.020: uart: nbytes-avail: 5 0.020: 00000000: fe 05 61 02 03 - |..a.. | 0.020: uart-rd: read(5 bytes) = 5 0.020: uart: nbytes-avail: 10 0.020: 00000000: fe 05 61 02 03 01 02 02-00 64 |..a......d | 0.020: uart: rx-msg looking for start 0.020: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.020: mlmeResetReq: wr_u8: resetParam: 1, 0x1 0.020: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.020: uart: TX Msg (start) [mlmeResetReq] 0.020: mlmeResetReq msg(0005) nbytes=6 len=1 [ 0xfe 0x01 0x22 0x01 0x01 0x23] 0.020: uart: TX 6 bytes 0.020: 00000000: fe 01 22 01 01 23 - |.."..# | 0.020: uart-wr: write(6 bytes) = 6 0.021: uart: TX Msg (Complete) r=6 [mlmeResetReq] 0.026: uart-rd: read(5 bytes) = 5 0.026: uart: nbytes-avail: 5 0.026: 00000000: fe 01 62 01 00 - |..b.. | 0.026: uart-rd: read(1 bytes) = 1 0.026: uart: nbytes-avail: 6 0.026: 00000000: fe 01 62 01 00 62 - |..b..b | 0.026: uart: rx-msg looking for start 0.026: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.026: incoming-msg: rd_u8: status: 0, 0x00 0.026: SREQ: (mlmeResetReq) SRSP: Result: 0 (0x00) 0.026: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.026: uart: TX Msg (start) [get-ext-addr] 0.026: get-ext-addr msg(0007) nbytes=6 len=1 [ 0xfe 0x01 0x27 0xee 0x01 0xc9] 0.026: uart: TX 6 bytes 0.026: 00000000: fe 01 27 ee 01 c9 - |..'... | 0.026: uart-wr: write(6 bytes) = 6 0.026: uart: TX Msg (Complete) r=6 [get-ext-addr] 0.029: uart-rd: read(5 bytes) = 5 0.029: uart: nbytes-avail: 5 0.029: 00000000: fe 09 67 ee 01 - |..g.. | 0.029: uart-rd: read(9 bytes) = 9 0.029: uart: nbytes-avail: 14 0.029: 00000000: fe 09 67 ee 01 2c 58 ad-34 00 4b 12 00 35 |..g..,X.4.K..5 | 0.029: uart: rx-msg looking for start 0.029: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.029: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.029: uart: TX Msg (start) [get-ext-addr] 0.029: get-ext-addr msg(0009) nbytes=6 len=1 [ 0xfe 0x01 0x27 0xee 0x02 0xca] 0.029: uart: TX 6 bytes 0.029: 00000000: fe 01 27 ee 02 ca - |..'... | 0.029: uart-wr: write(6 bytes) = 6 0.029: uart: TX Msg (Complete) r=6 [get-ext-addr] 0.032: uart-rd: read(5 bytes) = 5 0.032: uart: nbytes-avail: 5 0.032: 00000000: fe 09 67 ee 02 - |..g.. | 0.032: uart-rd: read(9 bytes) = 9 0.032: uart: nbytes-avail: 14 0.032: 00000000: fe 09 67 ee 02 ff ff ff-ff ff ff ff ff 82 |..g........... | 0.032: uart: rx-msg looking for start 0.032: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.032: pib-getset-array-common: wr_u8: pib-id8: 226, 0xe2 0.032: pib-getset-array-common: wrBuf: data-bytes, len: 8 0.032: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.033: uart: TX Msg (start) [pib-getset-array-common] 0.033: pib-getset-array-common msg(000b) nbytes=14 len=9 [ 0xfe 0x09 0x22 0x09 0xe2 0x2c 0x58 0xad] 0.033: uart: TX 14 bytes 0.033: 00000000: fe 09 22 09 e2 2c 58 ad-34 00 4b 12 00 74 |.."..,X.4.K..t | 0.033: uart-wr: write(14 bytes) = 14 0.033: uart: TX Msg (Complete) r=14 [pib-getset-array-common] 0.035: uart-rd: read(5 bytes) = 5 0.036: uart: nbytes-avail: 5 0.036: 00000000: fe 01 62 09 00 - |..b.. | 0.036: uart-rd: read(1 bytes) = 1 0.036: uart: nbytes-avail: 6 0.036: 00000000: fe 01 62 09 00 6a - |..b..j | 0.036: uart: rx-msg looking for start 0.036: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.036: incoming-msg: rd_u8: status: 0, 0x00 0.036: SREQ: (pib-getset-array-common) SRSP: Result: 0 (0x00) 0.036: pib-set-common: wr_u8: pib-id8: 232, 0xe8 0.036: pib-set-common: wr_u8: pib-value8: 3, 0x3 0.036: pib-set-common: wr_u8: filler: 0, 0x0 0.036: pib-set-common: wr_u8: filler: 0, 0x0 0.036: pib-set-common: wr_u8: filler: 0, 0x0 0.036: pib-set-common: wr_u8: filler: 0, 0x0 0.036: pib-set-common: wr_u8: filler: 0, 0x0 0.036: pib-set-common: wr_u8: filler: 0, 0x0 0.036: pib-set-common: wr_u8: filler: 0, 0x0 0.036: pib-set-common: wr_u8: filler: 0, 0x0 0.036: pib-set-common: wr_u8: filler: 0, 0x0 0.036: pib-set-common: wr_u8: filler: 0, 0x0 0.036: pib-set-common: wr_u8: filler: 0, 0x0 0.036: pib-set-common: wr_u8: filler: 0, 0x0 0.036: pib-set-common: wr_u8: filler: 0, 0x0 0.036: pib-set-common: wr_u8: filler: 0, 0x0 0.036: pib-set-common: wr_u8: filler: 0, 0x0 0.036: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.036: uart: TX Msg (start) [pib-set-common] 0.036: pib-set-common msg(000d) nbytes=22 len=17 [ 0xfe 0x11 0x22 0x09 0xe8 0x03 0x00 0x00] 0.036: uart: TX 22 bytes 0.036: 00000000: fe 11 22 09 e8 03 00 00-00 00 00 00 00 00 00 00 |..".............| 0.036: 00000010: 00 00 00 00 00 d1 - |...... | 0.036: uart-wr: write(22 bytes) = 22 0.036: uart: TX Msg (Complete) r=22 [pib-set-common] 0.041: uart-rd: read(5 bytes) = 5 0.041: uart: nbytes-avail: 5 0.041: 00000000: fe 01 62 09 00 - |..b.. | 0.041: uart-rd: read(1 bytes) = 1 0.041: uart: nbytes-avail: 6 0.041: 00000000: fe 01 62 09 00 6a - |..b..j | 0.041: uart: rx-msg looking for start 0.041: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.041: incoming-msg: rd_u8: status: 0, 0x00 0.041: SREQ: (pib-set-common) SRSP: Result: 0 (0x00) 0.041: pib-set-common: wr_u8: pib-id8: 231, 0xe7 0.041: pib-set-common: wr_u8: pib-value8: 9, 0x9 0.041: pib-set-common: wr_u8: filler: 0, 0x0 0.041: pib-set-common: wr_u8: filler: 0, 0x0 0.041: pib-set-common: wr_u8: filler: 0, 0x0 0.041: pib-set-common: wr_u8: filler: 0, 0x0 0.041: pib-set-common: wr_u8: filler: 0, 0x0 0.041: pib-set-common: wr_u8: filler: 0, 0x0 0.041: pib-set-common: wr_u8: filler: 0, 0x0 0.041: pib-set-common: wr_u8: filler: 0, 0x0 0.041: pib-set-common: wr_u8: filler: 0, 0x0 0.041: pib-set-common: wr_u8: filler: 0, 0x0 0.041: pib-set-common: wr_u8: filler: 0, 0x0 0.041: pib-set-common: wr_u8: filler: 0, 0x0 0.041: pib-set-common: wr_u8: filler: 0, 0x0 0.041: pib-set-common: wr_u8: filler: 0, 0x0 0.041: pib-set-common: wr_u8: filler: 0, 0x0 0.041: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.041: uart: TX Msg (start) [pib-set-common] 0.041: pib-set-common msg(000f) nbytes=22 len=17 [ 0xfe 0x11 0x22 0x09 0xe7 0x09 0x00 0x00] 0.041: uart: TX 22 bytes 0.042: 00000000: fe 11 22 09 e7 09 00 00-00 00 00 00 00 00 00 00 |..".............| 0.042: 00000010: 00 00 00 00 00 d4 - |...... | 0.042: uart-wr: write(22 bytes) = 22 0.042: uart: TX Msg (Complete) r=22 [pib-set-common] 0.045: uart-rd: read(5 bytes) = 5 0.045: uart: nbytes-avail: 5 0.045: 00000000: fe 01 62 09 00 - |..b.. | 0.045: uart-rd: read(1 bytes) = 1 0.045: uart: nbytes-avail: 6 0.045: 00000000: fe 01 62 09 00 6a - |..b..j | 0.045: uart: rx-msg looking for start 0.045: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.045: incoming-msg: rd_u8: status: 0, 0x00 0.045: SREQ: (pib-set-common) SRSP: Result: 0 (0x00) 0.045: Cllc_init B001 B001 0.045: pib-set-common: wr_u8: pib-id8: 82, 0x52 0.045: pib-set-common: wr_u8: pib-value8: 1, 0x1 0.045: pib-set-common: wr_u8: filler: 0, 0x0 0.045: pib-set-common: wr_u8: filler: 0, 0x0 0.045: pib-set-common: wr_u8: filler: 0, 0x0 0.045: pib-set-common: wr_u8: filler: 0, 0x0 0.045: pib-set-common: wr_u8: filler: 0, 0x0 0.045: pib-set-common: wr_u8: filler: 0, 0x0 0.045: pib-set-common: wr_u8: filler: 0, 0x0 0.045: pib-set-common: wr_u8: filler: 0, 0x0 0.045: pib-set-common: wr_u8: filler: 0, 0x0 0.045: pib-set-common: wr_u8: filler: 0, 0x0 0.045: pib-set-common: wr_u8: filler: 0, 0x0 0.045: pib-set-common: wr_u8: filler: 0, 0x0 0.046: pib-set-common: wr_u8: filler: 0, 0x0 0.046: pib-set-common: wr_u8: filler: 0, 0x0 0.046: pib-set-common: wr_u8: filler: 0, 0x0 0.046: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.046: uart: TX Msg (start) [pib-set-common] 0.046: pib-set-common msg(0011) nbytes=22 len=17 [ 0xfe 0x11 0x22 0x09 0x52 0x01 0x00 0x00] 0.046: uart: TX 22 bytes 0.046: 00000000: fe 11 22 09 52 01 00 00-00 00 00 00 00 00 00 00 |..".R...........| 0.046: 00000010: 00 00 00 00 00 69 - |.....i | 0.046: uart-wr: write(22 bytes) = 22 0.046: uart: TX Msg (Complete) r=22 [pib-set-common] 0.050: uart-rd: read(5 bytes) = 5 0.050: uart: nbytes-avail: 5 0.050: 00000000: fe 01 62 09 00 - |..b.. | 0.050: uart-rd: read(1 bytes) = 1 0.050: uart: nbytes-avail: 6 0.050: 00000000: fe 01 62 09 00 6a - |..b..j | 0.050: uart: rx-msg looking for start 0.050: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.050: incoming-msg: rd_u8: status: 0, 0x00 0.050: SREQ: (pib-set-common) SRSP: Result: 0 (0x00) 0.050: pib-set-common: wr_u8: pib-id8: 83, 0x53 0.050: pib-set-common: wr_u16: pib-value16: 43707, 0xaabb 0.050: pib-set-common: wr_u8: filler: 0, 0x0 0.050: pib-set-common: wr_u8: filler: 0, 0x0 0.050: pib-set-common: wr_u8: filler: 0, 0x0 0.050: pib-set-common: wr_u8: filler: 0, 0x0 0.050: pib-set-common: wr_u8: filler: 0, 0x0 0.050: pib-set-common: wr_u8: filler: 0, 0x0 0.050: pib-set-common: wr_u8: filler: 0, 0x0 0.050: pib-set-common: wr_u8: filler: 0, 0x0 0.050: pib-set-common: wr_u8: filler: 0, 0x0 0.050: pib-set-common: wr_u8: filler: 0, 0x0 0.050: pib-set-common: wr_u8: filler: 0, 0x0 0.050: pib-set-common: wr_u8: filler: 0, 0x0 0.050: pib-set-common: wr_u8: filler: 0, 0x0 0.050: pib-set-common: wr_u8: filler: 0, 0x0 0.050: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.050: uart: TX Msg (start) [pib-set-common] 0.050: pib-set-common msg(0013) nbytes=22 len=17 [ 0xfe 0x11 0x22 0x09 0x53 0xbb 0xaa 0x00] 0.050: uart: TX 22 bytes 0.050: 00000000: fe 11 22 09 53 bb aa 00-00 00 00 00 00 00 00 00 |..".S...........| 0.050: 00000010: 00 00 00 00 00 78 - |.....x | 0.050: uart-wr: write(22 bytes) = 22 0.050: uart: TX Msg (Complete) r=22 [pib-set-common] 0.054: uart-rd: read(5 bytes) = 5 0.054: uart: nbytes-avail: 5 0.054: 00000000: fe 01 62 09 00 - |..b.. | 0.054: uart-rd: read(1 bytes) = 1 0.054: uart: nbytes-avail: 6 0.054: 00000000: fe 01 62 09 00 6a - |..b..j | 0.054: uart: rx-msg looking for start 0.054: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.054: incoming-msg: rd_u8: status: 0, 0x00 0.054: SREQ: (pib-set-common) SRSP: Result: 0 (0x00) 0.054: Collector_updateFwList: new oad file: /home/epour/EPour.bin 0.054: Collector_updateFwList: Added /home/epour/EPour.bin, ID 0 0.054: TI CollectorNVOCMP_ALERT: message=NVOCMP Init. Called! 0.054: nvram: creating: nv-simulation.bin 0.054: nvram: save: nv-simulation.bin, length=32768 0.054: read: pg:0, ofs=0x0000, num=4 0.054: 00000000: ff ff ff ff - |.... | 0.055: read: pg:0, ofs=0x0004, num=4 0.055: 00000000: ff ff ff ff- | .... | 0.055: read: pg:0, ofs=0x0008, num=4 0.055: 00000000: -ff ff ff ff | .... | 0.055: read: pg:0, ofs=0x000c, num=4 0.055: 00000000: - ff ff ff ff | ....| 0.055: NVOCMP_ALERT: message=Corrupted or Version/Signature mismatch. 0.055: write: pg:0, ofs=0x0000, num=4 0.055: 00000000: ff 01 0f 96 - |.... | 0.055: write: pg:0, ofs=0x0004, num=4 0.055: 00000000: ff ff ff 96- | .... | 0.055: write: pg:0, ofs=0x0008, num=4 0.055: 00000000: -ff ff ff 96 | .... | 0.055: write: pg:0, ofs=0x000c, num=4 0.055: 00000000: - ff ff ff 96 | ....| 0.055: read: pg:0, ofs=0x0000, num=4 0.055: 00000000: ff 01 0f 96 - |.... | 0.055: read: pg:0, ofs=0x0004, num=4 0.055: 00000000: ff ff ff 96- | .... | 0.055: read: pg:0, ofs=0x0008, num=4 0.055: 00000000: -ff ff ff 96 | .... | 0.055: read: pg:0, ofs=0x000c, num=4 0.055: 00000000: - ff ff ff 96 | ....| 0.055: read: pg:1, ofs=0x0000, num=4 0.055: 00002000: ff ff ff ff - |.... | 0.055: read: pg:1, ofs=0x0004, num=4 0.055: 00002000: ff ff ff ff- | .... | 0.055: read: pg:1, ofs=0x0008, num=4 0.055: 00002000: -ff ff ff ff | .... | 0.055: read: pg:1, ofs=0x000c, num=4 0.055: 00002000: - ff ff ff ff | ....| 0.055: NVOCMP_ALERT: message=Corrupted or Version/Signature mismatch. 0.055: write: pg:1, ofs=0x0000, num=4 0.055: 00002000: ff 01 0f 96 - |.... | 0.055: write: pg:1, ofs=0x0004, num=4 0.055: 00002000: ff ff ff 96- | .... | 0.055: write: pg:1, ofs=0x0008, num=4 0.055: 00002000: -ff ff ff 96 | .... | 0.055: write: pg:1, ofs=0x000c, num=4 0.055: 00002000: - ff ff ff 96 | ....| 0.055: read: pg:1, ofs=0x0000, num=4 0.055: 00002000: ff 01 0f 96 - |.... | 0.055: read: pg:1, ofs=0x0004, num=4 0.055: 00002000: ff ff ff 96- | .... | 0.055: read: pg:1, ofs=0x0008, num=4 0.055: 00002000: -ff ff ff 96 | .... | 0.055: read: pg:1, ofs=0x000c, num=4 0.055: 00002000: - ff ff ff 96 | ....| 0.055: read: pg:2, ofs=0x0000, num=4 0.055: 00004000: ff ff ff ff - |.... | 0.055: read: pg:2, ofs=0x0004, num=4 0.055: 00004000: ff ff ff ff- | .... | 0.055: read: pg:2, ofs=0x0008, num=4 0.055: 00004000: -ff ff ff ff | .... | 0.055: read: pg:2, ofs=0x000c, num=4 0.055: 00004000: - ff ff ff ff | ....| 0.055: NVOCMP_ALERT: message=Corrupted or Version/Signature mismatch. 0.055: write: pg:2, ofs=0x0000, num=4 0.055: 00004000: ff 01 0f 96 - |.... | 0.055: write: pg:2, ofs=0x0004, num=4 0.055: 00004000: ff ff ff 96- | .... | 0.055: write: pg:2, ofs=0x0008, num=4 0.055: 00004000: -ff ff ff 96 | .... | 0.055: write: pg:2, ofs=0x000c, num=4 0.055: 00004000: - ff ff ff 96 | ....| 0.056: read: pg:2, ofs=0x0000, num=4 0.056: 00004000: ff 01 0f 96 - |.... | 0.056: read: pg:2, ofs=0x0004, num=4 0.056: 00004000: ff ff ff 96- | .... | 0.056: read: pg:2, ofs=0x0008, num=4 0.056: 00004000: -ff ff ff 96 | .... | 0.056: read: pg:2, ofs=0x000c, num=4 0.056: 00004000: - ff ff ff 96 | ....| 0.056: read: pg:3, ofs=0x0000, num=4 0.056: 00006000: ff ff ff ff - |.... | 0.056: read: pg:3, ofs=0x0004, num=4 0.056: 00006000: ff ff ff ff- | .... | 0.056: read: pg:3, ofs=0x0008, num=4 0.056: 00006000: -ff ff ff ff | .... | 0.056: read: pg:3, ofs=0x000c, num=4 0.056: 00006000: - ff ff ff ff | ....| 0.056: NVOCMP_ALERT: message=Corrupted or Version/Signature mismatch. 0.056: write: pg:3, ofs=0x0000, num=4 0.056: 00006000: ff 01 0f 96 - |.... | 0.056: write: pg:3, ofs=0x0004, num=4 0.056: 00006000: ff ff ff 96- | .... | 0.056: write: pg:3, ofs=0x0008, num=4 0.056: 00006000: -ff ff ff 96 | .... | 0.056: write: pg:3, ofs=0x000c, num=4 0.056: 00006000: - ff ff ff 96 | ....| 0.056: read: pg:3, ofs=0x0000, num=4 0.056: 00006000: ff 01 0f 96 - |.... | 0.056: read: pg:3, ofs=0x0004, num=4 0.056: 00006000: ff ff ff 96- | .... | 0.056: read: pg:3, ofs=0x0008, num=4 0.056: 00006000: -ff ff ff 96 | .... | 0.056: read: pg:3, ofs=0x000c, num=4 0.056: 00006000: - ff ff ff 96 | ....| 0.056: write: pg:0, ofs=0x0000, num=1 0.056: 00000000: 7e - |~ | 0.056: write: pg:3, ofs=0x0000, num=1 0.056: 00006000: fe - |. | 0.056: pib-set-common: wr_u8: pib-id8: 85, 0x55 0.056: pib-set-common: wr_u16: pib-value16: 8138, 0x1fca 0.056: pib-set-common: wr_u8: filler: 0, 0x0 0.056: pib-set-common: wr_u8: filler: 0, 0x0 0.056: pib-set-common: wr_u8: filler: 0, 0x0 0.056: pib-set-common: wr_u8: filler: 0, 0x0 0.056: pib-set-common: wr_u8: filler: 0, 0x0 0.056: pib-set-common: wr_u8: filler: 0, 0x0 0.056: pib-set-common: wr_u8: filler: 0, 0x0 0.056: pib-set-common: wr_u8: filler: 0, 0x0 0.056: pib-set-common: wr_u8: filler: 0, 0x0 0.056: pib-set-common: wr_u8: filler: 0, 0x0 0.056: pib-set-common: wr_u8: filler: 0, 0x0 0.056: pib-set-common: wr_u8: filler: 0, 0x0 0.056: pib-set-common: wr_u8: filler: 0, 0x0 0.056: pib-set-common: wr_u8: filler: 0, 0x0 0.056: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.056: uart: TX Msg (start) [pib-set-common] 0.056: pib-set-common msg(0015) nbytes=22 len=17 [ 0xfe 0x11 0x22 0x09 0x55 0xca 0x1f 0x00] 0.056: uart: TX 22 bytes 0.056: 00000000: fe 11 22 09 55 ca 1f 00-00 00 00 00 00 00 00 00 |..".U...........| 0.056: 00000010: 00 00 00 00 00 ba - |...... | 0.056: uart-wr: write(22 bytes) = 22 0.056: uart: TX Msg (Complete) r=22 [pib-set-common] 0.060: uart-rd: read(5 bytes) = 5 0.060: uart: nbytes-avail: 5 0.060: 00000000: fe 01 62 09 00 - |..b.. | 0.060: uart-rd: read(1 bytes) = 1 0.060: uart: nbytes-avail: 6 0.060: 00000000: fe 01 62 09 00 6a - |..b..j | 0.060: uart: rx-msg looking for start 0.061: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.061: incoming-msg: rd_u8: status: 0, 0x00 0.061: SREQ: (pib-set-common) SRSP: Result: 0 (0x00) 0.061: pib-set-common: wr_u8: pib-id8: 245, 0xf5 0.061: pib-set-common: wr_u8: pib-value8: 0, 0x0 0.061: pib-set-common: wr_u8: filler: 0, 0x0 0.061: pib-set-common: wr_u8: filler: 0, 0x0 0.061: pib-set-common: wr_u8: filler: 0, 0x0 0.061: pib-set-common: wr_u8: filler: 0, 0x0 0.061: pib-set-common: wr_u8: filler: 0, 0x0 0.061: pib-set-common: wr_u8: filler: 0, 0x0 0.061: pib-set-common: wr_u8: filler: 0, 0x0 0.061: pib-set-common: wr_u8: filler: 0, 0x0 0.061: pib-set-common: wr_u8: filler: 0, 0x0 0.061: pib-set-common: wr_u8: filler: 0, 0x0 0.061: pib-set-common: wr_u8: filler: 0, 0x0 0.061: pib-set-common: wr_u8: filler: 0, 0x0 0.061: pib-set-common: wr_u8: filler: 0, 0x0 0.061: pib-set-common: wr_u8: filler: 0, 0x0 0.061: pib-set-common: wr_u8: filler: 0, 0x0 0.061: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.061: uart: TX Msg (start) [pib-set-common] 0.061: pib-set-common msg(0017) nbytes=22 len=17 [ 0xfe 0x11 0x22 0x09 0xf5 0x00 0x00 0x00] 0.061: uart: TX 22 bytes 0.061: 00000000: fe 11 22 09 f5 00 00 00-00 00 00 00 00 00 00 00 |..".............| 0.061: 00000010: 00 00 00 00 00 cf - |...... | 0.061: uart-wr: write(22 bytes) = 22 0.061: uart: TX Msg (Complete) r=22 [pib-set-common] 0.065: uart-rd: read(5 bytes) = 5 0.065: uart: nbytes-avail: 5 0.065: 00000000: fe 01 62 09 00 - |..b.. | 0.065: uart-rd: read(1 bytes) = 1 0.065: uart: nbytes-avail: 6 0.065: 00000000: fe 01 62 09 00 6a - |..b..j | 0.065: uart: rx-msg looking for start 0.065: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.065: incoming-msg: rd_u8: status: 0, 0x00 0.065: SREQ: (pib-set-common) SRSP: Result: 0 (0x00) 0.065: pib-set-common: wr_u8: pib-id8: 224, 0xe0 0.065: pib-set-common: wr_u8: pib-value8: 14, 0xe 0.065: pib-set-common: wr_u8: filler: 0, 0x0 0.065: pib-set-common: wr_u8: filler: 0, 0x0 0.065: pib-set-common: wr_u8: filler: 0, 0x0 0.065: pib-set-common: wr_u8: filler: 0, 0x0 0.065: pib-set-common: wr_u8: filler: 0, 0x0 0.065: pib-set-common: wr_u8: filler: 0, 0x0 0.065: pib-set-common: wr_u8: filler: 0, 0x0 0.065: pib-set-common: wr_u8: filler: 0, 0x0 0.065: pib-set-common: wr_u8: filler: 0, 0x0 0.065: pib-set-common: wr_u8: filler: 0, 0x0 0.065: pib-set-common: wr_u8: filler: 0, 0x0 0.065: pib-set-common: wr_u8: filler: 0, 0x0 0.065: pib-set-common: wr_u8: filler: 0, 0x0 0.065: pib-set-common: wr_u8: filler: 0, 0x0 0.065: pib-set-common: wr_u8: filler: 0, 0x0 0.065: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.065: uart: TX Msg (start) [pib-set-common] 0.065: pib-set-common msg(0019) nbytes=22 len=17 [ 0xfe 0x11 0x22 0x09 0xe0 0x0e 0x00 0x00] 0.065: uart: TX 22 bytes 0.065: 00000000: fe 11 22 09 e0 0e 00 00-00 00 00 00 00 00 00 00 |..".............| 0.065: 00000010: 00 00 00 00 00 d4 - |...... | 0.065: uart-wr: write(22 bytes) = 22 0.065: uart: TX Msg (Complete) r=22 [pib-set-common] 0.069: uart-rd: read(5 bytes) = 5 0.069: uart: nbytes-avail: 5 0.069: 00000000: fe 01 62 09 00 - |..b.. | 0.069: uart-rd: read(1 bytes) = 1 0.069: uart: nbytes-avail: 6 0.069: 00000000: fe 01 62 09 00 6a - |..b..j | 0.069: uart: rx-msg looking for start 0.069: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.069: incoming-msg: rd_u8: status: 0, 0x00 0.069: SREQ: (pib-set-common) SRSP: Result: 0 (0x00) 0.069: pib-set-common: wr_u8: pib-id8: 79, 0x4f 0.069: pib-set-common: wr_u8: pib-value8: 3, 0x3 0.069: pib-set-common: wr_u8: filler: 0, 0x0 0.069: pib-set-common: wr_u8: filler: 0, 0x0 0.069: pib-set-common: wr_u8: filler: 0, 0x0 0.069: pib-set-common: wr_u8: filler: 0, 0x0 0.069: pib-set-common: wr_u8: filler: 0, 0x0 0.069: pib-set-common: wr_u8: filler: 0, 0x0 0.069: pib-set-common: wr_u8: filler: 0, 0x0 0.069: pib-set-common: wr_u8: filler: 0, 0x0 0.069: pib-set-common: wr_u8: filler: 0, 0x0 0.069: pib-set-common: wr_u8: filler: 0, 0x0 0.069: pib-set-common: wr_u8: filler: 0, 0x0 0.069: pib-set-common: wr_u8: filler: 0, 0x0 0.070: pib-set-common: wr_u8: filler: 0, 0x0 0.070: pib-set-common: wr_u8: filler: 0, 0x0 0.070: pib-set-common: wr_u8: filler: 0, 0x0 0.070: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.070: uart: TX Msg (start) [pib-set-common] 0.070: pib-set-common msg(001b) nbytes=22 len=17 [ 0xfe 0x11 0x22 0x09 0x4f 0x03 0x00 0x00] 0.070: uart: TX 22 bytes 0.070: 00000000: fe 11 22 09 4f 03 00 00-00 00 00 00 00 00 00 00 |..".O...........| 0.070: 00000010: 00 00 00 00 00 76 - |.....v | 0.070: uart-wr: write(22 bytes) = 22 0.070: uart: TX Msg (Complete) r=22 [pib-set-common] 0.073: uart-rd: read(5 bytes) = 5 0.073: uart: nbytes-avail: 5 0.073: 00000000: fe 01 62 09 00 - |..b.. | 0.073: uart-rd: read(1 bytes) = 1 0.073: uart: nbytes-avail: 6 0.073: 00000000: fe 01 62 09 00 6a - |..b..j | 0.073: uart: rx-msg looking for start 0.074: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.074: incoming-msg: rd_u8: status: 0, 0x00 0.074: SREQ: (pib-set-common) SRSP: Result: 0 (0x00) 0.074: pib-set-common: wr_u8: pib-id8: 87, 0x57 0.074: pib-set-common: wr_u8: pib-value8: 5, 0x5 0.074: pib-set-common: wr_u8: filler: 0, 0x0 0.074: pib-set-common: wr_u8: filler: 0, 0x0 0.074: pib-set-common: wr_u8: filler: 0, 0x0 0.074: pib-set-common: wr_u8: filler: 0, 0x0 0.074: pib-set-common: wr_u8: filler: 0, 0x0 0.074: pib-set-common: wr_u8: filler: 0, 0x0 0.074: pib-set-common: wr_u8: filler: 0, 0x0 0.074: pib-set-common: wr_u8: filler: 0, 0x0 0.074: pib-set-common: wr_u8: filler: 0, 0x0 0.074: pib-set-common: wr_u8: filler: 0, 0x0 0.074: pib-set-common: wr_u8: filler: 0, 0x0 0.074: pib-set-common: wr_u8: filler: 0, 0x0 0.074: pib-set-common: wr_u8: filler: 0, 0x0 0.074: pib-set-common: wr_u8: filler: 0, 0x0 0.074: pib-set-common: wr_u8: filler: 0, 0x0 0.074: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.074: uart: TX Msg (start) [pib-set-common] 0.074: pib-set-common msg(001d) nbytes=22 len=17 [ 0xfe 0x11 0x22 0x09 0x57 0x05 0x00 0x00] 0.074: uart: TX 22 bytes 0.074: 00000000: fe 11 22 09 57 05 00 00-00 00 00 00 00 00 00 00 |..".W...........| 0.074: 00000010: 00 00 00 00 00 68 - |.....h | 0.074: uart-wr: write(22 bytes) = 22 0.074: uart: TX Msg (Complete) r=22 [pib-set-common] 0.078: uart-rd: read(5 bytes) = 5 0.078: uart: nbytes-avail: 5 0.078: 00000000: fe 01 62 09 00 - |..b.. | 0.078: uart-rd: read(1 bytes) = 1 0.078: uart: nbytes-avail: 6 0.078: 00000000: fe 01 62 09 00 6a - |..b..j | 0.078: uart: rx-msg looking for start 0.078: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.078: incoming-msg: rd_u8: status: 0, 0x00 0.078: SREQ: (pib-set-common) SRSP: Result: 0 (0x00) 0.078: pib-set-common: wr_u8: pib-id8: 78, 0x4e 0.078: pib-set-common: wr_u8: pib-value8: 5, 0x5 0.078: pib-set-common: wr_u8: filler: 0, 0x0 0.078: pib-set-common: wr_u8: filler: 0, 0x0 0.078: pib-set-common: wr_u8: filler: 0, 0x0 0.078: pib-set-common: wr_u8: filler: 0, 0x0 0.078: pib-set-common: wr_u8: filler: 0, 0x0 0.078: pib-set-common: wr_u8: filler: 0, 0x0 0.078: pib-set-common: wr_u8: filler: 0, 0x0 0.079: pib-set-common: wr_u8: filler: 0, 0x0 0.079: pib-set-common: wr_u8: filler: 0, 0x0 0.079: pib-set-common: wr_u8: filler: 0, 0x0 0.079: pib-set-common: wr_u8: filler: 0, 0x0 0.079: pib-set-common: wr_u8: filler: 0, 0x0 0.079: pib-set-common: wr_u8: filler: 0, 0x0 0.079: pib-set-common: wr_u8: filler: 0, 0x0 0.079: pib-set-common: wr_u8: filler: 0, 0x0 0.079: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.079: uart: TX Msg (start) [pib-set-common] 0.079: pib-set-common msg(001f) nbytes=22 len=17 [ 0xfe 0x11 0x22 0x09 0x4e 0x05 0x00 0x00] 0.079: uart: TX 22 bytes 0.079: 00000000: fe 11 22 09 4e 05 00 00-00 00 00 00 00 00 00 00 |..".N...........| 0.079: 00000010: 00 00 00 00 00 71 - |.....q | 0.079: uart-wr: write(22 bytes) = 22 0.079: uart: TX Msg (Complete) r=22 [pib-set-common] 0.082: uart-rd: read(5 bytes) = 5 0.082: uart: nbytes-avail: 5 0.082: 00000000: fe 01 62 09 00 - |..b.. | 0.083: uart-rd: read(1 bytes) = 1 0.083: uart: nbytes-avail: 6 0.083: 00000000: fe 01 62 09 00 6a - |..b..j | 0.083: uart: rx-msg looking for start 0.083: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.083: incoming-msg: rd_u8: status: 0, 0x00 0.083: SREQ: (pib-set-common) SRSP: Result: 0 (0x00) 0.083: pib-set-common: wr_u8: pib-id8: 89, 0x59 0.083: pib-set-common: wr_u8: pib-value8: 5, 0x5 0.083: pib-set-common: wr_u8: filler: 0, 0x0 0.083: pib-set-common: wr_u8: filler: 0, 0x0 0.083: pib-set-common: wr_u8: filler: 0, 0x0 0.083: pib-set-common: wr_u8: filler: 0, 0x0 0.083: pib-set-common: wr_u8: filler: 0, 0x0 0.083: pib-set-common: wr_u8: filler: 0, 0x0 0.083: pib-set-common: wr_u8: filler: 0, 0x0 0.083: pib-set-common: wr_u8: filler: 0, 0x0 0.083: pib-set-common: wr_u8: filler: 0, 0x0 0.083: pib-set-common: wr_u8: filler: 0, 0x0 0.083: pib-set-common: wr_u8: filler: 0, 0x0 0.083: pib-set-common: wr_u8: filler: 0, 0x0 0.083: pib-set-common: wr_u8: filler: 0, 0x0 0.083: pib-set-common: wr_u8: filler: 0, 0x0 0.083: pib-set-common: wr_u8: filler: 0, 0x0 0.083: not-a-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.083: uart: TX Msg (start) [pib-set-common] 0.083: pib-set-common msg(0021) nbytes=22 len=17 [ 0xfe 0x11 0x22 0x09 0x59 0x05 0x00 0x00] 0.083: uart: TX 22 bytes 0.083: 00000000: fe 11 22 09 59 05 00 00-00 00 00 00 00 00 00 00 |..".Y...........| 0.083: 00000010: 00 00 00 00 00 66 - |.....f | 0.083: uart-wr: write(22 bytes) = 22 0.083: uart: TX Msg (Complete) r=22 [pib-set-common] 0.087: uart-rd: read(5 bytes) = 5 0.087: uart: nbytes-avail: 5 0.087: 00000000: fe 01 62 09 00 - |..b.. | 0.087: uart-rd: read(1 bytes) = 1 0.087: uart: nbytes-avail: 6 0.087: 00000000: fe 01 62 09 00 6a - |..b..j | 0.087: uart: rx-msg looking for start 0.087: not-a-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.087: incoming-msg: rd_u8: status: 0, 0x00 0.087: SREQ: (pib-set-common) SRSP: Result: 0 (0x00) 0.087: not-a-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.087: timer-thread: THREAD_create() 0.087: not-a-thread: MUTEX_unlock(timer-mutex) unlocked 0.087: not-a-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.087: not-a-thread: MUTEX_unlock(timer-mutex) unlocked 0.087: not-a-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.087: not-a-thread: MUTEX_unlock(timer-mutex) unlocked 0.087: not-a-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.087: not-a-thread: MUTEX_unlock(timer-mutex) unlocked 0.087: Found Mac Co-Processor Version info is: 0.087: Transport: 3 0.087: timer-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.087: timer-thread: MUTEX_unlock(timer-mutex) unlocked 0.088: timer-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.088: timer-thread: MUTEX_unlock(timer-mutex) unlocked 0.088: timer-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.088: timer-thread: MUTEX_unlock(timer-mutex) unlocked 0.088: timer-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.088: timer-thread: MUTEX_unlock(timer-mutex) unlocked 0.088: timer-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.088: timer-thread: MUTEX_unlock(timer-mutex) unlocked 0.088: Product: 1 0.088: Major: 2 0.088: Minor: 2 0.088: Maint: 0 0.088: server-thread: THREAD_create() 0.088: collector-thread: THREAD_create() 0.088: read: pg:0, ofs=0x0000, num=4 0.088: 00000000: 7e 01 0f 96 - |~... | 0.088: write: pg:0, ofs=0x0000, num=1 0.088: 00000000: 7c - || | 0.088: read: pg:0, ofs=0x0000, num=4 0.088: 00000000: 7c 01 0f 96 - ||... | 0.088: write: pg:0, ofs=0x0010, num=11 0.088: 00000010: 00 00 00 00 1c 06 00 00-13 26 96 |.........&. | 0.089: nvram: save: nv-simulation.bin, length=32768 0.089: socket(server:5000) ready to accept 0.089: read: pg:0, ofs=0x0014, num=7 0.089: 00000010: 1c 06 00 00-13 26 96 | .....&. | 0.089: secAddKeyInitFrameCounter: wr_u8: newKeyFlag: 1, 0x1 0.089: secAddKeyInitFrameCounter: wr_u16: replaceKeyIndex: 0, 0x0 0.089: secAddKeyInitFrameCounter: wrBuf: key, len: 16 0.089: secAddKeyInitFrameCounter: wr_u32: frameCounter: 0, 0x0 0.089: secAddKeyInitFrameCounter: wr_u8: lookupSize: 9, 0x9 0.089: secAddKeyInitFrameCounter: wrBuf: lookupData, len: 9 0.089: collector-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.089: uart: TX Msg (start) [secAddKeyInitFrameCounter] 0.089: secAddKeyInitFrameCounter msg(0023) nbytes=38 len=33 [ 0xfe 0x21 0x22 0x38 0x01 0x00 0x00 0x12] 0.089: uart: TX 38 bytes 0.089: 00000000: fe 21 22 38 01 00 00 12-34 56 78 9a bc de f0 00 |.!"8....4Vx.....| 0.089: 00000010: 00 00 00 00 00 00 00 00-00 00 00 09 33 33 33 33 |............3333| 0.089: 00000020: 33 33 33 33 03 30 - |3333.0 | 0.089: uart-wr: write(38 bytes) = 38 0.089: uart: TX Msg (Complete) r=38 [secAddKeyInitFrameCounter] 0.095: uart-rd: read(5 bytes) = 5 0.095: uart: nbytes-avail: 5 0.095: 00000000: fe 01 62 38 00 - |..b8. | 0.095: uart-rd: read(1 bytes) = 1 0.095: uart: nbytes-avail: 6 0.095: 00000000: fe 01 62 38 00 5b - |..b8.[ | 0.095: uart: rx-msg looking for start 0.095: collector-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.095: incoming-msg: rd_u8: status: 0, 0x00 0.095: SREQ: (secAddKeyInitFrameCounter) SRSP: Result: 0 (0x00) 0.095: pib-getset-array-common: wr_u8: pib-id8: 137, 0x89 0.095: pib-getset-array-common: wr_u16: idx1-not-used: 0, 0x0 0.095: pib-getset-array-common: wr_u16: idx2-not-used: 0, 0x0 0.095: pib-getset-array-common: wrBuf: data-bytes, len: 8 0.095: collector-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.095: uart: TX Msg (start) [pib-getset-array-common] 0.095: pib-getset-array-common msg(0025) nbytes=18 len=13 [ 0xfe 0x0d 0x22 0x31 0x89 0x00 0x00 0x00] 0.095: uart: TX 18 bytes 0.095: 00000000: fe 0d 22 31 89 00 00 00-00 33 33 33 33 33 33 33 |.."1.....3333333| 0.096: 00000010: 33 97 - |3. | 0.096: uart-wr: write(18 bytes) = 18 0.096: uart: TX Msg (Complete) r=18 [pib-getset-array-common] 0.099: uart-rd: read(5 bytes) = 5 0.099: uart: nbytes-avail: 5 0.099: 00000000: fe 01 62 31 00 - |..b1. | 0.099: uart-rd: read(1 bytes) = 1 0.099: uart: nbytes-avail: 6 0.099: 00000000: fe 01 62 31 00 52 - |..b1.R | 0.099: uart: rx-msg looking for start 0.099: collector-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.099: incoming-msg: rd_u8: status: 0, 0x00 0.099: SREQ: (pib-getset-array-common) SRSP: Result: 0 (0x00) 0.099: mlmeGetSetSecurityReqStruct: wr_u8: addr: 113, 0x71 0.099: mlmeGetSetSecurityReqStruct: wr_u16: idx1: 0, 0x0 0.099: mlmeGetSetSecurityReqStruct: wr_u16: idx2: 0, 0x0 0.099: collector-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.099: uart: TX Msg (start) [mlmeGetSetSecurityReqStruct] 0.099: mlmeGetSetSecurityReqStruct msg(0027) nbytes=10 len=5 [ 0xfe 0x05 0x22 0x31 0x71 0x00 0x00 0x00] 0.099: uart: TX 10 bytes 0.099: 00000000: fe 05 22 31 71 00 00 00-00 67 |.."1q....g | 0.099: uart-wr: write(10 bytes) = 10 0.099: uart: TX Msg (Complete) r=10 [mlmeGetSetSecurityReqStruct] 0.102: uart-rd: read(5 bytes) = 5 0.102: uart: nbytes-avail: 5 0.102: 00000000: fe 01 62 31 00 - |..b1. | 0.102: uart-rd: read(1 bytes) = 1 0.102: uart: nbytes-avail: 6 0.102: 00000000: fe 01 62 31 00 52 - |..b1.R | 0.102: uart: rx-msg looking for start 0.102: collector-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.102: incoming-msg: rd_u8: status: 0, 0x00 0.102: pib-getset-secure: wr_u8: pib-id8: 129, 0x81 0.102: pib-getset-secure: wr_u16: idx1-not-used: 0, 0x0 0.102: pib-getset-secure: wr_u16: idx2-not-used: 0, 0x0 0.102: pib-getset-secure: wr_u16: value16: 1, 0x1 0.102: collector-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.102: uart: TX Msg (start) [pib-getset-secure] 0.102: pib-getset-secure msg(0029) nbytes=12 len=7 [ 0xfe 0x07 0x22 0x31 0x81 0x00 0x00 0x00] 0.102: uart: TX 12 bytes 0.102: 00000000: fe 07 22 31 81 00 00 00-00 01 00 94 |.."1........ | 0.102: uart-wr: write(12 bytes) = 12 0.102: uart: TX Msg (Complete) r=12 [pib-getset-secure] 0.105: uart-rd: read(5 bytes) = 5 0.105: uart: nbytes-avail: 5 0.105: 00000000: fe 01 62 31 00 - |..b1. | 0.105: uart-rd: read(1 bytes) = 1 0.105: uart: nbytes-avail: 6 0.105: 00000000: fe 01 62 31 00 52 - |..b1.R | 0.105: uart: rx-msg looking for start 0.105: collector-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.105: incoming-msg: rd_u8: status: 0, 0x00 0.105: mlmeGetSetSecurityReqStruct: wr_u8: attr: 213, 0xd5 0.105: mlmeGetSetSecurityReqStruct: wr_u8: levelIndex: 0, 0x0 0.105: mlmeGetSetSecurityReqStruct: wr_u8: notused: 0, 0x0 0.105: mlmeGetSetSecurityReqStruct: wr_u16: notused: 0, 0x0 0.105: mlmeGetSetSecurityReqStruct: wr_u8: frameType: 1, 0x1 0.105: mlmeGetSetSecurityReqStruct: wr_u8: frameIdentifier: 4, 0x4 0.105: mlmeGetSetSecurityReqStruct: wr_u8: securityMinimum: 0, 0x0 0.105: mlmeGetSetSecurityReqStruct: wr_u8: override-security: 0, 0x0 0.105: collector-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.105: uart: TX Msg (start) [mlmeGetSetSecurityReqStruct] 0.105: mlmeGetSetSecurityReqStruct msg(002b) nbytes=14 len=9 [ 0xfe 0x09 0x22 0x31 0xd5 0x00 0x00 0x00] 0.105: uart: TX 14 bytes 0.105: 00000000: fe 09 22 31 d5 00 00 00-00 01 04 00 00 ca |.."1.......... | 0.105: uart-wr: write(14 bytes) = 14 0.105: uart: TX Msg (Complete) r=14 [mlmeGetSetSecurityReqStruct] 0.108: uart-rd: read(5 bytes) = 5 0.108: uart: nbytes-avail: 5 0.108: 00000000: fe 01 62 31 00 - |..b1. | 0.108: uart-rd: read(1 bytes) = 1 0.108: uart: nbytes-avail: 6 0.108: 00000000: fe 01 62 31 00 52 - |..b1.R | 0.108: uart: rx-msg looking for start 0.108: collector-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.108: incoming-msg: rd_u8: status: 0, 0x00 0.108: pib-set-common: wr_u8: pib-id8: 93, 0x5d 0.108: pib-set-common: wr_u8: pib-value8: 1, 0x1 0.108: pib-set-common: wr_u8: filler: 0, 0x0 0.108: pib-set-common: wr_u8: filler: 0, 0x0 0.108: pib-set-common: wr_u8: filler: 0, 0x0 0.108: pib-set-common: wr_u8: filler: 0, 0x0 0.108: pib-set-common: wr_u8: filler: 0, 0x0 0.108: pib-set-common: wr_u8: filler: 0, 0x0 0.108: pib-set-common: wr_u8: filler: 0, 0x0 0.108: pib-set-common: wr_u8: filler: 0, 0x0 0.108: pib-set-common: wr_u8: filler: 0, 0x0 0.108: pib-set-common: wr_u8: filler: 0, 0x0 0.108: pib-set-common: wr_u8: filler: 0, 0x0 0.108: pib-set-common: wr_u8: filler: 0, 0x0 0.109: pib-set-common: wr_u8: filler: 0, 0x0 0.109: pib-set-common: wr_u8: filler: 0, 0x0 0.109: pib-set-common: wr_u8: filler: 0, 0x0 0.109: collector-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.109: uart: TX Msg (start) [pib-set-common] 0.109: pib-set-common msg(002d) nbytes=22 len=17 [ 0xfe 0x11 0x22 0x09 0x5d 0x01 0x00 0x00] 0.109: uart: TX 22 bytes 0.109: 00000000: fe 11 22 09 5d 01 00 00-00 00 00 00 00 00 00 00 |..".]...........| 0.109: 00000010: 00 00 00 00 00 66 - |.....f | 0.109: uart-wr: write(22 bytes) = 22 0.109: uart: TX Msg (Complete) r=22 [pib-set-common] 0.112: uart-rd: read(5 bytes) = 5 0.112: uart: nbytes-avail: 5 0.112: 00000000: fe 01 62 09 00 - |..b.. | 0.112: uart-rd: read(1 bytes) = 1 0.112: uart: nbytes-avail: 6 0.112: 00000000: fe 01 62 09 00 6a - |..b..j | 0.112: uart: rx-msg looking for start 0.112: collector-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.112: incoming-msg: rd_u8: status: 0, 0x00 0.112: SREQ: (pib-set-common) SRSP: Result: 0 (0x00) 0.112: stateChangeUpdate, newstate: (1) startingCoordinator 0.112: collector-thread: MUTEX_lock(all-connections) success (recursion=1) 0.112: collector-thread: MUTEX_unlock(all-connections) unlocked 0.112: collector-thread: MUTEX_lock(all-connections) success (recursion=1) 0.112: collector-thread: MUTEX_unlock(all-connections) unlocked 0.112: collector-thread: MUTEX_lock(all-connections) success (recursion=1) 0.113: collector-thread: MUTEX_unlock(all-connections) unlocked 0.113: Cllc_startNetwork path2 0.113: Cllc_startNetwork Cllc_coordStates_scanActive 0.113: Cllc_process 0008 0.113: processState 1 0.113: mlmeScanReq: wr_u8: scanType: 1, 0x1 0.113: mlmeScanReq: wr_u8: scanDuration: 5, 0x5 0.113: mlmeScanReq: wr_u8: channelPage: 9, 0x9 0.113: mlmeScanReq: wr_u8: phyID: 3, 0x3 0.113: mlmeScanReq: wr_u8: maxResults: 0, 0x0 0.113: mlmeScanReq: wr_u8: permitJoining: 0, 0x0 0.113: mlmeScanReq: wr_u8: linkQuality: 1, 0x1 0.113: mlmeScanReq: wr_u8: percentFilter: 255, 0xff 0.113: mlmeScanReq: wr_u8: MPMScan: 0, 0x0 0.113: mlmeScanReq: wr_u8: MPMScantype: 0, 0x0 0.113: mlmeScanReq: wr_u16: MPMScanDuration: 0, 0x0 0.113: mlmeScanReq: wrBuf: keySource, len: 8 0.113: mlmeScanReq: wr_u8: securityLevel: 0, 0x0 0.113: mlmeScanReq: wr_u8: keyIdMode: 0, 0x0 0.113: mlmeScanReq: wr_u8: keyIndex: 0, 0x0 0.113: mlmeScanReq: wrBuf: scanChannels, len: 17 0.113: collector-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.113: uart: TX Msg (start) [mlmeScanReq] 0.113: mlmeScanReq msg(0030) nbytes=45 len=40 [ 0xfe 0x28 0x22 0x0c 0x01 0x05 0x09 0x03] 0.113: uart: TX 45 bytes 0.113: 00000000: fe 28 22 0c 01 05 09 03-00 00 01 ff 00 00 00 00 |.(".............| 0.113: 00000010: 00 00 00 00 00 00 00 00-00 00 00 01 00 00 00 00 |................| 0.113: 00000020: 00 00 00 00 00 00 00 00-00 00 00 00 f7 |............. | 0.113: uart-wr: write(45 bytes) = 45 0.113: uart: TX Msg (Complete) r=45 [mlmeScanReq] 0.119: uart-rd: read(5 bytes) = 5 0.119: uart: nbytes-avail: 5 0.119: 00000000: fe 01 62 0c 00 - |..b.. | 0.119: uart-rd: read(1 bytes) = 1 0.119: uart: nbytes-avail: 6 0.119: 00000000: fe 01 62 0c 00 6f - |..b..o | 0.119: uart: rx-msg looking for start 0.119: collector-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.119: incoming-msg: rd_u8: status: 0, 0x00 0.119: SREQ: (mlmeScanReq) SRSP: Result: 0 (0x00) 0.187: timer-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.187: timer-thread: MUTEX_unlock(timer-mutex) unlocked 0.187: timer-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.187: timer-thread: MUTEX_unlock(timer-mutex) unlocked 0.187: timer-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.187: timer-thread: MUTEX_unlock(timer-mutex) unlocked 0.187: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 0.187: collector-thread: MUTEX_unlock(mi-lock) unlocked 0.187: no-msg 0.187: collector-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.187: collector-thread: MUTEX_unlock(timer-mutex) unlocked 0.187: collector-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.187: collector-thread: MUTEX_unlock(timer-mutex) unlocked 0.187: timer-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.187: timer-thread: MUTEX_unlock(timer-mutex) unlocked 0.187: collector-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.187: collector-thread: MUTEX_unlock(timer-mutex) unlocked 0.187: collector-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.187: collector-thread: MUTEX_unlock(timer-mutex) unlocked 0.187: timer-thread: MUTEX_lock(timer-mutex) success (recursion=1) 0.187: timer-thread: MUTEX_unlock(timer-mutex) unlocked 0.187: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 0.187: collector-thread: MUTEX_unlock(mi-lock) unlocked 0.187: no-msg 0.380: socket(server:5000) new cid: 2, h: 11 0.380: socket(server:5000) peer: 127.0.0.1, port 37856 0.380: thread-u2s-0: THREAD_create() 0.380: s2u-0-iface: THREAD_create() 0.380: thread-u2s-0: MUTEX_lock(all-connections) success (recursion=1) 0.380: thread-u2s-0: MUTEX_unlock(all-connections) unlocked 0.381: s2u-0-iface: rx-msg looking for start 0.381: server-rd: read(4 bytes) = 4 0.381: s2u-0-iface: nbytes-avail: 4 0.381: 00000000: 00 00 0a 03 - |.... | 0.381: rx areq 0.381: incoming-msg msg(0032) nbytes=4 len=0 [ 0x00 0x00 0x0a 0x03] 0.381: s2u-0-iface: MUTEX_lock(mi-lock) success (recursion=1) 0.381: s2u-0-iface: MUTEX_unlock(mi-lock) unlocked 0.381: s2u-0-iface: rx-msg looking for start 0.381: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 0.381: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 0.381: START MSG: BBBBBBBBBBBBBBBBBBBBBBBBBBBBB 0.381: ______________________________ 0.381: getnwkinfo req message 0.381: ______________________________ 0.381: read: pg:0, ofs=0x0014, num=7 0.381: 00000010: 1c 06 00 00-13 26 96 | .....&. | 0.381: 0.381: Sending NwkCnf STATE = 1 0.381: 0.381: thread-u2s-0: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.381: s2u-0-iface: TX Msg (start) [(null)] 0.381: msg(0034) nbytes=22 len=18 [ 0x12 0x00 0x4a 0x05 0x00 0x00 0x00 0x00] 0.381: s2u-0-iface: TX 22 bytes 0.381: 00000000: 12 00 4a 05 00 00 00 00-00 00 00 00 00 01 00 00 |..J.............| 0.381: 00000010: 00 00 00 01 02 01 - |...... | 0.381: server-wr: write(22 bytes) = 22 0.381: s2u-0-iface: TX Msg (Complete) r=22 [(null)] 0.381: thread-u2s-0: MUTEX_unlock(mi-tx-lock) unlocked 0.381: END MSG: BBBBBBBBBBBBBBBBBBBBBBBBBBBBB 0.381: server-rd: read(4 bytes) = 4 0.381: s2u-0-iface: nbytes-avail: 4 0.381: 00000000: 25 00 0a 0d - |%... | 0.381: server-rd: read(37 bytes) = 33 0.423: server-rd: read(4 bytes) = 4 0.423: s2u-0-iface: nbytes-avail: 41 0.423: 00000000: 25 00 0a 0d 8c 00 00 2f-68 6f 6d 65 2f 65 70 6f |%....../home/epo| 0.423: 00000010: 75 72 2f 66 69 72 6d 77-61 72 65 2f 6c 69 76 65 |ur/firmware/live| 0.423: 00000020: 2e 62 69 6e 00 00 00 0a-06 |.bin..... | 0.424: rx areq 0.424: incoming-msg msg(0033) nbytes=41 len=37 [ 0x25 0x00 0x0a 0x0d 0x8c 0x00 0x00 0x2f] 0.424: s2u-0-iface: MUTEX_lock(mi-lock) success (recursion=1) 0.424: s2u-0-iface: MUTEX_unlock(mi-lock) unlocked 0.424: s2u-0-iface: rx-msg looking for start 0.424: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 0.424: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 0.424: START MSG: CCCCCCCCCCCCCCCCCCCCCCCCCCCCC 0.424: ______________________________ 0.424: rcvd req to send message to a device 0.424: ______________________________ 0.424: server-rd: read(4 bytes) = 4 0.424: s2u-0-iface: nbytes-avail: 4 0.424: appsrv_processTxDataReq 8C 0000 7 37 0.424: Smsgs_cmdIds_EpourOadFile received 0.424: 00000000: 04 00 0a 0b - |.... | 0.424: Collector_updateFwList: new oad file: /home/epour/firmware/live.bin 0.424: Collector_updateFwList: Added /home/epour/firmware/live.bin, ID 1 0.424: Info: OAD file /home/epour/firmware/live.bin 0.424: msgId == 8C 140 0.424: thread-u2s-0: MUTEX_lock(all-connections) success (recursion=1) 0.424: thread-u2s-0: MUTEX_unlock(all-connections) unlocked 0.424: thread-u2s-0: MUTEX_lock(all-connections) success (recursion=1) 0.424: thread-u2s-0: MUTEX_unlock(all-connections) unlocked 0.424: server-rd: read(4 bytes) = 4 0.424: s2u-0-iface: nbytes-avail: 8 0.424: MT_MSG: clone(appClient, id: 54) to: id: 55 0.424: thread-u2s-0: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.424: s2u-0-iface: TX Msg (start) [(null)] 0.424: msg(0037) nbytes=8 len=4 [ 0x04 0x00 0x4a 0x0e 0x00 0x00 0x00 0x00] 0.424: s2u-0-iface: TX 8 bytes 0.424: 00000000: 04 00 4a 0e 00 00 00 00- |..J..... | 0.424: 00000000: 04 00 0a 0b 00 00 00 00- |........ | 0.424: rx areq 0.424: incoming-msg msg(0035) nbytes=8 len=4 [ 0x04 0x00 0x0a 0x0b 0x00 0x00 0x00 0x00] 0.424: s2u-0-iface: MUTEX_lock(mi-lock) success (recursion=1) 0.424: s2u-0-iface: MUTEX_unlock(mi-lock) unlocked 0.424: server-wr: write(8 bytes) = 8 0.424: s2u-0-iface: TX Msg (Complete) r=8 [(null)] 0.424: thread-u2s-0: MUTEX_unlock(mi-tx-lock) unlocked 0.424: thread-u2s-0: MUTEX_lock(all-connections) success (recursion=1) 0.424: thread-u2s-0: MUTEX_unlock(all-connections) unlocked 0.424: thread-u2s-0: MUTEX_lock(all-connections) success (recursion=1) 0.424: thread-u2s-0: MUTEX_unlock(all-connections) unlocked 0.424: END MSG: CCCCCCCCCCCCCCCCCCCCCCCCCCCCC 0.424: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 0.424: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 0.424: START MSG: DDDDDDDDDDDDDDDDDDDDDDDDDDDDD 0.425: ______________________________ 0.425: rcvd join premit message 0.425: ______________________________ 0.425: 0.425: Sending duration: 0x0 0.425: 0.425: pib-set-common: wr_u8: pib-id8: 65, 0x41 0.425: pib-set-common: wr_u8: pib-value8: 0, 0x0 0.425: pib-set-common: wr_u8: filler: 0, 0x0 0.425: pib-set-common: wr_u8: filler: 0, 0x0 0.425: pib-set-common: wr_u8: filler: 0, 0x0 0.425: pib-set-common: wr_u8: filler: 0, 0x0 0.425: pib-set-common: wr_u8: filler: 0, 0x0 0.425: pib-set-common: wr_u8: filler: 0, 0x0 0.425: pib-set-common: wr_u8: filler: 0, 0x0 0.425: pib-set-common: wr_u8: filler: 0, 0x0 0.425: pib-set-common: wr_u8: filler: 0, 0x0 0.425: pib-set-common: wr_u8: filler: 0, 0x0 0.425: pib-set-common: wr_u8: filler: 0, 0x0 0.425: pib-set-common: wr_u8: filler: 0, 0x0 0.425: pib-set-common: wr_u8: filler: 0, 0x0 0.425: pib-set-common: wr_u8: filler: 0, 0x0 0.425: pib-set-common: wr_u8: filler: 0, 0x0 0.425: thread-u2s-0: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.425: uart: TX Msg (start) [pib-set-common] 0.425: pib-set-common msg(0039) nbytes=22 len=17 [ 0xfe 0x11 0x22 0x09 0x41 0x00 0x00 0x00] 0.425: uart: TX 22 bytes 0.425: 00000000: fe 11 22 09 41 00 00 00-00 00 00 00 00 00 00 00 |..".A...........| 0.425: 00000010: 00 00 00 00 00 7b - |.....{ | 0.425: s2u-0-iface: rx-msg looking for start 0.425: uart-wr: write(22 bytes) = 22 0.425: uart: TX Msg (Complete) r=22 [pib-set-common] 0.425: server-rd: read(4 bytes) = 4 0.425: s2u-0-iface: nbytes-avail: 4 0.425: 00000000: 00 00 0a 06 - |.... | 0.425: rx areq 0.425: incoming-msg msg(0038) nbytes=4 len=0 [ 0x00 0x00 0x0a 0x06] 0.425: s2u-0-iface: MUTEX_lock(mi-lock) success (recursion=1) 0.425: s2u-0-iface: MUTEX_unlock(mi-lock) unlocked 0.425: s2u-0-iface: rx-msg looking for start 0.429: uart-rd: read(5 bytes) = 5 0.429: uart: nbytes-avail: 5 0.429: 00000000: fe 01 62 09 00 - |..b.. | 0.429: uart-rd: read(1 bytes) = 1 0.429: uart: nbytes-avail: 6 0.429: 00000000: fe 01 62 09 00 6a - |..b..j | 0.429: uart: rx-msg looking for start 0.429: thread-u2s-0: MUTEX_unlock(mi-tx-lock) unlocked 0.429: incoming-msg: rd_u8: status: 0, 0x00 0.429: SREQ: (pib-set-common) SRSP: Result: 0 (0x00) 0.429: stateChangeUpdate, newstate: (6) joiningNotAllowed 0.429: thread-u2s-0: MUTEX_lock(all-connections) success (recursion=1) 0.429: thread-u2s-0: MUTEX_unlock(all-connections) unlocked 0.429: thread-u2s-0: MUTEX_lock(all-connections) success (recursion=1) 0.429: thread-u2s-0: MUTEX_unlock(all-connections) unlocked 0.429: MT_MSG: clone(appClient, id: 60) to: id: 61 0.429: thread-u2s-0: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.429: s2u-0-iface: TX Msg (start) [(null)] 0.429: msg(003d) nbytes=5 len=1 [ 0x01 0x00 0x4a 0x0a 0x06] 0.429: s2u-0-iface: TX 5 bytes 0.429: 00000000: 01 00 4a 0a 06 - |..J.. | 0.429: server-wr: write(5 bytes) = 5 0.429: s2u-0-iface: TX Msg (Complete) r=5 [(null)] 0.429: thread-u2s-0: MUTEX_unlock(mi-tx-lock) unlocked 0.429: thread-u2s-0: MUTEX_lock(all-connections) success (recursion=1) 0.429: thread-u2s-0: MUTEX_unlock(all-connections) unlocked 0.429: thread-u2s-0: MUTEX_lock(all-connections) success (recursion=1) 0.429: thread-u2s-0: MUTEX_unlock(all-connections) unlocked 0.429: 0.429: Sending permitCnf message 0.429: 0.429: thread-u2s-0: MUTEX_lock(all-connections) success (recursion=1) 0.429: thread-u2s-0: MUTEX_unlock(all-connections) unlocked 0.429: thread-u2s-0: MUTEX_lock(all-connections) success (recursion=1) 0.429: thread-u2s-0: MUTEX_unlock(all-connections) unlocked 0.429: MT_MSG: clone(appClient, id: 62) to: id: 63 0.429: thread-u2s-0: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.429: s2u-0-iface: TX Msg (start) [(null)] 0.429: msg(003f) nbytes=8 len=4 [ 0x04 0x00 0x4a 0x0c 0x00 0x00 0x00 0x00] 0.429: s2u-0-iface: TX 8 bytes 0.429: 00000000: 04 00 4a 0c 00 00 00 00- |..J..... | 0.429: server-wr: write(8 bytes) = 8 0.429: s2u-0-iface: TX Msg (Complete) r=8 [(null)] 0.429: thread-u2s-0: MUTEX_unlock(mi-tx-lock) unlocked 0.429: thread-u2s-0: MUTEX_lock(all-connections) success (recursion=1) 0.429: thread-u2s-0: MUTEX_unlock(all-connections) unlocked 0.429: thread-u2s-0: MUTEX_lock(all-connections) success (recursion=1) 0.430: thread-u2s-0: MUTEX_unlock(all-connections) unlocked 0.430: END MSG: DDDDDDDDDDDDDDDDDDDDDDDDDDDDD 0.430: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 0.430: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 0.430: START MSG: EEEEEEEEEEEEEEEEEEEEEEEEEEEEE 0.430: ______________________________ 0.430: rcvd get device array msg 0.430: ______________________________ 0.430: read: pg:0, ofs=0x0014, num=7 0.430: 00000010: 1c 06 00 00-13 26 96 | .....&. | 0.430: thread-u2s-0: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.430: s2u-0-iface: TX Msg (start) [(null)] 0.430: msg(0040) nbytes=7 len=3 [ 0x03 0x00 0x4a 0x07 0x00 0x00 0x00] 0.430: s2u-0-iface: TX 7 bytes 0.430: 00000000: 03 00 4a 07 00 00 00 - |..J.... | 0.430: server-wr: write(7 bytes) = 7 0.430: s2u-0-iface: TX Msg (Complete) r=7 [(null)] 0.430: thread-u2s-0: MUTEX_unlock(mi-tx-lock) unlocked 0.430: END MSG: EEEEEEEEEEEEEEEEEEEEEEEEEEEEE 0.487: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 0.487: collector-thread: MUTEX_unlock(mi-lock) unlocked 0.487: no-msg 0.767: uart-rd: read(5 bytes) = 5 0.767: uart: nbytes-avail: 5 0.767: 00000000: fe 16 42 8c ea - |..B.. | 0.767: uart-rd: read(22 bytes) = 11 0.768: uart-rd: read(11 bytes) = 11 0.768: uart: nbytes-avail: 27 0.768: 00000000: fe 16 42 8c ea 01 09 03-00 00 00 00 00 00 00 00 |..B.............| 0.768: 00000010: 00 00 00 00 00 00 00 00-00 00 39 |..........9 | 0.768: rx areq 0.768: incoming-msg msg(003b) nbytes=27 len=22 [ 0xfe 0x16 0x42 0x8c 0xea 0x01 0x09 0x03] 0.768: uart: MUTEX_lock(mi-lock) success (recursion=1) 0.768: uart: MUTEX_unlock(mi-lock) unlocked 0.768: uart: rx-msg looking for start 0.768: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 0.768: collector-thread: MUTEX_unlock(mi-lock) unlocked 0.768: uart: rx: scan-cnf 0.768: process-areq 0.768: scan-cnf msg(003b) nbytes=27 len=22 [ 0xfe 0x16 0x42 0x8c 0xea 0x01 0x09 0x03] 0.768: scan-cnf: rd_u8: status: 234, 0xea 0.768: scan-cnf: rd_u8: scanType: 1, 0x01 0.768: scan-cnf: rd_u8: channelPage: 9, 0x09 0.768: scan-cnf: rd_u8: phyId: 3, 0x03 0.768: scan-cnf: rdBuf: unscannedchannels, len: 17 0.768: scan-cnf: rd_u8: resultListSize: 0, 0x00 0.768: Cllc_process 0008 0.768: processState 2 0.768: mlmeScanReq: wr_u8: scanType: 0, 0x0 0.768: mlmeScanReq: wr_u8: scanDuration: 5, 0x5 0.768: mlmeScanReq: wr_u8: channelPage: 9, 0x9 0.768: mlmeScanReq: wr_u8: phyID: 3, 0x3 0.768: mlmeScanReq: wr_u8: maxResults: 0, 0x0 0.768: mlmeScanReq: wr_u8: permitJoining: 0, 0x0 0.768: mlmeScanReq: wr_u8: linkQuality: 1, 0x1 0.768: mlmeScanReq: wr_u8: percentFilter: 255, 0xff 0.768: mlmeScanReq: wr_u8: MPMScan: 0, 0x0 0.768: mlmeScanReq: wr_u8: MPMScantype: 0, 0x0 0.768: mlmeScanReq: wr_u16: MPMScanDuration: 0, 0x0 0.768: mlmeScanReq: wrBuf: keySource, len: 8 0.768: mlmeScanReq: wr_u8: securityLevel: 0, 0x0 0.768: mlmeScanReq: wr_u8: keyIdMode: 0, 0x0 0.768: mlmeScanReq: wr_u8: keyIndex: 0, 0x0 0.768: mlmeScanReq: wrBuf: scanChannels, len: 17 0.768: collector-thread: MUTEX_lock(mi-tx-lock) success (recursion=1) 0.769: uart: TX Msg (start) [mlmeScanReq] 0.769: mlmeScanReq msg(0042) nbytes=45 len=40 [ 0xfe 0x28 0x22 0x0c 0x00 0x05 0x09 0x03] 0.769: uart: TX 45 bytes 0.769: 00000000: fe 28 22 0c 00 05 09 03-00 00 01 ff 00 00 00 00 |.(".............| 0.769: 00000010: 00 00 00 00 00 00 00 00-00 00 00 01 00 00 00 00 |................| 0.769: 00000020: 00 00 00 00 00 00 00 00-00 00 00 00 f6 |............. | 0.769: uart-wr: write(45 bytes) = 45 0.769: uart: TX Msg (Complete) r=45 [mlmeScanReq] 0.775: uart-rd: read(5 bytes) = 5 0.775: uart: nbytes-avail: 5 0.775: 00000000: fe 01 62 0c 00 - |..b.. | 0.775: uart-rd: read(1 bytes) = 1 0.775: uart: nbytes-avail: 6 0.775: 00000000: fe 01 62 0c 00 6f - |..b..o | 0.775: uart: rx-msg looking for start 0.775: collector-thread: MUTEX_unlock(mi-tx-lock) unlocked 0.775: incoming-msg: rd_u8: status: 0, 0x00 0.775: SREQ: (mlmeScanReq) SRSP: Result: 0 (0x00) 1.075: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 1.075: collector-thread: MUTEX_unlock(mi-lock) unlocked 1.075: no-msg 1.087: timer-thread: MUTEX_lock(timer-mutex) success (recursion=1) 1.087: timer-thread: MUTEX_unlock(timer-mutex) unlocked 1.087: timer-thread: MUTEX_lock(timer-mutex) success (recursion=1) 1.087: timer-thread: MUTEX_unlock(timer-mutex) unlocked 1.088: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 1.088: collector-thread: MUTEX_unlock(mi-lock) unlocked 1.088: no-msg 1.388: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 1.388: collector-thread: MUTEX_unlock(mi-lock) unlocked 1.388: no-msg 1.430: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 1.430: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 1.484: uart-rd: read(5 bytes) = 5 1.484: uart: nbytes-avail: 5 1.484: 00000000: fe 97 42 8c 00 - |..B.. | 1.484: uart-rd: read(151 bytes) = 11 1.485: uart-rd: read(140 bytes) = 16 1.487: uart-rd: read(124 bytes) = 16 1.488: uart-rd: read(108 bytes) = 16 1.490: uart-rd: read(92 bytes) = 16 1.491: uart-rd: read(76 bytes) = 16 1.493: uart-rd: read(60 bytes) = 16 1.494: uart-rd: read(44 bytes) = 16 1.495: uart-rd: read(28 bytes) = 16 1.496: uart-rd: read(12 bytes) = 12 1.497: uart: nbytes-avail: 156 1.497: 00000000: fe 97 42 8c 00 00 09 03-00 00 00 00 00 00 00 00 |..B.............| 1.497: 00000010: 00 00 00 00 00 00 00 00-00 81 00 ff ff ff ff ff |................| 1.497: 00000020: ff ff ff ff ff ff ff ff-ff ff ff ff ff ff ff ff |................| 1.497: 00000030: ff ff ff ff ff ff ff ff-ff ff ff ff ff ff ff ff |................| 1.497: 00000040: ff ff ff ff ff ff ff ff-ff ff ff ff ff ff ff ff |................| 1.497: 00000050: ff ff ff ff ff ff ff ff-ff ff ff ff ff ff ff ff |................| 1.497: 00000060: ff ff ff ff ff ff ff ff-ff ff ff ff ff ff ff ff |................| 1.497: 00000070: ff ff ff ff ff ff ff ff-ff ff ff ff ff ff ff ff |................| 1.497: 00000080: ff ff ff ff ff ff ff ff-ff ff ff ff ff ff ff ff |................| 1.497: 00000090: ff ff ff ff ff ff ff ff-ff ff ff d2 |............ | 1.497: rx areq 1.497: incoming-msg msg(0043) nbytes=156 len=151 [ 0xfe 0x97 0x42 0x8c 0x00 0x00 0x09 0x03] 1.497: uart: MUTEX_lock(mi-lock) success (recursion=1) 1.497: uart: MUTEX_unlock(mi-lock) unlocked 1.497: uart: rx-msg looking for start 1.497: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 1.497: collector-thread: MUTEX_unlock(mi-lock) unlocked 1.497: uart: rx: scan-cnf 1.497: process-areq 1.497: scan-cnf msg(0043) nbytes=156 len=151 [ 0xfe 0x97 0x42 0x8c 0x00 0x00 0x09 0x03] 1.497: scan-cnf: rd_u8: status: 0, 0x00 1.497: scan-cnf: rd_u8: scanType: 0, 0x00 1.497: scan-cnf: rd_u8: channelPage: 9, 0x09 1.497: scan-cnf: rd_u8: phyId: 3, 0x03 1.497: scan-cnf: rdBuf: unscannedchannels, len: 17 1.497: scan-cnf: rd_u8: resultListSize: 129, 0x81 1.497: scan-cnf: rdBuf: energydata, len: 129 1.497: Cllc_process 0008 1.497: processState 3 1.797: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 1.797: collector-thread: MUTEX_unlock(mi-lock) unlocked 1.797: no-msg 2.097: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 2.097: collector-thread: MUTEX_unlock(mi-lock) unlocked 2.097: no-msg 2.398: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 2.398: collector-thread: MUTEX_unlock(mi-lock) unlocked 2.398: no-msg 2.430: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 2.430: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 2.698: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 2.698: collector-thread: MUTEX_unlock(mi-lock) unlocked 2.698: no-msg 2.998: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 2.998: collector-thread: MUTEX_unlock(mi-lock) unlocked 2.998: no-msg 3.298: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 3.298: collector-thread: MUTEX_unlock(mi-lock) unlocked 3.298: no-msg 3.430: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 3.430: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 3.598: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 3.599: collector-thread: MUTEX_unlock(mi-lock) unlocked 3.599: no-msg 3.899: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 3.899: collector-thread: MUTEX_unlock(mi-lock) unlocked 3.899: no-msg 4.199: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 4.199: collector-thread: MUTEX_unlock(mi-lock) unlocked 4.199: no-msg 4.431: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 4.431: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 4.499: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 4.499: collector-thread: MUTEX_unlock(mi-lock) unlocked 4.499: no-msg 4.800: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 4.800: collector-thread: MUTEX_unlock(mi-lock) unlocked 4.800: no-msg 5.100: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 5.100: collector-thread: MUTEX_unlock(mi-lock) unlocked 5.100: no-msg 5.400: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 5.400: collector-thread: MUTEX_unlock(mi-lock) unlocked 5.400: no-msg 5.431: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 5.431: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 5.700: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 5.701: collector-thread: MUTEX_unlock(mi-lock) unlocked 5.701: no-msg 6.001: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 6.001: collector-thread: MUTEX_unlock(mi-lock) unlocked 6.001: no-msg 6.301: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 6.301: collector-thread: MUTEX_unlock(mi-lock) unlocked 6.301: no-msg 6.431: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 6.431: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 6.601: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 6.601: collector-thread: MUTEX_unlock(mi-lock) unlocked 6.601: no-msg 6.901: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 6.901: collector-thread: MUTEX_unlock(mi-lock) unlocked 6.901: no-msg 7.201: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 7.202: collector-thread: MUTEX_unlock(mi-lock) unlocked 7.202: no-msg 7.431: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 7.431: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 7.502: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 7.502: collector-thread: MUTEX_unlock(mi-lock) unlocked 7.502: no-msg 7.802: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 7.802: collector-thread: MUTEX_unlock(mi-lock) unlocked 7.802: no-msg 8.102: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 8.102: collector-thread: MUTEX_unlock(mi-lock) unlocked 8.102: no-msg 8.402: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 8.403: collector-thread: MUTEX_unlock(mi-lock) unlocked 8.403: no-msg 8.431: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 8.431: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 8.703: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 8.703: collector-thread: MUTEX_unlock(mi-lock) unlocked 8.703: no-msg 9.003: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 9.003: collector-thread: MUTEX_unlock(mi-lock) unlocked 9.003: no-msg 9.303: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 9.303: collector-thread: MUTEX_unlock(mi-lock) unlocked 9.303: no-msg 9.432: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 9.432: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 9.603: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 9.603: collector-thread: MUTEX_unlock(mi-lock) unlocked 9.603: no-msg 9.903: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 9.904: collector-thread: MUTEX_unlock(mi-lock) unlocked 9.904: no-msg 10.188: timer-thread: MUTEX_lock(timer-mutex) success (recursion=1) 10.188: timer-thread: MUTEX_unlock(timer-mutex) unlocked 10.188: timer-thread: MUTEX_lock(timer-mutex) success (recursion=1) 10.188: timer-thread: MUTEX_unlock(timer-mutex) unlocked 10.188: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 10.188: collector-thread: MUTEX_unlock(mi-lock) unlocked 10.188: no-msg 10.188: collector-thread: MUTEX_lock(timer-mutex) success (recursion=1) 10.188: collector-thread: MUTEX_unlock(timer-mutex) unlocked 10.188: collector-thread: MUTEX_lock(timer-mutex) success (recursion=1) 10.188: collector-thread: MUTEX_unlock(timer-mutex) unlocked 10.188: timer-thread: MUTEX_lock(timer-mutex) success (recursion=1) 10.188: timer-thread: MUTEX_unlock(timer-mutex) unlocked 10.432: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 10.432: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 10.432: s2u-0-iface: rx-silent 10.432: s2u-0-iface: rx-msg looking for start 10.488: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 10.488: collector-thread: MUTEX_unlock(mi-lock) unlocked 10.488: no-msg 10.788: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 10.788: collector-thread: MUTEX_unlock(mi-lock) unlocked 10.788: no-msg 11.088: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 11.089: collector-thread: MUTEX_unlock(mi-lock) unlocked 11.089: no-msg 11.389: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 11.389: collector-thread: MUTEX_unlock(mi-lock) unlocked 11.389: no-msg 11.432: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 11.432: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 11.504: uart: rx-silent 11.504: uart: rx-msg looking for start 11.689: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 11.689: collector-thread: MUTEX_unlock(mi-lock) unlocked 11.689: no-msg 11.989: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 11.989: collector-thread: MUTEX_unlock(mi-lock) unlocked 11.989: no-msg 12.290: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 12.290: collector-thread: MUTEX_unlock(mi-lock) unlocked 12.290: no-msg 12.432: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 12.432: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 12.590: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 12.590: collector-thread: MUTEX_unlock(mi-lock) unlocked 12.590: no-msg 12.890: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 12.890: collector-thread: MUTEX_unlock(mi-lock) unlocked 12.890: no-msg 13.190: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 13.191: collector-thread: MUTEX_unlock(mi-lock) unlocked 13.191: no-msg 13.432: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 13.433: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 13.491: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 13.491: collector-thread: MUTEX_unlock(mi-lock) unlocked 13.491: no-msg 13.791: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 13.791: collector-thread: MUTEX_unlock(mi-lock) unlocked 13.791: no-msg 14.091: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 14.091: collector-thread: MUTEX_unlock(mi-lock) unlocked 14.091: no-msg 14.392: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 14.392: collector-thread: MUTEX_unlock(mi-lock) unlocked 14.392: no-msg 14.433: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 14.433: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 14.692: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 14.692: collector-thread: MUTEX_unlock(mi-lock) unlocked 14.692: no-msg 14.992: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 14.992: collector-thread: MUTEX_unlock(mi-lock) unlocked 14.993: no-msg 15.293: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 15.293: collector-thread: MUTEX_unlock(mi-lock) unlocked 15.293: no-msg 15.433: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 15.433: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 15.593: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 15.593: collector-thread: MUTEX_unlock(mi-lock) unlocked 15.593: no-msg 15.893: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 15.893: collector-thread: MUTEX_unlock(mi-lock) unlocked 15.893: no-msg 16.194: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 16.194: collector-thread: MUTEX_unlock(mi-lock) unlocked 16.194: no-msg 16.433: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 16.433: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 16.494: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 16.494: collector-thread: MUTEX_unlock(mi-lock) unlocked 16.494: no-msg 16.794: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 16.794: collector-thread: MUTEX_unlock(mi-lock) unlocked 16.794: no-msg 17.094: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 17.095: collector-thread: MUTEX_unlock(mi-lock) unlocked 17.095: no-msg 17.395: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 17.395: collector-thread: MUTEX_unlock(mi-lock) unlocked 17.395: no-msg 17.434: thread-u2s-0: MUTEX_lock(mi-lock) success (recursion=1) 17.434: thread-u2s-0: MUTEX_unlock(mi-lock) unlocked 17.695: collector-thread: MUTEX_lock(mi-lock) success (recursion=1) 17.695: collector-thread: MUTEX_unlock(mi-lock) unlocked 17.695: no-msg