arv-viewer sees cameras but unable to acquire frames

[hanshinlee77]

Hello,

I have installed the most recent aravis on Windows 11 (msys2 mingw64). aravis tools all recognize the existence of two GigE PoE cameras(AVT GT4400 and AVT Mako G419). When running either of the cameras in arv-viewer, the viewer window shows blank. I saw a similar issue from someone else on a different camera, but I think I am dealing with a different issue perhaps specific to my installation/camera combination? I changed firewall settings (completely turning on/off, making new rules for arv-viewer and other tools). But I always get the same results
Hope someone can help me.
Thanks,

arv-viewer shows the following debug outputs: 
$ arv-viewer-0.8.exe -d all
[17:40:48.959] dY., interface> Found 0 USB3Vision device (among 7 USB devices)
[17:40:48.967] dY., interface> [GvDiscoverSocket::new] Add interface 172.29.166.83 (172.29.191.255)
[17:40:48.969] dY., interface> [GvDiscoverSocket::new] Add interface 169.254.212.45 (169.254.255.255)
[17:40:48.973] dY., interface> [GvDiscoverSocket::new] Add interface 10.155.35.72 (10.155.255.255)
[17:40:48.976] dY., interface> [GvDiscoverSocket::new] Add interface 127.0.0.1 (127.255.255.255)
[17:40:48.981] dY., interface> [GvInterface::discovery] Device 'Allied Vision Technologies-GT4400 (02-2696D)-02-2696D-07028' found (interface 169.254.212.45) user_id '' - MAC '00:0f:31:03:81:47'
[17:40:48.998] dY., interface> [GvInterface::discovery] Device 'Allied Vision Technologies-Mako G-419B (5049)-50-0536871631' found (interface 169.254.212.45) user_id '' - MAC '00:0f:31:5b:8f:4f'
[17:40:55.658] dY., interface> Found 0 USB3Vision device (among 7 USB devices)
[17:40:55.658] dY., device> [GvDevice::new] Interface address = 169.254.212.45
[17:40:55.658] dY., device> [GvDevice::new] Device address = 169.254.145.82
[17:40:55.673] dY., device> [GvDevice::load_genicam] xml url = 'Local:avt_5049_1.52.xml;100000;34A7A' at 0x200
[17:40:55.675] dY., device> [GvDevice::load_genicam] Xml address = 0x100000 - size = 0x34a7a - avt_5049_1.52.xml
[17:40:56.483] dY., genicam> [Gc::set_default_node_data] Add 'DeviceVersion'
[17:40:56.483] dY., genicam> [Gc::set_default_node_data] Add 'DeviceManufacturerInfo'
[17:40:56.489] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevCurrentIPConfigurationLLA'
[17:40:56.492] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevCurrentIPConfigurationDHCP'
[17:40:56.494] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevCurrentIPConfigurationPersistentIP'
[17:40:56.496] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevCurrentIPAddress'
[17:40:56.499] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevCurrentSubnetMask'
[17:40:56.499] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevCurrentDefaultGateway'
[17:40:56.499] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevNumberOfNetworkInterfaces'
[17:40:56.499] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevPersistentIPAddress'
[17:40:56.509] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevPersistentSubnetMask'
[17:40:56.509] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevPersistentDefaultGateway'
[17:40:56.509] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevStreamChannelCount'
[17:40:56.509] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevStreamChannelSelector'
[17:40:56.509] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevSCPAddrCalc'
[17:40:56.519] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevTimestampTickFrequency'
[17:40:56.522] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevSCPHostPort'
[17:40:56.524] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevSCPSFireTestPacket'
[17:40:56.526] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevSCPSDoNotFragment'
[17:40:56.529] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevSCPSBigEndian'
[17:40:56.529] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevSCPSPacketSize'
[17:40:56.529] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevSCPD'
[17:40:56.529] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevSCDA'
[17:40:56.539] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevSCSP'
[17:40:56.539] dY., genicam> [Gc::set_default_node_data] Add 'ArvGevSCCFGMultipart'
[17:40:56.544] dY., genicam> [Gc::set_default_node_data] Add 'GevSCPD'
[17:40:56.549] dY., device> [GvDevice::new] Device endianness = big
[17:40:56.549] dY., device> [GvDevice::new] Packet resend     = yes
[17:40:56.549] dY., device> [GvDevice::new] Write memory      = yes
[17:40:56.549] dY., device> [GvDevice::new] Legacy endianness handling = yes
[17:40:57.586] dY., device> [GvDevice::create_stream] Number of stream channels = 1
[17:40:57.589] dY., device> [GvDevice::auto_packet_size] No GevSCPSFireTestPacket feature found
[17:40:57.595] dY., stream> [GvStream::stream_new] Stream channel = 0
[17:40:57.603] dY., stream> [GvStream::stream_new] Packet size = 8228 byte(s)
[17:40:57.613] dY., stream> [GvStream::stream_new] Destination stream port = 54144
[17:40:57.616] dY., stream> [GvStream::stream_new] Source stream port = 10010
[17:40:57.618] dY., misc> Thread made realtime!
[17:40:57.619] dY., stream> [GvStream::loop] Standard socket method
[17:40:57.713] dY., stream-thread> [GvStream::missing_packet_check] Maximum number of requests reached at dt = 37476, n_packet_requests = 512 (514 packets/frame), frame_id = 1
[17:40:57.718] dY++ stream-thread> [GvStream::loop] receive_messages failed: A non-blocking socket operation could not be completed immediately.
[17:40:57.751] dY., stream-thread> [GvStream::missing_packet_check] Maximum number of requests reached at dt = 37892, n_packet_requests = 512 (514 packets/frame), frame_id = 2
[17:40:57.756] dY++ stream-thread> [GvStream::loop] receive_messages failed: A non-blocking socket operation could not be completed immediately.
[17:40:57.789] dY., stream-thread> [GvStream::missing_packet_check] Maximum number of requests reached at dt = 38097, n_packet_requests = 512 (514 packets/frame), frame_id = 3
[17:40:57.795] dY++ stream-thread> [GvStream::loop] receive_messages failed: A non-blocking socket operation could not be completed immediately.
[17:40:57.819] dY++ stream-thread> [GvStream::check_frame_completion] Timeout for frame 1 at dt = 143016
[17:40:57.827] dY., stream-thread> [GvStream::missing_packet_check] Maximum number of requests reached at dt = 37752, n_packet_requests = 512 (514 packets/frame), frame_id = 4
[17:40:57.832] dY++ stream-thread> [GvStream::loop] receive_messages failed: A non-blocking socket operation could not be completed immediately.
[17:40:57.856] dY++ stream-thread> [GvStream::check_frame_completion] Timeout for frame 2 at dt = 142802
[17:40:57.865] dY., stream-thread> [GvStream::missing_packet_check] Maximum number of requests reached at dt = 37950, n_packet_requests = 512 (514 packets/frame), frame_id = 5
[17:40:57.869] dY++ stream-thread> [GvStream::loop] receive_messages failed: A non-blocking socket operation could not be completed immediately.
[17:40:57.892] dY++ stream-thread> [GvStream::check_frame_completion] Timeout for frame 3 at dt = 140537
[17:40:57.903] dY., stream-thread> [GvStream::missing_packet_check] Maximum number of requests reached at dt = 38004, n_packet_requests = 512 (514 packets/frame), frame_id = 6
[17:40:57.909] dY++ stream-thread> [GvStream::loop] receive_messages failed: A non-blocking socket operation could not be completed immediately.
[17:40:57.934] dY++ stream-thread> [GvStream::check_frame_completion] Timeout for frame 4 at dt = 144520
[17:40:57.941] dY., stream-thread> [GvStream::missing_packet_check] Maximum number of requests reached at dt = 37844, n_packet_requests = 512 (514 packets/frame), frame_id = 7
[17:40:57.946] dY++ stream-thread> [GvStream::loop] receive_messages failed: A non-blocking socket operation could not be completed immediately.
...
[17:41:00.060] dY++ stream-thread> [GvStream::check_frame_completion] Timeout for frame 60 at dt = 145779
[17:41:00.065] dY., stream-thread> [GvStream::missing_packet_check] Maximum number of requests reached at dt = 37910, n_packet_requests = 512 (514 packets/frame), frame_id = 63
[17:41:00.072] dY++ stream-thread> [GvStream::loop] receive_messages failed: A non-blocking socket operation could not be completed immediately.
[17:41:00.099] dY++ stream-thread> [GvStream::check_frame_completion] Timeout for frame 61 at dt = 146824
[17:41:00.104] dY., stream-thread> [GvStream::missing_packet_check] Maximum number of requests reached at dt = 37914, n_packet_requests = 512 (514 packets/frame), frame_id = 64
[17:41:00.111] dY++ stream-thread> [GvStream::loop] receive_messages failed: A non-blocking socket operation could not be completed immediately.
[17:41:00.136] dY++ stream-thread> [GvStream::check_frame_completion] Timeout for frame 62 at dt = 146128
[17:41:00.142] dY., stream-thread> [GvStream::missing_packet_check] Maximum number of requests reached at dt = 37945, n_packet_requests = 512 (514 packets/frame), frame_id = 65
[17:41:00.150] dY++ stream-thread> [GvStream::loop] receive_messages failed: A non-blocking socket operation could not be completed immediately.
[17:41:00.173] dY++ stream-thread> [GvStream::check_frame_completion] Timeout for frame 63 at dt = 145205
[17:41:00.179] dY., stream-thread> [GvStream::missing_packet_check] Maximum number of requests reached at dt = 37918, n_packet_requests = 512 (514 packets/frame), frame_id = 66
[17:41:00.184] dY++ stream-thread> [GvStream::loop] receive_messages failed: A non-blocking socket operation could not be completed immediately.
[17:41:00.209] dY++ stream-thread> [GvStream::check_frame_completion] Timeout for frame 64 at dt = 142329
[17:41:00.218] dY., stream-thread> [GvStream::missing_packet_check] Maximum number of requests reached at dt = 38092, n_packet_requests = 512 (514 packets/frame), frame_id = 67
[17:41:00.225] dY++ stream-thread> [GvStream::loop] receive_messages failed: A non-blocking socket operation could not be completed immediately.
[17:41:00.251] dY++ stream-thread> [GvStream::check_frame_completion] Timeout for frame 65 at dt = 146638
[17:41:00.251] dY., stream-thread> [GvStream::missing_packet_check] Maximum number of requests reached at dt = 37874, n_packet_requests = 512 (514 packets/frame), frame_id = 68
[17:41:00.264] dY++ stream-thread> [GvStream::loop] receive_messages failed: A non-blocking socket operation could not be completed immediately.
[17:41:00.281] dY++ stream-thread> [GvStream::check_frame_completion] Timeout for frame 66 at dt = 139621
[17:41:00.294] dY., stream>     bins    ;frame_retent; packet_time;inter_packet
                                    0;           0;          69;           0
                                 2000;           0;           0;           0
                                 4000;           0;           0;           0
                                 6000;           0;           0;           0
                                 8000;           0;           0;           0
                                10000;           0;           0;           0
                                12000;           0;           0;           0
                                14000;           0;           0;           0
                                16000;           0;           0;           0
                                18000;           0;           0;           0
                                20000;           0;           0;           0
                                22000;           0;           0;           0
                                24000;           0;           0;           0
                                26000;           0;           0;           0
                                28000;           0;           0;           0
                                30000;           1;           0;           0
                                32000;           0;           1;           1
                                34000;           0;           3;           3
                                36000;           0;          37;          37
                                38000;           0;          23;          23
                                40000;           0;           2;           2
                                42000;           0;           2;           2
                                44000;           0;           0;           0
                                46000;           0;           0;           0
                                48000;           0;           0;           0
                                50000;           0;           0;           0
                                52000;           0;           0;           0
                                54000;           0;           0;           0
                                56000;           0;           0;           0
                                58000;           0;           0;           0
                                60000;           0;           0;           0
                                62000;           0;           0;           0
                                64000;           0;           0;           0
                                66000;           0;           0;           0
                                68000;           1;           0;           0
                                70000;           0;           0;           0
                                72000;           0;           0;           0
                                74000;           0;           0;           0
                                76000;           0;           0;           0
                                78000;           0;           0;           0
                                80000;           0;           0;           0
                                82000;           0;           0;           0
                                84000;           0;           0;           0
                                86000;           0;           0;           0
                                88000;           0;           0;           0
                                90000;           0;           0;           0
                                92000;           0;           0;           0
                                94000;           0;           0;           0
                                96000;           0;           0;           0
                                98000;           0;           0;           0
                               100000;           0;           0;           0
                               102000;           0;           0;           0
                               104000;           0;           0;           0
                               106000;           1;           0;           0
                               108000;           0;           0;           0
                               110000;           0;           0;           0
                               112000;           0;           0;           0
                               114000;           0;           0;           0
                               116000;           0;           0;           0
                               118000;           0;           0;           0
                               120000;           0;           0;           0
                               122000;           0;           0;           0
                               124000;           0;           0;           0
                               126000;           0;           0;           0
                               128000;           0;           0;           0
                               130000;           0;           0;           0
                               132000;           0;           0;           0
                               134000;           0;           0;           0
                               136000;           1;           0;           0
                               138000;           6;           0;           0
                               140000;           5;           0;           0
                               142000;          15;           0;           0
                               144000;           8;           0;           0
                               146000;          10;           0;           0
                               148000;           5;           0;           0
                               150000;          15;           0;           0
                               152000;           1;           0;           0
                         -------------
                         >=    154000;           0;           0;           0
                         <          0;           0;           0;           0
                         min         :       30801;           0;       33449
                         max         :      152073;       42748;       42748
                         last max at :          17;          49;          24
                         counter     :          69:         137:          68
