2023-01-09 17:00:08,668 Writer#Run Send Primary Message 24
2023-01-09 17:00:08,670 Timer::StartT3Timer, SystemBytes=24
2023-01-09 17:00:08,670 WriteSendMessage: StartT3Timer 24
2023-01-09 17:00:08,670 [SEND] S1F1 W SystemBytes=24
00 00 00 0A 00 00 81 01 00 00 00 00 00 18 
2023-01-09 17:00:08,671 [SEND] S1F1 W System Bytes=24

2023-01-09 17:00:08,675 Timer::StartTimer -8
2023-01-09 17:00:08,676 Timer::StopTimer -8
2023-01-09 17:00:08,676 Read Data: 28 -- 00 00 01 02 00 00 00 00 00 18 01 02 41 06 45 51 50 54 53 54 41 06 56 31 2E 30 2E 30 
2023-01-09 17:00:08,676 Reader#ByteToBlock Header: 00 00 01 02 00 00 00 00 00 18 
2023-01-09 17:00:08,676 Reader#ByteToBlock Data: 01 02 41 06 45 51 50 54 53 54 41 06 56 31 2E 30 2E 30 
2023-01-09 17:00:08,676 HSMSPort::OnReadHsms Not control message.
2023-01-09 17:00:08,683 [RECV] S1F2  System Bytes=24 
2023-01-09 17:00:08,690 [RECV] S1F2 System Bytes=24
<L [2]
    <A [6] []  'EQPTST' >
    <A [6] []  'V1.0.0' >
>
.

