$ /tmp/stacker-bins/stacker-v0.20.1-14-g8bcaa49 --debug build stacker version v0.20.1-14-g8bcaa49 usernsexec-ing [usernsexec u 0 601270 1 1 200001 65535 g 0 2000 1 1 200001 65535 -- /tmp/stacker-bins/stacker-v0.20.1-14-g8bcaa49 --internal-userns --debug build] stacker version v0.20.1-14-g8bcaa49 initializing stacker recipe: stacker.yaml substituting $STACKER_ROOTFS_DIR to /home/scmoser/test/roots substituting $STACKER_STACKER_DIR to /home/scmoser/test/.stacker substituting $STACKER_OCI_DIR to /home/scmoser/test/oci stacker build order: 0 build /home/scmoser/test/stacker.yaml: requires: [] building: 0 /home/scmoser/test/stacker.yaml substituting $STACKER_ROOTFS_DIR to /home/scmoser/test/roots substituting $STACKER_STACKER_DIR to /home/scmoser/test/.stacker substituting $STACKER_OCI_DIR to /home/scmoser/test/oci Dependency Order [build-env] preparing image build-env... loading docker://aci-docker-reg.cisco.com/c3/minbase:1.0.7 Copying blob 0dab16140ba4 skipped: already exists Copying blob 6754ae8b4482 skipped: already exists Copying config 7fe0fbc9f6 done Writing manifest to image destination Storing signatures unpacking to /home/scmoser/test/roots/build-env lxc rootfs overlay arg overlayfs:/home/scmoser/test/roots/sha256_6754ae8b4482caf0381ba778a105a956a9dd703193ecf25de47a3c8c258528cd/overlay:/home/scmoser/test/roots/sha256_0dab16140ba45e4d16857a1c36b0ba23da0e4df9eab34617591baf0f714e0744/overlay:/home/scmoser/test/roots/build-env/overlay stacker version v0.20.1-14-g8bcaa49 stacker subcommand: [/tmp/stacker-bins/stacker-v0.20.1-14-g8bcaa49 --oci-dir /home/scmoser/test/oci --roots-dir /home/scmoser/test/roots --stacker-dir /home/scmoser/test/.stacker --storage-type overlay --internal-userns --debug internal-go check-aa-profile lxc-container-default-cgns] error: write /proc/30260/attr/current: invalid argument main.doCheckAAProfile /stacker-tree/cmd/internal_go.go:128 github.com/urfave/cli.HandleAction /stacker-tree/.build/gopath/pkg/mod/github.com/urfave/cli@v1.22.5/app.go:524 github.com/urfave/cli.Command.Run /stacker-tree/.build/gopath/pkg/mod/github.com/urfave/cli@v1.22.5/command.go:173 github.com/urfave/cli.(*App).RunAsSubcommand /stacker-tree/.build/gopath/pkg/mod/github.com/urfave/cli@v1.22.5/app.go:405 github.com/urfave/cli.Command.startApp /stacker-tree/.build/gopath/pkg/mod/github.com/urfave/cli@v1.22.5/command.go:372 github.com/urfave/cli.Command.Run /stacker-tree/.build/gopath/pkg/mod/github.com/urfave/cli@v1.22.5/command.go:102 github.com/urfave/cli.(*App).Run /stacker-tree/.build/gopath/pkg/mod/github.com/urfave/cli@v1.22.5/app.go:277 main.main /stacker-tree/cmd/main.go:297 runtime.main /usr/lib/go/src/runtime/proc.go:250 runtime.goexit /usr/lib/go/src/runtime/asm_amd64.s:1571 couldn't find AppArmor profile lxc-container-default-cgns bind mounting /home/scmoser/test/.stacker/imports/build-env into container failed to start container build-env lxc build-env 20220506131310.677 ERROR overlay - storage/overlay.c:ovl_mount:490 - Invalid argument - Failed to mount "/home/scmoser/test/roots/sha256_6754ae8b4482caf0381ba778a105a956a9dd703193ecf25de47a3c8c258528cd/overlay:/home/scmoser/test/roots/sha256_0dab16140ba45e4d16857a1c36b0ba23da0e4df9eab34617591baf0f714e0744/overlay" on "/home/scmoser/test/.stacker/rootfsPivot" with options "upperdir=/home/scmoser/test/roots/build-env/overlay,lowerdir=/home/scmoser/test/roots/sha256_6754ae8b4482caf0381ba778a105a956a9dd703193ecf25de47a3c8c258528cd/overlay:/home/scmoser/test/roots/sha256_0dab16140ba45e4d16857a1c36b0ba23da0e4df9eab34617591baf0f714e0744/overlay" lxc build-env 20220506131310.677 ERROR conf - conf.c:lxc_mount_rootfs:1432 - Failed to mount rootfs "overlayfs:/home/scmoser/test/roots/sha256_6754ae8b4482caf0381ba778a105a956a9dd703193ecf25de47a3c8c258528cd/overlay:/home/scmoser/test/roots/sha256_0dab16140ba45e4d16857a1c36b0ba23da0e4df9eab34617591baf0f714e0744/overlay:/home/scmoser/test/roots/build-env/overlay" onto "/home/scmoser/test/.stacker/rootfsPivot" with options "(null)" lxc build-env 20220506131310.677 ERROR conf - conf.c:lxc_setup_rootfs_prepare_root:3964 - Failed to setup rootfs for lxc build-env 20220506131310.677 ERROR conf - conf.c:lxc_setup:4331 - Failed to setup rootfs lxc build-env 20220506131310.677 ERROR start - start.c:do_start:1275 - Failed to setup container "build-env" lxc build-env 20220506131310.677 ERROR sync - sync.c:sync_wait:34 - An error occurred in another process (expected sequence number 3) lxc build-env 20220506131310.679 ERROR start - start.c:__lxc_start:2074 - Failed to spawn container "build-env" error: run commands failed: execute failed: exit status 1 github.com/project-stacker/stacker.(*Builder).build /stacker-tree/build.go:437 github.com/project-stacker/stacker.(*Builder).BuildMultiple /stacker-tree/build.go:534 main.doBuild /stacker-tree/cmd/build.go:106 github.com/urfave/cli.HandleAction /stacker-tree/.build/gopath/pkg/mod/github.com/urfave/cli@v1.22.5/app.go:524 github.com/urfave/cli.Command.Run /stacker-tree/.build/gopath/pkg/mod/github.com/urfave/cli@v1.22.5/command.go:173 github.com/urfave/cli.(*App).Run /stacker-tree/.build/gopath/pkg/mod/github.com/urfave/cli@v1.22.5/app.go:277 main.main /stacker-tree/cmd/main.go:297 runtime.main /usr/lib/go/src/runtime/proc.go:250 runtime.goexit /usr/lib/go/src/runtime/asm_amd64.s:1571 error: exit status 1 github.com/project-stacker/stacker/container.MaybeRunInUserns /stacker-tree/container/userns.go:90 main.main.func3 /stacker-tree/cmd/main.go:292 github.com/urfave/cli.(*App).Run /stacker-tree/.build/gopath/pkg/mod/github.com/urfave/cli@v1.22.5/app.go:264 main.main /stacker-tree/cmd/main.go:297 runtime.main /usr/lib/go/src/runtime/proc.go:250 runtime.goexit /usr/lib/go/src/runtime/asm_amd64.s:1571 $ cat .stacker/lxc.log lxc 20220506131305.431 TRACE lxccontainer - lxccontainer.c:do_lxcapi_save_config:2672 - Saved config file "/tmp/stacker_build-env_run527100866" lxc build-env 20220506131310.658 TRACE commands - commands.c:lxc_server_init:2078 - Created abstract unix socket "/home/scmoser/test/roots/build-env/command" lxc build-env 20220506131310.658 TRACE start - start.c:lxc_init_handler:757 - Unix domain socket 3 for command server is ready lxc build-env 20220506131310.659 TRACE execute - execute.c:lxc_execute:49 - Doing lxc_execute lxc build-env 20220506131310.659 INFO lsm - lsm/lsm.c:lsm_init_static:38 - Initialized LSM security driver nop lxc build-env 20220506131310.659 TRACE start - start.c:lxc_init:781 - Initialized LSM lxc build-env 20220506131310.659 TRACE start - start.c:lxc_serve_state_clients:486 - Set container state to STARTING lxc build-env 20220506131310.659 TRACE start - start.c:lxc_serve_state_clients:489 - No state clients registered lxc build-env 20220506131310.659 INFO utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment lxc build-env 20220506131310.659 TRACE start - start.c:lxc_init:787 - Set container state to "STARTING" lxc build-env 20220506131310.659 TRACE start - start.c:lxc_init:843 - Set environment variables lxc build-env 20220506131310.659 TRACE start - start.c:lxc_init:848 - Ran pre-start hooks lxc build-env 20220506131310.659 TRACE start - start.c:setup_signal_fd:373 - Created signal file descriptor 6 lxc build-env 20220506131310.659 TRACE start - start.c:lxc_init:861 - Set up signal fd lxc build-env 20220506131310.659 INFO cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3092 - Permission denied - Legacy hierarchy not writable, skipping lxc build-env 20220506131310.659 INFO cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3092 - Permission denied - Legacy hierarchy not writable, skipping lxc build-env 20220506131310.659 INFO cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3092 - Permission denied - Legacy hierarchy not writable, skipping lxc build-env 20220506131310.659 INFO cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3092 - Permission denied - Legacy hierarchy not writable, skipping lxc build-env 20220506131310.659 INFO cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3092 - Permission denied - Legacy hierarchy not writable, skipping lxc build-env 20220506131310.659 INFO cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3092 - Permission denied - Legacy hierarchy not writable, skipping lxc build-env 20220506131310.659 INFO cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3092 - Permission denied - Legacy hierarchy not writable, skipping lxc build-env 20220506131310.659 INFO cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3092 - Permission denied - Legacy hierarchy not writable, skipping lxc build-env 20220506131310.659 INFO cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3092 - Permission denied - Legacy hierarchy not writable, skipping lxc build-env 20220506131310.659 INFO cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3092 - Permission denied - Legacy hierarchy not writable, skipping lxc build-env 20220506131310.659 INFO cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3092 - Permission denied - Legacy hierarchy not writable, skipping lxc build-env 20220506131310.659 INFO cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3092 - Permission denied - Legacy hierarchy not writable, skipping lxc build-env 20220506131310.659 INFO cgfsng - cgroups/cgfsng.c:legacy_hierarchy_delegated:3092 - Permission denied - Legacy hierarchy not writable, skipping lxc build-env 20220506131310.660 TRACE cgroup - cgroups/cgroup.c:cgroup_init:40 - Initialized cgroup driver cgfsng lxc build-env 20220506131310.660 TRACE cgroup - cgroups/cgroup.c:cgroup_init:47 - Unified cgroup layout lxc build-env 20220506131310.660 TRACE start - start.c:lxc_init:868 - Initialized cgroup driver lxc build-env 20220506131310.660 TRACE start - start.c:lxc_init:875 - Read seccomp policy lxc build-env 20220506131310.660 TRACE start - start.c:lxc_init:882 - Initialized LSM lxc build-env 20220506131310.660 INFO start - start.c:lxc_init:884 - Container "build-env" is initialized lxc build-env 20220506131310.660 DEBUG storage - storage/storage.c:get_storage_by_name:209 - Detected rootfs type "overlay" lxc build-env 20220506131310.660 TRACE conf - conf.c:lxc_rootfs_init:560 - Invalid argument - Not pinning on stacking filesystem lxc build-env 20220506131310.660 TRACE sync - sync.c:lxc_sync_init:139 - Initialized synchronization infrastructure lxc build-env 20220506131310.660 TRACE start - start.c:lxc_spawn:1745 - Cloned child process 30271 lxc build-env 20220506131310.660 TRACE start - start.c:core_scheduling:1563 - No new core scheduling domain requested lxc build-env 20220506131310.660 TRACE utils - utils.c:lxc_can_use_pidfd:1819 - Kernel supports pidfds lxc build-env 20220506131310.660 INFO start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWNS lxc build-env 20220506131310.660 INFO start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWPID lxc build-env 20220506131310.660 INFO start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWUTS lxc build-env 20220506131310.660 INFO start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWIPC lxc build-env 20220506131310.661 INFO start - start.c:lxc_spawn:1765 - Cloned CLONE_NEWCGROUP lxc build-env 20220506131310.661 DEBUG start - start.c:lxc_try_preserve_namespace:139 - Preserved mnt namespace via fd 13 and stashed path as mnt:/proc/30270/fd/13 lxc build-env 20220506131310.661 DEBUG start - start.c:lxc_try_preserve_namespace:139 - Preserved pid namespace via fd 14 and stashed path as pid:/proc/30270/fd/14 lxc build-env 20220506131310.661 DEBUG start - start.c:lxc_try_preserve_namespace:139 - Preserved uts namespace via fd 15 and stashed path as uts:/proc/30270/fd/15 lxc build-env 20220506131310.661 DEBUG start - start.c:lxc_try_preserve_namespace:139 - Preserved ipc namespace via fd 16 and stashed path as ipc:/proc/30270/fd/16 lxc build-env 20220506131310.661 DEBUG start - start.c:lxc_try_preserve_namespace:139 - Preserved cgroup namespace via fd 17 and stashed path as cgroup:/proc/30270/fd/17 lxc build-env 20220506131310.661 TRACE sync - sync.c:lxc_sync_barrier_child:97 - Parent waking child with sequence startup and waiting with sequence configure lxc build-env 20220506131310.661 TRACE sync - sync.c:lxc_sync_wait_parent:110 - Child waiting for parent with sequence startup lxc build-env 20220506131310.661 TRACE sync - sync.c:lxc_sync_wake_parent:104 - Child waking parent with sequence configure lxc build-env 20220506131310.661 TRACE conf - conf.c:lxc_set_environment:5753 - Set environment variable: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin lxc build-env 20220506131310.661 DEBUG start - start.c:lxc_try_preserve_namespace:139 - Preserved net namespace via fd 4 and stashed path as net:/proc/30270/fd/4 lxc build-env 20220506131310.661 TRACE conf - conf.c:lxc_set_environment:5753 - Set environment variable: TERM=xterm lxc build-env 20220506131310.661 TRACE conf - conf.c:lxc_set_environment:5753 - Set environment variable: STACKER_LAYER_NAME=build-env lxc build-env 20220506131310.661 TRACE sync - sync.c:lxc_sync_wait_parent:110 - Child waiting for parent with sequence post-configure lxc build-env 20220506131310.661 WARN start - start.c:lxc_spawn:1835 - Operation not permitted - Failed to allocate new network namespace id lxc build-env 20220506131310.661 TRACE sync - sync.c:lxc_sync_wake_child:122 - Parent waking child with sequence post-configure lxc build-env 20220506131310.661 TRACE sync - sync.c:lxc_sync_wait_child:116 - Parent waiting for child with sequence idmapped-mounts lxc build-env 20220506131310.662 TRACE conf - conf.c:turn_into_dependent_mounts:3923 - Turned all mount table entries into dependent mount lxc build-env 20220506131310.674 INFO overlay - storage/overlay.c:ovl_mount:481 - Permission denied - Failed to mount "/home/scmoser/test/roots/sha256_6754ae8b4482caf0381ba778a105a956a9dd703193ecf25de47a3c8c258528cd/overlay:/home/scmoser/test/roots/sha256_0dab16140ba45e4d16857a1c36b0ba23da0e4df9eab34617591baf0f714e0744/overlay" on "/home/scmoser/test/.stacker/rootfsPivot" with options "upperdir=/home/scmoser/test/roots/build-env/overlay,lowerdir=/home/scmoser/test/roots/sha256_6754ae8b4482caf0381ba778a105a956a9dd703193ecf25de47a3c8c258528cd/overlay:/home/scmoser/test/roots/sha256_0dab16140ba45e4d16857a1c36b0ba23da0e4df9eab34617591baf0f714e0744/overlay,workdir=/home/scmoser/test/roots/build-env/work". Retrying without workdir lxc build-env 20220506131310.677 ERROR overlay - storage/overlay.c:ovl_mount:490 - Invalid argument - Failed to mount "/home/scmoser/test/roots/sha256_6754ae8b4482caf0381ba778a105a956a9dd703193ecf25de47a3c8c258528cd/overlay:/home/scmoser/test/roots/sha256_0dab16140ba45e4d16857a1c36b0ba23da0e4df9eab34617591baf0f714e0744/overlay" on "/home/scmoser/test/.stacker/rootfsPivot" with options "upperdir=/home/scmoser/test/roots/build-env/overlay,lowerdir=/home/scmoser/test/roots/sha256_6754ae8b4482caf0381ba778a105a956a9dd703193ecf25de47a3c8c258528cd/overlay:/home/scmoser/test/roots/sha256_0dab16140ba45e4d16857a1c36b0ba23da0e4df9eab34617591baf0f714e0744/overlay" lxc build-env 20220506131310.677 ERROR conf - conf.c:lxc_mount_rootfs:1432 - Failed to mount rootfs "overlayfs:/home/scmoser/test/roots/sha256_6754ae8b4482caf0381ba778a105a956a9dd703193ecf25de47a3c8c258528cd/overlay:/home/scmoser/test/roots/sha256_0dab16140ba45e4d16857a1c36b0ba23da0e4df9eab34617591baf0f714e0744/overlay:/home/scmoser/test/roots/build-env/overlay" onto "/home/scmoser/test/.stacker/rootfsPivot" with options "(null)" lxc build-env 20220506131310.677 ERROR conf - conf.c:lxc_setup_rootfs_prepare_root:3964 - Failed to setup rootfs for lxc build-env 20220506131310.677 ERROR conf - conf.c:lxc_setup:4331 - Failed to setup rootfs lxc build-env 20220506131310.677 ERROR start - start.c:do_start:1275 - Failed to setup container "build-env" lxc build-env 20220506131310.677 TRACE sync - sync.c:lxc_sync_wake_parent:104 - Child waking parent with sequence error lxc build-env 20220506131310.677 ERROR sync - sync.c:sync_wait:34 - An error occurred in another process (expected sequence number 3) lxc build-env 20220506131310.677 TRACE start - start.c:lxc_serve_state_clients:486 - Set container state to ABORTING lxc build-env 20220506131310.677 TRACE start - start.c:lxc_serve_state_clients:489 - No state clients registered lxc build-env 20220506131310.677 INFO utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment lxc build-env 20220506131310.679 ERROR start - start.c:__lxc_start:2074 - Failed to spawn container "build-env" lxc build-env 20220506131310.679 TRACE start - start.c:lxc_serve_state_clients:486 - Set container state to ABORTING lxc build-env 20220506131310.679 TRACE start - start.c:lxc_serve_state_clients:489 - No state clients registered lxc build-env 20220506131310.679 INFO utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment lxc build-env 20220506131310.679 WARN start - start.c:lxc_abort:1039 - No such process - Failed to send SIGKILL via pidfd 12 for process 30271 lxc build-env 20220506131310.679 TRACE start - start.c:lxc_serve_state_clients:486 - Set container state to STOPPING lxc build-env 20220506131310.679 TRACE start - start.c:lxc_serve_state_clients:489 - No state clients registered lxc build-env 20220506131310.679 INFO utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment lxc build-env 20220506131310.679 TRACE start - start.c:lxc_end:966 - Closed command socket lxc build-env 20220506131310.679 INFO utils - utils.c:get_rundir:260 - XDG_RUNTIME_DIR isn't set in the environment lxc build-env 20220506131310.679 TRACE start - start.c:lxc_end:977 - Set container state to "STOPPED"