[19:01:49.622] New invocation is queued and will start shortly
[19:01:50.975] Starting the invocation (attempt 1)
[19:01:51.001] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/cc9e3940277d001a027de297790e3f4927e7aa89"
[19:01:51.001] Popped gitiles commit info from properties and tags
[19:01:51.001] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:01:51.003] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:01:51.003] Buildbucket request:
{
"requestId": "8910197083445780496",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8910197083445780496",
"job": "fuchsia/core.arm64-asan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "78860c55ac4f59a99180a953c391c3d26c60aaf4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@78860c55ac4f59a99180a953c391c3d26c60aaf4",
"title": "78860c55ac4f59a99180a953c391c3d26c60aaf4",
"url": "https://fuchsia.googlesource.com/integration/+/78860c55ac4f59a99180a953c391c3d26c60aaf4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "618fc5eb6953532a4c8d8dd491e455155615c5ce"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@618fc5eb6953532a4c8d8dd491e455155615c5ce",
"title": "618fc5eb6953532a4c8d8dd491e455155615c5ce",
"url": "https://fuchsia.googlesource.com/integration/+/618fc5eb6953532a4c8d8dd491e455155615c5ce"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "73e02af90cb1300b31027ca8fec63bd10d5d110e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@73e02af90cb1300b31027ca8fec63bd10d5d110e",
"title": "73e02af90cb1300b31027ca8fec63bd10d5d110e",
"url": "https://fuchsia.googlesource.com/integration/+/73e02af90cb1300b31027ca8fec63bd10d5d110e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b605813e435845ce3a0580e1a6570ee53d9dd6ac"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b605813e435845ce3a0580e1a6570ee53d9dd6ac",
"title": "b605813e435845ce3a0580e1a6570ee53d9dd6ac",
"url": "https://fuchsia.googlesource.com/integration/+/b605813e435845ce3a0580e1a6570ee53d9dd6ac"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e1a2f21925ba90c4be4abcdc2f57202fa8436487"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e1a2f21925ba90c4be4abcdc2f57202fa8436487",
"title": "e1a2f21925ba90c4be4abcdc2f57202fa8436487",
"url": "https://fuchsia.googlesource.com/integration/+/e1a2f21925ba90c4be4abcdc2f57202fa8436487"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8c142ec4b65ed99e4601ca3355ca72282f8cd010"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8c142ec4b65ed99e4601ca3355ca72282f8cd010",
"title": "8c142ec4b65ed99e4601ca3355ca72282f8cd010",
"url": "https://fuchsia.googlesource.com/integration/+/8c142ec4b65ed99e4601ca3355ca72282f8cd010"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2ba83a851d6e478950acde4424a30ad34a067099"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2ba83a851d6e478950acde4424a30ad34a067099",
"title": "2ba83a851d6e478950acde4424a30ad34a067099",
"url": "https://fuchsia.googlesource.com/integration/+/2ba83a851d6e478950acde4424a30ad34a067099"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "37719cfc17404f71a526b5057696e3b430c57dff"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@37719cfc17404f71a526b5057696e3b430c57dff",
"title": "37719cfc17404f71a526b5057696e3b430c57dff",
"url": "https://fuchsia.googlesource.com/integration/+/37719cfc17404f71a526b5057696e3b430c57dff"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2b37726181eef1ecf67c17f90a4de24829e53136"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2b37726181eef1ecf67c17f90a4de24829e53136",
"title": "2b37726181eef1ecf67c17f90a4de24829e53136",
"url": "https://fuchsia.googlesource.com/integration/+/2b37726181eef1ecf67c17f90a4de24829e53136"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cc9e3940277d001a027de297790e3f4927e7aa89"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cc9e3940277d001a027de297790e3f4927e7aa89",
"title": "cc9e3940277d001a027de297790e3f4927e7aa89",
"url": "https://fuchsia.googlesource.com/integration/+/cc9e3940277d001a027de297790e3f4927e7aa89"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "cc9e3940277d001a027de297790e3f4927e7aa89",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8910197083445780496"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.arm64-asan-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:01:51.625] Scheduled build:
{
"id": "8744767021270710369",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-06-18T19:01:51.065258754Z",
"updateTime": "2024-06-18T19:01:51.065258754Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "cc9e3940277d001a027de297790e3f4927e7aa89",
"ref": "refs/heads/main"
}
}
}
[19:01:51.625] Task URL: https://cr-buildbucket.appspot.com/build/8744767021270710369
[19:01:51.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:2:0) after 2m38s
[19:01:52.629] Received PubSub notification, asking Buildbucket for the build status
[19:01:52.719] Build status: SCHEDULED
[19:01:57.194] Received PubSub notification, asking Buildbucket for the build status
[19:01:57.223] Build status: STARTED
[19:04:29.644] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:2:0)
[19:04:29.665] Build status: STARTED
[19:04:29.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:5:0) after 1m52s
[19:06:21.691] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:5:0)
[19:06:21.770] Build status: STARTED
[19:06:21.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:6:0) after 4m56s
[19:11:17.792] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:6:0)
[19:11:17.910] Build status: STARTED
[19:11:17.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:7:0) after 4m39s
[19:15:56.931] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:7:0)
[19:15:56.947] Build status: STARTED
[19:15:56.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:8:0) after 7m28s
[19:23:25.002] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:8:0)
[19:23:25.027] Build status: STARTED
[19:23:25.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:9:0) after 9m7s
[19:32:32.110] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:9:0)
[19:32:32.130] Build status: STARTED
[19:32:32.130] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:10:0) after 1m48s
[19:34:20.122] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:10:0)
[19:34:20.141] Build status: STARTED
[19:34:20.141] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:11:0) after 4m11s
[19:38:31.137] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:11:0)
[19:38:31.155] Build status: STARTED
[19:38:31.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:12:0) after 9m48s
[19:48:19.227] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:12:0)
[19:48:19.247] Build status: STARTED
[19:48:19.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:13:0) after 7m3s
[19:55:22.337] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:13:0)
[19:55:22.356] Build status: STARTED
[19:55:22.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:14:0) after 5m2s
[20:00:24.376] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:14:0)
[20:00:24.399] Build status: STARTED
[20:00:24.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:15:0) after 6m24s
[20:06:48.418] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:15:0)
[20:06:48.439] Build status: STARTED
[20:06:48.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:16:0) after 1m24s
[20:08:12.452] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:16:0)
[20:08:12.472] Build status: STARTED
[20:08:12.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:17:0) after 6m55s
[20:15:07.485] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:17:0)
[20:15:07.507] Build status: STARTED
[20:15:07.507] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:18:0) after 1m23s
[20:16:30.499] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:18:0)
[20:16:30.525] Build status: STARTED
[20:16:30.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8910197083445780496:19:0) after 7m23s
[20:17:57.097] Received PubSub notification, asking Buildbucket for the build status
[20:17:57.150] Build:
{
"id": "8744767021270710369",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-06-18T19:01:51.065258754Z",
"startTime": "2024-06-18T19:01:56.978392294Z",
"endTime": "2024-06-18T20:17:56.175600344Z",
"updateTime": "2024-06-18T20:17:56.175600344Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "cc9e3940277d001a027de297790e3f4927e7aa89",
"ref": "refs/heads/main"
}
}
}
[20:17:57.150] Invocation finished in 1h16m7.542928591s with status SUCCEEDED