libplctag.NET icon indicating copy to clipboard operation
libplctag.NET copied to clipboard
verified publisher icon libplctag

Logix Emulator - Timeout issue

Open patopat opened this issue 3 years ago • 13 comments

Hi,

I run the libplctag.NET sample ILoggerExample with modified name,gateway and Path in the host machine. I configured in a VM an emulator test project : image image

image

image

        class Example : IHostedService
        {
            public async Task StartAsync(CancellationToken cancellationToken)
            {
                var myTag = new Tag<DintPlcMapper, int>()
                {
                    //Name = "MyTag[0]",
                    //Gateway = "127.0.0.1",
                    //Path = "1,0",
                    Name = "Test",
                    Gateway = "192.168.80.130",
                    Path = "2,0",
                    PlcType = PlcType.ControlLogix,
                    Protocol = Protocol.ab_eip,
                    Timeout = TimeSpan.FromSeconds(5),
                };

                await myTag.InitializeAsync();
            }

            public Task StopAsync(CancellationToken cancellationToken) => Task.CompletedTask;

        }

it seems connection ok, but something is wrong, could you help me ?

Here is the log window :

dbug: Microsoft.Extensions.Hosting.Internal.Host[1]
      Hosting starting
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.212 thread(1) tag(0) INFO plc_tag_create_ex:833 Starting
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.216 thread(1) tag(0) INFO initialize_modules:177 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.217 thread(1) tag(0) INFO initialize_modules:186 Creating library mutex.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.218 thread(1) tag(0) DETAIL mutex_create:726 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.221 thread(1) tag(0) DETAIL mutex_create:753 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.222 thread(1) tag(0) INFO initialize_modules:207 Initialized library modules.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.223 thread(1) tag(0) INFO lib_init:135 Setting up global library data.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.224 thread(1) tag(0) INFO lib_init:137 Creating tag hashtable.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.225 thread(1) tag(0) INFO hashtable_create:75 Starting
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.227 thread(1) tag(0) INFO hashtable_create:99 Done
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.242 thread(1) tag(0) INFO lib_init:143 Creating tag hashtable mutex.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.262 thread(1) tag(0) DETAIL mutex_create:726 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.468 thread(1) tag(0) DETAIL mutex_create:753 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.592 thread(1) tag(0) INFO lib_init:149 Creating tag condition variable.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.710 thread(1) tag(0) DETAIL cond_create:1087 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.768 thread(1) tag(0) DETAIL cond_create:1114 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.880 thread(1) tag(0) INFO lib_init:155 Creating tag tickler thread.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.895 thread(1) tag(0) DETAIL thread_create:884 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.902 thread(1) tag(0) DETAIL thread_create:917 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.921 thread(1) tag(0) INFO lib_init:161 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.916 thread(2) tag(0) INFO tag_tickler_func:508 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:36.968 thread(1) tag(0) INFO initialize_modules:210 Initializing AB module.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.009 thread(1) tag(0) INFO ab_init:123 Initializing AB protocol library.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.016 thread(1) tag(0) DETAIL mutex_create:726 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.021 thread(1) tag(0) DETAIL mutex_create:753 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.024 thread(1) tag(0) INFO ab_init:132 Finished initializing AB protocol library.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.085 thread(1) tag(0) INFO initialize_modules:215 Initializing Modbus module.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.111 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.138 thread(1) tag(0) INFO mb_init:2405 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.161 thread(1) tag(0) DETAIL mb_init:2407 Setting up mutex.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.166 thread(1) tag(0) DETAIL mutex_create:726 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.170 thread(1) tag(0) DETAIL mutex_create:753 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.173 thread(1) tag(0) INFO mb_init:2416 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.174 thread(1) tag(0) INFO initialize_modules:226 Done initializing library modules.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.178 thread(1) tag(0) INFO initialize_modules:231 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.184 thread(1) tag(0) INFO find_tag_create_func:99 Matched protocol=ab_eip
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.187 thread(1) tag(0) INFO ab_tag_create:173 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.188 thread(1) tag(0) INFO rc_alloc_impl:111 Starting, called from ab_tag_create:180
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.191 thread(1) tag(0) INFO rc_alloc_impl:130 Done
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.192 thread(1) tag(0) DETAIL rc_alloc_impl:135 Returning memory pointer 000002A53800CA90
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.195 thread(1) tag(0) DETAIL ab_tag_create:186 tag=000002A53800CA90
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.197 thread(1) tag(0) INFO plc_tag_generic_init_tag:462 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.205 thread(1) tag(0) DETAIL mutex_create:726 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.208 thread(1) tag(0) DETAIL mutex_create:753 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.212 thread(1) tag(0) DETAIL mutex_create:726 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.213 thread(1) tag(0) DETAIL mutex_create:753 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.215 thread(1) tag(0) DETAIL cond_create:1087 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.217 thread(1) tag(0) DETAIL cond_create:1114 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.218 thread(1) tag(0) INFO plc_tag_generic_init_tag:493 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.219 thread(1) tag(0) DETAIL get_plc_type:915 Found ControlLogix/CompactLogix PLC.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.221 thread(1) tag(0) DETAIL get_plc_type:915 Found ControlLogix/CompactLogix PLC.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.223 thread(1) tag(0) DETAIL session_find_or_create:235 Starting
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.224 thread(1) tag(0) DETAIL session_find_or_create:259 Creating new session.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.226 thread(1) tag(0) INFO session_create_unsafe:451 Starting
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.227 thread(1) tag(0) DETAIL session_create_unsafe:454 Session should use connected messaging.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.229 thread(1) tag(0) INFO rc_alloc_impl:111 Starting, called from session_create_unsafe:459
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.232 thread(1) tag(0) INFO rc_alloc_impl:130 Done
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.237 thread(1) tag(0) DETAIL rc_alloc_impl:135 Returning memory pointer 000002A539D3C760
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.238 thread(1) tag(0) DETAIL cip_encode_path:72 Starting
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.240 thread(1) tag(0) DETAIL match_numeric_segment:189 Starting at position 0 in string 2,0.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.242 thread(1) tag(0) DETAIL match_numeric_segment:216 Done.   Found numeric segment 2.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.243 thread(1) tag(0) DETAIL cip_encode_path:83 Found numeric segment.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.244 thread(1) tag(0) DETAIL cip_encode_path:79 Skipping separator character ','.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.245 thread(1) tag(0) DETAIL match_numeric_segment:189 Starting at position 2 in string 2,0.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.247 thread(1) tag(0) DETAIL match_numeric_segment:216 Done.   Found numeric segment 0.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.248 thread(1) tag(0) DETAIL cip_encode_path:83 Found numeric segment.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.250 thread(1) tag(0) DETAIL cip_encode_path:124 PLC needs connection, adding path to the router object.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.251 thread(1) tag(0) DETAIL cip_encode_path:156 IOI size before 6
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.252 thread(1) tag(0) DETAIL cip_encode_path:177 Done
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.253 thread(1) tag(0) DETAIL session_create_unsafe:507 Setting connection_group_id to 0.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.255 thread(1) tag(0) DETAIL session_create_unsafe:547 Set maximum payload size to 508 bytes.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.258 thread(1) tag(0) DETAIL add_session_unsafe:315 Starting
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.260 thread(1) tag(0) DETAIL add_session_unsafe:325 Done
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.261 thread(1) tag(0) INFO session_create_unsafe:564 Done
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.262 thread(1) tag(0) INFO session_init:580 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.262 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.266 thread(1) tag(0) DETAIL mutex_create:726 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.268 thread(1) tag(0) DETAIL mutex_create:753 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.274 thread(1) tag(0) DETAIL cond_create:1087 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.285 thread(1) tag(0) DETAIL cond_create:1114 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.287 thread(1) tag(0) DETAIL thread_create:884 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.289 thread(1) tag(0) DETAIL thread_create:917 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.292 thread(3) tag(0) INFO session_handler:1014 Starting thread for session 000002A539D3C760
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.292 thread(1) tag(0) INFO session_init:602 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.294 thread(3) tag(0) DETAIL session_handler:1034 in SESSION_OPEN_SOCKET_START state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.297 thread(1) tag(0) DETAIL session_find_or_create:305 Done
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.302 thread(3) tag(0) INFO session_open_socket:620 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.304 thread(1) tag(0) DETAIL ab_tag_create:287 using session=000002A539D3C760
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.308 thread(3) tag(0) DETAIL socket_create:1311 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.312 thread(3) tag(0) DETAIL socket_create:1334 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.312 thread(1) tag(0) DETAIL get_tag_data_type:532 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.314 thread(3) tag(0) DETAIL session_open_socket:654 Using default port 44818.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.317 thread(1) tag(0) DETAIL str_str_cmp_i:444 Needle string is longer than haystack string.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.320 thread(3) tag(0) DETAIL socket_connect_tcp_start:1355 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.324 thread(1) tag(0) DETAIL str_str_cmp_i:444 Needle string is longer than haystack string.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.328 thread(3) tag(0) DETAIL socket_connect_tcp_start:1404 Found numeric IP address: 192.168.80.130
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.341 thread(1) tag(0) INFO get_tag_data_type:644 Setting element size to 4.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.343 thread(3) tag(0) DETAIL socket_connect_tcp_start:1444 Setting up wake pipe.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.345 thread(1) tag(0) DETAIL get_tag_data_type:662 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.353 thread(3) tag(0) INFO sock_create_event_wakeup_channel:2161 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.356 thread(1) tag(0) DETAIL ab_tag_create:353 Setting up Logix tag.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.359 thread(3) tag(0) INFO sock_create_event_wakeup_channel:2308 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.363 thread(1) tag(0) DETAIL ab_tag_create:364 Using default Logix byte order.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.366 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.369 thread(3) tag(0) DETAIL socket_connect_tcp_start:1475 Socket connection attempt 0 started successfully.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.378 thread(1) tag(0) DETAIL ab_tag_create:370 Setting default Logix vtable.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.380 thread(3) tag(0) DETAIL socket_connect_tcp_start:1501 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.382 thread(1) tag(0) DETAIL parse_symbolic_segment:633 Starting with name index=0 and encoded name index=1.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.383 thread(3) tag(0) INFO session_open_socket:669 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.384 thread(3) tag(0) DETAIL session_handler:1051 Connect started, going to state SESSION_OPEN_SOCKET_WAIT.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.388 thread(1) tag(0) DETAIL parse_symbolic_segment:672 Parsed symbolic segment "Test" in tag name.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.390 thread(1) tag(0) DETAIL ab_tag_create:500 Kicking off initial read.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.391 thread(1) tag(0) INFO tag_read_start:305 Starting
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.392 thread(1) tag(0) INFO build_read_request_connected:416 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.393 thread(1) tag(0) DETAIL session_create_request:2496 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.394 thread(1) tag(0) INFO rc_alloc_impl:111 Starting, called from session_create_request:2505
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.395 thread(3) tag(0) DETAIL session_handler:1059 in SESSION_OPEN_SOCKET_WAIT state.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.397 thread(1) tag(0) INFO rc_alloc_impl:130 Done
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.399 thread(3) tag(0) DETAIL socket_connect_tcp_check:1518 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.400 thread(1) tag(0) DETAIL rc_alloc_impl:135 Returning memory pointer 000002A515F09EA0
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.401 thread(3) tag(0) DETAIL socket_connect_tcp_check:1538 Socket is connected.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.402 thread(1) tag(0) DETAIL session_create_request:2519 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.403 thread(3) tag(0) DETAIL socket_connect_tcp_check:1598 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.405 thread(1) tag(0) INFO session_add_request:942 Starting. sess=000002A539D3C760, req=000002A515F09EA0
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.406 thread(3) tag(0) INFO session_handler:1065 Socket connection succeeded.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.408 thread(1) tag(0) DETAIL session_add_request_unsafe:907 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.410 thread(1) tag(0) DETAIL session_add_request_unsafe:926 Total requests in the queue: 1
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.411 thread(1) tag(0) DETAIL session_add_request_unsafe:928 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.411 thread(1) tag(0) INFO session_add_request:950 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.411 thread(3) tag(0) DETAIL session_handler:1086 in SESSION_REGISTER state.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.412 thread(1) tag(0) INFO build_read_request_connected:501 Done
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.413 thread(3) tag(0) INFO session_register:682 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.415 thread(1) tag(0) INFO tag_read_start:334 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.415 thread(3) tag(0) INFO send_eip_request:1849 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.416 thread(1) tag(0) DETAIL ab_tag_create:513 Using vtable 00007FF9676B4120.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.417 thread(3) tag(0) INFO send_eip_request:1862 Sending packet of size 28
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.418 thread(1) tag(0) INFO ab_tag_create:515 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.420 thread(3) tag(0) INFO send_eip_request:1863  65 00 04 00 00 00 00 00 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.421 thread(1) tag(0) INFO set_tag_byte_order:3859 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.422 thread(3) tag(0) INFO send_eip_request:1863  00 00 00 00 00 00 00 00 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.423 thread(1) tag(0) INFO set_tag_byte_order:4160 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.424 thread(3) tag(0) INFO send_eip_request:1863  00 00 00 00 01 00 00 00
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.425 thread(1) tag(0) DETAIL add_tag_lookup:4263 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.426 thread(3) tag(0) DETAIL socket_write:1945 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.428 thread(1) tag(0) DETAIL add_tag_lookup:4283 Found unused ID 11
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.428 thread(3) tag(0) DETAIL socket_write:2072 Done: result = 28.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.430 thread(3) tag(0) INFO send_eip_request:1905 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.430 thread(1) tag(0) DETAIL add_tag_lookup:4303 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.430 thread(3) tag(0) INFO recv_eip_response:1925 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.432 thread(1) tag(11) INFO plc_tag_create_ex:959 Returning mapped tag ID 11
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.432 thread(3) tag(0) DETAIL socket_read:1807 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.433 thread(1) tag(11) DETAIL plc_tag_create_ex:985 Tag status after creation is PLCTAG_STATUS_PENDING.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.434 thread(3) tag(0) DETAIL socket_read:1934 Done: result = 24.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.437 thread(1) tag(11) INFO plc_tag_create_ex:1060 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.437 thread(3) tag(0) DETAIL socket_read:1807 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.439 thread(3) tag(0) DETAIL socket_read:1934 Done: result = 4.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.440 thread(3) tag(0) INFO recv_eip_response:1996 request received all needed data (28 bytes of 28).
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.442 thread(3) tag(0) INFO recv_eip_response:1998  65 00 04 00 42 00 ee ee 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.443 thread(3) tag(0) INFO recv_eip_response:1998  00 00 00 00 00 00 00 00 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.444 thread(3) tag(0) INFO recv_eip_response:1998  00 00 00 00 01 00 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.445 thread(3) tag(0) INFO recv_eip_response:2005 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.446 thread(3) tag(0) INFO session_register:749 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.448 thread(3) tag(0) DETAIL session_handler:1102 in SESSION_SEND_FORWARD_OPEN state.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.449 thread(3) tag(0) INFO send_forward_open_request:2043 Starting
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.450 thread(3) tag(0) DETAIL send_forward_open_request:2126 Set maximum payload size guess to 4002 bytes.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.451 thread(3) tag(0) INFO send_extended_forward_open_request:2230 Starting
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.452 thread(3) tag(0) INFO send_eip_request:1849 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.454 thread(3) tag(0) INFO send_eip_request:1862 Sending packet of size 92
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.456 thread(3) tag(0) INFO send_eip_request:1863  6f 00 44 00 42 00 ee ee 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.458 thread(1) tag(11) INFO plc_tag_register_callback:1125 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.458 thread(3) tag(0) INFO send_eip_request:1863  00 00 3f 22 00 00 00 00 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.459 thread(1) tag(11) INFO plc_tag_register_callback_ex:1180 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.461 thread(3) tag(0) INFO send_eip_request:1863  00 00 00 00 00 00 00 00 01 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.462 thread(1) tag(11) INFO plc_tag_register_callback_ex:1203 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.463 thread(3) tag(0) INFO send_eip_request:1863  02 00 00 00 00 00 b2 00 34 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.464 thread(1) tag(11) INFO plc_tag_register_callback:1129 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.465 thread(3) tag(0) INFO send_eip_request:1863  5b 02 20 06 24 01 0a 05 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.468 thread(3) tag(0) INFO send_eip_request:1863  00 00 62 1f 00 00 d4 01 3d f3
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.470 thread(3) tag(0) INFO send_eip_request:1863  45 43 50 21 01 00 00 00 40 42
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.472 thread(3) tag(0) INFO send_eip_request:1863  0f 00 a2 0f 00 42 40 42 0f 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.474 thread(3) tag(0) INFO send_eip_request:1863  a2 0f 00 42 a3 03 02 00 20 02
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.476 thread(3) tag(0) INFO send_eip_request:1863  24 01
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:37.476 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.824 thread(3) tag(0) DETAIL socket_write:1945 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.829 thread(3) tag(0) DETAIL socket_write:2072 Done: result = 92.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.830 thread(3) tag(0) INFO send_eip_request:1905 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.831 thread(3) tag(0) INFO send_extended_forward_open_request:2289 Done
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.831 thread(1) tag(11) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.832 thread(3) tag(0) INFO send_forward_open_request:2131 Done
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.834 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.834 thread(3) tag(0) DETAIL session_handler:1108 Send Forward Open succeeded, going to SESSION_RECEIVE_FORWARD_OPEN state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.835 thread(1) tag(11) DETAIL check_read_status_connected:1343 Read request status is not OK.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.838 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.840 thread(3) tag(0) DETAIL session_handler:1115 in SESSION_RECEIVE_FORWARD_OPEN state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.840 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.842 thread(3) tag(0) INFO receive_forward_open_response:2302 Starting
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.843 thread(3) tag(0) INFO recv_eip_response:1925 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.844 thread(2) tag(0) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.844 thread(3) tag(0) DETAIL socket_read:1807 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.846 thread(2) tag(0) DETAIL check_read_status_connected:1343 Read request status is not OK.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.846 thread(3) tag(0) DETAIL socket_read:1934 Done: result = 24.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.848 thread(3) tag(0) DETAIL socket_read:1807 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.849 thread(3) tag(0) DETAIL socket_read:1934 Done: result = 20.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.849 thread(3) tag(0) INFO recv_eip_response:1996 request received all needed data (44 bytes of 44).
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.851 thread(3) tag(0) INFO recv_eip_response:1998  6f 00 14 00 42 00 ee ee 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.851 thread(3) tag(0) INFO recv_eip_response:1998  00 00 3f 22 00 00 00 00 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.853 thread(3) tag(0) INFO recv_eip_response:1998  00 00 00 00 00 00 00 00 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.853 thread(3) tag(0) INFO recv_eip_response:1998  02 00 00 00 00 00 b2 00 04 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.855 thread(3) tag(0) INFO recv_eip_response:1998  db 00 08 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.855 thread(3) tag(0) INFO recv_eip_response:2005 Done.
warn: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.857 thread(3) tag(0) WARN receive_forward_open_response:2326 Forward Open command failed, response code: Unsupported service. (8)
warn: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.881 thread(3) tag(0) WARN receive_forward_open_response:2329 Received CIP command unsupported error from the PLC!
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.882 thread(3) tag(0) INFO receive_forward_open_response:2369 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.882 thread(3) tag(0) DETAIL session_handler:1126 PLC does not support ForwardOpenEx, trying old ForwardOpen.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.884 thread(3) tag(0) DETAIL session_handler:1102 in SESSION_SEND_FORWARD_OPEN state.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.884 thread(3) tag(0) INFO send_forward_open_request:2043 Starting
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.885 thread(3) tag(0) DETAIL send_forward_open_request:2093 Set maximum payload size guess to 508 bytes.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.887 thread(3) tag(0) INFO send_old_forward_open_request:2143 Starting
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.888 thread(3) tag(0) INFO send_eip_request:1849 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.888 thread(3) tag(0) INFO send_eip_request:1862 Sending packet of size 88
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.889 thread(3) tag(0) INFO send_eip_request:1863  6f 00 40 00 42 00 ee ee 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.891 thread(3) tag(0) INFO send_eip_request:1863  00 00 40 22 00 00 00 00 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.891 thread(3) tag(0) INFO send_eip_request:1863  00 00 00 00 00 00 00 00 01 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.892 thread(3) tag(0) INFO send_eip_request:1863  02 00 00 00 00 00 b2 00 30 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.893 thread(3) tag(0) INFO send_eip_request:1863  54 02 20 06 24 01 0a 05 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.894 thread(3) tag(0) INFO send_eip_request:1863  00 00 62 1f 00 00 d5 01 3d f3
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.895 thread(3) tag(0) INFO send_eip_request:1863  45 43 50 21 01 00 00 00 40 42
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.896 thread(3) tag(0) INFO send_eip_request:1863  0f 00 fc 43 40 42 0f 00 fc 43
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.897 thread(3) tag(0) INFO send_eip_request:1863  a3 03 02 00 20 02 24 01
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.898 thread(3) tag(0) DETAIL socket_write:1945 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.899 thread(3) tag(0) DETAIL socket_write:2072 Done: result = 88.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.900 thread(3) tag(0) INFO send_eip_request:1905 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.901 thread(3) tag(0) INFO send_old_forward_open_request:2217 Done
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.902 thread(3) tag(0) INFO send_forward_open_request:2131 Done
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.903 thread(3) tag(0) DETAIL session_handler:1108 Send Forward Open succeeded, going to SESSION_RECEIVE_FORWARD_OPEN state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.904 thread(3) tag(0) DETAIL session_handler:1115 in SESSION_RECEIVE_FORWARD_OPEN state.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.904 thread(3) tag(0) INFO receive_forward_open_response:2302 Starting
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.906 thread(3) tag(0) INFO recv_eip_response:1925 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.907 thread(3) tag(0) DETAIL socket_read:1807 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.908 thread(3) tag(0) DETAIL socket_read:1934 Done: result = 24.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.909 thread(3) tag(0) DETAIL socket_read:1807 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.910 thread(3) tag(0) DETAIL socket_read:1934 Done: result = 36.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.912 thread(3) tag(0) INFO recv_eip_response:1996 request received all needed data (60 bytes of 60).
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.913 thread(3) tag(0) INFO recv_eip_response:1998  6f 00 24 00 42 00 ee ee 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.914 thread(3) tag(0) INFO recv_eip_response:1998  00 00 40 22 00 00 00 00 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.915 thread(3) tag(0) INFO recv_eip_response:1998  00 00 00 00 00 00 00 00 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.915 thread(3) tag(0) INFO recv_eip_response:1998  02 00 00 00 00 00 b2 00 14 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.917 thread(3) tag(0) INFO recv_eip_response:1998  d4 00 02 00 d5 01 3d f3 45 43
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.918 thread(3) tag(0) INFO recv_eip_response:1998  50 21 03 00 02 00 20 02 24 01
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.919 thread(3) tag(0) INFO recv_eip_response:2005 Done.
warn: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.919 thread(3) tag(0) WARN receive_forward_open_response:2326 Forward Open command failed, response code: Resource Unavailable for Unconnected Send (2)
warn: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.922 thread(3) tag(0) WARN receive_forward_open_response:2351 CIP error code Resource Unavailable for Unconnected Send (The Target device does not have the resources to process the Unconnected Send request.)!
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.923 thread(3) tag(0) INFO receive_forward_open_response:2369 Done.
warn: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.924 thread(3) tag(0) WARN session_handler:1130 Receive Forward Open failed PLCTAG_ERR_REMOTE_ERR!
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.925 thread(3) tag(0) DETAIL session_handler:1199 in SESSION_UNREGISTER state.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.927 thread(3) tag(0) INFO session_unregister:759 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.927 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.928 thread(3) tag(0) INFO session_unregister:763 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.929 thread(3) tag(0) DETAIL session_handler:1210 in SESSION_CLOSE_SOCKET state.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.931 thread(3) tag(0) INFO session_close_socket:772 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.931 thread(3) tag(0) INFO socket_close:2083 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.932 thread(3) tag(0) INFO socket_close:2119 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.933 thread(3) tag(0) INFO socket_destroy:2128 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.934 thread(3) tag(0) INFO socket_close:2083 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.935 thread(3) tag(0) INFO socket_close:2119 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.936 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.937 thread(3) tag(0) INFO socket_destroy:2145 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.937 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.938 thread(3) tag(0) INFO session_close_socket:780 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.939 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.940 thread(3) tag(0) DETAIL session_handler:1225 in SESSION_START_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.941 thread(2) tag(0) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.943 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:38.944 thread(2) tag(0) DETAIL check_read_status_connected:1343 Read request status is not OK.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.029 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.031 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.032 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.033 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.034 thread(2) tag(0) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.035 thread(2) tag(0) DETAIL check_read_status_connected:1343 Read request status is not OK.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.041 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.130 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.133 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.134 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.135 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.137 thread(2) tag(0) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.138 thread(2) tag(0) DETAIL check_read_status_connected:1343 Read request status is not OK.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.142 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.234 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.249 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.253 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.261 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.263 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.265 thread(2) tag(0) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.282 thread(2) tag(0) DETAIL check_read_status_connected:1343 Read request status is not OK.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.349 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.351 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.365 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.399 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.400 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.402 thread(2) tag(0) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.402 thread(2) tag(0) DETAIL check_read_status_connected:1343 Read request status is not OK.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.452 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.464 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.466 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.468 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.469 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.470 thread(2) tag(0) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.472 thread(2) tag(0) DETAIL check_read_status_connected:1343 Read request status is not OK.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.553 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.565 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.570 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.572 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.573 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.574 thread(2) tag(0) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.576 thread(2) tag(0) DETAIL check_read_status_connected:1343 Read request status is not OK.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.654 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.670 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.675 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.677 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.680 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.681 thread(2) tag(0) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.682 thread(2) tag(0) DETAIL check_read_status_connected:1343 Read request status is not OK.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.755 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.777 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.831 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.846 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.848 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.850 thread(2) tag(0) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.852 thread(2) tag(0) DETAIL check_read_status_connected:1343 Read request status is not OK.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.855 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.918 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.924 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.930 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.931 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.933 thread(2) tag(0) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.936 thread(2) tag(0) DETAIL check_read_status_connected:1343 Read request status is not OK.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:39.956 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.025 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.059 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.195 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.288 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.320 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.388 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.436 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.506 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.543 thread(2) tag(0) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.607 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.636 thread(2) tag(0) DETAIL check_read_status_connected:1343 Read request status is not OK.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.661 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.667 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.701 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.712 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.714 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.719 thread(2) tag(0) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.812 thread(2) tag(0) DETAIL check_read_status_connected:1343 Read request status is not OK.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.818 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.880 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:40.973 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.021 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.067 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.099 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.242 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.356 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.581 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.607 thread(2) tag(0) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.649 thread(4) tag(11) INFO plc_tag_abort:1419 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.671 thread(2) tag(0) DETAIL check_read_status_connected:1343 Read request status is not OK.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.678 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.692 thread(4) tag(11) DETAIL ab_tag_abort:731 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.749 thread(4) tag(11) DETAIL ab_tag_abort:747 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.772 thread(4) tag(11) DETAIL tag_raise_event:186 PLCTAG_EVENT_ABORTED raised with status PLCTAG_ERR_ABORT.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.790 thread(4) tag(11) DETAIL tag_raise_event:190 Raising synthesized created event on abort event.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.790 thread(3) tag(0) DETAIL purge_aborted_requests_unsafe:1332 Session thread releasing aborted request 000002A515F09EA0.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.817 thread(2) tag(11) DETAIL plc_tag_generic_handle_event_callbacks:397 Tag creation complete with status PLCTAG_ERR_ABORT.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.814 thread(4) tag(11) DETAIL plc_tag_tickler_wake_impl:215 Starting. Called from plc_tag_abort:1448.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.831 thread(3) tag(0) DETAIL rc_dec_impl:242 Calling cleanup functions due to call at purge_aborted_requests_unsafe:1339 for 000002A515F09EA0.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.854 thread(4) tag(11) DETAIL plc_tag_tickler_wake_impl:228 Done. Called from plc_tag_abort:1448.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.936 thread(3) tag(0) INFO refcount_cleanup:256 Starting
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.951 thread(3) tag(0) DETAIL request_destroy:2538 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.962 thread(2) tag(11) DETAIL plc_tag_generic_handle_event_callbacks:421 Tag operation aborted with status PLCTAG_ERR_ABORT.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:41.985 thread(3) tag(0) DETAIL request_destroy:2547 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.013 thread(4) tag(0) INFO plc_tag_abort:1454 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.014 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.054 thread(3) tag(0) INFO refcount_cleanup:268 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.119 thread(3) tag(0) DETAIL purge_aborted_requests_unsafe:1347 Removed 1 aborted requests.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.187 thread(4) tag(11) INFO plc_tag_unregister_callback:1226 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.239 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.293 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.306 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.313 thread(4) tag(11) INFO plc_tag_unregister_callback:1245 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.332 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.334 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.395 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.425 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.426 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.479 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.527 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.528 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.550 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.671 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.671 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.732 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.765 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.778 thread(3) tag(0) DETAIL session_handler:1237 in SESSION_WAIT_RETRY state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.780 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.809 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-25 11:23:42.849 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.

