[00:42:00.447] New invocation is queued and will start shortly
[00:42:01.554] Starting the invocation (attempt 1)
[00:42:01.585] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/224ab3de7f0ad8068ec7d5aeeb9eb2e1e154fae8"
[00:42:01.585] Popped gitiles commit info from properties and tags
[00:42:01.585] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:42:01.585] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:42:01.586] Buildbucket request:
{
"requestId": "8927207910824387712",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8927207910824387712",
"job": "fuchsia/core.arm64-asan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "51464164e301c458fcd16fe06eb29eb6fd2268f6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@51464164e301c458fcd16fe06eb29eb6fd2268f6",
"title": "51464164e301c458fcd16fe06eb29eb6fd2268f6",
"url": "https://fuchsia.googlesource.com/integration/+/51464164e301c458fcd16fe06eb29eb6fd2268f6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "64a76ba1b1af1331d5c30f41bfb602e5244d54be"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@64a76ba1b1af1331d5c30f41bfb602e5244d54be",
"title": "64a76ba1b1af1331d5c30f41bfb602e5244d54be",
"url": "https://fuchsia.googlesource.com/integration/+/64a76ba1b1af1331d5c30f41bfb602e5244d54be"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6cfc160ac44df822f518d34e7b8df6dc9e6f6463"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6cfc160ac44df822f518d34e7b8df6dc9e6f6463",
"title": "6cfc160ac44df822f518d34e7b8df6dc9e6f6463",
"url": "https://fuchsia.googlesource.com/integration/+/6cfc160ac44df822f518d34e7b8df6dc9e6f6463"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "39a5894a28d67e078d87f76a752c6a4861ecb28b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@39a5894a28d67e078d87f76a752c6a4861ecb28b",
"title": "39a5894a28d67e078d87f76a752c6a4861ecb28b",
"url": "https://fuchsia.googlesource.com/integration/+/39a5894a28d67e078d87f76a752c6a4861ecb28b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c96a8f1d3c5948a6eecb93287df73f1969da0b71"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c96a8f1d3c5948a6eecb93287df73f1969da0b71",
"title": "c96a8f1d3c5948a6eecb93287df73f1969da0b71",
"url": "https://fuchsia.googlesource.com/integration/+/c96a8f1d3c5948a6eecb93287df73f1969da0b71"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6e5ec07e50689e8e8ce4d65b1353456cc34f57f0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6e5ec07e50689e8e8ce4d65b1353456cc34f57f0",
"title": "6e5ec07e50689e8e8ce4d65b1353456cc34f57f0",
"url": "https://fuchsia.googlesource.com/integration/+/6e5ec07e50689e8e8ce4d65b1353456cc34f57f0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bb611748f973e83d306847c7e198f331de1a9898"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bb611748f973e83d306847c7e198f331de1a9898",
"title": "bb611748f973e83d306847c7e198f331de1a9898",
"url": "https://fuchsia.googlesource.com/integration/+/bb611748f973e83d306847c7e198f331de1a9898"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9e8828f844fb3506dfd1471c876ea609d527102b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9e8828f844fb3506dfd1471c876ea609d527102b",
"title": "9e8828f844fb3506dfd1471c876ea609d527102b",
"url": "https://fuchsia.googlesource.com/integration/+/9e8828f844fb3506dfd1471c876ea609d527102b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "16ab15c007d608236c5e631bf066ff3289daacb9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@16ab15c007d608236c5e631bf066ff3289daacb9",
"title": "16ab15c007d608236c5e631bf066ff3289daacb9",
"url": "https://fuchsia.googlesource.com/integration/+/16ab15c007d608236c5e631bf066ff3289daacb9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "224ab3de7f0ad8068ec7d5aeeb9eb2e1e154fae8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@224ab3de7f0ad8068ec7d5aeeb9eb2e1e154fae8",
"title": "224ab3de7f0ad8068ec7d5aeeb9eb2e1e154fae8",
"url": "https://fuchsia.googlesource.com/integration/+/224ab3de7f0ad8068ec7d5aeeb9eb2e1e154fae8"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "224ab3de7f0ad8068ec7d5aeeb9eb2e1e154fae8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8927207910824387712"
},
{
"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"
}
}
[00:42:02.213] Scheduled build:
{
"id": "8761777848853785441",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2023-12-14T00:42:01.698068316Z",
"updateTime": "2023-12-14T00:42:01.698068316Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "224ab3de7f0ad8068ec7d5aeeb9eb2e1e154fae8",
"ref": "refs/heads/main"
}
}
}
[00:42:02.213] Task URL: https://cr-buildbucket.appspot.com/build/8761777848853785441
[00:42:02.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:2:0) after 1m30s
[00:42:03.074] Received PubSub notification, asking Buildbucket for the build status
[00:42:03.107] Build status: SCHEDULED
[00:42:04.174] Received PubSub notification, asking Buildbucket for the build status
[00:42:04.231] Build status: STARTED
[00:43:32.323] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:2:0)
[00:43:32.344] Build status: STARTED
[00:43:32.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:5:0) after 5m15s
[00:48:47.413] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:5:0)
[00:48:47.479] Build status: STARTED
[00:48:47.479] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:6:0) after 2m33s
[00:51:20.716] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:6:0)
[00:51:20.759] Build status: STARTED
[00:51:20.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:7:0) after 2m23s
[00:53:43.892] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:7:0)
[00:53:43.915] Build status: STARTED
[00:53:43.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:8:0) after 7m0s
[01:00:44.145] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:8:0)
[01:00:44.227] Build status: STARTED
[01:00:44.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:9:0) after 1m8s
[01:01:52.340] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:9:0)
[01:01:52.376] Build status: STARTED
[01:01:52.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:10:0) after 7m27s
[01:09:19.498] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:10:0)
[01:09:19.518] Build status: STARTED
[01:09:19.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:11:0) after 3m36s
[01:12:55.536] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:11:0)
[01:12:55.564] Build status: STARTED
[01:12:55.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:12:0) after 5m14s
[01:18:09.653] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:12:0)
[01:18:09.676] Build status: STARTED
[01:18:09.676] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:13:0) after 2m31s
[01:20:40.867] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:13:0)
[01:20:40.895] Build status: STARTED
[01:20:40.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:14:0) after 9m37s
[01:30:18.328] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:14:0)
[01:30:18.358] Build status: STARTED
[01:30:18.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:15:0) after 2m3s
[01:32:21.580] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:15:0)
[01:32:21.600] Build status: STARTED
[01:32:21.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:16:0) after 4m50s
[01:37:11.927] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:16:0)
[01:37:11.998] Build status: STARTED
[01:37:11.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:17:0) after 8m24s
[01:45:36.056] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:17:0)
[01:45:36.126] Build status: STARTED
[01:45:36.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:18:0) after 7m58s
[01:53:34.161] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:18:0)
[01:53:34.206] Build status: STARTED
[01:53:34.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8927207910824387712:19:0) after 6m21s
[01:59:16.171] Received PubSub notification, asking Buildbucket for the build status
[01:59:16.200] Build:
{
"id": "8761777848853785441",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2023-12-14T00:42:01.698068316Z",
"startTime": "2023-12-14T00:42:03.370762Z",
"endTime": "2023-12-14T01:59:15.163797Z",
"updateTime": "2023-12-14T01:59:15.163797Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "224ab3de7f0ad8068ec7d5aeeb9eb2e1e154fae8",
"ref": "refs/heads/main"
}
}
}
[01:59:16.200] Invocation finished in 1h17m15.764228411s with status SUCCEEDED