[17:41:00.454] dY., stream> [GvStream::finalize] n_completed_buffers    = 0
[17:41:00.457] dY., stream> [GvStream::finalize] n_failures             = 66
[17:41:00.459] dY., stream> [GvStream::finalize] n_underruns            = 0
[17:41:00.461] dY., stream> [GvStream::finalize] n_timeouts             = 66
[17:41:00.463] dY., stream> [GvStream::finalize] n_aborted              = 3
[17:41:00.466] dY., stream> [GvStream::finalize] n_missing_frames       = 0
[17:41:00.468] dY., stream> [GvStream::finalize] n_size_mismatch_errors = 0
[17:41:00.470] dY., stream> [GvStream::finalize] n_received_packets     = 137
[17:41:00.472] dY., stream> [GvStream::finalize] n_missing_packets      = 33858
[17:41:00.475] dY., stream> [GvStream::finalize] n_error_packets        = 0
[17:41:00.478] dY., stream> [GvStream::finalize] n_ignored_packets      = 0
[17:41:00.480] dY., stream> [GvStream::finalize] n_resend_requests      = 0
[17:41:00.483] dY., stream> [GvStream::finalize] n_resent_packets       = 0
[17:41:00.485] dY., stream> [GvStream::finalize] n_resend_ratio_reached = 68
[17:41:00.487] dY., stream> [GvStream::finalize] n_resend_disabled      = 0
[17:41:00.489] dY., stream> [GvStream::finalize] n_duplicated_packets   = 0
[17:41:00.491] dY., stream> [GvStream::finalize] n_transferred_bytes    = 4124
[17:41:00.494] dY., stream> [GvStream::finalize] n_ignored_bytes        = 0
[17:41:00.496] dY., stream> [Stream::finalize] Flush 0 buffer[s] in input queue
[17:41:00.500] dY., stream> [Stream::finalize] Flush 10 buffer[s] in output queue

