[19:45:53.489] New invocation is queued and will start shortly
[19:45:54.796] Starting the invocation (attempt 1)
[19:45:54.828] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/2a4d83dd2bb3d523128fc327dc7dfa9cf7b97983"
[19:45:54.828] Popped gitiles commit info from properties and tags
[19:45:54.828] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:45:54.828] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:45:54.829] Buildbucket request:
{
"requestId": "8934383701170672240",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8934383701170672240",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c1d558f81ae9f6d32900c995822233521a99835b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c1d558f81ae9f6d32900c995822233521a99835b",
"title": "c1d558f81ae9f6d32900c995822233521a99835b",
"url": "https://fuchsia.googlesource.com/integration/+/c1d558f81ae9f6d32900c995822233521a99835b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9ccdd9f5cfd8bca06beef5795c2d854521d59a4e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9ccdd9f5cfd8bca06beef5795c2d854521d59a4e",
"title": "9ccdd9f5cfd8bca06beef5795c2d854521d59a4e",
"url": "https://fuchsia.googlesource.com/integration/+/9ccdd9f5cfd8bca06beef5795c2d854521d59a4e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "61523ac661c87f1e9602ff69661710143204c0c3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@61523ac661c87f1e9602ff69661710143204c0c3",
"title": "61523ac661c87f1e9602ff69661710143204c0c3",
"url": "https://fuchsia.googlesource.com/integration/+/61523ac661c87f1e9602ff69661710143204c0c3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "30aa63bbace86fe9cc9e727efbc8f37cad1e055b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@30aa63bbace86fe9cc9e727efbc8f37cad1e055b",
"title": "30aa63bbace86fe9cc9e727efbc8f37cad1e055b",
"url": "https://fuchsia.googlesource.com/integration/+/30aa63bbace86fe9cc9e727efbc8f37cad1e055b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6e971c6a50813004a9d96bbb94064f67302922c5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6e971c6a50813004a9d96bbb94064f67302922c5",
"title": "6e971c6a50813004a9d96bbb94064f67302922c5",
"url": "https://fuchsia.googlesource.com/integration/+/6e971c6a50813004a9d96bbb94064f67302922c5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e8f851e292f40b473e44a78accd047093d55a89b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e8f851e292f40b473e44a78accd047093d55a89b",
"title": "e8f851e292f40b473e44a78accd047093d55a89b",
"url": "https://fuchsia.googlesource.com/integration/+/e8f851e292f40b473e44a78accd047093d55a89b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e79aa44c25636267ccefbd8925fcdcbc35d1e042"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e79aa44c25636267ccefbd8925fcdcbc35d1e042",
"title": "e79aa44c25636267ccefbd8925fcdcbc35d1e042",
"url": "https://fuchsia.googlesource.com/integration/+/e79aa44c25636267ccefbd8925fcdcbc35d1e042"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2dfb9a1a046e6dc6f33a5c506e4691d174eb8f88"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2dfb9a1a046e6dc6f33a5c506e4691d174eb8f88",
"title": "2dfb9a1a046e6dc6f33a5c506e4691d174eb8f88",
"url": "https://fuchsia.googlesource.com/integration/+/2dfb9a1a046e6dc6f33a5c506e4691d174eb8f88"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f8b784a93afb201d65177855248d6193e46481c5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f8b784a93afb201d65177855248d6193e46481c5",
"title": "f8b784a93afb201d65177855248d6193e46481c5",
"url": "https://fuchsia.googlesource.com/integration/+/f8b784a93afb201d65177855248d6193e46481c5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "75cb377600cbcaf283134397ee195575ae34ff85"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@75cb377600cbcaf283134397ee195575ae34ff85",
"title": "75cb377600cbcaf283134397ee195575ae34ff85",
"url": "https://fuchsia.googlesource.com/integration/+/75cb377600cbcaf283134397ee195575ae34ff85"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "261dd7fe7365e298e4e35b6c5610ecbbe8f60fbf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@261dd7fe7365e298e4e35b6c5610ecbbe8f60fbf",
"title": "261dd7fe7365e298e4e35b6c5610ecbbe8f60fbf",
"url": "https://fuchsia.googlesource.com/integration/+/261dd7fe7365e298e4e35b6c5610ecbbe8f60fbf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ec249f9aef93b52790dbcda1131224885121b530"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ec249f9aef93b52790dbcda1131224885121b530",
"title": "ec249f9aef93b52790dbcda1131224885121b530",
"url": "https://fuchsia.googlesource.com/integration/+/ec249f9aef93b52790dbcda1131224885121b530"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "644c6536b2ae5554573166f4dab711bde6899761"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@644c6536b2ae5554573166f4dab711bde6899761",
"title": "644c6536b2ae5554573166f4dab711bde6899761",
"url": "https://fuchsia.googlesource.com/integration/+/644c6536b2ae5554573166f4dab711bde6899761"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b9c9f4f9552f0ed2be4b497f33176d36320aabb5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b9c9f4f9552f0ed2be4b497f33176d36320aabb5",
"title": "b9c9f4f9552f0ed2be4b497f33176d36320aabb5",
"url": "https://fuchsia.googlesource.com/integration/+/b9c9f4f9552f0ed2be4b497f33176d36320aabb5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0b5995f0ff14dce78d941b87277cc5e969165161"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0b5995f0ff14dce78d941b87277cc5e969165161",
"title": "0b5995f0ff14dce78d941b87277cc5e969165161",
"url": "https://fuchsia.googlesource.com/integration/+/0b5995f0ff14dce78d941b87277cc5e969165161"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "29a049290ddcafc50063937eb99e3d39781400a1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@29a049290ddcafc50063937eb99e3d39781400a1",
"title": "29a049290ddcafc50063937eb99e3d39781400a1",
"url": "https://fuchsia.googlesource.com/integration/+/29a049290ddcafc50063937eb99e3d39781400a1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "34c318d4621f36e9bf6c7e827ce873b2f9938892"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@34c318d4621f36e9bf6c7e827ce873b2f9938892",
"title": "34c318d4621f36e9bf6c7e827ce873b2f9938892",
"url": "https://fuchsia.googlesource.com/integration/+/34c318d4621f36e9bf6c7e827ce873b2f9938892"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2a4d83dd2bb3d523128fc327dc7dfa9cf7b97983"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2a4d83dd2bb3d523128fc327dc7dfa9cf7b97983",
"title": "2a4d83dd2bb3d523128fc327dc7dfa9cf7b97983",
"url": "https://fuchsia.googlesource.com/integration/+/2a4d83dd2bb3d523128fc327dc7dfa9cf7b97983"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2a4d83dd2bb3d523128fc327dc7dfa9cf7b97983",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8934383701170672240"
},
{
"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"
}
}
[19:45:55.355] Scheduled build:
{
"id": "8768953639016512353",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-25T19:45:54.920368317Z",
"updateTime": "2023-09-25T19:45:54.920368317Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2a4d83dd2bb3d523128fc327dc7dfa9cf7b97983",
"ref": "refs/heads/main"
}
}
}
[19:45:55.355] Task URL: https://cr-buildbucket.appspot.com/build/8768953639016512353
[19:45:55.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:2:0) after 5m30s
[19:45:56.268] Received PubSub notification, asking Buildbucket for the build status
[19:45:56.285] Build status: SCHEDULED
[19:46:32.056] Received PubSub notification, asking Buildbucket for the build status
[19:46:32.076] Build status: STARTED
[19:51:25.372] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:2:0)
[19:51:25.394] Build status: STARTED
[19:51:25.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:5:0) after 9m36s
[20:01:01.472] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:5:0)
[20:01:01.507] Build status: STARTED
[20:01:01.507] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:6:0) after 3m18s
[20:04:20.086] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:6:0)
[20:04:20.109] Build status: STARTED
[20:04:20.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:7:0) after 6m29s
[20:10:49.126] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:7:0)
[20:10:49.146] Build status: STARTED
[20:10:49.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:8:0) after 6m41s
[20:17:30.506] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:8:0)
[20:17:30.531] Build status: STARTED
[20:17:30.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:9:0) after 1m59s
[20:19:29.754] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:9:0)
[20:19:29.789] Build status: STARTED
[20:19:29.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:10:0) after 8m53s
[20:28:23.016] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:10:0)
[20:28:23.039] Build status: STARTED
[20:28:23.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:11:0) after 8m5s
[20:36:28.071] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:11:0)
[20:36:28.087] Build status: STARTED
[20:36:28.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:12:0) after 3m31s
[20:39:59.104] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:12:0)
[20:39:59.144] Build status: STARTED
[20:39:59.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:13:0) after 9m23s
[20:49:22.182] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:13:0)
[20:49:22.199] Build status: STARTED
[20:49:22.199] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:14:0) after 2m48s
[20:52:10.281] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:14:0)
[20:52:10.304] Build status: STARTED
[20:52:10.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:15:0) after 7m37s
[20:59:47.722] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:15:0)
[20:59:47.743] Build status: STARTED
[20:59:47.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:16:0) after 4m39s
[21:04:26.759] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:16:0)
[21:04:26.775] Build status: STARTED
[21:04:26.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:17:0) after 1m4s
[21:05:30.797] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:17:0)
[21:05:30.815] Build status: STARTED
[21:05:30.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:18:0) after 1m36s
[21:07:06.855] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:18:0)
[21:07:06.895] Build status: STARTED
[21:07:06.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:19:0) after 8m47s
[21:15:53.984] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:19:0)
[21:15:54.001] Build status: STARTED
[21:15:54.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:20:0) after 6m54s
[21:22:48.034] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:20:0)
[21:22:48.057] Build status: STARTED
[21:22:48.057] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:21:0) after 9m42s
[21:32:30.136] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:21:0)
[21:32:30.161] Build status: STARTED
[21:32:30.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:22:0) after 2m29s
[21:34:59.183] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:22:0)
[21:34:59.201] Build status: STARTED
[21:34:59.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:23:0) after 4m40s
[21:39:39.226] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:23:0)
[21:39:39.244] Build status: STARTED
[21:39:39.244] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8934383701170672240:24:0) after 7m56s
[21:40:47.624] Received PubSub notification, asking Buildbucket for the build status
[21:40:47.643] Build:
{
"id": "8768953639016512353",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-25T19:45:54.920368317Z",
"startTime": "2023-09-25T19:46:31.609975Z",
"endTime": "2023-09-25T21:40:47.087089802Z",
"updateTime": "2023-09-25T21:40:47.087089802Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2a4d83dd2bb3d523128fc327dc7dfa9cf7b97983",
"ref": "refs/heads/main"
}
}
}
[21:40:47.643] Invocation finished in 1h54m54.16165389s with status SUCCEEDED