Best regards, Pat

patopat avatar Aug 25 '22 09:08 patopat

People have not had good luck with the emulator. That said, your path is incorrect. It should be "1,2". The first element is the port from the network module. In this case "1" means the back plane of the virtual chassis. Then the second element is the slot of the CPU. That would be "2".

kyle-github avatar Aug 25 '22 14:08 kyle-github

@kyle-github,

Ok, I changed the path like this :

    var myTag = new Tag<DintPlcMapper, int>()
    {
        //Name = "MyTag[0]",
        //Gateway = "127.0.0.1",
        //Path = "1,0",
        Name = "EmulatorTestProject.Test",
        Gateway = "192.168.80.130",
        Path = "1,2",
        PlcType = PlcType.ControlLogix,
        Protocol = Protocol.ab_eip,
        Timeout = TimeSpan.FromSeconds(20),
    };

Could you help me to find what exactly block the connection ? I guess it's when "warn: " messages are coming but I can't interpret what exactly is the meaning.

dbug: Microsoft.Extensions.Hosting.Internal.Host[1]
      Hosting starting
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.803 thread(1) tag(0) INFO plc_tag_create_ex:833 Starting
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.805 thread(1) tag(0) INFO initialize_modules:177 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.806 thread(1) tag(0) INFO initialize_modules:186 Creating library mutex.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.808 thread(1) tag(0) DETAIL mutex_create:726 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.809 thread(1) tag(0) DETAIL mutex_create:753 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.810 thread(1) tag(0) INFO initialize_modules:207 Initialized library modules.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.811 thread(1) tag(0) INFO lib_init:135 Setting up global library data.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.812 thread(1) tag(0) INFO lib_init:137 Creating tag hashtable.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.813 thread(1) tag(0) INFO hashtable_create:75 Starting
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.815 thread(1) tag(0) INFO hashtable_create:99 Done
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.815 thread(1) tag(0) INFO lib_init:143 Creating tag hashtable mutex.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.817 thread(1) tag(0) DETAIL mutex_create:726 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.818 thread(1) tag(0) DETAIL mutex_create:753 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.820 thread(1) tag(0) INFO lib_init:149 Creating tag condition variable.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.820 thread(1) tag(0) DETAIL cond_create:1087 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.822 thread(1) tag(0) DETAIL cond_create:1114 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.822 thread(1) tag(0) INFO lib_init:155 Creating tag tickler thread.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.824 thread(1) tag(0) DETAIL thread_create:884 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.825 thread(1) tag(0) DETAIL thread_create:917 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.826 thread(1) tag(0) INFO lib_init:161 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.826 thread(2) tag(0) INFO tag_tickler_func:508 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.826 thread(1) tag(0) INFO initialize_modules:210 Initializing AB module.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.829 thread(1) tag(0) INFO ab_init:123 Initializing AB protocol library.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.850 thread(1) tag(0) DETAIL mutex_create:726 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.852 thread(1) tag(0) DETAIL mutex_create:753 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.854 thread(1) tag(0) INFO ab_init:132 Finished initializing AB protocol library.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.856 thread(1) tag(0) INFO initialize_modules:215 Initializing Modbus module.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.860 thread(1) tag(0) INFO mb_init:2405 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.862 thread(1) tag(0) DETAIL mb_init:2407 Setting up mutex.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.864 thread(1) tag(0) DETAIL mutex_create:726 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.865 thread(1) tag(0) DETAIL mutex_create:753 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.867 thread(1) tag(0) INFO mb_init:2416 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.869 thread(1) tag(0) INFO initialize_modules:226 Done initializing library modules.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.870 thread(1) tag(0) INFO initialize_modules:231 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.872 thread(1) tag(0) INFO find_tag_create_func:99 Matched protocol=ab_eip
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.874 thread(1) tag(0) INFO ab_tag_create:173 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.876 thread(1) tag(0) INFO rc_alloc_impl:111 Starting, called from ab_tag_create:180
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.879 thread(1) tag(0) INFO rc_alloc_impl:130 Done
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.884 thread(1) tag(0) DETAIL rc_alloc_impl:135 Returning memory pointer 000002587024ED00
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.885 thread(1) tag(0) DETAIL ab_tag_create:186 tag=000002587024ED00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.887 thread(1) tag(0) INFO plc_tag_generic_init_tag:462 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.888 thread(1) tag(0) DETAIL mutex_create:726 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.889 thread(1) tag(0) DETAIL mutex_create:753 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.890 thread(1) tag(0) DETAIL mutex_create:726 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.892 thread(1) tag(0) DETAIL mutex_create:753 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.892 thread(1) tag(0) DETAIL cond_create:1087 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.893 thread(1) tag(0) DETAIL cond_create:1114 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.895 thread(1) tag(0) INFO plc_tag_generic_init_tag:493 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.895 thread(1) tag(0) DETAIL get_plc_type:915 Found ControlLogix/CompactLogix PLC.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.896 thread(1) tag(0) DETAIL get_plc_type:915 Found ControlLogix/CompactLogix PLC.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.898 thread(1) tag(0) DETAIL session_find_or_create:235 Starting
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.899 thread(1) tag(0) DETAIL session_find_or_create:259 Creating new session.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.900 thread(1) tag(0) INFO session_create_unsafe:451 Starting
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.901 thread(1) tag(0) DETAIL session_create_unsafe:454 Session should use connected messaging.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.902 thread(1) tag(0) INFO rc_alloc_impl:111 Starting, called from session_create_unsafe:459
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.903 thread(1) tag(0) INFO rc_alloc_impl:130 Done
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.904 thread(1) tag(0) DETAIL rc_alloc_impl:135 Returning memory pointer 0000025870255E90
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.905 thread(1) tag(0) DETAIL cip_encode_path:72 Starting
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.906 thread(1) tag(0) DETAIL match_numeric_segment:189 Starting at position 0 in string 1,2.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.907 thread(1) tag(0) DETAIL match_numeric_segment:216 Done.   Found numeric segment 1.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.908 thread(1) tag(0) DETAIL cip_encode_path:83 Found numeric segment.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.909 thread(1) tag(0) DETAIL cip_encode_path:79 Skipping separator character ','.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.910 thread(1) tag(0) DETAIL match_numeric_segment:189 Starting at position 2 in string 1,2.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.911 thread(1) tag(0) DETAIL match_numeric_segment:216 Done.   Found numeric segment 2.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.912 thread(1) tag(0) DETAIL cip_encode_path:83 Found numeric segment.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.915 thread(1) tag(0) DETAIL cip_encode_path:124 PLC needs connection, adding path to the router object.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.916 thread(1) tag(0) DETAIL cip_encode_path:156 IOI size before 6
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.917 thread(1) tag(0) DETAIL cip_encode_path:177 Done
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.944 thread(1) tag(0) DETAIL session_create_unsafe:507 Setting connection_group_id to 0.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.944 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.945 thread(1) tag(0) DETAIL session_create_unsafe:547 Set maximum payload size to 508 bytes.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.949 thread(1) tag(0) DETAIL add_session_unsafe:315 Starting
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.951 thread(1) tag(0) DETAIL add_session_unsafe:325 Done
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.953 thread(1) tag(0) INFO session_create_unsafe:564 Done
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.954 thread(1) tag(0) INFO session_init:580 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.955 thread(1) tag(0) DETAIL mutex_create:726 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.956 thread(1) tag(0) DETAIL mutex_create:753 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.957 thread(1) tag(0) DETAIL cond_create:1087 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.979 thread(1) tag(0) DETAIL cond_create:1114 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.980 thread(1) tag(0) DETAIL thread_create:884 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.982 thread(1) tag(0) DETAIL thread_create:917 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.983 thread(1) tag(0) INFO session_init:602 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.983 thread(3) tag(0) INFO session_handler:1014 Starting thread for session 0000025870255E90
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.984 thread(1) tag(0) DETAIL session_find_or_create:305 Done
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.986 thread(3) tag(0) DETAIL session_handler:1034 in SESSION_OPEN_SOCKET_START state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.987 thread(1) tag(0) DETAIL ab_tag_create:287 using session=0000025870255E90
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.988 thread(3) tag(0) INFO session_open_socket:620 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.989 thread(1) tag(0) DETAIL get_tag_data_type:532 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.990 thread(3) tag(0) DETAIL socket_create:1311 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.991 thread(1) tag(0) INFO get_tag_data_type:644 Setting element size to 4.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.993 thread(1) tag(0) DETAIL get_tag_data_type:662 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.994 thread(1) tag(0) DETAIL ab_tag_create:353 Setting up Logix tag.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.995 thread(1) tag(0) DETAIL ab_tag_create:364 Using default Logix byte order.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.996 thread(1) tag(0) DETAIL ab_tag_create:370 Setting default Logix vtable.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.997 thread(3) tag(0) DETAIL socket_create:1334 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.998 thread(1) tag(0) DETAIL parse_symbolic_segment:633 Starting with name index=0 and encoded name index=1.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:44.999 thread(3) tag(0) DETAIL session_open_socket:654 Using default port 44818.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.000 thread(3) tag(0) DETAIL socket_connect_tcp_start:1355 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.001 thread(1) tag(0) DETAIL parse_symbolic_segment:672 Parsed symbolic segment "EmulatorTestProject" in tag name.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.003 thread(1) tag(0) DETAIL parse_symbolic_segment:633 Starting with name index=20 and encoded name index=23.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.004 thread(1) tag(0) DETAIL parse_symbolic_segment:672 Parsed symbolic segment "Test" in tag name.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.005 thread(1) tag(0) DETAIL cip_encode_tag_name:535 Found symbolic segment ending at 24
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.006 thread(1) tag(0) DETAIL ab_tag_create:500 Kicking off initial read.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.007 thread(3) tag(0) DETAIL socket_connect_tcp_start:1404 Found numeric IP address: 192.168.80.130
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.007 thread(1) tag(0) INFO tag_read_start:305 Starting
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.008 thread(3) tag(0) DETAIL socket_connect_tcp_start:1444 Setting up wake pipe.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.010 thread(1) tag(0) INFO build_read_request_connected:416 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.011 thread(3) tag(0) INFO sock_create_event_wakeup_channel:2161 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.014 thread(1) tag(0) DETAIL session_create_request:2496 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.016 thread(1) tag(0) INFO rc_alloc_impl:111 Starting, called from session_create_request:2505
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.015 thread(3) tag(0) INFO sock_create_event_wakeup_channel:2308 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.016 thread(1) tag(0) INFO rc_alloc_impl:130 Done
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.019 thread(1) tag(0) DETAIL rc_alloc_impl:135 Returning memory pointer 0000025870178270
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.020 thread(3) tag(0) DETAIL socket_connect_tcp_start:1475 Socket connection attempt 0 started successfully.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.020 thread(1) tag(0) DETAIL session_create_request:2519 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.021 thread(1) tag(0) INFO session_add_request:942 Starting. sess=0000025870255E90, req=0000025870178270
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.023 thread(3) tag(0) DETAIL socket_connect_tcp_start:1501 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.025 thread(1) tag(0) DETAIL session_add_request_unsafe:907 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.025 thread(3) tag(0) INFO session_open_socket:669 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.028 thread(1) tag(0) DETAIL session_add_request_unsafe:926 Total requests in the queue: 1
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.028 thread(3) tag(0) DETAIL session_handler:1051 Connect started, going to state SESSION_OPEN_SOCKET_WAIT.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.030 thread(1) tag(0) DETAIL session_add_request_unsafe:928 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.032 thread(1) tag(0) INFO session_add_request:950 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.033 thread(3) tag(0) DETAIL session_handler:1059 in SESSION_OPEN_SOCKET_WAIT state.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.033 thread(1) tag(0) INFO build_read_request_connected:501 Done
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.035 thread(3) tag(0) DETAIL socket_connect_tcp_check:1518 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.037 thread(1) tag(0) INFO tag_read_start:334 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.038 thread(3) tag(0) DETAIL socket_connect_tcp_check:1538 Socket is connected.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.040 thread(1) tag(0) DETAIL ab_tag_create:513 Using vtable 00007FF838564120.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.041 thread(3) tag(0) DETAIL socket_connect_tcp_check:1598 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.043 thread(1) tag(0) INFO ab_tag_create:515 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.045 thread(3) tag(0) INFO session_handler:1065 Socket connection succeeded.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.049 thread(1) tag(0) INFO set_tag_byte_order:3859 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.052 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.052 thread(3) tag(0) DETAIL session_handler:1086 in SESSION_REGISTER state.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.052 thread(1) tag(0) INFO set_tag_byte_order:4160 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.055 thread(1) tag(0) DETAIL add_tag_lookup:4263 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.056 thread(3) tag(0) INFO session_register:682 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.079 thread(3) tag(0) INFO send_eip_request:1849 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.079 thread(1) tag(0) DETAIL add_tag_lookup:4283 Found unused ID 11
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.086 thread(1) tag(0) DETAIL add_tag_lookup:4303 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.089 thread(3) tag(0) INFO send_eip_request:1862 Sending packet of size 28
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.093 thread(1) tag(11) INFO plc_tag_create_ex:959 Returning mapped tag ID 11
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.095 thread(3) tag(0) INFO send_eip_request:1863  65 00 04 00 00 00 00 00 00 00
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.096 thread(1) tag(11) DETAIL plc_tag_create_ex:985 Tag status after creation is PLCTAG_STATUS_PENDING.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.099 thread(3) tag(0) INFO send_eip_request:1863  00 00 00 00 00 00 00 00 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.103 thread(1) tag(11) INFO plc_tag_create_ex:1060 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.103 thread(3) tag(0) INFO send_eip_request:1863  00 00 00 00 01 00 00 00
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.107 thread(3) tag(0) DETAIL socket_write:1945 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.108 thread(3) tag(0) DETAIL socket_write:2072 Done: result = 28.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.110 thread(3) tag(0) INFO send_eip_request:1905 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.112 thread(3) tag(0) INFO recv_eip_response:1925 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.113 thread(3) tag(0) DETAIL socket_read:1807 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.115 thread(3) tag(0) DETAIL socket_read:1934 Done: result = 24.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.116 thread(3) tag(0) DETAIL socket_read:1807 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.118 thread(3) tag(0) DETAIL socket_read:1934 Done: result = 4.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.119 thread(3) tag(0) INFO recv_eip_response:1996 request received all needed data (28 bytes of 28).
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.120 thread(3) tag(0) INFO recv_eip_response:1998  65 00 04 00 01 00 ee ee 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.122 thread(3) tag(0) INFO recv_eip_response:1998  00 00 00 00 00 00 00 00 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.122 thread(3) tag(0) INFO recv_eip_response:1998  00 00 00 00 01 00 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.124 thread(3) tag(0) INFO recv_eip_response:2005 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.124 thread(1) tag(11) INFO plc_tag_register_callback:1125 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.126 thread(3) tag(0) INFO session_register:749 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.127 thread(1) tag(11) INFO plc_tag_register_callback_ex:1180 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.129 thread(3) tag(0) DETAIL session_handler:1102 in SESSION_SEND_FORWARD_OPEN state.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.130 thread(1) tag(11) INFO plc_tag_register_callback_ex:1203 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.130 thread(3) tag(0) INFO send_forward_open_request:2043 Starting
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.132 thread(1) tag(11) INFO plc_tag_register_callback:1129 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.134 thread(3) tag(0) DETAIL send_forward_open_request:2126 Set maximum payload size guess to 4002 bytes.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.136 thread(3) tag(0) INFO send_extended_forward_open_request:2230 Starting
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.138 thread(3) tag(0) INFO send_eip_request:1849 Starting.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.139 thread(3) tag(0) INFO send_eip_request:1862 Sending packet of size 92
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.141 thread(3) tag(0) INFO send_eip_request:1863  6f 00 44 00 01 00 ee ee 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.142 thread(3) tag(0) INFO send_eip_request:1863  00 00 54 64 00 00 00 00 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.144 thread(3) tag(0) INFO send_eip_request:1863  00 00 00 00 00 00 00 00 01 00
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.144 thread(1) tag(11) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.144 thread(3) tag(0) INFO send_eip_request:1863  02 00 00 00 00 00 b2 00 34 00
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.146 thread(1) tag(11) DETAIL check_read_status_connected:1343 Read request status is not OK.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.147 thread(3) tag(0) INFO send_eip_request:1863  5b 02 20 06 24 01 0a 05 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.150 thread(3) tag(0) INFO send_eip_request:1863  00 00 43 10 00 00 ca 2f 3d f3
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.152 thread(3) tag(0) INFO send_eip_request:1863  45 43 50 21 01 00 00 00 40 42
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.152 thread(3) tag(0) INFO send_eip_request:1863  0f 00 a2 0f 00 42 40 42 0f 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.154 thread(3) tag(0) INFO send_eip_request:1863  a2 0f 00 42 a3 03 01 02 20 02
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.155 thread(3) tag(0) INFO send_eip_request:1863  24 01
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.155 thread(2) tag(0) DETAIL tag_tickler_func:631 Tag tickler thread timed out waiting for something to do.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.156 thread(3) tag(0) DETAIL socket_write:1945 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.159 thread(3) tag(0) DETAIL socket_write:2072 Done: result = 92.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.160 thread(2) tag(11) DETAIL tag_tickler_func:545 Tickling tag 11.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.161 thread(3) tag(0) INFO send_eip_request:1905 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.162 thread(2) tag(11) DETAIL plc_tag_generic_tickler:276 Tickling tag 11.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.164 thread(3) tag(0) INFO send_extended_forward_open_request:2289 Done
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.166 thread(2) tag(11) DETAIL plc_tag_generic_tickler:381 Done.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.166 thread(3) tag(0) INFO send_forward_open_request:2131 Done
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.169 thread(2) tag(0) DETAIL check_read_request_status:1088 Read not ready with status PLCTAG_STATUS_PENDING.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.169 thread(3) tag(0) DETAIL session_handler:1108 Send Forward Open succeeded, going to SESSION_RECEIVE_FORWARD_OPEN state.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.170 thread(2) tag(0) DETAIL check_read_status_connected:1343 Read request status is not OK.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.172 thread(3) tag(0) DETAIL session_handler:1115 in SESSION_RECEIVE_FORWARD_OPEN state.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.174 thread(3) tag(0) INFO receive_forward_open_response:2302 Starting
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.175 thread(3) tag(0) INFO recv_eip_response:1925 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.177 thread(3) tag(0) DETAIL socket_read:1807 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.178 thread(3) tag(0) DETAIL socket_read:1934 Done: result = 24.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.179 thread(3) tag(0) DETAIL socket_read:1807 Starting.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.181 thread(3) tag(0) DETAIL socket_read:1934 Done: result = 20.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.183 thread(3) tag(0) INFO recv_eip_response:1996 request received all needed data (44 bytes of 44).
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.184 thread(3) tag(0) INFO recv_eip_response:1998  6f 00 14 00 01 00 ee ee 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.185 thread(3) tag(0) INFO recv_eip_response:1998  00 00 54 64 00 00 00 00 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.186 thread(3) tag(0) INFO recv_eip_response:1998  00 00 00 00 00 00 00 00 00 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.188 thread(3) tag(0) INFO recv_eip_response:1998  02 00 00 00 00 00 b2 00 04 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.189 thread(3) tag(0) INFO recv_eip_response:1998  db 00 08 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.190 thread(3) tag(0) INFO recv_eip_response:2005 Done.
warn: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.192 thread(3) tag(0) WARN receive_forward_open_response:2326 Forward Open command failed, response code: Unsupported service. (8)
warn: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.226 thread(3) tag(0) WARN receive_forward_open_response:2329 Received CIP command unsupported error from the PLC!
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.228 thread(3) tag(0) INFO receive_forward_open_response:2369 Done.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.229 thread(3) tag(0) DETAIL session_handler:1126 PLC does not support ForwardOpenEx, trying old ForwardOpen.
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.230 thread(3) tag(0) DETAIL session_handler:1102 in SESSION_SEND_FORWARD_OPEN state.
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.231 thread(3) tag(0) INFO send_forward_open_request:2043 Starting
dbug: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 13:31:45.232 thread(3) tag(0) DETAIL send_forward_open_request:2093 Set maximum payload size guess to 508 bytes.