For arv-camera-test,  the debug output looks like: 
$ arv-camera-test-0.8.exe -d all
Looking for the first available camera
[17:36:28.187] 🅸 interface> Found 0 USB3Vision device (among 7 USB devices)
[17:36:28.196] 🅸 interface> [GvDiscoverSocket::new] Add interface 172.29.166.83 (172.29.191.255)
[17:36:28.198] 🅸 interface> [GvDiscoverSocket::new] Add interface 169.254.212.45 (169.254.255.255)
[17:36:28.198] 🅸 interface> [GvDiscoverSocket::new] Add interface 10.155.35.72 (10.155.255.255)
[17:36:28.200] 🅸 interface> [GvDiscoverSocket::new] Add interface 127.0.0.1 (127.255.255.255)
[17:36:28.200] 🆆 interface> [ArvGVInterface::send_discover_packet] Error sending packet using local broadcast: Error sending message: A socket operation was attempted to an unreachable network.
[17:36:28.200] 🆆 interface> [ArvGVInterface::send_discover_packet] Error sending packet using directed broadcast: Error sending message: A socket operation was attempted to an unreachable network.
[17:36:28.204] 🅸 interface> [GvInterface::discovery] Device 'Allied Vision Technologies-GT4400 (02-2696D)-02-2696D-07028' found (interface 169.254.212.45) user_id '' - MAC '00:0f:31:03:81:47'
[17:36:28.204] 🅸 device> [GvDevice::new] Interface address = 169.254.212.45
[17:36:28.204] 🅸 device> [GvDevice::new] Device address = 169.254.22.17
[17:36:28.207] 🅸 device> [GvDevice::load_genicam] xml url = 'Local:prosilica_022696D_1.54.xml;100000;461ED' at 0x200
[17:36:28.207] 🅸 device> [GvDevice::load_genicam] Xml address = 0x100000 - size = 0x461ed - prosilica_022696D_1.54.xml
[17:36:28.773] 🅸 genicam> [Gc::set_default_node_data] Add 'DeviceVersion'
[17:36:28.773] 🅸 genicam> [Gc::set_default_node_data] Add 'DeviceManufacturerInfo'
[17:36:28.773] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevCurrentIPConfigurationLLA'
[17:36:28.773] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevCurrentIPConfigurationDHCP'
[17:36:28.773] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevCurrentIPConfigurationPersistentIP'
[17:36:28.773] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevCurrentIPAddress'
[17:36:28.773] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevCurrentSubnetMask'
[17:36:28.773] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevCurrentDefaultGateway'
[17:36:28.775] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevNumberOfNetworkInterfaces'
[17:36:28.775] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevPersistentIPAddress'
[17:36:28.775] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevPersistentSubnetMask'
[17:36:28.775] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevPersistentDefaultGateway'
[17:36:28.775] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevStreamChannelCount'
[17:36:28.777] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevStreamChannelSelector'
[17:36:28.777] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevSCPAddrCalc'
[17:36:28.777] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevTimestampTickFrequency'
[17:36:28.777] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevSCPHostPort'
[17:36:28.777] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevSCPSFireTestPacket'
[17:36:28.779] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevSCPSDoNotFragment'
[17:36:28.779] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevSCPSBigEndian'
[17:36:28.779] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevSCPSPacketSize'
[17:36:28.779] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevSCPD'
[17:36:28.780] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevSCDA'
[17:36:28.780] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevSCSP'
[17:36:28.780] 🅸 genicam> [Gc::set_default_node_data] Add 'ArvGevSCCFGMultipart'
[17:36:28.780] 🅸 genicam> [Gc::set_default_node_data] Add 'GevSCPD'
[17:36:28.785] 🅸 device> [GvDevice::new] Device endianness = big
[17:36:28.785] 🅸 device> [GvDevice::new] Packet resend     = yes
[17:36:28.785] 🅸 device> [GvDevice::new] Write memory      = yes
[17:36:28.785] 🅸 device> [GvDevice::new] Legacy endianness handling = yes
vendor name            = Allied Vision Technologies
model name             = GT4400
device serial number   = 02-2696D-07028
image width            = 4432
image height           = 4436
horizontal binning     = 1
vertical binning       = 1
exposure               = 15000 ┬╡s
gain                   = 0 dB
payload                = 19660352 bytes
gv n_stream channels   = 1
gv current channel     = 0
gv packet delay        = 0 ns
[17:36:28.824] 🅸 device> [GvDevice::create_stream] Number of stream channels = 1
[17:36:28.824] 🅸 device> [GvDevice::auto_packet_size] No GevSCPSFireTestPacket feature found
[17:36:28.824] 🅸 stream> [GvStream::stream_new] Stream channel = 0
[17:36:28.828] 🅸 stream> [GvStream::stream_new] Packet size = 2030 byte(s)
[17:36:28.834] 🅸 stream> [GvStream::stream_new] Destination stream port = 50716
[17:36:28.834] 🅸 stream> [GvStream::stream_new] Source stream port = 10010
[17:36:28.834] 🅸 stream> [GvStream::loop] Standard socket method
gv packet size         = 2030 bytes
  0 frame/s  -       0 MiB/s
