canonical / steam-snap

Steam as a snap
https://snapcraft.io/steam
76 stars 10 forks source link

Unable to launch game #6

Closed Nightmayr closed 2 years ago

Nightmayr commented 2 years ago

I'm trying to launch a game (Apex Legends) but Steam shows the game launching for a few seconds and then provides the option to "Play" the game indicating the game failed to launch. Apex Legends makes use of EasyAnti-Cheat for online play (in case it's relevant to the failed launch).

Screencast:

https://user-images.githubusercontent.com/24817147/166121605-98a21483-7ddf-43fe-9af5-8a48c5a37295.mp4

Running from the terminal shows:

GameAction [AppID 1172470, ActionID 7] : LaunchApp changed task to SynchronizingCloud with ""
GameAction [AppID 1172470, ActionID 7] : LaunchApp changed task to SiteLicenseSeatCheckout with ""
GameAction [AppID 1172470, ActionID 7] : LaunchApp changed task to CreatingProcess with ""
GameAction [AppID 1172470, ActionID 7] : LaunchApp waiting for user response to CreatingProcess ""
GameAction [AppID 1172470, ActionID 7] : LaunchApp continues with user response "CreatingProcess"
/bin/sh\0-c\0/home/umayr/snap/steam/common/.local/share/Steam/ubuntu12_32/reaper SteamLaunch AppId=1172470 -- '/home/umayr/snap/steam/common/.local/share/Steam/steamapps/common/SteamLinuxRuntime_soldier'/_v2-entry-point --verb=waitforexitandrun -- '/home/umayr/snap/steam/common/.local/share/Steam/steamapps/common/Proton - Experimental'/proton waitforexitandrun  '/home/umayr/snap/steam/common/.local/share/Steam/steamapps/common/Apex Legends/EasyAntiCheat_launcher.exe' -steam\0
Game process added : AppID 1172470 "/home/umayr/snap/steam/common/.local/share/Steam/ubuntu12_32/reaper SteamLaunch AppId=1172470 -- '/home/umayr/snap/steam/common/.local/share/Steam/steamapps/common/SteamLinuxRuntime_soldier'/_v2-entry-point --verb=waitforexitandrun -- '/home/umayr/snap/steam/common/.local/share/Steam/steamapps/common/Proton - Experimental'/proton waitforexitandrun  '/home/umayr/snap/steam/common/.local/share/Steam/steamapps/common/Apex Legends/EasyAntiCheat_launcher.exe' -steam", ProcID 70415, IP 0.0.0.0:0
chdir /home/umayr/snap/steam/common/.local/share/Steam/steamapps/common/Apex Legends
ERROR: ld.so: object '/home/umayr/snap/steam/common/.local/share/Steam/ubuntu12_32/gameoverlayrenderer.so' from LD_PRELOAD cannot be preloaded (wrong ELF class: ELFCLASS32): ignored.
GameAction [AppID 1172470, ActionID 7] : LaunchApp changed task to WaitingGameWindow with ""
ERROR: ld.so: object '/home/umayr/snap/steam/common/.local/share/Steam/ubuntu12_64/gameoverlayrenderer.so' from LD_PRELOAD cannot be preloaded (wrong ELF class: ELFCLASS64): ignored.
ERROR: ld.so: object '/home/umayr/snap/steam/common/.local/share/Steam/ubuntu12_32/gameoverlayrenderer.so' from LD_PRELOAD cannot be preloaded (wrong ELF class: ELFCLASS32): ignored.
ERROR: ld.so: object '/home/umayr/snap/steam/common/.local/share/Steam/ubuntu12_32/gameoverlayrenderer.so' from LD_PRELOAD cannot be preloaded (wrong ELF class: ELFCLASS32): ignored.
ERROR: ld.so: object '/home/umayr/snap/steam/common/.local/share/Steam/ubuntu12_32/gameoverlayrenderer.so' from LD_PRELOAD cannot be preloaded (wrong ELF class: ELFCLASS32): ignored.
pid 70420 != 70417, skipping destruction (fork without exec?)

