[11:07:10.058] New invocation is queued and will start shortly
[11:07:11.145] Starting the invocation (attempt 1)
[11:07:11.177] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/1277bb5c55bd35eb3e9aae2e5200263d479d0e25"
[11:07:11.177] Popped gitiles commit info from properties and tags
[11:07:11.177] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[11:07:11.180] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[11:07:11.180] Buildbucket request:
{
"requestId": "9014685248694998528",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "0499aa54d7e11ee7c7e996b84ab4bca1619c9093"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/master@0499aa54d7e11ee7c7e996b84ab4bca1619c9093",
"title": "0499aa54d7e11ee7c7e996b84ab4bca1619c9093",
"url": "https://chromium.googlesource.com/v8/v8/+/0499aa54d7e11ee7c7e996b84ab4bca1619c9093"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "1277bb5c55bd35eb3e9aae2e5200263d479d0e25"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/master@1277bb5c55bd35eb3e9aae2e5200263d479d0e25",
"title": "1277bb5c55bd35eb3e9aae2e5200263d479d0e25",
"url": "https://chromium.googlesource.com/v8/v8/+/1277bb5c55bd35eb3e9aae2e5200263d479d0e25"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "1277bb5c55bd35eb3e9aae2e5200263d479d0e25",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9014685248694998528"
},
{
"key": "scheduler_job_id",
"value": "v8/V8 Win64 - dev image"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[11:07:11.760] Scheduled build:
{
"id": "8849255186732782704",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 1278,
"createdBy": "project:v8",
"createTime": "2021-04-22T11:07:11.296547Z",
"updateTime": "2021-04-22T11:07:11.673474Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "1277bb5c55bd35eb3e9aae2e5200263d479d0e25",
"ref": "refs/heads/master"
}
}
}
[11:07:11.760] Task URL: https://cr-buildbucket-dev.appspot.com/build/8849255186732782704
[11:07:11.760] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:2:0) after 3m45s
[11:10:56.864] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:2:0)
[11:10:56.931] Build status: SCHEDULED
[11:10:56.931] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:3:0) after 8m21s
[11:19:17.972] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:3:0)
[11:19:18.014] Build status: SCHEDULED
[11:19:18.014] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:4:0) after 3m15s
[11:22:33.055] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:4:0)
[11:22:33.113] Build status: SCHEDULED
[11:22:33.113] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:5:0) after 8m17s
[11:30:50.154] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:5:0)
[11:30:50.242] Build status: SCHEDULED
[11:30:50.242] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:6:0) after 9m50s
[11:40:40.268] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:6:0)
[11:40:40.324] Build status: SCHEDULED
[11:40:40.324] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:7:0) after 8m16s
[11:48:56.346] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:7:0)
[11:48:56.430] Build status: SCHEDULED
[11:48:56.431] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:8:0) after 3m36s
[11:52:32.463] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:8:0)
[11:52:32.549] Build status: SCHEDULED
[11:52:32.549] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:9:0) after 2m59s
[11:55:31.656] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:9:0)
[11:55:31.732] Build status: SCHEDULED
[11:55:31.732] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:10:0) after 7m45s
[12:03:16.755] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:10:0)
[12:03:16.808] Build status: SCHEDULED
[12:03:16.808] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:11:0) after 7m38s
[12:10:54.830] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:11:0)
[12:10:54.889] Build status: SCHEDULED
[12:10:54.889] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:12:0) after 3m47s
[12:14:41.952] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:12:0)
[12:14:42.010] Build status: SCHEDULED
[12:14:42.010] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:13:0) after 9m51s
[12:24:33.064] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:13:0)
[12:24:33.127] Build status: SCHEDULED
[12:24:33.127] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:14:0) after 2m35s
[12:27:08.154] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:14:0)
[12:27:08.217] Build status: SCHEDULED
[12:27:08.217] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:15:0) after 9m26s
[12:36:34.243] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:15:0)
[12:36:34.286] Build status: SCHEDULED
[12:36:34.286] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:16:0) after 8m33s
[12:45:07.347] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:16:0)
[12:45:07.403] Build status: SCHEDULED
[12:45:07.403] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:17:0) after 1m39s
[12:46:46.447] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:17:0)
[12:46:46.492] Build status: SCHEDULED
[12:46:46.492] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:18:0) after 9m48s
[12:56:34.513] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:18:0)
[12:56:34.578] Build status: SCHEDULED
[12:56:34.578] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:19:0) after 9m54s
[13:06:28.644] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:19:0)
[13:06:28.702] Build status: SCHEDULED
[13:06:28.702] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:20:0) after 9m50s
[13:16:18.752] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:20:0)
[13:16:18.802] Build status: SCHEDULED
[13:16:18.802] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:21:0) after 4m23s
[13:20:41.869] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:21:0)
[13:20:41.914] Build status: SCHEDULED
[13:20:41.914] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:22:0) after 9m0s
[13:29:41.952] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:22:0)
[13:29:41.998] Build status: SCHEDULED
[13:29:41.998] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:23:0) after 1m4s
[13:30:46.059] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:23:0)
[13:30:46.104] Build status: SCHEDULED
[13:30:46.104] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:24:0) after 1m42s
[13:32:28.078] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:24:0)
[13:32:28.115] Build status: SCHEDULED
[13:32:28.115] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:25:0) after 6m46s
[13:39:14.140] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:25:0)
[13:39:14.177] Build status: SCHEDULED
[13:39:14.177] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:26:0) after 8m22s
[13:47:36.261] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:26:0)
[13:47:36.317] Build status: SCHEDULED
[13:47:36.317] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:27:0) after 2m40s
[13:50:16.355] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:27:0)
[13:50:16.441] Build status: SCHEDULED
[13:50:16.441] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:28:0) after 3m29s
[13:53:45.462] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:28:0)
[13:53:45.536] Build status: SCHEDULED
[13:53:45.536] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:29:0) after 9m30s
[14:03:15.557] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:29:0)
[14:03:15.648] Build status: SCHEDULED
[14:03:15.648] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:30:0) after 1m20s
[14:04:35.756] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:30:0)
[14:04:35.824] Build status: SCHEDULED
[14:04:35.824] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:31:0) after 3m5s
[14:07:40.846] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:31:0)
[14:07:40.902] Build status: SCHEDULED
[14:07:40.902] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:32:0) after 8m5s
[14:15:45.955] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:32:0)
[14:15:46.008] Build status: SCHEDULED
[14:15:46.009] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:33:0) after 5m56s
[14:21:42.065] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:33:0)
[14:21:42.159] Build status: SCHEDULED
[14:21:42.159] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:34:0) after 2m20s
[14:24:02.179] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:34:0)
[14:24:02.219] Build status: SCHEDULED
[14:24:02.219] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:35:0) after 5m8s
[14:29:10.240] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:35:0)
[14:29:10.286] Build status: SCHEDULED
[14:29:10.286] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:36:0) after 8m43s
[14:34:11.148] Received PubSub notification, asking Buildbucket for the build status
[14:34:11.214] Build status: STARTED
[14:37:53.312] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:36:0)
[14:37:53.444] Build status: STARTED
[14:37:53.444] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9014685248694998528:38:0) after 3m41s
[14:38:36.282] Received PubSub notification, asking Buildbucket for the build status
[14:38:36.330] Build:
{
"id": "8849255186732782704",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 1278,
"createdBy": "project:v8",
"createTime": "2021-04-22T11:07:11.296547Z",
"startTime": "2021-04-22T14:34:07.675808Z",
"endTime": "2021-04-22T14:38:34.894636773Z",
"updateTime": "2021-04-22T14:38:34.895156492Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "1277bb5c55bd35eb3e9aae2e5200263d479d0e25",
"ref": "refs/heads/master"
}
}
}
[14:38:36.330] Invocation finished in 3h31m26.289180776s with status SUCCEEDED