[17:29:12.675] New invocation is queued and will start shortly
[17:29:13.872] Starting the invocation (attempt 1)
[17:29:13.919] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/3dea5aea0aa95a6c7d5707a5b03e75c756b967c4"
[17:29:13.919] Popped gitiles commit info from properties and tags
[17:29:13.919] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:29:13.919] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:29:13.919] Buildbucket request:
{
"requestId": "8966101238623613248",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8966101238623613248",
"job": "v8/V8 Blink Win",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "4804c4de313c2e5e41407518a97f61abf8d33331"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@4804c4de313c2e5e41407518a97f61abf8d33331",
"title": "4804c4de313c2e5e41407518a97f61abf8d33331",
"url": "https://chromium.googlesource.com/v8/v8/+/4804c4de313c2e5e41407518a97f61abf8d33331"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "252a9bfb2bbc23d00086c7c36880fa6c889b6249"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@252a9bfb2bbc23d00086c7c36880fa6c889b6249",
"title": "252a9bfb2bbc23d00086c7c36880fa6c889b6249",
"url": "https://chromium.googlesource.com/v8/v8/+/252a9bfb2bbc23d00086c7c36880fa6c889b6249"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "c5e56d450b172ccf7cdc02f2d014da7306a18938"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@c5e56d450b172ccf7cdc02f2d014da7306a18938",
"title": "c5e56d450b172ccf7cdc02f2d014da7306a18938",
"url": "https://chromium.googlesource.com/v8/v8/+/c5e56d450b172ccf7cdc02f2d014da7306a18938"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "964961b0292df3caede73bae6c9fbe5793ba4ea7"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@964961b0292df3caede73bae6c9fbe5793ba4ea7",
"title": "964961b0292df3caede73bae6c9fbe5793ba4ea7",
"url": "https://chromium.googlesource.com/v8/v8/+/964961b0292df3caede73bae6c9fbe5793ba4ea7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "2c0eb6446cef0a24bf28fcf076a070e1d4e7f36e"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@2c0eb6446cef0a24bf28fcf076a070e1d4e7f36e",
"title": "2c0eb6446cef0a24bf28fcf076a070e1d4e7f36e",
"url": "https://chromium.googlesource.com/v8/v8/+/2c0eb6446cef0a24bf28fcf076a070e1d4e7f36e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "4e39baa8acb4adf93e81005c0002490c332a634d"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@4e39baa8acb4adf93e81005c0002490c332a634d",
"title": "4e39baa8acb4adf93e81005c0002490c332a634d",
"url": "https://chromium.googlesource.com/v8/v8/+/4e39baa8acb4adf93e81005c0002490c332a634d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "3dea5aea0aa95a6c7d5707a5b03e75c756b967c4"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@3dea5aea0aa95a6c7d5707a5b03e75c756b967c4",
"title": "3dea5aea0aa95a6c7d5707a5b03e75c756b967c4",
"url": "https://chromium.googlesource.com/v8/v8/+/3dea5aea0aa95a6c7d5707a5b03e75c756b967c4"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "3dea5aea0aa95a6c7d5707a5b03e75c756b967c4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8966101238623613248"
},
{
"key": "scheduler_job_id",
"value": "v8/V8 Blink Win"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:29:14.464] Scheduled build:
{
"id": "8800671176536666305",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"number": 9747,
"createdBy": "project:v8",
"createTime": "2022-10-10T17:29:14.008265348Z",
"updateTime": "2022-10-10T17:29:14.008265348Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "3dea5aea0aa95a6c7d5707a5b03e75c756b967c4",
"ref": "refs/heads/main"
}
}
}
[17:29:14.464] Task URL: https://cr-buildbucket.appspot.com/build/8800671176536666305
[17:29:14.464] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:2:0) after 3m0s
[17:32:14.486] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:2:0)
[17:32:14.511] Build status: SCHEDULED
[17:32:14.512] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:3:0) after 8m54s
[17:35:02.013] Received PubSub notification, asking Buildbucket for the build status
[17:35:02.063] Build status: STARTED
[17:41:08.534] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:3:0)
[17:41:08.557] Build status: STARTED
[17:41:08.557] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:5:0) after 9m19s
[17:50:27.539] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:5:0)
[17:50:27.574] Build status: STARTED
[17:50:27.574] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:6:0) after 4m24s
[17:54:51.629] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:6:0)
[17:54:51.655] Build status: STARTED
[17:54:51.655] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:7:0) after 3m20s
[17:58:11.677] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:7:0)
[17:58:11.720] Build status: STARTED
[17:58:11.720] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:8:0) after 4m31s
[18:02:42.707] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:8:0)
[18:02:42.753] Build status: STARTED
[18:02:42.753] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:9:0) after 4m5s
[18:06:47.857] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:9:0)
[18:06:47.895] Build status: STARTED
[18:06:47.895] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:10:0) after 3m11s
[18:09:58.916] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:10:0)
[18:09:58.949] Build status: STARTED
[18:09:58.949] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:11:0) after 7m56s
[18:17:54.986] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:11:0)
[18:17:55.018] Build status: STARTED
[18:17:55.018] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:12:0) after 7m8s
[18:25:03.044] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:12:0)
[18:25:03.087] Build status: STARTED
[18:25:03.087] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:13:0) after 2m40s
[18:27:43.297] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:13:0)
[18:27:43.345] Build status: STARTED
[18:27:43.345] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:14:0) after 2m37s
[18:30:20.517] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:14:0)
[18:30:20.542] Build status: STARTED
[18:30:20.542] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:15:0) after 8m47s
[18:39:07.561] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:15:0)
[18:39:07.587] Build status: STARTED
[18:39:07.587] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:16:0) after 8m54s
[18:48:01.898] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:16:0)
[18:48:01.932] Build status: STARTED
[18:48:01.932] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:17:0) after 4m23s
[18:52:24.968] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:17:0)
[18:52:24.993] Build status: STARTED
[18:52:24.993] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:18:0) after 8m17s
[19:00:42.339] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:18:0)
[19:00:42.364] Build status: STARTED
[19:00:42.364] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:19:0) after 7m7s
[19:07:49.435] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:19:0)
[19:07:49.463] Build status: STARTED
[19:07:49.463] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:20:0) after 3m48s
[19:11:37.486] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:20:0)
[19:11:37.511] Build status: STARTED
[19:11:37.511] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:21:0) after 5m55s
[19:17:32.866] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:21:0)
[19:17:32.891] Build status: STARTED
[19:17:32.891] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:22:0) after 3m45s
[19:21:17.919] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:22:0)
[19:21:17.947] Build status: STARTED
[19:21:17.947] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:23:0) after 7m21s
[19:28:39.499] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:23:0)
[19:28:39.538] Build status: STARTED
[19:28:39.538] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:24:0) after 5m52s
[19:34:31.575] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:24:0)
[19:34:31.608] Build status: STARTED
[19:34:31.609] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:25:0) after 8m8s
[19:42:39.635] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:25:0)
[19:42:39.662] Build status: STARTED
[19:42:39.662] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:26:0) after 3m48s
[19:46:27.702] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:26:0)
[19:46:27.723] Build status: STARTED
[19:46:27.724] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:27:0) after 6m22s
[19:52:49.949] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:27:0)
[19:52:49.972] Build status: STARTED
[19:52:49.972] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:28:0) after 7m28s
[20:00:18.024] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:28:0)
[20:00:18.048] Build status: STARTED
[20:00:18.048] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:29:0) after 5m21s
[20:05:39.269] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:29:0)
[20:05:39.298] Build status: STARTED
[20:05:39.298] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:30:0) after 1m19s
[20:06:58.764] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:30:0)
[20:06:58.791] Build status: STARTED
[20:06:58.791] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:31:0) after 3m29s
[20:10:27.820] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:31:0)
[20:10:27.856] Build status: STARTED
[20:10:27.856] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:32:0) after 6m0s
[20:16:28.098] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:32:0)
[20:16:28.127] Build status: STARTED
[20:16:28.127] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:33:0) after 6m6s
[20:22:34.503] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:33:0)
[20:22:34.526] Build status: STARTED
[20:22:34.526] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:34:0) after 6m25s
[20:28:59.830] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:34:0)
[20:28:59.853] Build status: STARTED
[20:28:59.853] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:35:0) after 3m42s
[20:32:42.099] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:35:0)
[20:32:42.131] Build status: STARTED
[20:32:42.132] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:36:0) after 7m1s
[20:39:43.190] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:36:0)
[20:39:43.227] Build status: STARTED
[20:39:43.227] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966101238623613248:37:0) after 5m32s
[20:45:14.341] Received PubSub notification, asking Buildbucket for the build status
[20:45:14.371] Build:
{
"id": "8800671176536666305",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"number": 9747,
"createdBy": "project:v8",
"createTime": "2022-10-10T17:29:14.008265348Z",
"startTime": "2022-10-10T17:35:01.081105Z",
"endTime": "2022-10-10T20:45:13.461053Z",
"updateTime": "2022-10-10T20:45:13.461053Z",
"status": "INFRA_FAILURE",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "3dea5aea0aa95a6c7d5707a5b03e75c756b967c4",
"ref": "refs/heads/main"
}
}
}
[20:45:14.371] Invocation finished in 3h16m1.736684104s with status FAILED