(pressure-vessel-wrap:70417): steam-runtime-tools-CRITICAL **: Somehow got a wait_status that was neither exited nor signaled

(pressure-vessel-wrap:70417): steam-runtime-tools-CRITICAL **: Somehow got a wait_status that was neither exited nor signaled

(pressure-vessel-wrap:70417): steam-runtime-tools-CRITICAL **: file ../steam-runtime-tools/utils.c: line 213 (_srt_process_timeout_wait_status): should not be reached

(pressure-vessel-wrap:70417): steam-runtime-tools-CRITICAL **: file ../steam-runtime-tools/utils.c: line 213 (_srt_process_timeout_wait_status): should not be reached

(pressure-vessel-wrap:70417): steam-runtime-tools-CRITICAL **: Somehow got a wait_status that was neither exited nor signaled

(pressure-vessel-wrap:70417): steam-runtime-tools-CRITICAL **: file ../steam-runtime-tools/utils.c: line 213 (_srt_process_timeout_wait_status): should not be reached

(pressure-vessel-wrap:70417): steam-runtime-tools-CRITICAL **: Somehow got a wait_status that was neither exited nor signaled

(pressure-vessel-wrap:70417): steam-runtime-tools-CRITICAL **: file ../steam-runtime-tools/utils.c: line 213 (_srt_process_timeout_wait_status): should not be reached
GameAction [AppID 1172470, ActionID 7] : LaunchApp changed task to Completed with ""
pressure-vessel-wrap[70417]: W: "var/lib/snapd/hostfs/usr/share/nvidia" is unlikely to appear in "/run/host"
pressure-vessel-wrap[70417]: W: "var/lib/snapd/hostfs/usr/share/nvidia" is unlikely to appear in "/run/host"
bwrap: Can't bind mount /oldroot/var/lib/snapd/hostfs/usr/lib/i386-linux-gnu/libEGL_nvidia.so.510.60.02 on /newroot/var/lib/snapd/hostfs/usr/lib/i386-linux-gnu/libEGL_nvidia.so.510.60.02: Unable to mount source on destination: Permission denied
Game process removed: AppID 1172470 "/home/umayr/snap/steam/common/.local/share/Steam/ubuntu12_32/reaper SteamLaunch AppId=1172470 -- '/home/umayr/snap/steam/common/.local/share/Steam/steamapps/common/SteamLinuxRuntime_soldier'/_v2-entry-point --verb=waitforexitandrun -- '/home/umayr/snap/steam/common/.local/share/Steam/steamapps/common/Proton - Experimental'/proton waitforexitandrun  '/home/umayr/snap/steam/common/.local/share/Steam/steamapps/common/Apex Legends/EasyAntiCheat_launcher.exe' -steam", ProcID 70415 
ThreadGetProcessExitCode: no such process 70418
ThreadGetProcessExitCode: no such process 70417
ThreadGetProcessExitCode: no such process 70416

Judging from the log it looks like it could be nvidia GPU related.

Steam snap version: installed: 1.0.0.74 (6) 196MB

snapd version: installed: 2.55.4 (15793) 46MB snapd

GPU: NVIDIA GeForce RTX 2080 Ti (Driver 510.60.02)

pallebone commented 2 years ago

I tried to do what you asked and still got some more errors. I then added another directory as I noticed some additional errors there. This was my final file: Screenshot from 2022-07-28 23-35-36 File end: Screenshot from 2022-07-28 23-41-52

I added the line: /var/lib/snapd/hostfs/usr/lib/i386-linux-gnu/ r,

Errors now:

