._. SNAPD_DEBUG=1 snap run firefox --profile-manager 2023/05/31 15:21:47.201581 tool_linux.go:204: DEBUG: restarting into "/snap/snapd/current/usr/bin/snap" 2023/05/31 15:21:47.216995 logger.go:92: DEBUG: -- snap startup {"stage":"start", "time":"1685542907.216989"} 2023/05/31 15:21:47.223464 logger.go:92: DEBUG: executing snap-confine from /snap/snapd/19122/usr/lib/snapd/snap-confine 2023/05/31 15:21:47.224175 logger.go:92: DEBUG: SELinux not enabled 2023/05/31 15:21:47.224694 logger.go:92: DEBUG: creating transient scope snap.firefox.firefox 2023/05/31 15:21:47.227276 logger.go:92: DEBUG: using session bus 2023/05/31 15:21:47.229010 logger.go:92: DEBUG: create transient scope job: /org/freedesktop/systemd1/job/508 2023/05/31 15:21:47.230635 logger.go:92: DEBUG: job result is "done" 2023/05/31 15:21:47.230662 logger.go:92: DEBUG: transient scope snap.firefox.firefox.4e9755f4-b61c-45f2-b80e-f7ab4736c607.scope created 2023/05/31 15:21:47.231058 logger.go:92: DEBUG: waited 3.666588ms for tracking 2023/05/31 15:21:47.231074 logger.go:92: DEBUG: -- snap startup {"stage":"snap to snap-confine", "time":"1685542907.231070"} DEBUG: -- snap startup {"stage":"snap-confine enter", "time":"1685542907.233717"} DEBUG: umask reset, old umask was 02 DEBUG: security tag: snap.firefox.firefox DEBUG: executable: /usr/lib/snapd/snap-exec DEBUG: confinement: non-classic DEBUG: base snap: core20 DEBUG: ruid: 1000, euid: 0, suid: 0 DEBUG: rgid: 1000, egid: 1000, sgid: 1000 DEBUG: apparmor label on snap-confine is: /snap/snapd/19122/usr/lib/snapd/snap-confine DEBUG: apparmor mode is: enforce DEBUG: -- snap startup {"stage":"snap-confine mount namespace start", "time":"1685542907.234526"} DEBUG: creating lock directory /run/snapd/lock (if missing) DEBUG: set_effective_identity uid:0 (change: no), gid:0 (change: yes) DEBUG: opening lock directory /run/snapd/lock DEBUG: set_effective_identity uid:0 (change: no), gid:1000 (change: yes) DEBUG: opening lock file: /run/snapd/lock/.lock DEBUG: set_effective_identity uid:0 (change: no), gid:0 (change: yes) DEBUG: set_effective_identity uid:0 (change: no), gid:1000 (change: yes) DEBUG: sanity timeout initialized and set for 30 seconds DEBUG: acquiring exclusive lock (scope (global), uid 0) DEBUG: sanity timeout reset and disabled DEBUG: ensuring that snap mount directory is shared DEBUG: unsharing snap namespace directory DEBUG: set_effective_identity uid:0 (change: no), gid:0 (change: yes) DEBUG: set_effective_identity uid:0 (change: no), gid:1000 (change: yes) DEBUG: releasing lock 5 DEBUG: opened snap-update-ns executable as file descriptor 5 DEBUG: opened snap-discard-ns executable as file descriptor 6 DEBUG: creating lock directory /run/snapd/lock (if missing) DEBUG: set_effective_identity uid:0 (change: no), gid:0 (change: yes) DEBUG: opening lock directory /run/snapd/lock DEBUG: set_effective_identity uid:0 (change: no), gid:1000 (change: yes) DEBUG: opening lock file: /run/snapd/lock/firefox.lock DEBUG: set_effective_identity uid:0 (change: no), gid:0 (change: yes) DEBUG: set_effective_identity uid:0 (change: no), gid:1000 (change: yes) DEBUG: sanity timeout initialized and set for 30 seconds DEBUG: acquiring exclusive lock (scope firefox, uid 0) DEBUG: sanity timeout reset and disabled DEBUG: initializing mount namespace: firefox DEBUG: setting up device cgroup DEBUG: libudev has current tags support DEBUG: device /sys/devices/pci0000:00/0000:00:02.0/drm/card0 has matching current tag DEBUG: get bpf object at path /sys/fs/bpf/snap/snap_firefox_firefox DEBUG: found existing device map DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: get next key for map 8 DEBUG: found 22 existing entries in devices map DEBUG: delete key for c 136:-1 DEBUG: delete elem in map 8 DEBUG: delete key for c 81:0 DEBUG: delete elem in map 8 DEBUG: delete key for c 1:8 DEBUG: delete elem in map 8 DEBUG: delete key for c 139:-1 DEBUG: delete elem in map 8 DEBUG: delete key for c 226:0 DEBUG: delete elem in map 8 DEBUG: delete key for c 138:-1 DEBUG: delete elem in map 8 DEBUG: delete key for c 1:7 DEBUG: delete elem in map 8 DEBUG: delete key for c 81:1 DEBUG: delete elem in map 8 DEBUG: delete key for c 226:128 DEBUG: delete elem in map 8 DEBUG: delete key for c 5:1 DEBUG: delete elem in map 8 DEBUG: delete key for c 137:-1 DEBUG: delete elem in map 8 DEBUG: delete key for c 141:-1 DEBUG: delete elem in map 8 DEBUG: delete key for c 1:5 DEBUG: delete elem in map 8 DEBUG: delete key for c 5:0 DEBUG: delete elem in map 8 DEBUG: delete key for c 142:-1 DEBUG: delete elem in map 8 DEBUG: delete key for c 5:2 DEBUG: delete elem in map 8 DEBUG: delete key for c 10:239 DEBUG: delete elem in map 8 DEBUG: delete key for c 143:-1 DEBUG: delete elem in map 8 DEBUG: delete key for c 1:9 DEBUG: delete elem in map 8 DEBUG: delete key for c 1:3 DEBUG: delete elem in map 8 DEBUG: delete key for c 10:200 DEBUG: delete elem in map 8 DEBUG: delete key for c 140:-1 DEBUG: delete elem in map 8 DEBUG: load program of type 0xf, 33 instructions DEBUG: v2 allow c 1:3 DEBUG: v2 allow c 1:5 DEBUG: v2 allow c 1:7 DEBUG: v2 allow c 1:8 DEBUG: v2 allow c 1:9 DEBUG: v2 allow c 5:0 DEBUG: v2 allow c 5:1 DEBUG: v2 allow c 5:2 DEBUG: v2 allow c 136:4294967295 DEBUG: v2 allow c 137:4294967295 DEBUG: v2 allow c 138:4294967295 DEBUG: v2 allow c 139:4294967295 DEBUG: v2 allow c 140:4294967295 DEBUG: v2 allow c 141:4294967295 DEBUG: v2 allow c 142:4294967295 DEBUG: v2 allow c 143:4294967295 DEBUG: v2 allow c 10:239 DEBUG: v2 allow c 10:200 DEBUG: inspecting type of device: /dev/dri/card0 DEBUG: v2 allow c 226:0 DEBUG: device /sys/devices/pci0000:00/0000:00:02.0/drm/card0/card0-DP-1 has matching current tag DEBUG: cannot get major/minor numbers for syspath /sys/devices/pci0000:00/0000:00:02.0/drm/card0/card0-DP-1 DEBUG: device /sys/devices/pci0000:00/0000:00:02.0/drm/card0/card0-DP-2 has matching current tag DEBUG: cannot get major/minor numbers for syspath /sys/devices/pci0000:00/0000:00:02.0/drm/card0/card0-DP-2 DEBUG: device /sys/devices/pci0000:00/0000:00:02.0/drm/card0/card0-DP-3 has matching current tag DEBUG: cannot get major/minor numbers for syspath /sys/devices/pci0000:00/0000:00:02.0/drm/card0/card0-DP-3 DEBUG: device /sys/devices/pci0000:00/0000:00:02.0/drm/card0/card0-HDMI-A-1 has matching current tag DEBUG: cannot get major/minor numbers for syspath /sys/devices/pci0000:00/0000:00:02.0/drm/card0/card0-HDMI-A-1 DEBUG: device /sys/devices/pci0000:00/0000:00:02.0/drm/card0/card0-HDMI-A-2 has matching current tag DEBUG: cannot get major/minor numbers for syspath /sys/devices/pci0000:00/0000:00:02.0/drm/card0/card0-HDMI-A-2 DEBUG: device /sys/devices/pci0000:00/0000:00:02.0/drm/card0/card0-HDMI-A-3 has matching current tag DEBUG: cannot get major/minor numbers for syspath /sys/devices/pci0000:00/0000:00:02.0/drm/card0/card0-HDMI-A-3 DEBUG: device /sys/devices/pci0000:00/0000:00:02.0/drm/card0/card0-LVDS-1 has matching current tag DEBUG: cannot get major/minor numbers for syspath /sys/devices/pci0000:00/0000:00:02.0/drm/card0/card0-LVDS-1 DEBUG: device /sys/devices/pci0000:00/0000:00:02.0/drm/card0/card0-VGA-1 has matching current tag DEBUG: cannot get major/minor numbers for syspath /sys/devices/pci0000:00/0000:00:02.0/drm/card0/card0-VGA-1 DEBUG: device /sys/devices/pci0000:00/0000:00:02.0/drm/renderD128 has matching current tag DEBUG: inspecting type of device: /dev/dri/renderD128 DEBUG: v2 allow c 226:128 DEBUG: device /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.6/1-1.6:1.0/video4linux/video0 has matching current tag DEBUG: inspecting type of device: /dev/video0 DEBUG: v2 allow c 81:0 DEBUG: device /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.6/1-1.6:1.0/video4linux/video1 has matching current tag DEBUG: inspecting type of device: /dev/video1 DEBUG: v2 allow c 81:1 DEBUG: device /sys/devices/virtual/mem/full has matching current tag DEBUG: inspecting type of device: /dev/full DEBUG: v2 allow c 1:7 DEBUG: process in cgroup /user.slice/user-1000.slice/user@1000.service/app.slice/snap.firefox.firefox.4e9755f4-b61c-45f2-b80e-f7ab4736c607.scope DEBUG: cgroup /sys/fs/cgroup//user.slice/user-1000.slice/user@1000.service/app.slice/snap.firefox.firefox.4e9755f4-b61c-45f2-b80e-f7ab4736c607.scope opened at 10 DEBUG: attach type 0x6 program 9 to cgroup 10 DEBUG: associated snap application process 110659 with device cgroup snap.firefox.firefox DEBUG: forked support process 110682 DEBUG: changing apparmor hat to mount-namespace-capture-helper DEBUG: helper process waiting for command DEBUG: sanity timeout initialized and set for 30 seconds DEBUG: block device of snap core20, revision 1891 is 7:4 DEBUG: sanity timeout initialized and set for 30 seconds DEBUG: joining preserved mount namespace for inspection DEBUG: found base snap device 7:4 on /usr DEBUG: sanity timeout reset and disabled DEBUG: preserved mount is not stale, reusing DEBUG: joined preserved mount namespace firefox DEBUG: joining preserved per-user mount namespace DEBUG: unsharing the mount namespace (per-user) DEBUG: sc_setup_user_mounts: firefox DEBUG: performing operation: (disabled) use debug build to see details DEBUG: set_effective_identity uid:0 (change: no), gid:0 (change: yes) DEBUG: calling snapd tool snap-update-ns DEBUG: waiting for snapd tool snap-update-ns to terminate DEBUG: requesting changing of apparmor profile on next exec to snap-update-ns.firefox logger.go:92: DEBUG: current mount entries logger.go:92: DEBUG: desired mount entries (sorted) logger.go:92: DEBUG: - /run/user/1000/doc/by-app/snap.firefox /run/user/1000/doc none bind,rw,x-snapd.ignore-missing 0 0 logger.go:92: DEBUG: desiredIDs: map[/run/user/1000/doc:true] logger.go:92: DEBUG: reuse: map[] logger.go:92: DEBUG: processing mount entries logger.go:92: DEBUG: adding independent entry: /run/user/1000/doc/by-app/snap.firefox /run/user/1000/doc none bind,rw,x-snapd.ignore-missing 0 0 logger.go:92: DEBUG: all mimics: logger.go:92: DEBUG: mount entries ordered as they will be applied logger.go:92: DEBUG: - /run/user/1000/doc/by-app/snap.firefox /run/user/1000/doc none bind,rw,x-snapd.ignore-missing 0 0 logger.go:92: DEBUG: mount name:"/run/user/1000/doc/by-app/snap.firefox" dir:"/run/user/1000/doc" type:"none" opts:MS_BIND unparsed:"" (error: ) DEBUG: snap-update-ns finished successfully DEBUG: set_effective_identity uid:0 (change: no), gid:1000 (change: yes) DEBUG: NOT preserving per-user mount namespace DEBUG: releasing lock 7 DEBUG: sending command 0 to helper process (pid: 110682) DEBUG: waiting for response from helper DEBUG: sanity timeout reset and disabled DEBUG: helper process received command 0 DEBUG: helper process exiting DEBUG: waiting for the helper process to exit DEBUG: helper process exited normally DEBUG: resetting PATH to values in sync with core snap DEBUG: -- snap startup {"stage":"snap-confine mount namespace finish", "time":"1685542907.247602"} DEBUG: set_effective_identity uid:1000 (change: yes), gid:1000 (change: yes) DEBUG: creating user data directory: /home/yuzer/snap/firefox/2710 DEBUG: requesting changing of apparmor profile on next exec to snap.firefox.firefox DEBUG: ruid: 1000, euid: 1000, suid: 0 DEBUG: setting capabilities bounding set DEBUG: regaining SYS_ADMIN DEBUG: loading bpf program for security tag snap.firefox.firefox DEBUG: read 6624 bytes from /var/lib/snapd/seccomp/bpf//snap.firefox.firefox.bin DEBUG: read 152 bytes from /var/lib/snapd/seccomp/bpf/global.bin DEBUG: clearing SYS_ADMIN DEBUG: execv(/usr/lib/snapd/snap-exec, /usr/lib/snapd/snap-exec...) DEBUG: argv[1] = firefox DEBUG: argv[2] = --profile-manager DEBUG: umask restored to 02 DEBUG: working directory restored to /home/yuzer DEBUG: -- snap startup {"stage":"snap-confine to snap-exec", "time":"1685542907.249237"} 2023/05/31 15:21:47.253557 logger.go:92: DEBUG: -- snap startup {"stage":"snap-exec to app", "time":"1685542907.253550"} ATTENTION: default value of option mesa_glthread overridden by environment. ATTENTION: default value of option mesa_glthread overridden by environment. ATTENTION: default value of option mesa_glthread overridden by environment. ATTENTION: default value of option mesa_glthread overridden by environment. ATTENTION: default value of option mesa_glthread overridden by environment. ATTENTION: default value of option mesa_glthread overridden by environment. ATTENTION: default value of option mesa_glthread overridden by environment. ATTENTION: default value of option mesa_glthread overridden by environment. ._.