[19:10:04.300] New invocation is queued and will start shortly
[19:10:05.613] Starting the invocation (attempt 1)
[19:10:05.643] Popped buildset tag "buildset:commit/gitiles/dawn.googlesource.com/dawn/+/b58a264fc729fc9ea5403a4478c7b6b483f9a0af"
[19:10:05.643] Popped gitiles commit info from properties and tags
[19:10:05.643] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:10:05.643] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:10:05.644] Buildbucket request:
{
"requestId": "8910015370806283664",
"builder": {
"project": "dawn",
"bucket": "ci",
"builder": "win-clang-dbg-x86"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8910015370806283664",
"job": "dawn/win-clang-dbg-x86",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dawn.googlesource.com/dawn",
"revision": "ab67c2f6ee78d27732c7a448653d17b5da9c902d"
},
"id": "https://dawn.googlesource.com/dawn/+/refs/heads/main@ab67c2f6ee78d27732c7a448653d17b5da9c902d",
"title": "ab67c2f6ee78d27732c7a448653d17b5da9c902d",
"url": "https://dawn.googlesource.com/dawn/+/ab67c2f6ee78d27732c7a448653d17b5da9c902d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dawn.googlesource.com/dawn",
"revision": "8b211641664ad4fcbeb6ef03a0587c6893c52597"
},
"id": "https://dawn.googlesource.com/dawn/+/refs/heads/main@8b211641664ad4fcbeb6ef03a0587c6893c52597",
"title": "8b211641664ad4fcbeb6ef03a0587c6893c52597",
"url": "https://dawn.googlesource.com/dawn/+/8b211641664ad4fcbeb6ef03a0587c6893c52597"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dawn.googlesource.com/dawn",
"revision": "36fbf3d17dbb316b0aa6503cfcd88fd79b90f62a"
},
"id": "https://dawn.googlesource.com/dawn/+/refs/heads/main@36fbf3d17dbb316b0aa6503cfcd88fd79b90f62a",
"title": "36fbf3d17dbb316b0aa6503cfcd88fd79b90f62a",
"url": "https://dawn.googlesource.com/dawn/+/36fbf3d17dbb316b0aa6503cfcd88fd79b90f62a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dawn.googlesource.com/dawn",
"revision": "7329a91ec456cea77221bcca6b8dc9708c4e3063"
},
"id": "https://dawn.googlesource.com/dawn/+/refs/heads/main@7329a91ec456cea77221bcca6b8dc9708c4e3063",
"title": "7329a91ec456cea77221bcca6b8dc9708c4e3063",
"url": "https://dawn.googlesource.com/dawn/+/7329a91ec456cea77221bcca6b8dc9708c4e3063"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dawn.googlesource.com/dawn",
"revision": "b58a264fc729fc9ea5403a4478c7b6b483f9a0af"
},
"id": "https://dawn.googlesource.com/dawn/+/refs/heads/main@b58a264fc729fc9ea5403a4478c7b6b483f9a0af",
"title": "b58a264fc729fc9ea5403a4478c7b6b483f9a0af",
"url": "https://dawn.googlesource.com/dawn/+/b58a264fc729fc9ea5403a4478c7b6b483f9a0af"
}
]
}
},
"gitilesCommit": {
"host": "dawn.googlesource.com",
"project": "dawn",
"id": "b58a264fc729fc9ea5403a4478c7b6b483f9a0af",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8910015370806283664"
},
{
"key": "scheduler_job_id",
"value": "dawn/win-clang-dbg-x86"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:10:06.163] Scheduled build:
{
"id": "8744585308674825441",
"builder": {
"project": "dawn",
"bucket": "ci",
"builder": "win-clang-dbg-x86"
},
"createdBy": "project:dawn",
"createTime": "2024-06-20T19:10:05.701794882Z",
"updateTime": "2024-06-20T19:10:05.701794882Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "dawn.googlesource.com",
"project": "dawn",
"id": "b58a264fc729fc9ea5403a4478c7b6b483f9a0af",
"ref": "refs/heads/main"
}
}
}
[19:10:06.163] Task URL: https://cr-buildbucket.appspot.com/build/8744585308674825441
[19:10:06.163] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:2:0) after 5m38s
[19:10:24.616] Received PubSub notification, asking Buildbucket for the build status
[19:10:24.645] Build status: SCHEDULED
[19:15:44.263] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:2:0)
[19:15:44.289] Build status: SCHEDULED
[19:15:44.289] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:4:0) after 1m49s
[19:17:33.347] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:4:0)
[19:17:33.371] Build status: SCHEDULED
[19:17:33.371] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:5:0) after 1m14s
[19:18:47.512] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:5:0)
[19:18:47.538] Build status: SCHEDULED
[19:18:47.538] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:6:0) after 7m13s
[19:26:00.646] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:6:0)
[19:26:00.703] Build status: SCHEDULED
[19:26:00.703] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:7:0) after 1m54s
[19:27:54.716] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:7:0)
[19:27:54.736] Build status: SCHEDULED
[19:27:54.736] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:8:0) after 9m52s
[19:37:46.782] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:8:0)
[19:37:46.802] Build status: SCHEDULED
[19:37:46.802] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:9:0) after 5m31s
[19:43:17.820] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:9:0)
[19:43:17.852] Build status: SCHEDULED
[19:43:17.852] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:10:0) after 9m13s
[19:52:30.903] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:10:0)
[19:52:30.935] Build status: SCHEDULED
[19:52:30.935] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:11:0) after 2m48s
[19:55:18.951] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:11:0)
[19:55:18.993] Build status: SCHEDULED
[19:55:18.993] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:12:0) after 1m41s
[19:57:00.029] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:12:0)
[19:57:00.049] Build status: SCHEDULED
[19:57:00.049] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:13:0) after 1m14s
[19:58:14.037] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:13:0)
[19:58:14.062] Build status: SCHEDULED
[19:58:14.062] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:14:0) after 4m19s
[20:02:33.088] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:14:0)
[20:02:33.119] Build status: SCHEDULED
[20:02:33.119] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:15:0) after 8m44s
[20:11:17.139] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:15:0)
[20:11:17.197] Build status: SCHEDULED
[20:11:17.197] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:16:0) after 9m4s
[20:20:21.329] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:16:0)
[20:20:21.350] Build status: SCHEDULED
[20:20:21.350] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:17:0) after 5m53s
[20:26:14.379] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:17:0)
[20:26:14.400] Build status: SCHEDULED
[20:26:14.400] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:18:0) after 7m1s
[20:33:15.517] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:18:0)
[20:33:15.545] Build status: SCHEDULED
[20:33:15.545] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:19:0) after 1m29s
[20:34:44.563] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:19:0)
[20:34:44.599] Build status: SCHEDULED
[20:34:44.599] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:20:0) after 2m24s
[20:37:08.665] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:20:0)
[20:37:08.688] Build status: SCHEDULED
[20:37:08.688] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:21:0) after 8m43s
[20:37:26.246] Received PubSub notification, asking Buildbucket for the build status
[20:37:26.265] Build status: STARTED
[20:45:51.697] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:21:0)
[20:45:51.716] Build status: STARTED
[20:45:51.716] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:23:0) after 1m51s
[20:47:42.736] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:23:0)
[20:47:42.755] Build status: STARTED
[20:47:42.755] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:24:0) after 5m22s
[20:53:04.873] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:24:0)
[20:53:04.976] Build status: STARTED
[20:53:04.976] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:25:0) after 7m49s
[21:00:53.980] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:25:0)
[21:00:54.018] Build status: STARTED
[21:00:54.018] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:26:0) after 5m0s
[21:05:54.122] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:26:0)
[21:05:54.155] Build status: STARTED
[21:05:54.156] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:27:0) after 1m12s
[21:07:06.172] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:27:0)
[21:07:06.192] Build status: STARTED
[21:07:06.192] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:28:0) after 8m13s
[21:15:19.285] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:28:0)
[21:15:19.355] Build status: STARTED
[21:15:19.355] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:29:0) after 8m52s
[21:24:11.397] Handling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:29:0)
[21:24:11.481] Build status: STARTED
[21:24:11.481] Scheduling timer "check-buildbucket-build-status" (dawn/win-clang-dbg-x86:8910015370806283664:30:0) after 9m49s
[21:25:28.664] Received PubSub notification, asking Buildbucket for the build status
[21:25:28.694] Build:
{
"id": "8744585308674825441",
"builder": {
"project": "dawn",
"bucket": "ci",
"builder": "win-clang-dbg-x86"
},
"createdBy": "project:dawn",
"createTime": "2024-06-20T19:10:05.701794882Z",
"startTime": "2024-06-20T20:37:26.066211738Z",
"endTime": "2024-06-20T21:25:28.442632397Z",
"updateTime": "2024-06-20T21:25:28.442632397Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "dawn.googlesource.com",
"project": "dawn",
"id": "b58a264fc729fc9ea5403a4478c7b6b483f9a0af",
"ref": "refs/heads/main"
}
}
}
[21:25:28.694] Invocation finished in 2h15m24.408854528s with status SUCCEEDED