[23:36:50.580] New invocation is queued and will start shortly
[23:36:52.071] Starting the invocation (attempt 1)
[23:36:52.111] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/65eac9ab21ab037e68e6434a3548fddbee3fcbcb"
[23:36:52.111] Popped gitiles commit info from properties and tags
[23:36:52.111] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:36:52.111] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:36:52.111] Buildbucket request:
{
"requestId": "8989452126540257552",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ChromeOS FYI Release (amd64-generic)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "65eac9ab21ab037e68e6434a3548fddbee3fcbcb",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8989452126540257552"
},
{
"key": "scheduler_job_id",
"value": "chromium/ChromeOS FYI Release (amd64-generic)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:36:52.642] Scheduled build:
{
"id": "8824022064198509425",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ChromeOS FYI Release (amd64-generic)"
},
"number": 5182,
"createdBy": "project:chromium",
"createTime": "2022-01-25T23:36:52.179381002Z",
"updateTime": "2022-01-25T23:36:52.179381002Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "65eac9ab21ab037e68e6434a3548fddbee3fcbcb",
"ref": "refs/heads/main"
}
}
}
[23:36:52.642] Task URL: https://cr-buildbucket.appspot.com/build/8824022064198509425
[23:36:52.642] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:2:0) after 8m55s
[23:39:23.879] Received PubSub notification, asking Buildbucket for the build status
[23:39:23.903] Build status: STARTED
[23:45:47.939] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:2:0)
[23:45:47.960] Build status: STARTED
[23:45:47.960] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:4:0) after 5m58s
[23:51:45.981] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:4:0)
[23:51:46.002] Build status: STARTED
[23:51:46.002] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:5:0) after 4m6s
[23:55:52.312] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:5:0)
[23:55:52.343] Build status: STARTED
[23:55:52.343] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:6:0) after 5m46s
[00:01:38.479] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:6:0)
[00:01:38.501] Build status: STARTED
[00:01:38.501] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:7:0) after 9m47s
[00:11:25.581] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:7:0)
[00:11:25.603] Build status: STARTED
[00:11:25.603] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:8:0) after 4m49s
[00:16:14.627] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:8:0)
[00:16:14.653] Build status: STARTED
[00:16:14.653] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:9:0) after 7m55s
[00:24:09.680] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:9:0)
[00:24:09.700] Build status: STARTED
[00:24:09.700] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:10:0) after 3m10s
[00:27:19.959] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:10:0)
[00:27:19.984] Build status: STARTED
[00:27:19.984] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:11:0) after 9m35s
[00:36:55.026] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:11:0)
[00:36:55.052] Build status: STARTED
[00:36:55.052] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:12:0) after 2m31s
[00:39:26.114] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:12:0)
[00:39:26.147] Build status: STARTED
[00:39:26.147] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:13:0) after 6m55s
[00:46:21.365] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:13:0)
[00:46:21.404] Build status: STARTED
[00:46:21.404] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:14:0) after 8m10s
[00:54:31.429] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:14:0)
[00:54:31.457] Build status: STARTED
[00:54:31.457] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:15:0) after 8m34s
[01:03:05.483] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:15:0)
[01:03:05.502] Build status: STARTED
[01:03:05.502] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:16:0) after 7m45s
[01:10:50.537] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:16:0)
[01:10:50.563] Build status: STARTED
[01:10:50.563] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:17:0) after 9m49s
[01:20:40.064] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:17:0)
[01:20:40.116] Build status: STARTED
[01:20:40.116] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:18:0) after 9m26s
[01:30:06.269] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:18:0)
[01:30:06.297] Build status: STARTED
[01:30:06.297] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:19:0) after 4m28s
[01:34:34.606] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:19:0)
[01:34:34.631] Build status: STARTED
[01:34:34.631] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:20:0) after 4m16s
[01:38:50.666] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:20:0)
[01:38:50.703] Build status: STARTED
[01:38:50.703] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:21:0) after 9m6s
[01:47:56.727] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:21:0)
[01:47:56.748] Build status: STARTED
[01:47:56.748] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:22:0) after 2m27s
[01:50:23.874] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:22:0)
[01:50:23.962] Build status: STARTED
[01:50:23.962] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:23:0) after 9m25s
[01:59:49.354] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:23:0)
[01:59:49.382] Build status: STARTED
[01:59:49.382] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:24:0) after 9m46s
[02:09:35.489] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:24:0)
[02:09:35.539] Build status: STARTED
[02:09:35.539] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:25:0) after 3m55s
[02:13:30.597] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:25:0)
[02:13:30.647] Build status: STARTED
[02:13:30.647] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:26:0) after 7m55s
[02:21:25.797] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:26:0)
[02:21:25.825] Build status: STARTED
[02:21:25.825] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:27:0) after 4m57s
[02:26:22.889] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:27:0)
[02:26:22.953] Build status: STARTED
[02:26:22.953] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:28:0) after 5m12s
[02:31:35.357] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:28:0)
[02:31:35.382] Build status: STARTED
[02:31:35.383] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:29:0) after 3m17s
[02:34:52.583] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:29:0)
[02:34:52.603] Build status: STARTED
[02:34:52.603] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:30:0) after 4m36s
[02:39:28.671] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:30:0)
[02:39:28.706] Build status: STARTED
[02:39:28.706] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:31:0) after 1m52s
[02:41:20.813] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:31:0)
[02:41:20.850] Build status: STARTED
[02:41:20.850] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:32:0) after 4m53s
[02:46:13.976] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:32:0)
[02:46:14.003] Build status: STARTED
[02:46:14.003] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:33:0) after 8m38s
[02:54:52.027] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:33:0)
[02:54:52.047] Build status: STARTED
[02:54:52.047] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:34:0) after 5m54s
[03:00:46.096] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:34:0)
[03:00:46.117] Build status: STARTED
[03:00:46.117] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:35:0) after 6m21s
[03:07:07.194] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:35:0)
[03:07:07.255] Build status: STARTED
[03:07:07.255] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:36:0) after 6m25s
[03:13:32.470] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:36:0)
[03:13:32.492] Build status: STARTED
[03:13:32.492] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:37:0) after 1m4s
[03:14:36.725] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:37:0)
[03:14:36.747] Build status: STARTED
[03:14:36.747] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:38:0) after 1m22s
[03:15:58.780] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:38:0)
[03:15:58.813] Build status: STARTED
[03:15:58.813] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:39:0) after 3m54s
[03:19:53.006] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:39:0)
[03:19:53.055] Build status: STARTED
[03:19:53.055] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:40:0) after 3m1s
[03:22:54.522] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:40:0)
[03:22:54.543] Build status: STARTED
[03:22:54.543] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:41:0) after 8m23s
[03:31:17.597] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:41:0)
[03:31:17.671] Build status: STARTED
[03:31:17.671] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:42:0) after 4m40s
[03:35:57.700] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:42:0)
[03:35:57.733] Build status: STARTED
[03:35:57.733] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:43:0) after 4m36s
[03:40:33.860] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:43:0)
[03:40:33.884] Build status: STARTED
[03:40:33.884] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:44:0) after 4m12s
[03:44:45.910] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:44:0)
[03:44:45.945] Build status: STARTED
[03:44:45.945] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:45:0) after 6m19s
[03:51:04.967] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:45:0)
[03:51:04.984] Build status: STARTED
[03:51:04.984] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:46:0) after 7m59s
[03:59:04.009] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:46:0)
[03:59:04.075] Build status: STARTED
[03:59:04.075] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:47:0) after 9m59s
[04:09:03.293] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:47:0)
[04:09:03.318] Build status: STARTED
[04:09:03.318] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:48:0) after 3m7s
[04:12:11.245] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:48:0)
[04:12:11.268] Build status: STARTED
[04:12:11.268] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:49:0) after 6m11s
[04:18:22.345] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:49:0)
[04:18:22.363] Build status: STARTED
[04:18:22.363] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:50:0) after 5m23s
[04:23:45.383] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:50:0)
[04:23:45.404] Build status: STARTED
[04:23:45.404] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:51:0) after 4m48s
[04:28:33.428] Handling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:51:0)
[04:28:33.463] Build status: STARTED
[04:28:33.463] Scheduling timer "check-buildbucket-build-status" (chromium/ChromeOS FYI Release (amd64-generic):8989452126540257552:52:0) after 9m36s
[04:31:01.682] Received PubSub notification, asking Buildbucket for the build status
[04:31:01.777] Build:
{
"id": "8824022064198509425",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ChromeOS FYI Release (amd64-generic)"
},
"number": 5182,
"createdBy": "project:chromium",
"createTime": "2022-01-25T23:36:52.179381002Z",
"startTime": "2022-01-25T23:39:23.262660Z",
"endTime": "2022-01-26T04:31:01.007958054Z",
"updateTime": "2022-01-26T04:31:01.007958054Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "65eac9ab21ab037e68e6434a3548fddbee3fcbcb",
"ref": "refs/heads/main"
}
}
}
[04:31:01.777] Invocation finished in 4h54m11.214471347s with status SUCCEEDED