[22:59:01.227] New invocation is queued and will start shortly
[22:59:02.414] Starting the invocation (attempt 1)
[22:59:02.450] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/630d91e828b1e9f11005d130468866a3122c6065"
[22:59:02.450] Popped gitiles commit info from properties and tags
[22:59:02.450] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:59:02.455] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:59:02.459] Buildbucket request:
{
"requestId": "8921416184355644688",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8921416184355644688",
"job": "fuchsia/core.arm64-asan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fcab68db9f146bcc700ba29dc195ffc878f6c98e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fcab68db9f146bcc700ba29dc195ffc878f6c98e",
"title": "fcab68db9f146bcc700ba29dc195ffc878f6c98e",
"url": "https://fuchsia.googlesource.com/integration/+/fcab68db9f146bcc700ba29dc195ffc878f6c98e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e3e02de9c867056fab42e21229d5f87c73c8700a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e3e02de9c867056fab42e21229d5f87c73c8700a",
"title": "e3e02de9c867056fab42e21229d5f87c73c8700a",
"url": "https://fuchsia.googlesource.com/integration/+/e3e02de9c867056fab42e21229d5f87c73c8700a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a89e2157748cacd53bc4adda3840d191d661c608"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a89e2157748cacd53bc4adda3840d191d661c608",
"title": "a89e2157748cacd53bc4adda3840d191d661c608",
"url": "https://fuchsia.googlesource.com/integration/+/a89e2157748cacd53bc4adda3840d191d661c608"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "340611bbcd40548693b0b8f41adf24360ae8d740"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@340611bbcd40548693b0b8f41adf24360ae8d740",
"title": "340611bbcd40548693b0b8f41adf24360ae8d740",
"url": "https://fuchsia.googlesource.com/integration/+/340611bbcd40548693b0b8f41adf24360ae8d740"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c8dd731b88b82ae4c71a462b5ba7ce45dbe39aaa"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c8dd731b88b82ae4c71a462b5ba7ce45dbe39aaa",
"title": "c8dd731b88b82ae4c71a462b5ba7ce45dbe39aaa",
"url": "https://fuchsia.googlesource.com/integration/+/c8dd731b88b82ae4c71a462b5ba7ce45dbe39aaa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c13e6476d1ad3dd269ce4d02b55051550e73de31"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c13e6476d1ad3dd269ce4d02b55051550e73de31",
"title": "c13e6476d1ad3dd269ce4d02b55051550e73de31",
"url": "https://fuchsia.googlesource.com/integration/+/c13e6476d1ad3dd269ce4d02b55051550e73de31"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a2aabc604ae835c09f8ff7e29e22c2862776cd87"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a2aabc604ae835c09f8ff7e29e22c2862776cd87",
"title": "a2aabc604ae835c09f8ff7e29e22c2862776cd87",
"url": "https://fuchsia.googlesource.com/integration/+/a2aabc604ae835c09f8ff7e29e22c2862776cd87"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b819f1d9f96cdda833a2e482b234540a7c2481b7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b819f1d9f96cdda833a2e482b234540a7c2481b7",
"title": "b819f1d9f96cdda833a2e482b234540a7c2481b7",
"url": "https://fuchsia.googlesource.com/integration/+/b819f1d9f96cdda833a2e482b234540a7c2481b7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "974eeed189c65ca5511d8abc874d7b43bfa0c243"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@974eeed189c65ca5511d8abc874d7b43bfa0c243",
"title": "974eeed189c65ca5511d8abc874d7b43bfa0c243",
"url": "https://fuchsia.googlesource.com/integration/+/974eeed189c65ca5511d8abc874d7b43bfa0c243"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d1b15ba8bc4f4a9ff795522fe90f2785e410ab7e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d1b15ba8bc4f4a9ff795522fe90f2785e410ab7e",
"title": "d1b15ba8bc4f4a9ff795522fe90f2785e410ab7e",
"url": "https://fuchsia.googlesource.com/integration/+/d1b15ba8bc4f4a9ff795522fe90f2785e410ab7e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "61e0a26c0f466aeab5d3fc450c2d70458445fc71"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@61e0a26c0f466aeab5d3fc450c2d70458445fc71",
"title": "61e0a26c0f466aeab5d3fc450c2d70458445fc71",
"url": "https://fuchsia.googlesource.com/integration/+/61e0a26c0f466aeab5d3fc450c2d70458445fc71"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9cc6daeb2d7e38bd1e44e8bad41ec67f13ec6db0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9cc6daeb2d7e38bd1e44e8bad41ec67f13ec6db0",
"title": "9cc6daeb2d7e38bd1e44e8bad41ec67f13ec6db0",
"url": "https://fuchsia.googlesource.com/integration/+/9cc6daeb2d7e38bd1e44e8bad41ec67f13ec6db0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8110eb2373aad3b8ad935bd75733d754f771540f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8110eb2373aad3b8ad935bd75733d754f771540f",
"title": "8110eb2373aad3b8ad935bd75733d754f771540f",
"url": "https://fuchsia.googlesource.com/integration/+/8110eb2373aad3b8ad935bd75733d754f771540f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c2fb0d12d74959354efe7a84b36a938e71977a45"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c2fb0d12d74959354efe7a84b36a938e71977a45",
"title": "c2fb0d12d74959354efe7a84b36a938e71977a45",
"url": "https://fuchsia.googlesource.com/integration/+/c2fb0d12d74959354efe7a84b36a938e71977a45"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "630d91e828b1e9f11005d130468866a3122c6065"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@630d91e828b1e9f11005d130468866a3122c6065",
"title": "630d91e828b1e9f11005d130468866a3122c6065",
"url": "https://fuchsia.googlesource.com/integration/+/630d91e828b1e9f11005d130468866a3122c6065"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "630d91e828b1e9f11005d130468866a3122c6065",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8921416184355644688"
},
{
"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"
}
}
[22:59:02.897] Scheduled build:
{
"id": "8755986122334830769",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-02-15T22:59:02.527064161Z",
"updateTime": "2024-02-15T22:59:02.527064161Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "630d91e828b1e9f11005d130468866a3122c6065",
"ref": "refs/heads/main"
}
},
"canary": true
}
[22:59:02.897] Task URL: https://cr-buildbucket.appspot.com/build/8755986122334830769
[22:59:02.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:2:0) after 8m34s
[22:59:03.007] Received PubSub notification, asking Buildbucket for the build status
[22:59:03.045] Build status: SCHEDULED
[23:00:02.620] Received PubSub notification, asking Buildbucket for the build status
[23:00:02.652] Build status: STARTED
[23:07:36.921] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:2:0)
[23:07:36.953] Build status: STARTED
[23:07:36.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:5:0) after 2m35s
[23:10:12.000] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:5:0)
[23:10:12.035] Build status: STARTED
[23:10:12.035] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:6:0) after 9m39s
[23:19:51.068] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:6:0)
[23:19:51.088] Build status: STARTED
[23:19:51.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:7:0) after 1m41s
[23:21:32.107] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:7:0)
[23:21:32.139] Build status: STARTED
[23:21:32.139] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:8:0) after 3m11s
[23:24:43.182] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:8:0)
[23:24:43.201] Build status: STARTED
[23:24:43.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:9:0) after 8m47s
[23:33:30.267] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:9:0)
[23:33:30.286] Build status: STARTED
[23:33:30.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:10:0) after 1m12s
[23:34:42.283] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:10:0)
[23:34:42.311] Build status: STARTED
[23:34:42.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:11:0) after 1m36s
[23:36:18.336] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:11:0)
[23:36:18.352] Build status: STARTED
[23:36:18.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:12:0) after 5m42s
[23:42:00.418] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:12:0)
[23:42:00.486] Build status: STARTED
[23:42:00.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:13:0) after 7m32s
[23:49:33.228] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:13:0)
[23:49:33.258] Build status: STARTED
[23:49:33.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:14:0) after 4m34s
[23:54:07.638] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:14:0)
[23:54:07.661] Build status: STARTED
[23:54:07.661] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:15:0) after 3m49s
[23:57:56.676] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:15:0)
[23:57:56.701] Build status: STARTED
[23:57:56.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:16:0) after 4m29s
[00:02:25.742] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:16:0)
[00:02:25.767] Build status: STARTED
[00:02:25.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:17:0) after 5m40s
[00:08:05.817] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:17:0)
[00:08:05.836] Build status: STARTED
[00:08:05.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:18:0) after 7m8s
[00:15:13.938] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:18:0)
[00:15:13.964] Build status: STARTED
[00:15:13.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:19:0) after 7m37s
[00:22:50.984] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:19:0)
[00:22:51.009] Build status: STARTED
[00:22:51.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921416184355644688:20:0) after 6m5s
[00:23:01.621] Received PubSub notification, asking Buildbucket for the build status
[00:23:01.688] Build:
{
"id": "8755986122334830769",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-02-15T22:59:02.527064161Z",
"startTime": "2024-02-15T23:00:01.980877Z",
"endTime": "2024-02-16T00:23:00.958329Z",
"updateTime": "2024-02-16T00:23:00.958329Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "630d91e828b1e9f11005d130468866a3122c6065",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:23:01.688] Invocation finished in 1h24m0.472402669s with status SUCCEEDED