[17:36:30.294] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:30.294] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:30.799] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:30.799] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:31.303] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:31.303] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:31.809] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:31.809] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:32.310] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:32.310] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:32.834] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:32.834] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:33.344] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:33.344] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:33.858] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:33.883] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
[17:36:35.415] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:35.415] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:35.915] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:35.915] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:36.423] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:36.423] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:36.928] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:36.928] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:38.446] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:38.446] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:38.958] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:38.958] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:40.475] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:40.475] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
[17:36:44.028] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:44.028] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:45.535] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:45.535] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
[17:36:47.042] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:47.042] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
[17:36:49.571] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:49.571] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:50.082] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:50.082] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:50.597] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:50.598] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:51.105] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:51.106] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:51.608] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:51.609] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:52.127] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:52.127] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:52.629] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:52.629] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:53.137] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:53.137] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:53.639] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:53.639] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
[17:36:55.144] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:55.145] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:55.645] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:55.645] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:56.159] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:56.159] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:56.666] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:56.666] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:57.168] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:57.168] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:57.688] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:57.688] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:58.189] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:58.189] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:36:59.715] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:36:59.715] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:37:00.228] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:00.228] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:37:01.750] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:01.750] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
[17:37:05.570] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:05.571] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:37:06.107] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:06.108] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
 [17:37:07.725] 🆆 device>  0 frame/s[GvDevice::read_register] Ack reception timeout
  -       0 MiB/s