Jul 28 23:39:57 Aragorn kernel: [50607.786752] audit: type=1400 audit(1659065997.832:4823): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/etc/vulkan/explicit_layer.d/" pid=167773 comm="pressure-vessel" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 28 23:39:57 Aragorn kernel: [50607.788270] audit: type=1400 audit(1659065997.832:4824): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/etc/vulkan/implicit_layer.d/" pid=167773 comm="pressure-vessel" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 28 23:39:58 Aragorn kernel: [50608.060833] Lockdown: rpc-libvirtd: debugfs access is restricted; see man kernel_lockdown.7
Jul 28 23:39:58 Aragorn kernel: [50608.377258] audit: type=1400 audit(1659065998.424:4825): apparmor="DENIED" operation="mount" info="failed flags match" error=-13 profile="snap.steam.steam" name="/newroot/etc/nvidia/" pid=167807 comm="pv-bwrap" srcname="/oldroot/etc/nvidia/" flags="rw, rbind"
Jul 28 23:39:59 Aragorn kernel: [50609.522199] audit: type=1400 audit(1659065999.568:4826): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/etc/vulkan/explicit_layer.d/" pid=167810 comm="pressure-vessel" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 28 23:39:59 Aragorn kernel: [50609.522653] audit: type=1400 audit(1659065999.568:4827): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/etc/vulkan/implicit_layer.d/" pid=167810 comm="pressure-vessel" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 28 23:40:00 Aragorn kernel: [50610.074052] audit: type=1400 audit(1659066000.120:4828): apparmor="DENIED" operation="mount" info="failed flags match" error=-13 profile="snap.steam.steam" name="/newroot/etc/nvidia/" pid=167843 comm="pv-bwrap" srcname="/oldroot/etc/nvidia/" flags="rw, rbind"

Will try pick this up in the morning with you as I have to go to sleep now, hope thats ok :)

Pete

jhenstridge commented 2 years ago

This one looks new:

audit: type=1400 audit(1659066000.120:4828): apparmor="DENIED" operation="mount" info="failed flags match" error=-13 profile="snap.steam.steam" name="/newroot/etc/nvidia/" pid=167843 comm="pv-bwrap" srcname="/oldroot/etc/nvidia/" flags="rw, rbind"

It looks like it comes from this list of files/directories:

https://gitlab.steamos.cloud/steamrt/steam-runtime-tools/-/blob/master/pressure-vessel/runtime.c#L3006-3013

So that's more mounts we'll have to allow in the steam-support interface. If anyone wants to try modifying the AppArmor profile directly see if it helps, this would be the relevant rule:

mount options=(rw, rbind) /oldroot/etc/nvidia/ -> /newroot/etc/nvidia/,
pallebone commented 2 years ago

You are clearly a genius because a lot more happened when I made that change. The game opens and is... functional. However it does not make use of the Nvidia graphics card, and only uses the intel card on the laptop.

(this is my file now): Screenshot from 2022-07-29 08-33-21

This obviously drops the framerates and makes the game unplayable even though its running now (Im good but not 3fps good). I can confirm the GPU is not used by watching 'nvtop' and noting that r5apex.exe or anything to do with apex is not listed for the card.

Here is a new set of logs from the kern.log file:

Thanks again for your time.

P