2023-01-09 17:00:08,690 Timer::StopT3Timer, SystemBytes=24
2023-01-09 17:00:10,685 Timer::StartTimer -8
2023-01-09 17:00:10,685 Timer::StopTimer -8
2023-01-09 17:00:10,685 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 18 
2023-01-09 17:00:10,685 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 18 
2023-01-09 17:00:10,685 HSMSPort::OnReadHsms control message.
2023-01-09 17:00:10,685 [RECV] FF FF 00 00 00 05 00 00 02 18   LinkTest.Req
2023-01-09 17:00:10,685 [WRITE] [SB:536, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 18 
2023-01-09 17:00:10,685 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 18   LinkTest.Rsp
2023-01-09 17:00:41,689 Timer::StartTimer -8
2023-01-09 17:00:41,689 Timer::StopTimer -8
2023-01-09 17:00:41,689 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 19 
2023-01-09 17:00:41,689 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 19 
2023-01-09 17:00:41,689 HSMSPort::OnReadHsms control message.
2023-01-09 17:00:41,689 [RECV] FF FF 00 00 00 05 00 00 02 19   LinkTest.Req
2023-01-09 17:00:41,689 [WRITE] [SB:537, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 19 
2023-01-09 17:00:41,689 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 19   LinkTest.Rsp
2023-01-09 17:00:57,486 HSMSTimer::CheckOtherTimeout: 120.031065, -100
2023-01-09 17:00:57,486 Timer::StartTimer -6
2023-01-09 17:00:57,486 [WRITE] [SB:2130706436, 5] 00 00 00 0A FF FF 00 00 00 05 7F 00 00 04 
2023-01-09 17:00:57,486 [SEND] 00 00 00 0A FF FF 00 00 00 05 7F 00 00 04   LinkTest.Req
2023-01-09 17:00:57,486 Timer::StartTimer -100
2023-01-09 17:00:57,492 Timer::StartTimer -8
2023-01-09 17:00:57,492 Timer::StopTimer -8
2023-01-09 17:00:57,492 Read Data: 10 -- FF FF 00 00 00 06 7F 00 00 04 
2023-01-09 17:00:57,492 Reader#ByteToBlock Header: FF FF 00 00 00 06 7F 00 00 04 
2023-01-09 17:00:57,492 HSMSPort::OnReadHsms control message.
2023-01-09 17:00:57,492 Timer::StopTimer -6
2023-01-09 17:00:57,492 [RECV] FF FF 00 00 00 06 7F 00 00 04   LinkTest.Rsp
2023-01-09 17:01:08,721 Writer#Run Send Primary Message 25
2023-01-09 17:01:08,721 Timer::StartT3Timer, SystemBytes=25
2023-01-09 17:01:08,721 WriteSendMessage: StartT3Timer 25
2023-01-09 17:01:08,721 [SEND] S1F1 W SystemBytes=25
00 00 00 0A 00 00 81 01 00 00 00 00 00 19 
2023-01-09 17:01:08,721 [SEND] S1F1 W System Bytes=25

2023-01-09 17:01:08,725 Timer::StartTimer -8
2023-01-09 17:01:08,725 Timer::StopTimer -8
2023-01-09 17:01:08,725 Read Data: 28 -- 00 00 01 02 00 00 00 00 00 19 01 02 41 06 45 51 50 54 53 54 41 06 56 31 2E 30 2E 30 
2023-01-09 17:01:08,725 Reader#ByteToBlock Header: 00 00 01 02 00 00 00 00 00 19 
2023-01-09 17:01:08,725 Reader#ByteToBlock Data: 01 02 41 06 45 51 50 54 53 54 41 06 56 31 2E 30 2E 30 
2023-01-09 17:01:08,725 HSMSPort::OnReadHsms Not control message.
2023-01-09 17:01:08,736 [RECV] S1F2  System Bytes=25 
2023-01-09 17:01:08,742 [RECV] S1F2 System Bytes=25
<L [2]
    <A [6] []  'EQPTST' >
    <A [6] []  'V1.0.0' >
>
.

2023-01-09 17:01:08,742 Timer::StopT3Timer, SystemBytes=25
2023-01-09 17:01:12,692 Timer::StartTimer -8
2023-01-09 17:01:12,693 Timer::StopTimer -8
2023-01-09 17:01:12,693 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 1A 
2023-01-09 17:01:12,693 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 1A 
2023-01-09 17:01:12,693 HSMSPort::OnReadHsms control message.
2023-01-09 17:01:12,693 [RECV] FF FF 00 00 00 05 00 00 02 1A   LinkTest.Req
2023-01-09 17:01:12,693 [WRITE] [SB:538, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 1A 
2023-01-09 17:01:12,693 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 1A   LinkTest.Rsp
2023-01-09 17:01:43,697 Timer::StartTimer -8
2023-01-09 17:01:43,697 Timer::StopTimer -8
2023-01-09 17:01:43,697 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 1B 
2023-01-09 17:01:43,697 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 1B 
2023-01-09 17:01:43,697 HSMSPort::OnReadHsms control message.
2023-01-09 17:01:43,697 [RECV] FF FF 00 00 00 05 00 00 02 1B   LinkTest.Req
2023-01-09 17:01:43,697 [WRITE] [SB:539, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 1B 
2023-01-09 17:01:43,697 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 1B   LinkTest.Rsp
2023-01-09 17:01:44,469 Writer#Run Send Primary Message 26
2023-01-09 17:01:44,469 Timer::StartT3Timer, SystemBytes=26
2023-01-09 17:01:44,469 WriteSendMessage: StartT3Timer 26
2023-01-09 17:01:44,469 [SEND] S1F3 W SystemBytes=26
00 00 00 48 00 00 81 03 00 00 00 00 00 1A 01 0A B1 04 00 00 00 03 B1 04 00 00 00 0B B1 04 00 00 00 0D B1 04 00 00 00 0E B1 04 00 00 52 12 B1 04 00 00 52 13 B1 04 00 00 00 48 B1 04 00 00 00 29 B1 04 00 00 00 2D B1 04 00 00 00 2E 
2023-01-09 17:01:44,469 [SEND] S1F3 W System Bytes=26
<L [10]
    <U4 [1] [Clock]  3 >
    <U4 [1] [CommunicationState]  11 >
    <U4 [1] [CurrentControlState]  13 >
    <U4 [1] [PreviousControlState]  14 >
    <U4 [1] [CurrentEquipmentState]  21010 >
    <U4 [1] [PreviousEquipmentState]  21011 >
    <U4 [1] [PPExecName]  72 >
    <U4 [1] [SpoolingState]  41 >
    <U4 [1] [SpoolCountActual]  45 >
    <U4 [1] [SpoolCountTotal]  46 >
>
.

2023-01-09 17:01:44,474 Timer::StartTimer -8
2023-01-09 17:01:44,476 Timer::StopTimer -8
2023-01-09 17:01:44,476 Read Data: 72 -- 00 00 01 04 00 00 00 00 00 1A 01 0A 41 18 32 30 32 33 2D 30 31 2D 30 39 54 30 38 3A 35 38 3A 35 34 2E 35 39 39 5A A5 01 01 A5 01 05 A5 01 03 69 02 07 D0 69 02 03 E8 41 00 A5 01 00 B1 04 00 00 00 00 B1 04 00 00 00 00 
2023-01-09 17:01:44,476 Reader#ByteToBlock Header: 00 00 01 04 00 00 00 00 00 1A 
2023-01-09 17:01:44,476 Reader#ByteToBlock Data: 01 0A 41 18 32 30 32 33 2D 30 31 2D 30 39 54 30 38 3A 35 38 3A 35 34 2E 35 39 39 5A A5 01 01 A5 01 05 A5 01 03 69 02 07 D0 69 02 03 E8 41 00 A5 01 00 B1 04 00 00 00 00 B1 04 00 00 00 00 
2023-01-09 17:01:44,476 HSMSPort::OnReadHsms Not control message.
2023-01-09 17:01:44,484 [RECV] S1F4  System Bytes=26 
2023-01-09 17:01:44,484 [RECV] S1F4 System Bytes=26
<L [10]
    <A [24] [SVID]  '2023-01-09T08:58:54.599Z' >
    <U1 [1] []  1 >
    <U1 [1] []  5 >
    <U1 [1] []  3 >
    <I2 [1] []  2000 >
    <I2 [1] []  1000 >
    <A [0] []  '' >
    <U1 [1] []  0 >
    <U4 [1] []  0 >
    <U4 [1] []  0 >
>
.

2023-01-09 17:01:44,484 Timer::StopT3Timer, SystemBytes=26
2023-01-09 17:02:14,700 Timer::StartTimer -8
2023-01-09 17:02:14,700 Timer::StopTimer -8
2023-01-09 17:02:14,700 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 1C 
2023-01-09 17:02:14,700 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 1C 
2023-01-09 17:02:14,700 HSMSPort::OnReadHsms control message.
2023-01-09 17:02:14,700 [RECV] FF FF 00 00 00 05 00 00 02 1C   LinkTest.Req
2023-01-09 17:02:14,700 [WRITE] [SB:540, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 1C 
2023-01-09 17:02:14,700 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 1C   LinkTest.Rsp
2023-01-09 17:02:44,512 Writer#Run Send Primary Message 27
2023-01-09 17:02:44,512 Timer::StartT3Timer, SystemBytes=27
2023-01-09 17:02:44,512 WriteSendMessage: StartT3Timer 27
2023-01-09 17:02:44,512 [SEND] S1F1 W SystemBytes=27
00 00 00 0A 00 00 81 01 00 00 00 00 00 1B 
2023-01-09 17:02:44,512 [SEND] S1F1 W System Bytes=27

2023-01-09 17:02:44,520 Timer::StartTimer -8
2023-01-09 17:02:44,520 Timer::StopTimer -8
2023-01-09 17:02:44,520 Read Data: 28 -- 00 00 01 02 00 00 00 00 00 1B 01 02 41 06 45 51 50 54 53 54 41 06 56 31 2E 30 2E 30 
2023-01-09 17:02:44,520 Reader#ByteToBlock Header: 00 00 01 02 00 00 00 00 00 1B 
2023-01-09 17:02:44,520 Reader#ByteToBlock Data: 01 02 41 06 45 51 50 54 53 54 41 06 56 31 2E 30 2E 30 
2023-01-09 17:02:44,520 HSMSPort::OnReadHsms Not control message.
2023-01-09 17:02:44,526 [RECV] S1F2  System Bytes=27 
2023-01-09 17:02:44,533 [RECV] S1F2 System Bytes=27
<L [2]
    <A [6] []  'EQPTST' >
    <A [6] []  'V1.0.0' >
>
.

2023-01-09 17:02:44,533 Timer::StopT3Timer, SystemBytes=27
2023-01-09 17:02:45,705 Timer::StartTimer -8
2023-01-09 17:02:45,705 Timer::StopTimer -8
2023-01-09 17:02:45,705 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 1D 
2023-01-09 17:02:45,705 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 1D 
2023-01-09 17:02:45,705 HSMSPort::OnReadHsms control message.
2023-01-09 17:02:45,705 [RECV] FF FF 00 00 00 05 00 00 02 1D   LinkTest.Req
2023-01-09 17:02:45,705 [WRITE] [SB:541, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 1D 
2023-01-09 17:02:45,705 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 1D   LinkTest.Rsp
2023-01-09 17:02:58,482 HSMSTimer::CheckOtherTimeout: 120.9957851, -100
2023-01-09 17:02:58,482 Timer::StartTimer -6
2023-01-09 17:02:58,482 [WRITE] [SB:2130706437, 5] 00 00 00 0A FF FF 00 00 00 05 7F 00 00 05 
2023-01-09 17:02:58,482 [SEND] 00 00 00 0A FF FF 00 00 00 05 7F 00 00 05   LinkTest.Req
2023-01-09 17:02:58,482 Timer::StartTimer -100
2023-01-09 17:02:58,487 Timer::StartTimer -8
2023-01-09 17:02:58,487 Timer::StopTimer -8
2023-01-09 17:02:58,487 Read Data: 10 -- FF FF 00 00 00 06 7F 00 00 05 
2023-01-09 17:02:58,487 Reader#ByteToBlock Header: FF FF 00 00 00 06 7F 00 00 05 
2023-01-09 17:02:58,487 HSMSPort::OnReadHsms control message.
2023-01-09 17:02:58,487 Timer::StopTimer -6
2023-01-09 17:02:58,487 [RECV] FF FF 00 00 00 06 7F 00 00 05   LinkTest.Rsp
2023-01-09 17:03:16,708 Timer::StartTimer -8
2023-01-09 17:03:16,708 Timer::StopTimer -8
2023-01-09 17:03:16,708 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 1E 
2023-01-09 17:03:16,708 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 1E 
2023-01-09 17:03:16,708 HSMSPort::OnReadHsms control message.
2023-01-09 17:03:16,708 [RECV] FF FF 00 00 00 05 00 00 02 1E   LinkTest.Req
2023-01-09 17:03:16,708 [WRITE] [SB:542, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 1E 
2023-01-09 17:03:16,709 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 1E   LinkTest.Rsp
2023-01-09 17:03:34,889 Writer#Run Send Primary Message 28
2023-01-09 17:03:34,889 Timer::StartT3Timer, SystemBytes=28
2023-01-09 17:03:34,889 WriteSendMessage: StartT3Timer 28
2023-01-09 17:03:34,889 [SEND] S1F3 W SystemBytes=28
00 00 00 48 00 00 81 03 00 00 00 00 00 1C 01 0A B1 04 00 00 00 03 B1 04 00 00 00 0B B1 04 00 00 00 0D B1 04 00 00 00 0E B1 04 00 00 52 12 B1 04 00 00 52 13 B1 04 00 00 00 48 B1 04 00 00 00 29 B1 04 00 00 00 2D B1 04 00 00 00 2E 
2023-01-09 17:03:34,889 [SEND] S1F3 W System Bytes=28
<L [10]
    <U4 [1] [Clock]  3 >
    <U4 [1] [CommunicationState]  11 >
    <U4 [1] [CurrentControlState]  13 >
    <U4 [1] [PreviousControlState]  14 >
    <U4 [1] [CurrentEquipmentState]  21010 >
    <U4 [1] [PreviousEquipmentState]  21011 >
    <U4 [1] [PPExecName]  72 >
    <U4 [1] [SpoolingState]  41 >
    <U4 [1] [SpoolCountActual]  45 >
    <U4 [1] [SpoolCountTotal]  46 >
>
.

2023-01-09 17:03:34,895 Timer::StartTimer -8
2023-01-09 17:03:34,895 Timer::StopTimer -8
2023-01-09 17:03:34,895 Read Data: 72 -- 00 00 01 04 00 00 00 00 00 1C 01 0A 41 18 32 30 32 33 2D 30 31 2D 30 39 54 30 39 3A 30 30 3A 34 35 2E 30 31 38 5A A5 01 01 A5 01 05 A5 01 03 69 02 07 D0 69 02 03 E8 41 00 A5 01 00 B1 04 00 00 00 00 B1 04 00 00 00 00 
2023-01-09 17:03:34,895 Reader#ByteToBlock Header: 00 00 01 04 00 00 00 00 00 1C 
2023-01-09 17:03:34,895 Reader#ByteToBlock Data: 01 0A 41 18 32 30 32 33 2D 30 31 2D 30 39 54 30 39 3A 30 30 3A 34 35 2E 30 31 38 5A A5 01 01 A5 01 05 A5 01 03 69 02 07 D0 69 02 03 E8 41 00 A5 01 00 B1 04 00 00 00 00 B1 04 00 00 00 00 
2023-01-09 17:03:34,895 HSMSPort::OnReadHsms Not control message.
2023-01-09 17:03:34,903 [RECV] S1F4  System Bytes=28 
2023-01-09 17:03:34,903 [RECV] S1F4 System Bytes=28
<L [10]
    <A [24] [SVID]  '2023-01-09T09:00:45.018Z' >
    <U1 [1] []  1 >
    <U1 [1] []  5 >
    <U1 [1] []  3 >
    <I2 [1] []  2000 >
    <I2 [1] []  1000 >
    <A [0] []  '' >
    <U1 [1] []  0 >
    <U4 [1] []  0 >
    <U4 [1] []  0 >
>
.

2023-01-09 17:03:34,903 Timer::StopT3Timer, SystemBytes=28
2023-01-09 17:03:47,713 Timer::StartTimer -8
2023-01-09 17:03:47,713 Timer::StopTimer -8
2023-01-09 17:03:47,714 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 1F 
2023-01-09 17:03:47,714 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 1F 
2023-01-09 17:03:47,714 HSMSPort::OnReadHsms control message.
2023-01-09 17:03:47,714 [RECV] FF FF 00 00 00 05 00 00 02 1F   LinkTest.Req
2023-01-09 17:03:47,714 [WRITE] [SB:543, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 1F 
2023-01-09 17:03:47,714 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 1F   LinkTest.Rsp
2023-01-09 17:04:18,718 Timer::StartTimer -8
2023-01-09 17:04:18,718 Timer::StopTimer -8
2023-01-09 17:04:18,718 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 20 
2023-01-09 17:04:18,718 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 20 
2023-01-09 17:04:18,718 HSMSPort::OnReadHsms control message.
2023-01-09 17:04:18,718 [RECV] FF FF 00 00 00 05 00 00 02 20   LinkTest.Req
2023-01-09 17:04:18,718 [WRITE] [SB:544, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 20 
2023-01-09 17:04:18,718 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 20   LinkTest.Rsp
2023-01-09 17:04:34,944 Writer#Run Send Primary Message 29
2023-01-09 17:04:34,944 Timer::StartT3Timer, SystemBytes=29
2023-01-09 17:04:34,944 WriteSendMessage: StartT3Timer 29
2023-01-09 17:04:34,944 [SEND] S1F1 W SystemBytes=29
00 00 00 0A 00 00 81 01 00 00 00 00 00 1D 
2023-01-09 17:04:34,944 [SEND] S1F1 W System Bytes=29

2023-01-09 17:04:34,950 Timer::StartTimer -8
2023-01-09 17:04:34,950 Timer::StopTimer -8
2023-01-09 17:04:34,950 Read Data: 28 -- 00 00 01 02 00 00 00 00 00 1D 01 02 41 06 45 51 50 54 53 54 41 06 56 31 2E 30 2E 30 
2023-01-09 17:04:34,950 Reader#ByteToBlock Header: 00 00 01 02 00 00 00 00 00 1D 
2023-01-09 17:04:34,950 Reader#ByteToBlock Data: 01 02 41 06 45 51 50 54 53 54 41 06 56 31 2E 30 2E 30 
2023-01-09 17:04:34,950 HSMSPort::OnReadHsms Not control message.
2023-01-09 17:04:34,959 [RECV] S1F2  System Bytes=29 
2023-01-09 17:04:34,965 [RECV] S1F2 System Bytes=29
<L [2]
    <A [6] []  'EQPTST' >
    <A [6] []  'V1.0.0' >
>
.

2023-01-09 17:04:34,965 Timer::StopT3Timer, SystemBytes=29
2023-01-09 17:04:49,722 Timer::StartTimer -8
2023-01-09 17:04:49,722 Timer::StopTimer -8
2023-01-09 17:04:49,722 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 21 
2023-01-09 17:04:49,722 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 21 
2023-01-09 17:04:49,722 HSMSPort::OnReadHsms control message.
2023-01-09 17:04:49,722 [RECV] FF FF 00 00 00 05 00 00 02 21   LinkTest.Req
2023-01-09 17:04:49,722 [WRITE] [SB:545, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 21 
2023-01-09 17:04:49,723 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 21   LinkTest.Rsp
2023-01-09 17:04:58,551 HSMSTimer::CheckOtherTimeout: 120.0688097, -100
2023-01-09 17:04:58,551 Timer::StartTimer -6
2023-01-09 17:04:58,551 [WRITE] [SB:2130706438, 5] 00 00 00 0A FF FF 00 00 00 05 7F 00 00 06 
2023-01-09 17:04:58,551 [SEND] 00 00 00 0A FF FF 00 00 00 05 7F 00 00 06   LinkTest.Req
2023-01-09 17:04:58,552 Timer::StartTimer -100
2023-01-09 17:04:58,556 Timer::StartTimer -8
2023-01-09 17:04:58,556 Timer::StopTimer -8
2023-01-09 17:04:58,556 Read Data: 10 -- FF FF 00 00 00 06 7F 00 00 06 
2023-01-09 17:04:58,556 Reader#ByteToBlock Header: FF FF 00 00 00 06 7F 00 00 06 
2023-01-09 17:04:58,556 HSMSPort::OnReadHsms control message.
2023-01-09 17:04:58,556 Timer::StopTimer -6
2023-01-09 17:04:58,556 [RECV] FF FF 00 00 00 06 7F 00 00 06   LinkTest.Rsp
2023-01-09 17:05:20,726 Timer::StartTimer -8
2023-01-09 17:05:20,726 Timer::StopTimer -8
2023-01-09 17:05:20,726 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 22 
2023-01-09 17:05:20,726 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 22 
2023-01-09 17:05:20,726 HSMSPort::OnReadHsms control message.
2023-01-09 17:05:20,726 [RECV] FF FF 00 00 00 05 00 00 02 22   LinkTest.Req
2023-01-09 17:05:20,726 [WRITE] [SB:546, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 22 
2023-01-09 17:05:20,726 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 22   LinkTest.Rsp
2023-01-09 17:05:34,996 Writer#Run Send Primary Message 30
2023-01-09 17:05:34,996 Timer::StartT3Timer, SystemBytes=30
2023-01-09 17:05:34,996 WriteSendMessage: StartT3Timer 30
2023-01-09 17:05:34,996 [SEND] S1F1 W SystemBytes=30
00 00 00 0A 00 00 81 01 00 00 00 00 00 1E 
2023-01-09 17:05:34,996 [SEND] S1F1 W System Bytes=30

2023-01-09 17:05:35,003 Timer::StartTimer -8
2023-01-09 17:05:35,003 Timer::StopTimer -8
2023-01-09 17:05:35,003 Read Data: 28 -- 00 00 01 02 00 00 00 00 00 1E 01 02 41 06 45 51 50 54 53 54 41 06 56 31 2E 30 2E 30 
2023-01-09 17:05:35,003 Reader#ByteToBlock Header: 00 00 01 02 00 00 00 00 00 1E 
2023-01-09 17:05:35,003 Reader#ByteToBlock Data: 01 02 41 06 45 51 50 54 53 54 41 06 56 31 2E 30 2E 30 
2023-01-09 17:05:35,003 HSMSPort::OnReadHsms Not control message.
2023-01-09 17:05:35,011 [RECV] S1F2  System Bytes=30 
2023-01-09 17:05:35,017 [RECV] S1F2 System Bytes=30
<L [2]
    <A [6] []  'EQPTST' >
    <A [6] []  'V1.0.0' >
>
.

2023-01-09 17:05:35,017 Timer::StopT3Timer, SystemBytes=30
2023-01-09 17:05:51,731 Timer::StartTimer -8
2023-01-09 17:05:51,731 Timer::StopTimer -8
2023-01-09 17:05:51,731 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 23 
2023-01-09 17:05:51,731 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 23 
2023-01-09 17:05:51,731 HSMSPort::OnReadHsms control message.
2023-01-09 17:05:51,731 [RECV] FF FF 00 00 00 05 00 00 02 23   LinkTest.Req
2023-01-09 17:05:51,731 [WRITE] [SB:547, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 23 
2023-01-09 17:05:51,731 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 23   LinkTest.Rsp
2023-01-09 17:06:22,735 Timer::StartTimer -8
2023-01-09 17:06:22,735 Timer::StopTimer -8
2023-01-09 17:06:22,735 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 24 
2023-01-09 17:06:22,735 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 24 
2023-01-09 17:06:22,735 HSMSPort::OnReadHsms control message.
2023-01-09 17:06:22,735 [RECV] FF FF 00 00 00 05 00 00 02 24   LinkTest.Req
2023-01-09 17:06:22,735 [WRITE] [SB:548, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 24 
2023-01-09 17:06:22,735 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 24   LinkTest.Rsp
2023-01-09 17:06:35,050 Writer#Run Send Primary Message 31
2023-01-09 17:06:35,050 Timer::StartT3Timer, SystemBytes=31
2023-01-09 17:06:35,050 WriteSendMessage: StartT3Timer 31
2023-01-09 17:06:35,050 [SEND] S1F1 W SystemBytes=31
00 00 00 0A 00 00 81 01 00 00 00 00 00 1F 
2023-01-09 17:06:35,050 [SEND] S1F1 W System Bytes=31

2023-01-09 17:06:35,056 Timer::StartTimer -8
2023-01-09 17:06:35,056 Timer::StopTimer -8
2023-01-09 17:06:35,056 Read Data: 28 -- 00 00 01 02 00 00 00 00 00 1F 01 02 41 06 45 51 50 54 53 54 41 06 56 31 2E 30 2E 30 
2023-01-09 17:06:35,056 Reader#ByteToBlock Header: 00 00 01 02 00 00 00 00 00 1F 
2023-01-09 17:06:35,056 Reader#ByteToBlock Data: 01 02 41 06 45 51 50 54 53 54 41 06 56 31 2E 30 2E 30 
2023-01-09 17:06:35,056 HSMSPort::OnReadHsms Not control message.
2023-01-09 17:06:35,065 [RECV] S1F2  System Bytes=31 
2023-01-09 17:06:35,070 [RECV] S1F2 System Bytes=31
<L [2]
    <A [6] []  'EQPTST' >
    <A [6] []  'V1.0.0' >
>
.

2023-01-09 17:06:35,070 Timer::StopT3Timer, SystemBytes=31
2023-01-09 17:06:53,739 Timer::StartTimer -8
2023-01-09 17:06:53,739 Timer::StopTimer -8
2023-01-09 17:06:53,739 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 25 
2023-01-09 17:06:53,739 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 25 
2023-01-09 17:06:53,739 HSMSPort::OnReadHsms control message.
2023-01-09 17:06:53,739 [RECV] FF FF 00 00 00 05 00 00 02 25   LinkTest.Req
2023-01-09 17:06:53,739 [WRITE] [SB:549, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 25 
2023-01-09 17:06:53,739 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 25   LinkTest.Rsp
2023-01-09 17:06:58,617 HSMSTimer::CheckOtherTimeout: 120.0649858, -100
2023-01-09 17:06:58,617 Timer::StartTimer -6
2023-01-09 17:06:58,617 [WRITE] [SB:2130706439, 5] 00 00 00 0A FF FF 00 00 00 05 7F 00 00 07 
2023-01-09 17:06:58,617 [SEND] 00 00 00 0A FF FF 00 00 00 05 7F 00 00 07   LinkTest.Req
2023-01-09 17:06:58,617 Timer::StartTimer -100
2023-01-09 17:06:58,622 Timer::StartTimer -8
2023-01-09 17:06:58,622 Timer::StopTimer -8
2023-01-09 17:06:58,622 Read Data: 10 -- FF FF 00 00 00 06 7F 00 00 07 
2023-01-09 17:06:58,622 Reader#ByteToBlock Header: FF FF 00 00 00 06 7F 00 00 07 
2023-01-09 17:06:58,622 HSMSPort::OnReadHsms control message.
2023-01-09 17:06:58,622 Timer::StopTimer -6
2023-01-09 17:06:58,622 [RECV] FF FF 00 00 00 06 7F 00 00 07   LinkTest.Rsp
2023-01-09 17:07:24,742 Timer::StartTimer -8
2023-01-09 17:07:24,742 Timer::StopTimer -8
2023-01-09 17:07:24,742 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 26 
2023-01-09 17:07:24,742 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 26 
2023-01-09 17:07:24,742 HSMSPort::OnReadHsms control message.
2023-01-09 17:07:24,742 [RECV] FF FF 00 00 00 05 00 00 02 26   LinkTest.Req
2023-01-09 17:07:24,742 [WRITE] [SB:550, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 26 
2023-01-09 17:07:24,743 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 26   LinkTest.Rsp
2023-01-09 17:07:35,106 Writer#Run Send Primary Message 32
2023-01-09 17:07:35,106 Timer::StartT3Timer, SystemBytes=32
2023-01-09 17:07:35,106 WriteSendMessage: StartT3Timer 32
2023-01-09 17:07:35,106 [SEND] S1F1 W SystemBytes=32
00 00 00 0A 00 00 81 01 00 00 00 00 00 20 
2023-01-09 17:07:35,106 [SEND] S1F1 W System Bytes=32

2023-01-09 17:07:35,110 Timer::StartTimer -8
2023-01-09 17:07:35,110 Timer::StopTimer -8
2023-01-09 17:07:35,110 Read Data: 28 -- 00 00 01 02 00 00 00 00 00 20 01 02 41 06 45 51 50 54 53 54 41 06 56 31 2E 30 2E 30 
2023-01-09 17:07:35,110 Reader#ByteToBlock Header: 00 00 01 02 00 00 00 00 00 20 
2023-01-09 17:07:35,110 Reader#ByteToBlock Data: 01 02 41 06 45 51 50 54 53 54 41 06 56 31 2E 30 2E 30 
2023-01-09 17:07:35,110 HSMSPort::OnReadHsms Not control message.
2023-01-09 17:07:35,121 [RECV] S1F2  System Bytes=32 
2023-01-09 17:07:35,128 [RECV] S1F2 System Bytes=32
<L [2]
    <A [6] []  'EQPTST' >
    <A [6] []  'V1.0.0' >
>
.

2023-01-09 17:07:35,128 Timer::StopT3Timer, SystemBytes=32
2023-01-09 17:07:55,747 Timer::StartTimer -8
2023-01-09 17:07:55,747 Timer::StopTimer -8
2023-01-09 17:07:55,747 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 27 
2023-01-09 17:07:55,747 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 27 
2023-01-09 17:07:55,747 HSMSPort::OnReadHsms control message.
2023-01-09 17:07:55,747 [RECV] FF FF 00 00 00 05 00 00 02 27   LinkTest.Req
2023-01-09 17:07:55,747 [WRITE] [SB:551, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 27 
2023-01-09 17:07:55,747 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 27   LinkTest.Rsp
2023-01-09 17:08:26,752 Timer::StartTimer -8
2023-01-09 17:08:26,752 Timer::StopTimer -8
2023-01-09 17:08:26,752 Read Data: 10 -- FF FF 00 00 00 05 00 00 02 28 
2023-01-09 17:08:26,752 Reader#ByteToBlock Header: FF FF 00 00 00 05 00 00 02 28 
2023-01-09 17:08:26,752 HSMSPort::OnReadHsms control message.
2023-01-09 17:08:26,752 [RECV] FF FF 00 00 00 05 00 00 02 28   LinkTest.Req
2023-01-09 17:08:26,752 [WRITE] [SB:552, 6] 00 00 00 0A FF FF 00 00 00 06 00 00 02 28 
2023-01-09 17:08:26,752 [SEND] 00 00 00 0A FF FF 00 00 00 06 00 00 02 28   LinkTest.Rsp
2023-01-09 17:08:33,058 Start to Close the LEDE_01 SECS Port
2023-01-09 17:08:33,059 UpdateStatus: Prev:0=>Now:1=>New:-2
2023-01-09 17:08:33,059 UpdateStatus: Prev:1=>Now:-2
2023-01-09 17:08:33,059 Terminate LEDE_01#Reader Thread.
2023-01-09 17:08:33,059 [WRITE] [SB:2130706440, 9] 00 00 00 0A FF FF 00 00 00 09 7F 00 00 08 
2023-01-09 17:08:33,059 [SEND] 00 00 00 0A FF FF 00 00 00 09 7F 00 00 08   Separate.Req
2023-01-09 17:08:33,123 Terminate LEDE_01#Parser Thread.
2023-01-09 17:08:33,124 Terminate - Connector Thread.
2023-01-09 17:08:33,125 Terminate LEDE_01#Writer Thread.
2023-01-09 17:08:33,138 LEDE_01#Parser Thread Status = False
2023-01-09 17:08:33,138 LEDE_01#Writer Thread Status = False
2023-01-09 17:08:33,216 Terminate LEDE_01#Timer Thread.
2023-01-09 17:08:33,216 Timer::StopTimer -100
2023-01-09 17:08:33,216 Timer::StopTimer -6
2023-01-09 17:08:33,216 HSMSPort::TerminateSocket execute.
2023-01-09 17:08:33,225 Reader#Run
System.IO.IOException: 无法从传输连接中读取数据: 一个封锁操作被对 WSACancelBlockingCall 的调用中断。。 ---> System.Net.Sockets.SocketException: 一个封锁操作被对 WSACancelBlockingCall 的调用中断。
   在 System.Net.Sockets.Socket.Receive(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags)
   在 System.Net.Sockets.NetworkStream.Read(Byte[] buffer, Int32 offset, Int32 size)
   --- 内部异常堆栈跟踪的结尾 ---
   在 System.Net.Sockets.NetworkStream.Read(Byte[] buffer, Int32 offset, Int32 size)
   在 System.IO.BinaryReader.Read(Byte[] buffer, Int32 index, Int32 count)
   在 CSch2tI1noUwdMbW1CT.bpXunUIHrU3XGjcvxIh.mEFIsTOntY(Byte[]  )
   在 CSch2tI1noUwdMbW1CT.bpXunUIHrU3XGjcvxIh.d6Bd3MvACv() 位置 D:\glorysoft\git.project\EAP.Products\sh-div-xfw-0811\XFW\SourceCode\Glorysoft.SECSwell.Fx\HSMS\HSMSWriter.cs:行号 38
2023-01-09 17:08:33,236 Terminate LEDE_01#Reader Thread.
2023-01-09 17:08:33,236 Reader#FireDisconnect Invoked.
2023-01-09 17:08:33,236 LEDE_01#Reader Thread Status = False
2023-01-09 17:08:33,320 Terminate S9FxMonitor Thread.
2023-01-09 17:08:33,320 Completely Close the LEDE_01 SECS Port
2023-01-09 17:08:33,423 LEDE_01#Timer Thread Status = False