[19:31:32.281] New invocation is queued and will start shortly
[19:31:33.427] Starting the invocation (attempt 1)
[19:31:33.457] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/d5f68fad0d512524edfd117ba39e41bcb3417e22"
[19:31:33.457] Popped gitiles commit info from properties and tags
[19:31:33.457] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:31:33.458] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:31:33.459] Buildbucket request:
{
"requestId": "8935562364782152896",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8935562364782152896",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4e2ea3e00de32f23a347293339d76f28d770adef"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4e2ea3e00de32f23a347293339d76f28d770adef",
"title": "4e2ea3e00de32f23a347293339d76f28d770adef",
"url": "https://fuchsia.googlesource.com/integration/+/4e2ea3e00de32f23a347293339d76f28d770adef"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b00ae4df62d6735f42a685e510fcd6d3ec68c518"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b00ae4df62d6735f42a685e510fcd6d3ec68c518",
"title": "b00ae4df62d6735f42a685e510fcd6d3ec68c518",
"url": "https://fuchsia.googlesource.com/integration/+/b00ae4df62d6735f42a685e510fcd6d3ec68c518"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "50502d314fc5c0afdfad5b44f7306454d252e8e2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@50502d314fc5c0afdfad5b44f7306454d252e8e2",
"title": "50502d314fc5c0afdfad5b44f7306454d252e8e2",
"url": "https://fuchsia.googlesource.com/integration/+/50502d314fc5c0afdfad5b44f7306454d252e8e2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "385181f7840b45b08990bbf595d08019bb45a4ca"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@385181f7840b45b08990bbf595d08019bb45a4ca",
"title": "385181f7840b45b08990bbf595d08019bb45a4ca",
"url": "https://fuchsia.googlesource.com/integration/+/385181f7840b45b08990bbf595d08019bb45a4ca"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e4fc3afcad9a5d8f25e243b52ed73f4373c8f754"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e4fc3afcad9a5d8f25e243b52ed73f4373c8f754",
"title": "e4fc3afcad9a5d8f25e243b52ed73f4373c8f754",
"url": "https://fuchsia.googlesource.com/integration/+/e4fc3afcad9a5d8f25e243b52ed73f4373c8f754"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ef31978c174f199bfbce8a3f2634b8e26383dabe"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ef31978c174f199bfbce8a3f2634b8e26383dabe",
"title": "ef31978c174f199bfbce8a3f2634b8e26383dabe",
"url": "https://fuchsia.googlesource.com/integration/+/ef31978c174f199bfbce8a3f2634b8e26383dabe"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c343d0c8fb86acd326705a1afabd71684fd9b305"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c343d0c8fb86acd326705a1afabd71684fd9b305",
"title": "c343d0c8fb86acd326705a1afabd71684fd9b305",
"url": "https://fuchsia.googlesource.com/integration/+/c343d0c8fb86acd326705a1afabd71684fd9b305"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "29d2f33e4d115aa701020a4d5e482b27a9df7545"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@29d2f33e4d115aa701020a4d5e482b27a9df7545",
"title": "29d2f33e4d115aa701020a4d5e482b27a9df7545",
"url": "https://fuchsia.googlesource.com/integration/+/29d2f33e4d115aa701020a4d5e482b27a9df7545"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4837146c58beca48e6a3a680b33a4aea0a732c0f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4837146c58beca48e6a3a680b33a4aea0a732c0f",
"title": "4837146c58beca48e6a3a680b33a4aea0a732c0f",
"url": "https://fuchsia.googlesource.com/integration/+/4837146c58beca48e6a3a680b33a4aea0a732c0f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3100a2caced4d92c1462f9fe65eca4bcc39a8e7b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3100a2caced4d92c1462f9fe65eca4bcc39a8e7b",
"title": "3100a2caced4d92c1462f9fe65eca4bcc39a8e7b",
"url": "https://fuchsia.googlesource.com/integration/+/3100a2caced4d92c1462f9fe65eca4bcc39a8e7b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "66d1d99eb4e29d0efac538b1214c02c551546ece"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@66d1d99eb4e29d0efac538b1214c02c551546ece",
"title": "66d1d99eb4e29d0efac538b1214c02c551546ece",
"url": "https://fuchsia.googlesource.com/integration/+/66d1d99eb4e29d0efac538b1214c02c551546ece"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "144d06369a1ef20f1abbf9f85f9304efa2152f0a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@144d06369a1ef20f1abbf9f85f9304efa2152f0a",
"title": "144d06369a1ef20f1abbf9f85f9304efa2152f0a",
"url": "https://fuchsia.googlesource.com/integration/+/144d06369a1ef20f1abbf9f85f9304efa2152f0a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d5783bded89a71cde6012202a7fd8a0927167612"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d5783bded89a71cde6012202a7fd8a0927167612",
"title": "d5783bded89a71cde6012202a7fd8a0927167612",
"url": "https://fuchsia.googlesource.com/integration/+/d5783bded89a71cde6012202a7fd8a0927167612"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9e7e89f7431b8da13881c0ef17003febb4cc88a6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9e7e89f7431b8da13881c0ef17003febb4cc88a6",
"title": "9e7e89f7431b8da13881c0ef17003febb4cc88a6",
"url": "https://fuchsia.googlesource.com/integration/+/9e7e89f7431b8da13881c0ef17003febb4cc88a6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d5f68fad0d512524edfd117ba39e41bcb3417e22"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d5f68fad0d512524edfd117ba39e41bcb3417e22",
"title": "d5f68fad0d512524edfd117ba39e41bcb3417e22",
"url": "https://fuchsia.googlesource.com/integration/+/d5f68fad0d512524edfd117ba39e41bcb3417e22"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "d5f68fad0d512524edfd117ba39e41bcb3417e22",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8935562364782152896"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-debug-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:31:33.943] Scheduled build:
{
"id": "8770132302825733793",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-12T19:31:33.518228248Z",
"updateTime": "2023-09-12T19:31:33.518228248Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "d5f68fad0d512524edfd117ba39e41bcb3417e22",
"ref": "refs/heads/main"
}
}
}
[19:31:33.943] Task URL: https://cr-buildbucket.appspot.com/build/8770132302825733793
[19:31:33.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:2:0) after 6m45s
[19:31:36.797] Received PubSub notification, asking Buildbucket for the build status
[19:31:36.887] Build status: SCHEDULED
[19:33:24.349] Received PubSub notification, asking Buildbucket for the build status
[19:33:24.368] Build status: STARTED
[19:38:19.015] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:2:0)
[19:38:19.041] Build status: STARTED
[19:38:19.041] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:5:0) after 1m13s
[19:39:32.081] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:5:0)
[19:39:32.138] Build status: STARTED
[19:39:32.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:6:0) after 9m3s
[19:48:35.155] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:6:0)
[19:48:35.174] Build status: STARTED
[19:48:35.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:7:0) after 5m13s
[19:53:48.171] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:7:0)
[19:53:48.216] Build status: STARTED
[19:53:48.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:8:0) after 8m5s
[20:01:53.230] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:8:0)
[20:01:53.260] Build status: STARTED
[20:01:53.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:9:0) after 5m18s
[20:07:11.547] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:9:0)
[20:07:11.569] Build status: STARTED
[20:07:11.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:10:0) after 6m59s
[20:14:10.596] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:10:0)
[20:14:10.612] Build status: STARTED
[20:14:10.612] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:11:0) after 9m27s
[20:23:37.630] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:11:0)
[20:23:37.694] Build status: STARTED
[20:23:37.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:12:0) after 2m56s
[20:26:33.787] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:12:0)
[20:26:33.807] Build status: STARTED
[20:26:33.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:13:0) after 8m23s
[20:34:57.485] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:13:0)
[20:34:57.507] Build status: STARTED
[20:34:57.507] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:14:0) after 8m58s
[20:43:55.874] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:14:0)
[20:43:55.891] Build status: STARTED
[20:43:55.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:15:0) after 2m3s
[20:45:59.501] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:15:0)
[20:45:59.517] Build status: STARTED
[20:45:59.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8935562364782152896:16:0) after 1m55s
[20:46:10.812] Received PubSub notification, asking Buildbucket for the build status
[20:46:10.826] Build:
{
"id": "8770132302825733793",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-12T19:31:33.518228248Z",
"startTime": "2023-09-12T19:33:23.017268Z",
"endTime": "2023-09-12T20:46:10.459358227Z",
"updateTime": "2023-09-12T20:46:10.459358227Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "d5f68fad0d512524edfd117ba39e41bcb3417e22",
"ref": "refs/heads/main"
}
}
}
[20:46:10.826] Invocation finished in 1h14m38.558612847s with status SUCCEEDED