[14:12:50.155] New invocation is queued and will start shortly
[14:12:51.310] Starting the invocation (attempt 1)
[14:12:51.341] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/6867a41e7c7c7da40308800fc264bcb07a25efc0"
[14:12:51.341] Popped gitiles commit info from properties and tags
[14:12:51.341] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:12:51.341] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:12:51.342] Buildbucket request:
{
"requestId": "8958503448284700480",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8958503448284700480",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3b8bbb2d9e5ccd933b6bde3881be92df09d9f421"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3b8bbb2d9e5ccd933b6bde3881be92df09d9f421",
"title": "3b8bbb2d9e5ccd933b6bde3881be92df09d9f421",
"url": "https://fuchsia.googlesource.com/integration/+/3b8bbb2d9e5ccd933b6bde3881be92df09d9f421"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "83145f29d8661168c133a3bfc1be10a426d53cc4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@83145f29d8661168c133a3bfc1be10a426d53cc4",
"title": "83145f29d8661168c133a3bfc1be10a426d53cc4",
"url": "https://fuchsia.googlesource.com/integration/+/83145f29d8661168c133a3bfc1be10a426d53cc4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f95afbc22003553c564863a31c9a3fef910b7b2f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f95afbc22003553c564863a31c9a3fef910b7b2f",
"title": "f95afbc22003553c564863a31c9a3fef910b7b2f",
"url": "https://fuchsia.googlesource.com/integration/+/f95afbc22003553c564863a31c9a3fef910b7b2f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "04e5f47c7c42a61d9eaee055a6ec26ab355a63fd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@04e5f47c7c42a61d9eaee055a6ec26ab355a63fd",
"title": "04e5f47c7c42a61d9eaee055a6ec26ab355a63fd",
"url": "https://fuchsia.googlesource.com/integration/+/04e5f47c7c42a61d9eaee055a6ec26ab355a63fd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6867a41e7c7c7da40308800fc264bcb07a25efc0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6867a41e7c7c7da40308800fc264bcb07a25efc0",
"title": "6867a41e7c7c7da40308800fc264bcb07a25efc0",
"url": "https://fuchsia.googlesource.com/integration/+/6867a41e7c7c7da40308800fc264bcb07a25efc0"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6867a41e7c7c7da40308800fc264bcb07a25efc0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8958503448284700480"
},
{
"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"
}
}
[14:12:51.683] Scheduled build:
{
"id": "8793073386304104145",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-01-02T14:12:51.414604517Z",
"updateTime": "2023-01-02T14:12:51.414604517Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6867a41e7c7c7da40308800fc264bcb07a25efc0",
"ref": "refs/heads/main"
}
}
}
[14:12:51.683] Task URL: https://cr-buildbucket.appspot.com/build/8793073386304104145
[14:12:51.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:2:0) after 8m5s
[14:13:09.123] Received PubSub notification, asking Buildbucket for the build status
[14:13:09.157] Build status: STARTED
[14:20:56.811] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:2:0)
[14:20:56.845] Build status: STARTED
[14:20:56.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:4:0) after 9m16s
[14:30:12.886] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:4:0)
[14:30:12.967] Build status: STARTED
[14:30:12.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:5:0) after 6m1s
[14:36:14.537] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:5:0)
[14:36:14.563] Build status: STARTED
[14:36:14.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:6:0) after 5m52s
[14:42:07.188] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:6:0)
[14:42:07.210] Build status: STARTED
[14:42:07.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:7:0) after 4m4s
[14:46:11.234] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:7:0)
[14:46:11.274] Build status: STARTED
[14:46:11.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:8:0) after 1m48s
[14:47:59.294] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:8:0)
[14:47:59.319] Build status: STARTED
[14:47:59.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:9:0) after 1m56s
[14:49:55.341] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:9:0)
[14:49:55.365] Build status: STARTED
[14:49:55.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:10:0) after 8m32s
[14:58:27.383] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:10:0)
[14:58:27.407] Build status: STARTED
[14:58:27.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:11:0) after 5m55s
[15:04:22.479] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:11:0)
[15:04:22.501] Build status: STARTED
[15:04:22.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:12:0) after 4m12s
[15:08:34.645] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:12:0)
[15:08:34.680] Build status: STARTED
[15:08:34.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:13:0) after 2m44s
[15:11:19.141] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:13:0)
[15:11:19.179] Build status: STARTED
[15:11:19.179] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:14:0) after 9m30s
[15:20:49.206] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:14:0)
[15:20:49.234] Build status: STARTED
[15:20:49.234] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:15:0) after 3m14s
[15:24:03.582] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:15:0)
[15:24:03.606] Build status: STARTED
[15:24:03.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:16:0) after 7m58s
[15:32:01.729] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:16:0)
[15:32:01.755] Build status: STARTED
[15:32:01.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:17:0) after 3m12s
[15:35:14.095] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:17:0)
[15:35:14.120] Build status: STARTED
[15:35:14.120] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:18:0) after 8m44s
[15:43:58.141] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:18:0)
[15:43:58.239] Build status: STARTED
[15:43:58.239] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:19:0) after 2m58s
[15:46:56.286] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:19:0)
[15:46:56.306] Build status: STARTED
[15:46:56.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:20:0) after 9m59s
[15:56:55.596] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:20:0)
[15:56:55.642] Build status: STARTED
[15:56:55.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:21:0) after 6m13s
[16:03:08.664] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:21:0)
[16:03:08.868] Build status: STARTED
[16:03:08.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:22:0) after 7m47s
[16:10:55.851] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:22:0)
[16:10:55.878] Build status: STARTED
[16:10:55.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:23:0) after 7m40s
[16:18:36.011] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:23:0)
[16:18:36.035] Build status: STARTED
[16:18:36.035] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:24:0) after 7m3s
[16:25:39.250] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:24:0)
[16:25:39.281] Build status: STARTED
[16:25:39.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:25:0) after 8m36s
[16:34:15.740] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:25:0)
[16:34:15.775] Build status: STARTED
[16:34:15.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:26:0) after 2m2s
[16:36:17.968] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:26:0)
[16:36:18.052] Build status: STARTED
[16:36:18.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:27:0) after 3m54s
[16:40:12.222] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:27:0)
[16:40:12.245] Build status: STARTED
[16:40:12.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:28:0) after 7m42s
[16:47:54.436] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:28:0)
[16:47:54.463] Build status: STARTED
[16:47:54.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:29:0) after 1m35s
[16:49:29.729] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:29:0)
[16:49:29.753] Build status: STARTED
[16:49:29.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:30:0) after 7m2s
[16:56:32.008] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:30:0)
[16:56:32.029] Build status: STARTED
[16:56:32.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:31:0) after 1m18s
[16:57:50.055] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:31:0)
[16:57:50.078] Build status: STARTED
[16:57:50.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:32:0) after 1m54s
[16:59:44.108] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:32:0)
[16:59:44.134] Build status: STARTED
[16:59:44.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:33:0) after 2m44s
[17:02:28.193] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:33:0)
[17:02:28.217] Build status: STARTED
[17:02:28.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:34:0) after 9m19s
[17:11:47.237] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:34:0)
[17:11:47.260] Build status: STARTED
[17:11:47.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958503448284700480:35:0) after 8m1s
[17:18:25.399] Received PubSub notification, asking Buildbucket for the build status
[17:18:25.427] Build:
{
"id": "8793073386304104145",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-01-02T14:12:51.414604517Z",
"startTime": "2023-01-02T14:13:08.542531Z",
"endTime": "2023-01-02T17:18:25.149636389Z",
"updateTime": "2023-01-02T17:18:25.149636389Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6867a41e7c7c7da40308800fc264bcb07a25efc0",
"ref": "refs/heads/main"
}
}
}
[17:18:25.427] Invocation finished in 3h5m35.284406149s with status SUCCEEDED