[stdout] Docker app's state test === RUN TestAppStatus apps: '[eclient]' state: 'HALTED' secs: 300 time: 2024-09-25T12:14:29.643138082Z out: appName eclient state changed to HALTING app_test.go:171: ASSERTION FAILED (2024-09-25T12:19:29.644132191Z): expected apps [eclient] in HALTED state app_test.go:173: actual eclient: HALTING app_test.go:175: history of states for eclient: app_test.go:177: state: no info from controller received in: 2024-09-25T12:14:29.62702241Z app_test.go:177: state: HALTING received in: 2024-09-25T12:14:29.643135697Z --- app eclient logs --- {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"brctl: bridge bn1: Resource busy", "msgid":"1", "timestamp":"2024-09-25T12:14:12.709192381Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 12.951818][ T1] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.", "msgid":"2", "timestamp":"2024-09-25T12:15:00.519275578Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 15.937347][ T99] ata3: SATA link down (SStatus 0 SControl 300)", "msgid":"3", "timestamp":"2024-09-25T12:15:01.543117313Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 16.705416][ T101] ata4: SATA link down (SStatus 0 SControl 300)", "msgid":"4", "timestamp":"2024-09-25T12:15:08.999358367Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 24.733424][ T97] ata2: SATA link down (SStatus 0 SControl 300)", "msgid":"5", "timestamp":"2024-09-25T12:15:11.443478832Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 26.685586][ T95] ata1: SATA link down (SStatus 0 SControl 300)", "msgid":"6", "timestamp":"2024-09-25T12:15:12.715328929Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 28.045484][ T105] ata6: SATA link down (SStatus 0 SControl 300)", "msgid":"7", "timestamp":"2024-09-25T12:15:19.143473801Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 34.622392][ T103] ata5: SATA link down (SStatus 0 SControl 300)", "msgid":"8", "timestamp":"2024-09-25T12:15:19.575451286Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 35.871673][ T1] hub 2-0:1.0: USB hub found", "msgid":"9", "timestamp":"2024-09-25T12:15:21.471598748Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 36.703953][ T1] hub 2-0:1.0: 15 ports detected", "msgid":"10", "timestamp":"2024-09-25T12:15:22.567561192Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 37.826051][ T1] usbcore: registered new interface driver usb-storage", "msgid":"11", "timestamp":"2024-09-25T12:15:23.463601021Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 38.685070][ T69] usb 1-1: new high-speed USB device number 2 using xhci_hcd", "msgid":"12", "timestamp":"2024-09-25T12:15:26.343597936Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 42.428141][ T1] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12", "msgid":"13", "timestamp":"2024-09-25T12:15:30.343577452Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 52.071216][ T1] serio: i8042 KBD port at 0x60,0x64 irq 1", "msgid":"14", "timestamp":"2024-09-25T12:15:44.903796632Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 64.065461][ T1] serio: i8042 AUX port at 0x60,0x64 irq 12", "msgid":"15", "timestamp":"2024-09-25T12:15:58.256000138Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 76.066867][ T1] hv_vmbus: registering driver hyperv_keyboard", "msgid":"16", "timestamp":"2024-09-25T12:16:20.904406489Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 100.060479][ C0] watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [swapper/0:1]", "msgid":"17", "timestamp":"2024-09-25T12:16:40.904628204Z"} {"source":"565f3d84-8c16-4040-b03d-4d7924794273.1.1", "content":"[ 100.060482][ C0] Modules linked in:", "msgid":"18", "timestamp":"2024-09-25T12:16:5

Process completed with exit code 1.

