[18:17:57.009] New invocation is queued and will start shortly
[18:17:58.365] Starting the invocation (attempt 1)
[18:17:58.391] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/ecfd954e641b8d1a0ad7bc9aeb893e64e03a6cb0"
[18:17:58.391] Popped gitiles commit info from properties and tags
[18:17:58.391] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:17:58.391] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:17:58.391] Buildbucket request:
{
"requestId": "8884560902462165360",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-thinlto"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8884560902462165360",
"job": "fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b655e0a9f98f0acd620f848cd59350a90c64ff2c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b655e0a9f98f0acd620f848cd59350a90c64ff2c",
"title": "b655e0a9f98f0acd620f848cd59350a90c64ff2c",
"url": "https://fuchsia.googlesource.com/integration/+/b655e0a9f98f0acd620f848cd59350a90c64ff2c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5a5fe306cc4349802204a51b6a4deea05ddfaecb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5a5fe306cc4349802204a51b6a4deea05ddfaecb",
"title": "5a5fe306cc4349802204a51b6a4deea05ddfaecb",
"url": "https://fuchsia.googlesource.com/integration/+/5a5fe306cc4349802204a51b6a4deea05ddfaecb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "87fe5ce1cd75bb77533c508fafc4d515f1a70889"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@87fe5ce1cd75bb77533c508fafc4d515f1a70889",
"title": "87fe5ce1cd75bb77533c508fafc4d515f1a70889",
"url": "https://fuchsia.googlesource.com/integration/+/87fe5ce1cd75bb77533c508fafc4d515f1a70889"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d93e3cf5c76ef107281dda926cb0d1d911d6d647"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d93e3cf5c76ef107281dda926cb0d1d911d6d647",
"title": "d93e3cf5c76ef107281dda926cb0d1d911d6d647",
"url": "https://fuchsia.googlesource.com/integration/+/d93e3cf5c76ef107281dda926cb0d1d911d6d647"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "44865ad45832e19e4cda7f31eaa18a06521176b3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@44865ad45832e19e4cda7f31eaa18a06521176b3",
"title": "44865ad45832e19e4cda7f31eaa18a06521176b3",
"url": "https://fuchsia.googlesource.com/integration/+/44865ad45832e19e4cda7f31eaa18a06521176b3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8115011e2e2654c7a71599a2230cf665822954e2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8115011e2e2654c7a71599a2230cf665822954e2",
"title": "8115011e2e2654c7a71599a2230cf665822954e2",
"url": "https://fuchsia.googlesource.com/integration/+/8115011e2e2654c7a71599a2230cf665822954e2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "781843dab8b34475670772da51dbccfe97254a2c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@781843dab8b34475670772da51dbccfe97254a2c",
"title": "781843dab8b34475670772da51dbccfe97254a2c",
"url": "https://fuchsia.googlesource.com/integration/+/781843dab8b34475670772da51dbccfe97254a2c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "de40f04acfdfafe045f841220179fdd3b43b8cb7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@de40f04acfdfafe045f841220179fdd3b43b8cb7",
"title": "de40f04acfdfafe045f841220179fdd3b43b8cb7",
"url": "https://fuchsia.googlesource.com/integration/+/de40f04acfdfafe045f841220179fdd3b43b8cb7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "738883b31ba2c7a59082aea6b8f9ae2fe69faa69"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@738883b31ba2c7a59082aea6b8f9ae2fe69faa69",
"title": "738883b31ba2c7a59082aea6b8f9ae2fe69faa69",
"url": "https://fuchsia.googlesource.com/integration/+/738883b31ba2c7a59082aea6b8f9ae2fe69faa69"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ecfd954e641b8d1a0ad7bc9aeb893e64e03a6cb0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ecfd954e641b8d1a0ad7bc9aeb893e64e03a6cb0",
"title": "ecfd954e641b8d1a0ad7bc9aeb893e64e03a6cb0",
"url": "https://fuchsia.googlesource.com/integration/+/ecfd954e641b8d1a0ad7bc9aeb893e64e03a6cb0"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ecfd954e641b8d1a0ad7bc9aeb893e64e03a6cb0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8884560902462165360"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:17:58.839] Scheduled build:
{
"id": "8719130840235334353",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-thinlto"
},
"createdBy": "project:fuchsia",
"createTime": "2025-03-28T18:17:58.489765630Z",
"updateTime": "2025-03-28T18:17:58.489765630Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ecfd954e641b8d1a0ad7bc9aeb893e64e03a6cb0",
"ref": "refs/heads/main"
}
}
}
[18:17:58.839] Task URL: https://cr-buildbucket.appspot.com/build/8719130840235334353
[18:17:58.839] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:2:0) after 5m2s
[18:17:59.840] Received PubSub notification, asking Buildbucket for the build status
[18:17:59.926] Build status: SCHEDULED
[18:18:03.990] Received PubSub notification, asking Buildbucket for the build status
[18:18:04.016] Build status: STARTED
[18:23:00.947] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:2:0)
[18:23:00.977] Build status: STARTED
[18:23:00.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:5:0) after 3m41s
[18:26:42.013] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:5:0)
[18:26:42.036] Build status: STARTED
[18:26:42.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:6:0) after 7m19s
[18:34:01.050] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:6:0)
[18:34:01.081] Build status: STARTED
[18:34:01.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:7:0) after 1m28s
[18:35:29.144] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:7:0)
[18:35:29.160] Build status: STARTED
[18:35:29.160] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:8:0) after 9m12s
[18:44:41.357] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:8:0)
[18:44:41.374] Build status: STARTED
[18:44:41.374] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:9:0) after 9m24s
[18:54:05.440] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:9:0)
[18:54:05.458] Build status: STARTED
[18:54:05.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:10:0) after 2m50s
[18:56:55.531] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:10:0)
[18:56:55.548] Build status: STARTED
[18:56:55.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:11:0) after 1m56s
[18:58:51.566] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:11:0)
[18:58:51.589] Build status: STARTED
[18:58:51.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:12:0) after 1m23s
[19:00:14.609] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:12:0)
[19:00:14.627] Build status: STARTED
[19:00:14.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:13:0) after 4m26s
[19:04:40.692] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:13:0)
[19:04:40.871] Build status: STARTED
[19:04:40.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:14:0) after 1m44s
[19:06:25.006] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:14:0)
[19:06:25.021] Build status: STARTED
[19:06:25.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:15:0) after 8m7s
[19:14:32.086] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:15:0)
[19:14:32.100] Build status: STARTED
[19:14:32.100] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:16:0) after 2m9s
[19:16:41.276] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:16:0)
[19:16:41.302] Build status: STARTED
[19:16:41.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:17:0) after 6m19s
[19:23:00.510] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:17:0)
[19:23:00.536] Build status: STARTED
[19:23:00.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:18:0) after 2m19s
[19:25:19.535] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:18:0)
[19:25:19.557] Build status: STARTED
[19:25:19.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884560902462165360:19:0) after 5m15s
[19:26:24.801] Received PubSub notification, asking Buildbucket for the build status
[19:26:24.901] Build:
{
"id": "8719130840235334353",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-thinlto"
},
"createdBy": "project:fuchsia",
"createTime": "2025-03-28T18:17:58.489765630Z",
"startTime": "2025-03-28T18:18:03.796255718Z",
"endTime": "2025-03-28T19:26:22.962904323Z",
"updateTime": "2025-03-28T19:26:22.962904323Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ecfd954e641b8d1a0ad7bc9aeb893e64e03a6cb0",
"ref": "refs/heads/main"
}
}
}
[19:26:24.901] Invocation finished in 1h8m27.919011292s with status SUCCEEDED