[17:37:07.725] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:08.265] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:08.267] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
[17:37:11.615] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:11.619] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:37:12.226] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:12.226] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 fra[17:37:12.765] 🆆 device> [GvDevice::read_register] Ack reception timeout
me/s  -       0 MiB/s
[17:37:12.768] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:13.667] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:13.668] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:14.567] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:14.569] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:37:16.868] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:16.871] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:37:18.300] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:18.310] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:37:20.827] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:20.833] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:37:22.306] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:22.313] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[17:37:23.926] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:23.933] 🆆 device> [GvDevice::read_register] Ack reception timeout
[17:37:23.933] 🆆 device> [GvDevice::Heartbeat] Control access lost
Control lost
  0 frame/s  -       0 MiB/s
n_completed_buffers    = 0
n_failures             = 0
n_underruns            = 0
n_timeouts             = 0
n_aborted              = 0
n_missing_frames       = 0
n_size_mismatch_errors = 0
n_received_packets     = 0
n_missing_packets      = 0
n_error_packets        = 0
n_ignored_packets      = 0
n_resend_requests      = 0
n_resent_packets       = 0
n_resend_ratio_reached = 0
n_resend_disabled      = 0
n_duplicated_packets   = 0
n_transferred_bytes    = 0
n_ignored_bytes        = 0
[17:37:24.453] 🆆 device> [GvDevice::write_register] Ack reception timeout
[17:37:24.453] 🆆 device> [GvDevice::write_register] Ack reception timeout
[17:37:24.457] 🅸 stream>     bins    ;frame_retent; packet_time;inter_packet
                                    0;           0;           0;           0
                         -------------
                         >=      2000;           0;           0;           0
                         <          0;           0;           0;           0
                         min         :         n/a;         n/a;         n/a
                         max         :         n/a;         n/a;         n/a
                         last max at :           0;           0;           0
                         counter     :           0:           0:           0
