[16:38:44.192] New invocation is queued and will start shortly
[16:38:45.252] Starting the invocation (attempt 1)
[16:38:45.279] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3dccb91006d50093c5931a66a780625c695b3506"
[16:38:45.279] Popped gitiles commit info from properties and tags
[16:38:45.279] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:38:45.279] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:38:45.279] Buildbucket request:
{
"requestId": "8886107292860643184",
"builder": {
"project": "chromium",
"bucket": "reclient",
"builder": "Linux Builder (canonical wd) (reclient compare)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3dccb91006d50093c5931a66a780625c695b3506",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8886107292860643184"
},
{
"key": "scheduler_job_id",
"value": "chromium/Linux Builder (canonical wd) (reclient compare)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:38:45.828] Scheduled build:
{
"id": "8720677230986003889",
"builder": {
"project": "chromium",
"bucket": "reclient",
"builder": "Linux Builder (canonical wd) (reclient compare)"
},
"number": 10038,
"createdBy": "project:chromium",
"createTime": "2025-03-11T16:38:45.347545329Z",
"updateTime": "2025-03-11T16:38:45.347545329Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3dccb91006d50093c5931a66a780625c695b3506",
"ref": "refs/heads/main"
}
}
}
[16:38:45.828] Task URL: https://cr-buildbucket.appspot.com/build/8720677230986003889
[16:38:45.828] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:2:0) after 5m20s
[16:38:46.818] Received PubSub notification, asking Buildbucket for the build status
[16:38:46.851] Build status: SCHEDULED
[16:39:53.472] Received PubSub notification, asking Buildbucket for the build status
[16:39:53.499] Build status: STARTED
[16:44:05.863] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:2:0)
[16:44:05.894] Build status: STARTED
[16:44:05.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:5:0) after 4m58s
[16:49:04.011] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:5:0)
[16:49:04.038] Build status: STARTED
[16:49:04.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:6:0) after 7m5s
[16:56:09.196] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:6:0)
[16:56:09.262] Build status: STARTED
[16:56:09.262] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:7:0) after 7m3s
[17:03:12.291] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:7:0)
[17:03:12.309] Build status: STARTED
[17:03:12.309] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:8:0) after 4m3s
[17:07:15.441] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:8:0)
[17:07:15.462] Build status: STARTED
[17:07:15.462] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:9:0) after 2m6s
[17:09:21.490] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:9:0)
[17:09:21.512] Build status: STARTED
[17:09:21.512] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:10:0) after 4m21s
[17:13:42.597] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:10:0)
[17:13:42.629] Build status: STARTED
[17:13:42.629] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:11:0) after 9m15s
[17:22:57.683] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:11:0)
[17:22:57.717] Build status: STARTED
[17:22:57.717] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:12:0) after 5m50s
[17:28:47.718] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:12:0)
[17:28:47.740] Build status: STARTED
[17:28:47.740] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:13:0) after 3m43s
[17:32:30.768] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:13:0)
[17:32:30.786] Build status: STARTED
[17:32:30.786] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:14:0) after 7m54s
[17:40:24.811] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:14:0)
[17:40:24.840] Build status: STARTED
[17:40:24.840] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:15:0) after 3m2s
[17:43:26.864] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:15:0)
[17:43:26.883] Build status: STARTED
[17:43:26.883] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:16:0) after 9m11s
[17:52:37.931] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:16:0)
[17:52:37.949] Build status: STARTED
[17:52:37.949] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:17:0) after 9m13s
[18:01:50.989] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:17:0)
[18:01:51.006] Build status: STARTED
[18:01:51.006] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:18:0) after 5m1s
[18:06:52.033] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:18:0)
[18:06:52.049] Build status: STARTED
[18:06:52.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:19:0) after 4m40s
[18:11:32.077] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:19:0)
[18:11:32.098] Build status: STARTED
[18:11:32.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:20:0) after 1m9s
[18:12:41.119] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:20:0)
[18:12:41.164] Build status: STARTED
[18:12:41.164] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:21:0) after 6m58s
[18:19:39.172] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:21:0)
[18:19:39.191] Build status: STARTED
[18:19:39.191] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:22:0) after 7m32s
[18:27:11.230] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:22:0)
[18:27:11.263] Build status: STARTED
[18:27:11.263] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:23:0) after 4m26s
[18:31:37.287] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:23:0)
[18:31:37.306] Build status: STARTED
[18:31:37.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:24:0) after 9m54s
[18:41:31.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:24:0)
[18:41:31.401] Build status: STARTED
[18:41:31.401] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:25:0) after 9m29s
[18:51:00.439] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:25:0)
[18:51:00.455] Build status: STARTED
[18:51:00.455] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:26:0) after 1m59s
[18:52:59.540] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:26:0)
[18:52:59.561] Build status: STARTED
[18:52:59.561] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:27:0) after 7m34s
[19:00:33.601] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:27:0)
[19:00:33.623] Build status: STARTED
[19:00:33.623] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:28:0) after 1m52s
[19:02:25.641] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:28:0)
[19:02:26.188] Build status: STARTED
[19:02:26.188] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:29:0) after 7m3s
[19:09:29.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:29:0)
[19:09:29.279] Build status: STARTED
[19:09:29.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:30:0) after 9m49s
[19:19:18.304] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:30:0)
[19:19:18.332] Build status: STARTED
[19:19:18.332] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:31:0) after 6m45s
[19:26:03.424] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:31:0)
[19:26:03.451] Build status: STARTED
[19:26:03.451] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:32:0) after 1m16s
[19:27:19.479] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:32:0)
[19:27:19.496] Build status: STARTED
[19:27:19.496] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:33:0) after 7m10s
[19:34:29.628] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:33:0)
[19:34:29.646] Build status: STARTED
[19:34:29.646] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:34:0) after 6m59s
[19:41:28.674] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:34:0)
[19:41:28.690] Build status: STARTED
[19:41:28.690] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:35:0) after 2m19s
[19:43:47.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:35:0)
[19:43:47.741] Build status: STARTED
[19:43:47.741] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:36:0) after 8m7s
[19:51:54.761] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:36:0)
[19:51:54.839] Build status: STARTED
[19:51:54.839] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:37:0) after 2m52s
[19:54:46.960] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:37:0)
[19:54:46.986] Build status: STARTED
[19:54:46.986] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:38:0) after 4m53s
[19:59:40.073] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:38:0)
[19:59:40.090] Build status: STARTED
[19:59:40.090] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:39:0) after 1m9s
[20:00:49.161] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:39:0)
[20:00:49.176] Build status: STARTED
[20:00:49.176] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8886107292860643184:40:0) after 6m48s
[20:05:42.473] Received PubSub notification, asking Buildbucket for the build status
[20:05:42.496] Build:
{
"id": "8720677230986003889",
"builder": {
"project": "chromium",
"bucket": "reclient",
"builder": "Linux Builder (canonical wd) (reclient compare)"
},
"number": 10038,
"createdBy": "project:chromium",
"createTime": "2025-03-11T16:38:45.347545329Z",
"startTime": "2025-03-11T16:39:53.247997913Z",
"endTime": "2025-03-11T20:05:42.094252560Z",
"updateTime": "2025-03-11T20:05:42.094252560Z",
"status": "INFRA_FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3dccb91006d50093c5931a66a780625c695b3506",
"ref": "refs/heads/main"
}
}
}
[20:05:42.497] Invocation finished in 3h26m58.320137646s with status FAILED