[03:24:20.068] New invocation is queued and will start shortly
[03:24:21.137] Starting the invocation (attempt 1)
[03:24:21.164] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/8f8bd5773a8ac0a53da4f1c1337158e51a9cdec4"
[03:24:21.164] Popped gitiles commit info from properties and tags
[03:24:21.164] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:24:21.164] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:24:21.164] Buildbucket request:
{
"requestId": "8888693987371780160",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "minimal.riscv64-coverage"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8888693987371780160",
"job": "fuchsia/minimal.riscv64-coverage-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b0e01b5e6edaa9c28df16308d3b1e3dfe71b520e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b0e01b5e6edaa9c28df16308d3b1e3dfe71b520e",
"title": "b0e01b5e6edaa9c28df16308d3b1e3dfe71b520e",
"url": "https://fuchsia.googlesource.com/integration/+/b0e01b5e6edaa9c28df16308d3b1e3dfe71b520e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "70bf28675a18683dfc24d1a9f39fce26a35451de"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@70bf28675a18683dfc24d1a9f39fce26a35451de",
"title": "70bf28675a18683dfc24d1a9f39fce26a35451de",
"url": "https://fuchsia.googlesource.com/integration/+/70bf28675a18683dfc24d1a9f39fce26a35451de"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d9264afaf0a47cd7d0d44e9d96f65fc0d657214d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d9264afaf0a47cd7d0d44e9d96f65fc0d657214d",
"title": "d9264afaf0a47cd7d0d44e9d96f65fc0d657214d",
"url": "https://fuchsia.googlesource.com/integration/+/d9264afaf0a47cd7d0d44e9d96f65fc0d657214d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "314478997857b5271832bbb3c08dc6db89288005"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@314478997857b5271832bbb3c08dc6db89288005",
"title": "314478997857b5271832bbb3c08dc6db89288005",
"url": "https://fuchsia.googlesource.com/integration/+/314478997857b5271832bbb3c08dc6db89288005"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0ab4ed5ad26e91ef8fc57821af6ce4199e32c0ba"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0ab4ed5ad26e91ef8fc57821af6ce4199e32c0ba",
"title": "0ab4ed5ad26e91ef8fc57821af6ce4199e32c0ba",
"url": "https://fuchsia.googlesource.com/integration/+/0ab4ed5ad26e91ef8fc57821af6ce4199e32c0ba"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "344e75953ea8a82b436d06b1de94c2c02b157b29"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@344e75953ea8a82b436d06b1de94c2c02b157b29",
"title": "344e75953ea8a82b436d06b1de94c2c02b157b29",
"url": "https://fuchsia.googlesource.com/integration/+/344e75953ea8a82b436d06b1de94c2c02b157b29"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4c4949bea34e3927f4e9476abcaf7a95b2b8c1c3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4c4949bea34e3927f4e9476abcaf7a95b2b8c1c3",
"title": "4c4949bea34e3927f4e9476abcaf7a95b2b8c1c3",
"url": "https://fuchsia.googlesource.com/integration/+/4c4949bea34e3927f4e9476abcaf7a95b2b8c1c3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fb5908056f7558f3475180ab2a36309970b25347"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fb5908056f7558f3475180ab2a36309970b25347",
"title": "fb5908056f7558f3475180ab2a36309970b25347",
"url": "https://fuchsia.googlesource.com/integration/+/fb5908056f7558f3475180ab2a36309970b25347"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9bf6a3b34b1983897316357b389fd709a7269d70"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9bf6a3b34b1983897316357b389fd709a7269d70",
"title": "9bf6a3b34b1983897316357b389fd709a7269d70",
"url": "https://fuchsia.googlesource.com/integration/+/9bf6a3b34b1983897316357b389fd709a7269d70"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c9f0533d6dc7f697400b600e5bacf2444c5916ed"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c9f0533d6dc7f697400b600e5bacf2444c5916ed",
"title": "c9f0533d6dc7f697400b600e5bacf2444c5916ed",
"url": "https://fuchsia.googlesource.com/integration/+/c9f0533d6dc7f697400b600e5bacf2444c5916ed"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "89431a45be0299515b59735b9078105c9aab0b5d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@89431a45be0299515b59735b9078105c9aab0b5d",
"title": "89431a45be0299515b59735b9078105c9aab0b5d",
"url": "https://fuchsia.googlesource.com/integration/+/89431a45be0299515b59735b9078105c9aab0b5d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8f8bd5773a8ac0a53da4f1c1337158e51a9cdec4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8f8bd5773a8ac0a53da4f1c1337158e51a9cdec4",
"title": "8f8bd5773a8ac0a53da4f1c1337158e51a9cdec4",
"url": "https://fuchsia.googlesource.com/integration/+/8f8bd5773a8ac0a53da4f1c1337158e51a9cdec4"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8f8bd5773a8ac0a53da4f1c1337158e51a9cdec4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8888693987371780160"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/minimal.riscv64-coverage-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:24:21.525] Scheduled build:
{
"id": "8723263925485993057",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "minimal.riscv64-coverage"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-11T03:24:21.238361277Z",
"updateTime": "2025-02-11T03:24:21.238361277Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8f8bd5773a8ac0a53da4f1c1337158e51a9cdec4",
"ref": "refs/heads/main"
}
}
}
[03:24:21.525] Task URL: https://cr-buildbucket.appspot.com/build/8723263925485993057
[03:24:21.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:2:0) after 6m51s
[03:24:22.506] Received PubSub notification, asking Buildbucket for the build status
[03:24:22.528] Build status: SCHEDULED
[03:24:26.821] Received PubSub notification, asking Buildbucket for the build status
[03:24:26.838] Build status: STARTED
[03:31:12.549] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:2:0)
[03:31:12.574] Build status: STARTED
[03:31:12.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:5:0) after 8m7s
[03:39:19.695] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:5:0)
[03:39:19.725] Build status: STARTED
[03:39:19.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:6:0) after 1m2s
[03:40:21.807] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:6:0)
[03:40:21.834] Build status: STARTED
[03:40:21.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:7:0) after 2m38s
[03:42:59.850] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:7:0)
[03:42:59.879] Build status: STARTED
[03:42:59.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:8:0) after 6m10s
[03:49:09.964] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:8:0)
[03:49:10.031] Build status: STARTED
[03:49:10.031] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:9:0) after 4m13s
[03:53:23.089] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:9:0)
[03:53:23.115] Build status: STARTED
[03:53:23.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:10:0) after 6m58s
[04:00:21.137] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:10:0)
[04:00:21.163] Build status: STARTED
[04:00:21.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:11:0) after 3m49s
[04:04:10.185] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:11:0)
[04:04:10.983] Build status: STARTED
[04:04:10.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:12:0) after 9m35s
[04:13:46.006] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:12:0)
[04:13:46.203] Build status: STARTED
[04:13:46.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:13:0) after 3m57s
[04:17:43.222] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:13:0)
[04:17:43.239] Build status: STARTED
[04:17:43.240] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:14:0) after 1m34s
[04:19:17.316] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:14:0)
[04:19:17.347] Build status: STARTED
[04:19:17.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:15:0) after 3m29s
[04:22:46.365] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:15:0)
[04:22:46.388] Build status: STARTED
[04:22:46.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:16:0) after 4m27s
[04:27:13.613] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:16:0)
[04:27:13.649] Build status: STARTED
[04:27:13.649] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:17:0) after 3m59s
[04:31:12.689] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:17:0)
[04:31:12.706] Build status: STARTED
[04:31:12.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:18:0) after 9m1s
[04:40:13.929] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:18:0)
[04:40:13.955] Build status: STARTED
[04:40:13.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:19:0) after 7m48s
[04:48:02.152] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:19:0)
[04:48:02.187] Build status: STARTED
[04:48:02.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:20:0) after 7m29s
[04:55:31.303] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:20:0)
[04:55:31.321] Build status: STARTED
[04:55:31.321] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:21:0) after 7m48s
[05:03:19.349] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:21:0)
[05:03:19.365] Build status: STARTED
[05:03:19.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:22:0) after 3m43s
[05:07:02.364] Handling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:22:0)
[05:07:02.517] Build status: STARTED
[05:07:02.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/minimal.riscv64-coverage-e5a0ec7f:8888693987371780160:23:0) after 9m34s
[05:08:19.583] Received PubSub notification, asking Buildbucket for the build status
[05:08:19.648] Build:
{
"id": "8723263925485993057",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "minimal.riscv64-coverage"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-11T03:24:21.238361277Z",
"startTime": "2025-02-11T03:24:26.641946418Z",
"endTime": "2025-02-11T05:08:18.973803319Z",
"updateTime": "2025-02-11T05:08:18.973803319Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8f8bd5773a8ac0a53da4f1c1337158e51a9cdec4",
"ref": "refs/heads/main"
}
}
}
[05:08:19.648] Invocation finished in 1h43m59.592713956s with status SUCCEEDED