QA » openQA Project

# OPENQA_BASEDIR: /tmp/7v4CAal1xt/full-stack.d

# OPENQA_CONFIG: /tmp/7v4CAal1xt/full-stack.d/config

[INFO] using database schema "public"

[info] [pid:12072] setting database search path to public when registering Minion plugin

ok 1 - assets are prefetched

# Used ports: {}

# Starting WebSocket service. Port: 9527

# Bogus: 0 | No wait: 0

# PID of openqa-websocket: 12076

# Starting WebUI service. Port: 9526

# PID of openqa-webapi: 12077

[info] [pid:12077] setting database search path to public when registering Minion plugin

[info] Listening at "http://127.0.0.1:9527"

Web application available at http://127.0.0.1:9527

# PID of openqa-livehandler: 12156

ok 2 - resultdir "/tmp/7v4CAal1xt/full-stack.d/openqa/testresults" exists

ok 3 - on main page

ok 4 - no one logged in

ok 5 - click_ok'Login clicked'

ok 6 - back on main page

ok 7 - click_ok'disable tour permanently'

ok 8 - click_ok'confirm dismissing tour'

[warn] [pid:12077] Unable to wakeup scheduler: Connection refused

ok 9 - click_ok'Clicked All Tests to look for scheduled job'

ok 10 - tests followed

# Wait for jQuery successful: wait before checking for scheduled job

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

ok 11 - scheduled test page

ok 12 - test 1 is scheduled

ok 13 - no unexpected js warnings after test 1 was scheduled

# Subtest: testhelper

ok 1 - BAIL_OUT invoked

[debug] [pid:12077] Unrecognized referer 'localhost'

# scheduled jobs: [

# {

# 'cluster_jobs' => {

# '1' => {

# 'chained_children' => [],

# 'chained_parents' => [],

# 'directly_chained_children' => [],

# 'directly_chained_parents' => [],

# 'is_parent_or_initial_job' => 1,

# 'ok' => 0,

# 'parallel_children' => [],

# 'parallel_parents' => [],

# 'state' => 'scheduled'

# }

# },

# 'id' => 1,

# 'priority' => 50,

# 'priority_offset' => 0,

# 'state' => 'scheduled',

# 'test' => 'core',

# 'worker_classes' => [

# 'qemu_i386'

# ]

# }

# ]

# free workers: []

ok 2 - fail invoked

1..2

ok 14 - testhelper

ok 15 - Worker started as IPC::Run=HASH(0x55eab0de40c0)

ok 16 - job with worker class qemu_i386 scheduled

[info] [pid:12162] worker 1:

- config file: /tmp/7v4CAal1xt/full-stack.d/config/workers.ini

- worker hostname: 1234cbae1a16

- isotovideo version: 24

- websocket API version: 1

- web UI hosts: http://localhost:9526

- class: qemu_i386,qemu_x86_64

- no cleanup: no

- pool directory: /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1

[info] [pid:12162] Project dir for host http://localhost:9526 is /tmp/7v4CAal1xt/full-stack.d/openqa/share

[info] [pid:12162] Registering with openQA http://localhost:9526

[info] [pid:12162] Establishing ws connection via ws://localhost:9526/api/v1/ws/1

ok 17 - at least one free worker with class qemu_i386 registered

[info] [pid:12162] Registered and connected via websockets with openQA host http://localhost:9526 and worker ID 1

[debug] [pid:12072] +=----------------=+

[debug] [pid:12072] -> Scheduling new jobs.

[debug] [pid:12072] Free workers: 1/1

[debug] [pid:12076] Updating seen of worker 1 from worker_status (free)

[debug] [pid:12072] Scheduled jobs: 1

[debug] [pid:12072] Need to schedule 1 parallel jobs for job 1 (with priority 50)

[debug] [pid:12072] Assigned job '1' to worker ID '1'

[debug] [pid:12072] [Job#1] Prepare for being processed by worker 1

[debug] [pid:12076] Started to send message to 1 for job(s) 1

[debug] [pid:12162] Accepting job 1 from http://localhost:9526.

[debug] [pid:12072] Sent job(s) '1' to worker '1'

[debug] [pid:12072] Scheduler took 0.10719s to perform operations and allocated 1 jobs

[debug] [pid:12162] Setting job 1 from http://localhost:9526 up

[debug] [pid:12072] Allocated: { job => 1, worker => 1 }

[debug] [pid:12162] Preparing Mojo::IOLoop::ReadWriteProcess::Session

[info] [pid:12162] +++ setup notes +++

[info] [pid:12162] Running on 1234cbae1a16:1 (Linux 5.11.0-1027-aws #30~20.04.1-Ubuntu SMP Thu Jan 13 11:46:53 UTC 2022 x86_64)

[debug] [pid:12162] Job settings:

[debug] [pid:12162]

ARCH=i386

BUILD=1

DISTRI=tinycore

FLAVOR=flavor

INTEGRATION_TESTS=1

ISO=Core-7.2.iso

JOBTOKEN=Dlzx3oXeqolMvMJJ

LOG_LEVEL=debug

MACHINE=coolone

NAME=00000001-tinycore-1-flavor-i386-Build1-core@coolone

OPENQA_HOSTNAME=localhost:9526

OPENQA_URL=http://localhost:9526

PAUSE_AT=shutdown

PRJDIR=/tmp/7v4CAal1xt/full-stack.d/openqa/share

PUBLISH_HDD_1=core-hdd.qcow2

QEMU=i386

QEMUPORT=20012

QEMU_NO_FDC_SET=1

QEMU_NO_KVM=1

QEMU_NO_TABLET=1

RETRY_DELAY=5

RETRY_DELAY_IF_WEBUI_BUSY=60

TEST=core

UEFI_PFLASH_VARS=/usr/share/qemu/ovmf-x86_64.bin

VERSION=1

VNC=91

WORKER_CLASS=qemu_i386,qemu_x86_64

WORKER_HOSTNAME=127.0.0.1

WORKER_ID=1

WORKER_INSTANCE=1

[debug] [pid:12162] Linked asset "/tmp/7v4CAal1xt/full-stack.d/openqa/share/factory/iso/Core-7.2.iso" to "/tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/Core-7.2.iso"

[debug] [pid:12162] Symlinked from "/tmp/7v4CAal1xt/full-stack.d/openqa/share/tests/tinycore" to "/tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/tinycore"

[info] [pid:12162] Preparing cgroup to start isotovideo

[warn] [pid:12162] Disabling cgroup usage because cgroup creation failed: mkdir /sys/fs/cgroup/systemd/docker: Permission denied at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/File.pm line 84.

[info] [pid:12162] You can define a custom slice with OPENQA_CGROUP_SLICE or indicating the base mount with MOJO_CGROUP_FS.

[info] [pid:12162] Starting isotovideo container

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 0 of 3240)

[debug] [pid:12162] Registered process:12169

[info] [pid:12169] 12169: WORKING 1

[info] [pid:12162] isotovideo has been started (PID: 12169)

[debug] [pid:12169] +++ worker notes +++

[debug] [pid:12162] Running job 1 from http://localhost:9526: 00000001-tinycore-1-flavor-i386-Build1-core@coolone.

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/1/status

[debug] [pid:12076] Worker 1 accepted job 1

[debug] [pid:12076] Updating seen of worker 1 from worker_status (working)

[debug] [pid:12162] Upload concluded (no current module)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 1 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 2 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 3 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 4 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12156] connecting to os-autoinst command server for job 1 at ws://127.0.0.1:20013/Dlzx3oXeqolMvMJJ/ws

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Asking the worker 1 to start providing livestream for job 1

[debug] [pid:12162] Starting livelog

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/1/status

[debug] [pid:12162] Upload concluded (no current module)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 5 of 3240)

ok 18 - \#nav-item-for-live present

ok 19 - test 1 is running

# Subtest: wait until developer console becomes available

[debug] [pid:12077] Asking worker 1 to stop providing livestream for job 1

[debug] [pid:12162] Stopping livelog

# Waiting for 'URL for os-autoinst cmd srv' to become available

# waiting for worker to propagate URL for os-autoinst cmd srv

# Wait for jQuery successful: developer console available

ok 1 - No unexpected js warnings

ok 2 - found connection opened at 112

1..2

ok 20 - wait until developer console becomes available

# Subtest: pause at certain test

ok 1 - No unexpected js warnings

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/1/status

[debug] [pid:12162] Upload concluded (no current module)

# waiting for paused, developer console contains:

# status: Connecting to ws://127.0.0.1:20013/Dlzx3oXeqolMvMJJ/ws

# status: Using proxy: no

# status: Connection opened

# ==> {"cmd":"status"}

# Wait for jQuery successful: (?^u:(\"paused\":|\"test_execution_paused\":\".*\")) remaining wait time 1075s

# waiting for paused, developer console contains:

# status: Connecting to ws://127.0.0.1:20013/Dlzx3oXeqolMvMJJ/ws

# status: Using proxy: no

# status: Connection opened

# ==> {"cmd":"status"}

# <== {"current_api_function":null,"current_test_full_name":null,"devel_mode_major_version":1,"devel_mode_minor_version":1,"pause_on_next_command":0,"pause_on_screen_mismatch":false,"pause_test_name":"shutdown","running":null,"tags":null,"test_execution_paused":0}

