[18:53:56.615] New invocation is queued and will start shortly
[18:53:57.836] Starting the invocation (attempt 1)
[18:53:57.867] Popped buildset tag "buildset:commit/gitiles/boringssl.googlesource.com/boringssl/+/5b845de636224ef3e065be8e1c7d2df3389aa175"
[18:53:57.867] Popped gitiles commit info from properties and tags
[18:53:57.867] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:53:57.867] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:53:57.867] Buildbucket request:
{
"requestId": "8946345769020649776",
"builder": {
"project": "boringssl",
"bucket": "ci",
"builder": "win64_small"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8946345769020649776",
"job": "boringssl/win64_small",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://boringssl.googlesource.com/boringssl",
"revision": "5b845de636224ef3e065be8e1c7d2df3389aa175"
},
"id": "https://boringssl.googlesource.com/boringssl/+/refs/heads/master@5b845de636224ef3e065be8e1c7d2df3389aa175",
"title": "5b845de636224ef3e065be8e1c7d2df3389aa175",
"url": "https://boringssl.googlesource.com/boringssl/+/5b845de636224ef3e065be8e1c7d2df3389aa175"
}
]
}
},
"gitilesCommit": {
"host": "boringssl.googlesource.com",
"project": "boringssl",
"id": "5b845de636224ef3e065be8e1c7d2df3389aa175",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8946345769020649776"
},
{
"key": "scheduler_job_id",
"value": "boringssl/win64_small"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:53:58.160] Scheduled build:
{
"id": "8780915706979905153",
"builder": {
"project": "boringssl",
"bucket": "ci",
"builder": "win64_small"
},
"createdBy": "project:boringssl",
"createTime": "2023-05-16T18:53:57.933923418Z",
"updateTime": "2023-05-16T18:53:57.933923418Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "boringssl.googlesource.com",
"project": "boringssl",
"id": "5b845de636224ef3e065be8e1c7d2df3389aa175",
"ref": "refs/heads/master"
}
},
"canary": true
}
[18:53:58.160] Task URL: https://cr-buildbucket.appspot.com/build/8780915706979905153
[18:53:58.160] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:2:0) after 5m48s
[18:59:46.215] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:2:0)
[18:59:46.237] Build status: SCHEDULED
[18:59:46.237] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:3:0) after 2m10s
[19:01:56.337] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:3:0)
[19:01:56.360] Build status: SCHEDULED
[19:01:56.360] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:4:0) after 7m38s
[19:09:34.384] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:4:0)
[19:09:34.414] Build status: SCHEDULED
[19:09:34.414] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:5:0) after 2m52s
[19:12:26.478] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:5:0)
[19:12:26.548] Build status: SCHEDULED
[19:12:26.548] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:6:0) after 3m56s
[19:16:22.576] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:6:0)
[19:16:22.598] Build status: SCHEDULED
[19:16:22.598] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:7:0) after 2m19s
[19:18:42.096] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:7:0)
[19:18:42.128] Build status: SCHEDULED
[19:18:42.128] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:8:0) after 7m42s
[19:26:24.196] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:8:0)
[19:26:24.233] Build status: SCHEDULED
[19:26:24.233] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:9:0) after 8m6s
[19:34:30.540] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:9:0)
[19:34:30.558] Build status: SCHEDULED
[19:34:30.558] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:10:0) after 8m45s
[19:43:15.749] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:10:0)
[19:43:15.831] Build status: SCHEDULED
[19:43:15.831] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:11:0) after 1m34s
[19:44:49.870] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:11:0)
[19:44:50.078] Build status: SCHEDULED
[19:44:50.078] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:12:0) after 4m45s
[19:49:35.413] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:12:0)
[19:49:35.436] Build status: SCHEDULED
[19:49:35.436] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:13:0) after 9m47s
[19:59:22.525] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:13:0)
[19:59:22.544] Build status: SCHEDULED
[19:59:22.544] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:14:0) after 9m54s
[20:09:16.567] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:14:0)
[20:09:16.615] Build status: SCHEDULED
[20:09:16.615] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:15:0) after 2m44s
[20:12:00.630] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:15:0)
[20:12:00.652] Build status: SCHEDULED
[20:12:00.652] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:16:0) after 7m54s
[20:19:54.815] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:16:0)
[20:19:54.833] Build status: SCHEDULED
[20:19:54.833] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:17:0) after 4m20s
[20:24:14.850] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:17:0)
[20:24:14.877] Build status: SCHEDULED
[20:24:14.877] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:18:0) after 9m52s
[20:34:06.973] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:18:0)
[20:34:06.996] Build status: SCHEDULED
[20:34:06.996] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:19:0) after 7m31s
[20:41:38.024] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:19:0)
[20:41:38.090] Build status: SCHEDULED
[20:41:38.090] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:20:0) after 2m11s
[20:43:49.266] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:20:0)
[20:43:49.313] Build status: SCHEDULED
[20:43:49.313] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:21:0) after 1m9s
[20:44:58.510] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:21:0)
[20:44:58.608] Build status: SCHEDULED
[20:44:58.608] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:22:0) after 4m41s
[20:49:39.868] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:22:0)
[20:49:39.892] Build status: SCHEDULED
[20:49:39.892] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:23:0) after 8m20s
[20:54:49.297] Received PubSub notification, asking Buildbucket for the build status
[20:54:49.319] Build status: STARTED
[20:58:00.111] Handling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:23:0)
[20:58:00.143] Build status: STARTED
[20:58:00.143] Scheduling timer "check-buildbucket-build-status" (boringssl/win64_small:8946345769020649776:25:0) after 9m24s
[21:01:06.880] Received PubSub notification, asking Buildbucket for the build status
[21:01:06.913] Build:
{
"id": "8780915706979905153",
"builder": {
"project": "boringssl",
"bucket": "ci",
"builder": "win64_small"
},
"createdBy": "project:boringssl",
"createTime": "2023-05-16T18:53:57.933923418Z",
"startTime": "2023-05-16T20:54:48.709727Z",
"endTime": "2023-05-16T21:01:06.576028822Z",
"updateTime": "2023-05-16T21:01:06.576028822Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "boringssl.googlesource.com",
"project": "boringssl",
"id": "5b845de636224ef3e065be8e1c7d2df3389aa175",
"ref": "refs/heads/master"
}
},
"canary": true
}
[21:01:06.913] Invocation finished in 2h7m10.309683734s with status SUCCEEDED