[23:03:00.328] New invocation is queued and will start shortly
[23:03:01.497] Starting the invocation (attempt 1)
[23:03:01.529] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/b7d5d7f518a9712b1ff799eddebd6255f168e2cd"
[23:03:01.529] Popped gitiles commit info from properties and tags
[23:03:01.529] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:03:01.529] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:03:01.531] Buildbucket request:
{
"requestId": "8929750854544297488",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8929750854544297488",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d12c73846998ea3ee5fe8c261f9cb6b44f75fe59"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d12c73846998ea3ee5fe8c261f9cb6b44f75fe59",
"title": "d12c73846998ea3ee5fe8c261f9cb6b44f75fe59",
"url": "https://fuchsia.googlesource.com/integration/+/d12c73846998ea3ee5fe8c261f9cb6b44f75fe59"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c78ef64b064bd7457dbbaa07ff69dc66706be535"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c78ef64b064bd7457dbbaa07ff69dc66706be535",
"title": "c78ef64b064bd7457dbbaa07ff69dc66706be535",
"url": "https://fuchsia.googlesource.com/integration/+/c78ef64b064bd7457dbbaa07ff69dc66706be535"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "31a17ccc3b2a33a89a13e1b6d965b5a787948c1f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@31a17ccc3b2a33a89a13e1b6d965b5a787948c1f",
"title": "31a17ccc3b2a33a89a13e1b6d965b5a787948c1f",
"url": "https://fuchsia.googlesource.com/integration/+/31a17ccc3b2a33a89a13e1b6d965b5a787948c1f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1dd7a429cd5c1b7442e4d9d83ced43c821317b43"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1dd7a429cd5c1b7442e4d9d83ced43c821317b43",
"title": "1dd7a429cd5c1b7442e4d9d83ced43c821317b43",
"url": "https://fuchsia.googlesource.com/integration/+/1dd7a429cd5c1b7442e4d9d83ced43c821317b43"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "777389ff6fbea5a12d5953eb5df4ccdba33f8174"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@777389ff6fbea5a12d5953eb5df4ccdba33f8174",
"title": "777389ff6fbea5a12d5953eb5df4ccdba33f8174",
"url": "https://fuchsia.googlesource.com/integration/+/777389ff6fbea5a12d5953eb5df4ccdba33f8174"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ee1f447dc6891ce839a6385a6a191a71c3c1e097"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ee1f447dc6891ce839a6385a6a191a71c3c1e097",
"title": "ee1f447dc6891ce839a6385a6a191a71c3c1e097",
"url": "https://fuchsia.googlesource.com/integration/+/ee1f447dc6891ce839a6385a6a191a71c3c1e097"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "73b890cb81595a020291ce5b84e86eb00c053991"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@73b890cb81595a020291ce5b84e86eb00c053991",
"title": "73b890cb81595a020291ce5b84e86eb00c053991",
"url": "https://fuchsia.googlesource.com/integration/+/73b890cb81595a020291ce5b84e86eb00c053991"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fb63e36f2cda377f488dd6e43194f0b34edd5d3f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fb63e36f2cda377f488dd6e43194f0b34edd5d3f",
"title": "fb63e36f2cda377f488dd6e43194f0b34edd5d3f",
"url": "https://fuchsia.googlesource.com/integration/+/fb63e36f2cda377f488dd6e43194f0b34edd5d3f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "eff7d5aa8ed3614a71ce562600a0b9df3902bc4c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@eff7d5aa8ed3614a71ce562600a0b9df3902bc4c",
"title": "eff7d5aa8ed3614a71ce562600a0b9df3902bc4c",
"url": "https://fuchsia.googlesource.com/integration/+/eff7d5aa8ed3614a71ce562600a0b9df3902bc4c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5c9d864b6d91b8e5c584c238067744f0803d917a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5c9d864b6d91b8e5c584c238067744f0803d917a",
"title": "5c9d864b6d91b8e5c584c238067744f0803d917a",
"url": "https://fuchsia.googlesource.com/integration/+/5c9d864b6d91b8e5c584c238067744f0803d917a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "db1cc3861fde70833de22805064a4ffc49163c47"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@db1cc3861fde70833de22805064a4ffc49163c47",
"title": "db1cc3861fde70833de22805064a4ffc49163c47",
"url": "https://fuchsia.googlesource.com/integration/+/db1cc3861fde70833de22805064a4ffc49163c47"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1a430cb06f53fad74ee96a46cddf00a0f1e33d64"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1a430cb06f53fad74ee96a46cddf00a0f1e33d64",
"title": "1a430cb06f53fad74ee96a46cddf00a0f1e33d64",
"url": "https://fuchsia.googlesource.com/integration/+/1a430cb06f53fad74ee96a46cddf00a0f1e33d64"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "902fc9f95bf26acff2df0682fc362897af532599"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@902fc9f95bf26acff2df0682fc362897af532599",
"title": "902fc9f95bf26acff2df0682fc362897af532599",
"url": "https://fuchsia.googlesource.com/integration/+/902fc9f95bf26acff2df0682fc362897af532599"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c152cdaf08dcfeeaf3e49255e2ed749da7769c7c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c152cdaf08dcfeeaf3e49255e2ed749da7769c7c",
"title": "c152cdaf08dcfeeaf3e49255e2ed749da7769c7c",
"url": "https://fuchsia.googlesource.com/integration/+/c152cdaf08dcfeeaf3e49255e2ed749da7769c7c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7c3adf2fecea1bf7df52b06732204130b1797bf3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7c3adf2fecea1bf7df52b06732204130b1797bf3",
"title": "7c3adf2fecea1bf7df52b06732204130b1797bf3",
"url": "https://fuchsia.googlesource.com/integration/+/7c3adf2fecea1bf7df52b06732204130b1797bf3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ad3d93ede62879917c3f0e7605714094b2200af4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ad3d93ede62879917c3f0e7605714094b2200af4",
"title": "ad3d93ede62879917c3f0e7605714094b2200af4",
"url": "https://fuchsia.googlesource.com/integration/+/ad3d93ede62879917c3f0e7605714094b2200af4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6fa6ce0d50e10750c18fabb125312bcfe45bf2f2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6fa6ce0d50e10750c18fabb125312bcfe45bf2f2",
"title": "6fa6ce0d50e10750c18fabb125312bcfe45bf2f2",
"url": "https://fuchsia.googlesource.com/integration/+/6fa6ce0d50e10750c18fabb125312bcfe45bf2f2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b7d5d7f518a9712b1ff799eddebd6255f168e2cd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b7d5d7f518a9712b1ff799eddebd6255f168e2cd",
"title": "b7d5d7f518a9712b1ff799eddebd6255f168e2cd",
"url": "https://fuchsia.googlesource.com/integration/+/b7d5d7f518a9712b1ff799eddebd6255f168e2cd"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b7d5d7f518a9712b1ff799eddebd6255f168e2cd",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8929750854544297488"
},
{
"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"
}
}
[23:03:02.105] Scheduled build:
{
"id": "8764320792509313425",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-15T23:03:01.646565670Z",
"updateTime": "2023-11-15T23:03:01.646565670Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b7d5d7f518a9712b1ff799eddebd6255f168e2cd",
"ref": "refs/heads/main"
}
}
}
[23:03:02.105] Task URL: https://cr-buildbucket.appspot.com/build/8764320792509313425
[23:03:02.105] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:2:0) after 3m19s
[23:03:03.026] Received PubSub notification, asking Buildbucket for the build status
[23:03:03.046] Build status: SCHEDULED
[23:03:03.612] Received PubSub notification, asking Buildbucket for the build status
[23:03:03.637] Build status: STARTED
[23:06:21.164] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:2:0)
[23:06:21.236] Build status: STARTED
[23:06:21.237] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:5:0) after 2m33s
[23:08:54.261] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:5:0)
[23:08:54.283] Build status: STARTED
[23:08:54.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:6:0) after 9m15s
[23:18:09.301] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:6:0)
[23:18:09.341] Build status: STARTED
[23:18:09.342] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:7:0) after 8m12s
[23:26:21.362] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:7:0)
[23:26:21.382] Build status: STARTED
[23:26:21.383] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:8:0) after 3m21s
[23:29:42.363] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:8:0)
[23:29:42.379] Build status: STARTED
[23:29:42.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:9:0) after 8m31s
[23:38:13.405] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:9:0)
[23:38:13.433] Build status: STARTED
[23:38:13.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:10:0) after 4m35s
[23:42:48.638] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:10:0)
[23:42:48.656] Build status: STARTED
[23:42:48.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:11:0) after 6m20s
[23:49:08.695] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:11:0)
[23:49:08.770] Build status: STARTED
[23:49:08.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:12:0) after 7m40s
[23:56:48.790] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:12:0)
[23:56:48.813] Build status: STARTED
[23:56:48.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:13:0) after 3m48s
[00:00:36.918] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:13:0)
[00:00:36.948] Build status: STARTED
[00:00:36.948] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:14:0) after 4m15s
[00:04:52.227] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:14:0)
[00:04:52.248] Build status: STARTED
[00:04:52.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:15:0) after 2m19s
[00:07:11.254] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:15:0)
[00:07:11.276] Build status: STARTED
[00:07:11.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:16:0) after 6m52s
[00:14:03.488] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:16:0)
[00:14:03.515] Build status: STARTED
[00:14:03.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:17:0) after 9m8s
[00:23:11.537] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:17:0)
[00:23:11.566] Build status: STARTED
[00:23:11.566] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:18:0) after 6m3s
[00:29:14.754] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:18:0)
[00:29:14.773] Build status: STARTED
[00:29:14.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:19:0) after 3m17s
[00:32:32.041] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:19:0)
[00:32:32.060] Build status: STARTED
[00:32:32.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:20:0) after 3m10s
[00:35:42.164] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:20:0)
[00:35:42.199] Build status: STARTED
[00:35:42.199] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:21:0) after 3m50s
[00:39:32.384] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:21:0)
[00:39:32.471] Build status: STARTED
[00:39:32.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:22:0) after 4m56s
[00:44:29.115] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:22:0)
[00:44:29.133] Build status: STARTED
[00:44:29.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:23:0) after 1m49s
[00:46:18.170] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:23:0)
[00:46:18.240] Build status: STARTED
[00:46:18.240] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:24:0) after 1m3s
[00:47:21.348] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:24:0)
[00:47:21.448] Build status: STARTED
[00:47:21.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:25:0) after 1m52s
[00:49:13.468] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:25:0)
[00:49:13.491] Build status: STARTED
[00:49:13.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:26:0) after 6m7s
[00:55:20.526] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:26:0)
[00:55:20.550] Build status: STARTED
[00:55:20.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:27:0) after 2m16s
[00:57:36.656] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:27:0)
[00:57:36.728] Build status: STARTED
[00:57:36.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:28:0) after 6m9s
[01:03:45.758] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:28:0)
[01:03:45.804] Build status: STARTED
[01:03:45.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:29:0) after 8m18s
[01:12:04.014] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:29:0)
[01:12:04.048] Build status: STARTED
[01:12:04.048] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:30:0) after 6m14s
[01:18:18.303] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:30:0)
[01:18:18.322] Build status: STARTED
[01:18:18.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8929750854544297488:31:0) after 9m55s
[01:22:13.640] Received PubSub notification, asking Buildbucket for the build status
[01:22:13.664] Build:
{
"id": "8764320792509313425",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-15T23:03:01.646565670Z",
"startTime": "2023-11-15T23:03:02.682517Z",
"endTime": "2023-11-16T01:22:07.208406Z",
"updateTime": "2023-11-16T01:22:07.208406Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b7d5d7f518a9712b1ff799eddebd6255f168e2cd",
"ref": "refs/heads/main"
}
}
}
[01:22:13.664] Invocation finished in 2h19m13.342518542s with status SUCCEEDED