[18:24:44.155] New invocation is queued and will start shortly
[18:24:45.301] Starting the invocation (attempt 1)
[18:24:45.417] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/b40428a30ba55518d25a20cf2b3fb1c2d7a0ed18"
[18:24:45.417] Popped gitiles commit info from properties and tags
[18:24:45.417] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[18:24:45.417] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[18:24:45.418] Buildbucket request:
{
"requestId": "9012030407254185024",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "965ff050e231d77ce69a248f184181f853f9ee33"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@965ff050e231d77ce69a248f184181f853f9ee33",
"title": "965ff050e231d77ce69a248f184181f853f9ee33",
"url": "https://chromium.googlesource.com/infra/infra/+/965ff050e231d77ce69a248f184181f853f9ee33"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "e6a7e1e7084d085e4668ce738a81b6c2287e03cb"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@e6a7e1e7084d085e4668ce738a81b6c2287e03cb",
"title": "e6a7e1e7084d085e4668ce738a81b6c2287e03cb",
"url": "https://chromium.googlesource.com/infra/infra/+/e6a7e1e7084d085e4668ce738a81b6c2287e03cb"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "6aa2a13ed902aa1be4113156987e9ee2a1cb485a"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@6aa2a13ed902aa1be4113156987e9ee2a1cb485a",
"title": "6aa2a13ed902aa1be4113156987e9ee2a1cb485a",
"url": "https://chromium.googlesource.com/infra/infra/+/6aa2a13ed902aa1be4113156987e9ee2a1cb485a"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "66844da65eb06dbe66dd8cb850f92735ed4d775d"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@66844da65eb06dbe66dd8cb850f92735ed4d775d",
"title": "66844da65eb06dbe66dd8cb850f92735ed4d775d",
"url": "https://chromium.googlesource.com/infra/infra/+/66844da65eb06dbe66dd8cb850f92735ed4d775d"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "4e63262e9350710006514e6ff2a67aa9a57a600a"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4e63262e9350710006514e6ff2a67aa9a57a600a",
"title": "4e63262e9350710006514e6ff2a67aa9a57a600a",
"url": "https://chromium.googlesource.com/infra/infra/+/4e63262e9350710006514e6ff2a67aa9a57a600a"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "c611129dbdb02098b0547062731866fd84ce19fb"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c611129dbdb02098b0547062731866fd84ce19fb",
"title": "c611129dbdb02098b0547062731866fd84ce19fb",
"url": "https://chromium.googlesource.com/infra/infra/+/c611129dbdb02098b0547062731866fd84ce19fb"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "423dbddf4ce86bcec324cab315e15700ee66d8ae"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@423dbddf4ce86bcec324cab315e15700ee66d8ae",
"title": "423dbddf4ce86bcec324cab315e15700ee66d8ae",
"url": "https://chromium.googlesource.com/infra/infra/+/423dbddf4ce86bcec324cab315e15700ee66d8ae"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "a88efff70baf307448da537b45174f227b9b0b1d"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@a88efff70baf307448da537b45174f227b9b0b1d",
"title": "a88efff70baf307448da537b45174f227b9b0b1d",
"url": "https://chromium.googlesource.com/infra/infra/+/a88efff70baf307448da537b45174f227b9b0b1d"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "b40428a30ba55518d25a20cf2b3fb1c2d7a0ed18"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@b40428a30ba55518d25a20cf2b3fb1c2d7a0ed18",
"title": "b40428a30ba55518d25a20cf2b3fb1c2d7a0ed18",
"url": "https://chromium.googlesource.com/infra/infra/+/b40428a30ba55518d25a20cf2b3fb1c2d7a0ed18"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "b40428a30ba55518d25a20cf2b3fb1c2d7a0ed18",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9012030407254185024"
},
{
"key": "scheduler_job_id",
"value": "infra/infra-continuous-win10-64"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[18:24:46.054] Scheduled build:
{
"id": "8846600345173153025",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 19483,
"createdBy": "project:infra",
"createTime": "2021-05-21T18:24:45.505332264Z",
"updateTime": "2021-05-21T18:24:45.768543563Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "b40428a30ba55518d25a20cf2b3fb1c2d7a0ed18",
"ref": "refs/heads/master"
}
}
}
[18:24:46.054] Task URL: https://cr-buildbucket-dev.appspot.com/build/8846600345173153025
[18:24:46.054] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:2:0) after 8m12s
[18:32:58.108] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:2:0)
[18:32:58.140] Build status: SCHEDULED
[18:32:58.140] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:3:0) after 3m14s
[18:36:12.306] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:3:0)
[18:36:12.340] Build status: SCHEDULED
[18:36:12.340] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:4:0) after 4m43s
[18:40:55.408] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:4:0)
[18:40:55.442] Build status: SCHEDULED
[18:40:55.442] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:5:0) after 8m25s
[18:49:20.526] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:5:0)
[18:49:20.561] Build status: SCHEDULED
[18:49:20.561] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:6:0) after 1m38s
[18:50:58.578] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:6:0)
[18:50:58.623] Build status: SCHEDULED
[18:50:58.623] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:7:0) after 5m34s
[18:56:32.708] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:7:0)
[18:56:32.735] Build status: SCHEDULED
[18:56:32.735] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:8:0) after 2m48s
[18:59:20.808] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:8:0)
[18:59:20.858] Build status: SCHEDULED
[18:59:20.859] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:9:0) after 7m27s
[19:06:47.884] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:9:0)
[19:06:47.920] Build status: SCHEDULED
[19:06:47.920] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:10:0) after 2m44s
[19:09:32.042] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:10:0)
[19:09:32.092] Build status: SCHEDULED
[19:09:32.092] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:11:0) after 6m47s
[19:16:19.128] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:11:0)
[19:16:19.172] Build status: SCHEDULED
[19:16:19.172] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:12:0) after 3m36s
[19:19:55.226] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:12:0)
[19:19:55.261] Build status: SCHEDULED
[19:19:55.261] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:13:0) after 9m43s
[19:29:38.321] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:13:0)
[19:29:38.347] Build status: SCHEDULED
[19:29:38.347] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:14:0) after 4m16s
[19:33:54.411] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:14:0)
[19:33:54.456] Build status: SCHEDULED
[19:33:54.456] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:15:0) after 5m44s
[19:39:38.519] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:15:0)
[19:39:38.548] Build status: SCHEDULED
[19:39:38.548] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:16:0) after 6m37s
[19:46:15.611] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:16:0)
[19:46:15.642] Build status: SCHEDULED
[19:46:15.642] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:17:0) after 3m57s
[19:50:13.210] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:17:0)
[19:50:13.243] Build status: SCHEDULED
[19:50:13.243] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:18:0) after 4m50s
[19:55:03.268] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:18:0)
[19:55:03.295] Build status: SCHEDULED
[19:55:03.295] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:19:0) after 6m53s
[20:01:56.318] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:19:0)
[20:01:56.357] Build status: SCHEDULED
[20:01:56.357] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:20:0) after 9m28s
[20:11:24.409] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:20:0)
[20:11:24.440] Build status: SCHEDULED
[20:11:24.440] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:21:0) after 9m55s
[20:21:19.525] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:21:0)
[20:21:19.558] Build status: SCHEDULED
[20:21:19.558] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:22:0) after 2m28s
[20:23:47.618] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:22:0)
[20:23:47.663] Build status: SCHEDULED
[20:23:47.663] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:23:0) after 5m25s
[20:29:12.723] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:23:0)
[20:29:12.769] Build status: SCHEDULED
[20:29:12.769] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:24:0) after 3m0s
[20:32:12.809] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:24:0)
[20:32:12.848] Build status: SCHEDULED
[20:32:12.848] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:25:0) after 7m17s
[20:39:29.926] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:25:0)
[20:39:29.996] Build status: SCHEDULED
[20:39:29.996] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:26:0) after 2m9s
[20:41:39.020] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:26:0)
[20:41:39.064] Build status: SCHEDULED
[20:41:39.064] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:27:0) after 1m56s
[20:43:35.109] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:27:0)
[20:43:35.148] Build status: SCHEDULED
[20:43:35.148] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:28:0) after 2m19s
[20:45:54.215] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:28:0)
[20:45:54.261] Build status: SCHEDULED
[20:45:54.261] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:29:0) after 5m12s
[20:51:06.289] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:29:0)
[20:51:06.337] Build status: SCHEDULED
[20:51:06.337] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:30:0) after 7m50s
[20:58:56.419] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:30:0)
[20:58:56.454] Build status: SCHEDULED
[20:58:56.454] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:31:0) after 2m46s
[21:01:42.477] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:31:0)
[21:01:42.504] Build status: SCHEDULED
[21:01:42.504] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:32:0) after 9m48s
[21:11:30.651] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:32:0)
[21:11:30.702] Build status: SCHEDULED
[21:11:30.703] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:33:0) after 9m56s
[21:21:26.939] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:33:0)
[21:21:26.974] Build status: SCHEDULED
[21:21:26.974] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:34:0) after 7m46s
[21:29:13.019] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:34:0)
[21:29:13.054] Build status: SCHEDULED
[21:29:13.054] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:35:0) after 4m27s
[21:33:40.090] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:35:0)
[21:33:40.134] Build status: SCHEDULED
[21:33:40.134] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:36:0) after 7m8s
[21:40:48.155] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:36:0)
[21:40:48.205] Build status: SCHEDULED
[21:40:48.205] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:37:0) after 4m22s
[21:45:10.227] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:37:0)
[21:45:10.284] Build status: SCHEDULED
[21:45:10.284] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:38:0) after 6m21s
[21:51:31.309] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:38:0)
[21:51:31.355] Build status: SCHEDULED
[21:51:31.355] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:39:0) after 2m57s
[21:54:28.376] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:39:0)
[21:54:28.419] Build status: SCHEDULED
[21:54:28.420] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:40:0) after 8m42s
[22:03:10.445] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:40:0)
[22:03:10.484] Build status: SCHEDULED
[22:03:10.484] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:41:0) after 5m33s
[22:08:43.505] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:41:0)
[22:08:43.537] Build status: SCHEDULED
[22:08:43.537] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:42:0) after 5m29s
[22:14:12.565] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:42:0)
[22:14:12.600] Build status: SCHEDULED
[22:14:12.600] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:43:0) after 9m21s
[22:23:33.633] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:43:0)
[22:23:33.688] Build status: STARTED
[22:23:33.688] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:44:0) after 4m6s
[22:27:39.716] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:44:0)
[22:27:39.752] Build status: STARTED
[22:27:39.752] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:45:0) after 9m39s
[22:37:18.778] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9012030407254185024:45:0)
[22:37:18.831] Build:
{
"id": "8846600345173153025",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 19483,
"createdBy": "project:infra",
"createTime": "2021-05-21T18:24:45.505332264Z",
"startTime": "2021-05-21T22:17:56.507246035Z",
"endTime": "2021-05-21T22:28:24.616929768Z",
"updateTime": "2021-05-21T22:28:24.617395812Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "b40428a30ba55518d25a20cf2b3fb1c2d7a0ed18",
"ref": "refs/heads/master"
}
}
}
[22:37:18.831] Invocation finished in 4h12m34.69391728s with status SUCCEEDED