[20:39:10.210] New invocation is queued and will start shortly
[20:39:11.305] Starting the invocation (attempt 1)
[20:39:11.352] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1066a87a4470121f6f0fb46e096d5de2e86074c4"
[20:39:11.352] Popped gitiles commit info from properties and tags
[20:39:11.352] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:39:11.352] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:39:11.352] Buildbucket request:
{
"requestId": "8945523776032885040",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1066a87a4470121f6f0fb46e096d5de2e86074c4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8945523776032885040"
},
{
"key": "scheduler_job_id",
"value": "chromium/Linux CFI"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:39:11.839] Scheduled build:
{
"id": "8780093714068087921",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 25266,
"createdBy": "project:chromium",
"createTime": "2023-05-25T20:39:11.461229196Z",
"updateTime": "2023-05-25T20:39:11.461229196Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1066a87a4470121f6f0fb46e096d5de2e86074c4",
"ref": "refs/heads/main"
}
},
"canary": true
}
[20:39:11.839] Task URL: https://cr-buildbucket.appspot.com/build/8780093714068087921
[20:39:11.840] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:2:0) after 3m26s
[20:41:19.285] Received PubSub notification, asking Buildbucket for the build status
[20:41:19.307] Build status: STARTED
[20:42:37.974] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:2:0)
[20:42:38.109] Build status: STARTED
[20:42:38.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:4:0) after 8m37s
[20:51:15.231] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:4:0)
[20:51:15.258] Build status: STARTED
[20:51:15.258] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:5:0) after 1m23s
[20:52:38.626] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:5:0)
[20:52:38.647] Build status: STARTED
[20:52:38.647] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:6:0) after 8m26s
[21:01:04.801] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:6:0)
[21:01:04.830] Build status: STARTED
[21:01:04.830] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:7:0) after 6m23s
[21:07:28.103] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:7:0)
[21:07:28.133] Build status: STARTED
[21:07:28.133] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:8:0) after 6m0s
[21:13:28.146] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:8:0)
[21:13:28.169] Build status: STARTED
[21:13:28.169] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:9:0) after 7m20s
[21:20:48.303] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:9:0)
[21:20:48.321] Build status: STARTED
[21:20:48.321] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:10:0) after 5m0s
[21:25:48.384] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:10:0)
[21:25:48.402] Build status: STARTED
[21:25:48.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:11:0) after 3m44s
[21:29:32.428] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:11:0)
[21:29:32.447] Build status: STARTED
[21:29:32.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:12:0) after 6m48s
[21:36:20.558] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:12:0)
[21:36:20.581] Build status: STARTED
[21:36:20.581] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:13:0) after 2m15s
[21:38:35.604] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:13:0)
[21:38:35.624] Build status: STARTED
[21:38:35.624] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:14:0) after 9m17s
[21:47:52.983] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:14:0)
[21:47:52.999] Build status: STARTED
[21:47:52.999] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:15:0) after 3m34s
[21:51:27.026] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:15:0)
[21:51:27.099] Build status: STARTED
[21:51:27.099] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:16:0) after 9m33s
[22:01:00.292] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:16:0)
[22:01:00.314] Build status: STARTED
[22:01:00.314] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:17:0) after 2m22s
[22:03:22.340] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:17:0)
[22:03:22.360] Build status: STARTED
[22:03:22.360] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:18:0) after 5m40s
[22:09:02.412] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:18:0)
[22:09:02.432] Build status: STARTED
[22:09:02.432] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:19:0) after 7m53s
[22:16:55.813] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:19:0)
[22:16:55.831] Build status: STARTED
[22:16:55.831] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:20:0) after 3m8s
[22:20:03.900] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:20:0)
[22:20:03.946] Build status: STARTED
[22:20:03.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:21:0) after 7m1s
[22:27:04.969] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:21:0)
[22:27:04.997] Build status: STARTED
[22:27:04.997] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:22:0) after 5m5s
[22:32:10.020] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:22:0)
[22:32:10.045] Build status: STARTED
[22:32:10.045] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:23:0) after 1m27s
[22:33:37.045] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:23:0)
[22:33:37.067] Build status: STARTED
[22:33:37.067] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:24:0) after 8m40s
[22:42:17.093] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:24:0)
[22:42:17.111] Build status: STARTED
[22:42:17.111] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:25:0) after 5m37s
[22:47:54.158] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:25:0)
[22:47:54.191] Build status: STARTED
[22:47:54.191] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:26:0) after 8m9s
[22:56:03.214] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:26:0)
[22:56:03.237] Build status: STARTED
[22:56:03.237] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:27:0) after 7m15s
[23:03:18.355] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:27:0)
[23:03:18.397] Build status: STARTED
[23:03:18.397] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:28:0) after 2m27s
[23:05:45.505] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:28:0)
[23:05:45.523] Build status: STARTED
[23:05:45.523] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:29:0) after 2m31s
[23:08:16.760] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:29:0)
[23:08:16.777] Build status: STARTED
[23:08:16.777] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:30:0) after 1m25s
[23:09:41.799] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:30:0)
[23:09:41.815] Build status: STARTED
[23:09:41.815] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:31:0) after 7m26s
[23:17:08.034] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:31:0)
[23:17:08.097] Build status: STARTED
[23:17:08.097] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:32:0) after 5m24s
[23:22:32.254] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:32:0)
[23:22:32.270] Build status: STARTED
[23:22:32.270] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:33:0) after 5m42s
[23:28:14.650] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:33:0)
[23:28:14.672] Build status: STARTED
[23:28:14.672] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:34:0) after 2m42s
[23:30:56.694] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:34:0)
[23:30:56.711] Build status: STARTED
[23:30:56.711] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:35:0) after 5m9s
[23:36:06.053] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:35:0)
[23:36:06.075] Build status: STARTED
[23:36:06.075] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:36:0) after 3m14s
[23:39:20.198] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:36:0)
[23:39:20.218] Build status: STARTED
[23:39:20.218] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:37:0) after 4m31s
[23:43:51.323] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:37:0)
[23:43:51.338] Build status: STARTED
[23:43:51.338] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:38:0) after 1m26s
[23:45:17.368] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:38:0)
[23:45:17.392] Build status: STARTED
[23:45:17.392] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:39:0) after 4m28s
[23:49:45.420] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:39:0)
[23:49:45.448] Build status: STARTED
[23:49:45.448] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:40:0) after 8m28s
[23:58:13.692] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:40:0)
[23:58:13.711] Build status: STARTED
[23:58:13.711] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:41:0) after 2m58s
[00:01:11.976] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:41:0)
[00:01:12.061] Build status: STARTED
[00:01:12.061] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:42:0) after 2m46s
[00:03:58.092] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:42:0)
[00:03:58.112] Build status: STARTED
[00:03:58.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8945523776032885040:43:0) after 3m40s
[00:06:55.608] Received PubSub notification, asking Buildbucket for the build status
[00:06:55.629] Build:
{
"id": "8780093714068087921",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 25266,
"createdBy": "project:chromium",
"createTime": "2023-05-25T20:39:11.461229196Z",
"startTime": "2023-05-25T20:41:18.622957Z",
"endTime": "2023-05-26T00:06:55.115481929Z",
"updateTime": "2023-05-26T00:06:55.115481929Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1066a87a4470121f6f0fb46e096d5de2e86074c4",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:06:55.629] Invocation finished in 3h27m45.425298653s with status SUCCEEDED