Jul 29 08:39:01 Aragorn kernel: [82951.867223] audit: type=1400 audit(1659098341.491:10341): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/etc/vulkan/explicit_layer.d/" pid=262672 comm="pressure-vessel" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:01 Aragorn kernel: [82951.867645] audit: type=1400 audit(1659098341.491:10342): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/etc/vulkan/implicit_layer.d/" pid=262672 comm="pressure-vessel" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:02 Aragorn kernel: [82952.551604] audit: type=1400 audit(1659098342.175:10343): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/usr/local/lib/" pid=262706 comm="ldconfig.real" requested_mask="r" denied_mask="r" fsuid=1000 ouid=1000
Jul 29 08:39:02 Aragorn kernel: [82952.650960] audit: type=1326 audit(1659098342.275:10344): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262710 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=141 compat=0 ip=0x7ff258b281eb code=0x50000
Jul 29 08:39:02 Aragorn kernel: [82952.650966] audit: type=1326 audit(1659098342.275:10345): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262710 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=141 compat=0 ip=0x7ff258b281eb code=0x50000
Jul 29 08:39:02 Aragorn kernel: [82952.683134] audit: type=1400 audit(1659098342.307:10346): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/sys/devices/system/node/online" pid=262708 comm="wine64" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:02 Aragorn kernel: [82952.686696] audit: type=1326 audit(1659098342.311:10347): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262710 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7ff258b1684b code=0x50000
Jul 29 08:39:02 Aragorn kernel: [82952.688954] audit: type=1400 audit(1659098342.311:10348): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/sys/devices/system/node/online" pid=262712 comm="wine64" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:02 Aragorn kernel: [82952.690281] audit: type=1326 audit(1659098342.315:10349): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262712 comm="wineboot.exe" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E6536342D7072656C6F61646572 sig=0 arch=c000003e syscall=65535 compat=0 ip=0x600000000005 code=0x50000
Jul 29 08:39:02 Aragorn kernel: [82952.700295] audit: type=1400 audit(1659098342.323:10350): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/sys/devices/system/clocksource/clocksource0/current_clocksource" pid=262710 comm="wineserver" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:04 Aragorn kernel: [82954.826690] Lockdown: rpc-libvirtd: debugfs access is restricted; see man kernel_lockdown.7
Jul 29 08:39:06 Aragorn kernel: [82956.875998] kauditd_printk_skb: 341 callbacks suppressed
Jul 29 08:39:06 Aragorn kernel: [82956.876000] audit: type=1326 audit(1659098346.499:10692): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262822 comm="steam.exe" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E6536342D7072656C6F61646572 sig=0 arch=c000003e syscall=65535 compat=0 ip=0x600000000005 code=0x50000
Jul 29 08:39:06 Aragorn kernel: [82956.901641] audit: type=1326 audit(1659098346.527:10693): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:06 Aragorn kernel: [82956.903878] audit: type=1400 audit(1659098346.527:10694): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/sys/devices/system/node/online" pid=262863 comm="wine64" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:06 Aragorn kernel: [82956.904993] audit: type=1326 audit(1659098346.527:10695): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262863 comm="conhost.exe" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E6536342D7072656C6F61646572 sig=0 arch=c000003e syscall=65535 compat=0 ip=0x600000000005 code=0x50000
Jul 29 08:39:06 Aragorn kernel: [82956.917961] audit: type=1326 audit(1659098346.543:10696): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:06 Aragorn kernel: [82956.923569] audit: type=1326 audit(1659098346.547:10697): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:06 Aragorn kernel: [82956.925539] audit: type=1400 audit(1659098346.551:10698): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/sys/devices/system/node/online" pid=262866 comm="wine64" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:06 Aragorn kernel: [82956.926644] audit: type=1326 audit(1659098346.551:10699): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262866 comm="explorer.exe" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E6536342D7072656C6F61646572 sig=0 arch=c000003e syscall=65535 compat=0 ip=0x600000000005 code=0x50000
Jul 29 08:39:06 Aragorn kernel: [82956.953572] audit: type=1400 audit(1659098346.579:10700): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/etc/vulkan/implicit_layer.d/" pid=262866 comm="explorer.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=1000
Jul 29 08:39:06 Aragorn kernel: [82956.954027] audit: type=1400 audit(1659098346.579:10701): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/etc/vulkan/explicit_layer.d/" pid=262866 comm="explorer.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=1000
Jul 29 08:39:07 Aragorn kernel: [82957.830172] Lockdown: rpc-libvirtd: debugfs access is restricted; see man kernel_lockdown.7
Jul 29 08:39:10 Aragorn kernel: [82960.840308] Lockdown: rpc-libvirtd: debugfs access is restricted; see man kernel_lockdown.7
Jul 29 08:39:11 Aragorn kernel: [82961.878180] kauditd_printk_skb: 743 callbacks suppressed
Jul 29 08:39:11 Aragorn kernel: [82961.878184] audit: type=1400 audit(1659098351.503:11445): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/251732/comm" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:11 Aragorn kernel: [82961.878773] audit: type=1400 audit(1659098351.503:11446): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/251732/cmdline" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:11 Aragorn kernel: [82961.880287] audit: type=1400 audit(1659098351.503:11447): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/252510/comm" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:11 Aragorn kernel: [82961.880652] audit: type=1400 audit(1659098351.503:11448): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/252510/cmdline" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:11 Aragorn kernel: [82961.882549] audit: type=1400 audit(1659098351.507:11449): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/254142/comm" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:11 Aragorn kernel: [82961.882966] audit: type=1400 audit(1659098351.507:11450): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/254142/cmdline" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:11 Aragorn kernel: [82961.884423] audit: type=1400 audit(1659098351.507:11451): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/255066/comm" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:11 Aragorn kernel: [82961.884998] audit: type=1400 audit(1659098351.507:11452): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/255066/cmdline" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:11 Aragorn kernel: [82961.886441] audit: type=1400 audit(1659098351.511:11453): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/255155/comm" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:11 Aragorn kernel: [82961.886937] audit: type=1400 audit(1659098351.511:11454): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/255155/cmdline" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:13 Aragorn kernel: [82963.834814] Lockdown: rpc-libvirtd: debugfs access is restricted; see man kernel_lockdown.7
Jul 29 08:39:16 Aragorn kernel: [82966.842658] Lockdown: rpc-libvirtd: debugfs access is restricted; see man kernel_lockdown.7
Jul 29 08:39:17 Aragorn kernel: [82968.166812] kauditd_printk_skb: 44 callbacks suppressed
Jul 29 08:39:17 Aragorn kernel: [82968.166815] audit: type=1326 audit(1659098357.795:11499): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:17 Aragorn kernel: [82968.167245] audit: type=1326 audit(1659098357.795:11500): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:17 Aragorn kernel: [82968.167313] audit: type=1326 audit(1659098357.795:11501): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:17 Aragorn kernel: [82968.167523] audit: type=1326 audit(1659098357.795:11502): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:17 Aragorn kernel: [82968.168939] audit: type=1326 audit(1659098357.795:11503): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:17 Aragorn kernel: [82968.169095] audit: type=1326 audit(1659098357.795:11504): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:19 Aragorn kernel: [82969.846395] Lockdown: rpc-libvirtd: debugfs access is restricted; see man kernel_lockdown.7
Jul 29 08:39:20 Aragorn kernel: [82971.050020] audit: type=1326 audit(1659098360.679:11505): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:20 Aragorn kernel: [82971.064467] audit: type=1400 audit(1659098360.691:11506): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/sys/devices/virtual/dmi/id/bios_vendor" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:20 Aragorn kernel: [82971.064472] audit: type=1400 audit(1659098360.691:11507): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/sys/devices/virtual/dmi/id/bios_version" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:20 Aragorn kernel: [82971.064475] audit: type=1400 audit(1659098360.691:11508): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/sys/devices/virtual/dmi/id/bios_date" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=0
Jul 29 08:39:22 Aragorn kernel: [82972.846923] Lockdown: rpc-libvirtd: debugfs access is restricted; see man kernel_lockdown.7
Jul 29 08:39:24 Aragorn kernel: [82975.264524] kauditd_printk_skb: 76 callbacks suppressed
Jul 29 08:39:24 Aragorn kernel: [82975.264528] audit: type=1326 audit(1659098364.891:11585): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:24 Aragorn kernel: [82975.270244] audit: type=1326 audit(1659098364.899:11586): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:24 Aragorn kernel: [82975.273846] audit: type=1326 audit(1659098364.903:11587): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:24 Aragorn kernel: [82975.274295] audit: type=1326 audit(1659098364.903:11588): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:24 Aragorn kernel: [82975.274900] audit: type=1326 audit(1659098364.903:11589): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:24 Aragorn kernel: [82975.279158] audit: type=1326 audit(1659098364.907:11590): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:24 Aragorn kernel: [82975.279409] audit: type=1326 audit(1659098364.907:11591): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:24 Aragorn kernel: [82975.283111] audit: type=1326 audit(1659098364.911:11592): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:24 Aragorn kernel: [82975.287356] audit: type=1326 audit(1659098364.915:11593): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:24 Aragorn kernel: [82975.313858] audit: type=1326 audit(1659098364.943:11594): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:25 Aragorn kernel: [82975.851159] Lockdown: rpc-libvirtd: debugfs access is restricted; see man kernel_lockdown.7
Jul 29 08:39:28 Aragorn kernel: [82978.850336] Lockdown: rpc-libvirtd: debugfs access is restricted; see man kernel_lockdown.7
Jul 29 08:39:31 Aragorn kernel: [82981.854664] Lockdown: rpc-libvirtd: debugfs access is restricted; see man kernel_lockdown.7
Jul 29 08:39:33 Aragorn kernel: [82983.590911] kauditd_printk_skb: 1 callbacks suppressed
Jul 29 08:39:33 Aragorn kernel: [82983.590914] audit: type=1326 audit(1659098373.219:11596): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:33 Aragorn kernel: [82984.186515] audit: type=1326 audit(1659098373.814:11597): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:34 Aragorn kernel: [82984.858700] Lockdown: rpc-libvirtd: debugfs access is restricted; see man kernel_lockdown.7
Jul 29 08:39:37 Aragorn kernel: [82987.862752] Lockdown: rpc-libvirtd: debugfs access is restricted; see man kernel_lockdown.7
Jul 29 08:39:38 Aragorn kernel: [82988.587517] audit: type=1326 audit(1659098378.214:11598): auid=1000 uid=1000 gid=1000 ses=4 subj==snap.steam.steam (enforce) pid=262824 comm="wineserver" exe=2F686F6D652F617261676F726E2F736E61702F737465616D2F636F6D6D6F6E2F2E6C6F63616C2F73686172652F537465616D2F737465616D617070732F636F6D6D6F6E2F50726F746F6E20372E302F646973742F62696E2F77696E65736572766572 sig=0 arch=c000003e syscall=203 compat=0 ip=0x7f95b9d4184b code=0x50000
Jul 29 08:39:39 Aragorn kernel: [82989.927235] audit: type=1400 audit(1659098379.554:11599): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/262914/pagemap" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=1000
Jul 29 08:39:39 Aragorn kernel: [82989.927248] audit: type=1400 audit(1659098379.554:11600): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/262914/pagemap" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=1000
Jul 29 08:39:39 Aragorn kernel: [82989.927258] audit: type=1400 audit(1659098379.554:11601): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/262914/pagemap" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=1000
Jul 29 08:39:39 Aragorn kernel: [82989.927272] audit: type=1400 audit(1659098379.554:11602): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/262914/pagemap" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=1000
Jul 29 08:39:39 Aragorn kernel: [82989.927294] audit: type=1400 audit(1659098379.554:11603): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/262914/pagemap" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=1000
Jul 29 08:39:39 Aragorn kernel: [82989.927307] audit: type=1400 audit(1659098379.554:11604): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/262914/pagemap" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=1000
Jul 29 08:39:39 Aragorn kernel: [82989.927318] audit: type=1400 audit(1659098379.554:11605): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/262914/pagemap" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=1000
Jul 29 08:39:39 Aragorn kernel: [82989.927335] audit: type=1400 audit(1659098379.554:11606): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/262914/pagemap" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=1000
Jul 29 08:39:39 Aragorn kernel: [82989.927367] audit: type=1400 audit(1659098379.554:11607): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/262914/pagemap" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=1000
Jul 29 08:39:39 Aragorn kernel: [82989.927406] audit: type=1400 audit(1659098379.554:11608): apparmor="DENIED" operation="open" profile="snap.steam.steam" name="/proc/262914/pagemap" pid=262914 comm="R5Apex.exe" requested_mask="r" denied_mask="r" fsuid=1000 ouid=1000
Jul 29 08:39:40 Aragorn kernel: [82990.862503] Lockdown: rpc-libvirtd: debugfs access is restricted; see man kernel_lockdown.7
Jul 29 08:39:43 Aragorn kernel: [82993.862537] Lockdown: rpc-libvirtd: debugfs access is restricted; see man kernel_lockdown.7
root@Aragorn:/var/lib/snapd/apparmor/profiles# 
ashuntu commented 2 years ago

