[02:00:02.671] New invocation is queued and will start shortly
[02:00:03.806] Starting the invocation (attempt 1)
[02:00:03.841] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:00:03.842] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:00:03.842] Buildbucket request:
{
"bucket": "luci.flutter.prod",
"client_operation_id": "9018705937223396688",
"parameters_json": "{\"builder_name\":\"recipe-deps-roller\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"cron\":{\"generation\":\"5748\"},\"id\":\"cron:v1:5748\"}]}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:recipe-deps-roller",
"scheduler_invocation_id:9018705937223396688",
"scheduler_job_id:flutter/recipe-deps-roller",
"user_agent:luci-scheduler"
]
}
[02:00:07.695] Buildbucket response:
{
"build": {
"bucket": "luci.flutter.prod",
"canary_preference": "PROD",
"created_by": "project:flutter",
"created_ts": "1615255207255345",
"id": "8853275871755646544",
"parameters_json": "{\"builder_name\": \"recipe-deps-roller\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"5748\"}, \"id\": \"cron:v1:5748\"}]}}}",
"project": "flutter",
"result_details_json": "{\"properties\": {}}",
"service_account": "flutter-prod-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615255207476397",
"tags": [
"builder:recipe-deps-roller",
"scheduler_invocation_id:9018705937223396688",
"scheduler_job_id:flutter/recipe-deps-roller",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/flutter/buildbucket/cr-buildbucket.appspot.com/8853275871755646544/+/annotations",
"swarming_tag:luci_project:flutter",
"swarming_tag:recipe_name:recipe_autoroller",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1615255207476509",
"url": "https://ci.chromium.org/b/8853275871755646544",
"utcnow_ts": "1615255207616038"
}
}
[02:00:07.695] Task URL: https://ci.chromium.org/b/8853275871755646544
[02:00:07.696] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:2:0) after 1m0s
[02:00:50.382] Received PubSub notification, asking Buildbucket for the build status
[02:00:50.485] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:08.917] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:2:0)
[02:01:08.917] Timer tick, asking Buildbucket for the build status
[02:01:09.026] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:09.026] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:4:0) after 1m0s
[02:02:09.148] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:4:0)
[02:02:09.148] Timer tick, asking Buildbucket for the build status
[02:02:09.218] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:09.218] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:5:0) after 1m0s
[02:03:09.279] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:5:0)
[02:03:09.279] Timer tick, asking Buildbucket for the build status
[02:03:09.386] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:09.386] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:6:0) after 1m0s
[02:04:09.444] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:6:0)
[02:04:09.444] Timer tick, asking Buildbucket for the build status
[02:04:09.526] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:09.526] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:7:0) after 1m0s
[02:05:09.582] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:7:0)
[02:05:09.582] Timer tick, asking Buildbucket for the build status
[02:05:09.664] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:09.664] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:8:0) after 1m0s
[02:06:09.715] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:8:0)
[02:06:09.715] Timer tick, asking Buildbucket for the build status
[02:06:10.362] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:10.362] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:9:0) after 1m0s
[02:07:11.036] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:9:0)
[02:07:11.036] Timer tick, asking Buildbucket for the build status
[02:07:11.201] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:11.201] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:10:0) after 1m0s
[02:08:11.229] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:10:0)
[02:08:11.229] Timer tick, asking Buildbucket for the build status
[02:08:11.738] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:11.738] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:11:0) after 1m0s
[02:09:11.851] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:11:0)
[02:09:11.851] Timer tick, asking Buildbucket for the build status
[02:09:12.119] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:12.119] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:12:0) after 1m0s
[02:10:12.230] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:12:0)
[02:10:12.230] Timer tick, asking Buildbucket for the build status
[02:10:12.633] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:12.633] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:13:0) after 1m0s
[02:11:12.685] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:13:0)
[02:11:12.685] Timer tick, asking Buildbucket for the build status
[02:11:12.921] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:12.921] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:14:0) after 1m0s
[02:12:13.037] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:14:0)
[02:12:13.037] Timer tick, asking Buildbucket for the build status
[02:12:15.897] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:15.897] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:15:0) after 1m0s
[02:13:16.013] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:15:0)
[02:13:16.013] Timer tick, asking Buildbucket for the build status
[02:13:16.130] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:16.130] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:16:0) after 1m0s
[02:14:16.197] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:16:0)
[02:14:16.197] Timer tick, asking Buildbucket for the build status
[02:14:16.275] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:16.275] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:17:0) after 1m0s
[02:15:16.344] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:17:0)
[02:15:16.344] Timer tick, asking Buildbucket for the build status
[02:15:16.441] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:16.441] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:18:0) after 1m0s
[02:16:16.528] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:18:0)
[02:16:16.528] Timer tick, asking Buildbucket for the build status
[02:16:16.610] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:16.610] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:19:0) after 1m0s
[02:17:16.695] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:19:0)
[02:17:16.695] Timer tick, asking Buildbucket for the build status
[02:17:16.763] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:16.763] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:20:0) after 1m0s
[02:18:16.831] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:20:0)
[02:18:16.831] Timer tick, asking Buildbucket for the build status
[02:18:16.923] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:16.923] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:21:0) after 1m0s
[02:19:16.948] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:21:0)
[02:19:16.948] Timer tick, asking Buildbucket for the build status
[02:19:17.043] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:17.043] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:22:0) after 1m0s
[02:20:17.117] Handling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:22:0)
[02:20:17.117] Timer tick, asking Buildbucket for the build status
[02:20:17.254] Build 8853275871755646544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:17.254] Scheduling timer "check-buildbucket-build-status" (flutter/recipe-deps-roller:9018705937223396688:23:0) after 1m0s
[02:20:59.881] Received PubSub notification, asking Buildbucket for the build status
[02:20:59.967] Build 8853275871755646544: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[02:20:59.967] Buildbucket build:
{
"build": {
"bucket": "luci.flutter.prod",
"canary_preference": "PROD",
"cancelation_reason": "TIMEOUT",
"completed_ts": "1615256459353734",
"created_by": "project:flutter",
"created_ts": "1615255207255345",
"id": "8853275871755646544",
"parameters_json": "{\"builder_name\": \"recipe-deps-roller\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"5748\"}, \"id\": \"cron:v1:5748\"}]}}}",
"project": "flutter",
"result": "CANCELED",
"result_details_json": "{\"properties\": {\"$fuchsia/goma\": {\"server\": \"rbe-prod1.endpoints.fuchsia-infra-goma-prod.cloud.goog\"}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$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}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"5748\"}, \"id\": \"cron:v1:5748\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"flutter-devicelab-linux-3\", \"buildername\": \"recipe-deps-roller\", \"clobber\": false, \"db_gcs_bucket\": \"flutter-recipe-roller-db\", \"goma_jobs\": \"200\", \"mastername\": \"recipes\", \"path_config\": \"generic\", \"projects\": [[\"flutter\", \"https://flutter.googlesource.com/recipes\"]], \"recipe\": \"recipe_autoroller\", \"upload_packages\": false}, \"swarming\": {\"bot_dimensions\": {\"android_devices\": [\"1\"], \"caches\": [\"android_sdk\", \"builder_18381bed795ac3843a394cce7c4ebd9c21ef8e531053e23de308af922d6aa845_v2\", \"builder_1e6eed44c66663acb73f99fba0ce48537385c638a1bdcfcf0185841c20bc56cb_v2\", \"builder_1eb3954fc393da93289a03c155fd25c8048b9c92b10e234383ec6484d6b78cd1_v2\", \"builder_20e84dc22035ad808261988fcf4d979c841eef7f1f8c4a06dfeed04759c31d96_v2\", \"builder_2d45127de1ec7a40f9a0f03764520e45d87d83f8e80011e46ee8940797baf476_v2\", \"builder_36f1a05fba2cfb870ca1da7a79afac4317a075a84605ac7b292a4fb13b3ad133_v2\", \"builder_406ffb892f724bc8a9a32307afc5bfa93260d1efcaa77ae1500c697ac425824d_v2\", \"builder_418a0d0126def1e48f5eb1e3e1307a0849a5775b65a83893ee6ef3191aea7e1f_v2\", \"builder_44fe3e5b5b4d76dcc8a732e9694cd430d14bfc2b886e64661a962bcaa4ec32f3_v2\", \"builder_4516b0a19ffd2e72a268008297938cd22f20cf94ff0ddc3835459e240a1d5d00_v2\", \"builder_4b57fa91bf20d8d4b749184066e31447a9d25ce91632ffa6ca1cc6a8d89f548e_v2\", \"builder_4f9b1140fb69d983763751dffdae2469508d880e5c2cfad2b5291b39c96c1f67_v2\", \"builder_504710a97d4e9d168800308fcad11ddf6454db4b8aba994066bdad933bbccb12_v2\", \"builder_63f7b7a0edd82bd00034a41b75dd89d67d10f141bef5d6d9c08f85662b6c81ce_v2\", \"builder_6c3f9a3a5d60f24fa2e6138464cbb88145e4884966bce3e543aa78b70a2a2402_v2\", \"builder_6ee66f3d217769b1fffbdfbb13d5fc61b2966886f7f144dd1a4d651ead114e30_v2\", \"builder_6fe305b1a28256cb59f4779f080a5de5c72f777916057d60805a8a7cbf1cfb72_v2\", \"builder_746acbb2769f7bb97187026a6c9b4a71b3f733fdc3a32d070fccef75c1f763c9_v2\", \"builder_761776b21e83503509ca23df068db705dd8301f8c0387f9dd4832b9b0c01e11e_v2\", \"builder_7d24b5f268260563a456d8be778dbce65216239f9916d22da91fbc96403ca817_v2\", \"builder_7e44649863ef2291790bf1192ef776c37a5e17a8e9680de7f709ba1e6ce36e1c_v2\", \"builder_7fe7957a5c8cab0dfbae01105913b1f64a919a26de78e9d1acb2669082a1f8ba_v2\", \"builder_806e95f4088cbbddab099d9e1ace0738f6441f82ad52b4b74250321deda1d0bb_v2\", \"builder_81ab7749fcc8d613006b94e57ff49d4be52790403f651ca2b3e78704e19c766f_v2\", \"builder_84a1635e1f7490577b9c5a1866e46812ee0acabfd62fea8c7f27ff78fc14d217_v2\", \"builder_86121bf70fa362896b121f17aa918c8ab8fd51c565e2a3e066ac73387dd02245_v2\", \"builder_8d2a377977780b911247a8b16970c9c45bed9269811ab1fb4c0ce3b8abd7bb26_v2\", \"builder_9336b8c5f03b67d082f071960ebd944023f2a47f740bf50924aed121a3facac1_v2\", \"builder_936d9683e83a0f224adbfb8359851ded79da6558fc64ac1ef2bdb8c9be6e1f12_v2\", \"builder_9ad055f146f8a718cd36873a0a1a1a6df3b40e71b9a2ce607cd8af4adf9807f4_v2\", \"builder_a1a699446e7e48052c0b1cdda6b5d67c01a00da421ac967b34f29f46a7c70e73_v2\", \"builder_a33cb53da2c6291b999d6e38cc18a4161dcdc29e2d6af2ff76342b2b0d3f92e4_v2\", \"builder_a4d41fc7ba717049ee439c793ed8190b6291604b49c717d4cc127a51f3cdb59e_v2\", \"builder_a6b44eeaf4dfa76c0992515f6e27fecaf333e3e3cee8c8ef1ad6c8fa1193f177_v2\", \"builder_a6ee6eedf2ed47bfe2210a39fa650a277d40212436cac09dcaa805e92fd6b471_v2\", \"builder_c440b65f908d0d2411b2c8b6c5898f502a05056d02c063eaa4a8210206b21cbc_v2\", \"builder_c50983e82e081cc1d9851d61171e97f2bbe48792c12c7bb3022e254b4e0a5edd_v2\", \"builder_cd29ffee0620a7a07c171a1d237ecf8bcdf979419b60f73ba781da145e6f41f5_v2\", \"builder_d872991be7b2488190631731b6ab02a8c84af9be207249815b5c95254c11cea7_v2\", \"builder_dbe584d8783d8ba25acebea78137572e02204172952200d357466e198772b823_v2\", \"builder_ebfce7bf2f2adfa5bf264bb5c7353422d41a9cf1de61d26447c831e070d51c72_v2\", \"builder_f2dbc7767433f7c74e91f9b73683a0b1eaad24f0428c459149ff037b2f7decd4_v2\", \"chrome_and_driver\", \"flutter_sdk\", \"git\", \"goma_v2\", \"openjdk\", \"pub_cache\", \"vpython\"], \"device_gms_core_version\": [\"19.8.31\"], \"device_os\": [\"N\", \"NPJS25.93-14-8.1-9\"], \"device_os_flavor\": [\"motorola\"], \"device_os_type\": [\"user\"], \"device_playstore_version\": [\"17.8.16-all\"], \"device_type\": [\"athene\", \"msm8952\"], \"gce\": [\"0\"], \"id\": [\"flutter-devicelab-linux-3\"], \"inside_docker\": [\"0\"], \"locale\": [\"en_US.UTF-8\"], \"os\": [\"Android\"], \"pool\": [\"luci.flutter.prod\"], \"python\": [\"2.7.16\"], \"server_version\": [\"5744-5c0a4b4\"]}}}",
"service_account": "flutter-prod-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615255249592025",
"status": "COMPLETED",
"status_changed_ts": "1615256459684060",
"tags": [
"builder:recipe-deps-roller",
"scheduler_invocation_id:9018705937223396688",
"scheduler_job_id:flutter/recipe-deps-roller",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/flutter/buildbucket/cr-buildbucket.appspot.com/8853275871755646544/+/annotations",
"swarming_tag:luci_project:flutter",
"swarming_tag:os:Android",
"swarming_tag:recipe_name:recipe_autoroller",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:522d88142f648a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615256459699248",
"url": "https://ci.chromium.org/b/8853275871755646544",
"utcnow_ts": "1615256459959533"
}
}
[02:20:59.967] Invocation finished in 20m57.309911584s with status FAILED