[04:04:48.723] New invocation is queued and will start shortly
[04:04:49.939] Starting the invocation (attempt 1)
[04:04:49.976] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/865e3d8b6cee4e58638772c8b150dba7cd91ec6c"
[04:04:49.976] Popped gitiles commit info from properties and tags
[04:04:49.976] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:04:49.976] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:04:49.977] Buildbucket request:
{
"requestId": "8907988594579401216",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8907988594579401216",
"job": "fuchsia/core.x64-full_determinism-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "39b98a9185c0548606deab7a4d89de1c92c87833"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@39b98a9185c0548606deab7a4d89de1c92c87833",
"title": "39b98a9185c0548606deab7a4d89de1c92c87833",
"url": "https://fuchsia.googlesource.com/integration/+/39b98a9185c0548606deab7a4d89de1c92c87833"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6c9a498e89234982844e94751189fac75bb4036d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6c9a498e89234982844e94751189fac75bb4036d",
"title": "6c9a498e89234982844e94751189fac75bb4036d",
"url": "https://fuchsia.googlesource.com/integration/+/6c9a498e89234982844e94751189fac75bb4036d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "20378488790b9d5532c2a04658cc29ecac49bdb0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@20378488790b9d5532c2a04658cc29ecac49bdb0",
"title": "20378488790b9d5532c2a04658cc29ecac49bdb0",
"url": "https://fuchsia.googlesource.com/integration/+/20378488790b9d5532c2a04658cc29ecac49bdb0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8e51881edac277356f45af6e81887250fbe60fac"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8e51881edac277356f45af6e81887250fbe60fac",
"title": "8e51881edac277356f45af6e81887250fbe60fac",
"url": "https://fuchsia.googlesource.com/integration/+/8e51881edac277356f45af6e81887250fbe60fac"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "77cbdd9a3b0b2d1df91fce26780bd1adcc299519"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@77cbdd9a3b0b2d1df91fce26780bd1adcc299519",
"title": "77cbdd9a3b0b2d1df91fce26780bd1adcc299519",
"url": "https://fuchsia.googlesource.com/integration/+/77cbdd9a3b0b2d1df91fce26780bd1adcc299519"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c641c00982c8cec9c3b361780285a2eeb3dd8073"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c641c00982c8cec9c3b361780285a2eeb3dd8073",
"title": "c641c00982c8cec9c3b361780285a2eeb3dd8073",
"url": "https://fuchsia.googlesource.com/integration/+/c641c00982c8cec9c3b361780285a2eeb3dd8073"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b33b149345bfcc6d4e2d8f695f032e3d350dada7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b33b149345bfcc6d4e2d8f695f032e3d350dada7",
"title": "b33b149345bfcc6d4e2d8f695f032e3d350dada7",
"url": "https://fuchsia.googlesource.com/integration/+/b33b149345bfcc6d4e2d8f695f032e3d350dada7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "865e3d8b6cee4e58638772c8b150dba7cd91ec6c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@865e3d8b6cee4e58638772c8b150dba7cd91ec6c",
"title": "865e3d8b6cee4e58638772c8b150dba7cd91ec6c",
"url": "https://fuchsia.googlesource.com/integration/+/865e3d8b6cee4e58638772c8b150dba7cd91ec6c"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "865e3d8b6cee4e58638772c8b150dba7cd91ec6c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8907988594579401216"
},
{
"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"
}
}
[04:04:50.411] Scheduled build:
{
"id": "8742558532535422545",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"createdBy": "project:fuchsia",
"createTime": "2024-07-13T04:04:50.050966942Z",
"updateTime": "2024-07-13T04:04:50.050966942Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "865e3d8b6cee4e58638772c8b150dba7cd91ec6c",
"ref": "refs/heads/main"
}
}
}
[04:04:50.411] Task URL: https://cr-buildbucket.appspot.com/build/8742558532535422545
[04:04:50.411] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:2:0) after 8m9s
[04:04:51.445] Received PubSub notification, asking Buildbucket for the build status
[04:04:51.479] Build status: SCHEDULED
[04:04:54.686] Received PubSub notification, asking Buildbucket for the build status
[04:04:54.743] Build status: STARTED
[04:12:59.716] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:2:0)
[04:12:59.757] Build status: STARTED
[04:12:59.757] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:5:0) after 3m13s
[04:16:12.990] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:5:0)
[04:16:13.016] Build status: STARTED
[04:16:13.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:6:0) after 4m25s
[04:20:38.060] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:6:0)
[04:20:38.083] Build status: STARTED
[04:20:38.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:7:0) after 3m4s
[04:23:42.154] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:7:0)
[04:23:42.252] Build status: STARTED
[04:23:42.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:8:0) after 5m59s
[04:29:41.270] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:8:0)
[04:29:41.287] Build status: STARTED
[04:29:41.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:9:0) after 9m32s
[04:39:13.404] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:9:0)
[04:39:13.501] Build status: STARTED
[04:39:13.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:10:0) after 2m54s
[04:42:07.544] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:10:0)
[04:42:07.565] Build status: STARTED
[04:42:07.565] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:11:0) after 6m50s
[04:48:57.584] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:11:0)
[04:48:57.604] Build status: STARTED
[04:48:57.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:12:0) after 9m15s
[04:58:12.643] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:12:0)
[04:58:12.662] Build status: STARTED
[04:58:12.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:13:0) after 3m32s
[05:01:44.823] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:13:0)
[05:01:44.850] Build status: STARTED
[05:01:44.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:14:0) after 5m53s
[05:07:37.871] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:14:0)
[05:07:37.896] Build status: STARTED
[05:07:37.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:15:0) after 2m25s
[05:10:02.973] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:15:0)
[05:10:03.005] Build status: STARTED
[05:10:03.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:16:0) after 4m1s
[05:14:04.100] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:16:0)
[05:14:04.126] Build status: STARTED
[05:14:04.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:17:0) after 1m13s
[05:15:17.198] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:17:0)
[05:15:17.242] Build status: STARTED
[05:15:17.242] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:18:0) after 3m43s
[05:19:00.266] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:18:0)
[05:19:00.296] Build status: STARTED
[05:19:00.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:19:0) after 3m59s
[05:22:59.512] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:19:0)
[05:22:59.532] Build status: STARTED
[05:22:59.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:20:0) after 5m44s
[05:28:43.563] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:20:0)
[05:28:43.583] Build status: STARTED
[05:28:43.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:21:0) after 6m34s
[05:35:17.616] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:21:0)
[05:35:17.642] Build status: STARTED
[05:35:17.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:22:0) after 9m18s
[05:44:35.773] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:22:0)
[05:44:35.793] Build status: STARTED
[05:44:35.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:23:0) after 4m56s
[05:49:32.009] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:23:0)
[05:49:32.031] Build status: STARTED
[05:49:32.031] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:24:0) after 5m39s
[05:55:11.049] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:24:0)
[05:55:11.090] Build status: STARTED
[05:55:11.090] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:25:0) after 7m20s
[06:02:31.116] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:25:0)
[06:02:31.151] Build status: STARTED
[06:02:31.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:26:0) after 6m55s
[06:09:26.184] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:26:0)
[06:09:26.283] Build status: STARTED
[06:09:26.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:27:0) after 2m33s
[06:11:59.502] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:27:0)
[06:11:59.521] Build status: STARTED
[06:11:59.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:28:0) after 4m20s
[06:16:19.553] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:28:0)
[06:16:19.600] Build status: STARTED
[06:16:19.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:29:0) after 1m19s
[06:17:38.687] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:29:0)
[06:17:38.713] Build status: STARTED
[06:17:38.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:30:0) after 8m35s
[06:26:13.785] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:30:0)
[06:26:13.811] Build status: STARTED
[06:26:13.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:31:0) after 1m18s
[06:27:31.831] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:31:0)
[06:27:31.850] Build status: STARTED
[06:27:31.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:32:0) after 1m9s
[06:28:40.969] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:32:0)
[06:28:41.000] Build status: STARTED
[06:28:41.000] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:33:0) after 3m32s
[06:32:13.113] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:33:0)
[06:32:13.203] Build status: STARTED
[06:32:13.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:34:0) after 8m2s
[06:40:15.379] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:34:0)
[06:40:15.400] Build status: STARTED
[06:40:15.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:35:0) after 7m15s
[06:47:30.637] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:35:0)
[06:47:30.725] Build status: STARTED
[06:47:30.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:36:0) after 9m24s
[06:56:54.742] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:36:0)
[06:56:54.760] Build status: STARTED
[06:56:54.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:37:0) after 9m32s
[07:06:26.972] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:37:0)
[07:06:27.045] Build status: STARTED
[07:06:27.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:38:0) after 3m31s
[07:09:58.078] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:38:0)
[07:09:58.098] Build status: STARTED
[07:09:58.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:39:0) after 9m38s
[07:19:36.117] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:39:0)
[07:19:36.133] Build status: STARTED
[07:19:36.133] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:40:0) after 9m0s
[07:28:36.144] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:40:0)
[07:28:36.160] Build status: STARTED
[07:28:36.160] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:41:0) after 7m13s
[07:35:49.199] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:41:0)
[07:35:49.329] Build status: STARTED
[07:35:49.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:42:0) after 4m6s
[07:39:55.420] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:42:0)
[07:39:55.445] Build status: STARTED
[07:39:55.445] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:43:0) after 8m38s
[07:48:33.477] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:43:0)
[07:48:33.506] Build status: STARTED
[07:48:33.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:44:0) after 6m10s
[07:54:43.562] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:44:0)
[07:54:43.587] Build status: STARTED
[07:54:43.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8907988594579401216:45:0) after 6m16s
[07:58:26.147] Received PubSub notification, asking Buildbucket for the build status
[07:58:26.168] Build:
{
"id": "8742558532535422545",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"createdBy": "project:fuchsia",
"createTime": "2024-07-13T04:04:50.050966942Z",
"startTime": "2024-07-13T04:04:54.430569156Z",
"endTime": "2024-07-13T07:58:25.844148978Z",
"updateTime": "2024-07-13T07:58:25.844148978Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "865e3d8b6cee4e58638772c8b150dba7cd91ec6c",
"ref": "refs/heads/main"
}
}
}
[07:58:26.168] Invocation finished in 3h53m37.450481766s with status FAILED