Project

General

Profile

Feature #21705 ยป cloudtest.log.txt

Tom Clegg, 05/15/2024 02:23 PM

 
1
workbench.tordo:~# ./arvados-server --version
2
./arvados-server e1e4cf1e604f0d2bbf4f959123edbf0b9d3474df-dev (go1.22.2)
3
workbench.tordo:~# ./arvados-server cloudtest
4
WARN[2024-05-15T14:18:02.098834379Z] deprecated or unknown config entry: Clusters.tordo.Workbench.MultiSiteSearch 
5
WARN[2024-05-15T14:18:02.099011554Z] deprecated or unknown config entry: Clusters.tordo.Workbench.SecretKeyBase 
6
INFO[2024-05-15T14:18:02.103836372Z] getting instance list                         FilterTags="map[]"
7
INFO[2024-05-15T14:18:02.399591278Z] got instance list                             Duration=295.659747ms N=23
8
INFO[2024-05-15T14:18:02.399648095Z] creating instance                             DeployPublicKey=true ImageID=ami-008be7e0232db39fb InitCommand="umask 0177 && echo -n \"978370c23b7d22f5eed3d98c5c47b63b20797131\" >/var/run/arvados-instance-secret\n" InstanceType=t3small ProviderInstanceType=t3.small Tags="map[ArvadosCloudTestPID:291151 ArvadosInstanceSecret:978370c23b7d22f5eed3d98c5c47b63b20797131 ArvadosInstanceSetID:zzzzz-zzzzz-zzzzzzcloudtest]"
9
INFO[2024-05-15T14:18:04.918040397Z] created instance                              Duration=2.518337987s Instance=i-0119b7335db336cfd
10
INFO[2024-05-15T14:18:04.918094138Z] showing login information                     Command="ssh -p22 admin@10.253.254.169"
11
INFO[2024-05-15T14:18:04.918106674Z] getting instance list                         FilterTags="map[ArvadosInstanceSetID:zzzzz-zzzzz-zzzzzzcloudtest]"
12
INFO[2024-05-15T14:18:05.003835563Z] got instance list                             Duration=85.712059ms N=0
13
ERRO[2024-05-15T14:18:05.003859869Z] cloud/driver Create succeeded, but instance is not in list  error="test instance missing from cloud provider's list"
14
INFO[2024-05-15T14:18:05.003873991Z] all expected tags are present                
15
INFO[2024-05-15T14:18:05.003884681Z] executing remote command                      Command="systemctl is-system-running"
16
INFO[2024-05-15T14:19:05.005425884Z] remote command failed                         Command="systemctl is-system-running" Duration=1m0.001525305s error="dial tcp 10.253.254.169:22: i/o timeout" stderr= stdout=
17
INFO[2024-05-15T14:19:05.005475848Z] waiting ProbeInterval                         Duration=10s
18
INFO[2024-05-15T14:19:15.006111282Z] getting instance list                         FilterTags="map[ArvadosInstanceSetID:zzzzz-zzzzz-zzzzzzcloudtest]"
19
INFO[2024-05-15T14:19:15.153190943Z] got instance list                             Duration=147.02648ms N=1
20
INFO[2024-05-15T14:19:15.153223317Z] found our instance in returned list           Address=10.253.254.169 Instance=i-0119b7335db336cfd
21
INFO[2024-05-15T14:19:15.153237392Z] executing remote command                      Command="systemctl is-system-running"
22
INFO[2024-05-15T14:19:17.995261776Z] remote command failed                         Command="systemctl is-system-running" Duration=2.84200462s error="Process exited with status 1" stderr= stdout="starting\n"
23
INFO[2024-05-15T14:19:17.995308921Z] waiting ProbeInterval                         Duration=10s
24
INFO[2024-05-15T14:19:27.999176693Z] getting instance list                         FilterTags="map[ArvadosInstanceSetID:zzzzz-zzzzz-zzzzzzcloudtest]"
25
INFO[2024-05-15T14:19:28.088517278Z] got instance list                             Duration=89.295203ms N=1
26
INFO[2024-05-15T14:19:28.088552431Z] found our instance in returned list           Address=10.253.254.169 Instance=i-0119b7335db336cfd
27
INFO[2024-05-15T14:19:28.088566369Z] executing remote command                      Command="systemctl is-system-running"
28
INFO[2024-05-15T14:19:28.099643669Z] remote command failed                         Command="systemctl is-system-running" Duration=11.062793ms error="Process exited with status 1" stderr= stdout="starting\n"
29
INFO[2024-05-15T14:19:28.099685548Z] waiting ProbeInterval                         Duration=10s
30
INFO[2024-05-15T14:19:38.103546850Z] getting instance list                         FilterTags="map[ArvadosInstanceSetID:zzzzz-zzzzz-zzzzzzcloudtest]"
31
INFO[2024-05-15T14:19:38.180994126Z] got instance list                             Duration=77.402892ms N=1
32
INFO[2024-05-15T14:19:38.181030276Z] found our instance in returned list           Address=10.253.254.169 Instance=i-0119b7335db336cfd
33
INFO[2024-05-15T14:19:38.181044746Z] executing remote command                      Command="systemctl is-system-running"
34
INFO[2024-05-15T14:19:38.193860303Z] remote command succeeded                      Command="systemctl is-system-running" Duration=12.799229ms stderr= stdout="running\n"
35
INFO[2024-05-15T14:19:38.193901282Z] showing login information                     Command="ssh -p22 admin@10.253.254.169"
36
INFO[2024-05-15T14:19:38.193913283Z] destroying instance                           Instance=i-0119b7335db336cfd
37
INFO[2024-05-15T14:19:38.554454219Z] destroyed instance                            Duration=360.525316ms Instance=i-0119b7335db336cfd
38
INFO[2024-05-15T14:19:38.554488043Z] getting instance list                         FilterTags="map[ArvadosInstanceSetID:zzzzz-zzzzz-zzzzzzcloudtest]"
39
INFO[2024-05-15T14:19:38.630260875Z] got instance list                             Duration=75.744015ms N=1
40
INFO[2024-05-15T14:19:38.630310825Z] found our instance in returned list           Address=10.253.254.169 Instance=i-0119b7335db336cfd
41
INFO[2024-05-15T14:19:38.630324773Z] instance still exists after calling Destroy   Instance=i-0119b7335db336cfd
42
INFO[2024-05-15T14:19:38.630338021Z] waiting SyncInterval                          Duration=1m0s
43
INFO[2024-05-15T14:20:38.634675501Z] destroying instance                           Instance=i-0119b7335db336cfd
44
INFO[2024-05-15T14:20:38.916516806Z] destroyed instance                            Duration=281.804505ms Instance=i-0119b7335db336cfd
45
INFO[2024-05-15T14:20:38.916550899Z] getting instance list                         FilterTags="map[ArvadosInstanceSetID:zzzzz-zzzzz-zzzzzzcloudtest]"
46
INFO[2024-05-15T14:20:39.050555969Z] got instance list                             Duration=133.978872ms N=0
47
INFO[2024-05-15T14:20:39.050586718Z] instance no longer appears in list            Instance=i-0119b7335db336cfd
48
INFO[2024-05-15T14:20:39.050600463Z] exiting                                      
    (1-1/1)