[23:39:19.007] New invocation is queued and will start shortly
[23:39:20.332] Starting the invocation (attempt 1)
[23:39:20.357] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/a5c6688929c58aa416460c4fb8f62e0967fa8085"
[23:39:20.357] Popped gitiles commit info from properties and tags
[23:39:20.357] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:39:20.357] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:39:20.357] Buildbucket request:
{
"requestId": "8921504246096881328",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8921504246096881328",
"job": "fuchsia/core.arm64-asan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b12c2d568875262435c886e93fae0715044872f6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b12c2d568875262435c886e93fae0715044872f6",
"title": "b12c2d568875262435c886e93fae0715044872f6",
"url": "https://fuchsia.googlesource.com/integration/+/b12c2d568875262435c886e93fae0715044872f6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "17f61d750c78a5202acf98e0568bca4212972e5d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@17f61d750c78a5202acf98e0568bca4212972e5d",
"title": "17f61d750c78a5202acf98e0568bca4212972e5d",
"url": "https://fuchsia.googlesource.com/integration/+/17f61d750c78a5202acf98e0568bca4212972e5d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ab8dc0b6b588d8937ecafca106a854b7192c646c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ab8dc0b6b588d8937ecafca106a854b7192c646c",
"title": "ab8dc0b6b588d8937ecafca106a854b7192c646c",
"url": "https://fuchsia.googlesource.com/integration/+/ab8dc0b6b588d8937ecafca106a854b7192c646c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2906744bbc5aea7fe139fd3764143140f7d12de5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2906744bbc5aea7fe139fd3764143140f7d12de5",
"title": "2906744bbc5aea7fe139fd3764143140f7d12de5",
"url": "https://fuchsia.googlesource.com/integration/+/2906744bbc5aea7fe139fd3764143140f7d12de5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2af9a9a300e2443760d62d1fbc87cab8ca56c459"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2af9a9a300e2443760d62d1fbc87cab8ca56c459",
"title": "2af9a9a300e2443760d62d1fbc87cab8ca56c459",
"url": "https://fuchsia.googlesource.com/integration/+/2af9a9a300e2443760d62d1fbc87cab8ca56c459"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c1da5290abb53ee0a039355a1c3d0a60feed13b1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c1da5290abb53ee0a039355a1c3d0a60feed13b1",
"title": "c1da5290abb53ee0a039355a1c3d0a60feed13b1",
"url": "https://fuchsia.googlesource.com/integration/+/c1da5290abb53ee0a039355a1c3d0a60feed13b1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0f9c7f39363c6e974aff3fcab91458bfa1ee6ce9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0f9c7f39363c6e974aff3fcab91458bfa1ee6ce9",
"title": "0f9c7f39363c6e974aff3fcab91458bfa1ee6ce9",
"url": "https://fuchsia.googlesource.com/integration/+/0f9c7f39363c6e974aff3fcab91458bfa1ee6ce9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ca53dc1ad5bf4d4894725022c2de3b83526aae6d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ca53dc1ad5bf4d4894725022c2de3b83526aae6d",
"title": "ca53dc1ad5bf4d4894725022c2de3b83526aae6d",
"url": "https://fuchsia.googlesource.com/integration/+/ca53dc1ad5bf4d4894725022c2de3b83526aae6d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1a5893ca3ce92bf5e9b83e937abdee9fe5eb3c35"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1a5893ca3ce92bf5e9b83e937abdee9fe5eb3c35",
"title": "1a5893ca3ce92bf5e9b83e937abdee9fe5eb3c35",
"url": "https://fuchsia.googlesource.com/integration/+/1a5893ca3ce92bf5e9b83e937abdee9fe5eb3c35"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1d09fa05be3df672cdeb7feb3dd84ea39341e997"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1d09fa05be3df672cdeb7feb3dd84ea39341e997",
"title": "1d09fa05be3df672cdeb7feb3dd84ea39341e997",
"url": "https://fuchsia.googlesource.com/integration/+/1d09fa05be3df672cdeb7feb3dd84ea39341e997"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3f6a9235860926db355a28302a75eab3535bf711"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3f6a9235860926db355a28302a75eab3535bf711",
"title": "3f6a9235860926db355a28302a75eab3535bf711",
"url": "https://fuchsia.googlesource.com/integration/+/3f6a9235860926db355a28302a75eab3535bf711"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5af0de02801dcd20b4c84f36c9b7437eb9b53941"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5af0de02801dcd20b4c84f36c9b7437eb9b53941",
"title": "5af0de02801dcd20b4c84f36c9b7437eb9b53941",
"url": "https://fuchsia.googlesource.com/integration/+/5af0de02801dcd20b4c84f36c9b7437eb9b53941"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cba98d1e98b4d88f960d331e6deb6987361e7dbf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cba98d1e98b4d88f960d331e6deb6987361e7dbf",
"title": "cba98d1e98b4d88f960d331e6deb6987361e7dbf",
"url": "https://fuchsia.googlesource.com/integration/+/cba98d1e98b4d88f960d331e6deb6987361e7dbf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a5c6688929c58aa416460c4fb8f62e0967fa8085"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a5c6688929c58aa416460c4fb8f62e0967fa8085",
"title": "a5c6688929c58aa416460c4fb8f62e0967fa8085",
"url": "https://fuchsia.googlesource.com/integration/+/a5c6688929c58aa416460c4fb8f62e0967fa8085"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a5c6688929c58aa416460c4fb8f62e0967fa8085",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8921504246096881328"
},
{
"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"
}
}
[23:39:20.766] Scheduled build:
{
"id": "8756074183959777217",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-02-14T23:39:20.417755694Z",
"updateTime": "2024-02-14T23:39:20.417755694Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a5c6688929c58aa416460c4fb8f62e0967fa8085",
"ref": "refs/heads/main"
}
},
"canary": true
}
[23:39:20.766] Task URL: https://cr-buildbucket.appspot.com/build/8756074183959777217
[23:39:20.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:2:0) after 9m56s
[23:39:21.757] Received PubSub notification, asking Buildbucket for the build status
[23:39:21.774] Build status: SCHEDULED
[23:42:45.589] Received PubSub notification, asking Buildbucket for the build status
[23:42:45.612] Build status: STARTED
[23:49:17.035] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:2:0)
[23:49:17.183] Build status: STARTED
[23:49:17.183] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:5:0) after 8m41s
[23:57:58.210] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:5:0)
[23:57:58.238] Build status: STARTED
[23:57:58.238] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:6:0) after 7m27s
[00:05:25.283] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:6:0)
[00:05:25.336] Build status: STARTED
[00:05:25.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:7:0) after 5m22s
[00:10:47.405] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:7:0)
[00:10:47.428] Build status: STARTED
[00:10:47.428] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:8:0) after 7m22s
[00:18:09.448] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:8:0)
[00:18:09.466] Build status: STARTED
[00:18:09.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:9:0) after 6m2s
[00:24:11.445] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:9:0)
[00:24:11.467] Build status: STARTED
[00:24:11.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:10:0) after 4m46s
[00:28:58.227] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:10:0)
[00:28:58.299] Build status: STARTED
[00:28:58.299] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:11:0) after 8m17s
[00:37:15.754] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:11:0)
[00:37:15.786] Build status: STARTED
[00:37:15.787] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:12:0) after 7m13s
[00:44:28.799] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:12:0)
[00:44:28.830] Build status: STARTED
[00:44:28.830] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:13:0) after 2m26s
[00:46:54.847] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:13:0)
[00:46:54.868] Build status: STARTED
[00:46:54.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:14:0) after 4m35s
[00:51:29.908] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:14:0)
[00:51:29.942] Build status: STARTED
[00:51:29.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:15:0) after 5m50s
[00:57:19.965] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:15:0)
[00:57:19.991] Build status: STARTED
[00:57:19.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:16:0) after 2m43s
[01:00:03.003] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:16:0)
[01:00:03.033] Build status: STARTED
[01:00:03.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:17:0) after 5m32s
[01:05:35.079] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:17:0)
[01:05:35.113] Build status: STARTED
[01:05:35.113] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8921504246096881328:18:0) after 6m1s
[01:09:07.934] Received PubSub notification, asking Buildbucket for the build status
[01:09:07.953] Build:
{
"id": "8756074183959777217",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-02-14T23:39:20.417755694Z",
"startTime": "2024-02-14T23:42:42.804264Z",
"endTime": "2024-02-15T01:09:07.151392Z",
"updateTime": "2024-02-15T01:09:07.151392Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a5c6688929c58aa416460c4fb8f62e0967fa8085",
"ref": "refs/heads/main"
}
},
"canary": true
}
[01:09:07.953] Invocation finished in 1h29m48.957264363s with status SUCCEEDED