[stdout] Docker app's state test === RUN TestAppStatus apps: '[dhcp-server]' state: 'RUNNING' secs: 600 time: 2024-09-25T12:27:25.406652712Z out: appName dhcp-server state changed to UNKNOWN time: 2024-09-25T12:27:27.6331355Z out: appName dhcp-server state changed to RESOLVING_TAG time: 2024-09-25T12:27:28.634032788Z out: appName dhcp-server state changed to DOWNLOAD_STARTED time: 2024-09-25T12:27:28.634058166Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (0%) time: 2024-09-25T12:27:28.634063486Z out: appName dhcp-server state changed to DOWNLOAD_STARTED time: 2024-09-25T12:27:28.634094163Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (50%) time: 2024-09-25T12:27:29.634515736Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (0%) time: 2024-09-25T12:27:32.636762832Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (3%) time: 2024-09-25T12:27:33.638091194Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (12%) time: 2024-09-25T12:27:33.63811581Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (18%) time: 2024-09-25T12:27:33.638134906Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (68%) time: 2024-09-25T12:27:34.63850007Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (27%) time: 2024-09-25T12:27:34.638531769Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (36%) time: 2024-09-25T12:27:34.63854794Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (37%) time: 2024-09-25T12:27:34.638561544Z out: appName dhcp-server state changed to LOADING time: 2024-09-25T12:27:36.639865215Z out: appName dhcp-server state changed to CREATING_VOLUME time: 2024-09-25T12:27:54.65350027Z out: appName dhcp-server state changed to INSTALLED time: 2024-09-25T12:27:55.65521934Z out: appName dhcp-server state changed to BOOTING time: 2024-09-25T12:27:56.656383571Z out: appName dhcp-server state changed to RUNNING testProc.go:84: time: 2024-09-25T12:27:56.656408858Z out: projects.ProcInfoFunc done with return: time: 2024-09-25T12:27:56.656403108Z out: app dhcp-server state RUNNING testContext.go:271: done for device d15e8e1d-d254-45e9-813d-69f3be664fe6 --- PASS: TestAppStatus (32.26s) PASS [stderr] time="2024-09-25T12:27:56Z" level=info msg="time: 2024-09-25T12:27:56.656408858Z out: projects.ProcInfoFunc done with return: \ntime: 2024-09-25T12:27:56.656403108Z out: app dhcp-server state RUNNING\n" > exec -t 2m bash wait_and_get_ifconfig.sh dhcp-server 8027 [stderr] % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:01 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:02 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:03 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:04 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:05 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:06 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:07 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:08 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:09 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:10 --:--:-- 0 curl: (28) Operation timed out after 10001 milliseconds with 0 bytes received % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:01 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:02 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:--

Process completed with exit code 1.

[stdout] Log/Info/Metric Test === RUN TestInfo lim_test.go:273: time: 2024-09-25T12:29:20.07224891Z out: Wait for info of 7563089d-01ac-4e0c-81d0-19ec806b2ffc number=1 timewait=3m0s testContext.go:302: WaitForProc terminated by timeout 3m0s testContext.go:285: WaitForProcWithErrorCallback terminated by timeout 3m0s --- FAIL: TestInfo (180.00s) FAIL FAIL: ../volume/testdata/volumes_test.txt:40: command failure

Process completed with exit code 1.

[stdout] time="2024-09-25T12:41:02Z" level=fatal msg="command curl failed: exit status 56" time="2024-09-25T12:41:07Z" level=fatal msg="command curl failed: exit status 56" [stderr] % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:01 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:02 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:03 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:04 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:05 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:06 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:07 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:08 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:09 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:10 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:11 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:12 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:13 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:14 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:15 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:16 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:17 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:18 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:19 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:20 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:21 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:22 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:23 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:24 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:25 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:26 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:27 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:28 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:29 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:30 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:31 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:32 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:33 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:34 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:35 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:36 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:37 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:38 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:39 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:40 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:41 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:42 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:43 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:44 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:45 --:--:-- 0 0 0 0 0 0 0 0

Process completed with exit code 1.

