UTM
UTM copied to clipboard
utmapp
Alert when QEMU fails to launch doesn't include error message
Describe the issue After starting ANY vm, immediately an error pops up saying: "启动 QEMU 时出错". App language in the system preferences is set to English.
Configuration
- UTM Version: 2.4.1 (40)
- OS Version: macOS Monterey 12.0.1
- M1 Mac mini
Crash log No crash log is available after that error.
Debug log QEMU Debug Log: debug.log
Upload VM VM Direct Download Link
Please try this: add English to the preferred languages list in System Preferences => Language & Region. This way UTM will show English UI.
Unfortunately, the Debug Log is blank. This leads me to believe there is a system error that occurs before UTM can even start the VM properly.
Have you tried restarting your Mac?
Does this error occur with x86_64 VM as well? Please try creating a new VM with x86_64 system and starting that.
Please try this: add English to the preferred languages list in System Preferences => Language & Region. This way UTM will show English UI.
The default app language is set to English.
Unfortunately, the Debug Log is blank. This leads me to believe there is a system error that occurs before UTM can even start the VM properly.
Have you tried restarting your Mac?
Does this error occur with x86_64 VM as well? Please try creating a new VM with x86_64 system and starting that.
I restarted UTM and my Mac few times. This problem happens with x86_64 VM's as well.
The default app language is set to English.
that’s not what I asked you to do:
Please try this: add English to the preferred languages list in System Preferences => Language & Region. This way UTM will show English UI.
English doesn’t have to be the default system language, it just has to be in the list.
English doesn’t have to be the default system language, it just has to be in the list.
I meant exactly that. It is on the list, but it is set as the default language as well.
Ok thanks for double-checking. I would like to solve this, so let's try another way to get some technical diagnostic data from your Mac.
Have you used the Console app before? We can get a detailed log from it. Here's the steps to get additional info via Console app:
- Quit UTM if it is opened.
- Open Console app, click Start streaming.
- In the Console app search box type "UTM" and press return, then click on the grey "any" dropdown and choose "process". The app should show no results (if there are any, click Clear).
- Open UTM and attempt to start a VM (the error popup should show)
- Quit UTM
- In Console app, click one of the lines of activity (center of the window), then in the Menu Bar, choose Edit => Select All and Edit => Copy.
- Open TextEdit, choose File => New, then Format => Make Plain Text.
- Choose Edit => Paste, then File => Save
- In the "Save As:" field, type
UTM-System-Log.log, in the "Where" field choose Desktop, then click Save. - Open this issue page in your web browser and attach the
UTM-System-Log.logfile in a new comment.
Thank you in advance!
Sorry for the long response time. I wasn't at home for a few days. Down below I have attached the file that you have asked for.
Thank you for providing the system log. I don't recognise any of the lines as errors. Here is a more readable version of the log for others to check out:
08.298197 UTM Requesting container lookup; personaid = -1, type = NOPERSONA, name = <unknown>, class = 2, identifier = <private>, temp = 0, create = 0, euid = 501, uid = 501
08.299247 UTM container_create_or_lookup_for_platform: success
08.350240 UTM Received configuration update from daemon (initial)
08.351272 UTM CHECKIN: pid=7433
08.359380 UTM CHECKEDIN: pid=7433 asn=0x0-0xa50a5 foreground=1
08.362390 UTM FRONTLOGGING: version 1
08.362461 UTM Registered, pid=7433 ASN=0x0,0xa50a5
08.365659 UTM BringForward: pid=7433 asn=0x0-0xa50a5 bringForward=1 foreground=1 uiElement=0 launchedByLS=1 modifiersCount=1 allDisabled=0
08.366091 UTM BringFrontModifier: pid=7433 asn=0x0-0xa50a5 Modifier 0 hideAfter=0 hideOthers=0 dontMakeFrontmost=0 mouseDown=0/0 seed=0/0
08.366333 UTM BringForward: pid=7433 asn=0x0-0xa50a5
08.366446 UTM SetFrontProcess: asn=0x0-0xa50a5 options=0
08.376262 UTM Current system appearance, (HLTB: 2), (SLS: 1)
08.377300 UTM No persisted cache on this platform.
08.378314 UTM IOServiceOpen failed for class 'AppleNVMeEAN'
08.378324 UTM Could not open EAN service and connect
08.378330 UTM Failed to copy 'aptk' from EAN
08.378339 UTM Failed to copy APTicket properties. Falling back to default policy.
08.378346 UTM Creating default MGSysConfigPolicy
08.378524 UTM syscfg is not initialized!
08.378535 UTM CFDictionaryRef copySyscfgDictionary(void) enumeration of <private> failed.
08.378545 UTM Failed to find key <private>
08.380681 UTM Post-registration system appearance: (HLTB: 2)
08.445868 UTM Registering for test daemon availability notify post.
08.445998 UTM notify_get_state check indicated test daemon not ready.
08.446125 UTM notify_get_state check indicated test daemon not ready.
08.489325 UTM SignalReady: pid=7433 asn=0x0-0xa50a5
08.490597 UTM SIGNAL: pid=7433 asn=0x0x-0xa50a5
08.502763 UTM Initializing connection
08.502798 UTM Removing all cached process handles
08.502837 UTM Sending handshake request attempt #1 to server
08.502849 UTM Creating connection to com.apple.runningboard
08.503387 UTM Handshake succeeded
08.503401 UTM Identity resolved as app<application.com.utmapp.UTM.279430.280219(501)>
08.526964 UTM NSApp cache appearance:
-NSRequiresAquaSystemAppearance: 0
-appearance: (null)
-effectiveAppearance: <NSCompositeAppearance: 0x600003d07600
(
"<NSDarkAquaAppearance: 0x600003d0a880>",
"<NSSystemAppearance: 0x600003d0c700>"
)>
08.528690 UTM Type "utm" was expected to be declared and exported in the Info.plist of UTM.app, but it was not found.
12.739578 UTM Entering exit handler.
12.739602 UTM Exiting exit handler.
I removed the prefix default 20:49: from all the lines to make it easier to read.
FWIW I just tested what I assume is the same ArchLinux ARM VM from the UTM Gallery website:
- Go to UTM Gallery site for ArchLinux ARM
- Click on Open in UTM
- Wait until VM appears in UTM with Play button
- Click Play button
- VM boots as expected
So I can't reproduce this. My setup:
- UTM Version: 2.4.1 (40)
- OS Version: macOS Monterey 12.1 RC (21C51)
- M1 MacBook Pro
Here I attach additional log after trying to start the VM, not just UTM.
I am having the exact same problem. I don't have a fix yet tho
Configuration
- UTM Version: Version 2.4.1 (40)
- OS Version: macOS Big Sur 11.6
- M1 MacBook Air
Here is the relevant part from @zakhil-dev new system log:
19:28:25.784183+0100 UTM Running: -L "/Volumes/Dysk zewnętrzny/Applications/UTM.app/Contents/Resources/qemu" -S -qmp tcp:127.0.0.1:4000,server,nowait -nodefaults -vga none -nographic -chardev "pipe,id=term0,path=/Users/piotrek/Library/Group Containers/WDNLXAD4W8.com.utmapp.UTM/E3459327-D6AC-409C-ABCA-08EAAC345F57.terminal" -serial chardev:term0 -cpu cortex-a72 -smp cpus=8,sockets=1,cores=8,threads=1 -machine virt,highmem=off -accel hvf -accel tcg,tb-size=512 -boot menu=on -m 2048 -device ac97 -name "ArchLinux ARM" -device qemu-xhci,id=usb-bus -device usb-tablet,bus=usb-bus.0 -device usb-mouse,bus=usb-bus.0 -device usb-kbd,bus=usb-bus.0 -device qemu-xhci,id=usb-controller-0 -chardev spicevmc,name=usbredir,id=usbredirchardev0 -device usb-redir,chardev=usbredirchardev0,id=usbredirdev0,bus=usb-controller-0.0 -chardev spicevmc,name=usbredir,id=usbredirchardev1 -device usb-redir,chardev=usbredirchardev1,id=usbredirdev1,bus=usb-controller-0.0 -chardev spicevmc,name=usbredir,id=usbredirchardev2 -device usb-redir,chardev=usbredirc
19:28:25.809106+0100 UTM TCP Conn [1:0x6000015d9810] using empty proxy configuration
19:28:25.810061+0100 UTM Stream client bypassing proxies on TCP Conn [1:0x6000015d9810]
19:28:25.810468+0100 UTM TCP Conn 0x6000015d9810 started
19:28:25.810988+0100 UTM [C1 9851D6D7-1A85-4163-B789-946C0E373333 127.0.0.1:4000 tcp, definite, attribution: developer, context: Default Network Context (private), proc: FC72DC0E-88DF-332A-BB5E-15354D110883, no proxy, no fallback, allow socket access] start
19:28:25.811362+0100 UTM [C1 127.0.0.1:4000 initial path ((null))] event: path:start @0.000s
19:28:25.811721+0100 UTM [C1 127.0.0.1:4000 waiting path (satisfied (Path is satisfied), interface: lo0)] event: path:satisfied @0.000s, uuid: F5F65EBD-C776-4977-8B50-526A310CFD1A
19:28:25.812726+0100 UTM [C1 127.0.0.1:4000 in_progress socket-flow (satisfied (Path is satisfied), interface: lo0)] event: flow:start_connect @0.001s
19:28:25.812736+0100 UTM nw_connection_report_state_with_handler_on_nw_queue [C1] reporting state preparing
19:28:25.813340+0100 UTM nw_socket_handle_socket_event [C1:1] Socket received CONNRESET event
19:28:25.813509+0100 UTM nw_socket_handle_socket_event [C1:1] Socket received DISCONNECTED event
error 19:28:25.813708+0100 UTM nw_socket_handle_socket_event <private> Socket SO_ERROR [61: Connection refused]
19:28:25.814151+0100 UTM nw_flow_disconnected [C1 127.0.0.1:4000 in_progress socket-flow (satisfied (Path is satisfied), viable, interface: lo0)] Output protocol disconnected
19:28:25.814718+0100 UTM [C1 127.0.0.1:4000 failed socket-flow (satisfied (Path is satisfied), viable, interface: lo0)] event: flow:failed_connect @0.003s, error Connection refused
19:28:25.815398+0100 UTM nw_connection_report_state_with_handler_on_nw_queue [C1] reporting state failed error Connection refused
19:28:25.815920+0100 UTM TCP Conn 0x6000015d9810 event 3. err: 61
error 19:28:25.815948+0100 UTM nw_connection_get_connected_socket [C1] Client called nw_connection_get_connected_socket on unconnected nw_connection
19:28:25.816140+0100 UTM TCP Conn 0x6000015d9810 complete. fd: -1, err: 61
error 19:28:25.816298+0100 UTM TCP Conn 0x6000015d9810 Failed : error 0:61 [61]
19:28:25.816579+0100 UTM Stream error Error Domain=NSPOSIXErrorDomain Code=61 "Connection refused" UserInfo={_kCFStreamErrorCodeKey=61, _kCFStreamErrorDomainKey=1}
19:28:25.816781+0100 UTM QMP stream error seen: Error Domain=NSPOSIXErrorDomain Code=61 "Connection refused" UserInfo={_kCFStreamErrorCodeKey=61, _kCFStreamErrorDomainKey=1}
19:28:25.817204+0100 UTM TCP Conn 0x6000015d9810 canceled
19:28:25.817222+0100 UTM [C1 9851D6D7-1A85-4163-B789-946C0E373333 127.0.0.1:4000 tcp, legacy-socket, definite, attribution: developer] cancel
19:28:25.817430+0100 UTM QMP connection failed, retries left: 29
19:28:25.817600+0100 UTM [C1 127.0.0.1:4000 tcp, legacy-socket, definite, attribution: developer] cancelled
19:28:25.818624+0100 UTM nw_connection_report_state_with_handler_on_nw_queue [C1] reporting state cancelled error Connection refused
19:28:25.822381+0100 UTM filteredItemsFromItems:<private> [8263]--> <private>
19:28:25.822405+0100 UTM Requesting sharingServicesForFilteredItems:<private> mask:<private>
19:28:25.822426+0100 UTM Query extensions (async) for items: <private> onlyViewerOrEditor:1
19:28:25.822482+0100 UTM filteredItemsFromItems:<private> [8263]--> <private>
19:28:25.822498+0100 UTM Requesting sharingServicesForFilteredItems:<private> mask:<private>
19:28:25.822509+0100 UTM Query extensions (async) for items: <private> onlyViewerOrEditor:1
19:28:25.822576+0100 UTM 0x120030d00 - [PID=8268] WebProcessProxy::didFinishLaunching:
19:28:25.824162+0100 UTM Faulting in CFHTTPCookieStorage singleton
19:28:25.824176+0100 UTM Creating default cookie storage with default identifier
19:28:25.824567+0100 UTM Faulting in NSHTTPCookieStorage singleton
19:28:25.827562+0100 UTM Matching dictionary: <private>, attributesArray: <private>
19:28:25.827807+0100 UTM Matching dictionary: <private>, attributesArray: <private>
19:28:25.827801+0100 UTM Discover extensions with attributes <private>
19:28:25.827999+0100 UTM Discover extensions with attributes <private>
19:28:25.828447+0100 UTM [d <private>] <PKHost:0x60000375e000> Beginning discovery for flags: 1024, point: com.apple.ui-services
19:28:25.828584+0100 UTM [d <private>] <PKHost:0x60000375e000> Beginning discovery for flags: 1024, point: com.apple.ui-services
19:28:25.835881+0100 UTM 0x120060340 - NetworkProcessProxy is taking a background assertion because a web process is requesting a connection
19:28:25.841020+0100 UTM filteredItemsFromItems:<private> [8263]--> <private>
19:28:25.841136+0100 UTM Requesting sharingServicesForFilteredItems:<private> mask:<private>
19:28:25.841196+0100 UTM Query extensions (async) for items: <private> onlyViewerOrEditor:1
19:28:25.841269+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::updateActivityState: view visibility state changed 0 -> 1
19:28:25.841291+0100 UTM Matching dictionary: <private>, attributesArray: <private>
19:28:25.841399+0100 UTM Discover extensions with attributes <private>
19:28:25.840996+0100 UTM [d <private>] <PKHost:0x60000375e000> Completed discovery. Final # of matches: 1
19:28:25.842849+0100 UTM [d <private>] <PKHost:0x60000375e000> Beginning discovery for flags: 1024, point: com.apple.ui-services
19:28:25.842953+0100 UTM 1 plugins found
19:28:25.847970+0100 UTM Service with identifier <private> passes activation rule: 0
19:28:25.847985+0100 UTM Service dictionary for plugin <private> not available, skip it.
19:28:25.848037+0100 UTM 0 compatible services found for attributes <private>
19:28:25.848143+0100 UTM Discovery done
19:28:25.848257+0100 UTM Discover extensions with attributes <private>
19:28:25.848463+0100 UTM [d <private>] <PKHost:0x60000375e000> Beginning discovery for flags: 1024, point: com.apple.services
19:28:25.850317+0100 UTM [d <private>] <PKHost:0x60000375e000> Completed discovery. Final # of matches: 1
19:28:25.850369+0100 UTM 1 plugins found
19:28:25.850545+0100 UTM Service with identifier <private> passes activation rule: 1
19:28:25.864164+0100 UTM 1 compatible services found for attributes <private>
19:28:25.864272+0100 UTM Discovery done
19:28:25.864418+0100 UTM Discover extensions with attributes <private>
19:28:25.864626+0100 UTM [d <private>] <PKHost:0x60000375e000> Beginning discovery for flags: 1024, point: com.apple.services
19:28:25.865082+0100 UTM [d <private>] <PKHost:0x60000375e000> Completed discovery. Final # of matches: 1
19:28:25.865387+0100 UTM 1 plugins found
19:28:25.865801+0100 UTM Service with identifier <private> passes activation rule: 0
19:28:25.865813+0100 UTM Service dictionary for plugin <private> not available, skip it.
19:28:25.865844+0100 UTM [d <private>] <PKHost:0x60000375e000> Completed discovery. Final # of matches: 0
19:28:25.865931+0100 UTM 0 compatible services found for attributes <private>
19:28:25.865993+0100 UTM 0 plugins found
19:28:25.866031+0100 UTM Discovery done
19:28:25.866092+0100 UTM 0 compatible services found for attributes <private>
19:28:25.866175+0100 UTM Discover extensions with attributes <private>
19:28:25.866291+0100 UTM Discovery done
19:28:25.866504+0100 UTM Completed querying extensions: <private>
19:28:25.866585+0100 UTM [d <private>] <PKHost:0x60000375e000> Beginning discovery for flags: 1024, point: com.apple.services
19:28:25.866699+0100 UTM [d <private>] <PKHost:0x60000375e000> Completed discovery. Final # of matches: 0
19:28:25.866777+0100 UTM 0 plugins found
19:28:25.866935+0100 UTM 0 compatible services found for attributes <private>
19:28:25.867062+0100 UTM Discovery done
19:28:25.867378+0100 UTM Completed querying extensions: <private>
19:28:25.867974+0100 UTM [d <private>] <PKHost:0x60000375e000> Completed discovery. Final # of matches: 0
19:28:25.867991+0100 UTM 0 plugins found
19:28:25.868006+0100 UTM 0 compatible services found for attributes <private>
19:28:25.868060+0100 UTM Discovery done
19:28:25.868355+0100 UTM Completed querying extensions: <private>
19:28:25.872637+0100 UTM Sorted services: <private>
19:28:25.872787+0100 UTM Sorted services: <private>
19:28:25.873024+0100 UTM Sorted services: <private>
19:28:25.939086+0100 UTM 0x120011be0 - [PID=0, throttler=0x120030e70] ProcessThrottler::Activity::invalidate: Ending background activity / 'WebProcess initialization'
19:28:25.993012+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::decidePolicyForNavigationAction: frameID=3, navigationID=1
19:28:25.993278+0100 UTM 0x1200118d0 - SOAuthorizationCoordinator::tryAuthorize
19:28:25.994191+0100 UTM -[SOConfigurationClient init] on <private>
19:28:25.994262+0100 UTM <SOServiceConnection: 0x600002248000>: new XPC connection
19:28:25.994366+0100 UTM 0x1200118d0 - SOAuthorizationCoordinator::tryAuthorize: Cannot authorize the requested URL.
19:28:25.995420+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::decidePolicyForNavigationAction: listener called: frameID=3, navigationID=1, policyAction=0, safeBrowsingWarning=0, isAppBoundDomain=0
19:28:25.995439+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::receivedNavigationPolicyDecision: frameID=3, navigationID=1, policyAction=0
19:28:25.995533+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::decidePolicyForNavigationAction: keep using process 8268 for navigation, reason=Process has not yet committed any provisional loads
19:28:26.063534+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::didStartProvisionalLoadForFrame: frameID=3
19:28:26.063555+0100 UTM 0x120030d00 - [PID=8268] WebProcessProxy::didStartProvisionalLoadForMainFrame:
19:28:26.068382+0100 UTM Stop operation timeout or force quit
19:28:26.068447+0100 UTM Successfuly disconnected!
19:28:26.068475+0100 UTM Successfuly disconnected!
19:28:26.068475+0100 UTM Input dispatch_io is being closed
19:28:26.068491+0100 UTM Exit operation timeout or force quit
19:28:26.068521+0100 UTM Successfuly disconnected!
19:28:26.068713+0100 UTM Cleanup completed!
19:28:26.068737+0100 UTM Source got cancelled
19:28:26.073496+0100 UTM Requested resource was not within the code object: /System/Library/Frameworks/AppKit.framework/Resources/NSAlertPanelVertical.nib, /Volumes/Dysk zewnętrzny/Applications/UTM.app/Contents
19:28:26.073508+0100 UTM MacOS error: -50
19:28:26.081213+0100 UTM Ignoring nib not in main bundle: <private>, <private> v<private>
19:28:26.084927+0100 UTM RPC: Error Domain=com.utmapp.utm Code=-1 "No connection for RPC." UserInfo={NSLocalizedDescription=No connection for RPC.}
19:28:26.376346+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::didNavigateWithNavigationDataShared:
19:28:26.376399+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::didCommitLoadForFrame: frameID=3
19:28:26.376462+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::didFinishDocumentLoadForFrame: frameID=3
19:28:26.376496+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::decidePolicyForNavigationAction: frameID=12, navigationID=0
19:28:26.376564+0100 UTM 0x1200118d0 - SOAuthorizationCoordinator::tryAuthorize
19:28:26.376791+0100 UTM 0x1200118d0 - SOAuthorizationCoordinator::tryAuthorize: Cannot authorize the requested URL.
19:28:26.376873+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::didStartProvisionalLoadForFrame: frameID=12
19:28:26.376895+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::didCommitLoadForFrame: frameID=12
19:28:26.376907+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::didFinishDocumentLoadForFrame: frameID=12
19:28:26.376918+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::didFinishLoadForFrame: frameID=12
19:28:26.377515+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::decidePolicyForNavigationAction: listener called: frameID=12, navigationID=2, policyAction=0, safeBrowsingWarning=0, isAppBoundDomain=0
19:28:26.377533+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::receivedNavigationPolicyDecision: frameID=12, navigationID=2, policyAction=0
19:28:26.379312+0100 UTM 0x135991018 - [pageProxyID=5, webPageID=6, PID=8268] WebPageProxy::didFinishLoadForFrame: frameID=3
19:28:26.397768+0100 UTM 0x12015c0c0 - GPUProcessProxy is taking a background assertion because a web process is requesting a connection
19:28:26.464349+0100 UTM WebMediaSessionManager::addPlaybackTargetPickerClient 23
19:28:26.475014+0100 UTM WebMediaSessionManager::addPlaybackTargetPickerClient 29
19:28:26.475397+0100 UTM WebMediaSessionManager::addPlaybackTargetPickerClient 34
19:28:26.917586+0100 UTM TCP Conn [2:0x6000015f4210] using empty proxy configuration
19:28:26.917619+0100 UTM Stream client bypassing proxies on TCP Conn [2:0x6000015f4210]
19:28:26.917635+0100 UTM TCP Conn 0x6000015f4210 started
19:28:26.917848+0100 UTM TCP Conn 0x6000015f4210 canceled
19:28:26.917767+0100 UTM [C2 EB0F94F8-33A3-437B-8F2D-85569688ADD8 127.0.0.1:4000 tcp, definite, attribution: developer, context: Default Network Context (private), proc: FC72DC0E-88DF-332A-BB5E-15354D110883, no proxy, no fallback, allow socket access] start
19:28:26.918181+0100 UTM [C2 127.0.0.1:4000 initial path ((null))] event: path:start @0.000s
19:28:26.918349+0100 UTM [C2 127.0.0.1:4000 waiting path (satisfied (Path is satisfied), viable, interface: lo0)] event: path:satisfied @0.000s, uuid: F5F65EBD-C776-4977-8B50-526A310CFD1A
19:28:26.918977+0100 UTM [C2 127.0.0.1:4000 in_progress socket-flow (satisfied (Path is satisfied), viable, interface: lo0)] event: flow:start_connect @0.000s
19:28:26.918999+0100 UTM nw_connection_report_state_with_handler_on_nw_queue [C2] reporting state preparing
19:28:26.919166+0100 UTM [C2 EB0F94F8-33A3-437B-8F2D-85569688ADD8 127.0.0.1:4000 tcp, legacy-socket, definite, attribution: developer] cancel
19:28:26.919193+0100 UTM [C2 127.0.0.1:4000 tcp, legacy-socket, definite, attribution: developer] cancelled
19:28:26.919341+0100 UTM nw_flow_disconnected [C2 127.0.0.1:4000 cancelled socket-flow ((null))] Output protocol disconnected
19:28:26.919374+0100 UTM nw_connection_report_state_with_handler_on_nw_queue [C2] reporting state cancelled
19:28:32.936317+0100 UTM Stop operation timeout or force quit
19:28:32.936399+0100 UTM Exit operation timeout or force quit
The lines that begin with error are where the system log message type is "error".
UTM attempts to start the QEMU backend with management port at local TCP 4000 (-qmp tcp:127.0.0.1:4000); but gets Connection refused.
@zakhil-dev do you have some kind of Firewall enabled, or are you seeing a "QEMUHelper" crash report appear? It might be useful to re-do the test and instead of searching for "utm" in the Console, search for "qemu".
The only firewall which was running was the system one. I have turned it off, but the error still occurred. Down below I attach logs from the Console app after searching "qemu" in it.
Just updated to MacOS 12.1, and I want to let you know that the issue is still happening.
The only firewall which was running was the system one. I have turned it off, but the error still occurred. Down below I attach logs from the Console app after searching "qemu" in it.
There are three errors in this log:
QEMUHelper cannot open file at line 45340 of [d24547a13b] QEMUHelper os_unix.c:45340: (2) open(/var/db/DetachedSignatures) - No such file or directory QEMUHelper Error starting QEMU: Error Domain=NSPOSIXErrorDomain Code=1 "Operation not permitted"
I just successfully started a UTM VM on my Mac and my System Log also includes the first two errors, but not the last one. This line of log and the error alert text is generated in the following lines of UTM code:
https://github.com/utmapp/UTM/blob/6524c733d36f183c5b393b54e91f7f0cd2ae7ad7/QEMUHelper/QEMUHelper.m#L127-L128
So here in line 128 we have the bug that leads to the missing information in the popup. The returned text does not include the underlying error message, that should be fixed.
As to the actual error why you can't launch VMs, I am not sure what causes "Operation not permitted". Maybe your macOS user isn't admin? (not sure if this is required, actually - just guessing)
I'm seeing the same error after upgrading to macOS 12.1. FWIW I don't have any qemu-* executables in PATH anymore after the upgrade (not sure if they were they prior to the upgrade tho):
❯ qemu
zsh: command not found: qemu
Is this related? Does UTM maintain its own internal qemu binaries or does it search PATH? Are there any known fixes for this?
@samuela UTM is a sandboxed Mac app, so you won't find its included version of QEMU as a standalone binary. In other words, there is no need to install QEMU separately, unless you want to run VMs without UTM.
Thank you for letting us know you were able to resolve your issue by reinstalling. @zakhil-dev and @SimaoDias might also want to try this. Your VMs are not deleted when you reinstall UTM.
Hi @conath.
I uninstalled UTM and installed it fresh from https://mac.getutm.app.
It is working now. My configuration is the same. Did the dmg package change? I didn't update my OS
UTM Version: Version 2.4.1 (40) OS Version: macOS Big Sur 11.6 M1 MacBook Air
No, the dmg did not change. Not sure what’s at fault here, either a file permission error or another macOS security system that interferes with launching the QEMU process.
Re-opening because the UI bug remains: we need to show a meaningful error message.
I just got my first Apple Silicon Mac (M1 Mac Mini, for what it's worth) and ran into this problem, as well as a different "solution"/workaround.
Basic steps to reproduce:
- Have a(n Apple Silicon) Mac running Monterey (12.5.1 21G83 in my case) with two admin users, which I will call
admin1andadmin2. - With
admin1, download UTM from mac.getutm.app or GitHub, and install it into the Applications folder. - With
admin2, create a new VM (virtualized or emulated, it doesn't make a difference) and try to start it.
The workaround is to sudo chown -R admin2 /Applications/UTM.app between steps 2 and 3. I imagine that this breaks UTM for admin1, but I did not test that yet. I also don't yet know if this issue is exclusive to Apple Silicon or if it also affects Intel.
ETA: I forgot to mention, this is with UTM 3.2.4.
Just in case this helps anyone in future. I had a similar issue with UTM not being able to launch a VM post an update of UTM with an error of "Operation not permitted". After a bit of digging, I found that the QEMULauncher had been quarantined by MacOS Safari due to being downloaded.
To check:
❯ ls -l@ /Applications/UTM.app/Contents/XPCServices/QEMUHelper.xpc/Contents/MacOS/QEMULauncher.app/Contents/MacOS/QEMULauncher
-rwxr-xr-x@ 1 ben admin 168480 4 Jul 01:16 /Applications/UTM.app/Contents/XPCServices/QEMUHelper.xpc/Contents/MacOS/QEMULauncher.app/Contents/MacOS/QEMULauncher
com.apple.quarantine 57
Note the com.apple.quarantine.
To fix:
xattr -d com.apple.quarantine /Applications/UTM.app/Contents/XPCServices/QEMUHelper.xpc/Contents/MacOS/QEMULauncher.app/Contents/MacOS/QEMULauncher
