[05:29:30.092] New invocation is queued and will start shortly
[05:29:31.232] Starting the invocation (attempt 1)
[05:29:31.266] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/e0e9d48a8e9c2b33e32b433b209a323f6f6ff0c0"
[05:29:31.266] Popped gitiles commit info from properties and tags
[05:29:31.266] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:29:31.266] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:29:31.291] Buildbucket request:
{
"requestId": "8958717567564329056",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8958717567564329056",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e40f3a19756d4804cabb7247fdecb94876096054"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e40f3a19756d4804cabb7247fdecb94876096054",
"title": "e40f3a19756d4804cabb7247fdecb94876096054",
"url": "https://fuchsia.googlesource.com/integration/+/e40f3a19756d4804cabb7247fdecb94876096054"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "aeacd875e70be04b712cd86bd4ebec2e0748b24d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@aeacd875e70be04b712cd86bd4ebec2e0748b24d",
"title": "aeacd875e70be04b712cd86bd4ebec2e0748b24d",
"url": "https://fuchsia.googlesource.com/integration/+/aeacd875e70be04b712cd86bd4ebec2e0748b24d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f42d16dfab353d2e815e26c2887a8d0660b51638"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f42d16dfab353d2e815e26c2887a8d0660b51638",
"title": "f42d16dfab353d2e815e26c2887a8d0660b51638",
"url": "https://fuchsia.googlesource.com/integration/+/f42d16dfab353d2e815e26c2887a8d0660b51638"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "75ad8b3723c913a2e6781c1bd946ca614f287d86"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@75ad8b3723c913a2e6781c1bd946ca614f287d86",
"title": "75ad8b3723c913a2e6781c1bd946ca614f287d86",
"url": "https://fuchsia.googlesource.com/integration/+/75ad8b3723c913a2e6781c1bd946ca614f287d86"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cb1ac0e7b106d9be9d5bc6e487a0801ef3baf406"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cb1ac0e7b106d9be9d5bc6e487a0801ef3baf406",
"title": "cb1ac0e7b106d9be9d5bc6e487a0801ef3baf406",
"url": "https://fuchsia.googlesource.com/integration/+/cb1ac0e7b106d9be9d5bc6e487a0801ef3baf406"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1bba36fdff4695899593a29bb45c58b7372ed33d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1bba36fdff4695899593a29bb45c58b7372ed33d",
"title": "1bba36fdff4695899593a29bb45c58b7372ed33d",
"url": "https://fuchsia.googlesource.com/integration/+/1bba36fdff4695899593a29bb45c58b7372ed33d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e8de2ba88889ec2ce500ec50d6907442c1549aa4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e8de2ba88889ec2ce500ec50d6907442c1549aa4",
"title": "e8de2ba88889ec2ce500ec50d6907442c1549aa4",
"url": "https://fuchsia.googlesource.com/integration/+/e8de2ba88889ec2ce500ec50d6907442c1549aa4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ef55a21679294ca606d2b7c0f1c24b1ed4244668"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ef55a21679294ca606d2b7c0f1c24b1ed4244668",
"title": "ef55a21679294ca606d2b7c0f1c24b1ed4244668",
"url": "https://fuchsia.googlesource.com/integration/+/ef55a21679294ca606d2b7c0f1c24b1ed4244668"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "76bab1054565106d08e89d39690ea6e8eeffad26"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@76bab1054565106d08e89d39690ea6e8eeffad26",
"title": "76bab1054565106d08e89d39690ea6e8eeffad26",
"url": "https://fuchsia.googlesource.com/integration/+/76bab1054565106d08e89d39690ea6e8eeffad26"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ce0b32f1aa08a79df75e24aff58a90b54e2caee0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ce0b32f1aa08a79df75e24aff58a90b54e2caee0",
"title": "ce0b32f1aa08a79df75e24aff58a90b54e2caee0",
"url": "https://fuchsia.googlesource.com/integration/+/ce0b32f1aa08a79df75e24aff58a90b54e2caee0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bd8a04f66dbd14e1a7f20b94079e78369eac8f62"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bd8a04f66dbd14e1a7f20b94079e78369eac8f62",
"title": "bd8a04f66dbd14e1a7f20b94079e78369eac8f62",
"url": "https://fuchsia.googlesource.com/integration/+/bd8a04f66dbd14e1a7f20b94079e78369eac8f62"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e0e9d48a8e9c2b33e32b433b209a323f6f6ff0c0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e0e9d48a8e9c2b33e32b433b209a323f6f6ff0c0",
"title": "e0e9d48a8e9c2b33e32b433b209a323f6f6ff0c0",
"url": "https://fuchsia.googlesource.com/integration/+/e0e9d48a8e9c2b33e32b433b209a323f6f6ff0c0"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "e0e9d48a8e9c2b33e32b433b209a323f6f6ff0c0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8958717567564329056"
},
{
"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"
}
}
[05:29:31.608] Scheduled build:
{
"id": "8793287505572822177",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-12-31T05:29:31.367576112Z",
"updateTime": "2022-12-31T05:29:31.367576112Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "e0e9d48a8e9c2b33e32b433b209a323f6f6ff0c0",
"ref": "refs/heads/main"
}
}
}
[05:29:31.608] Task URL: https://cr-buildbucket.appspot.com/build/8793287505572822177
[05:29:31.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:2:0) after 4m21s
[05:29:42.598] Received PubSub notification, asking Buildbucket for the build status
[05:29:42.619] Build status: STARTED
[05:33:52.625] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:2:0)
[05:33:52.650] Build status: STARTED
[05:33:52.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:4:0) after 6m50s
[05:40:42.669] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:4:0)
[05:40:42.695] Build status: STARTED
[05:40:42.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:5:0) after 5m14s
[05:45:56.713] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:5:0)
[05:45:56.751] Build status: STARTED
[05:45:56.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:6:0) after 1m33s
[05:47:29.768] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:6:0)
[05:47:29.788] Build status: STARTED
[05:47:29.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:7:0) after 7m39s
[05:55:08.834] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:7:0)
[05:55:08.854] Build status: STARTED
[05:55:08.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:8:0) after 3m44s
[05:58:52.827] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:8:0)
[05:58:52.849] Build status: STARTED
[05:58:52.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:9:0) after 8m23s
[06:07:15.983] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:9:0)
[06:07:16.078] Build status: STARTED
[06:07:16.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:10:0) after 9m36s
[06:16:52.192] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:10:0)
[06:16:52.228] Build status: STARTED
[06:16:52.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:11:0) after 3m38s
[06:20:30.427] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:11:0)
[06:20:30.455] Build status: STARTED
[06:20:30.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:12:0) after 9m32s
[06:30:02.484] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:12:0)
[06:30:02.512] Build status: STARTED
[06:30:02.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:13:0) after 4m19s
[06:34:21.528] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:13:0)
[06:34:21.562] Build status: STARTED
[06:34:21.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:14:0) after 6m3s
[06:40:24.579] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:14:0)
[06:40:24.604] Build status: STARTED
[06:40:24.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:15:0) after 2m14s
[06:42:38.641] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:15:0)
[06:42:38.666] Build status: STARTED
[06:42:38.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:16:0) after 7m4s
[06:49:42.687] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:16:0)
[06:49:42.707] Build status: STARTED
[06:49:42.707] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:17:0) after 4m17s
[06:53:59.756] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:17:0)
[06:53:59.777] Build status: STARTED
[06:53:59.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:18:0) after 1m49s
[06:55:48.796] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:18:0)
[06:55:48.817] Build status: STARTED
[06:55:48.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:19:0) after 5m36s
[07:01:24.793] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:19:0)
[07:01:24.816] Build status: STARTED
[07:01:24.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:20:0) after 1m45s
[07:03:09.842] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:20:0)
[07:03:09.873] Build status: STARTED
[07:03:09.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:21:0) after 5m42s
[07:08:51.897] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:21:0)
[07:08:51.920] Build status: STARTED
[07:08:51.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:22:0) after 7m25s
[07:16:16.982] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:22:0)
[07:16:17.046] Build status: STARTED
[07:16:17.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:23:0) after 6m45s
[07:23:02.514] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:23:0)
[07:23:02.535] Build status: STARTED
[07:23:02.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:24:0) after 1m17s
[07:24:19.578] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:24:0)
[07:24:19.608] Build status: STARTED
[07:24:19.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:25:0) after 6m4s
[07:30:23.872] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:25:0)
[07:30:23.893] Build status: STARTED
[07:30:23.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:26:0) after 1m37s
[07:32:00.879] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:26:0)
[07:32:00.903] Build status: STARTED
[07:32:00.903] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:27:0) after 6m47s
[07:38:47.924] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:27:0)
[07:38:47.947] Build status: STARTED
[07:38:47.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:28:0) after 3m58s
[07:42:45.965] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:28:0)
[07:42:45.991] Build status: STARTED
[07:42:45.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:29:0) after 7m52s
[07:50:38.020] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:29:0)
[07:50:38.041] Build status: STARTED
[07:50:38.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:30:0) after 4m34s
[07:55:12.045] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:30:0)
[07:55:12.069] Build status: STARTED
[07:55:12.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:31:0) after 9m22s
[08:04:34.091] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:31:0)
[08:04:34.116] Build status: STARTED
[08:04:34.116] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:32:0) after 2m8s
[08:06:42.159] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:32:0)
[08:06:42.180] Build status: STARTED
[08:06:42.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:33:0) after 5m19s
[08:12:01.301] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:33:0)
[08:12:01.357] Build status: STARTED
[08:12:01.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:34:0) after 9m43s
[08:21:44.478] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:34:0)
[08:21:44.505] Build status: STARTED
[08:21:44.505] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:35:0) after 6m18s
[08:28:02.646] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:35:0)
[08:28:02.670] Build status: STARTED
[08:28:02.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:36:0) after 4m59s
[08:33:01.714] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:36:0)
[08:33:01.736] Build status: STARTED
[08:33:01.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8958717567564329056:37:0) after 9m13s
[08:35:06.573] Received PubSub notification, asking Buildbucket for the build status
[08:35:06.595] Build:
{
"id": "8793287505572822177",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-12-31T05:29:31.367576112Z",
"startTime": "2022-12-31T05:29:41.447378Z",
"endTime": "2022-12-31T08:35:05.749400555Z",
"updateTime": "2022-12-31T08:35:05.749400555Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "e0e9d48a8e9c2b33e32b433b209a323f6f6ff0c0",
"ref": "refs/heads/main"
}
}
}
[08:35:06.595] Invocation finished in 3h5m36.510650825s with status SUCCEEDED