Failed Devices when flashing to multiple SDs simulanously

Hi,
I just upgraded to Etcher v 1.5.104 (from 1.5.57).

I’m flashing to 7 SD cards simulatously, which worked perfectly before.
Now, I’m getting, right after starting the flash process, error messages like this:

Every time I get 4-6 failing devices. And no more information.
Even reformatted them right before flash process.

Is there any debug information I can provide to nail down the problem?

Thanks
Fritz

Here’s the developer console output for another try.
It says that some devices are busy but I don’t think so… (just added the first failing device error message)

Thanks
Fritz

Elevating command: /Applications/balenaEtcher.app/Contents/Frameworks/balenaEtcher Helper (Renderer).app/Contents/MacOS/balenaEtcher Helper (Renderer) /Applications/balenaEtcher.app/Contents/Resources/app/generated/child-writer.js
gui.js:48798 Mon Aug 24 2020 18:40:57 GMT+0200 (Central European Summer Time) SafeWebview loaded ({"event":{"id":1,"url":"https://assets.balena.io/etcher-featured/index.html?borderRight=false&darkBackground=true&etcher-version=1.5.104&api-version=2&optOutAnalytics=false","method":"GET","timestamp":1598287257633.676,"resourceType":"mainFrame","ip":"104.31.83.157","fromCache":false,"statusLine":"HTTP/1.1 200","statusCode":200,"responseHeaders":{"access-control-allow-origin":["*"],"age":["134"],"cache-control":["max-age=600"],"cf-cache-status":["DYNAMIC"],"cf-ray":["5c7e84a00e202798-PRG"],"cf-request-id":["04c2f13803000027980ba26200000001"],"content-encoding":["br"],"content-type":["text/html; charset=utf-8"],"date":["Mon, 24 Aug 2020 16:40:57 GMT"],"expect-ct":["max-age=604800, report-uri=\"https://report-uri.cloudflare.com/cdn-cgi/beacon/expect-ct\""],"expires":["Mon, 24 Aug 2020 06:09:58 GMT"],"last-modified":["Tue, 18 Aug 2020 09:51:57 GMT"],"server":["cloudflare"],"status":["200"],"vary":["Accept-Encoding"],"via":["1.1 varnish"],"x-cache":["HIT"],"x-cache-hits":["1"],"x-fastly-request-id":["573a7177ca3b908a895a7062d8e33d9b5a126ab8"],"x-github-request-id":["3F92:7C87:476543:59D925:5F43575D"],"x-proxy-cache":["MISS"],"x-served-by":["cache-vie21632-VIE"],"x-timer":["S1598287258.616034,VS0,VE1"]},"referrer":"","error":"net::OK"},"sample":0.1,"applicationSessionUuid":"6ca8d74d-09ef-4c49-bc92-c535ca496c11","flashingWorkflowUuid":"b681f889-5ad4-4768-b405-195fc9c84b92"})
gui.js:5626  0 devices, 0% at 0.00 MB/s (total 0.00 MB/s)  with 0 failed devices
gui.js:48798 Successfully connected to IPC server: etcher-server-48488, socket root /var/folders/8q/l8_yfrqs03xc98sdypxlj0hc0000gn/T/
gui.js:48798 Image: /Users/fritz/Downloads/balena-cloud-prod01-raspberrypi3-2.51.1+rev1-v11.4.10.img
gui.js:48798 Devices: /dev/disk3, /dev/disk4, /dev/disk5, /dev/disk6, /dev/disk7, /dev/disk8, /dev/disk9
gui.js:48798 Umount on success: true
gui.js:48798 Validate on success: true
gui.js:48798 Auto blockmapping: true
gui.js:48798 Decompress first: true
gui.js:48798 Mon Aug 24 2020 18:41:01 GMT+0200 (Central European Summer Time) Flash error ({"image":"/Users/fritz/Downloads/balena-cloud-prod01-raspberrypi3-2.51.1+rev1-v11.4.10.img","drives":[{"size":7948206080,"isVirtual":false,"enumerator":"DiskArbitration","logicalBlockSize":512,"raw":"/dev/rdisk3","error":null,"isReadOnly":false,"displayName":"/dev/disk3","blockSize":512,"isSCSI":false,"isRemovable":true,"device":"/dev/disk3","busVersion":null,"isSystem":false,"busType":"USB","isCard":false,"isUSB":true,"devicePath":"IODeviceTree:/PCI0@0/XHC1@14","mountpoints":[{"path":"/Volumes/Untitled 2","label":"Untitled 2"}],"description":"Generic- SD/MMC/MS/MSPRO Media","isUAS":null},{"size":7948206080,"isVirtual":false,"enumerator":"DiskArbitration","logicalBlockSize":512,"raw":"/dev/rdisk4","error":null,"isReadOnly":false,"displayName":"/dev/disk4","blockSize":512,"isSCSI":false,"isRemovable":true,"device":"/dev/disk4","busVersion":null,"isSystem":false,"busType":"USB","isCard":false,"isUSB":true,"devicePath":"IODeviceTree:/PCI0@0/XHC1@14","mountpoints":[{"path":"/Volumes/resin-boot","label":"resin-boot"}],"description":"Generic- SD/MMC/MS/MSPRO Media","isUAS":null},{"size":7948206080,"isVirtual":false,"enumerator":"DiskArbitration","logicalBlockSize":512,"raw":"/dev/rdisk5","error":null,"isReadOnly":false,"displayName":"/dev/disk5","blockSize":512,"isSCSI":false,"isRemovable":true,"device":"/dev/disk5","busVersion":null,"isSystem":false,"busType":"USB","isCard":false,"isUSB":true,"devicePath":"IODeviceTree:/PCI0@0/XHC1@14","mountpoints":[{"path":"/Volumes/Untitled 3","label":"Untitled 3"}],"description":"Generic- SD/MMC/MS/MSPRO Media","isUAS":null},{"size":7948206080,"isVirtual":false,"enumerator":"DiskArbitration","logicalBlockSize":512,"raw":"/dev/rdisk6","error":null,"isReadOnly":false,"displayName":"/dev/disk6","blockSize":512,"isSCSI":false,"isRemovable":true,"device":"/dev/disk6","busVersion":null,"isSystem":false,"busType":"USB","isCard":false,"isUSB":true,"devicePath":"IODeviceTree:/PCI0@0/XHC1@14","mountpoints":[{"path":"/Volumes/Untitled 4","label":"Untitled 4"}],"description":"Generic- SD/MMC/MS/MSPRO Media","isUAS":null},{"size":7948206080,"isVirtual":false,"enumerator":"DiskArbitration","logicalBlockSize":512,"raw":"/dev/rdisk7","error":null,"isReadOnly":false,"displayName":"/dev/disk7","blockSize":512,"isSCSI":false,"isRemovable":true,"device":"/dev/disk7","busVersion":null,"isSystem":false,"busType":"USB","isCard":false,"isUSB":true,"devicePath":"IODeviceTree:/PCI0@0/XHC1@14","mountpoints":[{"path":"/Volumes/Untitled 7","label":"Untitled 7"}],"description":"Generic- SD/MMC/MS/MSPRO Media","isUAS":null},{"size":7948206080,"isVirtual":false,"enumerator":"DiskArbitration","logicalBlockSize":512,"raw":"/dev/rdisk8","error":null,"isReadOnly":false,"displayName":"/dev/disk8","blockSize":512,"isSCSI":false,"isRemovable":true,"device":"/dev/disk8","busVersion":null,"isSystem":false,"busType":"USB","isCard":false,"isUSB":true,"devicePath":"IODeviceTree:/PCI0@0/XHC1@14","mountpoints":[{"path":"/Volumes/Untitled 6","label":"Untitled 6"}],"description":"Generic- SD/MMC/MS/MSPRO Media","isUAS":null},{"size":7948206080,"isVirtual":false,"enumerator":"DiskArbitration","logicalBlockSize":512,"raw":"/dev/rdisk9","error":null,"isReadOnly":false,"displayName":"/dev/disk9","blockSize":512,"isSCSI":false,"isRemovable":true,"device":"/dev/disk9","busVersion":null,"isSystem":false,"busType":"USB","isCard":false,"isUSB":true,"devicePath":"IODeviceTree:/PCI0@0/XHC1@14","mountpoints":[{"path":"/Volumes/resin-boot 1","label":"resin-boot"}],"description":"Generic- SD/MMC/MS/MSPRO Media","isUAS":null}],"driveCount":7,"uuid":"20b9034d-b79a-41d1-b1c7-82698e6cde57","flashInstanceUuid":"20b9034d-b79a-41d1-b1c7-82698e6cde57","unmountOnSuccess":true,"validateWriteOnSuccess":true,"error":{"name":"Error","message":"EBUSY: resource busy or locked, open '/dev/rdisk4'","stack":"Error: EBUSY: resource busy or locked, open '/dev/rdisk4'","code":"EBUSY","syscall":"open","errno":-16},"sample":0.1,"applicationSessionUuid":"6ca8d74d-09ef-4c49-bc92-c535ca496c11","flashingWorkflowUuid":"b681f889-5ad4-4768-b405-195fc9c84b92"})

Hi,
I was about to ask precisely those logs, so thanks for anticipating!
Since the error is about the resource(s) being busy, there’s not much we can do. It means the OS is keeping the resource busy, as the error points out, and as such it’s best to wait a bit before re-issuing another flash.
Depending on the content of the targets when you connect them, you might need to first clear some OS dialogues (i.e. when the content is unreadable, it tells you that the OS couldn’t mount the target)

Hi,
thanks, but this is a bit strange.
Today I already flashed already ~50 devices using 1.5.57. For the last batch I wanted to upgrade to the latest Etcher release and then the issues started.

Might there be some race condition situation or something similar when trying to access the devices?

Thanks

Maybe, but the race condition is with the OS - something that we don’t control.
Does it still flash without errors when if you try to use v1.5.57? If so, we might have a regression in how we choose to open devices

Hi,
it seems that just the reinstall of Etcher was the problem.
I found a similar issue here with reading of disk images:
https://github.com/balena-io/etcher/issues/2835#issuecomment-668698183 The last comment (linked) was the indicator: Went (on my Mac) to System Preferences -> Security & Privacy and granted “Full Disk Access” permission to balenaEtcher.
Now it seems to work.

Cheers