[10:04:40.556] New invocation is queued and will start shortly
[10:04:41.690] Starting the invocation (attempt 1)
[10:04:41.799] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/45f4d59010c08ef3df4df3d2d438f3a61b581b34"
[10:04:41.799] Popped gitiles commit info from properties and tags
[10:04:41.799] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:04:41.799] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:04:41.799] Buildbucket request:
{
"requestId": "9001462023240194192",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid x86"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "45f4d59010c08ef3df4df3d2d438f3a61b581b34",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9001462023240194192"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTAndroid x86"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[10:04:42.275] Scheduled build:
{
"id": "8836031961198904577",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid x86"
},
"number": 1985,
"createdBy": "project:chromium",
"createTime": "2021-09-15T10:04:41.867557069Z",
"updateTime": "2021-09-15T10:04:42.104917986Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "45f4d59010c08ef3df4df3d2d438f3a61b581b34",
"ref": "refs/heads/main"
}
}
}
[10:04:42.275] Task URL: https://cr-buildbucket.appspot.com/build/8836031961198904577
[10:04:42.275] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:2:0) after 5m20s
[10:05:03.286] Received PubSub notification, asking Buildbucket for the build status
[10:05:03.320] Build status: STARTED
[10:10:02.278] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:2:0)
[10:10:02.314] Build status: STARTED
[10:10:02.314] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:4:0) after 6m1s
[10:16:03.498] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:4:0)
[10:16:03.534] Build status: STARTED
[10:16:03.534] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:5:0) after 3m47s
[10:19:50.652] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:5:0)
[10:19:50.683] Build status: STARTED
[10:19:50.683] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:6:0) after 5m21s
[10:25:11.704] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:6:0)
[10:25:11.734] Build status: STARTED
[10:25:11.734] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:7:0) after 6m3s
[10:31:14.884] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:7:0)
[10:31:14.929] Build status: STARTED
[10:31:14.929] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:8:0) after 9m4s
[10:40:18.972] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:8:0)
[10:40:19.002] Build status: STARTED
[10:40:19.002] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:9:0) after 5m16s
[10:45:35.036] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:9:0)
[10:45:35.111] Build status: STARTED
[10:45:35.111] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:10:0) after 3m3s
[10:48:38.183] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:10:0)
[10:48:38.212] Build status: STARTED
[10:48:38.212] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:11:0) after 9m42s
[10:58:20.358] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:11:0)
[10:58:20.402] Build status: STARTED
[10:58:20.402] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:12:0) after 9m54s
[11:08:14.433] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:12:0)
[11:08:14.488] Build status: STARTED
[11:08:14.488] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:13:0) after 8m59s
[11:17:13.672] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:13:0)
[11:17:13.706] Build status: STARTED
[11:17:13.706] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:14:0) after 2m19s
[11:19:32.875] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:14:0)
[11:19:32.908] Build status: STARTED
[11:19:32.908] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:15:0) after 1m57s
[11:21:29.930] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:15:0)
[11:21:29.986] Build status: STARTED
[11:21:29.986] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:16:0) after 8m32s
[11:30:02.270] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:16:0)
[11:30:02.307] Build status: STARTED
[11:30:02.307] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:17:0) after 9m59s
[11:40:01.328] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:17:0)
[11:40:01.383] Build status: STARTED
[11:40:01.383] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:18:0) after 2m23s
[11:42:24.387] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:18:0)
[11:42:24.422] Build status: STARTED
[11:42:24.422] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:19:0) after 4m4s
[11:46:28.498] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:19:0)
[11:46:28.531] Build status: STARTED
[11:46:28.531] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:20:0) after 5m3s
[11:51:31.599] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:20:0)
[11:51:31.639] Build status: STARTED
[11:51:31.639] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:21:0) after 1m1s
[11:52:32.674] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:21:0)
[11:52:32.714] Build status: STARTED
[11:52:32.714] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:22:0) after 4m14s
[11:56:46.949] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:22:0)
[11:56:47.036] Build status: STARTED
[11:56:47.036] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:23:0) after 9m14s
[12:06:01.142] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:23:0)
[12:06:01.201] Build status: STARTED
[12:06:01.201] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:24:0) after 4m36s
[12:10:37.495] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:24:0)
[12:10:37.537] Build status: STARTED
[12:10:37.537] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:25:0) after 2m39s
[12:13:16.732] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:25:0)
[12:13:16.792] Build status: STARTED
[12:13:16.792] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:26:0) after 6m56s
[12:20:12.814] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:26:0)
[12:20:12.864] Build status: STARTED
[12:20:12.864] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:27:0) after 4m27s
[12:24:40.085] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:27:0)
[12:24:40.119] Build status: STARTED
[12:24:40.119] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:28:0) after 5m30s
[12:30:10.188] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:28:0)
[12:30:10.216] Build status: STARTED
[12:30:10.216] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:29:0) after 4m23s
[12:34:33.385] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:29:0)
[12:34:33.416] Build status: STARTED
[12:34:33.416] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:30:0) after 5m32s
[12:40:05.451] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:30:0)
[12:40:05.510] Build status: STARTED
[12:40:05.510] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:31:0) after 6m32s
[12:46:37.532] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:31:0)
[12:46:37.572] Build status: STARTED
[12:46:37.572] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:32:0) after 3m35s
[12:50:12.601] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:32:0)
[12:50:12.638] Build status: STARTED
[12:50:12.638] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:33:0) after 9m12s
[12:59:24.698] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:33:0)
[12:59:24.736] Build status: STARTED
[12:59:24.736] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:34:0) after 4m21s
[13:03:45.761] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:34:0)
[13:03:45.806] Build status: STARTED
[13:03:45.806] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:35:0) after 4m0s
[13:07:45.834] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:35:0)
[13:07:45.866] Build status: STARTED
[13:07:45.866] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:36:0) after 9m48s
[13:17:33.890] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:36:0)
[13:17:33.945] Build status: STARTED
[13:17:33.945] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:37:0) after 5m31s
[13:23:04.977] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:37:0)
[13:23:05.012] Build status: STARTED
[13:23:05.012] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:38:0) after 9m49s
[13:32:54.032] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:38:0)
[13:32:54.070] Build status: STARTED
[13:32:54.070] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:39:0) after 9m48s
[13:42:42.178] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:39:0)
[13:42:42.256] Build status: STARTED
[13:42:42.256] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:40:0) after 4m40s
[13:47:22.520] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:40:0)
[13:47:22.555] Build status: STARTED
[13:47:22.555] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:41:0) after 4m40s
[13:52:02.580] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:41:0)
[13:52:02.614] Build status: STARTED
[13:52:02.614] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:42:0) after 1m23s
[13:53:25.746] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:42:0)
[13:53:25.777] Build status: STARTED
[13:53:25.777] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:43:0) after 6m41s
[14:00:06.805] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:43:0)
[14:00:06.839] Build status: STARTED
[14:00:06.839] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:44:0) after 2m3s
[14:02:09.973] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:44:0)
[14:02:10.016] Build status: STARTED
[14:02:10.016] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:45:0) after 8m5s
[14:10:15.374] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:45:0)
[14:10:15.406] Build status: STARTED
[14:10:15.406] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:46:0) after 7m14s
[14:17:29.492] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:46:0)
[14:17:29.537] Build status: STARTED
[14:17:29.537] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:47:0) after 9m28s
[14:26:57.567] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:47:0)
[14:26:57.621] Build status: STARTED
[14:26:57.621] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:48:0) after 7m58s
[14:34:55.831] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:48:0)
[14:34:55.863] Build status: STARTED
[14:34:55.863] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:49:0) after 6m1s
[14:40:56.938] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:49:0)
[14:40:56.971] Build status: STARTED
[14:40:56.971] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:50:0) after 4m55s
[14:45:52.003] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:50:0)
[14:45:52.036] Build status: STARTED
[14:45:52.036] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:51:0) after 4m11s
[14:50:03.333] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:51:0)
[14:50:03.370] Build status: STARTED
[14:50:03.370] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:52:0) after 1m44s
[14:51:47.457] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:52:0)
[14:51:47.493] Build status: STARTED
[14:51:47.493] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:53:0) after 4m40s
[14:56:27.621] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:53:0)
[14:56:27.647] Build status: STARTED
[14:56:27.647] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:54:0) after 9m40s
[15:06:07.674] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:54:0)
[15:06:07.719] Build status: STARTED
[15:06:07.719] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:55:0) after 2m16s
[15:08:23.755] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:55:0)
[15:08:23.794] Build status: STARTED
[15:08:23.794] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:56:0) after 5m25s
[15:13:48.915] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:56:0)
[15:13:48.965] Build status: STARTED
[15:13:48.965] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:57:0) after 8m17s
[15:22:05.990] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:57:0)
[15:22:06.077] Build status: STARTED
[15:22:06.077] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:58:0) after 1m27s
[15:23:33.106] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:58:0)
[15:23:33.136] Build status: STARTED
[15:23:33.136] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:59:0) after 3m14s
[15:26:47.256] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:59:0)
[15:26:47.294] Build status: STARTED
[15:26:47.294] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:60:0) after 1m28s
[15:28:15.320] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:60:0)
[15:28:15.360] Build status: STARTED
[15:28:15.360] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:61:0) after 6m55s
[15:35:10.388] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:61:0)
[15:35:10.422] Build status: STARTED
[15:35:10.422] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:62:0) after 2m55s
[15:38:05.502] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:62:0)
[15:38:05.555] Build status: STARTED
[15:38:05.555] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9001462023240194192:63:0) after 2m26s
[15:38:13.759] Received PubSub notification, asking Buildbucket for the build status
[15:38:13.819] Build:
{
"id": "8836031961198904577",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid x86"
},
"number": 1985,
"createdBy": "project:chromium",
"createTime": "2021-09-15T10:04:41.867557069Z",
"startTime": "2021-09-15T10:05:02.474164Z",
"endTime": "2021-09-15T15:38:06.108583568Z",
"updateTime": "2021-09-15T15:38:06.109121307Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "45f4d59010c08ef3df4df3d2d438f3a61b581b34",
"ref": "refs/heads/main"
}
}
}
[15:38:13.819] Invocation finished in 5h33m33.282475189s with status SUCCEEDED