[18:24:58.948] New invocation is queued and will start shortly
[18:25:00.056] Starting the invocation (attempt 1)
[18:25:00.094] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/19a78360a36cb0848649f67cfc09d3f0bf9831f6"
[18:25:00.094] Popped gitiles commit info from properties and tags
[18:25:00.094] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:25:00.094] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:25:00.094] Buildbucket request:
{
"requestId": "8991283686434304384",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "19a78360a36cb0848649f67cfc09d3f0bf9831f6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8991283686434304384"
},
{
"key": "scheduler_job_id",
"value": "chromium/Deterministic Linux (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:25:00.756] Scheduled build:
{
"id": "8825853624489806737",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 23955,
"createdBy": "project:chromium",
"createTime": "2022-01-05T18:25:00.171908284Z",
"updateTime": "2022-01-05T18:25:00.171908284Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "19a78360a36cb0848649f67cfc09d3f0bf9831f6",
"ref": "refs/heads/main"
}
},
"canary": true
}
[18:25:00.756] Task URL: https://cr-buildbucket.appspot.com/build/8825853624489806737
[18:25:00.756] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:2:0) after 7m48s
[18:25:23.699] Received PubSub notification, asking Buildbucket for the build status
[18:25:23.727] Build status: STARTED
[18:32:49.145] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:2:0)
[18:32:49.184] Build status: STARTED
[18:32:49.184] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:4:0) after 7m30s
[18:40:19.400] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:4:0)
[18:40:19.432] Build status: STARTED
[18:40:19.448] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:5:0) after 1m29s
[18:41:48.493] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:5:0)
[18:41:48.530] Build status: STARTED
[18:41:48.530] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:6:0) after 4m47s
[18:46:35.555] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:6:0)
[18:46:35.582] Build status: STARTED
[18:46:35.582] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:7:0) after 4m22s
[18:50:57.601] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:7:0)
[18:50:57.620] Build status: STARTED
[18:50:57.620] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:8:0) after 3m41s
[18:54:38.741] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:8:0)
[18:54:38.760] Build status: STARTED
[18:54:38.760] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:9:0) after 5m19s
[18:59:57.782] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:9:0)
[18:59:57.807] Build status: STARTED
[18:59:57.807] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:10:0) after 9m1s
[19:08:58.828] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:10:0)
[19:08:58.884] Build status: STARTED
[19:08:58.884] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:11:0) after 3m27s
[19:12:25.891] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:11:0)
[19:12:25.934] Build status: STARTED
[19:12:25.934] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:12:0) after 7m26s
[19:19:51.954] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:12:0)
[19:19:51.981] Build status: STARTED
[19:19:51.981] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:13:0) after 9m30s
[19:29:22.005] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:13:0)
[19:29:22.033] Build status: STARTED
[19:29:22.033] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:14:0) after 8m0s
[19:37:22.071] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:14:0)
[19:37:22.094] Build status: STARTED
[19:37:22.094] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:15:0) after 7m52s
[19:45:14.159] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:15:0)
[19:45:14.187] Build status: STARTED
[19:45:14.187] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:16:0) after 6m14s
[19:51:28.209] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:16:0)
[19:51:28.239] Build status: STARTED
[19:51:28.239] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:17:0) after 2m39s
[19:54:07.324] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:17:0)
[19:54:07.358] Build status: STARTED
[19:54:07.358] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:18:0) after 7m30s
[20:01:37.390] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:18:0)
[20:01:37.432] Build status: STARTED
[20:01:37.432] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:19:0) after 6m0s
[20:07:37.626] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:19:0)
[20:07:37.694] Build status: STARTED
[20:07:37.694] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:20:0) after 7m52s
[20:15:29.777] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:20:0)
[20:15:29.816] Build status: STARTED
[20:15:29.816] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:21:0) after 1m51s
[20:17:20.841] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:21:0)
[20:17:20.885] Build status: STARTED
[20:17:20.885] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:22:0) after 3m20s
[20:20:40.941] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:22:0)
[20:20:40.972] Build status: STARTED
[20:20:40.972] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:23:0) after 5m34s
[20:26:15.035] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:23:0)
[20:26:15.063] Build status: STARTED
[20:26:15.063] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:24:0) after 1m6s
[20:27:21.220] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:24:0)
[20:27:21.242] Build status: STARTED
[20:27:21.242] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:25:0) after 4m32s
[20:31:53.351] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:25:0)
[20:31:53.372] Build status: STARTED
[20:31:53.372] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:26:0) after 3m46s
[20:35:39.502] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:26:0)
[20:35:39.521] Build status: STARTED
[20:35:39.521] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:27:0) after 7m1s
[20:42:40.885] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:27:0)
[20:42:40.904] Build status: STARTED
[20:42:40.904] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:28:0) after 7m19s
[20:50:00.151] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:28:0)
[20:50:00.172] Build status: STARTED
[20:50:00.172] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:29:0) after 4m21s
[20:54:21.318] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:29:0)
[20:54:21.357] Build status: STARTED
[20:54:21.357] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:30:0) after 1m14s
[20:55:35.659] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:30:0)
[20:55:35.690] Build status: STARTED
[20:55:35.690] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:31:0) after 7m32s
[21:03:08.301] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:31:0)
[21:03:08.321] Build status: STARTED
[21:03:08.321] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:32:0) after 9m29s
[21:12:37.439] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:32:0)
[21:12:37.463] Build status: STARTED
[21:12:37.463] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:33:0) after 2m28s
[21:15:05.502] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:33:0)
[21:15:05.531] Build status: STARTED
[21:15:05.531] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:34:0) after 3m0s
[21:18:05.667] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:34:0)
[21:18:05.696] Build status: STARTED
[21:18:05.696] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:35:0) after 2m15s
[21:20:21.097] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:35:0)
[21:20:21.128] Build status: STARTED
[21:20:21.128] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8991283686434304384:36:0) after 9m37s
[21:26:46.489] Received PubSub notification, asking Buildbucket for the build status
[21:26:46.512] Build:
{
"id": "8825853624489806737",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 23955,
"createdBy": "project:chromium",
"createTime": "2022-01-05T18:25:00.171908284Z",
"startTime": "2022-01-05T18:25:18.081619Z",
"endTime": "2022-01-05T21:26:46.152809686Z",
"updateTime": "2022-01-05T21:26:46.152809686Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "19a78360a36cb0848649f67cfc09d3f0bf9831f6",
"ref": "refs/heads/main"
}
},
"canary": true
}
[21:26:46.512] Invocation finished in 3h1m47.578684319s with status SUCCEEDED