[17:37:24.457] 🅸 stream> [GvStream::finalize] n_completed_buffers    = 0
[17:37:24.457] 🅸 stream> [GvStream::finalize] n_failures             = 0
[17:37:24.457] 🅸 stream> [GvStream::finalize] n_underruns            = 0
[17:37:24.457] 🅸 stream> [GvStream::finalize] n_timeouts             = 0
[17:37:24.457] 🅸 stream> [GvStream::finalize] n_aborted              = 0
[17:37:24.457] 🅸 stream> [GvStream::finalize] n_missing_frames       = 0
[17:37:24.459] 🅸 stream> [GvStream::finalize] n_size_mismatch_errors = 0
[17:37:24.459] 🅸 stream> [GvStream::finalize] n_received_packets     = 0
[17:37:24.459] 🅸 stream> [GvStream::finalize] n_missing_packets      = 0
[17:37:24.459] 🅸 stream> [GvStream::finalize] n_error_packets        = 0
[17:37:24.459] 🅸 stream> [GvStream::finalize] n_ignored_packets      = 0
[17:37:24.459] 🅸 stream> [GvStream::finalize] n_resend_requests      = 0
[17:37:24.459] 🅸 stream> [GvStream::finalize] n_resent_packets       = 0
[17:37:24.459] 🅸 stream> [GvStream::finalize] n_resend_ratio_reached = 0
[17:37:24.459] 🅸 stream> [GvStream::finalize] n_resend_disabled      = 0
[17:37:24.461] 🅸 stream> [GvStream::finalize] n_duplicated_packets   = 0
[17:37:24.461] 🅸 stream> [GvStream::finalize] n_transferred_bytes    = 0
[17:37:24.461] 🅸 stream> [GvStream::finalize] n_ignored_bytes        = 0
[17:37:24.461] 🅸 stream> [Stream::finalize] Flush 50 buffer[s] in input queue
[17:37:24.461] 🅸 stream> [Stream::finalize] Flush 0 buffer[s] in output queue

[EmmanuelP]

Hi,

[17:40:57.603] dY., stream> [GvStream::stream_new] Packet size = 8228 byte(s)

The packet size is probably too big, aravis is only receiving header packets. And without the test packet feature, it can not fix it:

[17:40:57.589] dY., device> [GvDevice::auto_packet_size] No GevSCPSFireTestPacket feature found

Try to reduce the packet size. For example:

arv-tool-0.8 control GevSCPSPacketSize=1400

[hanshinlee77]

Thank you for the pointer. I will give it a try.

One side note is that the cameras were running with Jumbo frame enabled on an Ethernet port.
Shouldn't this have been enough to deal with the packet size?

[EmmanuelP]

If the camera is connected directly to the machine, that should work, yes. But if the connection goes through one or more switches, you have to enable jumbo frames on each of them.

[hanshinlee77]

Hello, it did not work.
I noticed that the frame rate came out to be all 0 frames/sec.
At the end of the test, it said it lost control and could not stop the channel due to access denied. I presume it is because of the lost control. Also noticed is this message "[GvDevice::read_register] Ack reception timeout". Not sure what this means.

In another thread, I saw that aravis uses heartbeat and if a camera does not have that feature it won't work with that camera? Did I understand it right? Not sure if this is related though..

By the way, the camera was directly connected to a laptop.

Thanks,

