[02:48:11.165] New invocation is queued and will start shortly
[02:48:12.280] Starting the invocation (attempt 1)
[02:48:12.307] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/27df72993050dfe98b678d3f445b3e9af52f5ab4"
[02:48:12.307] Popped gitiles commit info from properties and tags
[02:48:12.307] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:48:12.307] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:48:12.308] Buildbucket request:
{
"requestId": "8896849988603032576",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.x64-release-profile-kernel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8896849988603032576",
"job": "fuchsia/core.x64-release-profile-kernel-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "84ab00f6838956e37d03acfa08dd94d228de4c14"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@84ab00f6838956e37d03acfa08dd94d228de4c14",
"title": "84ab00f6838956e37d03acfa08dd94d228de4c14",
"url": "https://fuchsia.googlesource.com/fuchsia/+/84ab00f6838956e37d03acfa08dd94d228de4c14"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "7b937ba9484dd06cdd32f55b915102819e2bbf19"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@7b937ba9484dd06cdd32f55b915102819e2bbf19",
"title": "7b937ba9484dd06cdd32f55b915102819e2bbf19",
"url": "https://fuchsia.googlesource.com/fuchsia/+/7b937ba9484dd06cdd32f55b915102819e2bbf19"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "c914341a2e063a66ab0a709e78c35ea6c18c5844"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@c914341a2e063a66ab0a709e78c35ea6c18c5844",
"title": "c914341a2e063a66ab0a709e78c35ea6c18c5844",
"url": "https://fuchsia.googlesource.com/fuchsia/+/c914341a2e063a66ab0a709e78c35ea6c18c5844"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "7bcc654f6dcdb4298c02b7a73d487dfd0ed1edc1"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@7bcc654f6dcdb4298c02b7a73d487dfd0ed1edc1",
"title": "7bcc654f6dcdb4298c02b7a73d487dfd0ed1edc1",
"url": "https://fuchsia.googlesource.com/fuchsia/+/7bcc654f6dcdb4298c02b7a73d487dfd0ed1edc1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9b41af0e3fc06eeb4cb8016e4a8bef6ab04e2b15"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9b41af0e3fc06eeb4cb8016e4a8bef6ab04e2b15",
"title": "9b41af0e3fc06eeb4cb8016e4a8bef6ab04e2b15",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9b41af0e3fc06eeb4cb8016e4a8bef6ab04e2b15"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "db6d8d941dde7be77e4e06d3fcb113b4a7e39566"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@db6d8d941dde7be77e4e06d3fcb113b4a7e39566",
"title": "db6d8d941dde7be77e4e06d3fcb113b4a7e39566",
"url": "https://fuchsia.googlesource.com/fuchsia/+/db6d8d941dde7be77e4e06d3fcb113b4a7e39566"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "ddc1bfb403ff361666fccd88cac8a6f067f2e53e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@ddc1bfb403ff361666fccd88cac8a6f067f2e53e",
"title": "ddc1bfb403ff361666fccd88cac8a6f067f2e53e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/ddc1bfb403ff361666fccd88cac8a6f067f2e53e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "35a180340f1fd88ae0bfafe88b2befcd7b8b1ac0"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@35a180340f1fd88ae0bfafe88b2befcd7b8b1ac0",
"title": "35a180340f1fd88ae0bfafe88b2befcd7b8b1ac0",
"url": "https://fuchsia.googlesource.com/fuchsia/+/35a180340f1fd88ae0bfafe88b2befcd7b8b1ac0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "29fc9749b2c6c2c77dab8c0cddc7847174545fcc"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@29fc9749b2c6c2c77dab8c0cddc7847174545fcc",
"title": "29fc9749b2c6c2c77dab8c0cddc7847174545fcc",
"url": "https://fuchsia.googlesource.com/fuchsia/+/29fc9749b2c6c2c77dab8c0cddc7847174545fcc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "d1ba5741498863824068d3b76e0bdb089b1ee140"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@d1ba5741498863824068d3b76e0bdb089b1ee140",
"title": "d1ba5741498863824068d3b76e0bdb089b1ee140",
"url": "https://fuchsia.googlesource.com/fuchsia/+/d1ba5741498863824068d3b76e0bdb089b1ee140"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "27df72993050dfe98b678d3f445b3e9af52f5ab4"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@27df72993050dfe98b678d3f445b3e9af52f5ab4",
"title": "27df72993050dfe98b678d3f445b3e9af52f5ab4",
"url": "https://fuchsia.googlesource.com/fuchsia/+/27df72993050dfe98b678d3f445b3e9af52f5ab4"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "27df72993050dfe98b678d3f445b3e9af52f5ab4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8896849988603032576"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-release-profile-kernel-af244ac6"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:48:12.699] Scheduled build:
{
"id": "8731419926675400881",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.x64-release-profile-kernel"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-13T02:48:12.379064408Z",
"updateTime": "2024-11-13T02:48:12.379064408Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "27df72993050dfe98b678d3f445b3e9af52f5ab4",
"ref": "refs/heads/main"
}
}
}
[02:48:12.699] Task URL: https://cr-buildbucket.appspot.com/build/8731419926675400881
[02:48:12.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:2:0) after 7m33s
[02:48:13.684] Received PubSub notification, asking Buildbucket for the build status
[02:48:13.701] Build status: SCHEDULED
[02:48:36.431] Received PubSub notification, asking Buildbucket for the build status
[02:48:36.460] Build status: STARTED
[02:55:45.714] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:2:0)
[02:55:45.733] Build status: STARTED
[02:55:45.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:5:0) after 2m37s
[02:58:22.760] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:5:0)
[02:58:22.777] Build status: STARTED
[02:58:22.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:6:0) after 2m39s
[03:01:01.859] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:6:0)
[03:01:01.878] Build status: STARTED
[03:01:01.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:7:0) after 9m15s
[03:10:16.894] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:7:0)
[03:10:16.922] Build status: STARTED
[03:10:16.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:8:0) after 9m24s
[03:19:41.015] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:8:0)
[03:19:41.034] Build status: STARTED
[03:19:41.034] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:9:0) after 7m34s
[03:27:15.100] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:9:0)
[03:27:15.157] Build status: STARTED
[03:27:15.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:10:0) after 5m50s
[03:33:05.236] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:10:0)
[03:33:05.262] Build status: STARTED
[03:33:05.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:11:0) after 5m36s
[03:38:41.280] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:11:0)
[03:38:41.306] Build status: STARTED
[03:38:41.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:12:0) after 9m31s
[03:48:12.548] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:12:0)
[03:48:12.568] Build status: STARTED
[03:48:12.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:13:0) after 6m17s
[03:54:29.585] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:13:0)
[03:54:29.604] Build status: STARTED
[03:54:29.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:14:0) after 4m41s
[03:59:11.113] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:14:0)
[03:59:11.136] Build status: STARTED
[03:59:11.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:15:0) after 2m40s
[04:01:51.470] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:15:0)
[04:01:51.488] Build status: STARTED
[04:01:51.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:16:0) after 5m23s
[04:07:14.683] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:16:0)
[04:07:14.707] Build status: STARTED
[04:07:14.707] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896849988603032576:17:0) after 6m8s
[04:11:12.857] Received PubSub notification, asking Buildbucket for the build status
[04:11:12.877] Build:
{
"id": "8731419926675400881",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.x64-release-profile-kernel"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-13T02:48:12.379064408Z",
"startTime": "2024-11-13T02:48:36.187799102Z",
"endTime": "2024-11-13T04:11:12.570389013Z",
"updateTime": "2024-11-13T04:11:12.570389013Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "27df72993050dfe98b678d3f445b3e9af52f5ab4",
"ref": "refs/heads/main"
}
}
}
[04:11:12.877] Invocation finished in 1h23m1.719428728s with status SUCCEEDED