[04:30:49.594] New invocation is queued and will start shortly
[04:30:50.682] Starting the invocation (attempt 1)
[04:30:50.706] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b404807517649b5ad19881e0ee005459be151fe3"
[04:30:50.706] Popped gitiles commit info from properties and tags
[04:30:50.706] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:30:50.706] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:30:50.706] Buildbucket request:
{
"requestId": "8938608732530166384",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b404807517649b5ad19881e0ee005459be151fe3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8938608732530166384"
},
{
"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"
}
}
[04:30:51.461] Scheduled build:
{
"id": "8773178670594959617",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 25796,
"createdBy": "project:chromium",
"createTime": "2023-08-10T04:30:50.818837097Z",
"updateTime": "2023-08-10T04:30:50.818837097Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b404807517649b5ad19881e0ee005459be151fe3",
"ref": "refs/heads/main"
}
},
"canary": true
}
[04:30:51.461] Task URL: https://cr-buildbucket.appspot.com/build/8773178670594959617
[04:30:51.462] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:2:0) after 5m3s
[04:30:59.882] Received PubSub notification, asking Buildbucket for the build status
[04:30:59.911] Build status: SCHEDULED
[04:33:45.615] Received PubSub notification, asking Buildbucket for the build status
[04:33:45.649] Build status: STARTED
[04:35:54.519] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:2:0)
[04:35:54.539] Build status: STARTED
[04:35:54.539] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:5:0) after 1m7s
[04:37:01.795] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:5:0)
[04:37:01.829] Build status: STARTED
[04:37:01.829] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:6:0) after 2m55s
[04:39:56.868] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:6:0)
[04:39:56.885] Build status: STARTED
[04:39:56.885] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:7:0) after 8m11s
[04:48:08.035] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:7:0)
[04:48:08.067] Build status: STARTED
[04:48:08.067] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:8:0) after 3m25s
[04:51:33.150] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:8:0)
[04:51:33.169] Build status: STARTED
[04:51:33.169] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:9:0) after 3m3s
[04:54:36.187] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:9:0)
[04:54:36.244] Build status: STARTED
[04:54:36.245] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:10:0) after 1m28s
[04:56:04.404] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:10:0)
[04:56:04.427] Build status: STARTED
[04:56:04.427] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:11:0) after 2m23s
[04:58:27.725] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:11:0)
[04:58:27.746] Build status: STARTED
[04:58:27.746] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:12:0) after 9m8s
[05:07:35.887] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:12:0)
[05:07:35.907] Build status: STARTED
[05:07:35.907] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:13:0) after 2m58s
[05:10:33.943] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:13:0)
[05:10:33.965] Build status: STARTED
[05:10:33.965] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:14:0) after 4m21s
[05:14:55.412] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:14:0)
[05:14:55.457] Build status: STARTED
[05:14:55.457] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:15:0) after 2m9s
[05:17:04.488] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:15:0)
[05:17:04.510] Build status: STARTED
[05:17:04.511] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:16:0) after 3m57s
[05:21:01.524] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:16:0)
[05:21:01.556] Build status: STARTED
[05:21:01.557] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:17:0) after 7m36s
[05:28:37.613] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:17:0)
[05:28:37.639] Build status: STARTED
[05:28:37.639] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:18:0) after 4m23s
[05:33:00.658] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:18:0)
[05:33:00.679] Build status: STARTED
[05:33:00.679] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:19:0) after 4m14s
[05:37:15.045] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:19:0)
[05:37:15.064] Build status: STARTED
[05:37:15.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:20:0) after 8m34s
[05:45:49.163] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:20:0)
[05:45:49.184] Build status: STARTED
[05:45:49.184] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:21:0) after 1m22s
[05:47:11.201] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:21:0)
[05:47:11.221] Build status: STARTED
[05:47:11.221] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:22:0) after 3m50s
[05:51:01.237] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:22:0)
[05:51:01.266] Build status: STARTED
[05:51:01.266] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:23:0) after 7m40s
[05:58:41.288] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:23:0)
[05:58:41.354] Build status: STARTED
[05:58:41.354] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:24:0) after 3m5s
[06:01:46.372] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:24:0)
[06:01:46.404] Build status: STARTED
[06:01:46.404] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:25:0) after 6m18s
[06:08:04.522] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:25:0)
[06:08:04.543] Build status: STARTED
[06:08:04.543] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:26:0) after 3m52s
[06:11:56.567] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:26:0)
[06:11:56.593] Build status: STARTED
[06:11:56.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:27:0) after 1m35s
[06:13:31.609] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:27:0)
[06:13:31.626] Build status: STARTED
[06:13:31.626] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:28:0) after 6m25s
[06:19:56.663] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:28:0)
[06:19:56.683] Build status: STARTED
[06:19:56.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:29:0) after 7m15s
[06:27:11.687] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:29:0)
[06:27:11.738] Build status: STARTED
[06:27:11.738] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:30:0) after 5m58s
[06:33:09.754] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:30:0)
[06:33:09.899] Build status: STARTED
[06:33:09.899] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:31:0) after 3m27s
[06:36:36.914] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:31:0)
[06:36:36.929] Build status: STARTED
[06:36:36.929] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:32:0) after 8m53s
[06:45:30.015] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:32:0)
[06:45:30.037] Build status: STARTED
[06:45:30.037] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:33:0) after 8m48s
[06:54:18.128] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:33:0)
[06:54:18.154] Build status: STARTED
[06:54:18.154] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:34:0) after 7m53s
[07:02:11.295] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:34:0)
[07:02:11.318] Build status: STARTED
[07:02:11.318] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:35:0) after 1m29s
[07:03:40.406] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:35:0)
[07:03:40.434] Build status: STARTED
[07:03:40.434] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:36:0) after 1m20s
[07:05:00.498] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:36:0)
[07:05:00.542] Build status: STARTED
[07:05:00.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:37:0) after 2m13s
[07:07:13.563] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:37:0)
[07:07:13.581] Build status: STARTED
[07:07:13.581] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:38:0) after 8m0s
[07:15:13.699] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:38:0)
[07:15:13.827] Build status: STARTED
[07:15:13.827] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:39:0) after 1m57s
[07:17:10.935] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:39:0)
[07:17:10.971] Build status: STARTED
[07:17:10.971] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:40:0) after 1m56s
[07:19:06.993] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:40:0)
[07:19:07.015] Build status: STARTED
[07:19:07.015] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:41:0) after 9m40s
[07:28:47.285] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:41:0)
[07:28:47.306] Build status: STARTED
[07:28:47.307] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:42:0) after 8m27s
[07:37:14.277] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:42:0)
[07:37:14.301] Build status: STARTED
[07:37:14.301] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:43:0) after 5m19s
[07:42:33.727] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:43:0)
[07:42:33.749] Build status: STARTED
[07:42:33.749] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:44:0) after 5m27s
[07:48:00.766] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:44:0)
[07:48:00.785] Build status: STARTED
[07:48:00.785] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:45:0) after 4m49s
[07:52:49.858] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:45:0)
[07:52:49.877] Build status: STARTED
[07:52:49.877] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:46:0) after 3m56s
[07:56:46.116] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:46:0)
[07:56:46.139] Build status: STARTED
[07:56:46.139] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8938608732530166384:47:0) after 6m56s
[08:02:44.960] Received PubSub notification, asking Buildbucket for the build status
[08:02:44.983] Build:
{
"id": "8773178670594959617",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 25796,
"createdBy": "project:chromium",
"createTime": "2023-08-10T04:30:50.818837097Z",
"startTime": "2023-08-10T04:33:44.874452Z",
"endTime": "2023-08-10T08:02:44.689801719Z",
"updateTime": "2023-08-10T08:02:44.689801719Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b404807517649b5ad19881e0ee005459be151fe3",
"ref": "refs/heads/main"
}
},
"canary": true
}
[08:02:44.983] Invocation finished in 3h31m55.393170513s with status FAILED