$ arv-camera-test-0.8.exe -d all
Looking for the first available camera
[10:06:12.374] 🅸 interface> Found 0 USB3Vision device (among 4 USB devices)
[10:06:13.301] 🅸 device> [GvDevice::new] Device endianness = big
[10:06:13.301] 🅸 device> [GvDevice::new] Packet resend     = yes
[10:06:13.301] 🅸 device> [GvDevice::new] Write memory      = yes
[10:06:13.301] 🅸 device> [GvDevice::new] Legacy endianness handling = yes
vendor name            = Allied Vision Technologies
model name             = Manta_G-201B-30
device serial number   = 50-0503336107
image width            = 1624
image height           = 1234
horizontal binning     = 1
vertical binning       = 1
exposure               = 150000 ┬╡s
gain                   = 0 dB
payload                = 2004016 bytes
gv n_stream channels   = 1
gv current channel     = 0
gv packet delay        = 0 ns
[10:06:13.367] 🅸 device> [GvDevice::create_stream] Number of stream channels = 1
[10:06:13.368] 🅸 device> [GvDevice::auto_packet_size] No GevSCPSFireTestPacket feature found
[10:06:13.370] 🅸 stream> [GvStream::stream_new] Stream channel = 0
[10:06:13.378] 🅸 stream> [GvStream::stream_new] Packet size = 1500 byte(s)
[10:06:13.389] 🅸 stream> [GvStream::stream_new] Destination stream port = 52062
[10:06:13.389] 🅸 stream> [GvStream::stream_new] Source stream port = 10010
[10:06:13.390] 🅸 stream> [GvStream::loop] Standard socket method
gv packet size         = 1500 bytes
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
[10:06:26.442] 🆆 device> [GvDevice::read_register] Ack reception timeout
[10:06:26.442] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
[10:06:38.307] 🆆 device> [GvDevice::read_register] Ack reception timeout
[10:06:38.308] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
  0 frame/s  -       0 MiB/s
[10:06:49.094] 🆆 device> [GvDevice::read_register] Ack reception timeout
[10:06:49.094] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[10:06:49.596] 🆆 device> [GvDevice::read_register] Ack reception timeout
[10:06:49.596] 🆆 device> [GvDevice::read_register] Ack reception timeout
[10:06:50.099] 🆆 device> [GvDevice::read_register] Ack reception timeout
[10:06:50.099] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[10:06:50.601] 🆆 device> [GvDevice::read_register] Ack reception timeout
[10:06:50.602] 🆆 device> [GvDevice::read_register] Ack reception timeout
  0 frame/s  -       0 MiB/s
[10:06:51.856] 🆆 device> [GvDevice::Heartbeat] Control access lost
Control lost
  0 frame/s  -       0 MiB/s
n_completed_buffers    = 0
n_failures             = 0
n_underruns            = 0
n_timeouts             = 0
n_aborted              = 0
n_missing_frames       = 0
n_size_mismatch_errors = 0
n_received_packets     = 0
n_missing_packets      = 0
n_error_packets        = 0
n_ignored_packets      = 0
n_resend_requests      = 0
n_resent_packets       = 0
n_resend_ratio_reached = 0
n_resend_disabled      = 0
n_duplicated_packets   = 0
n_transferred_bytes    = 0
n_ignored_bytes        = 0
[10:06:52.593] 🆆 stream> Failed to stop stream channel 0 (GigEVision write_register error (access-denied))
[10:06:52.594] 🅸 stream>     bins    ;frame_retent; packet_time;inter_packet
                                    0;           0;           0;           0
                         -------------
                         >=      2000;           0;           0;           0
                         <          0;           0;           0;           0
                         min         :         n/a;         n/a;         n/a
                         max         :         n/a;         n/a;         n/a
                         last max at :           0;           0;           0
                         counter     :           0:           0:           0