MANUALLY REMOVED NEXT LINES

Regards, Pat

patopat avatar Aug 29 '22 11:08 patopat

Hi Pat,

Thanks for the log dump. Unfortunately, it was cut off at a normal point. The library tries the newer extended CIP connection opening handshake command and if that does not succeed drops back to the older one. The last thing we see happen above is that the newer command is returning a "not supported" error from the PLC, so the library will drop back, but that is in the log after this point. The WARN messages are completely normal. I should probably change the log status to INFO for those as they are not error conditions.

Could you attach the full log?

kyle-github avatar Aug 29 '22 14:08 kyle-github

Hi @kyle-github,

Yes :) cmd.log

patopat avatar Aug 29 '22 14:08 patopat

Here's the relevant part:

info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 16:24:36.868 thread(3) tag(0) INFO recv_eip_response:1998  d4 00 01 01 09 01 9f 5d 3d f3
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 16:24:36.870 thread(3) tag(0) INFO recv_eip_response:1998  45 43 50 21 02 00
info: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 16:24:36.874 thread(3) tag(0) INFO recv_eip_response:2005 Done.
warn: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 16:24:36.877 thread(3) tag(0) WARN receive_forward_open_response:2326 Forward Open command failed, response code: Invalid Connection Size (1)
warn: CSharpDotNetCore.LibPlcTagLogSource[0]
      2022-08-29 16:24:36.879 thread(3) tag(0) WARN receive_forward_open_response:2351 CIP error code Invalid Connection Size (This error occurs when the target device doesn't support the requested connection size. Check the documentation of the manufacturer's device to verify the correct Connection size required by the device. Note that most devices specify this value in terms of bytes. The Productivity Suite CPU does not return this error and will instead use errors 0x0126, 0x0127 and 0x0128.)!

