[20:35:49.424] New invocation is queued and will start shortly
[20:35:50.679] Starting the invocation (attempt 1)
[20:35:50.714] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/8a300841006109926f9d1c80c8b805f55aae2714"
[20:35:50.714] Popped gitiles commit info from properties and tags
[20:35:50.714] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:35:50.714] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:35:50.715] Buildbucket request:
{
"requestId": "8906929678897136256",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-kasan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8906929678897136256",
"job": "fuchsia/core.arm64-kasan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "85c78eb21b95079c4156664bf985aff33dbb3a4a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@85c78eb21b95079c4156664bf985aff33dbb3a4a",
"title": "85c78eb21b95079c4156664bf985aff33dbb3a4a",
"url": "https://fuchsia.googlesource.com/integration/+/85c78eb21b95079c4156664bf985aff33dbb3a4a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "52ff3992e4c81b1c33699cf87d9d2ed5a6da193f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@52ff3992e4c81b1c33699cf87d9d2ed5a6da193f",
"title": "52ff3992e4c81b1c33699cf87d9d2ed5a6da193f",
"url": "https://fuchsia.googlesource.com/integration/+/52ff3992e4c81b1c33699cf87d9d2ed5a6da193f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a20164d2c455d66124cdc9cfb97a6fde32ca70a0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a20164d2c455d66124cdc9cfb97a6fde32ca70a0",
"title": "a20164d2c455d66124cdc9cfb97a6fde32ca70a0",
"url": "https://fuchsia.googlesource.com/integration/+/a20164d2c455d66124cdc9cfb97a6fde32ca70a0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7e4e2698ac3d51709672389229cb9d459018d375"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7e4e2698ac3d51709672389229cb9d459018d375",
"title": "7e4e2698ac3d51709672389229cb9d459018d375",
"url": "https://fuchsia.googlesource.com/integration/+/7e4e2698ac3d51709672389229cb9d459018d375"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bdde165345346f8ce359e48312149230a51882f0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bdde165345346f8ce359e48312149230a51882f0",
"title": "bdde165345346f8ce359e48312149230a51882f0",
"url": "https://fuchsia.googlesource.com/integration/+/bdde165345346f8ce359e48312149230a51882f0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fdf26a2735b9b6bf0a29631314e018838234b73d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fdf26a2735b9b6bf0a29631314e018838234b73d",
"title": "fdf26a2735b9b6bf0a29631314e018838234b73d",
"url": "https://fuchsia.googlesource.com/integration/+/fdf26a2735b9b6bf0a29631314e018838234b73d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e89f1e7740e4244fdb1a2bf337823802636a15dd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e89f1e7740e4244fdb1a2bf337823802636a15dd",
"title": "e89f1e7740e4244fdb1a2bf337823802636a15dd",
"url": "https://fuchsia.googlesource.com/integration/+/e89f1e7740e4244fdb1a2bf337823802636a15dd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e2b2904b45bb97df57261c837483c4f9f4224e96"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e2b2904b45bb97df57261c837483c4f9f4224e96",
"title": "e2b2904b45bb97df57261c837483c4f9f4224e96",
"url": "https://fuchsia.googlesource.com/integration/+/e2b2904b45bb97df57261c837483c4f9f4224e96"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0b4b0780882cd2573b7b3608fd76388a651a1788"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0b4b0780882cd2573b7b3608fd76388a651a1788",
"title": "0b4b0780882cd2573b7b3608fd76388a651a1788",
"url": "https://fuchsia.googlesource.com/integration/+/0b4b0780882cd2573b7b3608fd76388a651a1788"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d4ec64f10ea092dbbd071bbc3ffaa774e131c369"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d4ec64f10ea092dbbd071bbc3ffaa774e131c369",
"title": "d4ec64f10ea092dbbd071bbc3ffaa774e131c369",
"url": "https://fuchsia.googlesource.com/integration/+/d4ec64f10ea092dbbd071bbc3ffaa774e131c369"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cccd77ee699b9961ad8ce936d1748bc3d107faac"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cccd77ee699b9961ad8ce936d1748bc3d107faac",
"title": "cccd77ee699b9961ad8ce936d1748bc3d107faac",
"url": "https://fuchsia.googlesource.com/integration/+/cccd77ee699b9961ad8ce936d1748bc3d107faac"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8334db2e775c071b84234efb254a4f094d83a149"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8334db2e775c071b84234efb254a4f094d83a149",
"title": "8334db2e775c071b84234efb254a4f094d83a149",
"url": "https://fuchsia.googlesource.com/integration/+/8334db2e775c071b84234efb254a4f094d83a149"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7f8f4e4bb5d244f4acf1f78018958018b68fdd20"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7f8f4e4bb5d244f4acf1f78018958018b68fdd20",
"title": "7f8f4e4bb5d244f4acf1f78018958018b68fdd20",
"url": "https://fuchsia.googlesource.com/integration/+/7f8f4e4bb5d244f4acf1f78018958018b68fdd20"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f0446d6599ef779f737530d72c568faf1266182a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f0446d6599ef779f737530d72c568faf1266182a",
"title": "f0446d6599ef779f737530d72c568faf1266182a",
"url": "https://fuchsia.googlesource.com/integration/+/f0446d6599ef779f737530d72c568faf1266182a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8a300841006109926f9d1c80c8b805f55aae2714"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8a300841006109926f9d1c80c8b805f55aae2714",
"title": "8a300841006109926f9d1c80c8b805f55aae2714",
"url": "https://fuchsia.googlesource.com/integration/+/8a300841006109926f9d1c80c8b805f55aae2714"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8a300841006109926f9d1c80c8b805f55aae2714",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8906929678897136256"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.arm64-kasan-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:35:51.168] Scheduled build:
{
"id": "8741499616819137569",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-kasan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-07-24T20:35:50.772845574Z",
"updateTime": "2024-07-24T20:35:50.772845574Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8a300841006109926f9d1c80c8b805f55aae2714",
"ref": "refs/heads/main"
}
}
}
[20:35:51.168] Task URL: https://cr-buildbucket.appspot.com/build/8741499616819137569
[20:35:51.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:2:0) after 1m26s
[20:36:21.663] Received PubSub notification, asking Buildbucket for the build status
[20:36:21.697] Build status: STARTED
[20:36:28.438] Received PubSub notification, asking Buildbucket for the build status
[20:36:28.469] Build status: STARTED
[20:37:17.328] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:2:0)
[20:37:17.430] Build status: STARTED
[20:37:17.430] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:5:0) after 3m1s
[20:40:18.457] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:5:0)
[20:40:18.483] Build status: STARTED
[20:40:18.483] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:6:0) after 3m56s
[20:44:14.557] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:6:0)
[20:44:14.576] Build status: STARTED
[20:44:14.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:7:0) after 8m47s
[20:53:01.642] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:7:0)
[20:53:01.670] Build status: STARTED
[20:53:01.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:8:0) after 8m33s
[21:01:34.792] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:8:0)
[21:01:34.852] Build status: STARTED
[21:01:34.852] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:9:0) after 5m34s
[21:07:08.976] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:9:0)
[21:07:08.994] Build status: STARTED
[21:07:08.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:10:0) after 8m58s
[21:16:07.036] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:10:0)
[21:16:07.056] Build status: STARTED
[21:16:07.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:11:0) after 5m6s
[21:21:13.073] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:11:0)
[21:21:13.105] Build status: STARTED
[21:21:13.105] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:12:0) after 1m33s
[21:22:46.142] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:12:0)
[21:22:46.157] Build status: STARTED
[21:22:46.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:13:0) after 4m40s
[21:27:26.231] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:13:0)
[21:27:26.252] Build status: STARTED
[21:27:26.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:14:0) after 5m4s
[21:32:30.386] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:14:0)
[21:32:30.409] Build status: STARTED
[21:32:30.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:15:0) after 6m36s
[21:39:06.575] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:15:0)
[21:39:06.806] Build status: STARTED
[21:39:06.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:16:0) after 6m2s
[21:45:08.827] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:16:0)
[21:45:08.900] Build status: STARTED
[21:45:08.900] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:17:0) after 7m50s
[21:52:58.943] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:17:0)
[21:52:58.970] Build status: STARTED
[21:52:58.970] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:18:0) after 7m44s
[22:00:43.003] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:18:0)
[22:00:43.027] Build status: STARTED
[22:00:43.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:19:0) after 4m28s
[22:05:11.170] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:19:0)
[22:05:11.189] Build status: STARTED
[22:05:11.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:20:0) after 9m29s
[22:14:40.210] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:20:0)
[22:14:40.235] Build status: STARTED
[22:14:40.235] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:21:0) after 5m53s
[22:20:33.394] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:21:0)
[22:20:33.415] Build status: STARTED
[22:20:33.415] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8906929678897136256:22:0) after 6m55s
[22:26:02.840] Received PubSub notification, asking Buildbucket for the build status
[22:26:02.861] Build:
{
"id": "8741499616819137569",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-kasan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-07-24T20:35:50.772845574Z",
"startTime": "2024-07-24T20:35:59.798141085Z",
"endTime": "2024-07-24T22:26:02.214573505Z",
"updateTime": "2024-07-24T22:26:02.214573505Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8a300841006109926f9d1c80c8b805f55aae2714",
"ref": "refs/heads/main"
}
}
}
[22:26:02.861] Invocation finished in 1h50m13.454542441s with status SUCCEEDED