[22:46:16.481] New invocation is queued and will start shortly
[22:46:17.569] Starting the invocation (attempt 1)
[22:46:17.596] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/c76a87cbcc574d169d9e34b647dc617d2ed22c96"
[22:46:17.596] Popped gitiles commit info from properties and tags
[22:46:17.596] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:46:17.596] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:46:17.597] Buildbucket request:
{
"requestId": "8928030564805585696",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8928030564805585696",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "884d9eb623800809f759859352fe7e6928f3be62"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@884d9eb623800809f759859352fe7e6928f3be62",
"title": "884d9eb623800809f759859352fe7e6928f3be62",
"url": "https://fuchsia.googlesource.com/integration/+/884d9eb623800809f759859352fe7e6928f3be62"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9d8a5c3a6c3649512f80d80435358b1621c9f4b2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9d8a5c3a6c3649512f80d80435358b1621c9f4b2",
"title": "9d8a5c3a6c3649512f80d80435358b1621c9f4b2",
"url": "https://fuchsia.googlesource.com/integration/+/9d8a5c3a6c3649512f80d80435358b1621c9f4b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bbe2f52a5c64fd56cf89106683767e793f0e5a18"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bbe2f52a5c64fd56cf89106683767e793f0e5a18",
"title": "bbe2f52a5c64fd56cf89106683767e793f0e5a18",
"url": "https://fuchsia.googlesource.com/integration/+/bbe2f52a5c64fd56cf89106683767e793f0e5a18"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "701fcbc20db73d47aad922e5ceaf9f02dbde9819"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@701fcbc20db73d47aad922e5ceaf9f02dbde9819",
"title": "701fcbc20db73d47aad922e5ceaf9f02dbde9819",
"url": "https://fuchsia.googlesource.com/integration/+/701fcbc20db73d47aad922e5ceaf9f02dbde9819"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "dcde2e78c042b49b5f47d51c6c7731e7e413581d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@dcde2e78c042b49b5f47d51c6c7731e7e413581d",
"title": "dcde2e78c042b49b5f47d51c6c7731e7e413581d",
"url": "https://fuchsia.googlesource.com/integration/+/dcde2e78c042b49b5f47d51c6c7731e7e413581d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6ec846afd2b9ccbb2a614778a32145c4bad4a42f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6ec846afd2b9ccbb2a614778a32145c4bad4a42f",
"title": "6ec846afd2b9ccbb2a614778a32145c4bad4a42f",
"url": "https://fuchsia.googlesource.com/integration/+/6ec846afd2b9ccbb2a614778a32145c4bad4a42f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2f7f6e3aba5300083ae416a29b87be6cf41e572e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2f7f6e3aba5300083ae416a29b87be6cf41e572e",
"title": "2f7f6e3aba5300083ae416a29b87be6cf41e572e",
"url": "https://fuchsia.googlesource.com/integration/+/2f7f6e3aba5300083ae416a29b87be6cf41e572e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e14d9db79a7b422e1b922bb8063a0628d8810458"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e14d9db79a7b422e1b922bb8063a0628d8810458",
"title": "e14d9db79a7b422e1b922bb8063a0628d8810458",
"url": "https://fuchsia.googlesource.com/integration/+/e14d9db79a7b422e1b922bb8063a0628d8810458"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3822de7ad85eb641b84a7da38c3dfb1d3c64f34b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3822de7ad85eb641b84a7da38c3dfb1d3c64f34b",
"title": "3822de7ad85eb641b84a7da38c3dfb1d3c64f34b",
"url": "https://fuchsia.googlesource.com/integration/+/3822de7ad85eb641b84a7da38c3dfb1d3c64f34b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1b0aa33a7a2fd3be853ea45927ec22421af7a0e4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1b0aa33a7a2fd3be853ea45927ec22421af7a0e4",
"title": "1b0aa33a7a2fd3be853ea45927ec22421af7a0e4",
"url": "https://fuchsia.googlesource.com/integration/+/1b0aa33a7a2fd3be853ea45927ec22421af7a0e4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0ef8af214d2926d21eae7734fd544ea6fec64ab6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0ef8af214d2926d21eae7734fd544ea6fec64ab6",
"title": "0ef8af214d2926d21eae7734fd544ea6fec64ab6",
"url": "https://fuchsia.googlesource.com/integration/+/0ef8af214d2926d21eae7734fd544ea6fec64ab6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2e9d848592b0334d922d11de7d92d88db0559f50"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2e9d848592b0334d922d11de7d92d88db0559f50",
"title": "2e9d848592b0334d922d11de7d92d88db0559f50",
"url": "https://fuchsia.googlesource.com/integration/+/2e9d848592b0334d922d11de7d92d88db0559f50"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5cf48d06d445558c609a6b135f8fb034dea4da42"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5cf48d06d445558c609a6b135f8fb034dea4da42",
"title": "5cf48d06d445558c609a6b135f8fb034dea4da42",
"url": "https://fuchsia.googlesource.com/integration/+/5cf48d06d445558c609a6b135f8fb034dea4da42"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "decd2053eb85612a2184a1e2d67105fa1438a7ce"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@decd2053eb85612a2184a1e2d67105fa1438a7ce",
"title": "decd2053eb85612a2184a1e2d67105fa1438a7ce",
"url": "https://fuchsia.googlesource.com/integration/+/decd2053eb85612a2184a1e2d67105fa1438a7ce"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "48e34a62cbcde56fd764cd2a612104d259a2202c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@48e34a62cbcde56fd764cd2a612104d259a2202c",
"title": "48e34a62cbcde56fd764cd2a612104d259a2202c",
"url": "https://fuchsia.googlesource.com/integration/+/48e34a62cbcde56fd764cd2a612104d259a2202c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0073cbc625c772e6964b0098921e68c15002cfb2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0073cbc625c772e6964b0098921e68c15002cfb2",
"title": "0073cbc625c772e6964b0098921e68c15002cfb2",
"url": "https://fuchsia.googlesource.com/integration/+/0073cbc625c772e6964b0098921e68c15002cfb2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8e6c4f79f73101409d9b2341baf984c16b1be2e0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8e6c4f79f73101409d9b2341baf984c16b1be2e0",
"title": "8e6c4f79f73101409d9b2341baf984c16b1be2e0",
"url": "https://fuchsia.googlesource.com/integration/+/8e6c4f79f73101409d9b2341baf984c16b1be2e0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8a94465821687ec9b0a5ea4e0b498eea2fed673a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8a94465821687ec9b0a5ea4e0b498eea2fed673a",
"title": "8a94465821687ec9b0a5ea4e0b498eea2fed673a",
"url": "https://fuchsia.googlesource.com/integration/+/8a94465821687ec9b0a5ea4e0b498eea2fed673a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c76a87cbcc574d169d9e34b647dc617d2ed22c96"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c76a87cbcc574d169d9e34b647dc617d2ed22c96",
"title": "c76a87cbcc574d169d9e34b647dc617d2ed22c96",
"url": "https://fuchsia.googlesource.com/integration/+/c76a87cbcc574d169d9e34b647dc617d2ed22c96"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c76a87cbcc574d169d9e34b647dc617d2ed22c96",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8928030564805585696"
},
{
"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"
}
}
[22:46:19.077] Scheduled build:
{
"id": "8762600502784593521",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-12-04T22:46:17.773943327Z",
"updateTime": "2023-12-04T22:46:17.773943327Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c76a87cbcc574d169d9e34b647dc617d2ed22c96",
"ref": "refs/heads/main"
}
}
}
[22:46:19.077] Task URL: https://cr-buildbucket.appspot.com/build/8762600502784593521
[22:46:19.077] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:2:0) after 6m53s
[22:46:20.034] Received PubSub notification, asking Buildbucket for the build status
[22:46:20.056] Build status: SCHEDULED
[22:46:51.547] Received PubSub notification, asking Buildbucket for the build status
[22:46:51.566] Build status: STARTED
[22:53:12.185] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:2:0)
[22:53:12.212] Build status: STARTED
[22:53:12.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:5:0) after 7m1s
[23:00:13.338] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:5:0)
[23:00:13.426] Build status: STARTED
[23:00:13.426] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:6:0) after 1m13s
[23:01:26.464] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:6:0)
[23:01:26.502] Build status: STARTED
[23:01:26.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:7:0) after 8m35s
[23:10:01.594] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:7:0)
[23:10:01.630] Build status: STARTED
[23:10:01.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:8:0) after 4m18s
[23:14:19.738] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:8:0)
[23:14:19.758] Build status: STARTED
[23:14:19.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:9:0) after 9m0s
[23:23:19.811] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:9:0)
[23:23:19.833] Build status: STARTED
[23:23:19.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:10:0) after 7m22s
[23:30:41.852] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:10:0)
[23:30:41.994] Build status: STARTED
[23:30:41.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:11:0) after 4m0s
[23:34:42.130] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:11:0)
[23:34:42.162] Build status: STARTED
[23:34:42.162] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:12:0) after 4m53s
[23:39:35.239] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:12:0)
[23:39:35.262] Build status: STARTED
[23:39:35.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:13:0) after 1m10s
[23:40:45.258] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:13:0)
[23:40:45.279] Build status: STARTED
[23:40:45.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:14:0) after 9m16s
[23:50:01.315] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:14:0)
[23:50:01.338] Build status: STARTED
[23:50:01.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8928030564805585696:15:0) after 6m36s
[23:55:21.553] Received PubSub notification, asking Buildbucket for the build status
[23:55:21.575] Build:
{
"id": "8762600502784593521",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-12-04T22:46:17.773943327Z",
"startTime": "2023-12-04T22:46:49.657034Z",
"endTime": "2023-12-04T23:55:20.343778Z",
"updateTime": "2023-12-04T23:55:20.343778Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c76a87cbcc574d169d9e34b647dc617d2ed22c96",
"ref": "refs/heads/main"
}
}
}
[23:55:21.575] Invocation finished in 1h9m5.112510981s with status SUCCEEDED