[22:22:10.149] New invocation is queued and will start shortly
[22:22:11.290] Starting the invocation (attempt 1)
[22:22:11.334] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/91bd1f0ff3b6f28e1ab755e1d21baa5fca1593ea"
[22:22:11.334] Popped gitiles commit info from properties and tags
[22:22:11.334] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:22:11.334] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:22:11.335] Buildbucket request:
{
"requestId": "8898859858199484288",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-coverage"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8898859858199484288",
"job": "fuchsia/fuchsia-coverage-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "0855c16648c4b4d66415f96895bccac30c2e78e7"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@0855c16648c4b4d66415f96895bccac30c2e78e7",
"title": "0855c16648c4b4d66415f96895bccac30c2e78e7",
"url": "https://fuchsia.googlesource.com/fuchsia/+/0855c16648c4b4d66415f96895bccac30c2e78e7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6e1e47bd0ffcd3ab00ff0a431b68e2302a6c6093"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6e1e47bd0ffcd3ab00ff0a431b68e2302a6c6093",
"title": "6e1e47bd0ffcd3ab00ff0a431b68e2302a6c6093",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6e1e47bd0ffcd3ab00ff0a431b68e2302a6c6093"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a6de3142c4db67e20caa096cefe618507a50aef1"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a6de3142c4db67e20caa096cefe618507a50aef1",
"title": "a6de3142c4db67e20caa096cefe618507a50aef1",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a6de3142c4db67e20caa096cefe618507a50aef1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "de4b9e20ecef19e2ba913f6faba7c791aff3a67c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@de4b9e20ecef19e2ba913f6faba7c791aff3a67c",
"title": "de4b9e20ecef19e2ba913f6faba7c791aff3a67c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/de4b9e20ecef19e2ba913f6faba7c791aff3a67c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "4d5d42163678d3946456a5fe402e865e5f7924f7"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@4d5d42163678d3946456a5fe402e865e5f7924f7",
"title": "4d5d42163678d3946456a5fe402e865e5f7924f7",
"url": "https://fuchsia.googlesource.com/fuchsia/+/4d5d42163678d3946456a5fe402e865e5f7924f7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "0b85993f69c60fad12c5791bf3a16ce62333474a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@0b85993f69c60fad12c5791bf3a16ce62333474a",
"title": "0b85993f69c60fad12c5791bf3a16ce62333474a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/0b85993f69c60fad12c5791bf3a16ce62333474a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "d99b463bab52cf598e979cd2b40973936e19b917"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@d99b463bab52cf598e979cd2b40973936e19b917",
"title": "d99b463bab52cf598e979cd2b40973936e19b917",
"url": "https://fuchsia.googlesource.com/fuchsia/+/d99b463bab52cf598e979cd2b40973936e19b917"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "7b1ea9092f3d5426871f0ab7d4f2cd9d592ccffc"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@7b1ea9092f3d5426871f0ab7d4f2cd9d592ccffc",
"title": "7b1ea9092f3d5426871f0ab7d4f2cd9d592ccffc",
"url": "https://fuchsia.googlesource.com/fuchsia/+/7b1ea9092f3d5426871f0ab7d4f2cd9d592ccffc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "d1995757d7d5b8d0ffa770c3046d00cb191a1e4e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@d1995757d7d5b8d0ffa770c3046d00cb191a1e4e",
"title": "d1995757d7d5b8d0ffa770c3046d00cb191a1e4e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/d1995757d7d5b8d0ffa770c3046d00cb191a1e4e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1c48f38e61f6a2281e26d30df0f1af8977ce3c25"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1c48f38e61f6a2281e26d30df0f1af8977ce3c25",
"title": "1c48f38e61f6a2281e26d30df0f1af8977ce3c25",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1c48f38e61f6a2281e26d30df0f1af8977ce3c25"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "91bd1f0ff3b6f28e1ab755e1d21baa5fca1593ea"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@91bd1f0ff3b6f28e1ab755e1d21baa5fca1593ea",
"title": "91bd1f0ff3b6f28e1ab755e1d21baa5fca1593ea",
"url": "https://fuchsia.googlesource.com/fuchsia/+/91bd1f0ff3b6f28e1ab755e1d21baa5fca1593ea"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "91bd1f0ff3b6f28e1ab755e1d21baa5fca1593ea",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8898859858199484288"
},
{
"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"
}
}
[22:22:11.802] Scheduled build:
{
"id": "8733429796191418273",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-coverage"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-21T22:22:11.443254684Z",
"updateTime": "2024-10-21T22:22:11.443254684Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "91bd1f0ff3b6f28e1ab755e1d21baa5fca1593ea",
"ref": "refs/heads/main"
}
}
}
[22:22:11.802] Task URL: https://cr-buildbucket.appspot.com/build/8733429796191418273
[22:22:11.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:2:0) after 2m55s
[22:22:12.804] Received PubSub notification, asking Buildbucket for the build status
[22:22:12.851] Build status: SCHEDULED
[22:22:16.973] Received PubSub notification, asking Buildbucket for the build status
[22:22:17.049] Build status: STARTED
[22:25:06.817] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:2:0)
[22:25:06.899] Build status: STARTED
[22:25:06.899] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:5:0) after 1m5s
[22:26:11.916] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:5:0)
[22:26:11.936] Build status: STARTED
[22:26:11.936] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:6:0) after 6m12s
[22:32:23.981] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:6:0)
[22:32:24.007] Build status: STARTED
[22:32:24.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:7:0) after 1m6s
[22:33:30.076] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:7:0)
[22:33:30.104] Build status: STARTED
[22:33:30.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:8:0) after 9m4s
[22:42:34.354] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:8:0)
[22:42:34.373] Build status: STARTED
[22:42:34.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:9:0) after 7m58s
[22:50:32.399] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:9:0)
[22:50:32.416] Build status: STARTED
[22:50:32.416] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:10:0) after 7m49s
[22:58:21.459] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:10:0)
[22:58:21.481] Build status: STARTED
[22:58:21.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:11:0) after 6m26s
[23:04:47.510] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:11:0)
[23:04:47.532] Build status: STARTED
[23:04:47.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:12:0) after 3m10s
[23:07:57.595] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:12:0)
[23:07:57.613] Build status: STARTED
[23:07:57.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:13:0) after 3m50s
[23:11:47.697] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:13:0)
[23:11:47.716] Build status: STARTED
[23:11:47.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:14:0) after 3m33s
[23:15:20.797] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:14:0)
[23:15:20.816] Build status: STARTED
[23:15:20.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:15:0) after 6m55s
[23:22:15.882] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:15:0)
[23:22:15.902] Build status: STARTED
[23:22:15.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:16:0) after 9m10s
[23:31:25.925] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:16:0)
[23:31:25.945] Build status: STARTED
[23:31:25.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:17:0) after 5m27s
[23:36:53.041] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:17:0)
[23:36:53.077] Build status: STARTED
[23:36:53.077] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:18:0) after 2m59s
[23:39:52.147] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:18:0)
[23:39:52.168] Build status: STARTED
[23:39:52.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:19:0) after 3m58s
[23:43:50.190] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:19:0)
[23:43:50.211] Build status: STARTED
[23:43:50.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:20:0) after 3m25s
[23:47:15.229] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:20:0)
[23:47:15.257] Build status: STARTED
[23:47:15.257] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:21:0) after 8m53s
[23:56:08.491] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:21:0)
[23:56:08.509] Build status: STARTED
[23:56:08.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:22:0) after 9m32s
[00:05:40.528] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:22:0)
[00:05:40.545] Build status: STARTED
[00:05:40.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:23:0) after 7m47s
[00:13:27.570] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:23:0)
[00:13:27.610] Build status: STARTED
[00:13:27.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:24:0) after 4m52s
[00:18:19.627] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:24:0)
[00:18:19.647] Build status: STARTED
[00:18:19.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:25:0) after 6m25s
[00:24:44.664] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:25:0)
[00:24:44.683] Build status: STARTED
[00:24:44.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:26:0) after 2m5s
[00:26:49.708] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:26:0)
[00:26:49.731] Build status: STARTED
[00:26:49.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:27:0) after 3m48s
[00:30:37.753] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:27:0)
[00:30:37.774] Build status: STARTED
[00:30:37.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:28:0) after 9m49s
[00:40:26.791] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:28:0)
[00:40:26.809] Build status: STARTED
[00:40:26.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:29:0) after 4m33s
[00:44:59.881] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:29:0)
[00:44:59.905] Build status: STARTED
[00:44:59.905] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:30:0) after 7m50s
[00:52:49.954] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:30:0)
[00:52:49.977] Build status: STARTED
[00:52:49.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:31:0) after 8m2s
[01:00:52.003] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:31:0)
[01:00:52.030] Build status: STARTED
[01:00:52.031] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:32:0) after 9m29s
[01:10:21.034] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:32:0)
[01:10:21.055] Build status: STARTED
[01:10:21.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:33:0) after 8m57s
[01:19:18.078] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:33:0)
[01:19:18.099] Build status: STARTED
[01:19:18.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:34:0) after 7m45s
[01:27:03.178] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:34:0)
[01:27:03.194] Build status: STARTED
[01:27:03.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:35:0) after 5m39s
[01:32:42.214] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:35:0)
[01:32:42.234] Build status: STARTED
[01:32:42.234] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:36:0) after 4m12s
[01:36:54.327] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:36:0)
[01:36:54.359] Build status: STARTED
[01:36:54.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:37:0) after 7m53s
[01:44:47.444] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:37:0)
[01:44:47.492] Build status: STARTED
[01:44:47.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-coverage-af244ac6:8898859858199484288:38:0) after 8m33s
[01:45:14.533] Received PubSub notification, asking Buildbucket for the build status
[01:45:14.559] Build:
{
"id": "8733429796191418273",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-coverage"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-21T22:22:11.443254684Z",
"startTime": "2024-10-21T22:22:16.750251260Z",
"endTime": "2024-10-22T01:45:13.906614766Z",
"updateTime": "2024-10-22T01:45:13.906614766Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "91bd1f0ff3b6f28e1ab755e1d21baa5fca1593ea",
"ref": "refs/heads/main"
}
}
}
[01:45:14.559] Invocation finished in 3h23m4.415555001s with status SUCCEEDED