# <== {"can_handle":{"cmd":"backend_can_handle","function":"snapshots","json_cmd_token":"UooadNSe"},"current_api_function":"can_handle"}

# <== {"current_test_full_name":"tests-boot","set_current_test":"boot"}

# <== {"check_screen":{"check":0,"mustmatch":"core","timeout":15},"current_api_function":"assert_screen"}

# <== {"current_api_function":"send_key","send_key":{"cmd":"backend_send_key","json_cmd_token":"VpdUKuZr","key":"ret"}}

# <== {"check_screen":{"check":0,"mustmatch":"on_prompt","timeout":90},"current_api_function":"assert_screen"}

# Wait for jQuery successful: (?^u:(\"paused\":|\"test_execution_paused\":\".*\")) remaining wait time 1070s

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/1/status

[debug] [pid:12162] Upload concluded (at boot)

# Wait for jQuery successful: (?^u:(\"paused\":|\"test_execution_paused\":\".*\")) remaining wait time 1065s

ok 2 - found paused at 1451

ok 3 - No unexpected js warnings

ok 4 - found resume at 2280

1..4

ok 21 - pause at certain test

# Subtest: schedule job

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12156] connecting to os-autoinst command server for job 1 at ws://127.0.0.1:20013/Dlzx3oXeqolMvMJJ/ws

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Asking the worker 1 to start providing livestream for job 1

[debug] [pid:12162] Starting livelog

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/1/status

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] creating f41/844/68dbb276a03240907d0e7e500d.png

[debug] [pid:12077] creating 68d/7ac/83452b72555f8a6b386629f91f.png

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 0 of 3240)

[debug] [pid:12401] Optimizing /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/testresults/boot-1.png

[debug] [pid:12401] Uploading artefact boot-1.png as f4184468dbb276a03240907d0e7e500d

[debug] [pid:12401] Optimizing /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/testresults/.thumbs/boot-1.png

[debug] [pid:12401] Uploading artefact boot-1.png as f4184468dbb276a03240907d0e7e500d

[debug] [pid:12401] Optimizing /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/testresults/boot-2.png

[debug] [pid:12401] Uploading artefact boot-2.png as 68d7ac83452b72555f8a6b386629f91f

[debug] [pid:12401] Optimizing /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/testresults/.thumbs/boot-2.png

[debug] [pid:12401] Uploading artefact boot-2.png as 68d7ac83452b72555f8a6b386629f91f

[debug] [pid:12162] Upload concluded (up to boot)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 1 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 2 of 3240)

[debug] [pid:12076] Updating seen of worker 1 from worker_status (working)

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/1/status

[debug] [pid:12162] Upload concluded (at shutdown)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 3 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 4 of 3240)

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/1/status

[debug] [pid:12162] Upload concluded (at shutdown)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 5 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 6 of 3240)

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/1/status

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 7 of 3240)

[debug] [pid:12162] Upload concluded (at shutdown)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 8 of 3240)

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/1/status

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 9 of 3240)

[debug] [pid:12162] Upload concluded (at shutdown)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] looking for "f41/844/.thumbs/68dbb276a03240907d0e7e500d.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/f41/844/.thumbs/68dbb276a03240907d0e7e500d.png",

pid => 12077,

}, "Mojo::Asset::File")

[debug] looking for "68d/7ac/.thumbs/83452b72555f8a6b386629f91f.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/68d/7ac/.thumbs/83452b72555f8a6b386629f91f.png",

pid => 12077,

}, "Mojo::Asset::File")

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 10 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 11 of 3240)

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/1/status

[debug] [pid:12077] creating e3b/43b/69c91fe4072dac1037f416c281.png

[debug] [pid:12077] creating e3b/43b/69c91fe4072dac1037f416c281.png

[debug] [pid:12077] creating 4ec/328/64be46d7336c00b3b3f4a5fe6d.png

[debug] [pid:12411] Optimizing /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/testresults/shutdown-2.png

[debug] [pid:12411] Uploading artefact shutdown-2.png as 4ec32864be46d7336c00b3b3f4a5fe6d

[debug] [pid:12411] Optimizing /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/testresults/.thumbs/shutdown-2.png

[debug] [pid:12411] Uploading artefact shutdown-2.png as 4ec32864be46d7336c00b3b3f4a5fe6d

[debug] [pid:12411] Optimizing /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/testresults/assert_screen-2.png

[debug] [pid:12411] Uploading artefact assert_screen-2.png as e3b43b69c91fe4072dac1037f416c281

[debug] [pid:12411] Optimizing /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/testresults/.thumbs/assert_screen-2.png

[debug] [pid:12411] Uploading artefact assert_screen-2.png as e3b43b69c91fe4072dac1037f416c281

[debug] [pid:12162] Upload concluded (up to shutdown)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 12 of 3240)

[info] [pid:12162] Isotovideo exit status: 0

[debug] [pid:12162] Stopping job 1 from http://localhost:9526: 00000001-tinycore-1-flavor-i386-Build1-core@coolone - reason: done

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/1/status

[info] [pid:12162] +++ worker notes +++

[info] [pid:12162] End time: 2022-02-15 12:27:53

[info] [pid:12162] Result: done

[info] [pid:12422] Uploading core-hdd.qcow2

[info] [pid:12422] Uploading core-hdd.qcow2 using multiple chunks

[info] [pid:12422] core-hdd.qcow2: local upload (no chunks needed)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 13 of 3240)

[info] [pid:12422] Uploading video.ogv

[debug] [pid:12422] Uploading artefact video.ogv

[info] [pid:12422] Uploading vars.json

[debug] [pid:12422] Uploading artefact vars.json

[info] [pid:12422] Uploading autoinst-log.txt

[debug] [pid:12422] Uploading artefact autoinst-log.txt

[info] [pid:12422] Uploading worker-log.txt

[debug] [pid:12422] Uploading artefact worker-log.txt

[info] [pid:12422] Uploading serial0.txt

[debug] [pid:12422] Uploading artefact serial0.txt

[info] [pid:12422] Uploading video_time.vtt

[debug] [pid:12422] Uploading artefact video_time.vtt

[info] [pid:12422] Uploading serial_terminal.txt

[debug] [pid:12422] Uploading artefact serial_terminal.txt

[info] [pid:12422] Uploading virtio_console1.log

[debug] [pid:12422] Uploading artefact virtio_console1.log

[debug] [pid:12162] Considering job 1 as regularly done

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/1/status

[debug] [pid:12077] creating e3b/43b/69c91fe4072dac1037f416c281.png

[debug] [pid:12077] creating e3b/43b/69c91fe4072dac1037f416c281.png

[debug] [pid:12077] creating f41/844/68dbb276a03240907d0e7e500d.png

[debug] [pid:12077] creating 68d/7ac/83452b72555f8a6b386629f91f.png

[debug] [pid:12077] creating 4ec/328/64be46d7336c00b3b3f4a5fe6d.png

[debug] [pid:12162] Upload concluded (no current module)

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/1/set_done?worker_id=1

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 14 of 3240)

[debug] [pid:12162] Job 1 from http://localhost:9526 finished - reason: done

[debug] [pid:12162] Cleaning up for next job

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 15 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 16 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 17 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 18 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 19 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] looking for "e3b/43b/.thumbs/69c91fe4072dac1037f416c281.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/e3b/43b/.thumbs/69c91fe4072dac1037f416c281.png",

pid => 12077,

}, "Mojo::Asset::File")

[debug] looking for "4ec/328/.thumbs/64be46d7336c00b3b3f4a5fe6d.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/4ec/328/.thumbs/64be46d7336c00b3b3f4a5fe6d.png",

pid => 12077,

}, "Mojo::Asset::File")

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 20 of 3240)

ok 1 - job 1 passed

ok 2 - autoinst log file generated

ok 3 - worker log file generated

ok 4 - autoinst log uploaded

ok 5 - worker log uploaded

ok 6 - local upload feature used

ok 7 - image of hdd uploaded

ok 8 - can stat /tmp/7v4CAal1xt/full-stack.d/openqa/share/factory/hdd/core-hdd.qcow2

ok 9 - exported image has correct permissions (420 -> 0644)

ok 10 - regular post via client script

ok 11 - send JSON data via client

ok 12 - group has been altered correctly

1..12

ok 22 - schedule job

# Subtest: clone job that crashes

[debug] Restarting job 1

[debug] [pid:12077] Duplicating jobs: {

1 => {

chained_children => [],

chained_parents => [],

directly_chained_children => [],

directly_chained_parents => [],

is_parent_or_initial_job => 1,

ok => 1,

parallel_children => [],

parallel_parents => [],

state => "done",

},

}

[debug] [pid:12077] Job 1 duplicated as 2

[warn] [pid:12077] Unable to wakeup scheduler: Connection refused

ok 1 - client returned new test_url for test 2

[debug] [pid:12077] Unrecognized referer 'localhost'

ok 2 - test 1 is restarted

[debug] looking for "f41/844/.thumbs/68dbb276a03240907d0e7e500d.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/f41/844/.thumbs/68dbb276a03240907d0e7e500d.png",

pid => 12077,

}, "Mojo::Asset::File")

[debug] looking for "e3b/43b/.thumbs/69c91fe4072dac1037f416c281.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/e3b/43b/.thumbs/69c91fe4072dac1037f416c281.png",

pid => 12077,

}, "Mojo::Asset::File")

