[07:18:58.128] New invocation is queued and will start shortly
[07:18:59.203] Starting the invocation (attempt 1)
[07:18:59.234] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bd6b801ed049f6ee4761133383640cf6b172a97e"
[07:18:59.234] Popped gitiles commit info from properties and tags
[07:18:59.234] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:18:59.235] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:18:59.235] Buildbucket request:
{
"requestId": "9014337217853803856",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Windows deterministic"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bd6b801ed049f6ee4761133383640cf6b172a97e",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9014337217853803856"
},
{
"key": "scheduler_job_id",
"value": "chromium/Windows deterministic"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[07:18:59.712] Scheduled build:
{
"id": "8848907155967827232",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Windows deterministic"
},
"number": 18291,
"createdBy": "project:chromium",
"createTime": "2021-04-26T07:18:59.298906Z",
"updateTime": "2021-04-26T07:18:59.643333Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bd6b801ed049f6ee4761133383640cf6b172a97e",
"ref": "refs/heads/master"
}
}
}
[07:18:59.712] Task URL: https://cr-buildbucket.appspot.com/build/8848907155967827232
[07:18:59.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:2:0) after 8m42s
[07:19:03.136] Received PubSub notification, asking Buildbucket for the build status
[07:19:03.174] Build status: STARTED
[07:27:41.827] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:2:0)
[07:27:41.854] Build status: STARTED
[07:27:41.854] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:4:0) after 7m28s
[07:35:10.084] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:4:0)
[07:35:10.115] Build status: STARTED
[07:35:10.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:5:0) after 1m32s
[07:36:42.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:5:0)
[07:36:42.402] Build status: STARTED
[07:36:42.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:6:0) after 1m5s
[07:37:47.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:6:0)
[07:37:47.516] Build status: STARTED
[07:37:47.516] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:7:0) after 9m12s
[07:47:00.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:7:0)
[07:47:00.066] Build status: STARTED
[07:47:00.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:8:0) after 9m4s
[07:56:04.291] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:8:0)
[07:56:04.320] Build status: STARTED
[07:56:04.320] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:9:0) after 9m38s
[08:05:42.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:9:0)
[08:05:42.406] Build status: STARTED
[08:05:42.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:10:0) after 7m48s
[08:13:30.458] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:10:0)
[08:13:30.486] Build status: STARTED
[08:13:30.486] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:11:0) after 6m20s
[08:19:51.064] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:11:0)
[08:19:51.107] Build status: STARTED
[08:19:51.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:12:0) after 2m24s
[08:22:15.225] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:12:0)
[08:22:15.266] Build status: STARTED
[08:22:15.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:13:0) after 5m29s
[08:27:44.364] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:13:0)
[08:27:44.407] Build status: STARTED
[08:27:44.407] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:14:0) after 5m57s
[08:33:41.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:14:0)
[08:33:41.734] Build status: STARTED
[08:33:41.734] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:15:0) after 9m19s
[08:43:00.749] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:15:0)
[08:43:00.778] Build status: STARTED
[08:43:00.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:16:0) after 1m56s
[08:44:56.997] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:16:0)
[08:44:57.035] Build status: STARTED
[08:44:57.035] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:17:0) after 2m30s
[08:47:27.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:17:0)
[08:47:27.317] Build status: STARTED
[08:47:27.317] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:18:0) after 1m0s
[08:48:27.567] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:18:0)
[08:48:27.593] Build status: STARTED
[08:48:27.593] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:19:0) after 6m18s
[08:54:45.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:19:0)
[08:54:45.811] Build status: STARTED
[08:54:45.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:20:0) after 9m27s
[09:04:13.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:20:0)
[09:04:13.766] Build status: STARTED
[09:04:13.766] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:21:0) after 3m24s
[09:07:37.966] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:21:0)
[09:07:37.998] Build status: STARTED
[09:07:37.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:22:0) after 4m38s
[09:12:16.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:22:0)
[09:12:16.117] Build status: STARTED
[09:12:16.117] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:23:0) after 4m14s
[09:16:30.175] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:23:0)
[09:16:30.206] Build status: STARTED
[09:16:30.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:24:0) after 5m3s
[09:21:33.225] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:24:0)
[09:21:33.250] Build status: STARTED
[09:21:33.250] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:25:0) after 8m22s
[09:29:55.268] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:25:0)
[09:29:55.294] Build status: STARTED
[09:29:55.294] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:26:0) after 8m6s
[09:38:01.289] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:26:0)
[09:38:01.325] Build status: STARTED
[09:38:01.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:27:0) after 8m58s
[09:46:59.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:27:0)
[09:46:59.376] Build status: STARTED
[09:46:59.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:28:0) after 2m17s
[09:49:16.359] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:28:0)
[09:49:16.385] Build status: STARTED
[09:49:16.385] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:29:0) after 7m5s
[09:56:21.420] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:29:0)
[09:56:21.447] Build status: STARTED
[09:56:21.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:30:0) after 3m7s
[09:59:28.573] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:30:0)
[09:59:28.598] Build status: STARTED
[09:59:28.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:31:0) after 1m58s
[10:01:26.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:31:0)
[10:01:26.652] Build status: STARTED
[10:01:26.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:32:0) after 6m28s
[10:07:54.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:32:0)
[10:07:54.699] Build status: STARTED
[10:07:54.699] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:33:0) after 6m15s
[10:14:09.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:33:0)
[10:14:09.750] Build status: STARTED
[10:14:09.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:34:0) after 5m18s
[10:19:28.164] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:34:0)
[10:19:28.198] Build status: STARTED
[10:19:28.198] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:35:0) after 9m51s
[10:29:19.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:35:0)
[10:29:19.464] Build status: STARTED
[10:29:19.464] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:36:0) after 3m17s
[10:32:37.715] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:36:0)
[10:32:37.751] Build status: STARTED
[10:32:37.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:37:0) after 5m42s
[10:38:19.885] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:37:0)
[10:38:19.918] Build status: STARTED
[10:38:19.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:38:0) after 2m33s
[10:40:52.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:38:0)
[10:40:52.968] Build status: STARTED
[10:40:52.968] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:39:0) after 3m12s
[10:44:04.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:39:0)
[10:44:05.016] Build status: STARTED
[10:44:05.016] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9014337217853803856:40:0) after 9m36s
[10:50:04.896] Received PubSub notification, asking Buildbucket for the build status
[10:50:04.926] Build:
{
"id": "8848907155967827232",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Windows deterministic"
},
"number": 18291,
"createdBy": "project:chromium",
"createTime": "2021-04-26T07:18:59.298906Z",
"startTime": "2021-04-26T07:19:02.421794Z",
"endTime": "2021-04-26T10:50:03.451131Z",
"updateTime": "2021-04-26T10:50:04.361103Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bd6b801ed049f6ee4761133383640cf6b172a97e",
"ref": "refs/heads/master"
}
}
}
[10:50:04.926] Invocation finished in 3h31m6.811251303s with status SUCCEEDED