[19:08:33.671] New invocation is queued and will start shortly
[19:08:35.044] Starting the invocation (attempt 1)
[19:08:35.081] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7d16712317e8ad9109fbf49f2fc16833c2047c11"
[19:08:35.081] Popped gitiles commit info from properties and tags
[19:08:35.081] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:08:35.081] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:08:35.081] Buildbucket request:
{
"requestId": "9009581531909536816",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "7d16712317e8ad9109fbf49f2fc16833c2047c11",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9009581531909536816"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTAndroid (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:08:35.662] Scheduled build:
{
"id": "8844151469709390049",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"number": 13014,
"createdBy": "project:chromium",
"createTime": "2021-06-17T19:08:35.139422410Z",
"updateTime": "2021-06-17T19:08:35.424062352Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "7d16712317e8ad9109fbf49f2fc16833c2047c11",
"ref": "refs/heads/main"
}
}
}
[19:08:35.662] Task URL: https://cr-buildbucket.appspot.com/build/8844151469709390049
[19:08:35.662] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:2:0) after 7m43s
[19:09:17.003] Received PubSub notification, asking Buildbucket for the build status
[19:09:17.027] Build status: STARTED
[19:16:18.799] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:2:0)
[19:16:18.835] Build status: STARTED
[19:16:18.835] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:4:0) after 5m0s
[19:21:18.918] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:4:0)
[19:21:18.951] Build status: STARTED
[19:21:18.951] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:5:0) after 9m14s
[19:30:32.974] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:5:0)
[19:30:33.013] Build status: STARTED
[19:30:33.013] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:6:0) after 8m2s
[19:38:35.038] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:6:0)
[19:38:35.071] Build status: STARTED
[19:38:35.071] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:7:0) after 9m33s
[19:48:08.050] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:7:0)
[19:48:08.176] Build status: STARTED
[19:48:08.176] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:8:0) after 2m58s
[19:51:06.321] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:8:0)
[19:51:06.351] Build status: STARTED
[19:51:06.351] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:9:0) after 4m41s
[19:55:47.815] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:9:0)
[19:55:47.841] Build status: STARTED
[19:55:47.841] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:10:0) after 1m32s
[19:57:19.904] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:10:0)
[19:57:19.944] Build status: STARTED
[19:57:19.944] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:11:0) after 9m37s
[20:06:57.033] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:11:0)
[20:06:57.248] Build status: STARTED
[20:06:57.248] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:12:0) after 2m1s
[20:08:58.288] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:12:0)
[20:08:58.326] Build status: STARTED
[20:08:58.326] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:13:0) after 7m27s
[20:16:25.335] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:13:0)
[20:16:25.369] Build status: STARTED
[20:16:25.369] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:14:0) after 3m0s
[20:19:25.601] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:14:0)
[20:19:25.638] Build status: STARTED
[20:19:25.639] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:15:0) after 3m41s
[20:23:06.737] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:15:0)
[20:23:06.772] Build status: STARTED
[20:23:06.772] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:16:0) after 3m36s
[20:26:42.792] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:16:0)
[20:26:42.831] Build status: STARTED
[20:26:42.831] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:17:0) after 4m58s
[20:31:40.806] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:17:0)
[20:31:40.866] Build status: STARTED
[20:31:40.866] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:18:0) after 5m25s
[20:37:05.912] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:18:0)
[20:37:05.945] Build status: STARTED
[20:37:05.945] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:19:0) after 1m21s
[20:38:26.974] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:19:0)
[20:38:27.003] Build status: STARTED
[20:38:27.003] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:20:0) after 9m10s
[20:47:37.143] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:20:0)
[20:47:37.246] Build status: STARTED
[20:47:37.246] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:21:0) after 6m16s
[20:53:53.268] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:21:0)
[20:53:53.295] Build status: STARTED
[20:53:53.295] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:22:0) after 8m40s
[21:02:33.335] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:22:0)
[21:02:33.370] Build status: STARTED
[21:02:33.370] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:23:0) after 6m25s
[21:08:58.600] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:23:0)
[21:08:58.635] Build status: STARTED
[21:08:58.635] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:24:0) after 2m41s
[21:11:39.856] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:24:0)
[21:11:39.913] Build status: STARTED
[21:11:39.913] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:25:0) after 1m29s
[21:13:09.091] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:25:0)
[21:13:09.124] Build status: STARTED
[21:13:09.124] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:26:0) after 4m54s
[21:18:03.258] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:26:0)
[21:18:03.419] Build status: STARTED
[21:18:03.419] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:27:0) after 6m38s
[21:24:41.542] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:27:0)
[21:24:41.578] Build status: STARTED
[21:24:41.578] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:28:0) after 2m16s
[21:26:57.672] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:28:0)
[21:26:57.706] Build status: STARTED
[21:26:57.706] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:29:0) after 6m44s
[21:33:41.749] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:29:0)
[21:33:41.778] Build status: STARTED
[21:33:41.778] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:30:0) after 9m46s
[21:43:27.857] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:30:0)
[21:43:28.853] Build status: STARTED
[21:43:28.853] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:31:0) after 8m55s
[21:52:23.910] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:31:0)
[21:52:23.973] Build status: STARTED
[21:52:23.973] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:32:0) after 4m46s
[21:57:09.995] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:32:0)
[21:57:10.039] Build status: STARTED
[21:57:10.039] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:33:0) after 8m12s
[22:05:22.105] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:33:0)
[22:05:22.154] Build status: STARTED
[22:05:22.155] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:34:0) after 4m41s
[22:10:03.336] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:34:0)
[22:10:03.426] Build status: STARTED
[22:10:03.426] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:35:0) after 1m14s
[22:11:17.471] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:35:0)
[22:11:17.513] Build status: STARTED
[22:11:17.513] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:36:0) after 3m54s
[22:15:11.685] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:36:0)
[22:15:11.755] Build status: STARTED
[22:15:11.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:37:0) after 8m47s
[22:23:58.897] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:37:0)
[22:23:58.974] Build status: STARTED
[22:23:58.974] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:38:0) after 6m53s
[22:30:52.429] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:38:0)
[22:30:52.469] Build status: STARTED
[22:30:52.469] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:39:0) after 8m20s
[22:39:12.703] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:39:0)
[22:39:12.739] Build status: STARTED
[22:39:12.739] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:40:0) after 3m3s
[22:42:15.764] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:40:0)
[22:42:15.845] Build status: STARTED
[22:42:15.845] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:41:0) after 3m17s
[22:45:33.205] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:41:0)
[22:45:33.237] Build status: STARTED
[22:45:33.237] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:42:0) after 5m5s
[22:50:38.403] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:42:0)
[22:50:38.440] Build status: STARTED
[22:50:38.440] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:43:0) after 7m2s
[22:57:40.463] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:43:0)
[22:57:40.490] Build status: STARTED
[22:57:40.490] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:44:0) after 3m32s
[23:01:12.516] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:44:0)
[23:01:12.545] Build status: STARTED
[23:01:12.545] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:45:0) after 9m33s
[23:10:45.578] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:45:0)
[23:10:45.605] Build status: STARTED
[23:10:45.605] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:46:0) after 7m14s
[23:17:59.656] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:46:0)
[23:17:59.684] Build status: STARTED
[23:17:59.684] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:47:0) after 9m42s
[23:27:41.890] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:47:0)
[23:27:41.933] Build status: STARTED
[23:27:41.933] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:48:0) after 6m52s
[23:34:34.083] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:48:0)
[23:34:34.116] Build status: STARTED
[23:34:34.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:49:0) after 8m46s
[23:43:20.319] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:49:0)
[23:43:20.351] Build status: STARTED
[23:43:20.351] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9009581531909536816:50:0) after 6m20s
[23:43:22.438] Received PubSub notification, asking Buildbucket for the build status
[23:43:22.471] Build:
{
"id": "8844151469709390049",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"number": 13014,
"createdBy": "project:chromium",
"createTime": "2021-06-17T19:08:35.139422410Z",
"startTime": "2021-06-17T19:09:16.553070Z",
"endTime": "2021-06-17T23:43:21.998421804Z",
"updateTime": "2021-06-17T23:43:21.998814066Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "7d16712317e8ad9109fbf49f2fc16833c2047c11",
"ref": "refs/heads/main"
}
}
}
[23:43:22.471] Invocation finished in 4h34m48.814172652s with status SUCCEEDED