[05:57:24.414] New invocation is queued and will start shortly
[05:57:25.488] Starting the invocation (attempt 1)
[05:57:25.523] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d50a80b9326c517d8797b136b392054d6e8e9059"
[05:57:25.523] Popped gitiles commit info from properties and tags
[05:57:25.523] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:57:25.523] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:57:25.523] Buildbucket request:
{
"requestId": "9017513243111466304",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d50a80b9326c517d8797b136b392054d6e8e9059",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9017513243111466304"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTiOS"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:57:25.979] Scheduled build:
{
"id": "8852083181215039184",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 17914,
"createdBy": "project:chromium",
"createTime": "2021-03-22T05:57:25.591546Z",
"updateTime": "2021-03-22T05:57:25.902917Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d50a80b9326c517d8797b136b392054d6e8e9059",
"ref": "refs/heads/master"
}
}
}
[05:57:25.979] Task URL: https://cr-buildbucket.appspot.com/build/8852083181215039184
[05:57:25.979] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:2:0) after 4m46s
[05:57:38.673] Received PubSub notification, asking Buildbucket for the build status
[05:57:38.747] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:11.998] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:2:0)
[06:02:11.998] Timer tick, asking Buildbucket for the build status
[06:02:12.237] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:12.237] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:4:0) after 1m0s
[06:03:12.308] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:4:0)
[06:03:12.308] Timer tick, asking Buildbucket for the build status
[06:03:12.830] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:12.830] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:5:0) after 1m0s
[06:04:12.842] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:5:0)
[06:04:12.842] Timer tick, asking Buildbucket for the build status
[06:04:12.939] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:12.939] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:6:0) after 1m0s
[06:05:12.957] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:6:0)
[06:05:12.957] Timer tick, asking Buildbucket for the build status
[06:05:13.222] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:13.222] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:7:0) after 1m0s
[06:06:13.219] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:7:0)
[06:06:13.256] Build status: STARTED
[06:06:13.256] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:8:0) after 9m34s
[06:15:47.421] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:8:0)
[06:15:47.457] Build status: STARTED
[06:15:47.457] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:9:0) after 5m5s
[06:20:52.575] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:9:0)
[06:20:52.575] Timer tick, asking Buildbucket for the build status
[06:20:52.867] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:52.867] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:10:0) after 1m0s
[06:21:52.938] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:10:0)
[06:21:52.938] Timer tick, asking Buildbucket for the build status
[06:21:53.006] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:53.006] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:11:0) after 1m0s
[06:22:53.024] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:11:0)
[06:22:53.024] Timer tick, asking Buildbucket for the build status
[06:22:53.202] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:53.202] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:12:0) after 1m0s
[06:23:53.223] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:12:0)
[06:23:53.246] Build status: STARTED
[06:23:53.246] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:13:0) after 2m10s
[06:26:03.516] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:13:0)
[06:26:03.516] Timer tick, asking Buildbucket for the build status
[06:26:03.593] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:03.593] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:14:0) after 1m0s
[06:27:03.617] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:14:0)
[06:27:03.648] Build status: STARTED
[06:27:03.648] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:15:0) after 7m53s
[06:34:56.834] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:15:0)
[06:34:56.834] Timer tick, asking Buildbucket for the build status
[06:34:56.923] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:56.923] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:16:0) after 1m0s
[06:35:57.046] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:16:0)
[06:35:57.071] Build status: STARTED
[06:35:57.071] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:17:0) after 3m0s
[06:38:57.135] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:17:0)
[06:38:57.135] Timer tick, asking Buildbucket for the build status
[06:38:57.208] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:57.208] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:18:0) after 1m0s
[06:39:57.320] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:18:0)
[06:39:57.320] Timer tick, asking Buildbucket for the build status
[06:39:57.379] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:57.379] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:19:0) after 1m0s
[06:40:57.395] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:19:0)
[06:40:57.395] Timer tick, asking Buildbucket for the build status
[06:40:57.537] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:57.537] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:20:0) after 1m0s
[06:41:57.564] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:20:0)
[06:41:57.564] Timer tick, asking Buildbucket for the build status
[06:41:57.634] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:57.634] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:21:0) after 1m0s
[06:42:57.671] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:21:0)
[06:42:57.671] Timer tick, asking Buildbucket for the build status
[06:42:57.748] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:57.748] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:22:0) after 1m0s
[06:43:57.765] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:22:0)
[06:43:57.765] Timer tick, asking Buildbucket for the build status
[06:43:57.831] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:57.831] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:23:0) after 1m0s
[06:44:57.847] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:23:0)
[06:44:57.872] Build status: STARTED
[06:44:57.872] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:24:0) after 6m47s
[06:51:44.905] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:24:0)
[06:51:44.948] Build status: STARTED
[06:51:44.948] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:25:0) after 2m32s
[06:54:17.154] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:25:0)
[06:54:17.154] Timer tick, asking Buildbucket for the build status
[06:54:17.265] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:17.265] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:26:0) after 1m0s
[06:55:17.285] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:26:0)
[06:55:17.285] Timer tick, asking Buildbucket for the build status
[06:55:17.350] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:17.350] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:27:0) after 1m0s
[06:56:17.367] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:27:0)
[06:56:17.367] Timer tick, asking Buildbucket for the build status
[06:56:17.478] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:17.478] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:28:0) after 1m0s
[06:57:17.494] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:28:0)
[06:57:17.591] Build status: STARTED
[06:57:17.591] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:29:0) after 3m19s
[07:00:36.814] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:29:0)
[07:00:36.814] Timer tick, asking Buildbucket for the build status
[07:00:36.883] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:36.883] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:30:0) after 1m0s
[07:01:37.086] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:30:0)
[07:01:37.130] Build status: STARTED
[07:01:37.130] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:31:0) after 7m49s
[07:09:26.256] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:31:0)
[07:09:26.256] Timer tick, asking Buildbucket for the build status
[07:09:26.355] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:26.356] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:32:0) after 1m0s
[07:10:26.379] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:32:0)
[07:10:26.433] Build status: STARTED
[07:10:26.433] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:33:0) after 3m25s
[07:13:51.613] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:33:0)
[07:13:51.613] Timer tick, asking Buildbucket for the build status
[07:13:51.719] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:51.719] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:34:0) after 1m0s
[07:14:51.778] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:34:0)
[07:14:51.778] Timer tick, asking Buildbucket for the build status
[07:14:51.845] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:51.845] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:35:0) after 1m0s
[07:15:52.077] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:35:0)
[07:15:52.105] Build status: STARTED
[07:15:52.105] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:36:0) after 8m58s
[07:24:50.238] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:36:0)
[07:24:50.238] Timer tick, asking Buildbucket for the build status
[07:24:50.325] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:50.325] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:37:0) after 1m0s
[07:25:50.629] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:37:0)
[07:25:50.629] Timer tick, asking Buildbucket for the build status
[07:25:50.695] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:50.695] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:38:0) after 1m0s
[07:26:50.898] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:38:0)
[07:26:50.898] Timer tick, asking Buildbucket for the build status
[07:26:51.022] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:51.022] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:39:0) after 1m0s
[07:27:51.043] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:39:0)
[07:27:51.071] Build status: STARTED
[07:27:51.071] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:40:0) after 8m27s
[07:36:18.517] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:40:0)
[07:36:18.557] Build status: STARTED
[07:36:18.557] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:41:0) after 5m56s
[07:42:14.704] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:41:0)
[07:42:14.727] Build status: STARTED
[07:42:14.727] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:42:0) after 3m0s
[07:45:14.923] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:42:0)
[07:45:14.923] Timer tick, asking Buildbucket for the build status
[07:45:15.014] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:15.014] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:43:0) after 1m0s
[07:46:15.033] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:43:0)
[07:46:15.033] Timer tick, asking Buildbucket for the build status
[07:46:15.109] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:15.109] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:44:0) after 1m0s
[07:47:15.135] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:44:0)
[07:47:15.171] Build status: STARTED
[07:47:15.171] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:45:0) after 3m40s
[07:50:55.209] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:45:0)
[07:50:55.291] Build status: STARTED
[07:50:55.291] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:46:0) after 4m25s
[07:55:20.341] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:46:0)
[07:55:20.372] Build status: STARTED
[07:55:20.372] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:47:0) after 5m17s
[08:00:37.641] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:47:0)
[08:00:37.674] Build status: STARTED
[08:00:37.674] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:48:0) after 7m22s
[08:07:59.933] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:48:0)
[08:07:59.933] Timer tick, asking Buildbucket for the build status
[08:08:00.269] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:00.269] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:49:0) after 1m0s
[08:09:00.279] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:49:0)
[08:09:00.308] Build status: STARTED
[08:09:00.308] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:50:0) after 1m51s
[08:10:51.394] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:50:0)
[08:10:51.428] Build status: STARTED
[08:10:51.428] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:51:0) after 8m0s
[08:18:51.621] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:51:0)
[08:18:51.621] Timer tick, asking Buildbucket for the build status
[08:18:51.728] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:51.728] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:52:0) after 1m0s
[08:19:51.806] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:52:0)
[08:19:51.806] Timer tick, asking Buildbucket for the build status
[08:19:51.882] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:51.882] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:53:0) after 1m0s
[08:20:52.060] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:53:0)
[08:20:52.088] Build status: STARTED
[08:20:52.088] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:54:0) after 6m19s
[08:27:11.755] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:54:0)
[08:27:11.780] Build status: STARTED
[08:27:11.780] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:55:0) after 4m22s
[08:31:33.799] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:55:0)
[08:31:33.824] Build status: STARTED
[08:31:33.824] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:56:0) after 1m47s
[08:33:20.976] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:56:0)
[08:33:21.002] Build status: STARTED
[08:33:21.002] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:57:0) after 8m19s
[08:41:40.185] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:57:0)
[08:41:40.185] Timer tick, asking Buildbucket for the build status
[08:41:40.278] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:40.278] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:58:0) after 1m0s
[08:42:40.378] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:58:0)
[08:42:40.403] Build status: STARTED
[08:42:40.403] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:59:0) after 6m54s
[08:49:34.754] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:59:0)
[08:49:34.779] Build status: STARTED
[08:49:34.779] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:60:0) after 5m48s
[08:55:22.825] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:60:0)
[08:55:22.825] Timer tick, asking Buildbucket for the build status
[08:55:22.908] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:22.908] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:61:0) after 1m0s
[08:56:23.052] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:61:0)
[08:56:23.052] Timer tick, asking Buildbucket for the build status
[08:56:23.259] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:23.259] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:62:0) after 1m0s
[08:57:23.270] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:62:0)
[08:57:23.294] Build status: STARTED
[08:57:23.294] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:63:0) after 3m42s
[09:01:06.705] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:63:0)
[09:01:06.731] Build status: STARTED
[09:01:06.731] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:64:0) after 1m47s
[09:02:53.986] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:64:0)
[09:02:53.986] Timer tick, asking Buildbucket for the build status
[09:02:54.075] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:54.075] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:65:0) after 1m0s
[09:03:54.485] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:65:0)
[09:03:54.485] Timer tick, asking Buildbucket for the build status
[09:03:54.540] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:54.540] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:66:0) after 1m0s
[09:04:54.660] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:66:0)
[09:04:54.660] Timer tick, asking Buildbucket for the build status
[09:04:54.729] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:54.729] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:67:0) after 1m0s
[09:05:54.826] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:67:0)
[09:05:54.856] Build status: STARTED
[09:05:54.856] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:68:0) after 7m13s
[09:13:07.987] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:68:0)
[09:13:07.988] Timer tick, asking Buildbucket for the build status
[09:13:08.078] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:08.078] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:69:0) after 1m0s
[09:14:08.102] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:69:0)
[09:14:08.128] Build status: STARTED
[09:14:08.128] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:70:0) after 4m58s
[09:19:06.175] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:70:0)
[09:19:06.175] Timer tick, asking Buildbucket for the build status
[09:19:06.349] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:06.349] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:71:0) after 1m0s
[09:20:06.413] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:71:0)
[09:20:06.413] Timer tick, asking Buildbucket for the build status
[09:20:06.472] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:06.472] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:72:0) after 1m0s
[09:21:06.492] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:72:0)
[09:21:06.517] Build status: STARTED
[09:21:06.517] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:73:0) after 5m35s
[09:26:41.883] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:73:0)
[09:26:41.908] Build status: STARTED
[09:26:41.908] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:74:0) after 6m53s
[09:33:34.984] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:74:0)
[09:33:35.010] Build status: STARTED
[09:33:35.010] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:75:0) after 5m47s
[09:39:22.171] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:75:0)
[09:39:22.195] Build status: STARTED
[09:39:22.195] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:76:0) after 4m32s
[09:43:54.454] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:76:0)
[09:43:54.481] Build status: STARTED
[09:43:54.481] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:77:0) after 8m46s
[09:52:40.600] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:77:0)
[09:52:40.600] Timer tick, asking Buildbucket for the build status
[09:52:40.683] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:40.683] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:78:0) after 1m0s
[09:53:40.704] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:78:0)
[09:53:40.750] Build status: STARTED
[09:53:40.750] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:79:0) after 9m39s
[10:03:19.815] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:79:0)
[10:03:19.845] Build status: STARTED
[10:03:19.845] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:80:0) after 7m12s
[10:10:31.864] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:80:0)
[10:10:31.893] Build status: STARTED
[10:10:31.893] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:81:0) after 4m40s
[10:15:11.928] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:81:0)
[10:15:11.928] Timer tick, asking Buildbucket for the build status
[10:15:12.053] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:12.053] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:82:0) after 1m0s
[10:16:12.068] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:82:0)
[10:16:12.068] Timer tick, asking Buildbucket for the build status
[10:16:12.142] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:12.142] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:83:0) after 1m0s
[10:17:12.165] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:83:0)
[10:17:12.190] Build status: STARTED
[10:17:12.190] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:84:0) after 5m52s
[10:23:04.676] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:84:0)
[10:23:04.676] Timer tick, asking Buildbucket for the build status
[10:23:05.070] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:05.070] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:85:0) after 1m0s
[10:24:05.128] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:85:0)
[10:24:05.128] Timer tick, asking Buildbucket for the build status
[10:24:05.606] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:05.606] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:86:0) after 1m0s
[10:25:05.875] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:86:0)
[10:25:05.909] Build status: STARTED
[10:25:05.909] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:87:0) after 2m52s
[10:27:58.024] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:87:0)
[10:27:58.025] Timer tick, asking Buildbucket for the build status
[10:27:58.091] Build 8852083181215039184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:58.091] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:88:0) after 1m0s
[10:28:58.138] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:88:0)
[10:28:58.170] Build status: STARTED
[10:28:58.170] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9017513243111466304:89:0) after 9m34s
[10:36:58.478] Received PubSub notification, asking Buildbucket for the build status
[10:36:58.563] Build 8852083181215039184: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:36:58.563] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1616409417198297",
"created_by": "project:chromium",
"created_ts": "1616392645591546",
"id": "8852083181215039184",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"d50a80b9326c517d8797b136b392054d6e8e9059\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/opt/s/w/ir/cache\", \"temp_dir\": \"/opt/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"build716-m9\", \"builder_group\": \"chromium.clang\", \"buildername\": \"ToTiOS\", \"buildnumber\": 17914, \"clang_revision\": \"llvmorg-13-init-4720-g7bafe336-1\", \"got_angle_revision\": \"90cf278c36871437c89b8f88d62e6a538b51501d\", \"got_dawn_revision\": \"8ac6a481a3ea9939a37e01713cd0875d2d191965\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"d50a80b9326c517d8797b136b392054d6e8e9059\", \"got_revision_cp\": \"refs/heads/master@{#865042}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"6092f990f654f2d7a4534ee8c5bbb5c4fb800bdc\", \"got_v8_revision_cp\": \"refs/heads/9.1.170@{#1}\", \"got_webrtc_revision\": \"c73257651b221585798dc46ec539f94f332500b6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33514}\", \"is_cached\": true, \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"chromium\", \"swarm_hashes_refs/heads/master(at){#865042}_without_patch\": {\"absl_hardening_tests\": \"cc869b8a1a9ea0cfb99fbfd82727ca7edd3ff12d\", \"base_unittests\": \"fd20598204cd7a7c9de8bae56fc916a7c7d58e9e\", \"boringssl_crypto_tests\": \"a2944cc761047f15d725d327a01526eb13d6fd93\", \"boringssl_ssl_tests\": \"1875e6861dfedcb0c033af138e223ffe8e02aa9e\", \"components_unittests\": \"274fdfa45fc336900c8b6249c6dff78f4ff52c26\", \"crypto_unittests\": \"2abc18aef8916ad9e4d4e2a6a1f800c2de133025\", \"gfx_unittests\": \"86e1ff5b4325d5c180d691713dacbe24be7073b6\", \"google_apis_unittests\": \"723d93160860ca13c40ca416009a8dbe52eb12e5\", \"ios_chrome_unittests\": \"730cc725993354ea9945f4022a9cc555db83b5b0\", \"ios_net_unittests\": \"2f744f8f3b709383ca4b0147b4ec6f54cc5fbbf3\", \"ios_web_inttests\": \"682aaf8c5bbdad1ed9f94f7fc163b75a2dccef52\", \"ios_web_unittests\": \"6008eb08ce3daa2298c271cdba2c4c6007ad6972\", \"ios_web_view_inttests\": \"18b73feea65fcd71654f540ac2a834d785c0e2cd\", \"net_unittests\": \"5514cb97633ac8fada7525f2de65b72842ac4c4b\", \"skia_unittests\": \"84a162b34ec9970ec15528f5e81b7b268a454654\", \"sql_unittests\": \"3d93dd706524d8b380a14b5318a3c53e6b4ac391\", \"ui_base_unittests\": \"fa70061121ac19efe515acdb9f5d8e4fce5111c4\", \"url_unittests\": \"c2858465a3eb5229e0b3be9811b3dc1ce081f70b\"}, \"xcode_build_version\": \"12d4e\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"ToTiOS\", \"ToTiOSDevice\"], \"caches\": [\"builder_4a4feefdd7e355061708c01720b06a0ca8c49ffc9968a2ebc674541738676b8f_v2\", \"git\", \"goma_v2\", \"vpython\", \"xcode_ios_12d4e\"], \"cores\": [\"12\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-i7-8700B\"], \"gce\": [\"0\"], \"gpu\": [\"8086\", \"8086:3e9b\"], \"hidpi\": [\"0\"], \"id\": [\"build716-m9\"], \"mac_model\": [\"Macmini8,1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Mac\", \"Mac-10\", \"Mac-10.15\", \"Mac-10.15.6\", \"Mac-10.15.6-19G2021\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2\", \"2.7\", \"2.7.16\"], \"server_version\": [\"5794-95315a2\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1616392658055646",
"status": "COMPLETED",
"status_changed_ts": "1616409418022986",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/17914",
"builder:ToTiOS",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d50a80b9326c517d8797b136b392054d6e8e9059",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017513243111466304",
"scheduler_job_id:chromium/ToTiOS",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852083181215039184/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Mac",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:527153f609284810",
"user_agent:luci-scheduler"
],
"updated_ts": "1616409418059799",
"url": "https://ci.chromium.org/b/8852083181215039184",
"utcnow_ts": "1616409418552190"
}
}
[10:36:58.563] Invocation finished in 4h39m34.164184329s with status SUCCEEDED