[05:44:27.270] New invocation is queued and will start shortly
[05:44:29.469] Starting the invocation (attempt 1)
[05:44:29.533] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/2b796c618ee471829a8e46d8a7e971c8f13fee27"
[05:44:29.533] Popped gitiles commit info from properties and tags
[05:44:29.533] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:44:29.534] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:44:29.534] Buildbucket request:
{
"requestId": "8872740105698303616",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-coverage"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8872740105698303616",
"job": "fuchsia/global.ci-fuchsia-coverage-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a6152cdc1e63ce05778712e3044b95da61f70d3c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a6152cdc1e63ce05778712e3044b95da61f70d3c",
"title": "a6152cdc1e63ce05778712e3044b95da61f70d3c",
"url": "https://fuchsia.googlesource.com/integration/+/a6152cdc1e63ce05778712e3044b95da61f70d3c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1a27683cdb5cc66d755d4181e647b20bce3b0b96"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1a27683cdb5cc66d755d4181e647b20bce3b0b96",
"title": "1a27683cdb5cc66d755d4181e647b20bce3b0b96",
"url": "https://fuchsia.googlesource.com/integration/+/1a27683cdb5cc66d755d4181e647b20bce3b0b96"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "84383aa1124b39bdb50f84c9042d656213328016"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@84383aa1124b39bdb50f84c9042d656213328016",
"title": "84383aa1124b39bdb50f84c9042d656213328016",
"url": "https://fuchsia.googlesource.com/integration/+/84383aa1124b39bdb50f84c9042d656213328016"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5dff05f3e478a6450e7fcc04c2c816d4e25472e3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5dff05f3e478a6450e7fcc04c2c816d4e25472e3",
"title": "5dff05f3e478a6450e7fcc04c2c816d4e25472e3",
"url": "https://fuchsia.googlesource.com/integration/+/5dff05f3e478a6450e7fcc04c2c816d4e25472e3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2b796c618ee471829a8e46d8a7e971c8f13fee27"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2b796c618ee471829a8e46d8a7e971c8f13fee27",
"title": "2b796c618ee471829a8e46d8a7e971c8f13fee27",
"url": "https://fuchsia.googlesource.com/integration/+/2b796c618ee471829a8e46d8a7e971c8f13fee27"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2b796c618ee471829a8e46d8a7e971c8f13fee27",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8872740105698303616"
},
{
"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"
}
}
[05:44:30.056] Scheduled build:
{
"id": "8707310042580119729",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-coverage"
},
"createdBy": "project:fuchsia",
"createTime": "2025-08-06T05:44:29.613971075Z",
"updateTime": "2025-08-06T05:44:29.613971075Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2b796c618ee471829a8e46d8a7e971c8f13fee27",
"ref": "refs/heads/main"
}
}
}
[05:44:30.056] Task URL: https://cr-buildbucket.appspot.com/build/8707310042580119729
[05:44:30.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:2:0) after 7m23s
[05:45:32.411] Received PubSub notification, asking Buildbucket for the build status
[05:45:32.439] Build status: STARTED
[05:45:40.280] Received PubSub notification, asking Buildbucket for the build status
[05:45:40.310] Build status: STARTED
[05:51:53.072] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:2:0)
[05:51:53.098] Build status: STARTED
[05:51:53.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:5:0) after 4m10s
[05:56:03.091] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:5:0)
[05:56:03.134] Build status: STARTED
[05:56:03.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:6:0) after 5m9s
[06:01:12.172] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:6:0)
[06:01:12.206] Build status: STARTED
[06:01:12.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:7:0) after 8m34s
[06:09:46.302] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:7:0)
[06:09:46.358] Build status: STARTED
[06:09:46.358] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:8:0) after 1m35s
[06:11:21.384] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:8:0)
[06:11:21.416] Build status: STARTED
[06:11:21.416] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:9:0) after 2m5s
[06:13:26.441] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:9:0)
[06:13:26.457] Build status: STARTED
[06:13:26.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:10:0) after 6m13s
[06:19:39.489] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:10:0)
[06:19:39.509] Build status: STARTED
[06:19:39.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:11:0) after 9m13s
[06:28:52.532] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:11:0)
[06:28:52.569] Build status: STARTED
[06:28:52.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:12:0) after 2m5s
[06:30:57.544] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:12:0)
[06:30:57.571] Build status: STARTED
[06:30:57.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:13:0) after 7m16s
[06:38:13.660] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:13:0)
[06:38:13.778] Build status: STARTED
[06:38:13.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:14:0) after 8m40s
[06:46:53.801] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:14:0)
[06:46:53.824] Build status: STARTED
[06:46:53.825] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:15:0) after 3m33s
[06:50:26.896] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:15:0)
[06:50:26.912] Build status: STARTED
[06:50:26.912] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:16:0) after 2m46s
[06:53:12.930] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:16:0)
[06:53:12.956] Build status: STARTED
[06:53:12.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:17:0) after 3m55s
[06:57:07.980] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:17:0)
[06:57:08.004] Build status: STARTED
[06:57:08.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:18:0) after 8m31s
[07:05:39.018] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:18:0)
[07:05:39.066] Build status: STARTED
[07:05:39.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:19:0) after 5m27s
[07:11:06.196] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:19:0)
[07:11:06.227] Build status: STARTED
[07:11:06.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:20:0) after 4m35s
[07:15:41.251] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:20:0)
[07:15:41.272] Build status: STARTED
[07:15:41.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:21:0) after 3m19s
[07:19:00.297] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:21:0)
[07:19:00.317] Build status: STARTED
[07:19:00.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:22:0) after 6m0s
[07:25:00.339] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:22:0)
[07:25:00.356] Build status: STARTED
[07:25:00.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:23:0) after 2m7s
[07:27:07.431] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:23:0)
[07:27:07.447] Build status: STARTED
[07:27:07.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:24:0) after 3m40s
[07:30:47.473] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:24:0)
[07:30:47.497] Build status: STARTED
[07:30:47.497] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:25:0) after 3m40s
[07:34:27.515] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:25:0)
[07:34:27.540] Build status: STARTED
[07:34:27.540] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:26:0) after 9m23s
[07:43:50.617] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:26:0)
[07:43:50.654] Build status: STARTED
[07:43:50.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:27:0) after 9m58s
[07:53:48.792] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:27:0)
[07:53:48.822] Build status: STARTED
[07:53:48.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:28:0) after 7m57s
[08:01:45.886] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:28:0)
[08:01:45.914] Build status: STARTED
[08:01:45.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:29:0) after 5m33s
[08:07:18.962] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:29:0)
[08:07:18.980] Build status: STARTED
[08:07:18.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:30:0) after 3m26s
[08:10:45.051] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:30:0)
[08:10:45.072] Build status: STARTED
[08:10:45.072] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:31:0) after 9m32s
[08:20:17.058] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:31:0)
[08:20:17.081] Build status: STARTED
[08:20:17.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:32:0) after 1m18s
[08:21:35.099] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:32:0)
[08:21:35.115] Build status: STARTED
[08:21:35.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:33:0) after 6m57s
[08:28:32.198] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:33:0)
[08:28:32.219] Build status: STARTED
[08:28:32.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:34:0) after 4m21s
[08:32:53.338] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:34:0)
[08:32:53.382] Build status: STARTED
[08:32:53.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:35:0) after 8m30s
[08:41:23.402] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:35:0)
[08:41:23.420] Build status: STARTED
[08:41:23.420] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:36:0) after 1m18s
[08:42:41.444] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:36:0)
[08:42:41.591] Build status: STARTED
[08:42:41.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:37:0) after 2m49s
[08:45:30.609] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:37:0)
[08:45:30.732] Build status: STARTED
[08:45:30.732] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:38:0) after 8m21s
[08:53:51.747] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:38:0)
[08:53:51.764] Build status: STARTED
[08:53:51.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:39:0) after 5m20s
[08:59:11.793] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:39:0)
[08:59:11.811] Build status: STARTED
[08:59:11.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:40:0) after 1m24s
[09:00:35.838] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:40:0)
[09:00:35.871] Build status: STARTED
[09:00:35.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:41:0) after 1m24s
[09:02:00.199] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:41:0)
[09:02:00.222] Build status: STARTED
[09:02:00.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8872740105698303616:42:0) after 8m6s
[09:09:32.400] Received PubSub notification, asking Buildbucket for the build status
[09:09:32.426] Build:
{
"id": "8707310042580119729",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-coverage"
},
"createdBy": "project:fuchsia",
"createTime": "2025-08-06T05:44:29.613971075Z",
"startTime": "2025-08-06T05:44:32.768992861Z",
"endTime": "2025-08-06T09:09:32.082316290Z",
"updateTime": "2025-08-06T09:09:32.082316290Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2b796c618ee471829a8e46d8a7e971c8f13fee27",
"ref": "refs/heads/main"
}
}
}
[09:09:32.426] Invocation finished in 3h25m5.170961989s with status SUCCEEDED