[00:43:22.171] New invocation is queued and will start shortly
[00:43:24.022] Starting the invocation (attempt 1)
[00:43:24.051] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/8cd89c2b6c4a652aca2a26651bee8f4795f6981f"
[00:43:24.051] Popped gitiles commit info from properties and tags
[00:43:24.051] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:43:24.051] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:43:24.051] Buildbucket request:
{
"requestId": "8894864708122924144",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8894864708122924144",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0f4c52fc0718fa40e40ff1a8e8fc4f484c50bc19"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0f4c52fc0718fa40e40ff1a8e8fc4f484c50bc19",
"title": "0f4c52fc0718fa40e40ff1a8e8fc4f484c50bc19",
"url": "https://fuchsia.googlesource.com/integration/+/0f4c52fc0718fa40e40ff1a8e8fc4f484c50bc19"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c51b15af5c504be3e4406d2cbdc55e7c9b62cc03"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c51b15af5c504be3e4406d2cbdc55e7c9b62cc03",
"title": "c51b15af5c504be3e4406d2cbdc55e7c9b62cc03",
"url": "https://fuchsia.googlesource.com/integration/+/c51b15af5c504be3e4406d2cbdc55e7c9b62cc03"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6d93645017e8578666fb567268160a55e60782d6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6d93645017e8578666fb567268160a55e60782d6",
"title": "6d93645017e8578666fb567268160a55e60782d6",
"url": "https://fuchsia.googlesource.com/integration/+/6d93645017e8578666fb567268160a55e60782d6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e0ce23f4133d692c31f3cbedf3dcd110b5fe6532"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e0ce23f4133d692c31f3cbedf3dcd110b5fe6532",
"title": "e0ce23f4133d692c31f3cbedf3dcd110b5fe6532",
"url": "https://fuchsia.googlesource.com/integration/+/e0ce23f4133d692c31f3cbedf3dcd110b5fe6532"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "09852e64156af16608033ec316910440302e806a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@09852e64156af16608033ec316910440302e806a",
"title": "09852e64156af16608033ec316910440302e806a",
"url": "https://fuchsia.googlesource.com/integration/+/09852e64156af16608033ec316910440302e806a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a8b4736ac106a6a770e93b5a8f0aa009a0e498b6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a8b4736ac106a6a770e93b5a8f0aa009a0e498b6",
"title": "a8b4736ac106a6a770e93b5a8f0aa009a0e498b6",
"url": "https://fuchsia.googlesource.com/integration/+/a8b4736ac106a6a770e93b5a8f0aa009a0e498b6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "46b3ea6eec0d4bf45d9a18ca574f612d983aa8bf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@46b3ea6eec0d4bf45d9a18ca574f612d983aa8bf",
"title": "46b3ea6eec0d4bf45d9a18ca574f612d983aa8bf",
"url": "https://fuchsia.googlesource.com/integration/+/46b3ea6eec0d4bf45d9a18ca574f612d983aa8bf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "722d6d8467cb8c3f47c7d755bd35eba8873a226e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@722d6d8467cb8c3f47c7d755bd35eba8873a226e",
"title": "722d6d8467cb8c3f47c7d755bd35eba8873a226e",
"url": "https://fuchsia.googlesource.com/integration/+/722d6d8467cb8c3f47c7d755bd35eba8873a226e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f465c70f6a5a8b9f47f4f9289f65cfb518e748c0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f465c70f6a5a8b9f47f4f9289f65cfb518e748c0",
"title": "f465c70f6a5a8b9f47f4f9289f65cfb518e748c0",
"url": "https://fuchsia.googlesource.com/integration/+/f465c70f6a5a8b9f47f4f9289f65cfb518e748c0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d6150f781083973bf0420bb8f904882852a20891"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d6150f781083973bf0420bb8f904882852a20891",
"title": "d6150f781083973bf0420bb8f904882852a20891",
"url": "https://fuchsia.googlesource.com/integration/+/d6150f781083973bf0420bb8f904882852a20891"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "121e6370fd3be887133ded41bf89142157d9203a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@121e6370fd3be887133ded41bf89142157d9203a",
"title": "121e6370fd3be887133ded41bf89142157d9203a",
"url": "https://fuchsia.googlesource.com/integration/+/121e6370fd3be887133ded41bf89142157d9203a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d5b169d424b8610b9649ff09abcdfb60f183e857"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d5b169d424b8610b9649ff09abcdfb60f183e857",
"title": "d5b169d424b8610b9649ff09abcdfb60f183e857",
"url": "https://fuchsia.googlesource.com/integration/+/d5b169d424b8610b9649ff09abcdfb60f183e857"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8cd89c2b6c4a652aca2a26651bee8f4795f6981f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8cd89c2b6c4a652aca2a26651bee8f4795f6981f",
"title": "8cd89c2b6c4a652aca2a26651bee8f4795f6981f",
"url": "https://fuchsia.googlesource.com/integration/+/8cd89c2b6c4a652aca2a26651bee8f4795f6981f"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8cd89c2b6c4a652aca2a26651bee8f4795f6981f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8894864708122924144"
},
{
"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"
}
}
[00:43:24.590] Scheduled build:
{
"id": "8729434645425850625",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-12-05T00:43:24.118909462Z",
"updateTime": "2024-12-05T00:43:24.118909462Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8cd89c2b6c4a652aca2a26651bee8f4795f6981f",
"ref": "refs/heads/main"
}
}
}
[00:43:24.590] Task URL: https://cr-buildbucket.appspot.com/build/8729434645425850625
[00:43:24.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:2:0) after 8m47s
[00:43:25.658] Received PubSub notification, asking Buildbucket for the build status
[00:43:25.687] Build status: SCHEDULED
[00:43:30.079] Received PubSub notification, asking Buildbucket for the build status
[00:43:30.115] Build status: STARTED
[00:52:11.610] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:2:0)
[00:52:11.637] Build status: STARTED
[00:52:11.637] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:5:0) after 1m38s
[00:53:49.656] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:5:0)
[00:53:49.677] Build status: STARTED
[00:53:49.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:6:0) after 3m20s
[00:57:09.733] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:6:0)
[00:57:09.755] Build status: STARTED
[00:57:09.755] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:7:0) after 7m35s
[01:04:44.777] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:7:0)
[01:04:44.793] Build status: STARTED
[01:04:44.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:8:0) after 2m13s
[01:06:58.038] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:8:0)
[01:06:58.054] Build status: STARTED
[01:06:58.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:9:0) after 1m29s
[01:08:27.073] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:9:0)
[01:08:27.104] Build status: STARTED
[01:08:27.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:10:0) after 2m38s
[01:11:05.148] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:10:0)
[01:11:05.174] Build status: STARTED
[01:11:05.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:11:0) after 6m18s
[01:17:23.187] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:11:0)
[01:17:23.204] Build status: STARTED
[01:17:23.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:12:0) after 6m7s
[01:23:30.223] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:12:0)
[01:23:30.250] Build status: STARTED
[01:23:30.250] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:13:0) after 1m30s
[01:25:00.266] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:13:0)
[01:25:00.291] Build status: STARTED
[01:25:00.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:14:0) after 1m31s
[01:26:31.315] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:14:0)
[01:26:31.344] Build status: STARTED
[01:26:31.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:15:0) after 2m27s
[01:28:58.360] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:15:0)
[01:28:58.384] Build status: STARTED
[01:28:58.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:16:0) after 4m6s
[01:33:04.465] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:16:0)
[01:33:04.492] Build status: STARTED
[01:33:04.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:17:0) after 2m22s
[01:35:26.561] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:17:0)
[01:35:26.575] Build status: STARTED
[01:35:26.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:18:0) after 5m20s
[01:40:46.637] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:18:0)
[01:40:46.695] Build status: STARTED
[01:40:46.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8894864708122924144:19:0) after 7m27s
[01:45:20.236] Received PubSub notification, asking Buildbucket for the build status
[01:45:20.259] Build:
{
"id": "8729434645425850625",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-12-05T00:43:24.118909462Z",
"startTime": "2024-12-05T00:43:29.833825318Z",
"endTime": "2024-12-05T01:45:14.623639172Z",
"updateTime": "2024-12-05T01:45:14.623639172Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8cd89c2b6c4a652aca2a26651bee8f4795f6981f",
"ref": "refs/heads/main"
}
}
}
[01:45:20.259] Invocation finished in 1h1m58.096644959s with status SUCCEEDED