gistfile1.txt

lxc-start arch 20181112132015.719 INFO     utils - utils.c:get_rundir:264 - XDG_RUNTIME_DIR isn't set in the environment.
lxc-start arch 20181112132015.722 INFO     confile - confile.c:set_config_idmaps:1542 - Read uid map: type u nsid 0 hostid 100000 range 1000
lxc-start arch 20181112132015.722 INFO     confile - confile.c:set_config_idmaps:1542 - Read uid map: type g nsid 0 hostid 100000 range 1000
lxc-start arch 20181112132015.724 INFO     lsm - lsm/lsm.c:lsm_init:47 - LSM security driver AppArmor
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:757 - Processing "reject_force_umount"
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:do_resolve_add_rule:503 - Set seccomp rule to reject force umounts
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:934 - Added native rule for arch 0 for reject_force_umount action 0(kill)
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:do_resolve_add_rule:503 - Set seccomp rule to reject force umounts
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:943 - Added compat rule for arch 1073741827 for reject_force_umount action 0(kill)
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:do_resolve_add_rule:503 - Set seccomp rule to reject force umounts
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:953 - Added compat rule for arch 1073741886 for reject_force_umount action 0(kill)
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:do_resolve_add_rule:503 - Set seccomp rule to reject force umounts
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:963 - Added native rule for arch -1073741762 for reject_force_umount action 0(kill)
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:757 - Processing "[all]"
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:757 - Processing "kexec_load errno 1"
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:934 - Added native rule for arch 0 for kexec_load action 327681(errno)
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:943 - Added compat rule for arch 1073741827 for kexec_load action 327681(errno)
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:953 - Added compat rule for arch 1073741886 for kexec_load action 327681(errno)
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:963 - Added native rule for arch -1073741762 for kexec_load action 327681(errno)
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:757 - Processing "open_by_handle_at errno 1"
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:934 - Added native rule for arch 0 for open_by_handle_at action 327681(errno)
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:943 - Added compat rule for arch 1073741827 for open_by_handle_at action 327681(errno)
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:953 - Added compat rule for arch 1073741886 for open_by_handle_at action 327681(errno)
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:963 - Added native rule for arch -1073741762 for open_by_handle_at action 327681(errno)
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:757 - Processing "init_module errno 1"
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:934 - Added native rule for arch 0 for init_module action 327681(errno)
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:943 - Added compat rule for arch 1073741827 for init_module action 327681(errno)
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:953 - Added compat rule for arch 1073741886 for init_module action 327681(errno)
lxc-start arch 20181112132015.726 INFO     seccomp - seccomp.c:parse_config_v2:963 - Added native rule for arch -1073741762 for init_module action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:757 - Processing "finit_module errno 1"
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:934 - Added native rule for arch 0 for finit_module action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:943 - Added compat rule for arch 1073741827 for finit_module action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:953 - Added compat rule for arch 1073741886 for finit_module action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:963 - Added native rule for arch -1073741762 for finit_module action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:757 - Processing "delete_module errno 1"
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:934 - Added native rule for arch 0 for delete_module action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:943 - Added compat rule for arch 1073741827 for delete_module action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:953 - Added compat rule for arch 1073741886 for delete_module action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:963 - Added native rule for arch -1073741762 for delete_module action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:757 - Processing "add_key errno 1"
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:934 - Added native rule for arch 0 for add_key action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:943 - Added compat rule for arch 1073741827 for add_key action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:953 - Added compat rule for arch 1073741886 for add_key action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:963 - Added native rule for arch -1073741762 for add_key action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:757 - Processing "keyctl errno 1"
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:934 - Added native rule for arch 0 for keyctl action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:943 - Added compat rule for arch 1073741827 for keyctl action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:953 - Added compat rule for arch 1073741886 for keyctl action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:963 - Added native rule for arch -1073741762 for keyctl action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:757 - Processing "request_key errno 1"
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:934 - Added native rule for arch 0 for request_key action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:943 - Added compat rule for arch 1073741827 for request_key action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:953 - Added compat rule for arch 1073741886 for request_key action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:963 - Added native rule for arch -1073741762 for request_key action 327681(errno)
lxc-start arch 20181112132015.727 INFO     seccomp - seccomp.c:parse_config_v2:967 - Merging compat seccomp contexts into main context
lxc-start arch 20181112132015.727 INFO     utils - utils.c:get_rundir:264 - XDG_RUNTIME_DIR isn't set in the environment.
lxc-start arch 20181112132015.728 INFO     conf - conf.c:run_script_argv:376 - Executing script "/run/osctl/pools/tank/hooks/ct-pre-start" for container "arch"
lxc-start arch 20181112132015.993 DEBUG    terminal - terminal.c:lxc_terminal_peer_default:711 - Using terminal "/dev/tty" as proxy
lxc-start arch 20181112132015.993 DEBUG    terminal - terminal.c:lxc_terminal_signal_init:189 - Created signal fd 9
lxc-start arch 20181112132015.993 DEBUG    terminal - terminal.c:lxc_terminal_winsz:87 - Set window size to 80 columns and 24 rows
lxc-start arch 20181112132015.993 DEBUG    conf - conf.c:chown_mapped_root:3172 - trying to chown "/dev/pts/0" to 3000
lxc-start arch 20181112132016.449 INFO     start - start.c:lxc_init:866 - Container "arch" is initialized
lxc-start arch 20181112132016.461 DEBUG    cgfsng - cgroups/cgfsng.c:cg_legacy_handle_cpuset_hierarchy:613 - "cgroup.clone_children" was already set to "1"
lxc-start arch 20181112132016.532 INFO     start - start.c:lxc_spawn:1657 - Cloned CLONE_NEWUSER
lxc-start arch 20181112132016.534 INFO     start - start.c:lxc_spawn:1657 - Cloned CLONE_NEWNS
lxc-start arch 20181112132016.536 INFO     start - start.c:lxc_spawn:1657 - Cloned CLONE_NEWPID
lxc-start arch 20181112132016.537 INFO     start - start.c:lxc_spawn:1657 - Cloned CLONE_NEWUTS
lxc-start arch 20181112132016.538 INFO     start - start.c:lxc_spawn:1657 - Cloned CLONE_NEWIPC
lxc-start arch 20181112132016.540 DEBUG    start - start.c:lxc_try_preserve_namespaces:205 - Preserved user namespace via fd 14
lxc-start arch 20181112132016.541 DEBUG    start - start.c:lxc_try_preserve_namespaces:205 - Preserved mnt namespace via fd 15
lxc-start arch 20181112132016.543 DEBUG    start - start.c:lxc_try_preserve_namespaces:205 - Preserved pid namespace via fd 16
lxc-start arch 20181112132016.545 DEBUG    start - start.c:lxc_try_preserve_namespaces:205 - Preserved uts namespace via fd 17
lxc-start arch 20181112132016.546 DEBUG    start - start.c:lxc_try_preserve_namespaces:205 - Preserved ipc namespace via fd 18
lxc-start arch 20181112132016.550 DEBUG    conf - conf.c:idmaptool_on_path_and_privileged:2842 - The binary "/run/current-system/sw/bin/newuidmap" does have the setuid bit set
lxc-start arch 20181112132016.554 DEBUG    conf - conf.c:idmaptool_on_path_and_privileged:2842 - The binary "/run/current-system/sw/bin/newgidmap" does have the setuid bit set
lxc-start arch 20181112132016.555 DEBUG    conf - conf.c:lxc_map_ids:2934 - Functional newuidmap and newgidmap binary found
lxc-start arch 20181112132016.117 INFO     start - start.c:do_start:1105 - Unshared CLONE_NEWNET
lxc-start arch 20181112132016.121 DEBUG    conf - conf.c:idmaptool_on_path_and_privileged:2842 - The binary "/run/current-system/sw/bin/newuidmap" does have the setuid bit set
lxc-start arch 20181112132016.121 DEBUG    conf - conf.c:idmaptool_on_path_and_privileged:2842 - The binary "/run/current-system/sw/bin/newgidmap" does have the setuid bit set
lxc-start arch 20181112132016.121 DEBUG    conf - conf.c:lxc_map_ids:2934 - Functional newuidmap and newgidmap binary found
lxc-start arch 20181112132016.181 DEBUG    start - start.c:lxc_spawn:1711 - Preserved net namespace via fd 10
lxc-start arch 20181112132016.182 DEBUG    start - start.c:lxc_spawn:1716 - Failed to allocate new network namespace id
lxc-start arch 20181112132016.182 NOTICE   utils - utils.c:lxc_switch_uid_gid:1326 - Switched to gid 0.
lxc-start arch 20181112132016.182 NOTICE   utils - utils.c:lxc_switch_uid_gid:1332 - Switched to uid 0.
lxc-start arch 20181112132016.182 NOTICE   utils - utils.c:lxc_setgroups:1344 - Dropped additional groups.
lxc-start arch 20181112132016.183 INFO     start - start.c:do_start:1213 - Unshared CLONE_NEWCGROUP
lxc-start arch 20181112132016.184 INFO     conf - conf.c:run_script_argv:376 - Executing script "/run/osctl/pools/tank/hooks/ct-pre-mount" for container "arch"
lxc-start arch 20181112132016.190 DEBUG    storage - storage/storage.c:storage_query:251 - Detected rootfs type "dir"
lxc-start arch 20181112132016.191 DEBUG    conf - conf.c:lxc_mount_rootfs:1343 - Mounted rootfs "/tank/ct/arch/private" onto "/var/lib/lxc/rootfs" with options "(null)"
lxc-start arch 20181112132016.191 INFO     conf - conf.c:setup_utsname:802 - Set hostname to "arch"
lxc-start arch 20181112132016.191 INFO     conf - conf.c:mount_autodev:1129 - Preparing "/dev"
lxc-start arch 20181112132016.191 INFO     conf - conf.c:mount_autodev:1176 - Prepared "/dev"
lxc-start arch 20181112132016.191 INFO     conf - conf.c:run_script_argv:376 - Executing script "/nix/store/vhkvvfbf9nvdpjzp27s2ahww3p4pw1lp-lxcfs-3.0.2/share/lxcfs/lxc.mount.hook" for container "arch"
lxc-start arch 20181112132016.240 INFO     conf - conf.c:run_script_argv:376 - Executing script "/run/osctl/pools/tank/hooks/ct-post-mount" for container "arch"
lxc-start arch 20181112132016.385 INFO     conf - conf.c:run_script_argv:376 - Executing script "/run/osctl/pools/tank/hooks/ct-autodev" for container "arch"
lxc-start arch 20181112132016.599 INFO     conf - conf.c:lxc_fill_autodev:1220 - Populating "/dev"
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:lxc_fill_autodev:1235 - Created device node "/var/lib/lxc/rootfs/dev/full"
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:lxc_fill_autodev:1235 - Created device node "/var/lib/lxc/rootfs/dev/null"
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:lxc_fill_autodev:1235 - Created device node "/var/lib/lxc/rootfs/dev/random"
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:lxc_fill_autodev:1235 - Created device node "/var/lib/lxc/rootfs/dev/tty"
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:lxc_fill_autodev:1235 - Created device node "/var/lib/lxc/rootfs/dev/urandom"
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:lxc_fill_autodev:1235 - Created device node "/var/lib/lxc/rootfs/dev/zero"
lxc-start arch 20181112132016.599 INFO     conf - conf.c:lxc_fill_autodev:1297 - Populated "/dev"
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:mount_entry:2031 - Remounting "/sys/fs/fuse/connections" on "/var/lib/lxc/rootfs/sys/fs/fuse/connections" to respect bind or remount options
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:mount_entry:2052 - Flags for "/sys/fs/fuse/connections" were 4096, required extra flags are 0
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:mount_entry:2062 - Mountflags already were 4096, skipping remount
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:mount_entry:2106 - Mounted "/sys/fs/fuse/connections" on "/var/lib/lxc/rootfs/sys/fs/fuse/connections" with filesystem type "none"
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:mount_entry:2031 - Remounting "/run/osctl/pools/tank/mounts/arch" on "/var/lib/lxc/rootfs/dev/.osctl-mount-helper" to respect bind or remount options
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:mount_entry:2052 - Flags for "/run/osctl/pools/tank/mounts/arch" were 4096, required extra flags are 1
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:mount_entry:2106 - Mounted "/run/osctl/pools/tank/mounts/arch" on "/var/lib/lxc/rootfs/dev/.osctl-mount-helper" with filesystem type "none"
lxc-start arch 20181112132016.599 INFO     conf - conf.c:mount_file_entries:2337 - Finished setting up mounts
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:lxc_setup_dev_console:1775 - Mounted pts device "/dev/pts/0" onto "/var/lib/lxc/rootfs/dev/console"
lxc-start arch 20181112132016.599 INFO     utils - utils.c:lxc_mount_proc_if_needed:1182 - I am 1, /proc/self points to "1"
lxc-start arch 20181112132016.599 WARN     conf - conf.c:lxc_setup_devpts:1620 - Invalid argument - Failed to unmount old devpts instance
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:lxc_setup_devpts:1657 - Mount new devpts instance with options "gid=5,newinstance,ptmxmode=0666,mode=0620,max=1024"
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:lxc_setup_devpts:1676 - Created dummy "/dev/ptmx" file as bind mount target
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:lxc_setup_devpts:1681 - Bind mounted "/dev/pts/ptmx" to "/dev/ptmx"
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:lxc_allocate_ttys:1000 - Created tty "/dev/pts/0" with master fd 11 and slave fd 14
lxc-start arch 20181112132016.599 DEBUG    conf - conf.c:lxc_allocate_ttys:1000 - Created tty "/dev/pts/1" with master fd 15 and slave fd 16
lxc-start arch 20181112132016.600 DEBUG    conf - conf.c:lxc_allocate_ttys:1000 - Created tty "/dev/pts/2" with master fd 17 and slave fd 18
lxc-start arch 20181112132016.600 DEBUG    conf - conf.c:lxc_allocate_ttys:1000 - Created tty "/dev/pts/3" with master fd 19 and slave fd 20
lxc-start arch 20181112132016.600 DEBUG    conf - conf.c:lxc_allocate_ttys:1000 - Created tty "/dev/pts/4" with master fd 21 and slave fd 22
lxc-start arch 20181112132016.600 DEBUG    conf - conf.c:lxc_allocate_ttys:1000 - Created tty "/dev/pts/5" with master fd 23 and slave fd 24
lxc-start arch 20181112132016.600 INFO     conf - conf.c:lxc_allocate_ttys:1016 - Finished creating 6 tty devices
lxc-start arch 20181112132016.600 DEBUG    conf - conf.c:lxc_setup_ttys:951 - Bind mounted "/dev/pts/0" onto "/dev/tty1"
lxc-start arch 20181112132016.600 DEBUG    conf - conf.c:lxc_setup_ttys:951 - Bind mounted "/dev/pts/1" onto "/dev/tty2"
lxc-start arch 20181112132016.600 DEBUG    conf - conf.c:lxc_setup_ttys:951 - Bind mounted "/dev/pts/2" onto "/dev/tty3"
lxc-start arch 20181112132016.600 DEBUG    conf - conf.c:lxc_setup_ttys:951 - Bind mounted "/dev/pts/3" onto "/dev/tty4"
lxc-start arch 20181112132016.600 DEBUG    conf - conf.c:lxc_setup_ttys:951 - Bind mounted "/dev/pts/4" onto "/dev/tty5"
lxc-start arch 20181112132016.600 DEBUG    conf - conf.c:lxc_setup_ttys:951 - Bind mounted "/dev/pts/5" onto "/dev/tty6"
lxc-start arch 20181112132016.600 INFO     conf - conf.c:lxc_setup_ttys:960 - Finished setting up 6 /dev/tty<N> device(s)
lxc-start arch 20181112132016.600 INFO     conf - conf.c:setup_personality:1720 - Set personality to "0x0"
lxc-start arch 20181112132016.600 DEBUG    conf - conf.c:setup_caps:2514 - Capabilities have been setup
lxc-start arch 20181112132016.600 NOTICE   conf - conf.c:lxc_setup:3704 - The container "arch" is set up
lxc-start arch 20181112132016.600 INFO     lsm - lsm/lsm.c:lsm_process_label_set_at:175 - Set AppArmor label to "ct-tank-arch//&:lxc-ct-tank-arch:"
lxc-start arch 20181112132016.600 INFO     apparmor - lsm/apparmor.c:apparmor_process_label_set:249 - Changed apparmor profile to ct-tank-arch//&:lxc-ct-tank-arch:
lxc-start arch 20181112132016.600 DEBUG    start - start.c:lxc_spawn:1788 - Preserved cgroup namespace via fd 19
lxc-start arch 20181112132016.600 INFO     conf - conf.c:run_script_argv:376 - Executing script "/run/osctl/pools/tank/hooks/ct-on-start" for container "arch"
lxc-start arch 20181112132016.740 NOTICE   utils - utils.c:lxc_switch_uid_gid:1326 - Switched to gid 0.
lxc-start arch 20181112132016.740 NOTICE   utils - utils.c:lxc_switch_uid_gid:1332 - Switched to uid 0.
lxc-start arch 20181112132016.740 NOTICE   start - start.c:start:1996 - Exec'ing "/sbin/init"
lxc-start arch 20181112132016.745 NOTICE   start - start.c:post_start:2007 - Started "/sbin/init" with pid "2243"
lxc-start arch 20181112132016.745 INFO     utils - utils.c:get_rundir:264 - XDG_RUNTIME_DIR isn't set in the environment.
lxc-start arch 20181112132016.748 NOTICE   start - start.c:signal_handler:401 - Received 17 from pid 2239 instead of container init 2243