2024-07-20 17:48:06.800 thread(1) tag(0) INFO plc_tag_create_ex:838 Starting
2024-07-20 17:48:06.800 thread(1) tag(0) INFO initialize_modules:177 Starting.
2024-07-20 17:48:06.800 thread(1) tag(0) INFO initialize_modules:186 Creating library mutex.
2024-07-20 17:48:06.800 thread(1) tag(0) DETAIL mutex_create:726 Starting.
2024-07-20 17:48:06.800 thread(1) tag(0) DETAIL mutex_create:753 Done.
2024-07-20 17:48:06.800 thread(1) tag(0) INFO initialize_modules:207 Initializing library modules.
2024-07-20 17:48:06.800 thread(1) tag(0) INFO lib_init:136 Starting.
2024-07-20 17:48:06.800 thread(1) tag(0) INFO lib_init:140 Setting up global library data.
2024-07-20 17:48:06.800 thread(1) tag(0) INFO lib_init:142 Creating tag hashtable.
2024-07-20 17:48:06.800 thread(1) tag(0) INFO hashtable_create:75 Starting
2024-07-20 17:48:06.800 thread(1) tag(0) INFO hashtable_create:99 Done
2024-07-20 17:48:06.800 thread(1) tag(0) INFO lib_init:148 Creating tag hashtable mutex.
2024-07-20 17:48:06.800 thread(1) tag(0) DETAIL mutex_create:726 Starting.
2024-07-20 17:48:06.800 thread(1) tag(0) DETAIL mutex_create:753 Done.
2024-07-20 17:48:06.800 thread(1) tag(0) INFO lib_init:154 Creating tag condition variable.
2024-07-20 17:48:06.800 thread(1) tag(0) DETAIL cond_create:1087 Starting.
2024-07-20 17:48:06.800 thread(1) tag(0) DETAIL cond_create:1114 Done.
2024-07-20 17:48:06.800 thread(1) tag(0) INFO lib_init:160 Creating tag tickler thread.
2024-07-20 17:48:06.800 thread(1) tag(0) DETAIL thread_create:884 Starting.
2024-07-20 17:48:06.800 thread(1) tag(0) DETAIL thread_create:917 Done.
2024-07-20 17:48:06.800 thread(1) tag(0) INFO lib_init:166 Done.
2024-07-20 17:48:06.800 thread(1) tag(0) INFO initialize_modules:210 Initializing AB module.
2024-07-20 17:48:06.800 thread(1) tag(0) INFO ab_init:125 Initializing AB protocol library.
2024-07-20 17:48:06.802 thread(1) tag(0) DETAIL mutex_create:726 Starting.
2024-07-20 17:48:06.802 thread(1) tag(0) DETAIL mutex_create:753 Done.
2024-07-20 17:48:06.802 thread(2) tag(0) INFO tag_tickler_func:513 Starting.
2024-07-20 17:48:06.802 thread(1) tag(0) INFO ab_init:134 Finished initializing AB protocol library.
2024-07-20 17:48:06.802 thread(1) tag(0) INFO initialize_modules:215 Initializing Modbus module.
2024-07-20 17:48:06.802 thread(1) tag(0) INFO mb_init:2469 Starting.
2024-07-20 17:48:06.802 thread(1) tag(0) DETAIL mb_init:2471 Setting up mutex.
2024-07-20 17:48:06.802 thread(1) tag(0) DETAIL mutex_create:726 Starting.
2024-07-20 17:48:06.802 thread(1) tag(0) DETAIL mutex_create:753 Done.
2024-07-20 17:48:06.802 thread(1) tag(0) INFO mb_init:2480 Done.
2024-07-20 17:48:06.802 thread(1) tag(0) INFO initialize_modules:226 Done initializing library modules.
2024-07-20 17:48:06.802 thread(1) tag(0) INFO initialize_modules:231 Done.
2024-07-20 17:48:06.802 thread(1) tag(0) DETAIL attr_create_from_str:129 Starting.
2024-07-20 17:48:06.802 thread(1) tag(0) DETAIL attr_create_from_str:158 Key-value pair "protocol=ab_eip".
2024-07-20 17:48:06.802 thread(1) tag(0) DETAIL attr_create_from_str:173 Key-value pair before trimming "protocol":"ab_eip".
2024-07-20 17:48:06.802 thread(1) tag(0) DETAIL attr_create_from_str:185 Key-value pair after trimming "protocol":"ab_eip".
2024-07-20 17:48:06.802 thread(1) tag(0) DETAIL attr_create_from_str:158 Key-value pair "gateway=192.168.250.1".
2024-07-20 17:48:06.802 thread(1) tag(0) DETAIL attr_create_from_str:173 Key-value pair before trimming "gateway":"192.168.250.1".
2024-07-20 17:48:06.802 thread(1) tag(0) DETAIL attr_create_from_str:185 Key-value pair after trimming "gateway":"192.168.250.1".
2024-07-20 17:48:06.804 thread(1) tag(0) DETAIL attr_create_from_str:158 Key-value pair "path=1,0".
2024-07-20 17:48:06.804 thread(1) tag(0) DETAIL attr_create_from_str:173 Key-value pair before trimming "path":"1,0".
2024-07-20 17:48:06.804 thread(1) tag(0) DETAIL attr_create_from_str:185 Key-value pair after trimming "path":"1,0".
2024-07-20 17:48:06.804 thread(1) tag(0) DETAIL attr_create_from_str:158 Key-value pair "plc=omron-njnx".
2024-07-20 17:48:06.804 thread(1) tag(0) DETAIL attr_create_from_str:173 Key-value pair before trimming "plc":"omron-njnx".
2024-07-20 17:48:06.804 thread(1) tag(0) DETAIL attr_create_from_str:185 Key-value pair after trimming "plc":"omron-njnx".
2024-07-20 17:48:06.804 thread(1) tag(0) DETAIL attr_create_from_str:158 Key-value pair "elem_size=256".
2024-07-20 17:48:06.804 thread(1) tag(0) DETAIL attr_create_from_str:173 Key-value pair before trimming "elem_size":"256".
2024-07-20 17:48:06.804 thread(1) tag(0) DETAIL attr_create_from_str:185 Key-value pair after trimming "elem_size":"256".
2024-07-20 17:48:06.804 thread(1) tag(0) DETAIL attr_create_from_str:158 Key-value pair "elem_count=2".
2024-07-20 17:48:06.804 thread(1) tag(0) DETAIL attr_create_from_str:173 Key-value pair before trimming "elem_count":"2".
2024-07-20 17:48:06.804 thread(1) tag(0) DETAIL attr_create_from_str:185 Key-value pair after trimming "elem_count":"2".
2024-07-20 17:48:06.804 thread(1) tag(0) DETAIL attr_create_from_str:158 Key-value pair "name=StringArray_256".
2024-07-20 17:48:06.804 thread(1) tag(0) DETAIL attr_create_from_str:173 Key-value pair before trimming "name":"StringArray_256".
2024-07-20 17:48:06.804 thread(1) tag(0) DETAIL attr_create_from_str:185 Key-value pair after trimming "name":"StringArray_256".
2024-07-20 17:48:06.806 thread(1) tag(0) DETAIL attr_create_from_str:216 Done.
2024-07-20 17:48:06.806 thread(1) tag(0) INFO find_tag_create_func:99 Matched protocol=ab_eip
2024-07-20 17:48:06.806 thread(1) tag(0) INFO ab_tag_create:175 Starting.
2024-07-20 17:48:06.806 thread(1) tag(0) INFO rc_alloc_impl:111 Starting, called from ab_tag_create:182
2024-07-20 17:48:06.806 thread(1) tag(0) INFO rc_alloc_impl:130 Done
2024-07-20 17:48:06.806 thread(1) tag(0) DETAIL rc_alloc_impl:135 Returning memory pointer 00C07A30
2024-07-20 17:48:06.806 thread(1) tag(0) DETAIL ab_tag_create:188 tag=00C07A30
2024-07-20 17:48:06.806 thread(1) tag(0) INFO plc_tag_generic_init_tag:467 Starting.
2024-07-20 17:48:06.806 thread(1) tag(0) DETAIL mutex_create:726 Starting.
2024-07-20 17:48:06.806 thread(1) tag(0) DETAIL mutex_create:753 Done.
2024-07-20 17:48:06.806 thread(1) tag(0) DETAIL mutex_create:726 Starting.
2024-07-20 17:48:06.806 thread(1) tag(0) DETAIL mutex_create:753 Done.
2024-07-20 17:48:06.806 thread(1) tag(0) DETAIL cond_create:1087 Starting.
2024-07-20 17:48:06.806 thread(1) tag(0) DETAIL cond_create:1114 Done.
2024-07-20 17:48:06.806 thread(1) tag(0) INFO plc_tag_generic_init_tag:498 Done.
2024-07-20 17:48:06.808 thread(1) tag(0) DETAIL get_plc_type:985 Found OMRON NJ/NX Series PLC.
2024-07-20 17:48:06.808 thread(1) tag(0) DETAIL get_plc_type:985 Found OMRON NJ/NX Series PLC.
2024-07-20 17:48:06.808 thread(1) tag(0) DETAIL session_find_or_create:254 Starting
2024-07-20 17:48:06.808 thread(1) tag(0) DETAIL session_find_or_create:278 Creating new session.
2024-07-20 17:48:06.808 thread(1) tag(0) INFO session_create_unsafe:475 Starting
2024-07-20 17:48:06.808 thread(1) tag(0) DETAIL session_create_unsafe:478 Session should use connected messaging.
2024-07-20 17:48:06.808 thread(1) tag(0) INFO rc_alloc_impl:111 Starting, called from session_create_unsafe:483
2024-07-20 17:48:06.808 thread(1) tag(0) INFO rc_alloc_impl:130 Done
2024-07-20 17:48:06.808 thread(1) tag(0) DETAIL rc_alloc_impl:135 Returning memory pointer 00C07C50
2024-07-20 17:48:06.808 thread(1) tag(0) DETAIL cip_encode_path:71 Starting
2024-07-20 17:48:06.808 thread(1) tag(0) DETAIL match_numeric_segment:195 Starting at position 0 in string 1,0.
2024-07-20 17:48:06.808 thread(1) tag(0) DETAIL match_numeric_segment:224 Remaining path ",0".
2024-07-20 17:48:06.808 thread(1) tag(0) DETAIL match_numeric_segment:229 Done. Found numeric segment 1.
2024-07-20 17:48:06.808 thread(1) tag(0) DETAIL cip_encode_path:89 Found numeric segment.
2024-07-20 17:48:06.808 thread(1) tag(0) DETAIL cip_encode_path:85 Skipping separator character ','.
2024-07-20 17:48:06.810 thread(1) tag(0) DETAIL match_numeric_segment:195 Starting at position 2 in string 1,0.
2024-07-20 17:48:06.810 thread(1) tag(0) DETAIL match_numeric_segment:224 Remaining path "".
2024-07-20 17:48:06.810 thread(1) tag(0) DETAIL match_numeric_segment:229 Done. Found numeric segment 0.
2024-07-20 17:48:06.810 thread(1) tag(0) DETAIL cip_encode_path:89 Found numeric segment.
2024-07-20 17:48:06.810 thread(1) tag(0) DETAIL cip_encode_path:130 PLC needs connection, adding path to the router object.
2024-07-20 17:48:06.810 thread(1) tag(0) DETAIL cip_encode_path:162 IOI size before 6
2024-07-20 17:48:06.812 thread(1) tag(0) DETAIL cip_encode_path:183 Done
2024-07-20 17:48:06.812 thread(1) tag(0) DETAIL session_create_unsafe:531 Setting connection_group_id to 0.
2024-07-20 17:48:06.812 thread(1) tag(0) DETAIL session_create_unsafe:571 Set maximum payload size to 1996 bytes.
2024-07-20 17:48:06.812 thread(1) tag(0) DETAIL add_session_unsafe:339 Starting
2024-07-20 17:48:06.812 thread(1) tag(0) DETAIL add_session_unsafe:349 Done
2024-07-20 17:48:06.812 thread(1) tag(0) INFO session_create_unsafe:588 Done
2024-07-20 17:48:06.812 thread(1) tag(0) DETAIL session_find_or_create:289 Passed attribute to prohibit use of extended ForwardOpen is 0.
2024-07-20 17:48:06.812 thread(1) tag(0) DETAIL session_find_or_create:290 Existing attribute to prohibit use of extended ForwardOpen is 0.
2024-07-20 17:48:06.812 thread(1) tag(0) INFO session_init:604 Starting.
2024-07-20 17:48:06.812 thread(1) tag(0) DETAIL mutex_create:726 Starting.
2024-07-20 17:48:06.812 thread(1) tag(0) DETAIL mutex_create:753 Done.
2024-07-20 17:48:06.812 thread(1) tag(0) DETAIL cond_create:1087 Starting.
2024-07-20 17:48:06.812 thread(1) tag(0) DETAIL cond_create:1114 Done.
2024-07-20 17:48:06.812 thread(1) tag(0) DETAIL thread_create:884 Starting.
2024-07-20 17:48:06.812 thread(1) tag(0) DETAIL thread_create:917 Done.
2024-07-20 17:48:06.814 thread(1) tag(0) INFO session_init:626 Done.
2024-07-20 17:48:06.814 thread(1) tag(0) DETAIL session_find_or_create:329 Done
2024-07-20 17:48:06.814 thread(3) tag(0) INFO session_handler:1038 Starting thread for session 00C07C50
2024-07-20 17:48:06.814 thread(1) tag(0) DETAIL ab_tag_create:289 using session=00C07C50
2024-07-20 17:48:06.814 thread(3) tag(0) DETAIL session_handler:1058 in SESSION_OPEN_SOCKET_START state.
2024-07-20 17:48:06.814 thread(1) tag(0) DETAIL get_tag_data_type:527 Starting.
2024-07-20 17:48:06.816 thread(3) tag(0) INFO session_open_socket:644 Starting.
2024-07-20 17:48:06.816 thread(1) tag(0) INFO get_tag_data_type:640 Setting element size to 256.
2024-07-20 17:48:06.816 thread(1) tag(0) DETAIL get_tag_data_type:658 Done.
2024-07-20 17:48:06.816 thread(3) tag(0) DETAIL socket_create:1311 Starting.
2024-07-20 17:48:06.816 thread(1) tag(0) DETAIL ab_tag_create:407 Setting up OMRON NJ/NX Series tag.
2024-07-20 17:48:06.816 thread(1) tag(0) DETAIL ab_tag_create:417 Using default Omron byte order.
2024-07-20 17:48:06.816 thread(1) tag(0) DETAIL ab_tag_create:423 Setting default Logix vtable.
2024-07-20 17:48:06.816 thread(1) tag(0) DETAIL parse_symbolic_segment:720 Starting with name index=0 and encoded name index=1.
2024-07-20 17:48:06.816 thread(1) tag(0) DETAIL parse_symbolic_segment:759 Parsed symbolic segment "StringArray_256" in tag name.
2024-07-20 17:48:06.816 thread(1) tag(0) DETAIL ab_tag_create:495 Kicking off initial read.
2024-07-20 17:48:06.816 thread(3) tag(0) DETAIL socket_create:1334 Done.
2024-07-20 17:48:06.816 thread(1) tag(0) INFO tag_read_start:312 Starting
2024-07-20 17:48:06.818 thread(1) tag(0) INFO build_read_request_connected:423 Starting.
2024-07-20 17:48:06.816 thread(3) tag(0) DETAIL session_open_socket:678 Using default port 44818.
2024-07-20 17:48:06.818 thread(1) tag(0) DETAIL session_create_request:2525 Starting.
2024-07-20 17:48:06.818 thread(3) tag(0) DETAIL socket_connect_tcp_start:1355 Starting.
2024-07-20 17:48:06.818 thread(1) tag(0) INFO rc_alloc_impl:111 Starting, called from session_create_request:2534
2024-07-20 17:48:06.818 thread(1) tag(0) INFO rc_alloc_impl:130 Done
2024-07-20 17:48:06.818 thread(1) tag(0) DETAIL rc_alloc_impl:135 Returning memory pointer 00B6F1B0
2024-07-20 17:48:06.818 thread(1) tag(0) DETAIL session_create_request:2548 Done.
2024-07-20 17:48:06.818 thread(1) tag(0) INFO session_add_request:966 Starting. sess=00C07C50, req=00B6F1B0
2024-07-20 17:48:06.818 thread(1) tag(0) DETAIL session_add_request_unsafe:931 Starting.
2024-07-20 17:48:06.818 thread(1) tag(0) DETAIL session_add_request_unsafe:950 Total requests in the queue: 1
2024-07-20 17:48:06.818 thread(1) tag(0) DETAIL session_add_request_unsafe:952 Done.
2024-07-20 17:48:06.818 thread(3) tag(0) DETAIL socket_connect_tcp_start:1404 Found numeric IP address: 192.168.250.1
2024-07-20 17:48:06.818 thread(1) tag(0) INFO session_add_request:974 Done.
2024-07-20 17:48:06.818 thread(1) tag(0) INFO build_read_request_connected:508 Done
2024-07-20 17:48:06.820 thread(1) tag(0) INFO tag_read_start:341 Done.
2024-07-20 17:48:06.818 thread(3) tag(0) DETAIL socket_connect_tcp_start:1444 Setting up wake pipe.
2024-07-20 17:48:06.820 thread(3) tag(0) INFO sock_create_event_wakeup_channel:2161 Starting.
2024-07-20 17:48:06.820 thread(1) tag(0) DETAIL ab_tag_create:508 Using vtable 78F7DC90.
2024-07-20 17:48:06.822 thread(1) tag(0) INFO ab_tag_create:510 Done.
2024-07-20 17:48:06.822 thread(1) tag(0) INFO set_tag_byte_order:3969 Starting.
2024-07-20 17:48:06.822 thread(1) tag(0) INFO set_tag_byte_order:4287 Done.
2024-07-20 17:48:06.822 thread(3) tag(0) INFO sock_create_event_wakeup_channel:2308 Done.
2024-07-20 17:48:06.822 thread(1) tag(0) DETAIL add_tag_lookup:4390 Starting.
2024-07-20 17:48:06.822 thread(1) tag(0) DETAIL add_tag_lookup:4410 Found unused ID 11
2024-07-20 17:48:06.822 thread(3) tag(0) DETAIL socket_connect_tcp_start:1475 Socket connection attempt 0 started successfully.
2024-07-20 17:48:06.822 thread(3) tag(0) DETAIL socket_connect_tcp_start:1501 Done.
2024-07-20 17:48:06.822 thread(1) tag(0) DETAIL add_tag_lookup:4430 Done.
2024-07-20 17:48:06.822 thread(3) tag(0) INFO session_open_socket:693 Done.
2024-07-20 17:48:06.822 thread(1) tag(11) INFO plc_tag_create_ex:973 Returning mapped tag ID 11
2024-07-20 17:48:06.822 thread(3) tag(0) DETAIL session_handler:1074 Connect started, going to state SESSION_OPEN_SOCKET_WAIT.
2024-07-20 17:48:06.822 thread(1) tag(11) DETAIL plc_tag_create_ex:999 Tag status after creation is PLCTAG_STATUS_PENDING.
2024-07-20 17:48:06.822 thread(3) tag(0) DETAIL session_handler:1086 in SESSION_OPEN_SOCKET_WAIT state.
2024-07-20 17:48:06.824 thread(1) tag(11) DETAIL plc_tag_tickler_wake_impl:220 Starting. Called from plc_tag_create_ex:1010.
2024-07-20 17:48:06.824 thread(3) tag(0) DETAIL socket_connect_tcp_check:1518 Starting.
2024-07-20 17:48:06.824 thread(1) tag(11) DETAIL plc_tag_tickler_wake_impl:233 Done. Called from plc_tag_create_ex:1010.
2024-07-20 17:48:06.824 thread(2) tag(11) DETAIL tag_tickler_func:550 Tickling tag 11.
2024-07-20 17:48:06.824 thread(2) tag(11) DETAIL plc_tag_generic_tickler:281 Tickling tag 11.
2024-07-20 17:48:06.824 thread(2) tag(11) DETAIL plc_tag_generic_tickler:386 Done.
2024-07-20 17:48:06.824 thread(2) tag(0) DETAIL check_read_request_status:1154 Read not ready with status PLCTAG_STATUS_PENDING.
2024-07-20 17:48:06.824 thread(2) tag(0) DETAIL check_read_status_connected:1369 Read request status is not OK.
2024-07-20 17:48:06.830 thread(3) tag(0) DETAIL socket_connect_tcp_check:1538 Socket is connected.
2024-07-20 17:48:06.830 thread(3) tag(0) DETAIL socket_connect_tcp_check:1598 Done.
2024-07-20 17:48:06.830 thread(3) tag(0) INFO session_handler:1092 Socket connection succeeded.
2024-07-20 17:48:06.830 thread(3) tag(0) DETAIL session_handler:1113 in SESSION_REGISTER state.
2024-07-20 17:48:06.830 thread(3) tag(0) INFO session_register:706 Starting.
2024-07-20 17:48:06.832 thread(3) tag(0) INFO send_eip_request:1876 Starting.
2024-07-20 17:48:06.832 thread(3) tag(0) INFO send_eip_request:1889 Sending packet of size 28
2024-07-20 17:48:06.832 thread(3) tag(0) INFO send_eip_request:1890 00000 65 00 04 00 00 00 00 00 00 00 00 00 00 00 00 00
2024-07-20 17:48:06.832 thread(3) tag(0) INFO send_eip_request:1890 00016 00 00 00 00 00 00 00 00 01 00 00 00
2024-07-20 17:48:06.832 thread(3) tag(0) DETAIL socket_write:1945 Starting.
2024-07-20 17:48:06.832 thread(3) tag(0) DETAIL socket_write:2072 Done: result = 28.
2024-07-20 17:48:06.832 thread(3) tag(0) INFO send_eip_request:1932 Done.
2024-07-20 17:48:06.832 thread(3) tag(0) INFO recv_eip_response:1952 Starting.
2024-07-20 17:48:06.832 thread(3) tag(0) DETAIL socket_read:1807 Starting.
2024-07-20 17:48:06.832 thread(3) tag(0) DETAIL socket_read:1836 Immediate read attempt did not succeed, now wait for select().
2024-07-20 17:48:06.836 thread(3) tag(0) DETAIL socket_read:1864 Socket can read data.
2024-07-20 17:48:06.836 thread(3) tag(0) DETAIL socket_read:1934 Done: result = 24.
2024-07-20 17:48:06.836 thread(3) tag(0) DETAIL socket_read:1807 Starting.
2024-07-20 17:48:06.836 thread(3) tag(0) DETAIL socket_read:1934 Done: result = 4.
2024-07-20 17:48:06.836 thread(3) tag(0) INFO recv_eip_response:2023 request received all needed data (28 bytes of 28).
2024-07-20 17:48:06.836 thread(3) tag(0) INFO recv_eip_response:2025 00000 65 00 04 00 70 01 1b 00 00 00 00 00 00 00 00 00
2024-07-20 17:48:06.836 thread(3) tag(0) INFO recv_eip_response:2025 00016 00 00 00 00 00 00 00 00 01 00 00 00
2024-07-20 17:48:06.838 thread(3) tag(0) INFO recv_eip_response:2032 Done.
2024-07-20 17:48:06.838 thread(3) tag(0) INFO session_register:773 Done.
2024-07-20 17:48:06.838 thread(3) tag(0) DETAIL session_handler:1129 in SESSION_SEND_FORWARD_OPEN state.
2024-07-20 17:48:06.838 thread(3) tag(0) INFO send_forward_open_request:2070 Starting
2024-07-20 17:48:06.838 thread(3) tag(0) DETAIL send_forward_open_request:2091 Flag prohibiting use of extended ForwardOpen is 0.
2024-07-20 17:48:06.838 thread(3) tag(0) DETAIL send_forward_open_request:2155 Set maximum payload size guess to 1996 bytes.
2024-07-20 17:48:06.838 thread(3) tag(0) INFO send_extended_forward_open_request:2259 Starting
2024-07-20 17:48:06.838 thread(3) tag(0) INFO send_eip_request:1876 Starting.
2024-07-20 17:48:06.838 thread(3) tag(0) INFO send_eip_request:1889 Sending packet of size 92
2024-07-20 17:48:06.838 thread(3) tag(0) INFO send_eip_request:1890 00000 6f 00 44 00 70 01 1b 00 00 00 00 00 60 52 00 00
2024-07-20 17:48:06.840 thread(3) tag(0) INFO send_eip_request:1890 00016 00 00 00 00 00 00 00 00 00 00 00 00 01 00 02 00
2024-07-20 17:48:06.840 thread(3) tag(0) INFO send_eip_request:1890 00032 00 00 00 00 b2 00 34 00 5b 02 20 06 24 01 0a 05
2024-07-20 17:48:06.840 thread(3) tag(0) INFO send_eip_request:1890 00048 00 00 00 00 37 0e 00 00 54 73 3d f3 45 43 50 21
2024-07-20 17:48:06.840 thread(3) tag(0) INFO send_eip_request:1890 00064 01 00 00 00 40 42 0f 00 cc 07 00 42 40 42 0f 00
2024-07-20 17:48:06.840 thread(3) tag(0) INFO send_eip_request:1890 00080 cc 07 00 42 a3 03 01 00 20 02 24 01
2024-07-20 17:48:06.842 thread(3) tag(0) DETAIL socket_write:1945 Starting.
2024-07-20 17:48:06.842 thread(3) tag(0) DETAIL socket_write:2072 Done: result = 92.
2024-07-20 17:48:06.842 thread(3) tag(0) INFO send_eip_request:1932 Done.
2024-07-20 17:48:06.844 thread(3) tag(0) INFO send_extended_forward_open_request:2318 Done
2024-07-20 17:48:06.844 thread(3) tag(0) INFO send_forward_open_request:2160 Done
2024-07-20 17:48:06.844 thread(3) tag(0) DETAIL session_handler:1135 Send Forward Open succeeded, going to SESSION_RECEIVE_FORWARD_OPEN state.
2024-07-20 17:48:06.846 thread(3) tag(0) DETAIL session_handler:1142 in SESSION_RECEIVE_FORWARD_OPEN state.
2024-07-20 17:48:06.846 thread(3) tag(0) INFO receive_forward_open_response:2331 Starting
2024-07-20 17:48:06.846 thread(3) tag(0) INFO recv_eip_response:1952 Starting.
2024-07-20 17:48:06.846 thread(3) tag(0) DETAIL socket_read:1807 Starting.
2024-07-20 17:48:06.846 thread(3) tag(0) DETAIL socket_read:1934 Done: result = 24.
2024-07-20 17:48:06.846 thread(3) tag(0) DETAIL socket_read:1807 Starting.
2024-07-20 17:48:06.846 thread(3) tag(0) DETAIL socket_read:1934 Done: result = 46.
2024-07-20 17:48:06.846 thread(3) tag(0) INFO recv_eip_response:2023 request received all needed data (70 bytes of 70).
2024-07-20 17:48:06.846 thread(3) tag(0) INFO recv_eip_response:2025 00000 6f 00 2e 00 70 01 1b 00 00 00 00 00 60 52 00 00
2024-07-20 17:48:06.848 thread(3) tag(0) INFO recv_eip_response:2025 00016 00 00 00 00 00 00 00 00 00 00 00 00 01 00 02 00
2024-07-20 17:48:06.848 thread(3) tag(0) INFO recv_eip_response:2025 00032 00 00 00 00 b2 00 1e 00 db 00 00 00 c1 0d 4c 0c
2024-07-20 17:48:06.848 thread(3) tag(0) INFO recv_eip_response:2025 00048 37 0e 00 00 54 73 3d f3 45 43 50 21 40 42 0f 00
2024-07-20 17:48:06.848 thread(3) tag(0) INFO recv_eip_response:2025 00064 40 42 0f 00 00 00
2024-07-20 17:48:06.848 thread(3) tag(0) INFO recv_eip_response:2032 Done.
2024-07-20 17:48:06.848 thread(3) tag(0) INFO receive_forward_open_response:2393 ForwardOpen succeeded with our connection ID e37 and the PLC connection ID c4c0dc1 with packet size 1996.
2024-07-20 17:48:06.848 thread(3) tag(0) INFO receive_forward_open_response:2398 Done.
2024-07-20 17:48:06.848 thread(3) tag(0) DETAIL session_handler:1161 Send Forward Open succeeded, going to SESSION_IDLE state.
2024-07-20 17:48:06.850 thread(3) tag(0) DETAIL session_handler:1168 in SESSION_IDLE state.
2024-07-20 17:48:06.850 thread(3) tag(0) DETAIL session_handler:1174 There are 1 requests pending before cleanup and sending.
2024-07-20 17:48:06.850 thread(3) tag(0) DETAIL get_payload_size:1679 Starting.
2024-07-20 17:48:06.850 thread(3) tag(0) DETAIL get_payload_size:1693 Done.
2024-07-20 17:48:06.852 thread(3) tag(0) INFO process_requests:1450 1 requests to process.
2024-07-20 17:48:06.852 thread(3) tag(0) INFO pack_requests:1714 Starting.
2024-07-20 17:48:06.852 thread(3) tag(0) INFO pack_requests:1724 Only one request, so done.
2024-07-20 17:48:06.852 thread(3) tag(0) INFO prepare_request:1816 Starting.
2024-07-20 17:48:06.852 thread(3) tag(0) DETAIL prepare_request:1845 cpf_targ_conn_id=c4c0dc1
2024-07-20 17:48:06.852 thread(3) tag(0) INFO prepare_request:1853 Preparing connected packet with connection ID e37 and sequence ID 1(1)
2024-07-20 17:48:06.852 thread(3) tag(0) INFO prepare_request:1860 Prepared packet of size 68
2024-07-20 17:48:06.852 thread(3) tag(0) INFO prepare_request:1861 00000 70 00 2c 00 70 01 1b 00 00 00 00 00 00 00 00 00
2024-07-20 17:48:06.852 thread(3) tag(0) INFO prepare_request:1861 00016 00 00 00 00 00 00 00 00 00 00 00 00 01 00 02 00
2024-07-20 17:48:06.852 thread(3) tag(0) INFO prepare_request:1861 00032 a1 00 04 00 c1 0d 4c 0c b1 00 18 00 01 00 4c 09
2024-07-20 17:48:06.852 thread(3) tag(0) INFO prepare_request:1861 00048 91 0f 53 74 72 69 6e 67 41 72 72 61 79 5f 32 35
2024-07-20 17:48:06.852 thread(3) tag(0) INFO prepare_request:1861 00064 36 00 02 00
2024-07-20 17:48:06.852 thread(3) tag(0) INFO prepare_request:1863 Done.
2024-07-20 17:48:06.852 thread(3) tag(0) INFO send_eip_request:1876 Starting.
2024-07-20 17:48:06.852 thread(3) tag(0) INFO send_eip_request:1889 Sending packet of size 68
2024-07-20 17:48:06.854 thread(3) tag(0) INFO send_eip_request:1890 00000 70 00 2c 00 70 01 1b 00 00 00 00 00 00 00 00 00
2024-07-20 17:48:06.854 thread(3) tag(0) INFO send_eip_request:1890 00016 00 00 00 00 00 00 00 00 00 00 00 00 01 00 02 00
2024-07-20 17:48:06.854 thread(3) tag(0) INFO send_eip_request:1890 00032 a1 00 04 00 c1 0d 4c 0c b1 00 18 00 01 00 4c 09
2024-07-20 17:48:06.854 thread(3) tag(0) INFO send_eip_request:1890 00048 91 0f 53 74 72 69 6e 67 41 72 72 61 79 5f 32 35
2024-07-20 17:48:06.854 thread(3) tag(0) INFO send_eip_request:1890 00064 36 00 02 00
2024-07-20 17:48:06.854 thread(3) tag(0) DETAIL socket_write:1945 Starting.
2024-07-20 17:48:06.854 thread(3) tag(0) DETAIL socket_write:2072 Done: result = 68.
2024-07-20 17:48:06.854 thread(3) tag(0) INFO send_eip_request:1932 Done.
2024-07-20 17:48:06.854 thread(3) tag(0) INFO recv_eip_response:1952 Starting.
2024-07-20 17:48:06.854 thread(3) tag(0) DETAIL socket_read:1807 Starting.
2024-07-20 17:48:06.854 thread(3) tag(0) DETAIL socket_read:1836 Immediate read attempt did not succeed, now wait for select().
2024-07-20 17:48:06.858 thread(3) tag(0) DETAIL socket_read:1864 Socket can read data.
2024-07-20 17:48:06.858 thread(3) tag(0) DETAIL socket_read:1934 Done: result = 24.
2024-07-20 17:48:06.858 thread(3) tag(0) DETAIL socket_read:1807 Starting.
2024-07-20 17:48:06.858 thread(3) tag(0) DETAIL socket_read:1934 Done: result = 28.
2024-07-20 17:48:06.858 thread(3) tag(0) INFO recv_eip_response:2023 request received all needed data (52 bytes of 52).
2024-07-20 17:48:06.858 thread(3) tag(0) INFO recv_eip_response:2025 00000 70 00 1c 00 70 01 1b 00 00 00 00 00 00 00 00 00
2024-07-20 17:48:06.858 thread(3) tag(0) INFO recv_eip_response:2025 00016 00 00 00 00 00 00 00 00 00 00 00 00 00 00 02 00
2024-07-20 17:48:06.858 thread(3) tag(0) INFO recv_eip_response:2025 00032 a1 00 04 00 37 0e 00 00 b1 00 08 00 01 00 cc 00
2024-07-20 17:48:06.860 thread(3) tag(0) INFO recv_eip_response:2025 00048 20 01 17 80
2024-07-20 17:48:06.862 thread(3) tag(0) INFO recv_eip_response:2032 Done.
2024-07-20 17:48:06.862 thread(3) tag(0) INFO unpack_response:1558 Starting.
2024-07-20 17:48:06.862 thread(3) tag(0) INFO unpack_response:1567 Got single response packet.  Copying 52 bytes unchanged.
2024-07-20 17:48:06.862 thread(3) tag(0) INFO unpack_response:1656 Unpacked packet:
2024-07-20 17:48:06.862 thread(3) tag(0) INFO unpack_response:1657 00000 70 00 1c 00 70 01 1b 00 00 00 00 00 00 00 00 00
2024-07-20 17:48:06.862 thread(3) tag(0) INFO unpack_response:1657 00016 00 00 00 00 00 00 00 00 00 00 00 00 00 00 02 00
2024-07-20 17:48:06.862 thread(3) tag(0) INFO unpack_response:1657 00032 a1 00 04 00 37 0e 00 00 b1 00 08 00 01 00 cc 00
2024-07-20 17:48:06.862 thread(3) tag(0) INFO unpack_response:1657 00048 20 01 17 80
2024-07-20 17:48:06.862 thread(3) tag(0) DETAIL unpack_response:1666 Done.
2024-07-20 17:48:06.862 thread(3) tag(0) DETAIL plc_tag_tickler_wake_impl:220 Starting. Called from process_requests:1538.
2024-07-20 17:48:06.862 thread(3) tag(0) DETAIL plc_tag_tickler_wake_impl:233 Done. Called from process_requests:1538.
2024-07-20 17:48:06.862 thread(2) tag(11) DETAIL tag_tickler_func:550 Tickling tag 11.
2024-07-20 17:48:06.862 thread(2) tag(11) DETAIL plc_tag_generic_tickler:281 Tickling tag 11.
2024-07-20 17:48:06.862 thread(2) tag(11) DETAIL plc_tag_generic_tickler:386 Done.
2024-07-20 17:48:06.864 thread(2) tag(0) WARN check_read_status_connected:1416 CIP read failed with status: 0x20 Invalid parameter.
2024-07-20 17:48:06.864 thread(2) tag(0) INFO check_read_status_connected:1417 A parameter associated with the request was invalid. This code is used when a parameter does meet the requirements defined in an Application Object specification.
2024-07-20 17:48:06.864 thread(2) tag(0) DETAIL rc_dec_impl:242 Calling cleanup functions due to call at check_read_status_connected:1520 for 00B6F1B0.
2024-07-20 17:48:06.864 thread(2) tag(0) INFO refcount_cleanup:256 Starting
2024-07-20 17:48:06.864 thread(2) tag(0) DETAIL request_destroy:2567 Starting.
2024-07-20 17:48:06.864 thread(2) tag(0) DETAIL request_destroy:2576 Done.
2024-07-20 17:48:06.864 thread(2) tag(0) INFO refcount_cleanup:268 Done.
2024-07-20 17:48:06.864 thread(2) tag(0) WARN check_read_status_connected:1547 Error received!
2024-07-20 17:48:06.864 thread(2) tag(0) DETAIL ab_tag_abort:727 Starting.
2024-07-20 17:48:06.864 thread(2) tag(0) DETAIL ab_tag_abort:736 Called without a request in flight.
2024-07-20 17:48:06.864 thread(2) tag(0) DETAIL ab_tag_abort:743 Done.
2024-07-20 17:48:06.864 thread(2) tag(0) DETAIL tag_raise_event:201 PLCTAG_EVENT_CREATED raised with status PLCTAG_ERR_BAD_PARAM.
2024-07-20 17:48:06.864 thread(2) tag(0) DETAIL tag_raise_event:218 PLCTAG_EVENT_READ_COMPLETED raised with status PLCTAG_ERR_BAD_PARAM.
2024-07-20 17:48:06.864 thread(2) tag(0) DETAIL plc_tag_tickler_wake_impl:220 Starting. Called from tag_tickler_func:569.
2024-07-20 17:48:06.864 thread(2) tag(0) DETAIL plc_tag_tickler_wake_impl:233 Done. Called from tag_tickler_func:569.
2024-07-20 17:48:06.866 thread(2) tag(11) DETAIL plc_tag_generic_handle_event_callbacks:402 Tag creation complete with status PLCTAG_ERR_BAD_PARAM.
2024-07-20 17:48:06.866 thread(1) tag(11) WARN plc_tag_create_ex:1047 Error PLCTAG_ERR_BAD_PARAM while trying to create tag!
2024-07-20 17:48:06.866 thread(1) tag(11) DETAIL ab_tag_abort:727 Starting.
2024-07-20 17:48:06.866 thread(1) tag(11) DETAIL ab_tag_abort:736 Called without a request in flight.
2024-07-20 17:48:06.866 thread(1) tag(11) DETAIL ab_tag_abort:743 Done.
2024-07-20 17:48:06.866 thread(1) tag(11) DETAIL hashtable_remove:232 Starting
2024-07-20 17:48:06.866 thread(1) tag(11) DETAIL hashtable_remove:250 Done
2024-07-20 17:48:06.872 thread(2) tag(0) DETAIL rc_dec_impl:242 Calling cleanup functions due to call at tag_tickler_func:619 for 00C07A30.
2024-07-20 17:48:06.872 thread(2) tag(0) INFO refcount_cleanup:256 Starting
2024-07-20 17:48:06.872 thread(2) tag(0) INFO ab_tag_destroy:795 Starting.
2024-07-20 17:48:06.872 thread(2) tag(0) DETAIL ab_tag_abort:727 Starting.
2024-07-20 17:48:06.872 thread(2) tag(0) DETAIL ab_tag_abort:736 Called without a request in flight.
2024-07-20 17:48:06.872 thread(2) tag(0) DETAIL ab_tag_abort:743 Done.
2024-07-20 17:48:06.872 thread(2) tag(0) DETAIL ab_tag_destroy:810 Getting ready to release tag session 00C07C50
2024-07-20 17:48:06.872 thread(2) tag(0) DETAIL ab_tag_destroy:812 Removing tag from session.
2024-07-20 17:48:06.874 thread(2) tag(0) DETAIL rc_dec_impl:242 Calling cleanup functions due to call at ab_tag_destroy:813 for 00C07C50.
2024-07-20 17:48:06.874 thread(2) tag(0) INFO refcount_cleanup:256 Starting
2024-07-20 17:48:06.874 thread(2) tag(0) INFO session_destroy:815 Starting.
2024-07-20 17:48:06.874 thread(2) tag(0) DETAIL remove_session:400 Starting.
2024-07-20 17:48:06.874 thread(2) tag(0) DETAIL remove_session_unsafe:376 Starting
2024-07-20 17:48:06.874 thread(2) tag(0) DETAIL remove_session_unsafe:391 Done
2024-07-20 17:48:06.874 thread(2) tag(0) DETAIL remove_session:408 Done.
2024-07-20 17:48:06.874 thread(2) tag(0) INFO session_destroy:826 Session sent 3 packets.
ErrorBadParam
2024-07-20 17:48:06.874 thread(2) tag(0) DETAIL session_destroy:837 Destroying session thread.
2024-07-20 17:48:06.874 thread(3) tag(0) DETAIL session_handler:1325 Critical block.
2024-07-20 17:48:06.874 thread(2) tag(0) INFO perform_forward_close:2043 Starting.
2024-07-20 17:48:06.874 thread(2) tag(0) INFO send_forward_close_req:2410 Starting
2024-07-20 17:48:06.876 thread(2) tag(0) DETAIL send_forward_close_req:2422 Forward Close connection path:
2024-07-20 17:48:06.876 thread(2) tag(0) DETAIL send_forward_close_req:2423 00000 01 00 20 02 24 01
2024-07-20 17:48:06.876 thread(2) tag(0) INFO send_eip_request:1876 Starting.
2024-07-20 17:48:06.876 thread(2) tag(0) INFO send_eip_request:1889 Sending packet of size 64
2024-07-20 17:48:06.876 thread(2) tag(0) INFO send_eip_request:1890 00000 6f 00 28 00 70 01 1b 00 00 00 00 00 61 52 00 00
2024-07-20 17:48:06.876 thread(2) tag(0) INFO send_eip_request:1890 00016 00 00 00 00 00 00 00 00 00 00 00 00 01 00 02 00
2024-07-20 17:48:06.876 thread(2) tag(0) INFO send_eip_request:1890 00032 00 00 00 00 b2 00 18 00 4e 02 20 06 24 01 0a 05
2024-07-20 17:48:06.876 thread(2) tag(0) INFO send_eip_request:1890 00048 54 73 3d f3 45 43 50 21 03 00 01 00 20 02 24 01
2024-07-20 17:48:06.876 thread(2) tag(0) DETAIL socket_write:1945 Starting.
2024-07-20 17:48:06.878 thread(2) tag(0) DETAIL socket_write:2072 Done: result = 64.
2024-07-20 17:48:06.878 thread(2) tag(0) INFO send_eip_request:1932 Done.
2024-07-20 17:48:06.878 thread(2) tag(0) INFO send_forward_close_req:2462 Done
2024-07-20 17:48:06.878 thread(2) tag(0) INFO recv_forward_close_resp:2473 Starting
2024-07-20 17:48:06.878 thread(2) tag(0) INFO recv_eip_response:1952 Starting.
2024-07-20 17:48:06.878 thread(2) tag(0) DETAIL socket_read:1807 Starting.
2024-07-20 17:48:06.878 thread(2) tag(0) DETAIL socket_read:1836 Immediate read attempt did not succeed, now wait for select().
2024-07-20 17:48:06.882 thread(2) tag(0) DETAIL socket_read:1864 Socket can read data.
2024-07-20 17:48:06.882 thread(2) tag(0) DETAIL socket_read:1934 Done: result = 24.
2024-07-20 17:48:06.882 thread(2) tag(0) DETAIL socket_read:1807 Starting.
2024-07-20 17:48:06.882 thread(2) tag(0) DETAIL socket_read:1934 Done: result = 30.
2024-07-20 17:48:06.882 thread(2) tag(0) INFO recv_eip_response:2023 request received all needed data (54 bytes of 54).
2024-07-20 17:48:06.882 thread(2) tag(0) INFO recv_eip_response:2025 00000 6f 00 1e 00 70 01 1b 00 00 00 00 00 61 52 00 00
2024-07-20 17:48:06.882 thread(2) tag(0) INFO recv_eip_response:2025 00016 00 00 00 00 00 00 00 00 00 00 00 00 01 00 02 00
2024-07-20 17:48:06.882 thread(2) tag(0) INFO recv_eip_response:2025 00032 00 00 00 00 b2 00 0e 00 ce 00 00 00 54 73 3d f3
2024-07-20 17:48:06.884 thread(2) tag(0) INFO recv_eip_response:2025 00048 45 43 50 21 00 00
2024-07-20 17:48:06.884 thread(2) tag(0) INFO recv_eip_response:2032 Done.
2024-07-20 17:48:06.884 thread(2) tag(0) INFO recv_forward_close_resp:2502 Connection close succeeded.
2024-07-20 17:48:06.884 thread(2) tag(0) INFO recv_forward_close_resp:2507 Done.
2024-07-20 17:48:06.884 thread(2) tag(0) INFO perform_forward_close:2059 Done.
2024-07-20 17:48:06.884 thread(2) tag(0) INFO session_unregister:783 Starting.
2024-07-20 17:48:06.884 thread(2) tag(0) INFO session_unregister:787 Done.
2024-07-20 17:48:06.884 thread(2) tag(0) INFO session_close_socket:796 Starting.
2024-07-20 17:48:06.884 thread(2) tag(0) INFO socket_close:2083 Starting.
2024-07-20 17:48:06.884 thread(2) tag(0) INFO socket_close:2119 Done.
2024-07-20 17:48:06.884 thread(2) tag(0) INFO socket_destroy:2128 Starting.
2024-07-20 17:48:06.884 thread(2) tag(0) INFO socket_close:2083 Starting.
2024-07-20 17:48:06.884 thread(2) tag(0) INFO socket_close:2119 Done.
2024-07-20 17:48:06.886 thread(2) tag(0) INFO socket_destroy:2145 Done.
2024-07-20 17:48:06.886 thread(2) tag(0) INFO session_close_socket:804 Done.
2024-07-20 17:48:06.886 thread(2) tag(0) DETAIL session_destroy:885 Destroying session condition variable.
2024-07-20 17:48:06.886 thread(2) tag(0) DETAIL cond_destroy:1242 Starting.
2024-07-20 17:48:06.886 thread(2) tag(0) DETAIL cond_destroy:1253 Done.
2024-07-20 17:48:06.886 thread(2) tag(0) DETAIL session_destroy:892 Destroying session mutex.
2024-07-20 17:48:06.886 thread(2) tag(0) DETAIL mutex_destroy:841 destroying mutex 00C08CA8
2024-07-20 17:48:06.886 thread(2) tag(0) DETAIL mutex_destroy:854 Done.
2024-07-20 17:48:06.886 thread(2) tag(0) DETAIL session_destroy:898 Cleaning up allocated memory for paths and host name.
2024-07-20 17:48:06.886 thread(2) tag(0) INFO session_destroy:914 Done.
2024-07-20 17:48:06.886 thread(2) tag(0) INFO refcount_cleanup:268 Done.
2024-07-20 17:48:06.886 thread(2) tag(0) DETAIL mutex_destroy:841 destroying mutex 00C07A5C
2024-07-20 17:48:06.886 thread(2) tag(0) DETAIL mutex_destroy:854 Done.
2024-07-20 17:48:06.886 thread(2) tag(0) DETAIL mutex_destroy:841 destroying mutex 00C07A60
2024-07-20 17:48:06.886 thread(2) tag(0) DETAIL mutex_destroy:854 Done.
2024-07-20 17:48:06.888 thread(2) tag(0) DETAIL cond_destroy:1242 Starting.
2024-07-20 17:48:06.888 thread(2) tag(0) DETAIL cond_destroy:1253 Done.
2024-07-20 17:48:06.888 thread(2) tag(0) INFO ab_tag_destroy:844 Finished releasing all tag resources.
2024-07-20 17:48:06.888 thread(2) tag(0) INFO ab_tag_destroy:846 done
2024-07-20 17:48:06.888 thread(2) tag(0) INFO refcount_cleanup:268 Done.
2