[00:33:02.586] New invocation is queued and will start shortly
[00:33:03.655] Starting the invocation (attempt 1)
[00:33:03.692] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/e5aa7999bf1bc9a6c7a03bbc65036325a4ec5503"
[00:33:03.692] Popped gitiles commit info from properties and tags
[00:33:03.692] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:33:03.692] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:33:03.692] Buildbucket request:
{
"requestId": "8908273708828767584",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8908273708828767584",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6b8453732b9129299170f7c3ef574ff219790fc5"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6b8453732b9129299170f7c3ef574ff219790fc5",
"title": "6b8453732b9129299170f7c3ef574ff219790fc5",
"url": "https://llvm.googlesource.com/llvm-project/+/6b8453732b9129299170f7c3ef574ff219790fc5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "240ec5a37517f55a64cd0c69fd4fa1613ab30f97"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@240ec5a37517f55a64cd0c69fd4fa1613ab30f97",
"title": "240ec5a37517f55a64cd0c69fd4fa1613ab30f97",
"url": "https://llvm.googlesource.com/llvm-project/+/240ec5a37517f55a64cd0c69fd4fa1613ab30f97"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "04f0adcd58baa530b13472a897b422fae46abfa2"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@04f0adcd58baa530b13472a897b422fae46abfa2",
"title": "04f0adcd58baa530b13472a897b422fae46abfa2",
"url": "https://llvm.googlesource.com/llvm-project/+/04f0adcd58baa530b13472a897b422fae46abfa2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "be9b1de1ab71e7d19c197fdfe1c3681f22c31923"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@be9b1de1ab71e7d19c197fdfe1c3681f22c31923",
"title": "be9b1de1ab71e7d19c197fdfe1c3681f22c31923",
"url": "https://llvm.googlesource.com/llvm-project/+/be9b1de1ab71e7d19c197fdfe1c3681f22c31923"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3a744283f4c56b57adb2c381c0aeaf7faf5120ec"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3a744283f4c56b57adb2c381c0aeaf7faf5120ec",
"title": "3a744283f4c56b57adb2c381c0aeaf7faf5120ec",
"url": "https://llvm.googlesource.com/llvm-project/+/3a744283f4c56b57adb2c381c0aeaf7faf5120ec"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a40daa34efd3cc0fe7ef50f936775287cb14b399"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a40daa34efd3cc0fe7ef50f936775287cb14b399",
"title": "a40daa34efd3cc0fe7ef50f936775287cb14b399",
"url": "https://llvm.googlesource.com/llvm-project/+/a40daa34efd3cc0fe7ef50f936775287cb14b399"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f137be30a4b6f49b86bfde6b5ee59463884916fc"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f137be30a4b6f49b86bfde6b5ee59463884916fc",
"title": "f137be30a4b6f49b86bfde6b5ee59463884916fc",
"url": "https://llvm.googlesource.com/llvm-project/+/f137be30a4b6f49b86bfde6b5ee59463884916fc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "d283627c4a0a4fec6c29d33fcbaf123365eeefae"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@d283627c4a0a4fec6c29d33fcbaf123365eeefae",
"title": "d283627c4a0a4fec6c29d33fcbaf123365eeefae",
"url": "https://llvm.googlesource.com/llvm-project/+/d283627c4a0a4fec6c29d33fcbaf123365eeefae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2d8b282225f88371b8187f2ec02280ee474e21d6"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2d8b282225f88371b8187f2ec02280ee474e21d6",
"title": "2d8b282225f88371b8187f2ec02280ee474e21d6",
"url": "https://llvm.googlesource.com/llvm-project/+/2d8b282225f88371b8187f2ec02280ee474e21d6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "14f745074d05fb42f5c8a625c79bf557bb980ebb"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@14f745074d05fb42f5c8a625c79bf557bb980ebb",
"title": "14f745074d05fb42f5c8a625c79bf557bb980ebb",
"url": "https://llvm.googlesource.com/llvm-project/+/14f745074d05fb42f5c8a625c79bf557bb980ebb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e5aa7999bf1bc9a6c7a03bbc65036325a4ec5503"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e5aa7999bf1bc9a6c7a03bbc65036325a4ec5503",
"title": "e5aa7999bf1bc9a6c7a03bbc65036325a4ec5503",
"url": "https://llvm.googlesource.com/llvm-project/+/e5aa7999bf1bc9a6c7a03bbc65036325a4ec5503"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "e5aa7999bf1bc9a6c7a03bbc65036325a4ec5503",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8908273708828767584"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:33:04.566] Scheduled build:
{
"id": "8742843646879804337",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-07-10T00:33:03.823618937Z",
"updateTime": "2024-07-10T00:33:03.823618937Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "e5aa7999bf1bc9a6c7a03bbc65036325a4ec5503",
"ref": "refs/heads/main"
}
}
}
[00:33:04.566] Task URL: https://cr-buildbucket.appspot.com/build/8742843646879804337
[00:33:04.566] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:2:0) after 6m53s
[00:33:05.399] Received PubSub notification, asking Buildbucket for the build status
[00:33:05.419] Build status: SCHEDULED
[00:36:10.176] Received PubSub notification, asking Buildbucket for the build status
[00:36:10.204] Build status: STARTED
[00:39:57.595] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:2:0)
[00:39:57.620] Build status: STARTED
[00:39:57.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:5:0) after 6m4s
[00:46:01.656] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:5:0)
[00:46:01.673] Build status: STARTED
[00:46:01.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:6:0) after 2m50s
[00:48:51.693] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:6:0)
[00:48:51.727] Build status: STARTED
[00:48:51.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:7:0) after 9m38s
[00:58:29.751] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:7:0)
[00:58:29.771] Build status: STARTED
[00:58:29.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:8:0) after 5m30s
[01:03:59.855] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:8:0)
[01:03:59.875] Build status: STARTED
[01:03:59.875] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:9:0) after 5m36s
[01:09:35.866] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:9:0)
[01:09:35.904] Build status: STARTED
[01:09:35.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:10:0) after 9m44s
[01:19:19.983] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:10:0)
[01:19:20.127] Build status: STARTED
[01:19:20.127] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:11:0) after 3m9s
[01:22:29.236] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:11:0)
[01:22:29.301] Build status: STARTED
[01:22:29.301] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:12:0) after 8m8s
[01:30:37.325] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:12:0)
[01:30:37.345] Build status: STARTED
[01:30:37.345] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:13:0) after 8m10s
[01:38:47.401] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:13:0)
[01:38:47.423] Build status: STARTED
[01:38:47.423] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:14:0) after 1m2s
[01:39:49.883] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:14:0)
[01:39:49.935] Build status: STARTED
[01:39:49.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:15:0) after 1m59s
[01:41:49.066] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:15:0)
[01:41:49.085] Build status: STARTED
[01:41:49.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:16:0) after 2m30s
[01:44:19.643] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:16:0)
[01:44:19.803] Build status: STARTED
[01:44:19.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:17:0) after 7m13s
[01:51:32.925] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:17:0)
[01:51:32.955] Build status: STARTED
[01:51:32.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:18:0) after 5m36s
[01:57:09.108] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:18:0)
[01:57:09.206] Build status: STARTED
[01:57:09.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8908273708828767584:19:0) after 5m58s
[02:01:35.344] Received PubSub notification, asking Buildbucket for the build status
[02:01:37.687] Build:
{
"id": "8742843646879804337",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-07-10T00:33:03.823618937Z",
"startTime": "2024-07-10T00:36:09.863263034Z",
"endTime": "2024-07-10T02:01:34.568825503Z",
"updateTime": "2024-07-10T02:01:34.568825503Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "e5aa7999bf1bc9a6c7a03bbc65036325a4ec5503",
"ref": "refs/heads/main"
}
}
}
[02:01:37.687] Invocation finished in 1h28m35.106426083s with status SUCCEEDED