[21:54:20.593] New invocation is queued and will start shortly
[21:54:21.712] Starting the invocation (attempt 1)
[21:54:21.739] Popped buildset tag "buildset:commit/gitiles/boringssl.googlesource.com/boringssl/+/add6ffb02826087230c2e756c39779bed0f5e345"
[21:54:21.739] Popped gitiles commit info from properties and tags
[21:54:21.739] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:54:21.739] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:54:21.739] Buildbucket request:
{
"requestId": "8937274722629704960",
"builder": {
"project": "boringssl",
"bucket": "ci",
"builder": "win64_rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8937274722629704960",
"job": "boringssl/win64_rel",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://boringssl.googlesource.com/boringssl",
"revision": "add6ffb02826087230c2e756c39779bed0f5e345"
},
"id": "https://boringssl.googlesource.com/boringssl/+/refs/heads/master@add6ffb02826087230c2e756c39779bed0f5e345",
"title": "add6ffb02826087230c2e756c39779bed0f5e345",
"url": "https://boringssl.googlesource.com/boringssl/+/add6ffb02826087230c2e756c39779bed0f5e345"
}
]
}
},
"gitilesCommit": {
"host": "boringssl.googlesource.com",
"project": "boringssl",
"id": "add6ffb02826087230c2e756c39779bed0f5e345",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8937274722629704960"
},
{
"key": "scheduler_job_id",
"value": "boringssl/win64_rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:54:22.375] Scheduled build:
{
"id": "8771844660690279281",
"builder": {
"project": "boringssl",
"bucket": "ci",
"builder": "win64_rel"
},
"createdBy": "project:boringssl",
"createTime": "2023-08-24T21:54:21.802494441Z",
"updateTime": "2023-08-24T21:54:21.802494441Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "boringssl.googlesource.com",
"project": "boringssl",
"id": "add6ffb02826087230c2e756c39779bed0f5e345",
"ref": "refs/heads/master"
}
}
}
[21:54:22.375] Task URL: https://cr-buildbucket.appspot.com/build/8771844660690279281
[21:54:22.375] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:2:0) after 1m36s
[21:54:23.412] Received PubSub notification, asking Buildbucket for the build status
[21:54:23.429] Build status: SCHEDULED
[21:55:58.388] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:2:0)
[21:55:58.417] Build status: SCHEDULED
[21:55:58.417] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:4:0) after 6m17s
[22:02:15.856] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:4:0)
[22:02:15.893] Build status: SCHEDULED
[22:02:15.894] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:5:0) after 7m7s
[22:09:22.912] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:5:0)
[22:09:22.930] Build status: SCHEDULED
[22:09:22.930] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:6:0) after 2m18s
[22:11:41.133] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:6:0)
[22:11:41.198] Build status: SCHEDULED
[22:11:41.198] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:7:0) after 5m13s
[22:16:54.264] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:7:0)
[22:16:54.321] Build status: SCHEDULED
[22:16:54.321] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:8:0) after 6m32s
[22:23:26.462] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:8:0)
[22:23:26.490] Build status: SCHEDULED
[22:23:26.490] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:9:0) after 2m6s
[22:25:32.633] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:9:0)
[22:25:32.652] Build status: SCHEDULED
[22:25:32.652] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:10:0) after 9m47s
[22:35:20.232] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:10:0)
[22:35:20.250] Build status: SCHEDULED
[22:35:20.250] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:11:0) after 2m20s
[22:37:40.478] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:11:0)
[22:37:40.502] Build status: SCHEDULED
[22:37:40.502] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:12:0) after 2m39s
[22:40:19.629] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:12:0)
[22:40:19.648] Build status: SCHEDULED
[22:40:19.648] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:13:0) after 9m44s
[22:50:03.753] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:13:0)
[22:50:03.789] Build status: SCHEDULED
[22:50:03.789] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:14:0) after 8m20s
[22:58:23.862] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:14:0)
[22:58:23.879] Build status: SCHEDULED
[22:58:23.879] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:15:0) after 8m15s
[23:06:38.958] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:15:0)
[23:06:38.994] Build status: SCHEDULED
[23:06:38.994] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:16:0) after 6m50s
[23:13:29.330] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:16:0)
[23:13:29.347] Build status: SCHEDULED
[23:13:29.347] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:17:0) after 1m49s
[23:15:18.375] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:17:0)
[23:15:18.400] Build status: SCHEDULED
[23:15:18.400] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:18:0) after 3m26s
[23:18:44.490] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:18:0)
[23:18:44.513] Build status: SCHEDULED
[23:18:44.513] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:19:0) after 6m41s
[23:25:25.535] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:19:0)
[23:25:25.551] Build status: SCHEDULED
[23:25:25.551] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:20:0) after 7m20s
[23:32:45.639] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:20:0)
[23:32:45.655] Build status: SCHEDULED
[23:32:45.655] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:21:0) after 4m28s
[23:37:13.780] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:21:0)
[23:37:13.796] Build status: SCHEDULED
[23:37:13.796] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:22:0) after 8m52s
[23:46:06.835] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:22:0)
[23:46:06.857] Build status: SCHEDULED
[23:46:06.857] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:23:0) after 7m13s
[23:53:20.501] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:23:0)
[23:53:20.521] Build status: SCHEDULED
[23:53:20.521] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:24:0) after 4m39s
[23:57:59.969] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:24:0)
[23:58:00.051] Build status: SCHEDULED
[23:58:00.051] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:25:0) after 8m34s
[00:06:34.066] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:25:0)
[00:06:34.097] Build status: SCHEDULED
[00:06:34.097] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:26:0) after 6m20s
[00:12:54.113] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:26:0)
[00:12:54.159] Build status: SCHEDULED
[00:12:54.159] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:27:0) after 5m33s
[00:18:27.202] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:27:0)
[00:18:27.219] Build status: SCHEDULED
[00:18:27.219] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:28:0) after 9m51s
[00:28:18.617] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:28:0)
[00:28:18.638] Build status: SCHEDULED
[00:28:18.638] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:29:0) after 8m35s
[00:36:53.933] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:29:0)
[00:36:53.972] Build status: SCHEDULED
[00:36:53.972] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:30:0) after 5m18s
[00:42:12.269] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:30:0)
[00:42:12.289] Build status: SCHEDULED
[00:42:12.289] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:31:0) after 8m35s
[00:50:47.263] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:31:0)
[00:50:47.282] Build status: SCHEDULED
[00:50:47.282] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:32:0) after 1m24s
[00:52:11.429] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:32:0)
[00:52:11.445] Build status: SCHEDULED
[00:52:11.445] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:33:0) after 1m33s
[00:53:44.585] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:33:0)
[00:53:44.637] Build status: SCHEDULED
[00:53:44.637] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:34:0) after 2m3s
[00:55:47.669] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:34:0)
[00:55:47.687] Build status: SCHEDULED
[00:55:47.687] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:35:0) after 6m35s
[01:01:41.085] Received PubSub notification, asking Buildbucket for the build status
[01:01:41.115] Build status: STARTED
[01:02:22.806] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:35:0)
[01:02:22.839] Build status: STARTED
[01:02:22.839] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:37:0) after 2m55s
[01:05:18.099] Handling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:37:0)
[01:05:18.138] Build status: STARTED
[01:05:18.138] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_rel:8937274722629704960:38:0) after 5m40s
[01:08:03.021] Received PubSub notification, asking Buildbucket for the build status
[01:08:03.041] Build:
{
"id": "8771844660690279281",
"builder": {
"project": "boringssl",
"bucket": "ci",
"builder": "win64_rel"
},
"createdBy": "project:boringssl",
"createTime": "2023-08-24T21:54:21.802494441Z",
"startTime": "2023-08-25T01:01:40.657172Z",
"endTime": "2023-08-25T01:08:02.764590599Z",
"updateTime": "2023-08-25T01:08:02.764590599Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "boringssl.googlesource.com",
"project": "boringssl",
"id": "add6ffb02826087230c2e756c39779bed0f5e345",
"ref": "refs/heads/master"
}
}
}
[01:08:03.041] Invocation finished in 3h13m42.471888287s with status SUCCEEDED