[20:08:44.773] New invocation is queued and will start shortly
[20:08:46.004] Starting the invocation (attempt 1)
[20:08:46.037] Ignoring unrecognized buildset tag "buildset:commit/git/207b51c7e4fa63a7289383c321333e5e006c3f41"
[20:08:46.037] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/207b51c7e4fa63a7289383c321333e5e006c3f41"
[20:08:46.037] Popped gitiles commit info from properties and tags
[20:08:46.037] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:08:46.038] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:08:46.038] Buildbucket request:
{
"requestId": "9017731472372103888",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "207b51c7e4fa63a7289383c321333e5e006c3f41",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9017731472372103888"
},
{
"key": "scheduler_job_id",
"value": "chromium/Deterministic Linux (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:08:46.532] Scheduled build:
{
"id": "8852301410297432000",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 21190,
"createdBy": "project:chromium",
"createTime": "2021-03-19T20:08:46.120520Z",
"updateTime": "2021-03-19T20:08:46.460720Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "207b51c7e4fa63a7289383c321333e5e006c3f41",
"ref": "refs/heads/master"
}
}
}
[20:08:46.532] Task URL: https://cr-buildbucket.appspot.com/build/8852301410297432000
[20:08:46.532] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:2:0) after 3m2s
[20:09:09.727] Received PubSub notification, asking Buildbucket for the build status
[20:09:09.921] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:48.552] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:2:0)
[20:11:48.580] Build status: STARTED
[20:11:48.580] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:4:0) after 2m1s
[20:13:49.628] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:4:0)
[20:13:49.628] Timer tick, asking Buildbucket for the build status
[20:13:49.704] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:49.704] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:5:0) after 1m0s
[20:14:49.734] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:5:0)
[20:14:49.760] Build status: STARTED
[20:14:49.760] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:6:0) after 5m12s
[20:20:01.783] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:6:0)
[20:20:01.810] Build status: STARTED
[20:20:01.810] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:7:0) after 4m49s
[20:24:50.870] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:7:0)
[20:24:50.897] Build status: STARTED
[20:24:50.897] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:8:0) after 8m23s
[20:33:13.888] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:8:0)
[20:33:13.888] Timer tick, asking Buildbucket for the build status
[20:33:13.969] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:13.969] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:9:0) after 1m0s
[20:34:14.092] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:9:0)
[20:34:14.092] Timer tick, asking Buildbucket for the build status
[20:34:14.177] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:14.177] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:10:0) after 1m0s
[20:35:14.315] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:10:0)
[20:35:14.315] Timer tick, asking Buildbucket for the build status
[20:35:14.389] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:14.390] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:11:0) after 1m0s
[20:36:14.499] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:11:0)
[20:36:14.499] Timer tick, asking Buildbucket for the build status
[20:36:14.565] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:14.565] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:12:0) after 1m0s
[20:37:14.590] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:12:0)
[20:37:14.590] Timer tick, asking Buildbucket for the build status
[20:37:14.685] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:14.685] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:13:0) after 1m0s
[20:38:14.754] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:13:0)
[20:38:14.780] Build status: STARTED
[20:38:14.780] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:14:0) after 2m36s
[20:40:50.802] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:14:0)
[20:40:50.828] Build status: STARTED
[20:40:50.828] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:15:0) after 8m20s
[20:49:10.861] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:15:0)
[20:49:10.899] Build status: STARTED
[20:49:10.899] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:16:0) after 5m6s
[20:54:16.930] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:16:0)
[20:54:16.930] Timer tick, asking Buildbucket for the build status
[20:54:17.028] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:17.028] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:17:0) after 1m0s
[20:55:17.055] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:17:0)
[20:55:17.083] Build status: STARTED
[20:55:17.083] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:18:0) after 1m11s
[20:56:28.105] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:18:0)
[20:56:28.147] Build status: STARTED
[20:56:28.147] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:19:0) after 5m28s
[21:01:56.254] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:19:0)
[21:01:56.287] Build status: STARTED
[21:01:56.287] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:20:0) after 5m30s
[21:07:26.313] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:20:0)
[21:07:26.353] Build status: STARTED
[21:07:26.353] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:21:0) after 8m43s
[21:16:09.380] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:21:0)
[21:16:09.380] Timer tick, asking Buildbucket for the build status
[21:16:09.488] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:09.488] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:22:0) after 1m0s
[21:17:09.505] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:22:0)
[21:17:09.505] Timer tick, asking Buildbucket for the build status
[21:17:09.646] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:09.646] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:23:0) after 1m0s
[21:18:09.665] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:23:0)
[21:18:09.665] Timer tick, asking Buildbucket for the build status
[21:18:09.746] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:09.746] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:24:0) after 1m0s
[21:19:09.798] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:24:0)
[21:19:09.798] Timer tick, asking Buildbucket for the build status
[21:19:10.091] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:10.091] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:25:0) after 1m0s
[21:20:10.128] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:25:0)
[21:20:10.158] Build status: STARTED
[21:20:10.158] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:26:0) after 6m58s
[21:27:08.274] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:26:0)
[21:27:08.274] Timer tick, asking Buildbucket for the build status
[21:27:08.367] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:08.367] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:27:0) after 1m0s
[21:28:08.350] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:27:0)
[21:28:08.378] Build status: STARTED
[21:28:08.378] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:28:0) after 7m43s
[21:35:51.399] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:28:0)
[21:35:51.399] Timer tick, asking Buildbucket for the build status
[21:35:51.487] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:51.487] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:29:0) after 1m0s
[21:36:51.508] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:29:0)
[21:36:51.508] Timer tick, asking Buildbucket for the build status
[21:36:51.595] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:51.595] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:30:0) after 1m0s
[21:37:51.614] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:30:0)
[21:37:51.643] Build status: STARTED
[21:37:51.643] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:31:0) after 4m3s
[21:41:54.649] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:31:0)
[21:41:54.649] Timer tick, asking Buildbucket for the build status
[21:41:54.837] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:54.837] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:32:0) after 1m0s
[21:42:54.897] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:32:0)
[21:42:54.928] Build status: STARTED
[21:42:54.928] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:33:0) after 7m14s
[21:50:08.953] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:33:0)
[21:50:08.978] Build status: STARTED
[21:50:08.978] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:34:0) after 9m5s
[21:59:14.043] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:34:0)
[21:59:14.043] Timer tick, asking Buildbucket for the build status
[21:59:14.128] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:14.128] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:35:0) after 1m0s
[22:00:14.170] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:35:0)
[22:00:14.204] Build status: STARTED
[22:00:14.204] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:36:0) after 1m33s
[22:01:47.376] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:36:0)
[22:01:47.423] Build status: STARTED
[22:01:47.423] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:37:0) after 2m19s
[22:04:06.545] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:37:0)
[22:04:06.573] Build status: STARTED
[22:04:06.573] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:38:0) after 7m9s
[22:11:15.600] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:38:0)
[22:11:15.600] Timer tick, asking Buildbucket for the build status
[22:11:15.730] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:15.730] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:39:0) after 1m0s
[22:12:15.819] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:39:0)
[22:12:15.848] Build status: STARTED
[22:12:15.848] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:40:0) after 2m45s
[22:15:01.120] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:40:0)
[22:15:01.120] Timer tick, asking Buildbucket for the build status
[22:15:01.205] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:01.205] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:41:0) after 1m0s
[22:16:01.262] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:41:0)
[22:16:01.294] Build status: STARTED
[22:16:01.294] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:42:0) after 2m14s
[22:18:15.361] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:42:0)
[22:18:15.385] Build status: STARTED
[22:18:15.385] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:43:0) after 7m37s
[22:25:52.456] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:43:0)
[22:25:52.456] Timer tick, asking Buildbucket for the build status
[22:25:52.594] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:52.594] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:44:0) after 1m0s
[22:26:52.625] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:44:0)
[22:26:52.655] Build status: STARTED
[22:26:52.655] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:45:0) after 5m36s
[22:32:28.679] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:45:0)
[22:32:28.679] Timer tick, asking Buildbucket for the build status
[22:32:28.865] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:28.865] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:46:0) after 1m0s
[22:33:28.877] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:46:0)
[22:33:28.877] Timer tick, asking Buildbucket for the build status
[22:33:29.517] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:29.517] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:47:0) after 1m0s
[22:34:29.539] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:47:0)
[22:34:29.539] Timer tick, asking Buildbucket for the build status
[22:34:29.634] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:29.634] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:48:0) after 1m0s
[22:35:29.658] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:48:0)
[22:35:29.686] Build status: STARTED
[22:35:29.686] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:49:0) after 5m52s
[22:41:21.725] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:49:0)
[22:41:21.829] Build status: STARTED
[22:41:21.829] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:50:0) after 5m52s
[22:47:13.849] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:50:0)
[22:47:13.876] Build status: STARTED
[22:47:13.876] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:51:0) after 5m59s
[22:53:12.902] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:51:0)
[22:53:12.926] Build status: STARTED
[22:53:12.926] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:52:0) after 6m42s
[22:59:55.043] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:52:0)
[22:59:55.043] Timer tick, asking Buildbucket for the build status
[22:59:55.484] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:55.484] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:53:0) after 1m0s
[23:00:55.595] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:53:0)
[23:00:55.595] Timer tick, asking Buildbucket for the build status
[23:00:55.675] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:55.675] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:54:0) after 1m0s
[23:01:55.693] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:54:0)
[23:01:55.693] Timer tick, asking Buildbucket for the build status
[23:01:55.762] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:55.762] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:55:0) after 1m0s
[23:02:55.824] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:55:0)
[23:02:55.853] Build status: STARTED
[23:02:55.853] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:56:0) after 8m45s
[23:11:40.849] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:56:0)
[23:11:40.849] Timer tick, asking Buildbucket for the build status
[23:11:40.935] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:40.935] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:57:0) after 1m0s
[23:12:41.086] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:57:0)
[23:12:41.086] Timer tick, asking Buildbucket for the build status
[23:12:41.207] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:41.207] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:58:0) after 1m0s
[23:13:41.229] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:58:0)
[23:13:41.229] Timer tick, asking Buildbucket for the build status
[23:13:41.319] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:41.319] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:59:0) after 1m0s
[23:14:41.354] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:59:0)
[23:14:41.383] Build status: STARTED
[23:14:41.383] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:60:0) after 7m35s
[23:22:16.523] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:60:0)
[23:22:16.550] Build status: STARTED
[23:22:16.550] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:61:0) after 6m5s
[23:28:21.667] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:61:0)
[23:28:21.667] Timer tick, asking Buildbucket for the build status
[23:28:21.963] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:21.963] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:62:0) after 1m0s
[23:29:21.934] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:62:0)
[23:29:21.935] Timer tick, asking Buildbucket for the build status
[23:29:22.075] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:22.075] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:63:0) after 1m0s
[23:30:22.253] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:63:0)
[23:30:22.253] Timer tick, asking Buildbucket for the build status
[23:30:22.449] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:22.449] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:64:0) after 1m0s
[23:31:22.499] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:64:0)
[23:31:22.499] Timer tick, asking Buildbucket for the build status
[23:31:22.704] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:22.704] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:65:0) after 1m0s
[23:32:22.811] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:65:0)
[23:32:22.839] Build status: STARTED
[23:32:22.839] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:66:0) after 8m35s
[23:40:57.864] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:66:0)
[23:40:57.890] Build status: STARTED
[23:40:57.890] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:67:0) after 6m22s
[23:47:19.925] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:67:0)
[23:47:19.956] Build status: STARTED
[23:47:19.956] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:68:0) after 4m33s
[23:51:52.977] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:68:0)
[23:51:53.009] Build status: STARTED
[23:51:53.010] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:69:0) after 1m40s
[23:53:33.069] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:69:0)
[23:53:33.095] Build status: STARTED
[23:53:33.095] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:70:0) after 1m38s
[23:55:11.260] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:70:0)
[23:55:11.260] Timer tick, asking Buildbucket for the build status
[23:55:11.350] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:11.350] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:71:0) after 1m0s
[23:56:11.475] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:71:0)
[23:56:11.475] Timer tick, asking Buildbucket for the build status
[23:56:11.822] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:11.822] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:72:0) after 1m0s
[23:57:11.887] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:72:0)
[23:57:11.887] Timer tick, asking Buildbucket for the build status
[23:57:12.183] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:12.183] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:73:0) after 1m0s
[23:58:12.268] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:73:0)
[23:58:12.268] Timer tick, asking Buildbucket for the build status
[23:58:12.370] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:12.370] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:74:0) after 1m0s
[23:59:12.502] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:74:0)
[23:59:12.528] Build status: STARTED
[23:59:12.528] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:75:0) after 7m21s
[00:06:33.577] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:75:0)
[00:06:33.577] Timer tick, asking Buildbucket for the build status
[00:06:33.698] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:33.698] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:76:0) after 1m0s
[00:07:33.727] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:76:0)
[00:07:33.757] Build status: STARTED
[00:07:33.757] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:77:0) after 6m23s
[00:13:56.778] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:77:0)
[00:13:56.778] Timer tick, asking Buildbucket for the build status
[00:13:56.857] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:56.857] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:78:0) after 1m0s
[00:14:56.931] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:78:0)
[00:14:56.975] Build status: STARTED
[00:14:56.975] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:79:0) after 8m24s
[00:23:21.002] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:79:0)
[00:23:21.027] Build status: STARTED
[00:23:21.027] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:80:0) after 3m54s
[00:27:15.097] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:80:0)
[00:27:15.191] Build status: STARTED
[00:27:15.191] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:81:0) after 4m34s
[00:31:49.246] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:81:0)
[00:31:49.246] Timer tick, asking Buildbucket for the build status
[00:31:49.360] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:49.360] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:82:0) after 1m0s
[00:32:49.431] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:82:0)
[00:32:49.459] Build status: STARTED
[00:32:49.459] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:83:0) after 2m58s
[00:35:47.479] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:83:0)
[00:35:47.509] Build status: STARTED
[00:35:47.509] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:84:0) after 3m32s
[00:39:19.530] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:84:0)
[00:39:19.559] Build status: STARTED
[00:39:19.559] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:85:0) after 2m42s
[00:42:01.588] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:85:0)
[00:42:01.619] Build status: STARTED
[00:42:01.619] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:86:0) after 9m55s
[00:51:56.747] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:86:0)
[00:51:56.747] Timer tick, asking Buildbucket for the build status
[00:51:56.880] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:56.880] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:87:0) after 1m0s
[00:52:56.995] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:87:0)
[00:52:56.995] Timer tick, asking Buildbucket for the build status
[00:52:57.090] Build 8852301410297432000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:57.090] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:88:0) after 1m0s
[00:53:57.273] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:88:0)
[00:53:57.365] Build status: STARTED
[00:53:57.365] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9017731472372103888:89:0) after 7m41s
[00:55:48.139] Received PubSub notification, asking Buildbucket for the build status
[00:55:48.213] Build 8852301410297432000: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:55:48.213] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1616201747689878",
"created_by": "project:chromium",
"created_ts": "1616184526120520",
"id": "8852301410297432000",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-32-1-pgry\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Deterministic Linux (dbg)\", \"buildnumber\": 21190, \"got_angle_revision\": \"15a1e7357d3816c724f6001b31d7e0778e9195e1\", \"got_dawn_revision\": \"682f32af32aea8e40775ec56b4e25ab7aaea1c28\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"207b51c7e4fa63a7289383c321333e5e006c3f41\", \"got_revision_cp\": \"refs/heads/master@{#864774}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"cb80f6e731e7e198ea5e9631149342ec2e189a0b\", \"got_v8_revision_cp\": \"refs/heads/9.1.161@{#1}\", \"got_webrtc_revision\": \"490c1503d92696894e9c88c2a40a24ed78abe0a8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33510}\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_6a7ea7a29847c5e3c31f9e7f29ef0a1aa1bd0d542498088304c0bb7ba89c3242_v2\", \"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-32-1-pgry\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2\", \"2.7\", \"2.7.12\"], \"server_version\": [\"5776-cb9e157\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1616184549135594",
"status": "COMPLETED",
"status_changed_ts": "1616201747919280",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/21190",
"builder:Deterministic Linux (dbg)",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/207b51c7e4fa63a7289383c321333e5e006c3f41",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017731472372103888",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852301410297432000/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:5264ec4f505e5a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1616201747923403",
"url": "https://ci.chromium.org/b/8852301410297432000",
"utcnow_ts": "1616201748201067"
}
}
[00:55:48.213] Invocation finished in 4h47m3.455682905s with status SUCCEEDED