[04:39:44.067] New invocation is queued and will start shortly
[04:39:45.158] Starting the invocation (attempt 1)
[04:39:45.187] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/0e7ddd6b1fc1b88f284d33e7600dc6efdd991c3c"
[04:39:45.187] Popped gitiles commit info from properties and tags
[04:39:45.187] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:39:45.187] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:39:45.188] Buildbucket request:
{
"requestId": "8865134032354075952",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-coverage"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8865134032354075952",
"job": "fuchsia/global.ci-fuchsia-coverage-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a412924f809b33138ac12bd9fddbbc7f3f48187b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a412924f809b33138ac12bd9fddbbc7f3f48187b",
"title": "a412924f809b33138ac12bd9fddbbc7f3f48187b",
"url": "https://fuchsia.googlesource.com/integration/+/a412924f809b33138ac12bd9fddbbc7f3f48187b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "941fd7f081ee37e98cd3850cd8ca736abe7fbfcf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@941fd7f081ee37e98cd3850cd8ca736abe7fbfcf",
"title": "941fd7f081ee37e98cd3850cd8ca736abe7fbfcf",
"url": "https://fuchsia.googlesource.com/integration/+/941fd7f081ee37e98cd3850cd8ca736abe7fbfcf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "591c195f3daa6f6ac984c412659356757e7b38e3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@591c195f3daa6f6ac984c412659356757e7b38e3",
"title": "591c195f3daa6f6ac984c412659356757e7b38e3",
"url": "https://fuchsia.googlesource.com/integration/+/591c195f3daa6f6ac984c412659356757e7b38e3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8d44028854af8d2de540758b9e442d6ad54de430"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8d44028854af8d2de540758b9e442d6ad54de430",
"title": "8d44028854af8d2de540758b9e442d6ad54de430",
"url": "https://fuchsia.googlesource.com/integration/+/8d44028854af8d2de540758b9e442d6ad54de430"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c67aa305921f8a9c9a289ee1d547e602943fc99b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c67aa305921f8a9c9a289ee1d547e602943fc99b",
"title": "c67aa305921f8a9c9a289ee1d547e602943fc99b",
"url": "https://fuchsia.googlesource.com/integration/+/c67aa305921f8a9c9a289ee1d547e602943fc99b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d8f4896290e80a8a45faf2e6cdc9d714c9258ecd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d8f4896290e80a8a45faf2e6cdc9d714c9258ecd",
"title": "d8f4896290e80a8a45faf2e6cdc9d714c9258ecd",
"url": "https://fuchsia.googlesource.com/integration/+/d8f4896290e80a8a45faf2e6cdc9d714c9258ecd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e8ea3cd82521de862975453aaa5c91dc5ee43c98"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e8ea3cd82521de862975453aaa5c91dc5ee43c98",
"title": "e8ea3cd82521de862975453aaa5c91dc5ee43c98",
"url": "https://fuchsia.googlesource.com/integration/+/e8ea3cd82521de862975453aaa5c91dc5ee43c98"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6f498b7d0ad841229ea6bb99ed1e78061028defc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6f498b7d0ad841229ea6bb99ed1e78061028defc",
"title": "6f498b7d0ad841229ea6bb99ed1e78061028defc",
"url": "https://fuchsia.googlesource.com/integration/+/6f498b7d0ad841229ea6bb99ed1e78061028defc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0e7ddd6b1fc1b88f284d33e7600dc6efdd991c3c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0e7ddd6b1fc1b88f284d33e7600dc6efdd991c3c",
"title": "0e7ddd6b1fc1b88f284d33e7600dc6efdd991c3c",
"url": "https://fuchsia.googlesource.com/integration/+/0e7ddd6b1fc1b88f284d33e7600dc6efdd991c3c"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "0e7ddd6b1fc1b88f284d33e7600dc6efdd991c3c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8865134032354075952"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-fuchsia-coverage-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:39:45.708] Scheduled build:
{
"id": "8699703970431712801",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-coverage"
},
"createdBy": "project:fuchsia",
"createTime": "2025-10-29T04:39:45.269919533Z",
"updateTime": "2025-10-29T04:39:45.269919533Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "0e7ddd6b1fc1b88f284d33e7600dc6efdd991c3c",
"ref": "refs/heads/main"
}
}
}
[04:39:45.708] Task URL: https://cr-buildbucket.appspot.com/build/8699703970431712801
[04:39:45.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:2:0) after 1m44s
[04:39:46.579] Received PubSub notification, asking Buildbucket for the build status
[04:39:46.602] Build status: SCHEDULED
[04:39:48.747] Received PubSub notification, asking Buildbucket for the build status
[04:39:48.762] Build status: STARTED
[04:41:29.720] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:2:0)
[04:41:29.740] Build status: STARTED
[04:41:29.740] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:5:0) after 6m15s
[04:47:44.819] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:5:0)
[04:47:44.836] Build status: STARTED
[04:47:44.837] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:6:0) after 8m4s
[04:55:48.959] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:6:0)
[04:55:48.986] Build status: STARTED
[04:55:48.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:7:0) after 6m52s
[05:02:41.000] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:7:0)
[05:02:41.021] Build status: STARTED
[05:02:41.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:8:0) after 4m25s
[05:07:06.035] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:8:0)
[05:07:06.068] Build status: STARTED
[05:07:06.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:9:0) after 6m3s
[05:13:09.098] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:9:0)
[05:13:09.133] Build status: STARTED
[05:13:09.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:10:0) after 6m45s
[05:19:54.149] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:10:0)
[05:19:54.168] Build status: STARTED
[05:19:54.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:11:0) after 2m22s
[05:22:16.564] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:11:0)
[05:22:16.585] Build status: STARTED
[05:22:16.585] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:12:0) after 5m44s
[05:28:00.682] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:12:0)
[05:28:00.703] Build status: STARTED
[05:28:00.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:13:0) after 3m21s
[05:31:21.773] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:13:0)
[05:31:21.802] Build status: STARTED
[05:31:21.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:14:0) after 2m53s
[05:34:14.865] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:14:0)
[05:34:14.924] Build status: STARTED
[05:34:14.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:15:0) after 6m15s
[05:40:29.893] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:15:0)
[05:40:29.957] Build status: STARTED
[05:40:29.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:16:0) after 2m56s
[05:43:25.987] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:16:0)
[05:43:26.010] Build status: STARTED
[05:43:26.010] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:17:0) after 6m14s
[05:49:40.244] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:17:0)
[05:49:40.260] Build status: STARTED
[05:49:40.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:18:0) after 5m58s
[05:55:38.275] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:18:0)
[05:55:38.298] Build status: STARTED
[05:55:38.298] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:19:0) after 1m15s
[05:56:53.417] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:19:0)
[05:56:53.480] Build status: STARTED
[05:56:53.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:20:0) after 4m0s
[06:00:53.592] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:20:0)
[06:00:53.671] Build status: STARTED
[06:00:53.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:21:0) after 9m27s
[06:10:21.316] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:21:0)
[06:10:21.349] Build status: STARTED
[06:10:21.349] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:22:0) after 5m55s
[06:16:16.373] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:22:0)
[06:16:16.399] Build status: STARTED
[06:16:16.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:23:0) after 9m9s
[06:25:25.390] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:23:0)
[06:25:25.535] Build status: STARTED
[06:25:25.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:24:0) after 3m10s
[06:28:35.602] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:24:0)
[06:28:35.644] Build status: STARTED
[06:28:35.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:25:0) after 3m45s
[06:32:20.749] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:25:0)
[06:32:20.927] Build status: STARTED
[06:32:20.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:26:0) after 5m37s
[06:37:58.043] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:26:0)
[06:37:58.059] Build status: STARTED
[06:37:58.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:27:0) after 8m19s
[06:46:17.141] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:27:0)
[06:46:17.158] Build status: STARTED
[06:46:17.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:28:0) after 6m53s
[06:53:10.252] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:28:0)
[06:53:10.390] Build status: STARTED
[06:53:10.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:29:0) after 9m55s
[07:03:05.430] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:29:0)
[07:03:05.446] Build status: STARTED
[07:03:05.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:30:0) after 1m53s
[07:04:58.461] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:30:0)
[07:04:58.482] Build status: STARTED
[07:04:58.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:31:0) after 4m18s
[07:09:16.551] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:31:0)
[07:09:16.573] Build status: STARTED
[07:09:16.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:32:0) after 6m37s
[07:15:53.656] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:32:0)
[07:15:53.679] Build status: STARTED
[07:15:53.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:33:0) after 3m46s
[07:19:39.740] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:33:0)
[07:19:39.758] Build status: STARTED
[07:19:39.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:34:0) after 5m33s
[07:25:12.873] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:34:0)
[07:25:13.012] Build status: STARTED
[07:25:13.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:35:0) after 4m45s
[07:29:58.082] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:35:0)
[07:29:58.160] Build status: STARTED
[07:29:58.160] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:36:0) after 4m37s
[07:34:35.240] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:36:0)
[07:34:35.265] Build status: STARTED
[07:34:35.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:37:0) after 7m44s
[07:42:19.561] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:37:0)
[07:42:19.581] Build status: STARTED
[07:42:19.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:38:0) after 3m55s
[07:46:14.598] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:38:0)
[07:46:14.620] Build status: STARTED
[07:46:14.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8865134032354075952:39:0) after 8m49s
[07:53:51.416] Received PubSub notification, asking Buildbucket for the build status
[07:53:51.431] Build:
{
"id": "8699703970431712801",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-coverage"
},
"createdBy": "project:fuchsia",
"createTime": "2025-10-29T04:39:45.269919533Z",
"startTime": "2025-10-29T04:39:48.428462815Z",
"endTime": "2025-10-29T07:53:51.180096906Z",
"updateTime": "2025-10-29T07:53:51.180096906Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "0e7ddd6b1fc1b88f284d33e7600dc6efdd991c3c",
"ref": "refs/heads/main"
}
}
}
[07:53:51.431] Invocation finished in 3h14m7.378797803s with status SUCCEEDED