[15:56:46.636] New invocation is queued and will start shortly
[15:56:47.842] Starting the invocation (attempt 1)
[15:56:47.886] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c42e997ff793b750d2e12b36062aec556e646401"
[15:56:47.886] Popped gitiles commit info from properties and tags
[15:56:47.886] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:56:47.887] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:56:47.887] Buildbucket request:
{
"requestId": "8978971823268030368",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c42e997ff793b750d2e12b36062aec556e646401",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8978971823268030368"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTiOS"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[15:56:48.589] Scheduled build:
{
"id": "8813541761190936673",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 21011,
"createdBy": "project:chromium",
"createTime": "2022-05-21T15:56:47.986992893Z",
"updateTime": "2022-05-21T15:56:47.986992893Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c42e997ff793b750d2e12b36062aec556e646401",
"ref": "refs/heads/main"
}
}
}
[15:56:48.589] Task URL: https://cr-buildbucket.appspot.com/build/8813541761190936673
[15:56:48.589] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:2:0) after 6m18s
[15:58:12.363] Received PubSub notification, asking Buildbucket for the build status
[15:58:12.386] Build status: STARTED
[16:03:06.703] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:2:0)
[16:03:06.723] Build status: STARTED
[16:03:06.723] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:4:0) after 6m6s
[16:09:12.859] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:4:0)
[16:09:12.886] Build status: STARTED
[16:09:12.886] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:5:0) after 4m18s
[16:13:30.928] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:5:0)
[16:13:30.946] Build status: STARTED
[16:13:30.946] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:6:0) after 7m58s
[16:21:28.964] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:6:0)
[16:21:28.993] Build status: STARTED
[16:21:28.993] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:7:0) after 7m23s
[16:28:52.010] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:7:0)
[16:28:52.038] Build status: STARTED
[16:28:52.038] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:8:0) after 6m10s
[16:35:02.059] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:8:0)
[16:35:02.127] Build status: STARTED
[16:35:02.127] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:9:0) after 9m40s
[16:44:42.347] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:9:0)
[16:44:42.377] Build status: STARTED
[16:44:42.377] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:10:0) after 5m16s
[16:49:58.396] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:10:0)
[16:49:58.427] Build status: STARTED
[16:49:58.427] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:11:0) after 2m22s
[16:52:20.449] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:11:0)
[16:52:20.490] Build status: STARTED
[16:52:20.490] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:12:0) after 2m19s
[16:54:39.507] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:12:0)
[16:54:39.550] Build status: STARTED
[16:54:39.550] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:13:0) after 5m1s
[16:59:40.642] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:13:0)
[16:59:40.670] Build status: STARTED
[16:59:40.670] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:14:0) after 5m21s
[17:05:01.694] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:14:0)
[17:05:01.716] Build status: STARTED
[17:05:01.716] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:15:0) after 4m7s
[17:09:08.747] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:15:0)
[17:09:08.846] Build status: STARTED
[17:09:08.846] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:16:0) after 6m36s
[17:15:44.875] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:16:0)
[17:15:44.904] Build status: STARTED
[17:15:44.904] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:17:0) after 6m31s
[17:22:15.928] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:17:0)
[17:22:15.953] Build status: STARTED
[17:22:15.954] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:18:0) after 7m2s
[17:29:18.358] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:18:0)
[17:29:18.572] Build status: STARTED
[17:29:18.572] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:19:0) after 4m43s
[17:34:01.598] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:19:0)
[17:34:01.620] Build status: STARTED
[17:34:01.620] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:20:0) after 6m2s
[17:40:03.751] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:20:0)
[17:40:03.776] Build status: STARTED
[17:40:03.776] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:21:0) after 8m8s
[17:48:11.793] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:21:0)
[17:48:11.816] Build status: STARTED
[17:48:11.816] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:22:0) after 1m36s
[17:49:47.875] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:22:0)
[17:49:47.906] Build status: STARTED
[17:49:47.906] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:23:0) after 7m51s
[17:57:38.924] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:23:0)
[17:57:38.954] Build status: STARTED
[17:57:38.954] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:24:0) after 4m31s
[18:02:09.975] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:24:0)
[18:02:10.001] Build status: STARTED
[18:02:10.001] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:25:0) after 4m51s
[18:07:01.102] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:25:0)
[18:07:01.128] Build status: STARTED
[18:07:01.128] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:26:0) after 4m8s
[18:11:09.145] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:26:0)
[18:11:09.172] Build status: STARTED
[18:11:09.172] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:27:0) after 6m45s
[18:17:54.266] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:27:0)
[18:17:54.304] Build status: STARTED
[18:17:54.304] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:28:0) after 4m31s
[18:22:25.324] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:28:0)
[18:22:25.346] Build status: STARTED
[18:22:25.346] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:29:0) after 8m3s
[18:30:28.347] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:29:0)
[18:30:28.381] Build status: STARTED
[18:30:28.381] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:30:0) after 1m28s
[18:31:56.414] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:30:0)
[18:31:56.444] Build status: STARTED
[18:31:56.444] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:31:0) after 6m20s
[18:38:16.595] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:31:0)
[18:38:16.635] Build status: STARTED
[18:38:16.635] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:32:0) after 8m4s
[18:46:20.656] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:32:0)
[18:46:20.695] Build status: STARTED
[18:46:20.695] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:33:0) after 6m42s
[18:53:02.823] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:33:0)
[18:53:02.847] Build status: STARTED
[18:53:02.847] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:34:0) after 6m55s
[18:59:57.887] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:34:0)
[18:59:57.922] Build status: STARTED
[18:59:57.922] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:35:0) after 8m9s
[19:08:07.229] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:35:0)
[19:08:07.253] Build status: STARTED
[19:08:07.253] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:36:0) after 1m35s
[19:09:42.267] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:36:0)
[19:09:42.297] Build status: STARTED
[19:09:42.297] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:37:0) after 4m24s
[19:14:06.418] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:37:0)
[19:14:06.448] Build status: STARTED
[19:14:06.448] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:38:0) after 7m41s
[19:21:47.470] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:38:0)
[19:21:47.502] Build status: STARTED
[19:21:47.502] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:39:0) after 9m44s
[19:31:31.477] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:39:0)
[19:31:31.511] Build status: STARTED
[19:31:31.511] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:40:0) after 9m46s
[19:41:17.544] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:40:0)
[19:41:17.582] Build status: STARTED
[19:41:17.582] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:41:0) after 2m37s
[19:43:55.057] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:41:0)
[19:43:55.095] Build status: STARTED
[19:43:55.095] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:42:0) after 2m57s
[19:46:52.217] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:42:0)
[19:46:52.278] Build status: STARTED
[19:46:52.278] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:43:0) after 3m12s
[19:50:04.622] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:43:0)
[19:50:04.659] Build status: STARTED
[19:50:04.659] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:44:0) after 5m38s
[19:55:43.208] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:44:0)
[19:55:43.238] Build status: STARTED
[19:55:43.238] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:45:0) after 1m36s
[19:57:19.982] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:45:0)
[19:57:20.030] Build status: STARTED
[19:57:20.030] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:46:0) after 1m18s
[19:58:38.386] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:46:0)
[19:58:38.498] Build status: STARTED
[19:58:38.498] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:47:0) after 3m43s
[20:02:21.569] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:47:0)
[20:02:21.604] Build status: STARTED
[20:02:21.604] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:48:0) after 6m56s
[20:09:17.627] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:48:0)
[20:09:17.657] Build status: STARTED
[20:09:17.657] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:49:0) after 8m28s
[20:17:45.681] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:49:0)
[20:17:45.716] Build status: STARTED
[20:17:45.716] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:50:0) after 5m38s
[20:23:23.865] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:50:0)
[20:23:23.887] Build status: STARTED
[20:23:23.887] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:51:0) after 5m42s
[20:29:05.911] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:51:0)
[20:29:05.931] Build status: STARTED
[20:29:05.931] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:52:0) after 1m3s
[20:30:09.055] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:52:0)
[20:30:09.089] Build status: STARTED
[20:30:09.089] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:53:0) after 2m5s
[20:32:14.422] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:53:0)
[20:32:14.447] Build status: STARTED
[20:32:14.447] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:54:0) after 6m36s
[20:38:50.580] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:54:0)
[20:38:50.639] Build status: STARTED
[20:38:50.639] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8978971823268030368:55:0) after 6m47s
[20:39:46.169] Received PubSub notification, asking Buildbucket for the build status
[20:39:46.189] Build:
{
"id": "8813541761190936673",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 21011,
"createdBy": "project:chromium",
"createTime": "2022-05-21T15:56:47.986992893Z",
"startTime": "2022-05-21T15:58:11.709561Z",
"endTime": "2022-05-21T20:39:45.065391390Z",
"updateTime": "2022-05-21T20:39:45.065391390Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c42e997ff793b750d2e12b36062aec556e646401",
"ref": "refs/heads/main"
}
}
}
[20:39:46.189] Invocation finished in 4h42m59.568320118s with status SUCCEEDED