[12:06:00.303] New invocation is queued and will start shortly
[12:06:01.389] Starting the invocation (attempt 1)
[12:06:01.453] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/85a491bdebf394567c4e526a718b230d23f09c04"
[12:06:01.453] Popped gitiles commit info from properties and tags
[12:06:01.453] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:06:01.453] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:06:01.453] Buildbucket request:
{
"requestId": "8943472331364056176",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8943472331364056176",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "aa27f757fdd36b9fdeb2c0bebed13ecd7ce30faf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@aa27f757fdd36b9fdeb2c0bebed13ecd7ce30faf",
"title": "aa27f757fdd36b9fdeb2c0bebed13ecd7ce30faf",
"url": "https://fuchsia.googlesource.com/integration/+/aa27f757fdd36b9fdeb2c0bebed13ecd7ce30faf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c03270cbe71cfcdf202688d74cc933afa0d72353"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c03270cbe71cfcdf202688d74cc933afa0d72353",
"title": "c03270cbe71cfcdf202688d74cc933afa0d72353",
"url": "https://fuchsia.googlesource.com/integration/+/c03270cbe71cfcdf202688d74cc933afa0d72353"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "971f47a32a8ab1f96ff738bf92ca31ec3e1e215e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@971f47a32a8ab1f96ff738bf92ca31ec3e1e215e",
"title": "971f47a32a8ab1f96ff738bf92ca31ec3e1e215e",
"url": "https://fuchsia.googlesource.com/integration/+/971f47a32a8ab1f96ff738bf92ca31ec3e1e215e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "913f31ae52b90f2775880005b4a853461c710ef3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@913f31ae52b90f2775880005b4a853461c710ef3",
"title": "913f31ae52b90f2775880005b4a853461c710ef3",
"url": "https://fuchsia.googlesource.com/integration/+/913f31ae52b90f2775880005b4a853461c710ef3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "85a491bdebf394567c4e526a718b230d23f09c04"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@85a491bdebf394567c4e526a718b230d23f09c04",
"title": "85a491bdebf394567c4e526a718b230d23f09c04",
"url": "https://fuchsia.googlesource.com/integration/+/85a491bdebf394567c4e526a718b230d23f09c04"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "85a491bdebf394567c4e526a718b230d23f09c04",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8943472331364056176"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/firebase-docs-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:06:01.752] Scheduled build:
{
"id": "8778042269432855201",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-06-17T12:06:01.521399260Z",
"updateTime": "2023-06-17T12:06:01.521399260Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "85a491bdebf394567c4e526a718b230d23f09c04",
"ref": "refs/heads/main"
}
}
}
[12:06:01.752] Task URL: https://cr-buildbucket.appspot.com/build/8778042269432855201
[12:06:01.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:2:0) after 2m8s
[12:08:09.831] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:2:0)
[12:08:09.853] Build status: SCHEDULED
[12:08:09.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:3:0) after 4m40s
[12:08:11.556] Received PubSub notification, asking Buildbucket for the build status
[12:08:11.580] Build status: STARTED
[12:12:49.868] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:3:0)
[12:12:49.889] Build status: STARTED
[12:12:49.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:5:0) after 7m45s
[12:20:34.955] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:5:0)
[12:20:34.971] Build status: STARTED
[12:20:34.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:6:0) after 2m5s
[12:22:40.053] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:6:0)
[12:22:40.068] Build status: STARTED
[12:22:40.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:7:0) after 2m1s
[12:24:41.084] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:7:0)
[12:24:41.102] Build status: STARTED
[12:24:41.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:8:0) after 9m51s
[12:34:32.171] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:8:0)
[12:34:32.193] Build status: STARTED
[12:34:32.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:9:0) after 3m45s
[12:38:17.307] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:9:0)
[12:38:17.363] Build status: STARTED
[12:38:17.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:10:0) after 3m27s
[12:41:44.502] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:10:0)
[12:41:44.518] Build status: STARTED
[12:41:44.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:11:0) after 5m50s
[12:47:34.571] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:11:0)
[12:47:34.590] Build status: STARTED
[12:47:34.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:12:0) after 5m34s
[12:53:08.719] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:12:0)
[12:53:08.743] Build status: STARTED
[12:53:08.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:13:0) after 8m22s
[13:01:30.890] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:13:0)
[13:01:30.907] Build status: STARTED
[13:01:30.907] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:14:0) after 4m34s
[13:06:05.047] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:14:0)
[13:06:05.081] Build status: STARTED
[13:06:05.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:15:0) after 1m4s
[13:07:09.106] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:15:0)
[13:07:09.293] Build status: STARTED
[13:07:09.293] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:16:0) after 8m41s
[13:15:50.321] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:16:0)
[13:15:50.346] Build status: STARTED
[13:15:50.346] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:17:0) after 7m22s
[13:23:12.370] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:17:0)
[13:23:12.393] Build status: STARTED
[13:23:12.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:18:0) after 3m30s
[13:26:42.529] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:18:0)
[13:26:42.547] Build status: STARTED
[13:26:42.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:19:0) after 4m50s
[13:31:32.567] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:19:0)
[13:31:32.583] Build status: STARTED
[13:31:32.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:20:0) after 4m42s
[13:36:14.700] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:20:0)
[13:36:14.720] Build status: STARTED
[13:36:14.720] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:21:0) after 1m16s
[13:37:30.733] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:21:0)
[13:37:30.753] Build status: STARTED
[13:37:30.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:22:0) after 1m9s
[13:38:39.768] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:22:0)
[13:38:39.861] Build status: STARTED
[13:38:39.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:23:0) after 5m39s
[13:44:18.882] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:23:0)
[13:44:18.905] Build status: STARTED
[13:44:18.905] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:24:0) after 1m37s
[13:45:55.930] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:24:0)
[13:45:55.953] Build status: STARTED
[13:45:55.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:25:0) after 8m1s
[13:53:57.056] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:25:0)
[13:53:57.084] Build status: STARTED
[13:53:57.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:26:0) after 9m8s
[14:03:05.220] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:26:0)
[14:03:05.241] Build status: STARTED
[14:03:05.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:27:0) after 3m7s
[14:06:12.257] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:27:0)
[14:06:12.275] Build status: STARTED
[14:06:12.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:28:0) after 8m35s
[14:14:47.290] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:28:0)
[14:14:47.308] Build status: STARTED
[14:14:47.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:29:0) after 8m42s
[14:23:29.427] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:29:0)
[14:23:29.445] Build status: STARTED
[14:23:29.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:30:0) after 5m45s
[14:29:14.687] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:30:0)
[14:29:14.705] Build status: STARTED
[14:29:14.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:31:0) after 7m9s
[14:36:23.790] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:31:0)
[14:36:23.810] Build status: STARTED
[14:36:23.810] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:32:0) after 9m30s
[14:45:53.829] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:32:0)
[14:45:53.850] Build status: STARTED
[14:45:53.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:33:0) after 9m29s
[14:55:23.028] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:33:0)
[14:55:23.071] Build status: STARTED
[14:55:23.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:34:0) after 9m4s
[15:04:27.149] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:34:0)
[15:04:27.167] Build status: STARTED
[15:04:27.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:35:0) after 1m18s
[15:05:45.190] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:35:0)
[15:05:45.211] Build status: STARTED
[15:05:45.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:36:0) after 3m6s
[15:08:51.507] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:36:0)
[15:08:51.535] Build status: STARTED
[15:08:51.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:37:0) after 3m37s
[15:12:28.551] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:37:0)
[15:12:28.566] Build status: STARTED
[15:12:28.566] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:38:0) after 9m1s
[15:21:29.682] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:38:0)
[15:21:29.703] Build status: STARTED
[15:21:29.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:39:0) after 5m40s
[15:27:09.721] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:39:0)
[15:27:09.741] Build status: STARTED
[15:27:09.741] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:40:0) after 6m10s
[15:33:19.862] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:40:0)
[15:33:19.880] Build status: STARTED
[15:33:19.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:41:0) after 6m39s
[15:39:59.007] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:41:0)
[15:39:59.026] Build status: STARTED
[15:39:59.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:42:0) after 3m29s
[15:43:28.289] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:42:0)
[15:43:28.317] Build status: STARTED
[15:43:28.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:43:0) after 3m51s
[15:47:19.389] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:43:0)
[15:47:19.427] Build status: STARTED
[15:47:19.427] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:44:0) after 1m25s
[15:48:44.597] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:44:0)
[15:48:44.618] Build status: STARTED
[15:48:44.618] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:45:0) after 8m27s
[15:57:11.622] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:45:0)
[15:57:11.642] Build status: STARTED
[15:57:11.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:46:0) after 9m2s
[16:06:13.678] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:46:0)
[16:06:13.700] Build status: STARTED
[16:06:13.700] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:47:0) after 1m8s
[16:07:21.772] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:47:0)
[16:07:21.791] Build status: STARTED
[16:07:21.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:48:0) after 7m45s
[16:15:06.814] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:48:0)
[16:15:06.845] Build status: STARTED
[16:15:06.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:49:0) after 1m46s
[16:16:52.880] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:49:0)
[16:16:52.901] Build status: STARTED
[16:16:52.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:50:0) after 7m41s
[16:24:33.918] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:50:0)
[16:24:33.937] Build status: STARTED
[16:24:33.937] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:51:0) after 9m16s
[16:33:49.998] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:51:0)
[16:33:50.022] Build status: STARTED
[16:33:50.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8943472331364056176:52:0) after 4m48s
[16:38:23.296] Received PubSub notification, asking Buildbucket for the build status
[16:38:23.335] Build:
{
"id": "8778042269432855201",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-06-17T12:06:01.521399260Z",
"startTime": "2023-06-17T12:08:10.793896Z",
"endTime": "2023-06-17T16:38:22.628745572Z",
"updateTime": "2023-06-17T16:38:22.628745572Z",
"status": "CANCELED",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "85a491bdebf394567c4e526a718b230d23f09c04",
"ref": "refs/heads/main"
}
}
}
[16:38:23.335] Invocation finished in 4h32m23.039772487s with status ABORTED