Interesting that this is the error we get. Not what I expected. The default is correct for a Control/CompactLogix, 544 bytes. But that isn't correct for the Emulator. I do not see any hints about the size in the returned packet. The remaining bytes are just a copy of the some of the connection set up info.

Generally you can request a smaller size than the maximum allowed, so the size needed is probably less than 544. When connecting to an older PLC such as a PLC/5, 508 is used. So when this error is found, it usually means that the requested connection size is too big.

I do not currently allow the tag string to specify the attempted connection size.

I will need to think about what to do here. Using the Emulator is something that others have wanted and you seem to be getting a lot farther than people have in the past.

kyle-github avatar Aug 29 '22 14:08 kyle-github

@kyle-github , Thank you very much for your help. Let me know if you need that I test some code to validate idea or anything else to help :)

Regards, Pat

patopat avatar Aug 29 '22 14:08 patopat

I was able to get this to work using FactoryTalk Logix Echo with the following command .\list_tags_logix.exe 127.0.0.2 1,0 Logix Echo allows you to assign your PLC an IP that matches your PC IP address, or any address from the loopback network (127). I chose 2 for the final octet.

Logix Echo is the new emulator for Rockwell PLCs that is only compatible with Firmware v33 and forward. I would be astounded if you could get the old emulator (Logix Emulate) to work, given that it uses RSLinx Classic, which doesn't assign the emulated PLC an IP address.

