[18:15:19.266] New invocation is queued and will start shortly
[18:15:20.328] Starting the invocation (attempt 1)
[18:15:20.358] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/6d866432ff570a9b438da5d494a9cba416732c7a"
[18:15:20.358] Popped gitiles commit info from properties and tags
[18:15:20.358] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:15:20.358] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:15:20.359] Buildbucket request:
{
"requestId": "8893711361455179600",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8893711361455179600",
"job": "fuchsia/core.x64-full_determinism-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1500c1444cb5430892bc42b8b67fb0fbdee77782"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1500c1444cb5430892bc42b8b67fb0fbdee77782",
"title": "1500c1444cb5430892bc42b8b67fb0fbdee77782",
"url": "https://fuchsia.googlesource.com/integration/+/1500c1444cb5430892bc42b8b67fb0fbdee77782"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b15d42f9235b3b0d3bad482b02a99cdf520bae1c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b15d42f9235b3b0d3bad482b02a99cdf520bae1c",
"title": "b15d42f9235b3b0d3bad482b02a99cdf520bae1c",
"url": "https://fuchsia.googlesource.com/integration/+/b15d42f9235b3b0d3bad482b02a99cdf520bae1c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "785d6bccb04e85d227a9aad775d14e89dfe6ac83"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@785d6bccb04e85d227a9aad775d14e89dfe6ac83",
"title": "785d6bccb04e85d227a9aad775d14e89dfe6ac83",
"url": "https://fuchsia.googlesource.com/integration/+/785d6bccb04e85d227a9aad775d14e89dfe6ac83"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "10489de1dd8d78ee59a4a9209c54fd5fba2c905a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@10489de1dd8d78ee59a4a9209c54fd5fba2c905a",
"title": "10489de1dd8d78ee59a4a9209c54fd5fba2c905a",
"url": "https://fuchsia.googlesource.com/integration/+/10489de1dd8d78ee59a4a9209c54fd5fba2c905a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c622f6d572905bb26654eb5ddeb482ef3a8647d4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c622f6d572905bb26654eb5ddeb482ef3a8647d4",
"title": "c622f6d572905bb26654eb5ddeb482ef3a8647d4",
"url": "https://fuchsia.googlesource.com/integration/+/c622f6d572905bb26654eb5ddeb482ef3a8647d4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "66810e7a923d9b888621ba6960ba17b2f9e7b9f8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@66810e7a923d9b888621ba6960ba17b2f9e7b9f8",
"title": "66810e7a923d9b888621ba6960ba17b2f9e7b9f8",
"url": "https://fuchsia.googlesource.com/integration/+/66810e7a923d9b888621ba6960ba17b2f9e7b9f8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "81e8f815a202550cc43f99a6a32e615abaaf15e1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@81e8f815a202550cc43f99a6a32e615abaaf15e1",
"title": "81e8f815a202550cc43f99a6a32e615abaaf15e1",
"url": "https://fuchsia.googlesource.com/integration/+/81e8f815a202550cc43f99a6a32e615abaaf15e1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6d866432ff570a9b438da5d494a9cba416732c7a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6d866432ff570a9b438da5d494a9cba416732c7a",
"title": "6d866432ff570a9b438da5d494a9cba416732c7a",
"url": "https://fuchsia.googlesource.com/integration/+/6d866432ff570a9b438da5d494a9cba416732c7a"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6d866432ff570a9b438da5d494a9cba416732c7a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8893711361455179600"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-full_determinism-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:15:20.730] Scheduled build:
{
"id": "8728281299598612129",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"createdBy": "project:fuchsia",
"createTime": "2024-12-17T18:15:20.411175675Z",
"updateTime": "2024-12-17T18:15:20.411175675Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6d866432ff570a9b438da5d494a9cba416732c7a",
"ref": "refs/heads/main"
}
}
}
[18:15:20.730] Task URL: https://cr-buildbucket.appspot.com/build/8728281299598612129
[18:15:20.730] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:2:0) after 7m40s
[18:15:21.709] Received PubSub notification, asking Buildbucket for the build status
[18:15:21.733] Build status: SCHEDULED
[18:15:29.241] Received PubSub notification, asking Buildbucket for the build status
[18:15:29.265] Build status: STARTED
[18:23:00.747] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:2:0)
[18:23:00.920] Build status: STARTED
[18:23:00.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:5:0) after 5m54s
[18:28:54.988] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:5:0)
[18:28:55.018] Build status: STARTED
[18:28:55.018] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:6:0) after 1m35s
[18:30:30.060] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:6:0)
[18:30:30.085] Build status: STARTED
[18:30:30.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:7:0) after 8m7s
[18:38:37.136] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:7:0)
[18:38:37.220] Build status: STARTED
[18:38:37.220] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:8:0) after 6m2s
[18:44:39.317] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:8:0)
[18:44:39.339] Build status: STARTED
[18:44:39.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:9:0) after 5m54s
[18:50:33.372] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:9:0)
[18:50:33.442] Build status: STARTED
[18:50:33.442] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:10:0) after 7m26s
[18:57:59.637] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:10:0)
[18:57:59.658] Build status: STARTED
[18:57:59.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:11:0) after 2m36s
[19:00:35.676] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:11:0)
[19:00:35.690] Build status: STARTED
[19:00:35.690] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:12:0) after 6m54s
[19:07:29.730] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:12:0)
[19:07:29.750] Build status: STARTED
[19:07:29.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:13:0) after 4m15s
[19:11:44.767] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:13:0)
[19:11:44.782] Build status: STARTED
[19:11:44.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:14:0) after 4m40s
[19:16:24.833] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:14:0)
[19:16:24.900] Build status: STARTED
[19:16:24.900] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:15:0) after 9m58s
[19:26:22.923] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:15:0)
[19:26:22.947] Build status: STARTED
[19:26:22.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:16:0) after 5m57s
[19:32:20.074] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:16:0)
[19:32:20.094] Build status: STARTED
[19:32:20.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:17:0) after 4m6s
[19:36:26.118] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:17:0)
[19:36:26.148] Build status: STARTED
[19:36:26.148] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:18:0) after 4m35s
[19:41:01.181] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:18:0)
[19:41:01.225] Build status: STARTED
[19:41:01.225] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:19:0) after 1m6s
[19:42:07.248] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:19:0)
[19:42:07.271] Build status: STARTED
[19:42:07.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:20:0) after 9m25s
[19:51:32.287] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:20:0)
[19:51:32.307] Build status: STARTED
[19:51:32.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:21:0) after 1m47s
[19:53:19.466] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:21:0)
[19:53:19.483] Build status: STARTED
[19:53:19.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:22:0) after 1m22s
[19:54:41.502] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:22:0)
[19:54:41.681] Build status: STARTED
[19:54:41.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:23:0) after 8m42s
[20:03:23.718] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:23:0)
[20:03:23.738] Build status: STARTED
[20:03:23.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:24:0) after 1m48s
[20:05:11.804] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:24:0)
[20:05:11.850] Build status: STARTED
[20:05:11.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:25:0) after 8m13s
[20:13:24.867] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:25:0)
[20:13:24.886] Build status: STARTED
[20:13:24.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:26:0) after 8m11s
[20:21:35.905] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:26:0)
[20:21:35.928] Build status: STARTED
[20:21:35.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:27:0) after 3m15s
[20:24:51.025] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:27:0)
[20:24:51.048] Build status: STARTED
[20:24:51.048] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:28:0) after 9m37s
[20:34:28.094] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:28:0)
[20:34:28.118] Build status: STARTED
[20:34:28.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:29:0) after 7m2s
[20:41:30.136] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:29:0)
[20:41:30.153] Build status: STARTED
[20:41:30.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:30:0) after 7m51s
[20:49:21.301] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:30:0)
[20:49:21.321] Build status: STARTED
[20:49:21.321] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:31:0) after 8m51s
[20:58:12.290] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:31:0)
[20:58:12.306] Build status: STARTED
[20:58:12.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:32:0) after 2m9s
[21:00:21.319] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:32:0)
[21:00:21.341] Build status: STARTED
[21:00:21.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:33:0) after 6m19s
[21:06:40.367] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:33:0)
[21:06:40.455] Build status: STARTED
[21:06:40.455] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:34:0) after 2m7s
[21:08:47.483] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:34:0)
[21:08:47.504] Build status: STARTED
[21:08:47.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:35:0) after 9m25s
[21:18:12.540] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:35:0)
[21:18:12.560] Build status: STARTED
[21:18:12.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:36:0) after 9m17s
[21:27:29.635] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:36:0)
[21:27:29.654] Build status: STARTED
[21:27:29.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:37:0) after 4m45s
[21:32:14.671] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:37:0)
[21:32:14.697] Build status: STARTED
[21:32:14.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:38:0) after 5m24s
[21:37:38.790] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:38:0)
[21:37:38.808] Build status: STARTED
[21:37:38.808] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:39:0) after 6m1s
[21:43:39.902] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:39:0)
[21:43:39.920] Build status: STARTED
[21:43:39.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:40:0) after 3m9s
[21:46:49.069] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:40:0)
[21:46:49.094] Build status: STARTED
[21:46:49.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:41:0) after 7m43s
[21:54:32.160] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:41:0)
[21:54:32.232] Build status: STARTED
[21:54:32.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:42:0) after 5m51s
[22:00:23.242] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:42:0)
[22:00:23.259] Build status: STARTED
[22:00:23.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8893711361455179600:43:0) after 4m41s
[22:00:55.499] Received PubSub notification, asking Buildbucket for the build status
[22:00:55.525] Build:
{
"id": "8728281299598612129",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"createdBy": "project:fuchsia",
"createTime": "2024-12-17T18:15:20.411175675Z",
"startTime": "2024-12-17T18:15:28.356893692Z",
"endTime": "2024-12-17T22:00:55.098443104Z",
"updateTime": "2024-12-17T22:00:55.098443104Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6d866432ff570a9b438da5d494a9cba416732c7a",
"ref": "refs/heads/main"
}
}
}
[22:00:55.525] Invocation finished in 3h45m36.268065961s with status FAILED