[debug] looking for "68d/7ac/.thumbs/83452b72555f8a6b386629f91f.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/68d/7ac/.thumbs/83452b72555f8a6b386629f91f.png",

pid => 12077,

}, "Mojo::Asset::File")

[debug] looking for "4ec/328/.thumbs/64be46d7336c00b3b3f4a5fe6d.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/4ec/328/.thumbs/64be46d7336c00b3b3f4a5fe6d.png",

pid => 12077,

}, "Mojo::Asset::File")

[debug] [pid:12077] Unrecognized referer 'localhost'

ok 3 - click_ok'clicked link to test 2'

[debug] [pid:12077] Unrecognized referer 'localhost'

ok 4 - job with worker class qemu_i386 scheduled

[debug] [pid:12077] Unrecognized referer 'localhost'

ok 5 - at least one free worker with class qemu_i386 registered

[debug] [pid:12072] +=----------------=+

[debug] [pid:12072] -> Scheduling new jobs.

[debug] [pid:12072] Free workers: 1/1

[debug] [pid:12072] Scheduled jobs: 1

[debug] [pid:12072] Need to schedule 1 parallel jobs for job 2 (with priority 50)

[debug] [pid:12072] Assigned job '2' to worker ID '1'

[debug] [pid:12072] [Job#2] Prepare for being processed by worker 1

[debug] [pid:12076] Started to send message to 1 for job(s) 2

[debug] [pid:12072] Sent job(s) '2' to worker '1'

[debug] [pid:12072] Scheduler took 0.10311s to perform operations and allocated 1 jobs

[debug] [pid:12162] Accepting job 2 from http://localhost:9526.

[debug] [pid:12072] Allocated: { job => 2, worker => 1 }

[debug] [pid:12162] Setting job 2 from http://localhost:9526 up

[debug] [pid:12162] Preparing Mojo::IOLoop::ReadWriteProcess::Session

[info] [pid:12162] +++ setup notes +++

[info] [pid:12162] Running on 1234cbae1a16:1 (Linux 5.11.0-1027-aws #30~20.04.1-Ubuntu SMP Thu Jan 13 11:46:53 UTC 2022 x86_64)

[debug] [pid:12162] Job settings:

[debug] [pid:12162]

ARCH=i386

BUILD=1

DISTRI=tinycore

FLAVOR=flavor

INTEGRATION_TESTS=1

ISO=Core-7.2.iso

JOBTOKEN=W5s4P1p_dmxSymZf

LOG_LEVEL=debug

MACHINE=coolone

NAME=00000002-tinycore-1-flavor-i386-Build1-core@coolone

OPENQA_HOSTNAME=localhost:9526

OPENQA_URL=http://localhost:9526

PAUSE_AT=shutdown

PRJDIR=/tmp/7v4CAal1xt/full-stack.d/openqa/share

PUBLISH_HDD_1=core-hdd.qcow2

QEMU=i386

QEMUPORT=20012

QEMU_NO_FDC_SET=1

QEMU_NO_KVM=1

QEMU_NO_TABLET=1

RETRY_DELAY=5

RETRY_DELAY_IF_WEBUI_BUSY=60

TEST=core

UEFI_PFLASH_VARS=/usr/share/qemu/ovmf-x86_64.bin

VERSION=1

VNC=91

WORKER_CLASS=qemu_i386,qemu_x86_64

WORKER_HOSTNAME=127.0.0.1

WORKER_ID=1

WORKER_INSTANCE=1

[debug] [pid:12162] Linked asset "/tmp/7v4CAal1xt/full-stack.d/openqa/share/factory/iso/Core-7.2.iso" to "/tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/Core-7.2.iso"

[debug] [pid:12162] Symlinked from "/tmp/7v4CAal1xt/full-stack.d/openqa/share/tests/tinycore" to "/tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/tinycore"

[info] [pid:12162] Preparing cgroup to start isotovideo

[warn] [pid:12162] Disabling cgroup usage because cgroup creation failed: mkdir /sys/fs/cgroup/systemd/docker: Permission denied at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/File.pm line 84.

[info] [pid:12162] You can define a custom slice with OPENQA_CGROUP_SLICE or indicating the base mount with MOJO_CGROUP_FS.

[info] [pid:12162] Starting isotovideo container

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 0 of 3240)

[debug] [pid:12162] Registered process:12434

[info] [pid:12434] 12434: WORKING 2

[info] [pid:12162] isotovideo has been started (PID: 12434)

[debug] [pid:12434] +++ worker notes +++

[debug] [pid:12162] Running job 2 from http://localhost:9526: 00000002-tinycore-1-flavor-i386-Build1-core@coolone.

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/2/status

[debug] [pid:12076] Worker 1 accepted job 2

[debug] [pid:12076] Updating seen of worker 1 from worker_status (working)

[debug] [pid:12162] Upload concluded (no current module)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 1 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 2 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 3 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 4 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 5 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 6 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 7 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 8 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 9 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12156] connecting to os-autoinst command server for job 2 at ws://127.0.0.1:20013/W5s4P1p_dmxSymZf/ws

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Asking the worker 1 to start providing livestream for job 2

[debug] [pid:12162] Starting livelog

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/2/status

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 10 of 3240)

[debug] [pid:12162] Upload concluded (at boot)

ok 6 - \#nav-item-for-live present

ok 7 - job 2 running

[info] [pid:12162] Received signal TERM

[debug] [pid:12162] Stopping job 2 from http://localhost:9526: 00000002-tinycore-1-flavor-i386-Build1-core@coolone - reason: quit

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/2/status

[debug] [pid:12162] Announcing job termination (due to quit) to command server via http://localhost:20013/W5s4P1p_dmxSymZf/broadcast

[info] [pid:12162] Isotovideo exit status: 1

[info] [pid:12162] +++ worker notes +++

[info] [pid:12162] End time: 2022-02-15 12:28:09

[info] [pid:12162] Result: quit

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/2/status

[debug] [pid:12162] Upload concluded (at boot)

[debug] [pid:12162] Job 2 stopped as incomplete

[debug] [pid:12162] Duplicating job 2

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/2/duplicate?dup_type_auto=1

[debug] Restarting job 2

[debug] [pid:12077] Duplicating jobs: {

2 => {

chained_children => [],

chained_parents => [],

directly_chained_children => [],

directly_chained_parents => [],

is_parent_or_initial_job => 1,

ok => 0,

parallel_children => [],

parallel_parents => [],

state => "uploading",

},

}

[debug] [pid:12077] Job 2 duplicated as 3

[warn] [pid:12077] Unable to wakeup scheduler: Connection refused

[debug] [pid:12162] Unable to read result-assert_screen.json: Can't open file "/tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/testresults/result-assert_screen.json": No such file or directory at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/File.pm line 132.

Mojo::File::slurp(Mojo::File=SCALAR(0x561ec5f0eb80)) called at /home/squamata/project/script/../lib/OpenQA/Worker/Job.pm line 1098

eval {...} called at /home/squamata/project/script/../lib/OpenQA/Worker/Job.pm line 1098

OpenQA::Worker::Job::_read_json_file(OpenQA::Worker::Job=HASH(0x561ec5ef7908), "result-assert_screen.json") called at /home/squamata/project/script/../lib/OpenQA/Worker/Job.pm line 1154

OpenQA::Worker::Job::_read_module_result(OpenQA::Worker::Job=HASH(0x561ec5ef7908), "assert_screen") called at /home/squamata/project/script/../lib/OpenQA/Worker/Job.pm line 1116

OpenQA::Worker::Job::_read_result_file(OpenQA::Worker::Job=HASH(0x561ec5ef7908), "", ARRAY(0x561ec5e48050)) called at /home/squamata/project/script/../lib/OpenQA/Worker/Job.pm line 786

OpenQA::Worker::Job::_upload_results_step_0_prepare(OpenQA::Worker::Job=HASH(0x561ec5ef7908), CODE(0x561ec5ef2858)) called at /home/squamata/project/script/../lib/OpenQA/Worker/Job.pm line 715

OpenQA::Worker::Job::_upload_results(OpenQA::Worker::Job=HASH(0x561ec5ef7908), CODE(0x561ec5ef2858)) called at /home/squamata/project/script/../lib/OpenQA/Worker/Job.pm line 526

OpenQA::Worker::Job::__ANON__(HASH(0x561ec5f0ea60)) called at /home/squamata/project/script/../lib/OpenQA/Worker/WebUIConnection.pm line 301

OpenQA::Worker::WebUIConnection::__ANON__(OpenQA::Client=HASH(0x561ec5ddbfe8), Mojo::Transaction::HTTP=HASH(0x561ec5f11f28), 1) called at /home/squamata/project/script/../lib/OpenQA/Worker/WebUIConnection.pm line 335

OpenQA::Worker::WebUIConnection::__ANON__(OpenQA::Client=HASH(0x561ec5ddbfe8), Mojo::Transaction::HTTP=HASH(0x561ec5f11f28)) called at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/UserAgent.pm line 249

Mojo::UserAgent::_finish(OpenQA::Client=HASH(0x561ec5ddbfe8), "f11d9491955ba7568cd0146b8b40003d") called at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/UserAgent.pm line 276

Mojo::UserAgent::_read(OpenQA::Client=HASH(0x561ec5ddbfe8), "f11d9491955ba7568cd0146b8b40003d", "HTTP/1.1 200 OK\x{d}\x{a}Content-Type: application/json;charset=UTF-8"...) called at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/UserAgent.pm line 136