aTunell avatar Aug 30 '22 17:08 aTunell

@aTunell ,

Could you confirm that is "FactoryTalk Logix Echo ControlLogix 5580" ? I can see a single node product also...

Regards, Pat

patopat avatar Aug 30 '22 18:08 patopat

I'm using Logix Echo Single Node. I'm not sure what the ControlLogix 5580 product is. I believe it may be an exclusive emulator for L8 controllers. Fact is, Logix Echo currently only supports L8 controllers anyway, but they're working on GuardLogix and Compact Logix for future releases. Logix Echo is currently at 1.0, and has some curious bugs, including not releasing floating licenses when you shut it down, so be careful. You'll also need to switch from RSLinx to FactoryTalk Linx. You don't need the paid version, though.

Logix Echo IS a superior product, though. Probably deserves a thread on its own for discussion.

aTunell avatar Aug 30 '22 18:08 aTunell

@aTunell - Welcome to the party! 🎉 (We work together and I knew he's been having success with the AB emulator recently).

If we can document how to use libplctag with an emulator I think it would help a ton of people. I'd love to have a process for setting up the emulator in a wiki/doc. We get a ton of questions on it through this project, libplctag main, and on the Google groups.

jkoplo avatar Aug 30 '22 23:08 jkoplo

@aTunell,

Thank you for your feedback. I will receive the FT Echo soon. Could you describe briefly on how do you set the tool and some tips to avoid spending lot of time ?

Regards, Pat

patopat avatar Sep 01 '22 19:09 patopat

Hi @kyle-github , @jkoplo , @aTunell

Good news 😃 !! I received the FT Echo and I successfully run the example ExampleListTags from LibPlcTag.Net in the VM where RA tools are installed. The next step is to run the example from my host machine to communicate via the VM...

image

Here is the changes made :

            var tags = new Tag<TagInfoPlcMapper, TagInfo[]>()
            {
                //Gateway = "192.168.0.10",
                Gateway = "127.0.0.2",
                Path = "1,0",
                PlcType = PlcType.ControlLogix,
                Protocol = Protocol.ab_eip,
                Name = "@tags"
            };

FT Echo config :

image

Logix Designer config :

image

Regards, Pat

patopat avatar Sep 03 '22 08:09 patopat

It is really good to hear that the newer emulator works. I just cannot comprehend why the emulator would be so broken before. It is their own code!

I would like to continue to see how far we can get with the older emulator. I only have Studio 5000 v31. From the logs above, it looks like it is really close to working.

kyle-github avatar Sep 03 '22 14:09 kyle-github

@kyle-github , The libplctag program is running successfully from my host machine and the VM where RA tools are installed :)

I keep the VM with Studio 5000 V32.04 and Emulate for some test.

Regards, Pat

patopat avatar Sep 04 '22 09:09 patopat

Hi @patopat @jkoplo @kyle-github - looks like this one has been resolved, OK to close or is there an action on documenting emulator usage?

timyhac avatar Sep 29 '22 21:09 timyhac

Hi @timyhac , If I have understood well, I'm waiting on news by @kyle-github from above comment : https://github.com/libplctag/libplctag.NET/issues/265#issuecomment-1230409676. So close this isuue if no further action will be done.

patopat avatar Oct 06 '22 08:10 patopat