[22:29:12.925] New invocation is queued and will start shortly
[22:29:14.108] Starting the invocation (attempt 1)
[22:29:14.148] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1cc656fa4b83a572a6156eef4104d80b133f37c9"
[22:29:14.148] Popped gitiles commit info from properties and tags
[22:29:14.148] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:29:14.148] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:29:14.148] Buildbucket request:
{
"requestId": "9008391147275562304",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1cc656fa4b83a572a6156eef4104d80b133f37c9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9008391147275562304"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTAndroid"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:29:14.871] Scheduled build:
{
"id": "8842961085215623312",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid"
},
"number": 16917,
"createdBy": "project:chromium",
"createTime": "2021-06-30T22:29:14.257956Z",
"updateTime": "2021-06-30T22:29:14.786644Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1cc656fa4b83a572a6156eef4104d80b133f37c9",
"ref": "refs/heads/main"
}
}
}
[22:29:14.871] Task URL: https://cr-buildbucket.appspot.com/build/8842961085215623312
[22:29:14.871] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:2:0) after 3m22s
[22:29:43.166] Received PubSub notification, asking Buildbucket for the build status
[22:29:43.196] Build status: STARTED
[22:32:37.000] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:2:0)
[22:32:37.033] Build status: STARTED
[22:32:37.033] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:4:0) after 6m23s
[22:39:00.171] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:4:0)
[22:39:00.204] Build status: STARTED
[22:39:00.204] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:5:0) after 1m16s
[22:40:16.481] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:5:0)
[22:40:16.518] Build status: STARTED
[22:40:16.518] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:6:0) after 6m54s
[22:47:10.878] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:6:0)
[22:47:10.910] Build status: STARTED
[22:47:10.910] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:7:0) after 5m47s
[22:52:57.941] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:7:0)
[22:52:57.978] Build status: STARTED
[22:52:57.978] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:8:0) after 1m43s
[22:54:41.088] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:8:0)
[22:54:41.123] Build status: STARTED
[22:54:41.123] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:9:0) after 3m24s
[22:58:05.152] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:9:0)
[22:58:05.182] Build status: STARTED
[22:58:05.182] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:10:0) after 8m13s
[23:06:18.252] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:10:0)
[23:06:18.288] Build status: STARTED
[23:06:18.288] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:11:0) after 7m11s
[23:13:29.520] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:11:0)
[23:13:29.558] Build status: STARTED
[23:13:29.558] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:12:0) after 1m16s
[23:14:45.698] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:12:0)
[23:14:45.728] Build status: STARTED
[23:14:45.728] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:13:0) after 1m57s
[23:16:42.840] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:13:0)
[23:16:42.873] Build status: STARTED
[23:16:42.873] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:14:0) after 4m37s
[23:21:20.000] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:14:0)
[23:21:20.041] Build status: STARTED
[23:21:20.041] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:15:0) after 8m22s
[23:29:42.067] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:15:0)
[23:29:42.102] Build status: STARTED
[23:29:42.102] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:16:0) after 2m26s
[23:32:08.129] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:16:0)
[23:32:08.162] Build status: STARTED
[23:32:08.163] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:17:0) after 1m8s
[23:33:16.190] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:17:0)
[23:33:16.229] Build status: STARTED
[23:33:16.229] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:18:0) after 3m57s
[23:37:13.327] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:18:0)
[23:37:13.407] Build status: STARTED
[23:37:13.407] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:19:0) after 1m36s
[23:38:49.530] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:19:0)
[23:38:49.568] Build status: STARTED
[23:38:49.568] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:20:0) after 2m12s
[23:41:01.639] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:20:0)
[23:41:01.668] Build status: STARTED
[23:41:01.668] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:21:0) after 9m53s
[23:50:54.725] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:21:0)
[23:50:54.759] Build status: STARTED
[23:50:54.759] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:22:0) after 9m48s
[00:00:42.919] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:22:0)
[00:00:42.954] Build status: STARTED
[00:00:42.955] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:23:0) after 2m28s
[00:03:11.083] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:23:0)
[00:03:11.109] Build status: STARTED
[00:03:11.109] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:24:0) after 8m18s
[00:11:29.168] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:24:0)
[00:11:29.212] Build status: STARTED
[00:11:29.212] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:25:0) after 8m44s
[00:20:13.420] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:25:0)
[00:20:13.455] Build status: STARTED
[00:20:13.455] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:26:0) after 4m54s
[00:25:07.643] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:26:0)
[00:25:07.680] Build status: STARTED
[00:25:07.680] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:27:0) after 9m29s
[00:34:36.774] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:27:0)
[00:34:36.825] Build status: STARTED
[00:34:36.825] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:28:0) after 3m55s
[00:38:31.886] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:28:0)
[00:38:31.925] Build status: STARTED
[00:38:31.925] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:29:0) after 5m48s
[00:44:19.965] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:29:0)
[00:44:19.999] Build status: STARTED
[00:44:19.999] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:30:0) after 6m45s
[00:51:05.211] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:30:0)
[00:51:05.248] Build status: STARTED
[00:51:05.248] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:31:0) after 7m15s
[00:58:20.549] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:31:0)
[00:58:20.583] Build status: STARTED
[00:58:20.583] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:32:0) after 3m18s
[01:01:38.594] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:32:0)
[01:01:38.623] Build status: STARTED
[01:01:38.623] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:33:0) after 3m36s
[01:05:14.748] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:33:0)
[01:05:14.783] Build status: STARTED
[01:05:14.783] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:34:0) after 3m13s
[01:08:27.869] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:34:0)
[01:08:27.903] Build status: STARTED
[01:08:27.903] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:35:0) after 1m28s
[01:09:55.937] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:35:0)
[01:09:55.967] Build status: STARTED
[01:09:55.967] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:36:0) after 6m7s
[01:16:03.045] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:36:0)
[01:16:03.091] Build status: STARTED
[01:16:03.091] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:37:0) after 1m49s
[01:17:52.151] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:37:0)
[01:17:52.193] Build status: STARTED
[01:17:52.193] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:38:0) after 6m4s
[01:23:56.418] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:38:0)
[01:23:56.444] Build status: STARTED
[01:23:56.444] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:39:0) after 9m38s
[01:33:34.902] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:39:0)
[01:33:34.936] Build status: STARTED
[01:33:34.936] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:40:0) after 6m31s
[01:40:05.976] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:40:0)
[01:40:06.145] Build status: STARTED
[01:40:06.145] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:41:0) after 6m49s
[01:46:55.184] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:41:0)
[01:46:55.219] Build status: STARTED
[01:46:55.219] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:42:0) after 7m20s
[01:54:15.408] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:42:0)
[01:54:15.437] Build status: STARTED
[01:54:15.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:43:0) after 1m3s
[01:55:18.458] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:43:0)
[01:55:18.494] Build status: STARTED
[01:55:18.494] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:44:0) after 3m41s
[01:58:59.659] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:44:0)
[01:58:59.690] Build status: STARTED
[01:58:59.690] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:45:0) after 8m12s
[02:07:11.892] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:45:0)
[02:07:11.928] Build status: STARTED
[02:07:11.928] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:46:0) after 7m50s
[02:15:01.960] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:46:0)
[02:15:01.987] Build status: STARTED
[02:15:01.987] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:47:0) after 1m57s
[02:16:59.011] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:47:0)
[02:16:59.043] Build status: STARTED
[02:16:59.043] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:48:0) after 1m32s
[02:18:31.116] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:48:0)
[02:18:31.152] Build status: STARTED
[02:18:31.152] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:49:0) after 8m54s
[02:27:25.180] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:49:0)
[02:27:25.214] Build status: STARTED
[02:27:25.214] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:50:0) after 6m18s
[02:33:43.246] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:50:0)
[02:33:43.277] Build status: STARTED
[02:33:43.277] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:51:0) after 1m49s
[02:35:32.340] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:51:0)
[02:35:32.367] Build status: STARTED
[02:35:32.367] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:52:0) after 5m2s
[02:40:34.464] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:52:0)
[02:40:34.500] Build status: STARTED
[02:40:34.500] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:53:0) after 9m6s
[02:49:40.638] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:53:0)
[02:49:40.671] Build status: STARTED
[02:49:40.671] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:54:0) after 5m7s
[02:54:47.723] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:54:0)
[02:54:47.752] Build status: STARTED
[02:54:47.752] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:55:0) after 6m5s
[03:00:52.801] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:55:0)
[03:00:52.859] Build status: STARTED
[03:00:52.859] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:56:0) after 4m26s
[03:05:18.927] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:56:0)
[03:05:18.970] Build status: STARTED
[03:05:18.970] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:57:0) after 6m25s
[03:11:44.027] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:57:0)
[03:11:44.062] Build status: STARTED
[03:11:44.062] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:58:0) after 5m20s
[03:17:04.089] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:58:0)
[03:17:04.213] Build status: STARTED
[03:17:04.213] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:59:0) after 3m59s
[03:21:03.256] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:59:0)
[03:21:03.285] Build status: STARTED
[03:21:03.285] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:60:0) after 8m49s
[03:29:52.387] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:60:0)
[03:29:52.414] Build status: STARTED
[03:29:52.414] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:61:0) after 9m33s
[03:39:25.443] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:61:0)
[03:39:25.488] Build status: STARTED
[03:39:25.488] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:62:0) after 5m45s
[03:45:10.507] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:62:0)
[03:45:10.539] Build status: STARTED
[03:45:10.539] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:63:0) after 5m34s
[03:50:44.605] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:63:0)
[03:50:44.635] Build status: STARTED
[03:50:44.635] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:64:0) after 9m40s
[04:00:24.682] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:64:0)
[04:00:24.862] Build status: STARTED
[04:00:24.862] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:65:0) after 5m25s
[04:05:49.992] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:65:0)
[04:05:50.022] Build status: STARTED
[04:05:50.022] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:66:0) after 4m49s
[04:10:39.235] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:66:0)
[04:10:39.273] Build status: STARTED
[04:10:39.273] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:67:0) after 2m20s
[04:12:59.265] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:67:0)
[04:12:59.292] Build status: STARTED
[04:12:59.292] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9008391147275562304:68:0) after 9m29s
[04:20:18.763] Received PubSub notification, asking Buildbucket for the build status
[04:20:18.793] Build:
{
"id": "8842961085215623312",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid"
},
"number": 16917,
"createdBy": "project:chromium",
"createTime": "2021-06-30T22:29:14.257956Z",
"startTime": "2021-06-30T22:29:35.715722Z",
"endTime": "2021-07-01T04:20:18.119645558Z",
"updateTime": "2021-07-01T04:20:18.120241104Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1cc656fa4b83a572a6156eef4104d80b133f37c9",
"ref": "refs/heads/main"
}
}
}
[04:20:18.793] Invocation finished in 5h51m5.884484229s with status FAILED