[15:47:03.300] New invocation is queued and will start shortly
[15:47:04.373] Starting the invocation (attempt 1)
[15:47:04.429] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c43300370279a727f2ac1a9015d77f8df91e51e5"
[15:47:04.429] Popped gitiles commit info from properties and tags
[15:47:04.429] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:47:04.429] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:47:04.429] Buildbucket request:
{
"requestId": "8989209892142511088",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c43300370279a727f2ac1a9015d77f8df91e51e5",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8989209892142511088"
},
{
"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"
}
}
[15:47:05.080] Scheduled build:
{
"id": "8823779830234592497",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 24220,
"createdBy": "project:chromium",
"createTime": "2022-01-28T15:47:04.489868205Z",
"updateTime": "2022-01-28T15:47:04.489868205Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c43300370279a727f2ac1a9015d77f8df91e51e5",
"ref": "refs/heads/main"
}
}
}
[15:47:05.081] Task URL: https://cr-buildbucket.appspot.com/build/8823779830234592497
[15:47:05.081] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:2:0) after 5m7s
[15:49:29.118] Received PubSub notification, asking Buildbucket for the build status
[15:49:29.137] Build status: STARTED
[15:52:12.303] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:2:0)
[15:52:12.324] Build status: STARTED
[15:52:12.324] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:4:0) after 7m21s
[15:59:33.355] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:4:0)
[15:59:33.392] Build status: STARTED
[15:59:33.392] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:5:0) after 6m5s
[16:05:38.491] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:5:0)
[16:05:38.526] Build status: STARTED
[16:05:38.526] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:6:0) after 7m55s
[16:13:33.553] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:6:0)
[16:13:33.584] Build status: STARTED
[16:13:33.584] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:7:0) after 9m22s
[16:22:55.603] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:7:0)
[16:22:55.625] Build status: STARTED
[16:22:55.625] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:8:0) after 8m49s
[16:31:44.640] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:8:0)
[16:31:44.709] Build status: STARTED
[16:31:44.709] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:9:0) after 1m25s
[16:33:09.777] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:9:0)
[16:33:09.804] Build status: STARTED
[16:33:09.805] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:10:0) after 3m40s
[16:36:49.947] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:10:0)
[16:36:49.983] Build status: STARTED
[16:36:49.983] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:11:0) after 3m36s
[16:40:26.008] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:11:0)
[16:40:26.039] Build status: STARTED
[16:40:26.039] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:12:0) after 9m17s
[16:49:43.070] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:12:0)
[16:49:43.102] Build status: STARTED
[16:49:43.102] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:13:0) after 2m52s
[16:52:35.102] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:13:0)
[16:52:35.121] Build status: STARTED
[16:52:35.121] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:14:0) after 4m24s
[16:56:59.376] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:14:0)
[16:56:59.400] Build status: STARTED
[16:56:59.400] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:15:0) after 9m1s
[17:06:00.852] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:15:0)
[17:06:00.879] Build status: STARTED
[17:06:00.880] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:16:0) after 7m25s
[17:13:25.916] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:16:0)
[17:13:25.934] Build status: STARTED
[17:13:25.934] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:17:0) after 6m14s
[17:19:39.955] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:17:0)
[17:19:39.977] Build status: STARTED
[17:19:39.977] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:18:0) after 5m28s
[17:25:08.233] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:18:0)
[17:25:08.255] Build status: STARTED
[17:25:08.255] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:19:0) after 2m1s
[17:27:09.264] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:19:0)
[17:27:09.293] Build status: STARTED
[17:27:09.293] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:20:0) after 8m5s
[17:35:14.485] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:20:0)
[17:35:14.510] Build status: STARTED
[17:35:14.510] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:21:0) after 5m2s
[17:40:16.588] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:21:0)
[17:40:16.613] Build status: STARTED
[17:40:16.613] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:22:0) after 3m11s
[17:43:27.687] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:22:0)
[17:43:27.706] Build status: STARTED
[17:43:27.706] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:23:0) after 7m37s
[17:51:05.116] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:23:0)
[17:51:05.162] Build status: STARTED
[17:51:05.162] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:24:0) after 8m54s
[17:59:59.217] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:24:0)
[17:59:59.248] Build status: STARTED
[17:59:59.248] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:25:0) after 3m8s
[18:03:07.375] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:25:0)
[18:03:07.449] Build status: STARTED
[18:03:07.449] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:26:0) after 7m4s
[18:10:11.473] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:26:0)
[18:10:11.494] Build status: STARTED
[18:10:11.494] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:27:0) after 5m2s
[18:15:14.098] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:27:0)
[18:15:14.119] Build status: STARTED
[18:15:14.119] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:28:0) after 6m27s
[18:21:41.155] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:28:0)
[18:21:41.184] Build status: STARTED
[18:21:41.184] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:29:0) after 5m44s
[18:27:25.232] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:29:0)
[18:27:25.259] Build status: STARTED
[18:27:25.259] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:30:0) after 1m16s
[18:28:41.579] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:30:0)
[18:28:41.596] Build status: STARTED
[18:28:41.596] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:31:0) after 9m9s
[18:37:50.650] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:31:0)
[18:37:50.677] Build status: STARTED
[18:37:50.677] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:32:0) after 3m20s
[18:41:10.734] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:32:0)
[18:41:10.994] Build status: STARTED
[18:41:10.994] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:33:0) after 7m21s
[18:48:32.345] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:33:0)
[18:48:32.363] Build status: STARTED
[18:48:32.363] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8989209892142511088:34:0) after 6m22s
[18:50:39.516] Received PubSub notification, asking Buildbucket for the build status
[18:50:39.540] Build:
{
"id": "8823779830234592497",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 24220,
"createdBy": "project:chromium",
"createTime": "2022-01-28T15:47:04.489868205Z",
"startTime": "2022-01-28T15:49:28.413920Z",
"endTime": "2022-01-28T18:50:39.052894044Z",
"updateTime": "2022-01-28T18:50:39.052894044Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c43300370279a727f2ac1a9015d77f8df91e51e5",
"ref": "refs/heads/main"
}
}
}
[18:50:39.540] Invocation finished in 3h3m36.254029448s with status SUCCEEDED