Mojo::UserAgent::__ANON__(Mojo::IOLoop::Stream=HASH(0x561ec5f092a0)) called at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/EventEmitter.pm line 15

Mojo::EventEmitter::emit(Mojo::IOLoop::Stream=HASH(0x561ec5f092a0), "read", "HTTP/1.1 200 OK\x{d}\x{a}Content-Type: application/json;charset=UTF-8"...) called at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/IOLoop/Stream.pm line 109

Mojo::IOLoop::Stream::_read(Mojo::IOLoop::Stream=HASH(0x561ec5f092a0)) called at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/IOLoop/Stream.pm line 57

Mojo::IOLoop::Stream::__ANON__(Mojo::Reactor::Poll=HASH(0x561ec3d72ba0)) called at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/Reactor/Poll.pm line 141

eval {...} called at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/Reactor/Poll.pm line 141

Mojo::Reactor::Poll::_try(Mojo::Reactor::Poll=HASH(0x561ec3d72ba0), "I/O watcher", CODE(0x561ec5e3bbe0), 0) called at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/Reactor/Poll.pm line 60

Mojo::Reactor::Poll::one_tick(Mojo::Reactor::Poll=HASH(0x561ec3d72ba0)) called at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/Reactor/Poll.pm line 101

Mojo::Reactor::Poll::start(Mojo::Reactor::Poll=HASH(0x561ec3d72ba0)) called at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/IOLoop.pm line 134

Mojo::IOLoop::start(Mojo::IOLoop=HASH(0x561ec3acd7f0)) called at /home/squamata/project/script/../lib/OpenQA/Worker.pm line 376

OpenQA::Worker::exec(OpenQA::Worker=HASH(0x561ec5dcd640)) called at ./script/worker line 126

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/2/status

[debug] [pid:12077] creating f41/844/68dbb276a03240907d0e7e500d.png

[debug] [pid:12162] Upload concluded (at boot)

[debug] [pid:12162] REST-API call: POST http://localhost:9526/api/v1/jobs/2/set_done?reason=quit%3A+worker+has+been+stopped+or+restarted&result=incomplete&worker_id=1

[debug] [pid:12077] checking take over from 1: GOOD vs assert_screen:none,shutdown:none

[debug] [pid:12162] Job 2 from http://localhost:9526 finished - reason: quit

[debug] [pid:12162] Informing http://localhost:9526 that we are going offline

[info] [pid:12076] Worker 1 websocket connection closed - 1006

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: incomplete)' (count 0 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: incomplete)' (count 1 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: incomplete)' (count 2 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] looking for "f41/844/.thumbs/68dbb276a03240907d0e7e500d.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/f41/844/.thumbs/68dbb276a03240907d0e7e500d.png",

pid => 12077,

}, "Mojo::Asset::File")

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: incomplete)' (count 3 of 3240)

ok 8 - job 2 crashed

ok 9 - test 2 is restarted by killing worker

1..9

ok 23 - clone job that crashes

# Subtest: cancel a scheduled job

[warn] [pid:12077] Unable to wakeup scheduler: Connection refused

ok 1 - click_ok'Clicked All Tests'

# Wait for jQuery successful: wait for All Tests displayed before looking for 3

[debug] [pid:12077] Unrecognized referer 'localhost'

ok 2 - click_ok'clicked on 3'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: scheduled)' (count 0 of 8100)

ok 3 - Job 3 was scheduled

1..3

ok 24 - cancel a scheduled job

ok 25 - click_ok'Clicked All Tests to go to test 4'

# Wait for jQuery successful: wait for All Tests displayed before looking for 3

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

ok 26 - click_ok'clicked on 4'

ok 27 - scheduled test page

ok 28 - test 4 is scheduled

ok 29 - no unexpected js warnings after test 4 was scheduled

[debug] [pid:12077] Unrecognized referer 'localhost'

ok 30 - Worker started as IPC::Run=HASH(0x55eab0eb69d0)

ok 31 - job with worker class qemu_i386 scheduled

[info] [pid:12644] worker 1:

- config file: /tmp/7v4CAal1xt/full-stack.d/config/workers.ini

- worker hostname: 1234cbae1a16

- isotovideo version: 24

- websocket API version: 1

- web UI hosts: http://localhost:9526

- class: qemu_i386,qemu_x86_64

- no cleanup: no

- pool directory: /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1

[info] [pid:12644] Project dir for host http://localhost:9526 is /tmp/7v4CAal1xt/full-stack.d/openqa/share

[info] [pid:12644] Registering with openQA http://localhost:9526

[info] [pid:12644] Establishing ws connection via ws://localhost:9526/api/v1/ws/1

[info] [pid:12644] Registered and connected via websockets with openQA host http://localhost:9526 and worker ID 1

[debug] [pid:12076] Updating seen of worker 1 from worker_status (free)

ok 32 - at least one free worker with class qemu_i386 registered

[debug] [pid:12072] +=----------------=+

[debug] [pid:12072] -> Scheduling new jobs.

[debug] [pid:12072] Free workers: 1/1

[debug] [pid:12072] Scheduled jobs: 1

[debug] [pid:12072] Need to schedule 1 parallel jobs for job 4 (with priority 50)

[debug] [pid:12072] Assigned job '4' to worker ID '1'

[debug] [pid:12072] [Job#4] Prepare for being processed by worker 1

[debug] [pid:12076] Started to send message to 1 for job(s) 4

[debug] [pid:12072] Sent job(s) '4' to worker '1'

[debug] [pid:12072] Scheduler took 0.10219s to perform operations and allocated 1 jobs

[debug] [pid:12072] Allocated: { job => 4, worker => 1 }

# Subtest: incomplete job because of setup failure

[debug] [pid:12644] Accepting job 4 from http://localhost:9526.

[debug] [pid:12644] Setting job 4 from http://localhost:9526 up

[debug] [pid:12644] Preparing Mojo::IOLoop::ReadWriteProcess::Session

[info] [pid:12644] +++ setup notes +++

[info] [pid:12644] Running on 1234cbae1a16:1 (Linux 5.11.0-1027-aws #30~20.04.1-Ubuntu SMP Thu Jan 13 11:46:53 UTC 2022 x86_64)

[debug] [pid:12644] Job settings:

[debug] [pid:12644]

ARCH=i386

BUILD=1

DISTRI=tinycore

FLAVOR=flavor

HDD_1=nihilist_disk.hda

INTEGRATION_TESTS=1

ISO=Core-7.2.iso

JOBTOKEN=jYLqqzusM3UiJ4qg

LOG_LEVEL=debug

MACHINE=noassets

NAME=00000004-tinycore-1-flavor-i386-Build1-core@noassets

OPENQA_HOSTNAME=localhost:9526

OPENQA_URL=http://localhost:9526

PRJDIR=/tmp/7v4CAal1xt/full-stack.d/openqa/share

PUBLISH_HDD_1=core-hdd.qcow2

QEMU=i386

QEMUPORT=20012

QEMU_NO_FDC_SET=1

QEMU_NO_KVM=1

QEMU_NO_TABLET=1

RETRY_DELAY=5

RETRY_DELAY_IF_WEBUI_BUSY=60

TEST=core

UEFI_PFLASH_VARS=/usr/share/qemu/ovmf-x86_64.bin

VERSION=1

VNC=91

WORKER_CLASS=qemu_i386,qemu_x86_64

WORKER_HOSTNAME=127.0.0.1

WORKER_ID=1

WORKER_INSTANCE=1

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: incomplete)' (count 0 of 3240)

[error] [pid:12644] HDD_1 handling Cannot find HDD_1 asset hdd/nihilist_disk.hda!

[error] [pid:12644] Unable to setup job 4: Cannot find HDD_1 asset hdd/nihilist_disk.hda!

[debug] [pid:12644] Stopping job 4 from http://localhost:9526: 00000004-tinycore-1-flavor-i386-Build1-core@noassets - reason: setup failure

[debug] [pid:12644] REST-API call: POST http://localhost:9526/api/v1/jobs/4/status

[debug] [pid:12076] Worker 1 accepted job 4

[info] [pid:12644] +++ worker notes +++

[info] [pid:12644] End time: 2022-02-15 12:28:18

[info] [pid:12644] Result: setup failure

[info] [pid:12650] Uploading autoinst-log.txt

[debug] [pid:12650] Uploading artefact autoinst-log.txt

[info] [pid:12650] Uploading worker-log.txt

[debug] [pid:12650] Uploading artefact worker-log.txt

[debug] [pid:12644] Job 4 stopped as incomplete

[warn] [pid:12644] Unable to upload results of the job because no command server URL or worker ID have been set.

[debug] [pid:12644] Upload concluded (no current module)

[debug] [pid:12644] REST-API call: POST http://localhost:9526/api/v1/jobs/4/set_done?reason=asset+failure%3A+Cannot+find+HDD_1+asset+hdd%2Fnihilist_disk.hda%21&result=incomplete&worker_id=1

[debug] [pid:12644] Job 4 from http://localhost:9526 finished - reason: setup failure

[debug] [pid:12644] Cleaning up for next job

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: incomplete)' (count 1 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: incomplete)' (count 2 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: incomplete)' (count 3 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: incomplete)' (count 4 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: incomplete)' (count 5 of 3240)

