builder: release-maple-devedition_schedule_publishing_in_balrog slave: bld-linux64-spot-309 starttime: 1513635513.18 results: failure (2) revision: 9573657ecdae4184a00d7b23faba1121934552dd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:33.178069) ========= master: http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:33.178418) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:33.178692) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:33.236674) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:33.236957) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/rel-map-de_sc_p_in_balrog-0000/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: CCACHE_HASHDIR= G_BROKEN_FILENAMES=1 HISTCONTROL=ignoredups HISTSIZE=1000 HOME=/home/cltbld HOSTNAME=bld-linux64-spot-309.build.releng.usw2.mozilla.com LANG=en_US.UTF-8 LESSOPEN=|/usr/bin/lesspipe.sh %s LOGNAME=cltbld MAIL=/var/spool/mail/cltbld PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin PWD=/builds/slave/rel-map-de_sc_p_in_balrog-0000 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld _=/tools/buildbot/bin/python using PTY: False --2017-12-18 14:18:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: ‘archiver_client.py’ 0K .......... . 100% 275M=0s 2017-12-18 14:18:33 (275 MB/s) - ‘archiver_client.py’ saved [12179/12179] program finished with exit code 0 elapsedTime=0.297451 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:33.544504) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:33.544802) ========= rm -rf scripts properties in dir /builds/slave/rel-map-de_sc_p_in_balrog-0000/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: CCACHE_HASHDIR= G_BROKEN_FILENAMES=1 HISTCONTROL=ignoredups HISTSIZE=1000 HOME=/home/cltbld HOSTNAME=bld-linux64-spot-309.build.releng.usw2.mozilla.com LANG=en_US.UTF-8 LESSOPEN=|/usr/bin/lesspipe.sh %s LOGNAME=cltbld MAIL=/var/spool/mail/cltbld PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin PWD=/builds/slave/rel-map-de_sc_p_in_balrog-0000 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.002508 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:33.592539) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:33.592871) ========= bash -c 'python archiver_client.py mozharness --repo projects/maple --rev 9573657ecdae4184a00d7b23faba1121934552dd --destination scripts --debug' in dir /builds/slave/rel-map-de_sc_p_in_balrog-0000/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/maple --rev 9573657ecdae4184a00d7b23faba1121934552dd --destination scripts --debug'] environment: CCACHE_HASHDIR= G_BROKEN_FILENAMES=1 HISTCONTROL=ignoredups HISTSIZE=1000 HOME=/home/cltbld HOSTNAME=bld-linux64-spot-309.build.releng.usw2.mozilla.com LANG=en_US.UTF-8 LESSOPEN=|/usr/bin/lesspipe.sh %s LOGNAME=cltbld MAIL=/var/spool/mail/cltbld PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin PWD=/builds/slave/rel-map-de_sc_p_in_balrog-0000 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld _=/tools/buildbot/bin/python using PTY: False 2017-12-18 14:18:33,633 truncating revision to first 12 chars 2017-12-18 14:18:33,633 Setting DEBUG logging. 2017-12-18 14:18:33,633 attempt 1/10 2017-12-18 14:18:33,633 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/maple/9573657ecdae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-12-18 14:18:34,040 unpacking tar archive at: maple-9573657ecdae/testing/mozharness/ program finished with exit code 0 elapsedTime=0.611009 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:34.212627) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:34.212923) ========= script_repo_revision: 9573657ecdae4184a00d7b23faba1121934552dd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:34.213267) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:34.213535) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:34.221400) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/release/publish_balrog.py ...' failed (results: 2, elapsed: 16 secs) (at 2017-12-18 14:18:34.221664) ========= /tools/buildbot/bin/python scripts/scripts/release/publish_balrog.py -c releases/dev_updates_firefox_devedition.py in dir /builds/slave/rel-map-de_sc_p_in_balrog-0000/. (timeout 3600 secs) (maxTime 14400 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/release/publish_balrog.py', '-c', 'releases/dev_updates_firefox_devedition.py'] environment: CCACHE_COMPRESS=1 CCACHE_DIR=/builds/ccache CCACHE_HASHDIR= CCACHE_UMASK=002 DISPLAY=:2 G_BROKEN_FILENAMES=1 HG_SHARE_BASE_DIR=/builds/hg-shared HISTCONTROL=ignoredups HISTSIZE=1000 HOME=/home/cltbld HOSTNAME=bld-linux64-spot-309.build.releng.usw2.mozilla.com LANG=en_US.UTF-8 LC_ALL=C LESSOPEN=|/usr/bin/lesspipe.sh %s LOGNAME=cltbld MAIL=/var/spool/mail/cltbld MOZ_AUTOMATION=1 MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_OBJDIR=obj-firefox PATH=/tools/buildbot/bin:/usr/local/bin:/usr/lib64/ccache:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/tools/git/bin:/tools/python27/bin:/tools/python27-mercurial/bin:/home/cltbld/bin PROPERTIES_FILE=/builds/slave/rel-map-de_sc_p_in_balrog-0000/buildprops.json PWD=/builds/slave/rel-map-de_sc_p_in_balrog-0000 SHELL=/bin/bash SHLVL=1 TERM=linux TINDERBOX_OUTPUT=1 TMOUT=86400 TOOLTOOL_CACHE=/builds/tooltool_cache TOOLTOOL_HOME=/builds USER=cltbld _=/tools/buildbot/bin/python using PTY: False 14:18:34 INFO - MultiFileLogger online at 20171218 14:18:34 in /builds/slave/rel-map-de_sc_p_in_balrog-0000 14:18:34 INFO - Using buildbot properties: 14:18:34 INFO - { 14:18:34 INFO - "channels": "aurora", 14:18:34 INFO - "product": "devedition", 14:18:34 INFO - "build_number": 4, 14:18:34 INFO - "taskId": "K_bWZwsCSN6V6L6-hiM00g", 14:18:34 INFO - "repository": "https://hg.mozilla.org/projects/maple", 14:18:34 INFO - "buildername": "release-maple-devedition_schedule_publishing_in_balrog", 14:18:34 INFO - "buildnumber": 6, 14:18:34 INFO - "balrog_api_root": "https://balrog-admin.stage.mozaws.net/api", 14:18:34 INFO - "basedir": "/builds/slave/rel-map-de_sc_p_in_balrog-0000", 14:18:34 INFO - "appVersion": "58.0", 14:18:34 INFO - "project": "", 14:18:34 INFO - "platform": null, 14:18:34 INFO - "version": "58.0b21", 14:18:34 INFO - "release_promotion": true, 14:18:34 INFO - "script_repo_revision": "9573657ecdae4184a00d7b23faba1121934552dd", 14:18:34 INFO - "branch": "maple", 14:18:34 INFO - "master": "http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/", 14:18:34 INFO - "revision": "9573657ecdae4184a00d7b23faba1121934552dd", 14:18:34 INFO - "slavename": "bld-linux64-spot-309", 14:18:34 INFO - "next_version": "58.0b21", 14:18:34 INFO - "repo_path": "projects/maple" 14:18:34 INFO - } 14:18:34 INFO - Overriding product with devedition 14:18:34 INFO - Overriding version with 58.0b21 14:18:34 INFO - Overriding build_number with 4 14:18:34 INFO - Overriding channels with aurora 14:18:34 INFO - Overriding balrog_api_root with https://balrog-admin.stage.mozaws.net/api 14:18:34 INFO - Run as scripts/scripts/release/publish_balrog.py -c releases/dev_updates_firefox_devedition.py 14:18:34 INFO - Dumping config to /builds/slave/rel-map-de_sc_p_in_balrog-0000/logs/localconfig.json. 14:18:34 INFO - {'append_to_log': False, 14:18:34 INFO - 'archive_domain': 'ftp.stage.mozaws.net', 14:18:34 INFO - 'archive_prefix': 'https://ftp.stage.mozaws.net/pub', 14:18:34 INFO - 'balrog_api_root': u'https://balrog-admin.stage.mozaws.net/api', 14:18:34 INFO - 'balrog_url': 'https://aus4.stage.mozaws.net/', 14:18:34 INFO - 'balrog_use_dummy_suffix': False, 14:18:34 INFO - 'balrog_username': 'balrog-stage-ffxbld', 14:18:34 INFO - 'base_work_dir': '/builds/slave/rel-map-de_sc_p_in_balrog-0000', 14:18:34 INFO - 'bouncer_product': 'devedition', 14:18:34 INFO - 'build_number': 4, 14:18:34 INFO - 'buildbot_json_path': 'buildprops.json', 14:18:34 INFO - 'channels': u'aurora', 14:18:34 INFO - 'config_files': ('releases/dev_updates_firefox_devedition.py',), 14:18:34 INFO - 'credentials_file': 'oauth.txt', 14:18:34 INFO - 'download_domain': 'download.mozilla.org', 14:18:34 INFO - 'ignore_no_changes': True, 14:18:34 INFO - 'log_level': 'info', 14:18:34 INFO - 'log_name': 'updates_devedition', 14:18:34 INFO - 'log_to_console': True, 14:18:34 INFO - 'opt_config_files': (), 14:18:34 INFO - 'previous_archive_prefix': 'https://archive.mozilla.org/pub', 14:18:34 INFO - 'product': u'devedition', 14:18:34 INFO - 'push_dest': 'ssh://hg.mozilla.org/users/asasaki_mozilla.com/tools', 14:18:34 INFO - 'repo': {'branch': 'default', 14:18:34 INFO - 'dest': 'tools', 14:18:34 INFO - 'repo': 'https://hg.mozilla.org/users/asasaki_mozilla.com/tools', 14:18:34 INFO - 'vcs': 'hg'}, 14:18:34 INFO - 'shipped-locales-url': 'https://hg.mozilla.org/projects/maple/raw-file/{revision}/browser/locales/shipped-locales', 14:18:34 INFO - 'ssh_key': '~/.ssh/ffxbld_rsa', 14:18:34 INFO - 'ssh_user': 'ffxbld', 14:18:34 INFO - 'stage_product': 'devedition', 14:18:34 INFO - 'update_channels': {'aurora': {'channel_names': ('aurora', 14:18:34 INFO - 'aurora-localtest', 14:18:34 INFO - 'aurora-cdntest'), 14:18:34 INFO - 'mar_channel_ids': (), 14:18:34 INFO - 'patcher_config': 'mozDevedition-branch-patcher2.cfg', 14:18:34 INFO - 'patcher_config_product_override': 'firefox', 14:18:34 INFO - 'publish_rules': (10,), 14:18:34 INFO - 'requires_mirrors': True, 14:18:34 INFO - 'rules_to_update': ('devedition-cdntest', 14:18:34 INFO - 'devedition-localtest'), 14:18:34 INFO - 'update_verify_channel': 'aurora-localtest', 14:18:34 INFO - 'version_regex': '^(\\d+\\.\\d+(b\\d+)?)$'}}, 14:18:34 INFO - 'vcs_share_base': '/builds/hg-shared', 14:18:34 INFO - 'version': u'58.0b21', 14:18:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:18:34 INFO - 'work_dir': 'build'} 14:18:34 INFO - [mozharness: 2017-12-18 22:18:34.350169Z] Running clobber step. 14:18:34 INFO - Running main action method: clobber 14:18:34 INFO - rmtree: /builds/slave/rel-map-de_sc_p_in_balrog-0000/build 14:18:34 INFO - [mozharness: 2017-12-18 22:18:34.352730Z] Finished clobber step (success) 14:18:34 INFO - [mozharness: 2017-12-18 22:18:34.353032Z] Running pull step. 14:18:34 INFO - Running main action method: pull 14:18:34 INFO - mkdir: /builds/slave/rel-map-de_sc_p_in_balrog-0000/build 14:18:34 INFO - Changing directory to /builds/slave/rel-map-de_sc_p_in_balrog-0000/build. 14:18:34 INFO - retry: Calling _get_revision with args: (, 'tools'), kwargs: {}, attempt #1 14:18:34 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--version'] 14:18:34 INFO - Copy/paste: hg --config ui.merge=internal:merge --version 14:18:34 INFO - Mercurial Distributed SCM (version 3.9.1) 14:18:34 INFO - (see https://mercurial-scm.org for more information) 14:18:34 INFO - Copyright (C) 2005-2016 Matt Mackall and others 14:18:34 INFO - This is free software; see the source for copying conditions. There is NO 14:18:34 INFO - warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. 14:18:34 INFO - Return code: 0 14:18:34 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', 'debuginstall', '--config=ui.username=worker'] 14:18:34 INFO - Copy/paste: hg --config ui.merge=internal:merge debuginstall --config=ui.username=worker 14:18:34 INFO - checking encoding (ascii)... 14:18:34 INFO - checking Python executable (/tools/python27/bin/python2.7) 14:18:34 INFO - checking Python version (2.7.3) 14:18:34 INFO - checking Python lib (/tools/python27/lib/python2.7)... 14:18:34 INFO - checking Mercurial version (3.9.1) 14:18:34 INFO - checking Mercurial custom build () 14:18:34 INFO - checking module policy (c) 14:18:34 INFO - checking installed modules (/tools/python27-mercurial/lib/python2.7/site-packages/mercurial)... 14:18:34 INFO - checking templates (/tools/python27-mercurial/lib/python2.7/site-packages/mercurial/templates)... 14:18:34 INFO - checking default template (/tools/python27-mercurial/lib/python2.7/site-packages/mercurial/templates/map-cmdline.default) 14:18:34 INFO - checking commit editor... (vi) 14:18:34 INFO - checking username (worker) 14:18:34 INFO - no problems detected 14:18:34 INFO - Return code: 0 14:18:34 INFO - Running command: ['hg', '--config', 'ui.merge=internal:merge', '--config', 'extensions.robustcheckout=/builds/slave/rel-map-de_sc_p_in_balrog-0000/scripts/external_tools/robustcheckout.py', 'robustcheckout', 'https://hg.mozilla.org/users/asasaki_mozilla.com/tools', 'tools', '--sharebase', '/builds/hg-shared', '--branch', 'default'] 14:18:34 INFO - Copy/paste: hg --config ui.merge=internal:merge --config extensions.robustcheckout=/builds/slave/rel-map-de_sc_p_in_balrog-0000/scripts/external_tools/robustcheckout.py robustcheckout https://hg.mozilla.org/users/asasaki_mozilla.com/tools tools --sharebase /builds/hg-shared --branch default 14:18:35 INFO - (using Mercurial 3.9.1) 14:18:35 INFO - ensuring https://hg.mozilla.org/users/asasaki_mozilla.com/tools@default is available at tools 14:18:35 INFO - warning: connecting to hg.mozilla.org using legacy security technology (TLS 1.0); see https://mercurial-scm.org/wiki/SecureConnections for more info 14:18:35 INFO - (shared store missing requirements: dotencode; deleting store and destination to ensure optimal behavior) 14:18:35 INFO - (sharing from new pooled repository 7ae7fb134bf7aec6ec96a062ff47a69053dd2973) 14:18:35 INFO - warning: connecting to hg.mozilla.org using legacy security technology (TLS 1.0); see https://mercurial-scm.org/wiki/SecureConnections for more info 14:18:48 INFO - 14:18:48 INFO - manifests [========================================> ] 5920/8160 14:18:48 INFO - 14:18:48 INFO - 14:18:48 INFO - files [ ] 12/2169 6m41s 14:18:48 INFO - files [ ] 17/2169 9m48s 14:18:48 INFO - files [========================> ] 977/2169 07s 14:18:48 INFO - files [==============================================> ] 1825/2169 02s 14:18:48 INFO - files [==================================================> ] 1972/2169 01s 14:18:48 INFO - 14:18:48 INFO - requesting all changes 14:18:48 INFO - adding changesets 14:18:48 INFO - adding manifests 14:18:48 INFO - adding file changes 14:18:48 INFO - added 8160 changesets with 18358 changes to 2169 files (+2 heads) 14:18:48 INFO - warning: connecting to hg.mozilla.org using legacy security technology (TLS 1.0); see https://mercurial-scm.org/wiki/SecureConnections for more info 14:18:48 INFO - searching for changes 14:18:48 INFO - no changes found 14:18:48 INFO - (pulling to obtain default) 14:18:48 INFO - warning: connecting to hg.mozilla.org using legacy security technology (TLS 1.0); see https://mercurial-scm.org/wiki/SecureConnections for more info 14:18:49 INFO - (remote resolved default to 2ad96c37d2705ab9c10cc4917f610c21a54d4f65; result is not deterministic) 14:18:49 INFO - (revision already present locally; not pulling) 14:18:49 INFO - 14:18:49 INFO - updating [====> ] 100/1058 14:18:49 INFO - 14:18:49 INFO - 1058 files updated, 0 files merged, 0 files removed, 0 files unresolved 14:18:49 INFO - updated to 2ad96c37d2705ab9c10cc4917f610c21a54d4f65 14:18:49 INFO - Return code: 0 14:18:49 INFO - Changing directory to /builds/slave/rel-map-de_sc_p_in_balrog-0000. 14:18:49 INFO - [mozharness: 2017-12-18 22:18:49.298419Z] Finished pull step (success) 14:18:49 INFO - [mozharness: 2017-12-18 22:18:49.298618Z] Running submit-to-balrog step. 14:18:49 INFO - Running main action method: submit_to_balrog 14:18:49 INFO - retry: Calling with args: (), kwargs: {}, attempt #1 14:18:49 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/rel-map-de_sc_p_in_balrog-0000/build/tools/scripts/build-promotion/balrog-release-shipper.py', '--api-root', u'https://balrog-admin.stage.mozaws.net/api', '--credentials-file', '/builds/slave/rel-map-de_sc_p_in_balrog-0000/oauth.txt', '--username', 'balrog-stage-ffxbld', '--version', u'58.0b21', '--product', u'devedition', '--build-number', '4', '--verbose', '--rules', '10'] 14:18:49 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/rel-map-de_sc_p_in_balrog-0000/build/tools/scripts/build-promotion/balrog-release-shipper.py --api-root https://balrog-admin.stage.mozaws.net/api --credentials-file /builds/slave/rel-map-de_sc_p_in_balrog-0000/oauth.txt --username balrog-stage-ffxbld --version 58.0b21 --product devedition --build-number 4 --verbose --rules 10 14:18:49 INFO - Balrog request to https://balrog-admin.stage.mozaws.net/api/rules/10 14:18:49 INFO - Data sent: None 14:18:49 INFO - Starting new HTTPS connection (1): balrog-admin.stage.mozaws.net 14:18:49 INFO - /builds/slave/rel-map-de_sc_p_in_balrog-0000/build/tools/scripts/build-promotion/../../lib/python/vendor/requests-2.7.0/requests/packages/urllib3/util/ssl_.py:90: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. For more information, see https://urllib3.readthedocs.org/en/latest/security.html#insecureplatformwarning. 14:18:49 INFO - InsecurePlatformWarning 14:18:50 INFO - "GET /api/rules/10 HTTP/1.1" 200 533 14:18:50 INFO - REQUEST STATS: {"url": "https://balrog-admin.stage.mozaws.net/api/rules/10", "timestamp": 1513635530.005383, "method": "GET", "elapsed_secs": 0.4530029296875, "status_code": 200} 14:18:50 INFO - Balrog request to https://balrog-admin.stage.mozaws.net/api/rules/10 14:18:50 INFO - Data sent: None 14:18:50 INFO - Starting new HTTPS connection (1): balrog-admin.stage.mozaws.net 14:18:50 INFO - /builds/slave/rel-map-de_sc_p_in_balrog-0000/build/tools/scripts/build-promotion/../../lib/python/vendor/requests-2.7.0/requests/packages/urllib3/util/ssl_.py:90: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. For more information, see https://urllib3.readthedocs.org/en/latest/security.html#insecureplatformwarning. 14:18:50 INFO - InsecurePlatformWarning 14:18:50 INFO - "HEAD /api/rules/10 HTTP/1.1" 200 0 14:18:50 INFO - REQUEST STATS: {"url": "https://balrog-admin.stage.mozaws.net/api/rules/10", "timestamp": 1513635530.448046, "method": "HEAD", "elapsed_secs": 0.4408869743347168, "status_code": 200} 14:18:50 INFO - Balrog request to https://balrog-admin.stage.mozaws.net/api/scheduled_changes/rules 14:18:50 INFO - Data sent: {u'comment': u'default aurora rule updated by buildbot, DO NOT DELETE', 'change_type': 'update', u'fallbackMapping': u'Devedition-57.0b14-build1-WNP', u'buildID': None, u'jaws': None, u'product': u'Firefox', u'instructionSet': None, u'mapping': 'Devedition-58.0b21-build4', u'alias': u'devedition', u'headerArchitecture': None, u'buildTarget': None, 'data_verison': 150, u'osVersion': None, u'distVersion': None, u'mig64': None, u'data_version': 150, 'when': 1513635649000, u'backgroundRate': 100, u'priority': 90, u'locale': None, u'version': None, u'update_type': u'minor', u'memory': None, u'distribution': None, u'rule_id': '10', u'channel': u'aurora'} 14:18:50 INFO - "POST /api/scheduled_changes/rules HTTP/1.1" 400 126 14:18:50 INFO - Caught HTTPError: {"status": 400, "type": "about:blank", "detail": "Invalid mapping value. No release name found in DB", "title": "Bad Request"} 14:18:50 INFO - REQUEST STATS: {"url": "https://balrog-admin.stage.mozaws.net/api/scheduled_changes/rules", "timestamp": 1513635530.557202, "method": "POST", "elapsed_secs": 0.10885310173034668, "status_code": 400} 14:18:50 INFO - Traceback (most recent call last): 14:18:50 INFO - File "/builds/slave/rel-map-de_sc_p_in_balrog-0000/build/tools/scripts/build-promotion/balrog-release-shipper.py", line 54, in 14:18:50 INFO - args.build_number, args.rule_ids, args.schedule_at) 14:18:50 INFO - File "/builds/slave/rel-map-de_sc_p_in_balrog-0000/build/tools/scripts/build-promotion/../../lib/python/balrog/submitter/cli.py", line 547, in run 14:18:50 INFO - ).add_scheduled_rule_change(**data) 14:18:50 INFO - File "/builds/slave/rel-map-de_sc_p_in_balrog-0000/build/tools/lib/python/vendor/balrogclient-0.0.4/balrogclient/api.py", line 254, in add_scheduled_rule_change 14:18:50 INFO - return self.request(method='POST', data=rule_data) 14:18:50 INFO - File "/builds/slave/rel-map-de_sc_p_in_balrog-0000/build/tools/lib/python/vendor/balrogclient-0.0.4/balrogclient/api.py", line 111, in request 14:18:50 INFO - return self.do_request(url, data, method) 14:18:50 INFO - File "/builds/slave/rel-map-de_sc_p_in_balrog-0000/build/tools/lib/python/vendor/balrogclient-0.0.4/balrogclient/api.py", line 130, in do_request 14:18:50 INFO - req.raise_for_status() 14:18:50 INFO - File "/builds/slave/rel-map-de_sc_p_in_balrog-0000/build/tools/scripts/build-promotion/../../lib/python/vendor/requests-2.7.0/requests/models.py", line 851, in raise_for_status 14:18:50 INFO - raise HTTPError(http_error_msg, response=self) 14:18:50 INFO - requests.exceptions.HTTPError: 400 Client Error: BAD REQUEST 14:18:50 ERROR - Return code: 1 14:18:50 ERROR - 1 not in success codes: [0] 14:18:50 WARNING - setting return code to 2 14:18:50 FATAL - Halting on failure while running ['/tools/buildbot/bin/python', '/builds/slave/rel-map-de_sc_p_in_balrog-0000/build/tools/scripts/build-promotion/balrog-release-shipper.py', '--api-root', u'https://balrog-admin.stage.mozaws.net/api', '--credentials-file', '/builds/slave/rel-map-de_sc_p_in_balrog-0000/oauth.txt', '--username', 'balrog-stage-ffxbld', '--version', u'58.0b21', '--product', u'devedition', '--build-number', '4', '--verbose', '--rules', '10'] 14:18:50 FATAL - Running post_fatal callback... 14:18:50 FATAL - Exiting 2 14:18:50 INFO - [mozharness: 2017-12-18 22:18:50.570888Z] Finished submit-to-balrog step (failed) 14:18:50 INFO - Running post-run listener: copy_logs_to_upload_dir 14:18:50 INFO - Copying logs to upload dir... 14:18:50 INFO - mkdir: /builds/slave/rel-map-de_sc_p_in_balrog-0000/build/upload/logs program finished with exit code 2 elapsedTime=16.359111 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/release/publish_balrog.py ...' failed (results: 2, elapsed: 16 secs) (at 2017-12-18 14:18:50.620622) ========= ========= Started no change (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:50.621966) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/rel-map-de_sc_p_in_balrog-0000/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: CCACHE_HASHDIR= G_BROKEN_FILENAMES=1 HISTCONTROL=ignoredups HISTSIZE=1000 HOME=/home/cltbld HOSTNAME=bld-linux64-spot-309.build.releng.usw2.mozilla.com LANG=en_US.UTF-8 LESSOPEN=|/usr/bin/lesspipe.sh %s LOGNAME=cltbld MAIL=/var/spool/mail/cltbld PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin PWD=/builds/slave/rel-map-de_sc_p_in_balrog-0000/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004704 ========= master_lag: 0.04 ========= ========= Finished no change (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:50.671028) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:50.671323) ========= rm -f oauth.txt in dir /builds/slave/rel-map-de_sc_p_in_balrog-0000/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: CCACHE_HASHDIR= G_BROKEN_FILENAMES=1 HISTCONTROL=ignoredups HISTSIZE=1000 HOME=/home/cltbld HOSTNAME=bld-linux64-spot-309.build.releng.usw2.mozilla.com LANG=en_US.UTF-8 LESSOPEN=|/usr/bin/lesspipe.sh %s LOGNAME=cltbld MAIL=/var/spool/mail/cltbld PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin PWD=/builds/slave/rel-map-de_sc_p_in_balrog-0000 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.002433 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-12-18 14:18:50.734726) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-12-18 14:18:50.735024) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-12-18 14:18:50.735267) ========= ========= Total master_lag: 0.21 =========