[01:33:18.583] New invocation is queued and will start shortly
[01:33:19.710] Starting the invocation (attempt 1)
[01:33:19.755] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/bcb6818356a200100426ce82172f33225530e6ff"
[01:33:19.755] Popped gitiles commit info from properties and tags
[01:33:19.755] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:33:19.755] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:33:19.755] Buildbucket request:
{
"requestId": "8889606942108640336",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8889606942108640336",
"job": "fuchsia/fuchsia-profile-core.x64-release-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "aa3b2eba3fce6aba9bda437780b130accc3c8eb0"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@aa3b2eba3fce6aba9bda437780b130accc3c8eb0",
"title": "aa3b2eba3fce6aba9bda437780b130accc3c8eb0",
"url": "https://fuchsia.googlesource.com/fuchsia/+/aa3b2eba3fce6aba9bda437780b130accc3c8eb0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "b57c538cc08efaeabd0998c8bdf4e1fe7d2c7678"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@b57c538cc08efaeabd0998c8bdf4e1fe7d2c7678",
"title": "b57c538cc08efaeabd0998c8bdf4e1fe7d2c7678",
"url": "https://fuchsia.googlesource.com/fuchsia/+/b57c538cc08efaeabd0998c8bdf4e1fe7d2c7678"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a0a890278e3a4e08a75034f7d20378e7c01811fb"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a0a890278e3a4e08a75034f7d20378e7c01811fb",
"title": "a0a890278e3a4e08a75034f7d20378e7c01811fb",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a0a890278e3a4e08a75034f7d20378e7c01811fb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "ebbdacbdd573dc778217563e1f6c5d5827ec562b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@ebbdacbdd573dc778217563e1f6c5d5827ec562b",
"title": "ebbdacbdd573dc778217563e1f6c5d5827ec562b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/ebbdacbdd573dc778217563e1f6c5d5827ec562b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "f91b72cd28f9ca5aaba59d9e99ed40059fb53861"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@f91b72cd28f9ca5aaba59d9e99ed40059fb53861",
"title": "f91b72cd28f9ca5aaba59d9e99ed40059fb53861",
"url": "https://fuchsia.googlesource.com/fuchsia/+/f91b72cd28f9ca5aaba59d9e99ed40059fb53861"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "bb80ac568bfd93094bbcb46c70f84a0f06477ce0"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@bb80ac568bfd93094bbcb46c70f84a0f06477ce0",
"title": "bb80ac568bfd93094bbcb46c70f84a0f06477ce0",
"url": "https://fuchsia.googlesource.com/fuchsia/+/bb80ac568bfd93094bbcb46c70f84a0f06477ce0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "61d9a7ae799124e03dde6a90b72b36a51c9d4f41"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@61d9a7ae799124e03dde6a90b72b36a51c9d4f41",
"title": "61d9a7ae799124e03dde6a90b72b36a51c9d4f41",
"url": "https://fuchsia.googlesource.com/fuchsia/+/61d9a7ae799124e03dde6a90b72b36a51c9d4f41"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "eddad7b6a40e3fa9684fde452023f2c55215d081"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@eddad7b6a40e3fa9684fde452023f2c55215d081",
"title": "eddad7b6a40e3fa9684fde452023f2c55215d081",
"url": "https://fuchsia.googlesource.com/fuchsia/+/eddad7b6a40e3fa9684fde452023f2c55215d081"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "bcb6818356a200100426ce82172f33225530e6ff"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@bcb6818356a200100426ce82172f33225530e6ff",
"title": "bcb6818356a200100426ce82172f33225530e6ff",
"url": "https://fuchsia.googlesource.com/fuchsia/+/bcb6818356a200100426ce82172f33225530e6ff"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "bcb6818356a200100426ce82172f33225530e6ff",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8889606942108640336"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/fuchsia-profile-core.x64-release-af244ac6"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:33:20.140] Scheduled build:
{
"id": "8724176880140254129",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-01T01:33:19.833369155Z",
"updateTime": "2025-02-01T01:33:19.833369155Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "bcb6818356a200100426ce82172f33225530e6ff",
"ref": "refs/heads/main"
}
}
}
[01:33:20.140] Task URL: https://cr-buildbucket.appspot.com/build/8724176880140254129
[01:33:20.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:2:0) after 6m46s
[01:33:21.136] Received PubSub notification, asking Buildbucket for the build status
[01:33:21.160] Build status: SCHEDULED
[01:33:24.877] Received PubSub notification, asking Buildbucket for the build status
[01:33:24.898] Build status: STARTED
[01:40:06.178] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:2:0)
[01:40:06.211] Build status: STARTED
[01:40:06.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:5:0) after 6m30s
[01:46:36.212] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:5:0)
[01:46:36.249] Build status: STARTED
[01:46:36.249] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:6:0) after 3m54s
[01:50:30.310] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:6:0)
[01:50:30.331] Build status: STARTED
[01:50:30.331] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:7:0) after 6m33s
[01:57:03.468] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:7:0)
[01:57:03.583] Build status: STARTED
[01:57:03.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:8:0) after 1m23s
[01:58:26.633] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:8:0)
[01:58:26.658] Build status: STARTED
[01:58:26.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:9:0) after 5m9s
[02:03:35.724] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:9:0)
[02:03:35.750] Build status: STARTED
[02:03:35.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:10:0) after 8m25s
[02:12:00.732] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:10:0)
[02:12:00.756] Build status: STARTED
[02:12:00.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:11:0) after 6m5s
[02:18:05.781] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:11:0)
[02:18:05.802] Build status: STARTED
[02:18:05.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:12:0) after 1m34s
[02:19:39.900] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:12:0)
[02:19:39.917] Build status: STARTED
[02:19:39.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:13:0) after 8m44s
[02:28:24.194] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:13:0)
[02:28:24.214] Build status: STARTED
[02:28:24.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:14:0) after 6m28s
[02:34:52.236] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:14:0)
[02:34:52.263] Build status: STARTED
[02:34:52.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:15:0) after 6m13s
[02:41:05.426] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:15:0)
[02:41:05.447] Build status: STARTED
[02:41:05.447] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:16:0) after 6m52s
[02:47:57.574] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:16:0)
[02:47:57.591] Build status: STARTED
[02:47:57.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:17:0) after 8m11s
[02:56:08.626] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:17:0)
[02:56:08.814] Build status: STARTED
[02:56:08.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:18:0) after 8m21s
[03:04:29.837] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:18:0)
[03:04:29.854] Build status: STARTED
[03:04:29.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:19:0) after 5m0s
[03:09:29.882] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:19:0)
[03:09:29.904] Build status: STARTED
[03:09:29.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:20:0) after 9m20s
[03:18:49.922] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:20:0)
[03:18:49.952] Build status: STARTED
[03:18:49.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8889606942108640336:21:0) after 8m36s
[03:25:06.172] Received PubSub notification, asking Buildbucket for the build status
[03:25:06.200] Build:
{
"id": "8724176880140254129",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-01T01:33:19.833369155Z",
"startTime": "2025-02-01T01:33:24.568940880Z",
"endTime": "2025-02-01T03:25:05.836373776Z",
"updateTime": "2025-02-01T03:25:05.836373776Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "bcb6818356a200100426ce82172f33225530e6ff",
"ref": "refs/heads/main"
}
}
}
[03:25:06.200] Invocation finished in 1h51m47.628221833s with status SUCCEEDED