[00:48:52.386] New invocation is queued and will start shortly
[00:48:53.485] Starting the invocation (attempt 1)
[00:48:53.510] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/2127db085c1b89018f0fcceb4a375d86c3e7e330"
[00:48:53.510] Popped gitiles commit info from properties and tags
[00:48:53.510] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:48:53.510] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:48:53.511] Buildbucket request:
{
"requestId": "8888975559056520928",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8888975559056520928",
"job": "fuchsia/fuchsia-profile-core.x64-release-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6c37b1896c9c5bcd786058f82dc20f2b7b9e9c73"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6c37b1896c9c5bcd786058f82dc20f2b7b9e9c73",
"title": "6c37b1896c9c5bcd786058f82dc20f2b7b9e9c73",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6c37b1896c9c5bcd786058f82dc20f2b7b9e9c73"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a0ff1fcecf5fb13d6a7b6423e5a223928a6f513c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a0ff1fcecf5fb13d6a7b6423e5a223928a6f513c",
"title": "a0ff1fcecf5fb13d6a7b6423e5a223928a6f513c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a0ff1fcecf5fb13d6a7b6423e5a223928a6f513c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8abeda55de5996387d7a2127bfe929c2418158de"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8abeda55de5996387d7a2127bfe929c2418158de",
"title": "8abeda55de5996387d7a2127bfe929c2418158de",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8abeda55de5996387d7a2127bfe929c2418158de"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "942b37c0b6a4b7b08d11c343aa075c710b748367"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@942b37c0b6a4b7b08d11c343aa075c710b748367",
"title": "942b37c0b6a4b7b08d11c343aa075c710b748367",
"url": "https://fuchsia.googlesource.com/fuchsia/+/942b37c0b6a4b7b08d11c343aa075c710b748367"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a420049f329cef49012b46eeeae458b44c89d016"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a420049f329cef49012b46eeeae458b44c89d016",
"title": "a420049f329cef49012b46eeeae458b44c89d016",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a420049f329cef49012b46eeeae458b44c89d016"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "2428191c44c0831f22b56977e6844d9cb54edcdc"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@2428191c44c0831f22b56977e6844d9cb54edcdc",
"title": "2428191c44c0831f22b56977e6844d9cb54edcdc",
"url": "https://fuchsia.googlesource.com/fuchsia/+/2428191c44c0831f22b56977e6844d9cb54edcdc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "d46c89d06433dce9ade268e67648f72ff92b7305"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@d46c89d06433dce9ade268e67648f72ff92b7305",
"title": "d46c89d06433dce9ade268e67648f72ff92b7305",
"url": "https://fuchsia.googlesource.com/fuchsia/+/d46c89d06433dce9ade268e67648f72ff92b7305"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "41ec4993dbf03ed53a13cfd1c4271bdbbf92f067"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@41ec4993dbf03ed53a13cfd1c4271bdbbf92f067",
"title": "41ec4993dbf03ed53a13cfd1c4271bdbbf92f067",
"url": "https://fuchsia.googlesource.com/fuchsia/+/41ec4993dbf03ed53a13cfd1c4271bdbbf92f067"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "815358793982c7041492cc09847c2f8d1f806e74"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@815358793982c7041492cc09847c2f8d1f806e74",
"title": "815358793982c7041492cc09847c2f8d1f806e74",
"url": "https://fuchsia.googlesource.com/fuchsia/+/815358793982c7041492cc09847c2f8d1f806e74"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "5f25c46410e297e05f5550c2cfe20f1ba66c27ed"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@5f25c46410e297e05f5550c2cfe20f1ba66c27ed",
"title": "5f25c46410e297e05f5550c2cfe20f1ba66c27ed",
"url": "https://fuchsia.googlesource.com/fuchsia/+/5f25c46410e297e05f5550c2cfe20f1ba66c27ed"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8ced856758d9a135ff59c03d2059fd5585124ee5"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8ced856758d9a135ff59c03d2059fd5585124ee5",
"title": "8ced856758d9a135ff59c03d2059fd5585124ee5",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8ced856758d9a135ff59c03d2059fd5585124ee5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6cc13d763a02fad7e10af2fd0686cc03502c5a8c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6cc13d763a02fad7e10af2fd0686cc03502c5a8c",
"title": "6cc13d763a02fad7e10af2fd0686cc03502c5a8c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6cc13d763a02fad7e10af2fd0686cc03502c5a8c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "dc56f74bf45061967577510d6a609ac41b26aa1e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@dc56f74bf45061967577510d6a609ac41b26aa1e",
"title": "dc56f74bf45061967577510d6a609ac41b26aa1e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/dc56f74bf45061967577510d6a609ac41b26aa1e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "2127db085c1b89018f0fcceb4a375d86c3e7e330"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@2127db085c1b89018f0fcceb4a375d86c3e7e330",
"title": "2127db085c1b89018f0fcceb4a375d86c3e7e330",
"url": "https://fuchsia.googlesource.com/fuchsia/+/2127db085c1b89018f0fcceb4a375d86c3e7e330"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "2127db085c1b89018f0fcceb4a375d86c3e7e330",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8888975559056520928"
},
{
"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"
}
}
[00:48:54.226] Scheduled build:
{
"id": "8723545497155499473",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-08T00:48:53.569591696Z",
"updateTime": "2025-02-08T00:48:53.569591696Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "2127db085c1b89018f0fcceb4a375d86c3e7e330",
"ref": "refs/heads/main"
}
}
}
[00:48:54.226] Task URL: https://cr-buildbucket.appspot.com/build/8723545497155499473
[00:48:54.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:2:0) after 2m0s
[00:48:55.096] Received PubSub notification, asking Buildbucket for the build status
[00:48:55.210] Build status: SCHEDULED
[00:48:58.980] Received PubSub notification, asking Buildbucket for the build status
[00:48:59.000] Build status: STARTED
[00:50:54.254] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:2:0)
[00:50:54.277] Build status: STARTED
[00:50:54.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:5:0) after 6m3s
[00:56:57.506] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:5:0)
[00:56:57.530] Build status: STARTED
[00:56:57.530] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:6:0) after 6m56s
[01:03:53.536] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:6:0)
[01:03:53.560] Build status: STARTED
[01:03:53.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:7:0) after 3m48s
[01:07:41.599] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:7:0)
[01:07:41.624] Build status: STARTED
[01:07:41.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:8:0) after 5m0s
[01:12:41.736] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:8:0)
[01:12:41.793] Build status: STARTED
[01:12:41.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:9:0) after 1m53s
[01:14:34.819] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:9:0)
[01:14:34.839] Build status: STARTED
[01:14:34.839] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:10:0) after 1m3s
[01:15:37.856] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:10:0)
[01:15:37.882] Build status: STARTED
[01:15:37.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:11:0) after 9m51s
[01:25:28.900] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:11:0)
[01:25:28.921] Build status: STARTED
[01:25:28.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:12:0) after 6m41s
[01:32:09.935] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:12:0)
[01:32:09.958] Build status: STARTED
[01:32:09.958] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:13:0) after 2m10s
[01:34:19.984] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:13:0)
[01:34:20.073] Build status: STARTED
[01:34:20.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:14:0) after 2m4s
[01:36:24.094] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:14:0)
[01:36:24.164] Build status: STARTED
[01:36:24.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:15:0) after 8m12s
[01:44:36.219] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:15:0)
[01:44:36.245] Build status: STARTED
[01:44:36.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:16:0) after 7m36s
[01:52:12.269] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:16:0)
[01:52:12.288] Build status: STARTED
[01:52:12.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:17:0) after 4m59s
[01:57:11.346] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:17:0)
[01:57:11.361] Build status: STARTED
[01:57:11.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:18:0) after 7m54s
[02:05:05.387] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:18:0)
[02:05:05.434] Build status: STARTED
[02:05:05.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:19:0) after 7m37s
[02:12:42.469] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:19:0)
[02:12:42.492] Build status: STARTED
[02:12:42.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:20:0) after 2m55s
[02:15:37.519] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:20:0)
[02:15:37.581] Build status: STARTED
[02:15:37.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:21:0) after 9m58s
[02:25:35.603] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:21:0)
[02:25:35.626] Build status: STARTED
[02:25:35.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:22:0) after 4m6s
[02:29:41.695] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:22:0)
[02:29:41.721] Build status: STARTED
[02:29:41.721] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:23:0) after 6m31s
[02:36:12.741] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:23:0)
[02:36:12.782] Build status: STARTED
[02:36:12.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:24:0) after 3m1s
[02:39:13.860] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:24:0)
[02:39:13.915] Build status: STARTED
[02:39:13.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:25:0) after 4m15s
[02:43:28.954] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:25:0)
[02:43:28.975] Build status: STARTED
[02:43:28.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888975559056520928:26:0) after 9m19s
[02:45:58.238] Received PubSub notification, asking Buildbucket for the build status
[02:45:58.293] Build:
{
"id": "8723545497155499473",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-08T00:48:53.569591696Z",
"startTime": "2025-02-08T00:48:58.766869295Z",
"endTime": "2025-02-08T02:45:57.819964860Z",
"updateTime": "2025-02-08T02:45:57.819964860Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "2127db085c1b89018f0fcceb4a375d86c3e7e330",
"ref": "refs/heads/main"
}
}
}
[02:45:58.293] Invocation finished in 1h57m5.920348348s with status SUCCEEDED