ok 1 - Job 4 crashed

# Waiting for 'autoinst-log.txt' to become available

ok 2 - Test 4 result correct: setup failure

ok 3 - Test 4 correct autoinst setup notes

ok 4 - Test 4: upload of autoinst-log.txt logged

[info] [pid:12644] Received signal TERM

[debug] [pid:12644] Informing http://localhost:9526 that we are going offline

[info] [pid:12076] Worker 1 websocket connection closed - 1006

1..4

ok 33 - incomplete job because of setup failure

ok 34 - Setting up Cache directory

ok 35 - Config file created

# Subtest: Cache tests

ok 1 - cache.sqlite is not present

# Starting cache minion worker

# Waiting for 'cache service at http://127.0.0.1:9530' to become available

# Starting worker cache service on port 9530

# Waiting for 'cache service worker' to become available

[warn] [pid:12077] Unable to wakeup scheduler: Connection refused

[debug] [pid:12077] Unrecognized referer 'localhost'

ok 2 - test 5 is scheduled

ok 3 - Worker started as IPC::Run=HASH(0x55eab0eca758)

[debug] [pid:12077] Unrecognized referer 'localhost'

ok 4 - job with worker class qemu_i386 scheduled

[info] [pid:12658] worker 1:

- config file: /tmp/7v4CAal1xt/full-stack.d/config/workers.ini

- worker hostname: 1234cbae1a16

- isotovideo version: 24

- websocket API version: 1

- web UI hosts: http://localhost:9526

- class: qemu_i386,qemu_x86_64

- no cleanup: no

- pool directory: /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1

[info] [pid:12658] CACHE: caching is enabled, setting up /tmp/7v4CAal1xt/full-stack.d/cache/localhost

[info] [pid:12658] Project dir for host http://localhost:9526 is /tmp/7v4CAal1xt/full-stack.d/openqa/share

[info] [pid:12658] Registering with openQA http://localhost:9526

[info] [pid:12658] Establishing ws connection via ws://localhost:9526/api/v1/ws/1

[info] [pid:12658] Registered and connected via websockets with openQA host http://localhost:9526 and worker ID 1

[debug] [pid:12076] Updating seen of worker 1 from worker_status (free)

ok 5 - at least one free worker with class qemu_i386 registered

[debug] [pid:12072] +=----------------=+

[debug] [pid:12072] -> Scheduling new jobs.

[debug] [pid:12072] Free workers: 1/1

[debug] [pid:12072] Scheduled jobs: 1

[debug] [pid:12072] Need to schedule 1 parallel jobs for job 5 (with priority 50)

[debug] [pid:12072] Assigned job '5' to worker ID '1'

[debug] [pid:12072] [Job#5] Prepare for being processed by worker 1

[debug] [pid:12076] Started to send message to 1 for job(s) 5

[debug] [pid:12072] Sent job(s) '5' to worker '1'

[debug] [pid:12072] Scheduler took 0.09552s to perform operations and allocated 1 jobs

[debug] [pid:12658] Accepting job 5 from http://localhost:9526.

[debug] [pid:12072] Allocated: { job => 5, worker => 1 }

[debug] [pid:12658] Setting job 5 from http://localhost:9526 up

[debug] [pid:12658] Preparing Mojo::IOLoop::ReadWriteProcess::Session

[info] [pid:12658] +++ setup notes +++

[info] [pid:12658] Running on 1234cbae1a16:1 (Linux 5.11.0-1027-aws #30~20.04.1-Ubuntu SMP Thu Jan 13 11:46:53 UTC 2022 x86_64)

[debug] [pid:12658] Job settings:

[debug] [pid:12658]

ARCH=i386

BUILD=1

CACHEDIRECTORY=/tmp/7v4CAal1xt/full-stack.d/cache

CACHELIMIT=50

DISTRI=tinycore

FLAVOR=flavor

INTEGRATION_TESTS=1

ISO=Core-7.2.iso

JOBTOKEN=UHDsvGadPNTdhlYW

LOCAL_UPLOAD=0

LOG_LEVEL=debug

MACHINE=coolone

NAME=00000005-tinycore-1-flavor-i386-Build1-core@coolone

OPENQA_HOSTNAME=localhost:9526

OPENQA_URL=http://localhost:9526

PRJDIR=/tmp/7v4CAal1xt/full-stack.d/openqa/share

PUBLISH_HDD_1=

QEMU=i386

QEMUPORT=20012

QEMU_NO_FDC_SET=1

QEMU_NO_KVM=1

QEMU_NO_TABLET=1

RETRY_DELAY=5

RETRY_DELAY_IF_WEBUI_BUSY=60

TEST=core

UEFI_PFLASH_VARS=/usr/share/qemu/ovmf-x86_64.bin

VERSION=1

VNC=91

WORKER_CLASS=qemu_i386,qemu_x86_64

WORKER_ID=1

WORKER_INSTANCE=1

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 0 of 3240)

[debug] [pid:12658] Found ISO, caching Core-7.2.iso

[info] [pid:12658] Downloading Core-7.2.iso, request #1 sent to Cache Service

[debug] [pid:12658] Updating status so job 5 is not considered dead.

[debug] [pid:12658] REST-API call: POST http://localhost:9526/api/v1/jobs/5/status

[debug] [pid:12076] Worker 1 accepted job 5

[debug] redirect to /assets/iso/Core-7.2.iso

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 1 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 2 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 3 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 4 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] attempt to open ws proxy for job tinycore-1-flavor-i386-Build1-core@coolone (5) where URL to os-autoinst command server is unknown

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Asking the worker 1 to start providing livestream for job 5

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:State: running)' (count 5 of 3240)

ok 6 - \#nav-item-for-live present

ok 7 - job 5 running

# Subtest: results of test 5

ok 1 - cache.sqlite file created

ok 2 - Directory within cache, not present after deploy

ok 3 - File within cache, not present after deploy

# Waiting for 'finished download' to become available

[debug] [pid:12658] Updating status so job 5 is not considered dead.

[debug] [pid:12658] REST-API call: POST http://localhost:9526/api/v1/jobs/5/status

[info] [pid:12658] Download of Core-7.2.iso processed

[debug] [pid:12658] Linked asset "/tmp/7v4CAal1xt/full-stack.d/cache/localhost/Core-7.2.iso" to "/tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/Core-7.2.iso"

[debug] [pid:12658] Symlinked from "/tmp/7v4CAal1xt/full-stack.d/openqa/share/tests/tinycore" to "/tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/tinycore"

[info] [pid:12658] Preparing cgroup to start isotovideo

[warn] [pid:12658] Disabling cgroup usage because cgroup creation failed: mkdir /sys/fs/cgroup/systemd/docker: Permission denied at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/File.pm line 84.

[info] [pid:12658] You can define a custom slice with OPENQA_CGROUP_SLICE or indicating the base mount with MOJO_CGROUP_FS.

[info] [pid:12658] Starting isotovideo container

[debug] [pid:12658] Registered process:12665

[info] [pid:12665] 12665: WORKING 5

[info] [pid:12658] isotovideo has been started (PID: 12665)

[debug] [pid:12665] +++ worker notes +++

[debug] [pid:12658] Running job 5 from http://localhost:9526: 00000005-tinycore-1-flavor-i386-Build1-core@coolone.

[debug] [pid:12658] REST-API call: POST http://localhost:9526/api/v1/jobs/5/status

ok 4 - iso is hardlinked to cache

[debug] [pid:12076] Updating seen of worker 1 from worker_status (working)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 0 of 3240)

[debug] [pid:12658] Upload concluded (no current module)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 1 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 2 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 3 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 4 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 5 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 6 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 7 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 8 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 9 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 10 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 11 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 12 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 13 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 14 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 15 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 16 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 17 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 18 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 19 of 3240)

[debug] [pid:12658] REST-API call: POST http://localhost:9526/api/v1/jobs/5/status

[debug] [pid:12658] Upload concluded (at boot)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 20 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 21 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 22 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12156] connecting to os-autoinst command server for job 5 at ws://1234cbae1a16:20013/UHDsvGadPNTdhlYW/ws

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 23 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 24 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 25 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 26 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 27 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 28 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 29 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 30 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 31 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 32 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 33 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 34 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 35 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 36 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 37 of 3240)

[debug] [pid:12076] Updating seen of worker 1 from worker_status (working)

[debug] [pid:12658] REST-API call: POST http://localhost:9526/api/v1/jobs/5/status

[debug] [pid:12077] creating f41/844/68dbb276a03240907d0e7e500d.png

[debug] [pid:12077] creating 68d/7ac/83452b72555f8a6b386629f91f.png

[debug] [pid:12658] Upload concluded (up to boot)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 38 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 39 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 40 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 41 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] looking for "68d/7ac/.thumbs/83452b72555f8a6b386629f91f.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/68d/7ac/.thumbs/83452b72555f8a6b386629f91f.png",

pid => 12077,

}, "Mojo::Asset::File")

[debug] looking for "f41/844/.thumbs/68dbb276a03240907d0e7e500d.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/f41/844/.thumbs/68dbb276a03240907d0e7e500d.png",

pid => 12077,

}, "Mojo::Asset::File")

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 42 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 43 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 44 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 45 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 46 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 47 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 48 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 49 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 50 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 51 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 52 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 53 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 54 of 3240)

[info] [pid:12658] Isotovideo exit status: 0