[10:06:52.600] 🅸 stream> [GvStream::finalize] n_completed_buffers    = 0
[10:06:52.601] 🅸 stream> [GvStream::finalize] n_failures             = 0
[10:06:52.601] 🅸 stream> [GvStream::finalize] n_underruns            = 0
[10:06:52.602] 🅸 stream> [GvStream::finalize] n_timeouts             = 0
[10:06:52.603] 🅸 stream> [GvStream::finalize] n_aborted              = 0
[10:06:52.604] 🅸 stream> [GvStream::finalize] n_missing_frames       = 0
[10:06:52.605] 🅸 stream> [GvStream::finalize] n_size_mismatch_errors = 0
[10:06:52.606] 🅸 stream> [GvStream::finalize] n_received_packets     = 0
[10:06:52.607] 🅸 stream> [GvStream::finalize] n_missing_packets      = 0
[10:06:52.608] 🅸 stream> [GvStream::finalize] n_error_packets        = 0
[10:06:52.608] 🅸 stream> [GvStream::finalize] n_ignored_packets      = 0
[10:06:52.609] 🅸 stream> [GvStream::finalize] n_resend_requests      = 0
[10:06:52.610] 🅸 stream> [GvStream::finalize] n_resent_packets       = 0
[10:06:52.610] 🅸 stream> [GvStream::finalize] n_resend_ratio_reached = 0
[10:06:52.611] 🅸 stream> [GvStream::finalize] n_resend_disabled      = 0
[10:06:52.612] 🅸 stream> [GvStream::finalize] n_duplicated_packets   = 0
[10:06:52.613] 🅸 stream> [GvStream::finalize] n_transferred_bytes    = 0
[10:06:52.614] 🅸 stream> [GvStream::finalize] n_ignored_bytes        = 0
[10:06:52.615] 🅸 stream> [Stream::finalize] Flush 50 buffer[s] in input queue
[10:06:52.616] 🅸 stream> [Stream::finalize] Flush 0 buffer[s] in output queue

[EmmanuelP]

@eudoxos , any idea ?

[eudoxos]

@hanshinlee77

Run with wireshark, you should see the incoming packets reaching your NIC.

1. If they come to NIC but not to aravis, then check that you don't have some vendor camera drivers (packet filters, don't remember what it's called under Windows now) installed on your NIC, they could be interfering. Or if there is some address translation (like containers) taking place.
2. If they don't come to the NIC at all, it is routing issue (or camera issue, but that is unlikely, right?). In which case check what GevSCDA and GevSCPHostPort is set to (you should see this in wireshark, and IIRC also in aravis log), because that is the host/port the camera will be sending the stream to.

I'd try with fixed IP first, not with LLA, and connecting the camera directly (with a 1:1 cable) to the NIC, just to avoid any interference. And without jumbo packets at first, keeping the packet size low.

[hanshinlee77]

@eudoxos
Thank you for the suggestions. It seemed the incoming packets did reach the NIC the camera (AVT Manta G-419) was plugged in (on a fixed IP address). I had three GigE filter drivers installed, one for FLIR, another for AVT, and another for Teledyne cameras. I have disabled their drivers on the NIC. arv-viewer-0.8 still behaved the same. arv-camera-test-0.8 registered 0 frames/sec.

It was interesting though that the vendor supplied viewers all worked (i.e. connecting/operating the camera) even disabling the vendors' drivers on the same set up. So, it means that the filter drivers weren't really disabled. With very limited knowledge about how these things work, I am not sure how to interpret this.

Anyhow, this isn't a huge issue for me as I will be getting a new linux laptop where I already know aravis works with this particular camera. I was just hoping that this would work on windows machines since we also have several windows laptops people use in projects for operating cameras like that. Currently everyone uses vendor supplied viewers which works ok but not great. It is hard to force them to switch to linux machines.

This might just be specific to my windows laptop and perhaps I could try aravis on others' windows machines to see if this issue replicates.

Thanks,

[EmmanuelP]

One aspect of this issue that is puzzling is the entire connection to the camera seems to be lost once streaming starts, as you can see by the Ack reception timeout messages and the final Failed to stop stream channel 0 (GigEVision write_register error (access-denied)). That could indicate an issue during the stream setup.

Did you check the destination IP/port in the packet emitted by the camera correspond to the host parameter ?

[hanshinlee77]

@EmmanuelP
No I couldn't get around to that part but will try that soon.

Two output lines from my previous runs show Destination stream port and Source stream port.

[17:36:28.834] 🅸 stream> [GvStream::stream_new] Destination stream port = 50716
[17:36:28.834] 🅸 stream> [GvStream::stream_new] Source stream port = 10010

Are these the kinds of ports that I should look for in wireshark?

[EmmanuelP]

Yes for the stream packets. It is alos interesting to see if the control packets are acknowledged by the camera, and with the same port as the one used for the camera configuration.

[hanshinlee77]

Hello, I tried to check if the packets are going from the source to the destination ports. Here is a screenshot of the wireshark output.
The source/destination ports were 10010 / 54794. I wasn't sure whether it was TCP or UDP but saw "UDP" in one of the output lines. I addeded then the UDP port filters in addition to the ip filters. Hope I got this correct.

[EmmanuelP]

Thanks. Unfortunately, the screenshot is not really helpful. Could you attach the capture file and the console output of:

./src/arv-camera-test-0.10 -w 64 -h 64 --duration=2 -d all:3

In order to reduce the console output size, you can remove the first part related to the Genicam data retrieval.