GoogleChromeLabs / chromeos_smart_card_connector

Smart Card Connector App for Chrome OS
https://chrome.google.com/webstore/detail/smart-card-connector/khpfeaanjngmcnplbdlpegiifgpfgdco
Apache License 2.0
133 stars 50 forks source link

Test flakiness on "Check "is_closed_" failed" #1096

Open emaxx-google opened 9 months ago

emaxx-google commented 9 months ago

Sample failure:

[ RUN      ] SmartCardConnectorApplicationTest.ShutdownWithActiveClient
[DEBUG] Performing services initialization...
[DEBUG] [PC/SC-Lite NaCl port] Initialization...
[DEBUG] [PC/SC-Lite NaCl port] Setting up PC/SC-Lite logging...
[INFO] ../../../../../third_party/pcsc-lite/src/src/debuglog.c:390:DebugLogSetLevel() debug level=debug
[INFO] ../../../../../third_party/pcsc-lite/src/src/debuglog.c:411:DebugLogSetCategory() Debug options: APDU
[DEBUG] [PC/SC-Lite NaCl port] PC/SC-Lite logging was set up.
[DEBUG] [PC/SC-Lite NaCl port] Allocating reader structures...
[DEBUG] [PC/SC-Lite NaCl port] Reader structures allocation finished with the following result: "Command successful.".
[DEBUG] [PC/SC-Lite NaCl port] Performing initial hot plug drivers search...
[DEBUG] [emulated IPC] A blocking in-memory file 1 was created
[DEBUG] [emulated IPC] A blocking in-memory file 2 was created
[DEBUG] [emulated IPC] The in-memory file 1 connected to the in-memory file 2
[DEBUG] [emulated IPC] The in-memory file 2 connected to the in-memory file 1
[DEBUG] [libusb] libusb_init#196(ctx=NULL): called...
[DEBUG] [libusb] libusb_init#196: returning "LIBUSB_SUCCESS" [0x00000000]
[DEBUG] [libusb] libusb_get_device_list#197(ctx=libusb_context<DEFAULT>, list=0xF3AFEFE0): called...
[DEBUG] [libusb] libusb_get_device_list#197: returning 0, *list=0xEF384970([])
[DEBUG] [libusb] libusb_free_device_list#198(list=0xEF384970([]), unref_devices=1): called...
[DEBUG] [libusb] libusb_free_device_list#198: returning
[DEBUG] [emulated IPC] A blocking in-memory file 3 was created
[DEBUG] [emulated IPC] A blocking in-memory file 4 was created
[DEBUG] [emulated IPC] The in-memory file 3 connected to the in-memory file 4
[DEBUG] [emulated IPC] The in-memory file 4 connected to the in-memory file 3
[DEBUG] [emulated IPC] The in-memory file 1 was closed
[INFO] ../../../../../third_party/pcsc-lite/src/src/hotplug_libusb.c:492:HPEstablishUSBNotifications() Driver ifd-ccid.bundle does not support IFD_GENERATE_HOTPLUG. Using active polling instead.
[DEBUG] [emulated IPC] The in-memory file 2 was closed
[DEBUG] [emulated IPC] The in-memory file 1 was destroyed
[DEBUG] [emulated IPC] The in-memory file 2 was destroyed
[DEBUG] [PC/SC-Lite NaCl port] Initial hot plug drivers search finished with the following result code: 0.
[DEBUG] [PC/SC-Lite NaCl port] Registering for hot plug events...
[DEBUG] [PC/SC-Lite NaCl port] Registering for hot plug events finished with the following result code: 0.
[DEBUG] [PC/SC-Lite NaCl port] Allocating client structures...
[DEBUG] [PC/SC-Lite NaCl port] Client structures allocation finished with the following result code: 1...
[DEBUG] [PC/SC-Lite NaCl port] Waiting for the readers initialization...
[DEBUG] [PC/SC-Lite NaCl port] Waiting for the readers initialization finished.
[DEBUG] [PC/SC-Lite NaCl port] Starting PC/SC-Lite daemon thread...
[DEBUG] [PC/SC-Lite NaCl port] PC/SC-Lite daemon thread has started.
[DEBUG] [PC/SC-Lite NaCl port] Initialization successfully finished.
[DEBUG] All services are successfully initialized, posting ready message...
[DEBUG] [PC/SC from foo (id 1234)] Created client handler
[DEBUG] [PC/SC-Lite clients manager] Created a new client handler for foo (handler id 1234)
[DEBUG] [PC/SC-Lite NaCl port] [daemon thread] Waiting for the new connected clients...
[DEBUG] [PC/SC from foo (id 1234)] Started processing request SCardEstablishContext(0x02,null,null)...
[INFO] [PC/SC from foo (id 1234)] SCardEstablishContext#199(dwScope=SCARD_SCOPE_SYSTEM, pvReserved1=null, pvReserved2=null): called...
[DEBUG] [emulated IPC] A non-blocking in-memory file 5 was created
[DEBUG] [emulated IPC] A non-blocking in-memory file 6 was created
[DEBUG] [emulated IPC] The in-memory file 5 connected to the in-memory file 6
[DEBUG] [emulated IPC] The in-memory file 6 connected to the in-memory file 5
[DEBUG] [PC/SC-Lite NaCl port] [daemon thread] A new client was connected, starting a handler thread...
[DEBUG] [PC/SC-Lite NaCl port] [daemon thread] Waiting for the new connected clients...
[INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:341:ContextThread() Authorized PC/SC client
[INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:345:ContextThread() Thread is started: dwClientID=6, threadContext @0xf412b8b0
[INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: CMD_VERSION from client 6
[INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:375:ContextThread() Client is protocol version 4:4
[INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:397:ContextThread() CMD_VERSION for client 6
[INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_clnt.c:610:SCardEstablishContextTH() Server is protocol version 4:4
[INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: ESTABLISH_CONTEXT from client 6
[INFO] ../../../../../third_party/pcsc-lite/src/src/winscard.c:210:SCardEstablishContextServer() Establishing Context: 0x776F9744
[INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:466:ContextThread() ESTABLISH_CONTEXT for client 6
[INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_clnt.c:3254:SCardAddContext() Allocating new SCONTEXTMAP @0xf4130660
[INFO] [PC/SC from foo (id 1234)] SCardEstablishContext#199: returning 0x00000000 ["Command successful."], hContext=0x776F9744
[DEBUG] [PC/SC from foo (id 1234)] Request SCardEstablishContext finished successfully with the following results: [0x00, 0x776F9744]
[DEBUG] [PC/SC-Lite clients manager] Deleted all 1 client handlers
[DEBUG] [PC/SC-Lite NaCl port] Shutting down the PC/SC-Lite daemon thread...
[DEBUG] [PC/SC-Lite NaCl port] [daemon thread] Shutting down...
[DEBUG] [PC/SC from foo (id 1234)] Performing forced cleanup: canceling all pending blocking requests for left context 0x776F9744
[INFO] ../../../../../third_party/pcsc-lite/src/src/hotplug_libusb.c:501:HPEstablishUSBNotifications() Polling forced every 1 second(s)
[DEBUG] [libusb] libusb_exit#200(ctx=libusb_context<DEFAULT>): called...
[DEBUG] [libusb] libusb_exit#200: returning
[INFO] ../../../../../third_party/pcsc-lite/src/src/hotplug_libusb.c:544:HPEstablishUSBNotifications() Hotplug stopped
[DEBUG] [emulated IPC] The in-memory file 3 was closed
[DEBUG] [emulated IPC] The in-memory file 4 was closed
[INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1374:RFCleanupReaders() entering cleaning function
[INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:156:ContextsDeinitialize() remaining threads: 1
[DEBUG] [PC/SC-Lite NaCl port] The PC/SC-Lite daemon thread shut down.
[DEBUG] [emulated IPC] The in-memory file 5 was destroyed
[FATAL] Check "is_closed_" failed. File "../src/public/ipc_emulation.cc", line 56, function "~InMemoryFile"