[debug] [pid:12658] Stopping job 5 from http://localhost:9526: 00000005-tinycore-1-flavor-i386-Build1-core@coolone - reason: done

[debug] [pid:12658] REST-API call: POST http://localhost:9526/api/v1/jobs/5/status

[info] [pid:12658] +++ worker notes +++

[info] [pid:12658] End time: 2022-02-15 12:29:07

[info] [pid:12658] Result: done

[info] [pid:12874] Uploading video.ogv

[debug] [pid:12874] Uploading artefact video.ogv

[info] [pid:12874] Uploading vars.json

[debug] [pid:12874] Uploading artefact vars.json

[info] [pid:12874] Uploading autoinst-log.txt

[debug] [pid:12874] Uploading artefact autoinst-log.txt

[info] [pid:12874] Uploading worker-log.txt

[debug] [pid:12874] Uploading artefact worker-log.txt

[info] [pid:12874] Uploading serial0.txt

[debug] [pid:12874] Uploading artefact serial0.txt

[info] [pid:12874] Uploading video_time.vtt

[debug] [pid:12874] Uploading artefact video_time.vtt

[info] [pid:12874] Uploading serial_terminal.txt

[debug] [pid:12874] Uploading artefact serial_terminal.txt

[info] [pid:12874] Uploading virtio_console1.log

[debug] [pid:12874] Uploading artefact virtio_console1.log

[debug] [pid:12658] Considering job 5 as regularly done

[debug] [pid:12658] REST-API call: POST http://localhost:9526/api/v1/jobs/5/status

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 55 of 3240)

[debug] [pid:12077] creating 68d/7ac/83452b72555f8a6b386629f91f.png

[debug] [pid:12077] creating 68d/7ac/83452b72555f8a6b386629f91f.png

[debug] [pid:12077] creating da0/10d/d856559816fdfe9ec648fa3f3f.png

[debug] [pid:12875] Optimizing /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/testresults/shutdown-2.png

[debug] [pid:12875] Uploading artefact shutdown-2.png as da010dd856559816fdfe9ec648fa3f3f

[debug] [pid:12875] Optimizing /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/testresults/.thumbs/shutdown-2.png

[debug] [pid:12875] Uploading artefact shutdown-2.png as da010dd856559816fdfe9ec648fa3f3f

[debug] [pid:12658] Upload concluded (no current module)

[debug] [pid:12658] REST-API call: POST http://localhost:9526/api/v1/jobs/5/set_done?worker_id=1

[debug] [pid:12658] Job 5 from http://localhost:9526 finished - reason: done

[debug] [pid:12658] Cleaning up for next job

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 56 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 57 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 58 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 59 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 60 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] looking for "da0/10d/.thumbs/d856559816fdfe9ec648fa3f3f.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/da0/10d/.thumbs/d856559816fdfe9ec648fa3f3f.png",

pid => 12077,

}, "Mojo::Asset::File")

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 61 of 3240)

ok 5 - job 5 passed

[info] [pid:12658] Received signal TERM

[debug] [pid:12658] Informing http://localhost:9526 that we are going offline

[info] [pid:12076] Worker 1 websocket connection closed - 1006

ok 6 - Test 5 autoinst-log.txt file created

ok 7 - Test 5, downloaded the right iso

ok 8 - Test 5 Core-7.2.iso size is correct

ok 9 - Test 5 result done

ok 10 - setup notes present

ok 11 - uploading of autoinst-log.txt logged

ok 12 - worker log file generated

ok 13 - autoinst log uploaded

ok 14 - worker log uploaded

ok 15 - local upload feature not used

ok 16 - Core-7.2.iso is the first element

ok 17 - file \#5 is the newest element

1..17

ok 8 - results of test 5

[debug] Restarting job 5

[debug] [pid:12077] Duplicating jobs: {

5 => {

chained_children => [],

chained_parents => [],

directly_chained_children => [],

directly_chained_parents => [],

is_parent_or_initial_job => 1,

ok => 1,

parallel_children => [],

parallel_parents => [],

state => "done",

},

}

[debug] [pid:12077] Job 5 duplicated as 6

[warn] [pid:12077] Unable to wakeup scheduler: Connection refused

ok 9 - client returned new test_url for test 6

[debug] [pid:12077] Unrecognized referer 'localhost'

ok 10 - test 6 is scheduled

ok 11 - Worker started as IPC::Run=HASH(0x55eab0e84970)

[debug] [pid:12077] Unrecognized referer 'localhost'

ok 12 - job with worker class qemu_i386 scheduled

[info] [pid:12880] worker 1:

- config file: /tmp/7v4CAal1xt/full-stack.d/config/workers.ini

- worker hostname: 1234cbae1a16

- isotovideo version: 24

- websocket API version: 1

- web UI hosts: http://localhost:9526

- class: qemu_i386,qemu_x86_64

- no cleanup: no

- pool directory: /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1

[info] [pid:12880] CACHE: caching is enabled, setting up /tmp/7v4CAal1xt/full-stack.d/cache/localhost

[info] [pid:12880] Project dir for host http://localhost:9526 is /tmp/7v4CAal1xt/full-stack.d/openqa/share

[info] [pid:12880] Registering with openQA http://localhost:9526

[info] [pid:12880] Establishing ws connection via ws://localhost:9526/api/v1/ws/1

[info] [pid:12880] Registered and connected via websockets with openQA host http://localhost:9526 and worker ID 1

[debug] [pid:12076] Updating seen of worker 1 from worker_status (free)

ok 13 - at least one free worker with class qemu_i386 registered

[debug] [pid:12072] +=----------------=+

[debug] [pid:12072] -> Scheduling new jobs.

[debug] [pid:12072] Free workers: 1/1

[debug] [pid:12072] Scheduled jobs: 1

[debug] [pid:12072] Need to schedule 1 parallel jobs for job 6 (with priority 50)

[debug] [pid:12072] Assigned job '6' to worker ID '1'

[debug] [pid:12072] [Job#6] Prepare for being processed by worker 1

[debug] [pid:12076] Started to send message to 1 for job(s) 6

[debug] [pid:12072] Sent job(s) '6' to worker '1'

[debug] [pid:12072] Scheduler took 0.10570s to perform operations and allocated 1 jobs

[debug] [pid:12880] Accepting job 6 from http://localhost:9526.

[debug] [pid:12072] Allocated: { job => 6, worker => 1 }

# Subtest: results of test 6

[debug] [pid:12880] Setting job 6 from http://localhost:9526 up

[debug] [pid:12880] Preparing Mojo::IOLoop::ReadWriteProcess::Session

[info] [pid:12880] +++ setup notes +++

[info] [pid:12880] Running on 1234cbae1a16:1 (Linux 5.11.0-1027-aws #30~20.04.1-Ubuntu SMP Thu Jan 13 11:46:53 UTC 2022 x86_64)

[debug] [pid:12880] Job settings:

[debug] [pid:12880]

ARCH=i386

BUILD=1

CACHEDIRECTORY=/tmp/7v4CAal1xt/full-stack.d/cache

CACHELIMIT=50

DISTRI=tinycore

FLAVOR=flavor

INTEGRATION_TESTS=1

ISO=Core-7.2.iso

JOBTOKEN=3qvFv7vgealwLeLC

LOCAL_UPLOAD=0

LOG_LEVEL=debug

MACHINE=coolone

NAME=00000006-tinycore-1-flavor-i386-Build1-core@coolone

OPENQA_HOSTNAME=localhost:9526

OPENQA_URL=http://localhost:9526

PRJDIR=/tmp/7v4CAal1xt/full-stack.d/openqa/share

PUBLISH_HDD_1=

QEMU=i386

QEMUPORT=20012

QEMU_NO_FDC_SET=1

QEMU_NO_KVM=1

QEMU_NO_TABLET=1

RETRY_DELAY=5

RETRY_DELAY_IF_WEBUI_BUSY=60

TEST=core

UEFI_PFLASH_VARS=/usr/share/qemu/ovmf-x86_64.bin

VERSION=1

VNC=91

WORKER_CLASS=qemu_i386,qemu_x86_64

WORKER_ID=1

WORKER_INSTANCE=1

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 0 of 3240)

[debug] [pid:12880] Found ISO, caching Core-7.2.iso

[info] [pid:12880] Downloading Core-7.2.iso, request #2 sent to Cache Service

[debug] [pid:12880] Updating status so job 6 is not considered dead.

[debug] [pid:12880] REST-API call: POST http://localhost:9526/api/v1/jobs/6/status

[debug] [pid:12076] Worker 1 accepted job 6

[debug] redirect to /assets/iso/Core-7.2.iso

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 1 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 2 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 3 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 4 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] attempt to open ws proxy for job tinycore-1-flavor-i386-Build1-core@coolone (6) where URL to os-autoinst command server is unknown

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Asking the worker 1 to start providing livestream for job 6

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 5 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 6 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 7 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 8 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 9 of 3240)

[debug] [pid:12880] Updating status so job 6 is not considered dead.

[debug] [pid:12880] REST-API call: POST http://localhost:9526/api/v1/jobs/6/status

[info] [pid:12880] Download of Core-7.2.iso processed

[debug] [pid:12880] Linked asset "/tmp/7v4CAal1xt/full-stack.d/cache/localhost/Core-7.2.iso" to "/tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/Core-7.2.iso"

