[03:13:49.493] New invocation is queued and will start shortly
[03:13:50.555] Starting the invocation (attempt 1)
[03:13:50.587] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/3de2ded7e419b54f52a5e09f1da69240b2109072"
[03:13:50.587] Popped gitiles commit info from properties and tags
[03:13:50.587] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:13:50.589] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:13:50.589] Buildbucket request:
{
"requestId": "8898479120950235648",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-coverage"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8898479120950235648",
"job": "fuchsia/fuchsia-coverage-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "752aa679854180c70551e47a0d55a9dd4f356fcf"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@752aa679854180c70551e47a0d55a9dd4f356fcf",
"title": "752aa679854180c70551e47a0d55a9dd4f356fcf",
"url": "https://fuchsia.googlesource.com/fuchsia/+/752aa679854180c70551e47a0d55a9dd4f356fcf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "3c0258c8b11d74c9da8ed6c012997259534531c0"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@3c0258c8b11d74c9da8ed6c012997259534531c0",
"title": "3c0258c8b11d74c9da8ed6c012997259534531c0",
"url": "https://fuchsia.googlesource.com/fuchsia/+/3c0258c8b11d74c9da8ed6c012997259534531c0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9d33842c1648175917ea8dcefe68cb2127580394"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9d33842c1648175917ea8dcefe68cb2127580394",
"title": "9d33842c1648175917ea8dcefe68cb2127580394",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9d33842c1648175917ea8dcefe68cb2127580394"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "32eb90cf69e283507622500c19139bdb4235161c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@32eb90cf69e283507622500c19139bdb4235161c",
"title": "32eb90cf69e283507622500c19139bdb4235161c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/32eb90cf69e283507622500c19139bdb4235161c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9d59b8cbca39550fee26ea96851469f7ab78c68f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9d59b8cbca39550fee26ea96851469f7ab78c68f",
"title": "9d59b8cbca39550fee26ea96851469f7ab78c68f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9d59b8cbca39550fee26ea96851469f7ab78c68f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "01e3aaf6613f62700eace90488d37016c7d2ffe9"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@01e3aaf6613f62700eace90488d37016c7d2ffe9",
"title": "01e3aaf6613f62700eace90488d37016c7d2ffe9",
"url": "https://fuchsia.googlesource.com/fuchsia/+/01e3aaf6613f62700eace90488d37016c7d2ffe9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "3de2ded7e419b54f52a5e09f1da69240b2109072"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@3de2ded7e419b54f52a5e09f1da69240b2109072",
"title": "3de2ded7e419b54f52a5e09f1da69240b2109072",
"url": "https://fuchsia.googlesource.com/fuchsia/+/3de2ded7e419b54f52a5e09f1da69240b2109072"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "3de2ded7e419b54f52a5e09f1da69240b2109072",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8898479120950235648"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/fuchsia-coverage-af244ac6"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:13:50.961] Scheduled build:
{
"id": "8733049059081988369",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-coverage"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-26T03:13:50.645758583Z",
"updateTime": "2024-10-26T03:13:50.645758583Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "3de2ded7e419b54f52a5e09f1da69240b2109072",
"ref": "refs/heads/main"
}
},
"canary": true
}
[03:13:50.961] Task URL: https://cr-buildbucket.appspot.com/build/8733049059081988369
[03:13:50.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:2:0) after 2m43s
[03:13:51.027] Received PubSub notification, asking Buildbucket for the build status
[03:13:51.056] Build status: SCHEDULED
[03:13:55.253] Received PubSub notification, asking Buildbucket for the build status
[03:13:55.346] Build status: STARTED
[03:16:34.117] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:2:0)
[03:16:34.136] Build status: STARTED
[03:16:34.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:5:0) after 5m40s
[03:22:14.139] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:5:0)
[03:22:14.158] Build status: STARTED
[03:22:14.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:6:0) after 8m27s
[03:30:41.554] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:6:0)
[03:30:41.572] Build status: STARTED
[03:30:41.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:7:0) after 8m3s
[03:38:44.564] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:7:0)
[03:38:44.585] Build status: STARTED
[03:38:44.585] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:8:0) after 3m9s
[03:41:53.777] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:8:0)
[03:41:53.797] Build status: STARTED
[03:41:53.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:9:0) after 9m11s
[03:51:04.825] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:9:0)
[03:51:04.844] Build status: STARTED
[03:51:04.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:10:0) after 8m19s
[03:59:23.933] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:10:0)
[03:59:23.955] Build status: STARTED
[03:59:23.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:11:0) after 9m37s
[04:09:00.977] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:11:0)
[04:09:01.001] Build status: STARTED
[04:09:01.002] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:12:0) after 4m8s
[04:13:09.390] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:12:0)
[04:13:09.409] Build status: STARTED
[04:13:09.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:13:0) after 4m32s
[04:17:41.526] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:13:0)
[04:17:41.581] Build status: STARTED
[04:17:41.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:14:0) after 5m43s
[04:23:24.599] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:14:0)
[04:23:24.616] Build status: STARTED
[04:23:24.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:15:0) after 8m26s
[04:31:50.751] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:15:0)
[04:31:50.770] Build status: STARTED
[04:31:50.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:16:0) after 3m37s
[04:35:28.010] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:16:0)
[04:35:28.028] Build status: STARTED
[04:35:28.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:17:0) after 3m8s
[04:38:36.145] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:17:0)
[04:38:36.167] Build status: STARTED
[04:38:36.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:18:0) after 5m37s
[04:44:13.192] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:18:0)
[04:44:13.214] Build status: STARTED
[04:44:13.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:19:0) after 7m45s
[04:51:58.268] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:19:0)
[04:51:58.287] Build status: STARTED
[04:51:58.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:20:0) after 2m8s
[04:54:06.547] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:20:0)
[04:54:06.566] Build status: STARTED
[04:54:06.566] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:21:0) after 3m3s
[04:57:09.673] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:21:0)
[04:57:09.717] Build status: STARTED
[04:57:09.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:22:0) after 3m39s
[05:00:48.739] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:22:0)
[05:00:48.836] Build status: STARTED
[05:00:48.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:23:0) after 8m34s
[05:09:22.852] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:23:0)
[05:09:22.876] Build status: STARTED
[05:09:22.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:24:0) after 4m40s
[05:14:03.149] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:24:0)
[05:14:03.169] Build status: STARTED
[05:14:03.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:25:0) after 8m27s
[05:22:30.237] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:25:0)
[05:22:30.255] Build status: STARTED
[05:22:30.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:26:0) after 4m38s
[05:27:08.537] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:26:0)
[05:27:08.558] Build status: STARTED
[05:27:08.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:27:0) after 5m29s
[05:32:37.639] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:27:0)
[05:32:37.658] Build status: STARTED
[05:32:37.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:28:0) after 6m48s
[05:39:25.683] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:28:0)
[05:39:25.703] Build status: STARTED
[05:39:25.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:29:0) after 3m57s
[05:43:22.720] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:29:0)
[05:43:22.736] Build status: STARTED
[05:43:22.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:30:0) after 6m33s
[05:49:55.754] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:30:0)
[05:49:55.770] Build status: STARTED
[05:49:55.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:31:0) after 5m26s
[05:55:21.786] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:31:0)
[05:55:21.806] Build status: STARTED
[05:55:21.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:32:0) after 3m30s
[05:58:52.033] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:32:0)
[05:58:52.051] Build status: STARTED
[05:58:52.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:33:0) after 4m7s
[06:02:59.113] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:33:0)
[06:02:59.134] Build status: STARTED
[06:02:59.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:34:0) after 8m24s
[06:11:23.141] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:34:0)
[06:11:23.161] Build status: STARTED
[06:11:23.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898479120950235648:35:0) after 3m45s
[06:14:28.686] Received PubSub notification, asking Buildbucket for the build status
[06:14:28.711] Build:
{
"id": "8733049059081988369",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-coverage"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-26T03:13:50.645758583Z",
"startTime": "2024-10-26T03:13:55.095575528Z",
"endTime": "2024-10-26T06:14:28.117103426Z",
"updateTime": "2024-10-26T06:14:28.117103426Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "3de2ded7e419b54f52a5e09f1da69240b2109072",
"ref": "refs/heads/main"
}
},
"canary": true
}
[06:14:28.711] Invocation finished in 3h0m39.230956244s with status SUCCEEDED