[01:37:30.375] New invocation is queued and will start shortly
[01:37:31.464] Starting the invocation (attempt 1)
[01:37:31.504] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/04ee3f4a8e063120eb7d617cfaf02306533d21a3"
[01:37:31.505] Popped gitiles commit info from properties and tags
[01:37:31.505] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:37:31.505] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:37:31.505] Buildbucket request:
{
"requestId": "8980656629321688016",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroidCoverage x86"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "04ee3f4a8e063120eb7d617cfaf02306533d21a3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8980656629321688016"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTAndroidCoverage x86"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:37:32.109] Scheduled build:
{
"id": "8815226567389493649",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroidCoverage x86"
},
"number": 3505,
"createdBy": "project:chromium",
"createTime": "2022-05-03T01:37:31.586827969Z",
"updateTime": "2022-05-03T01:37:31.586827969Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "04ee3f4a8e063120eb7d617cfaf02306533d21a3",
"ref": "refs/heads/main"
}
}
}
[01:37:32.109] Task URL: https://cr-buildbucket.appspot.com/build/8815226567389493649
[01:37:32.109] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:2:0) after 8m3s
[01:37:50.512] Received PubSub notification, asking Buildbucket for the build status
[01:37:50.579] Build status: STARTED
[01:45:35.129] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:2:0)
[01:45:35.151] Build status: STARTED
[01:45:35.151] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:4:0) after 2m41s
[01:48:16.260] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:4:0)
[01:48:16.289] Build status: STARTED
[01:48:16.289] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:5:0) after 2m57s
[01:51:13.313] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:5:0)
[01:51:13.336] Build status: STARTED
[01:51:13.336] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:6:0) after 4m18s
[01:55:31.470] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:6:0)
[01:55:31.493] Build status: STARTED
[01:55:31.493] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:7:0) after 1m58s
[01:57:29.516] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:7:0)
[01:57:29.535] Build status: STARTED
[01:57:29.535] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:8:0) after 8m56s
[02:06:25.880] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:8:0)
[02:06:25.902] Build status: STARTED
[02:06:25.902] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:9:0) after 3m8s
[02:09:34.004] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:9:0)
[02:09:34.028] Build status: STARTED
[02:09:34.028] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:10:0) after 4m22s
[02:13:56.398] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:10:0)
[02:13:56.487] Build status: STARTED
[02:13:56.487] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:11:0) after 3m21s
[02:17:17.720] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:11:0)
[02:17:17.776] Build status: STARTED
[02:17:17.776] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:12:0) after 4m23s
[02:21:40.803] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:12:0)
[02:21:40.828] Build status: STARTED
[02:21:40.828] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:13:0) after 2m1s
[02:23:41.958] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:13:0)
[02:23:41.986] Build status: STARTED
[02:23:41.986] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:14:0) after 8m33s
[02:32:15.329] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:14:0)
[02:32:15.366] Build status: STARTED
[02:32:15.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:15:0) after 7m53s
[02:40:08.394] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:15:0)
[02:40:08.451] Build status: STARTED
[02:40:08.451] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:16:0) after 6m33s
[02:46:41.523] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:16:0)
[02:46:41.553] Build status: STARTED
[02:46:41.553] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:17:0) after 7m58s
[02:54:39.580] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:17:0)
[02:54:39.603] Build status: STARTED
[02:54:39.603] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:18:0) after 1m54s
[02:56:33.935] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:18:0)
[02:56:33.968] Build status: STARTED
[02:56:33.968] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:19:0) after 4m59s
[03:01:33.284] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:19:0)
[03:01:33.362] Build status: STARTED
[03:01:33.362] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:20:0) after 4m24s
[03:05:57.511] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:20:0)
[03:05:57.533] Build status: STARTED
[03:05:57.533] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:21:0) after 5m59s
[03:11:56.676] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:21:0)
[03:11:56.726] Build status: STARTED
[03:11:56.726] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:22:0) after 8m5s
[03:20:01.866] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:22:0)
[03:20:01.903] Build status: STARTED
[03:20:01.903] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:23:0) after 4m2s
[03:24:04.023] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:23:0)
[03:24:04.057] Build status: STARTED
[03:24:04.057] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:24:0) after 5m20s
[03:29:24.086] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:24:0)
[03:29:24.174] Build status: STARTED
[03:29:24.174] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:25:0) after 2m43s
[03:32:07.288] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:25:0)
[03:32:07.395] Build status: STARTED
[03:32:07.395] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:26:0) after 1m8s
[03:33:15.640] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:26:0)
[03:33:15.675] Build status: STARTED
[03:33:15.675] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:27:0) after 3m0s
[03:36:15.699] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:27:0)
[03:36:15.721] Build status: STARTED
[03:36:15.721] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:28:0) after 8m28s
[03:44:43.835] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:28:0)
[03:44:43.951] Build status: STARTED
[03:44:43.951] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:29:0) after 3m33s
[03:48:16.998] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:29:0)
[03:48:17.022] Build status: STARTED
[03:48:17.022] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:30:0) after 9m59s
[03:58:16.320] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:30:0)
[03:58:16.351] Build status: STARTED
[03:58:16.351] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:31:0) after 1m38s
[03:59:54.515] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:31:0)
[03:59:54.549] Build status: STARTED
[03:59:54.549] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:32:0) after 1m6s
[04:01:00.583] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:32:0)
[04:01:00.612] Build status: STARTED
[04:01:00.612] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:33:0) after 6m26s
[04:07:26.732] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:33:0)
[04:07:26.764] Build status: STARTED
[04:07:26.764] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:34:0) after 2m30s
[04:09:56.881] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:34:0)
[04:09:56.917] Build status: STARTED
[04:09:56.918] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:35:0) after 7m47s
[04:17:43.943] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:35:0)
[04:17:43.990] Build status: STARTED
[04:17:43.990] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:36:0) after 5m25s
[04:23:09.017] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:36:0)
[04:23:09.048] Build status: STARTED
[04:23:09.048] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:37:0) after 7m6s
[04:30:15.089] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:37:0)
[04:30:15.121] Build status: STARTED
[04:30:15.121] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:38:0) after 8m51s
[04:39:06.146] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:38:0)
[04:39:06.177] Build status: STARTED
[04:39:06.177] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:39:0) after 2m57s
[04:42:03.202] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:39:0)
[04:42:03.224] Build status: STARTED
[04:42:03.224] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:40:0) after 2m48s
[04:44:51.251] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:40:0)
[04:44:51.279] Build status: STARTED
[04:44:51.280] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:41:0) after 4m58s
[04:49:49.568] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:41:0)
[04:49:49.677] Build status: STARTED
[04:49:49.677] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:42:0) after 2m42s
[04:52:31.800] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:42:0)
[04:52:31.833] Build status: STARTED
[04:52:31.833] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:43:0) after 5m36s
[04:58:07.990] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:43:0)
[04:58:08.013] Build status: STARTED
[04:58:08.013] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:44:0) after 6m12s
[05:04:20.009] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:44:0)
[05:04:20.084] Build status: STARTED
[05:04:20.084] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:45:0) after 9m32s
[05:13:52.372] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:45:0)
[05:13:52.398] Build status: STARTED
[05:13:52.398] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:46:0) after 4m12s
[05:18:04.482] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:46:0)
[05:18:04.541] Build status: STARTED
[05:18:04.542] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:47:0) after 9m19s
[05:27:23.568] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:47:0)
[05:27:23.636] Build status: STARTED
[05:27:23.636] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:48:0) after 6m45s
[05:34:08.661] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:48:0)
[05:34:08.702] Build status: STARTED
[05:34:08.702] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:49:0) after 5m15s
[05:39:23.727] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:49:0)
[05:39:23.755] Build status: STARTED
[05:39:23.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:50:0) after 1m37s
[05:41:00.818] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:50:0)
[05:41:00.854] Build status: STARTED
[05:41:00.854] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:51:0) after 8m25s
[05:49:26.014] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:51:0)
[05:49:26.040] Build status: STARTED
[05:49:26.040] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:52:0) after 6m29s
[05:55:55.067] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:52:0)
[05:55:55.092] Build status: STARTED
[05:55:55.092] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:53:0) after 7m37s
[06:03:32.245] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:53:0)
[06:03:32.278] Build status: STARTED
[06:03:32.278] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:54:0) after 8m54s
[06:12:26.441] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:54:0)
[06:12:26.475] Build status: STARTED
[06:12:26.476] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:55:0) after 5m26s
[06:17:52.600] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:55:0)
[06:17:52.620] Build status: STARTED
[06:17:52.620] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:56:0) after 9m54s
[06:27:46.837] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:56:0)
[06:27:46.922] Build status: STARTED
[06:27:46.922] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:57:0) after 5m53s
[06:33:39.945] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:57:0)
[06:33:40.000] Build status: STARTED
[06:33:40.000] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:58:0) after 7m38s
[06:41:18.022] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:58:0)
[06:41:18.059] Build status: STARTED
[06:41:18.059] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:59:0) after 8m0s
[06:49:18.076] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:59:0)
[06:49:18.144] Build status: STARTED
[06:49:18.144] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:60:0) after 2m4s
[06:51:22.444] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:60:0)
[06:51:22.471] Build status: STARTED
[06:51:22.471] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:61:0) after 1m30s
[06:52:52.479] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:61:0)
[06:52:52.501] Build status: STARTED
[06:52:52.501] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:62:0) after 4m6s
[06:56:58.523] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:62:0)
[06:56:58.546] Build status: STARTED
[06:56:58.546] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:63:0) after 3m45s
[07:00:43.641] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:63:0)
[07:00:43.675] Build status: STARTED
[07:00:43.675] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8980656629321688016:64:0) after 6m9s
[07:04:14.783] Received PubSub notification, asking Buildbucket for the build status
[07:04:14.823] Build:
{
"id": "8815226567389493649",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroidCoverage x86"
},
"number": 3505,
"createdBy": "project:chromium",
"createTime": "2022-05-03T01:37:31.586827969Z",
"startTime": "2022-05-03T01:37:49.262228Z",
"endTime": "2022-05-03T07:04:14.289232221Z",
"updateTime": "2022-05-03T07:04:14.289232221Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "04ee3f4a8e063120eb7d617cfaf02306533d21a3",
"ref": "refs/heads/main"
}
}
}
[07:04:14.823] Invocation finished in 5h26m44.462706073s with status SUCCEEDED