[debug] [pid:12880] Symlinked from "/tmp/7v4CAal1xt/full-stack.d/openqa/share/tests/tinycore" to "/tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/tinycore"

[info] [pid:12880] Preparing cgroup to start isotovideo

[warn] [pid:12880] Disabling cgroup usage because cgroup creation failed: mkdir /sys/fs/cgroup/systemd/docker: Permission denied at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/File.pm line 84.

[info] [pid:12880] You can define a custom slice with OPENQA_CGROUP_SLICE or indicating the base mount with MOJO_CGROUP_FS.

[info] [pid:12880] Starting isotovideo container

[debug] [pid:12880] Registered process:12888

[info] [pid:12888] 12888: WORKING 6

[info] [pid:12880] isotovideo has been started (PID: 12888)

[debug] [pid:12888] +++ worker notes +++

[debug] [pid:12880] Running job 6 from http://localhost:9526: 00000006-tinycore-1-flavor-i386-Build1-core@coolone.

[debug] [pid:12880] REST-API call: POST http://localhost:9526/api/v1/jobs/6/status

[debug] [pid:12076] Updating seen of worker 1 from worker_status (working)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 10 of 3240)

[debug] [pid:12880] Upload concluded (no current module)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 11 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 12 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 13 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 14 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 15 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 16 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 17 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 18 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 19 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 20 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 21 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 22 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 23 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 24 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 25 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 26 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 27 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 28 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 29 of 3240)

[debug] [pid:12880] REST-API call: POST http://localhost:9526/api/v1/jobs/6/status

[debug] [pid:12880] Upload concluded (at boot)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 30 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 31 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 32 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 33 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 34 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12156] connecting to os-autoinst command server for job 6 at ws://1234cbae1a16:20013/3qvFv7vgealwLeLC/ws

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 35 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 36 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 37 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 38 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 39 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 40 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 41 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 42 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 43 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 44 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 45 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 46 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 47 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 48 of 3240)

[debug] [pid:12076] Updating seen of worker 1 from worker_status (working)

[debug] [pid:12880] REST-API call: POST http://localhost:9526/api/v1/jobs/6/status

[debug] [pid:12077] creating f41/844/68dbb276a03240907d0e7e500d.png

[debug] [pid:12077] creating 68d/7ac/83452b72555f8a6b386629f91f.png

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 49 of 3240)

[debug] [pid:12880] Upload concluded (up to boot)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 50 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 51 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 52 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 53 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] looking for "f41/844/.thumbs/68dbb276a03240907d0e7e500d.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/f41/844/.thumbs/68dbb276a03240907d0e7e500d.png",

pid => 12077,

}, "Mojo::Asset::File")

[debug] looking for "68d/7ac/.thumbs/83452b72555f8a6b386629f91f.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/68d/7ac/.thumbs/83452b72555f8a6b386629f91f.png",

pid => 12077,

}, "Mojo::Asset::File")

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 54 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 55 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 56 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 57 of 3240)

[info] [pid:12880] Isotovideo exit status: 0

[debug] [pid:12880] Stopping job 6 from http://localhost:9526: 00000006-tinycore-1-flavor-i386-Build1-core@coolone - reason: done

[debug] [pid:12880] REST-API call: POST http://localhost:9526/api/v1/jobs/6/status

[info] [pid:12880] +++ worker notes +++

[info] [pid:12880] End time: 2022-02-15 12:29:47

[info] [pid:12880] Result: done

[info] [pid:13106] Uploading video.ogv

[debug] [pid:13106] Uploading artefact video.ogv

[info] [pid:13106] Uploading vars.json

[debug] [pid:13106] Uploading artefact vars.json

[info] [pid:13106] Uploading autoinst-log.txt

[debug] [pid:13106] Uploading artefact autoinst-log.txt

[info] [pid:13106] Uploading worker-log.txt

[debug] [pid:13106] Uploading artefact worker-log.txt

[info] [pid:13106] Uploading serial0.txt

[debug] [pid:13106] Uploading artefact serial0.txt

[info] [pid:13106] Uploading video_time.vtt

[debug] [pid:13106] Uploading artefact video_time.vtt

[info] [pid:13106] Uploading serial_terminal.txt

[debug] [pid:13106] Uploading artefact serial_terminal.txt

[info] [pid:13106] Uploading virtio_console1.log

[debug] [pid:13106] Uploading artefact virtio_console1.log

[debug] [pid:12880] Considering job 6 as regularly done

[debug] [pid:12880] REST-API call: POST http://localhost:9526/api/v1/jobs/6/status

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 58 of 3240)

[debug] [pid:12077] creating 68d/7ac/83452b72555f8a6b386629f91f.png

[debug] [pid:12077] creating 68d/7ac/83452b72555f8a6b386629f91f.png

[debug] [pid:12077] creating eca/cf6/c5978e04babecd7542a8c1e2eb.png

[debug] [pid:13107] Optimizing /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/testresults/shutdown-2.png

[debug] [pid:13107] Uploading artefact shutdown-2.png as ecacf6c5978e04babecd7542a8c1e2eb

[debug] [pid:13107] Optimizing /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/testresults/.thumbs/shutdown-2.png

[debug] [pid:13107] Uploading artefact shutdown-2.png as ecacf6c5978e04babecd7542a8c1e2eb

[debug] [pid:12880] Upload concluded (no current module)

[debug] [pid:12880] REST-API call: POST http://localhost:9526/api/v1/jobs/6/set_done?worker_id=1

[debug] [pid:12880] Job 6 from http://localhost:9526 finished - reason: done

[debug] [pid:12880] Cleaning up for next job

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 59 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 60 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 61 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 62 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 63 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] looking for "eca/cf6/.thumbs/c5978e04babecd7542a8c1e2eb.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/eca/cf6/.thumbs/c5978e04babecd7542a8c1e2eb.png",

pid => 12077,

}, "Mojo::Asset::File")

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 64 of 3240)

ok 1 - job 6 passed

[info] [pid:12880] Received signal TERM

[debug] [pid:12880] Informing http://localhost:9526 that we are going offline

[info] [pid:12076] Worker 1 websocket connection closed - 1006

ok 2 - Test 6 autoinst-log.txt file created

ok 3 - asset 5.qcow2 removed during cache init

ok 4 - Core-7.2.iso the most recent asset again

1..4

ok 14 - results of test 6

[debug] Restarting job 6

[debug] [pid:12077] Duplicating jobs: {

6 => {

chained_children => [],

chained_parents => [],

directly_chained_children => [],

directly_chained_parents => [],

is_parent_or_initial_job => 1,

ok => 1,

parallel_children => [],

parallel_parents => [],

state => "done",

},

}

[debug] [pid:12077] Job 6 duplicated as 7

[warn] [pid:12077] Unable to wakeup scheduler: Connection refused

ok 15 - client returned new test_url for test 7

[debug] [pid:12077] Unrecognized referer 'localhost'

ok 16 - test 7 is scheduled

ok 17 - Worker started as IPC::Run=HASH(0x55eab103c788)

[debug] [pid:12077] Unrecognized referer 'localhost'

ok 18 - job with worker class qemu_i386 scheduled

[info] [pid:13113] worker 1:

- config file: /tmp/7v4CAal1xt/full-stack.d/config/workers.ini

- worker hostname: 1234cbae1a16

- isotovideo version: 24

- websocket API version: 1

- web UI hosts: http://localhost:9526

- class: qemu_i386,qemu_x86_64

- no cleanup: no

- pool directory: /tmp/7v4CAal1xt/full-stack.d/openqa/pool/1

[info] [pid:13113] CACHE: caching is enabled, setting up /tmp/7v4CAal1xt/full-stack.d/cache/localhost

[info] [pid:13113] Project dir for host http://localhost:9526 is /tmp/7v4CAal1xt/full-stack.d/openqa/share

[info] [pid:13113] Registering with openQA http://localhost:9526

[info] [pid:13113] Establishing ws connection via ws://localhost:9526/api/v1/ws/1

[info] [pid:13113] Registered and connected via websockets with openQA host http://localhost:9526 and worker ID 1

[debug] [pid:12076] Updating seen of worker 1 from worker_status (free)

ok 19 - at least one free worker with class qemu_i386 registered

[debug] [pid:12072] +=----------------=+

[debug] [pid:12072] -> Scheduling new jobs.

[debug] [pid:12072] Free workers: 1/1

[debug] [pid:12072] Scheduled jobs: 1

[debug] [pid:12072] Need to schedule 1 parallel jobs for job 7 (with priority 50)

[debug] [pid:12072] Assigned job '7' to worker ID '1'

