[stdout] Docker app's state test === RUN TestAppStatus apps: '[t1 t2]' state: 'RUNNING' secs: 600 time: 2023-10-16T15:58:14.10301732Z out: appName t1 state changed to UNKNOWN time: 2023-10-16T15:58:14.103045052Z out: appName t2 state changed to UNKNOWN time: 2023-10-16T15:58:23.928764307Z out: appName t1 state changed to INITIAL time: 2023-10-16T15:58:25.931481541Z out: appName t1 state changed to RESOLVING_TAG time: 2023-10-16T15:58:33.936989363Z out: appName t2 state changed to RESOLVING_TAG time: 2023-10-16T15:58:41.942564222Z out: appName t2 state changed to DOWNLOAD_STARTED time: 2023-10-16T15:58:43.943912499Z out: appName t1 state changed to DOWNLOAD_STARTED time: 2023-10-16T15:58:45.946307651Z out: appName t2 state changed to UNKNOWN time: 2023-10-16T15:58:45.94633869Z out: appName t1 state changed to DOWNLOAD_STARTED (0%) time: 2023-10-16T15:59:29.986297473Z out: appName t1 state changed to DOWNLOAD_STARTED (100%) time: 2023-10-16T15:59:36.993276419Z out: appName t1 state changed to DOWNLOAD_STARTED (0%) time: 2023-10-16T16:00:28.048053276Z out: appName t1 state changed to DOWNLOAD_STARTED (4%) time: 2023-10-16T16:00:35.055890289Z out: appName t1 state changed to DOWNLOAD_STARTED (9%) time: 2023-10-16T16:00:41.061613558Z out: appName t1 state changed to DOWNLOAD_STARTED (10%) time: 2023-10-16T16:00:48.070351822Z out: appName t1 state changed to DOWNLOAD_STARTED (12%) time: 2023-10-16T16:00:55.075206761Z out: appName t1 state changed to DOWNLOAD_STARTED (27%) time: 2023-10-16T16:00:59.079389442Z out: appName t1 state changed to DOWNLOAD_STARTED (41%) time: 2023-10-16T16:01:01.081746479Z out: appName t1 state changed to DOWNLOAD_STARTED (42%) time: 2023-10-16T16:01:10.090308799Z out: appName t1 state changed to DOWNLOAD_STARTED (45%) time: 2023-10-16T16:01:16.095473906Z out: appName t1 state changed to DOWNLOAD_STARTED (53%) time: 2023-10-16T16:01:21.100339349Z out: appName t1 state changed to DOWNLOAD_STARTED (62%) time: 2023-10-16T16:01:24.103289515Z out: appName t1 state changed to DOWNLOAD_STARTED (64%) time: 2023-10-16T16:01:35.110177369Z out: appName t1 state changed to DOWNLOAD_STARTED (65%) time: 2023-10-16T16:01:41.115728328Z out: appName t1 state changed to DOWNLOAD_STARTED (79%) time: 2023-10-16T16:01:46.121230737Z out: appName t1 state changed to DOWNLOAD_STARTED (82%) time: 2023-10-16T16:01:52.126150029Z out: appName t1 state changed to DOWNLOAD_STARTED (90%) time: 2023-10-16T16:01:57.13082904Z out: appName t1 state changed to DOWNLOAD_STARTED (93%) time: 2023-10-16T16:02:06.139799567Z out: appName t1 state changed to DOWNLOAD_STARTED (96%) time: 2023-10-16T16:02:07.140917777Z out: appName t1 state changed to DOWNLOAD_STARTED (99%) time: 2023-10-16T16:02:16.149586281Z out: appName t1 state changed to DOWNLOAD_STARTED (100%) time: 2023-10-16T16:02:16.149615065Z out: appName t2 state changed to LOADING time: 2023-10-16T16:02:17.150155033Z out: appName t1 state changed to VERIFIED time: 2023-10-16T16:03:08.195850899Z out: appName t1 state changed to DELIVERED time: 2023-10-16T16:03:09.196130856Z out: appName t2 state changed to DELIVERED time: 2023-10-16T16:03:17.203465952Z out: appName t2 state changed to CREATING_VOLUME time: 2023-10-16T16:03:18.203858124Z out: appName t1 state changed to CREATING_VOLUME app_test.go:171: ASSERTION FAILED (2023-10-16T16:08:14.103355191Z): expected apps [t1 t2] in RUNNING state app_test.go:173: actual t1: CREATING_VOLUME app_test.go:175: history of states for t1: app_test.go:177: state: no info from controller received in: 2023-10-16T15:58:14.091318525Z app_test.go:177: state: UNKNOWN received in: 2023-10-16T15:58:14.103013123Z app_test.go:177: state: INITIAL received in: 2023-10-16T15:58:23.928758897Z app_test.go:177: state: RESOLVING_TAG received in: 2023-10-16T15:58:25.931474188Z app_test.go:177: state: DOWNLOAD_STARTED received in: 2023-10-16T15:58:43.943905495Z app_test.go:177: state: DOWNLOAD_STARTED (0%) received in: 2023-10-16T15:58:45.946338299Z app_test.go:177: state: D

Process completed with exit code 1.