[03:16:37.271] New invocation is queued and will start shortly
[03:16:38.435] Starting the invocation (attempt 1)
[03:16:38.466] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/a3d77f46179ce03bcf934ffa2ceca3e007ea7f46"
[03:16:38.466] Popped gitiles commit info from properties and tags
[03:16:38.466] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:16:38.466] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:16:38.466] Buildbucket request:
{
"requestId": "8896757602663088272",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-lto-no_rbe-build_only"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8896757602663088272",
"job": "fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0a4105f3b21a545478fc8925ff3824c2ac79bfc4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0a4105f3b21a545478fc8925ff3824c2ac79bfc4",
"title": "0a4105f3b21a545478fc8925ff3824c2ac79bfc4",
"url": "https://fuchsia.googlesource.com/integration/+/0a4105f3b21a545478fc8925ff3824c2ac79bfc4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "72b527dc05a5b797daaec9b2ae203b04e26fd220"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@72b527dc05a5b797daaec9b2ae203b04e26fd220",
"title": "72b527dc05a5b797daaec9b2ae203b04e26fd220",
"url": "https://fuchsia.googlesource.com/integration/+/72b527dc05a5b797daaec9b2ae203b04e26fd220"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "089c10aff1a774cac51234d44e12ce0e08776576"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@089c10aff1a774cac51234d44e12ce0e08776576",
"title": "089c10aff1a774cac51234d44e12ce0e08776576",
"url": "https://fuchsia.googlesource.com/integration/+/089c10aff1a774cac51234d44e12ce0e08776576"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "326f6390d5f72bff11f66271ec5cbde819fb367f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@326f6390d5f72bff11f66271ec5cbde819fb367f",
"title": "326f6390d5f72bff11f66271ec5cbde819fb367f",
"url": "https://fuchsia.googlesource.com/integration/+/326f6390d5f72bff11f66271ec5cbde819fb367f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f9d0729010d2c8f21a163000b14ba237fd8c563c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f9d0729010d2c8f21a163000b14ba237fd8c563c",
"title": "f9d0729010d2c8f21a163000b14ba237fd8c563c",
"url": "https://fuchsia.googlesource.com/integration/+/f9d0729010d2c8f21a163000b14ba237fd8c563c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d5c44bc7e6e913beb15848d74ca242789645e497"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d5c44bc7e6e913beb15848d74ca242789645e497",
"title": "d5c44bc7e6e913beb15848d74ca242789645e497",
"url": "https://fuchsia.googlesource.com/integration/+/d5c44bc7e6e913beb15848d74ca242789645e497"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "35176876d8cab95b37830b6ef109591c2340ce60"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@35176876d8cab95b37830b6ef109591c2340ce60",
"title": "35176876d8cab95b37830b6ef109591c2340ce60",
"url": "https://fuchsia.googlesource.com/integration/+/35176876d8cab95b37830b6ef109591c2340ce60"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0c306e7483d7a4134d786263933198dd0ee96f53"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0c306e7483d7a4134d786263933198dd0ee96f53",
"title": "0c306e7483d7a4134d786263933198dd0ee96f53",
"url": "https://fuchsia.googlesource.com/integration/+/0c306e7483d7a4134d786263933198dd0ee96f53"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "68b4053e3b309c64aa0152eba1c46411785f6a37"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@68b4053e3b309c64aa0152eba1c46411785f6a37",
"title": "68b4053e3b309c64aa0152eba1c46411785f6a37",
"url": "https://fuchsia.googlesource.com/integration/+/68b4053e3b309c64aa0152eba1c46411785f6a37"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7d83bc67716bc4891829ed69768a138fb753f642"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7d83bc67716bc4891829ed69768a138fb753f642",
"title": "7d83bc67716bc4891829ed69768a138fb753f642",
"url": "https://fuchsia.googlesource.com/integration/+/7d83bc67716bc4891829ed69768a138fb753f642"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c1ba5daae4f4255004a66a64f783fe4bb0f4edd0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c1ba5daae4f4255004a66a64f783fe4bb0f4edd0",
"title": "c1ba5daae4f4255004a66a64f783fe4bb0f4edd0",
"url": "https://fuchsia.googlesource.com/integration/+/c1ba5daae4f4255004a66a64f783fe4bb0f4edd0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "600114ec1b0dc330f34082ff0d8825e5be31dc99"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@600114ec1b0dc330f34082ff0d8825e5be31dc99",
"title": "600114ec1b0dc330f34082ff0d8825e5be31dc99",
"url": "https://fuchsia.googlesource.com/integration/+/600114ec1b0dc330f34082ff0d8825e5be31dc99"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2c6577f3512921e01ce7c0654eb25a6be69a840b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2c6577f3512921e01ce7c0654eb25a6be69a840b",
"title": "2c6577f3512921e01ce7c0654eb25a6be69a840b",
"url": "https://fuchsia.googlesource.com/integration/+/2c6577f3512921e01ce7c0654eb25a6be69a840b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "798c3913e88ef6abcd903cfebe1f20499756c199"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@798c3913e88ef6abcd903cfebe1f20499756c199",
"title": "798c3913e88ef6abcd903cfebe1f20499756c199",
"url": "https://fuchsia.googlesource.com/integration/+/798c3913e88ef6abcd903cfebe1f20499756c199"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a3d77f46179ce03bcf934ffa2ceca3e007ea7f46"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a3d77f46179ce03bcf934ffa2ceca3e007ea7f46",
"title": "a3d77f46179ce03bcf934ffa2ceca3e007ea7f46",
"url": "https://fuchsia.googlesource.com/integration/+/a3d77f46179ce03bcf934ffa2ceca3e007ea7f46"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a3d77f46179ce03bcf934ffa2ceca3e007ea7f46",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8896757602663088272"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:16:38.905] Scheduled build:
{
"id": "8731327540673786289",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-lto-no_rbe-build_only"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-14T03:16:38.536757654Z",
"updateTime": "2024-11-14T03:16:38.536757654Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a3d77f46179ce03bcf934ffa2ceca3e007ea7f46",
"ref": "refs/heads/main"
}
},
"canary": true
}
[03:16:38.905] Task URL: https://cr-buildbucket.appspot.com/build/8731327540673786289
[03:16:38.905] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:2:0) after 3m9s
[03:16:39.858] Received PubSub notification, asking Buildbucket for the build status
[03:16:39.874] Build status: SCHEDULED
[03:16:44.144] Received PubSub notification, asking Buildbucket for the build status
[03:16:44.165] Build status: STARTED
[03:19:47.970] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:2:0)
[03:19:48.016] Build status: STARTED
[03:19:48.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:5:0) after 5m44s
[03:25:32.000] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:5:0)
[03:25:32.088] Build status: STARTED
[03:25:32.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:6:0) after 2m29s
[03:28:01.146] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:6:0)
[03:28:01.187] Build status: STARTED
[03:28:01.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:7:0) after 5m13s
[03:33:14.320] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:7:0)
[03:33:14.350] Build status: STARTED
[03:33:14.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:8:0) after 4m14s
[03:37:28.417] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:8:0)
[03:37:28.438] Build status: STARTED
[03:37:28.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:9:0) after 9m41s
[03:47:09.462] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:9:0)
[03:47:09.489] Build status: STARTED
[03:47:09.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:10:0) after 7m48s
[03:54:57.507] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:10:0)
[03:54:57.528] Build status: STARTED
[03:54:57.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:11:0) after 9m50s
[04:04:47.624] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:11:0)
[04:04:47.648] Build status: STARTED
[04:04:47.648] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:12:0) after 2m13s
[04:07:00.646] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:12:0)
[04:07:00.698] Build status: STARTED
[04:07:00.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:13:0) after 1m23s
[04:08:24.128] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:13:0)
[04:08:24.144] Build status: STARTED
[04:08:24.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:14:0) after 1m36s
[04:10:00.138] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:14:0)
[04:10:00.160] Build status: STARTED
[04:10:00.160] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:15:0) after 2m23s
[04:12:23.282] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:15:0)
[04:12:23.300] Build status: STARTED
[04:12:23.300] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:16:0) after 6m18s
[04:18:41.320] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:16:0)
[04:18:41.336] Build status: STARTED
[04:18:41.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:17:0) after 9m31s
[04:28:12.338] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:17:0)
[04:28:12.357] Build status: STARTED
[04:28:12.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:18:0) after 9m7s
[04:37:19.410] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:18:0)
[04:37:19.506] Build status: STARTED
[04:37:19.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:19:0) after 2m30s
[04:39:49.586] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:19:0)
[04:39:49.608] Build status: STARTED
[04:39:49.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:20:0) after 4m30s
[04:44:19.610] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:20:0)
[04:44:19.633] Build status: STARTED
[04:44:19.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:21:0) after 5m51s
[04:50:10.693] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:21:0)
[04:50:10.709] Build status: STARTED
[04:50:10.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:22:0) after 5m59s
[04:56:09.744] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:22:0)
[04:56:09.767] Build status: STARTED
[04:56:09.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:23:0) after 8m20s
[05:04:29.875] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:23:0)
[05:04:29.898] Build status: STARTED
[05:04:29.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:24:0) after 3m51s
[05:08:20.916] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:24:0)
[05:08:21.098] Build status: STARTED
[05:08:21.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:25:0) after 6m28s
[05:14:49.136] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:25:0)
[05:14:49.154] Build status: STARTED
[05:14:49.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:26:0) after 1m49s
[05:16:38.189] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:26:0)
[05:16:38.206] Build status: STARTED
[05:16:38.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:27:0) after 3m18s
[05:19:56.248] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:27:0)
[05:19:56.265] Build status: STARTED
[05:19:56.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:28:0) after 7m43s
[05:27:39.285] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:28:0)
[05:27:39.371] Build status: STARTED
[05:27:39.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-lto-no_rbe-build_only-e5a0ec7f:8896757602663088272:29:0) after 8m33s
[05:28:46.636] Received PubSub notification, asking Buildbucket for the build status
[05:28:46.707] Build:
{
"id": "8731327540673786289",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-lto-no_rbe-build_only"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-14T03:16:38.536757654Z",
"startTime": "2024-11-14T03:16:43.740357773Z",
"endTime": "2024-11-14T05:28:46.263122641Z",
"updateTime": "2024-11-14T05:28:46.263122641Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a3d77f46179ce03bcf934ffa2ceca3e007ea7f46",
"ref": "refs/heads/main"
}
},
"canary": true
}
[05:28:46.707] Invocation finished in 2h12m9.450861232s with status SUCCEEDED