[debug] [pid:12072] [Job#7] Prepare for being processed by worker 1

[debug] [pid:12076] Started to send message to 1 for job(s) 7

[debug] [pid:12072] Sent job(s) '7' to worker '1'

[debug] [pid:12072] Scheduler took 0.09811s to perform operations and allocated 1 jobs

[debug] [pid:13113] Accepting job 7 from http://localhost:9526.

[debug] [pid:12072] Allocated: { job => 7, worker => 1 }

[debug] [pid:13113] Setting job 7 from http://localhost:9526 up

# Subtest: results of test 7

[debug] [pid:13113] Preparing Mojo::IOLoop::ReadWriteProcess::Session

[info] [pid:13113] +++ setup notes +++

[info] [pid:13113] Running on 1234cbae1a16:1 (Linux 5.11.0-1027-aws #30~20.04.1-Ubuntu SMP Thu Jan 13 11:46:53 UTC 2022 x86_64)

[debug] [pid:13113] Job settings:

[debug] [pid:13113]

ARCH=i386

BUILD=1

CACHEDIRECTORY=/tmp/7v4CAal1xt/full-stack.d/cache

CACHELIMIT=50

DISTRI=tinycore

FLAVOR=flavor

INTEGRATION_TESTS=1

ISO=Core-7.2.iso

JOBTOKEN=NyTrTQWMU40CyqS4

LOCAL_UPLOAD=0

LOG_LEVEL=debug

MACHINE=coolone

NAME=00000007-tinycore-1-flavor-i386-Build1-core@coolone

OPENQA_HOSTNAME=localhost:9526

OPENQA_URL=http://localhost:9526

PRJDIR=/tmp/7v4CAal1xt/full-stack.d/openqa/share

PUBLISH_HDD_1=

QEMU=i386

QEMUPORT=20012

QEMU_NO_FDC_SET=1

QEMU_NO_KVM=1

QEMU_NO_TABLET=1

RETRY_DELAY=5

RETRY_DELAY_IF_WEBUI_BUSY=60

TEST=core

UEFI_PFLASH_VARS=/usr/share/qemu/ovmf-x86_64.bin

VERSION=1

VNC=91

WORKER_CLASS=qemu_i386,qemu_x86_64

WORKER_ID=1

WORKER_INSTANCE=1

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 0 of 3240)

[debug] [pid:13113] Found ISO, caching Core-7.2.iso

[info] [pid:13113] Downloading Core-7.2.iso, request #3 sent to Cache Service

[debug] [pid:13113] Updating status so job 7 is not considered dead.

[debug] [pid:13113] REST-API call: POST http://localhost:9526/api/v1/jobs/7/status

[debug] [pid:12076] Worker 1 accepted job 7

[debug] redirect to /assets/iso/Core-7.2.iso

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 1 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 2 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 3 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 4 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] attempt to open ws proxy for job tinycore-1-flavor-i386-Build1-core@coolone (7) where URL to os-autoinst command server is unknown

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Asking the worker 1 to start providing livestream for job 7

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 5 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 6 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 7 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 8 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 9 of 3240)

[debug] [pid:13113] Updating status so job 7 is not considered dead.

[debug] [pid:13113] REST-API call: POST http://localhost:9526/api/v1/jobs/7/status

[info] [pid:13113] Download of Core-7.2.iso processed

[debug] [pid:13113] Linked asset "/tmp/7v4CAal1xt/full-stack.d/cache/localhost/Core-7.2.iso" to "/tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/Core-7.2.iso"

[debug] [pid:13113] Symlinked from "/tmp/7v4CAal1xt/full-stack.d/openqa/share/tests/tinycore" to "/tmp/7v4CAal1xt/full-stack.d/openqa/pool/1/tinycore"

[info] [pid:13113] Preparing cgroup to start isotovideo

[warn] [pid:13113] Disabling cgroup usage because cgroup creation failed: mkdir /sys/fs/cgroup/systemd/docker: Permission denied at /usr/lib/perl5/vendor_perl/5.26.1/Mojo/File.pm line 84.

[info] [pid:13113] You can define a custom slice with OPENQA_CGROUP_SLICE or indicating the base mount with MOJO_CGROUP_FS.

[info] [pid:13113] Starting isotovideo container

[debug] [pid:13113] Registered process:13120

[info] [pid:13120] 13120: WORKING 7

[info] [pid:13113] isotovideo has been started (PID: 13120)

[debug] [pid:13120] +++ worker notes +++

[debug] [pid:13113] Running job 7 from http://localhost:9526: 00000007-tinycore-1-flavor-i386-Build1-core@coolone.

[debug] [pid:13113] REST-API call: POST http://localhost:9526/api/v1/jobs/7/status

[debug] [pid:12076] Updating seen of worker 1 from worker_status (working)

[debug] [pid:13113] Upload concluded (no current module)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 10 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 11 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 12 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 13 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 14 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 15 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 16 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 17 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 18 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 19 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 20 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 21 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 22 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 23 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 24 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 25 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 26 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 27 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 28 of 3240)

[debug] [pid:13113] REST-API call: POST http://localhost:9526/api/v1/jobs/7/status

[debug] [pid:13113] Upload concluded (at boot)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 29 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 30 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 31 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 32 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 33 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12156] connecting to os-autoinst command server for job 7 at ws://1234cbae1a16:20013/NyTrTQWMU40CyqS4/ws

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 34 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 35 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 36 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 37 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 38 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 39 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 40 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 41 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 42 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 43 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 44 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 45 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 46 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 47 of 3240)

[debug] [pid:12076] Updating seen of worker 1 from worker_status (working)

[debug] [pid:13113] REST-API call: POST http://localhost:9526/api/v1/jobs/7/status

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 48 of 3240)

[debug] [pid:13113] Upload concluded (at boot)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 49 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 50 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 51 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 52 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 53 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 54 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 55 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 56 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 57 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 58 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 59 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 60 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 61 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 62 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 63 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 64 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 65 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 66 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 67 of 3240)

[debug] [pid:13113] REST-API call: POST http://localhost:9526/api/v1/jobs/7/status

[debug] [pid:12077] creating f41/844/68dbb276a03240907d0e7e500d.png

[debug] [pid:12077] creating 68d/7ac/83452b72555f8a6b386629f91f.png

[debug] [pid:13113] Upload concluded (up to boot)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 68 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 69 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 70 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 71 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] [pid:12077] Unrecognized referer 'localhost'

[debug] looking for "f41/844/.thumbs/68dbb276a03240907d0e7e500d.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/f41/844/.thumbs/68dbb276a03240907d0e7e500d.png",

pid => 12077,

}, "Mojo::Asset::File")

[debug] looking for "68d/7ac/.thumbs/83452b72555f8a6b386629f91f.png" in ["/tmp/7v4CAal1xt/full-stack.d/openqa/images"]

[debug] found bless({

path => "/tmp/7v4CAal1xt/full-stack.d/openqa/images/68d/7ac/.thumbs/83452b72555f8a6b386629f91f.png",

pid => 12077,

}, "Mojo::Asset::File")

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 72 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 73 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 74 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 75 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 76 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 77 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 78 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 79 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 80 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 81 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 82 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 83 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 84 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 85 of 3240)

[debug] [pid:12076] Updating seen of worker 1 from worker_status (working)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 86 of 3240)

[debug] [pid:13113] REST-API call: POST http://localhost:9526/api/v1/jobs/7/status

[debug] [pid:13113] Upload concluded (at shutdown)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 87 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 88 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 89 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 90 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 91 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 92 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 93 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 94 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 95 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 96 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 97 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 98 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 99 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 100 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 101 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 102 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 103 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 104 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 105 of 3240)

[debug] [pid:13113] REST-API call: POST http://localhost:9526/api/v1/jobs/7/status

[debug] [pid:13113] Upload concluded (at shutdown)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 106 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 107 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 108 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 109 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 110 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 111 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 112 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 113 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 114 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 115 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 116 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 117 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 118 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 119 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 120 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 121 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 122 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 123 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 124 of 3240)

[debug] [pid:12076] Updating seen of worker 1 from worker_status (working)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 125 of 3240)

[debug] [pid:13113] REST-API call: POST http://localhost:9526/api/v1/jobs/7/status

[debug] [pid:13113] Upload concluded (at shutdown)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 126 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 127 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 128 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 129 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 130 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 131 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 132 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 133 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 134 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 135 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 136 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 137 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 138 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 139 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 140 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 141 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 142 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 143 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 144 of 3240)

[debug] [pid:13113] REST-API call: POST http://localhost:9526/api/v1/jobs/7/status

[debug] [pid:13113] Upload concluded (at shutdown)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 145 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 146 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 147 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 148 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 149 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 150 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 151 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 152 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 153 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 154 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 155 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 156 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 157 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 158 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 159 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 160 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 161 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 162 of 3240)

[debug] [pid:12076] Updating seen of worker 1 from worker_status (working)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 163 of 3240)

[debug] [pid:13113] REST-API call: POST http://localhost:9526/api/v1/jobs/7/status

[debug] [pid:13113] Upload concluded (at shutdown)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 164 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 165 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 166 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 167 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 168 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 169 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 170 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 171 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 172 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 173 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 174 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 175 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 176 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 177 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 178 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 179 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 180 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 181 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 182 of 3240)

[debug] [pid:13113] REST-API call: POST http://localhost:9526/api/v1/jobs/7/status

[debug] [pid:13113] Upload concluded (at shutdown)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 183 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 184 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 185 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 186 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 187 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 188 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 189 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 190 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 191 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 192 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 193 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 194 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 195 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 196 of 3240)

[debug] [pid:12077] Unrecognized referer 'localhost'

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 197 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 198 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 199 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 200 of 3240)

[debug] [pid:12076] Updating seen of worker 1 from worker_status (working)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 201 of 3240)

# Wait for jQuery successful: wait_for_result_panel: waiting for '(?^u:Result: passed)' (count 202 of 3240)

[debug] [pid:13113] REST-API call: POST http://localhost:9526/api/v1/jobs/7/status

[debug] [pid:13113] Upload concluded (at shutdown)