[stdout] Docker app's state test === RUN TestAppStatus apps: '[app-profile-1 app-profile-2 app-profile-1-2 local-manager]' state: 'RUNNING' secs: 1200 time: 2024-09-25T12:27:10.493942838Z out: appName local-manager state changed to RUNNING time: 2024-09-25T12:27:10.493954361Z out: appName app-profile-1 state changed to RUNNING time: 2024-09-25T12:27:10.49395881Z out: appName app-profile-2 state changed to RESOLVING_TAG time: 2024-09-25T12:27:10.493962788Z out: appName app-profile-1-2 state changed to INITIAL time: 2024-09-25T12:27:10.507941604Z out: appName app-profile-1-2 state changed to RESOLVING_TAG time: 2024-09-25T12:27:11.509237717Z out: appName app-profile-2 state changed to LOADING time: 2024-09-25T12:27:11.509303195Z out: appName app-profile-1-2 state changed to CREATING_VOLUME time: 2024-09-25T12:27:11.509311001Z out: appName app-profile-2 state changed to CREATING_VOLUME time: 2024-09-25T12:27:12.510383583Z out: appName app-profile-2 state changed to INSTALLED time: 2024-09-25T12:27:12.510639266Z out: appName app-profile-1-2 state changed to INSTALLED: [description:"Remaining memory bytes 358088704 app instance needs 928514048" timestamp:{seconds:1727267231 nanos:427199217} severity:SEVERITY_WARNING entities:{entity:ENTITY_APP_INSTANCE entity_id:"8e83ed55-d322-4586-810d-d8e88ebfd059"} entities:{entity:ENTITY_APP_INSTANCE entity_id:"2759f953-b943-4bcd-8255-0039de450f79"} entities:{entity:ENTITY_APP_INSTANCE entity_id:"ec9efc80-57b2-4cab-89d4-b3611bbbf4ae"} retry_condition:"Retry will be triggered when one or more apps will shutdown"] time: 2024-09-25T12:27:13.512200468Z out: appName app-profile-2 state changed to BOOTING time: 2024-09-25T12:27:14.512811774Z out: appName app-profile-2 state changed to RUNNING app_test.go:171: ASSERTION FAILED (2024-09-25T12:47:10.494870656Z): expected apps [app-profile-1 app-profile-2 app-profile-1-2 local-manager] in RUNNING state app_test.go:173: actual app-profile-1: RUNNING --- app app-profile-1 logs --- {"source":"2759f953-b943-4bcd-8255-0039de450f79.1.2", "content":"brctl: bridge bn1: Resource busy", "msgid":"1", "timestamp":"2024-09-25T12:27:07.921708017Z"} {"source":"2759f953-b943-4bcd-8255-0039de450f79.1.2", "content":"[ 1.691738][ T1] loop: module loaded", "msgid":"2", "timestamp":"2024-09-25T12:27:11.458335641Z"} {"source":"2759f953-b943-4bcd-8255-0039de450f79.1.2", "content":"[ 1.793785][ T1] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized", "msgid":"3", "timestamp":"2024-09-25T12:27:11.561082825Z"} {"source":"2759f953-b943-4bcd-8255-0039de450f79.1.2", "content":"[ 1.794862][ T1] Microchip SmartPQI Driver (v2.1.18-045)", "msgid":"4", "timestamp":"2024-09-25T12:27:11.561805561Z"} {"source":"2759f953-b943-4bcd-8255-0039de450f79.1.2", "content":"[ 1.795465][ T1] megasas: 07.719.03.00-rc1", "msgid":"5", "timestamp":"2024-09-25T12:27:11.562261163Z"} {"source":"2759f953-b943-4bcd-8255-0039de450f79.1.2", "content":"[ 1.795930][ T1] mpt3sas version 43.100.00.00 loaded", "msgid":"6", "timestamp":"2024-09-25T12:27:11.562743548Z"} {"source":"2759f953-b943-4bcd-8255-0039de450f79.1.2", "content":"[ 1.796979][ T1] VMware PVSCSI driver - version 1.0.7.0-k", "msgid":"7", "timestamp":"2024-09-25T12:27:11.563955469Z"} {"source":"2759f953-b943-4bcd-8255-0039de450f79.1.2", "content":"[ 1.797557][ T1] hv_vmbus: registering driver hv_storvsc", "msgid":"8", "timestamp":"2024-09-25T12:27:11.564367378Z"} {"source":"2759f953-b943-4bcd-8255-0039de450f79.1.2", "content":"[ 1.798908][ T1] ACPI: \\_SB_.GSIA: Enabled at IRQ 16", "msgid":"9", "timestamp":"2024-09-25T12:27:11.565781935Z"} {"source":"2759f953-b943-4bcd-8255-0039de450f79.1.2", "content":"[ 1.803805][ T1] ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode", "msgid":"10", "timestamp":"2024-09-25T12:27:11.570969818Z"} {"source":"2759f953-b943-4bcd-8255-0039de450f79.1.2", "content":"[ 1.804528][ T1] ahci 0000:00:1f.2: flags: 64bit ncq only ", "msgid":"11", "timestamp":"2024-09-25T12:27:11.571420997Z"} {"source"

Process completed with exit code 1.

[stdout] Log/Info/Metric Test === RUN TestInfo lim_test.go:273: time: 2024-09-25T12:26:35.122861442Z out: Wait for info of 64b6d60d-cea0-4e9e-aaef-3b4394f6c5ae number=1 timewait=30m0s testContext.go:302: WaitForProc terminated by timeout 30m0s testContext.go:285: WaitForProcWithErrorCallback terminated by timeout 30m0s --- FAIL: TestInfo (1800.00s) FAIL [exit status 1] FAIL: ../update_eve_image/testdata/update_eve_image_http.txt:46: command failure

Process completed with exit code 1.

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/