I can confirm similar errors to @pallebone with Apex after making those apparmor changes.

ashuntu commented 2 years ago

@jhenstridge is this something that needs to be changed in snapd?

pallebone commented 2 years ago

Just checking in if there are any updates at all on this. It doesnt seem like there is any change when I try to open the game.

ashuntu commented 2 years ago

@pallebone Fixes still need to be made in snapd to accommodate some of the issues that are occurring, namely with Wine/Proton and dedicated GPUs. I'll be sure to comment in this issue again when I have more news to share.

pallebone commented 2 years ago

Hi

Is this fixed?

Kind regards Peter

ashuntu commented 2 years ago

@pallebone The issue seems to be resolved from some of our testing and the fixes that were pushed into snapd. You can try it yourself if you refresh snapd from edge (snap refresh snapd --edge). There's also fixes and improvements in Steam's edge channel, but no promise of stability there (snap refresh steam --edge).

If you still have problems feel free to open a new issue.

pallebone commented 2 years ago

I mean I just tried and it has the exact same issue (bwrap: Can't bind mount /oldroot/etc/nvidia on /newroot/etc/nvidia: Unable to mount source on destination: Permission denied) but if the issue is closed and we are 6 months without a resolution Im thinking that this isnt a super serious project so am just going to move on, no real point opening a new issue from my perspective.

Good luck, laters

Pete

kenvandine commented 2 years ago

I mean I just tried and it has the exact same issue (bwrap: Can't bind mount /oldroot/etc/nvidia on /newroot/etc/nvidia: Unable to mount source on destination: Permission denied) but if the issue is closed and we are 6 months without a resolution Im thinking that this isnt a super serious project so am just going to move on, no real point opening a new issue from my perspective.

Good luck, laters

Pete

We're not seeing it try to mount etc/nvidia in our testing, but this does seem to be a bit sensitive to the host file system. In my recent updates to the steam-support snapd interface, I didn't include that. We'll get that added ASAP. IIRC you are on debian?

pallebone commented 2 years ago

We're not seeing it try to mount etc/nvidia in our testing, but this does seem to be a bit sensitive to the host file system. In my recent updates to the steam-support snapd interface, I didn't include that. We'll get that added ASAP. IIRC you are on debian?

Hi Kenvandine,

Yes that is right I am using Debian 12 (bookworm), thank you.

Kind regards, Peter

kenvandine commented 2 years ago

We're not seeing it try to mount etc/nvidia in our testing, but this does seem to be a bit sensitive to the host file system. In my recent updates to the steam-support snapd interface, I didn't include that. We'll get that added ASAP. IIRC you are on debian?

Hi Kenvandine,

Yes that is right I am using Debian 12 (bookworm), thank you.

Kind regards, Peter

@pallebone could you please try this again with snapd from edge? The fix for mounting /etc/nvidia has landed, which hopefully has fixed the issue you ran into on debian.

pallebone commented 2 years ago

Thank you for persisting with the issue, and remaining professional when I was giving up.

The latest edge snap refresh as resolved the issue and the game indeed launches now. So this is a massive improvement. Only issue is the GPU is not sues so the fps is very low but this is a secondary problem and I will open a new case for that. The overall issue of it not launching is indeed fixed.

Thanks to @kenvandine and @ashuntu for your help.

Kind regards Peter

pallebone commented 2 years ago

Agree with case closure. Issue is resolved.

kenvandine commented 2 years ago

Agree with case closure. Issue is resolved.

Thanks! Your testing and feedback is greatly appreciated.