builder: release-comm-beta-thunderbird_antivirus slave: av-linux64-spot-004 starttime: 1506813952.53 results: failure (2) ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-30 16:25:52.533122) ========= master: http://buildbot-master72.bb.releng.usw2.mozilla.com:8001/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-30 16:25:52.533560) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-30 16:25:52.533869) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-30 16:25:52.588959) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-30 16:25:52.589244) ========= 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/tb-rel-c-beta-av-0000000000000/. (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: BUILDBOTCUSTOM=https://hg.mozilla.org/build/buildbotcustom BUILDBOT_CONFIGS=https://hg.mozilla.org/build/buildbot-configs CCACHE_HASHDIR= CLOBBERER_URL=https://api.pub.build.mozilla.org/clobberer/forceclobber G_BROKEN_FILENAMES=1 HISTCONTROL=ignoredups HISTSIZE=1000 HOME=/home/cltbld HOSTNAME=av-linux64-spot-004.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/tb-rel-c-beta-av-0000000000000 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld _=/tools/buildbot/bin/python using PTY: False --2017-09-30 16:25:53-- 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% 121M=0s 2017-09-30 16:25:53 (121 MB/s) - ‘archiver_client.py’ saved [12179/12179] program finished with exit code 0 elapsedTime=1.056625 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-30 16:25:53.656602) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 16:25:53.656962) ========= rm -rf scripts properties in dir /builds/slave/tb-rel-c-beta-av-0000000000000/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: BUILDBOTCUSTOM=https://hg.mozilla.org/build/buildbotcustom BUILDBOT_CONFIGS=https://hg.mozilla.org/build/buildbot-configs CCACHE_HASHDIR= CLOBBERER_URL=https://api.pub.build.mozilla.org/clobberer/forceclobber G_BROKEN_FILENAMES=1 HISTCONTROL=ignoredups HISTSIZE=1000 HOME=/home/cltbld HOSTNAME=av-linux64-spot-004.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/tb-rel-c-beta-av-0000000000000 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.002833 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 16:25:53.704170) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-30 16:25:53.704463) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --tag THUNDERBIRD_56_0b4_RELEASE --destination scripts --debug' in dir /builds/slave/tb-rel-c-beta-av-0000000000000/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --tag THUNDERBIRD_56_0b4_RELEASE --destination scripts --debug'] environment: BUILDBOTCUSTOM=https://hg.mozilla.org/build/buildbotcustom BUILDBOT_CONFIGS=https://hg.mozilla.org/build/buildbot-configs CCACHE_HASHDIR= CLOBBERER_URL=https://api.pub.build.mozilla.org/clobberer/forceclobber G_BROKEN_FILENAMES=1 HISTCONTROL=ignoredups HISTSIZE=1000 HOME=/home/cltbld HOSTNAME=av-linux64-spot-004.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/tb-rel-c-beta-av-0000000000000 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld _=/tools/buildbot/bin/python using PTY: False 2017-09-30 16:25:53,758 Setting DEBUG logging. 2017-09-30 16:25:53,759 "THUNDERBIRD_56_0b4_RELEASE" was passed as the tag. Querying remote repository for corresponding rev hash. warning: connecting to hg.mozilla.org using legacy security technology (TLS 1.0); see https://mercurial-scm.org/wiki/SecureConnections for more info 2017-09-30 16:25:54,781 revision being used: c94b394a1aa3 2017-09-30 16:25:54,781 attempt 1/10 2017-09-30 16:25:54,781 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c94b394a1aa3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-30 16:25:55,176 unpacking tar archive at: mozilla-beta-c94b394a1aa3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.570748 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-30 16:25:55.282534) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-30 16:25:55.282885) ========= script_repo_revision: THUNDERBIRD_56_0b4_RELEASE ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-30 16:25:55.283262) ========= ========= Started 'python2.7 scripts/scripts/release/antivirus.py ...' failed (results: 2, elapsed: 12 secs) (at 2017-09-30 16:25:55.283560) ========= python2.7 scripts/scripts/release/antivirus.py --product thunderbird --version 56.0b4 --build-number 10 --bucket-name net-mozaws-prod-delivery-archive --tools-revision THUNDERBIRD_56_0b4_RELEASE --tools-repo https://hg.mozilla.org/build/tools in dir /builds/slave/tb-rel-c-beta-av-0000000000000/. (timeout 10800 secs) watching logfiles {} argv: ['python2.7', 'scripts/scripts/release/antivirus.py', '--product', 'thunderbird', '--version', '56.0b4', '--build-number', '10', '--bucket-name', 'net-mozaws-prod-delivery-archive', '--tools-revision', 'THUNDERBIRD_56_0b4_RELEASE', '--tools-repo', 'https://hg.mozilla.org/build/tools'] environment: BUILDBOTCUSTOM=https://hg.mozilla.org/build/buildbotcustom BUILDBOT_CONFIGS=https://hg.mozilla.org/build/buildbot-configs CCACHE_HASHDIR= CLOBBERER_URL=https://api.pub.build.mozilla.org/clobberer/forceclobber G_BROKEN_FILENAMES=1 HISTCONTROL=ignoredups HISTSIZE=1000 HOME=/home/cltbld HOSTNAME=av-linux64-spot-004.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 PROPERTIES_FILE=/builds/slave/tb-rel-c-beta-av-0000000000000/buildprops.json PWD=/builds/slave/tb-rel-c-beta-av-0000000000000 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld _=/tools/buildbot/bin/python using PTY: False 16:25:55 INFO - MultiFileLogger online at 20170930 16:25:55 in /builds/slave/tb-rel-c-beta-av-0000000000000 16:25:55 INFO - Run as scripts/scripts/release/antivirus.py --product thunderbird --version 56.0b4 --build-number 10 --bucket-name net-mozaws-prod-delivery-archive --tools-revision THUNDERBIRD_56_0b4_RELEASE --tools-repo https://hg.mozilla.org/build/tools 16:25:55 INFO - Dumping config to /builds/slave/tb-rel-c-beta-av-0000000000000/logs/localconfig.json. 16:25:55 INFO - {'append_to_log': False, 16:25:55 INFO - 'base_work_dir': '/builds/slave/tb-rel-c-beta-av-0000000000000', 16:25:55 INFO - 'bucket_name': 'net-mozaws-prod-delivery-archive', 16:25:55 INFO - 'build_number': '10', 16:25:55 INFO - 'download_parallelization': 6, 16:25:55 INFO - 'log_level': 'info', 16:25:55 INFO - 'log_to_console': True, 16:25:55 INFO - 'opt_config_files': (), 16:25:55 INFO - 'pip_index': True, 16:25:55 INFO - 'product': 'thunderbird', 16:25:55 INFO - 'scan_parallelization': 4, 16:25:55 INFO - 'tools_repo': 'https://hg.mozilla.org/build/tools', 16:25:55 INFO - 'tools_revision': 'THUNDERBIRD_56_0b4_RELEASE', 16:25:55 INFO - 'version': '56.0b4', 16:25:55 INFO - 'virtualenv_modules': ('boto', 'redo', 'mar'), 16:25:55 INFO - 'virtualenv_path': 'venv', 16:25:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:25:55 INFO - 'work_dir': 'build'} 16:25:55 INFO - [mozharness: 2017-09-30 23:25:55.431346Z] Running create-virtualenv step. 16:25:55 INFO - Running main action method: create_virtualenv 16:25:55 INFO - Creating virtualenv /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv 16:25:55 INFO - mkdir: /builds/slave/tb-rel-c-beta-av-0000000000000/build 16:25:55 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/tb-rel-c-beta-av-0000000000000/build/venv'] in /builds/slave/tb-rel-c-beta-av-0000000000000/build 16:25:55 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv 16:25:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:25:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:25:55 INFO - New python executable in /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/python2.7 16:25:55 INFO - Also creating executable in /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/python 16:25:58 INFO - Installing distribute.............................................................................................................................................................................................done. 16:25:58 INFO - Installing pip...............done. 16:25:58 INFO - Return code: 0 16:25:58 INFO - Getting output from command: ['/builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/pip', '--version'] 16:25:58 INFO - Copy/paste: /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/pip --version 16:25:58 INFO - Reading from file tmpfile_stdout 16:25:58 INFO - Output received: 16:25:58 INFO - pip 1.1 from /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:25:58 INFO - Installing boto into virtualenv /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv 16:25:58 INFO - retry: Calling run_command with args: [['/builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/pip', 'install', '--timeout', '120', 'boto']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2258d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232b228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f13e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24685e0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c4108>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2292be8>, 'level': 'critical'}], 'cwd': '/builds/slave/tb-rel-c-beta-av-0000000000000/build', 'env': {'LESSOPEN': '|/usr/bin/lesspipe.sh %s', 'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PATH': '/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin', 'HOME': '/home/cltbld', 'LANG': 'en_US.UTF-8', 'CCACHE_HASHDIR': '', 'TERM': 'linux', 'SHELL': '/bin/bash', 'SHLVL': '1', 'G_BROKEN_FILENAMES': '1', 'HISTSIZE': '1000', 'PROPERTIES_FILE': '/builds/slave/tb-rel-c-beta-av-0000000000000/buildprops.json', 'BUILDBOTCUSTOM': 'https://hg.mozilla.org/build/buildbotcustom', '_': '/tools/buildbot/bin/python', 'BUILDBOT_CONFIGS': 'https://hg.mozilla.org/build/buildbot-configs', 'HOSTNAME': 'av-linux64-spot-004.build.releng.usw2.mozilla.com', 'HISTCONTROL': 'ignoredups', 'PWD': '/builds/slave/tb-rel-c-beta-av-0000000000000', 'CLOBBERER_URL': 'https://api.pub.build.mozilla.org/clobberer/forceclobber', 'MAIL': '/var/spool/mail/cltbld'}}, attempt #1 16:25:58 INFO - Running command: ['/builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/pip', 'install', '--timeout', '120', 'boto'] in /builds/slave/tb-rel-c-beta-av-0000000000000/build 16:25:58 INFO - Copy/paste: /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/pip install --timeout 120 boto 16:25:58 INFO - Using env: {'BUILDBOTCUSTOM': 'https://hg.mozilla.org/build/buildbotcustom', 16:25:58 INFO - 'BUILDBOT_CONFIGS': 'https://hg.mozilla.org/build/buildbot-configs', 16:25:58 INFO - 'CCACHE_HASHDIR': '', 16:25:58 INFO - 'CLOBBERER_URL': 'https://api.pub.build.mozilla.org/clobberer/forceclobber', 16:25:58 INFO - 'G_BROKEN_FILENAMES': '1', 16:25:58 INFO - 'HISTCONTROL': 'ignoredups', 16:25:58 INFO - 'HISTSIZE': '1000', 16:25:58 INFO - 'HOME': '/home/cltbld', 16:25:58 INFO - 'HOSTNAME': 'av-linux64-spot-004.build.releng.usw2.mozilla.com', 16:25:58 INFO - 'LANG': 'en_US.UTF-8', 16:25:58 INFO - 'LESSOPEN': '|/usr/bin/lesspipe.sh %s', 16:25:58 INFO - 'LOGNAME': 'cltbld', 16:25:58 INFO - 'MAIL': '/var/spool/mail/cltbld', 16:25:58 INFO - 'PATH': '/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin', 16:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/tb-rel-c-beta-av-0000000000000/buildprops.json', 16:25:58 INFO - 'PWD': '/builds/slave/tb-rel-c-beta-av-0000000000000', 16:25:58 INFO - 'SHELL': '/bin/bash', 16:25:58 INFO - 'SHLVL': '1', 16:25:58 INFO - 'TERM': 'linux', 16:25:58 INFO - 'TMOUT': '86400', 16:25:58 INFO - 'USER': 'cltbld', 16:25:58 INFO - '_': '/tools/buildbot/bin/python'} 16:26:05 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:26:05 INFO - Downloading/unpacking boto 16:26:05 INFO - Running setup.py egg_info for package boto 16:26:05 INFO - Downloading/unpacking botocore>=1.7.0,<1.8.0 (from boto) 16:26:05 INFO - Running setup.py egg_info for package botocore 16:26:05 INFO - no previously-included directories found matching 'docs/build' 16:26:05 INFO - Downloading/unpacking jmespath>=0.7.1,<1.0.0 (from boto) 16:26:05 INFO - Downloading jmespath-0.9.3.tar.gz 16:26:05 INFO - Running setup.py egg_info for package jmespath 16:26:05 INFO - Downloading/unpacking s3transfer>=0.1.10,<0.2.0 (from boto) 16:26:05 INFO - Running setup.py egg_info for package s3transfer 16:26:05 INFO - Downloading/unpacking python-dateutil>=2.1,<3.0.0 (from botocore>=1.7.0,<1.8.0->boto) 16:26:05 INFO - Running setup.py egg_info for package python-dateutil 16:26:05 INFO - warning: no previously-included files matching '__pycache__' found anywhere in distribution 16:26:05 INFO - warning: no previously-included files matching '*.py[co]' found anywhere in distribution 16:26:05 INFO - Downloading/unpacking docutils>=0.10 (from botocore>=1.7.0,<1.8.0->boto) 16:26:05 INFO - Running setup.py egg_info for package docutils 16:26:05 INFO - warning: no files found matching 'MANIFEST' 16:26:05 INFO - warning: no previously-included files matching '*.pyc' found under directory '*' 16:26:05 INFO - warning: no previously-included files matching '*~' found under directory '*' 16:26:05 INFO - warning: no previously-included files matching '.DS_Store' found under directory '*' 16:26:05 INFO - Downloading/unpacking futures>=2.2.0,<4.0.0 (from s3transfer>=0.1.10,<0.2.0->boto) 16:26:05 INFO - Downloading futures-3.1.1.tar.gz 16:26:05 INFO - Running setup.py egg_info for package futures 16:26:05 INFO - Downloading/unpacking six>=1.5 (from python-dateutil>=2.1,<3.0.0->botocore>=1.7.0,<1.8.0->boto) 16:26:05 INFO - Downloading six-1.10.0.tar.gz 16:26:05 INFO - Running setup.py egg_info for package six 16:26:05 INFO - no previously-included directories found matching 'documentation/_build' 16:26:05 INFO - Installing collected packages: boto, botocore, jmespath, s3transfer, python-dateutil, docutils, futures, six 16:26:05 INFO - Running setup.py install for boto 16:26:05 INFO - Running setup.py install for botocore 16:26:05 INFO - no previously-included directories found matching 'docs/build' 16:26:05 INFO - Running setup.py install for jmespath 16:26:05 INFO - changing mode of build/scripts-2.7/jp.py from 664 to 775 16:26:05 INFO - changing mode of /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/jp.py to 775 16:26:05 INFO - Running setup.py install for s3transfer 16:26:05 INFO - Running setup.py install for python-dateutil 16:26:05 INFO - warning: no previously-included files matching '__pycache__' found anywhere in distribution 16:26:05 INFO - warning: no previously-included files matching '*.py[co]' found anywhere in distribution 16:26:05 INFO - Running setup.py install for docutils 16:26:05 INFO - changing mode of build/scripts-2.7/rst2html.py from 664 to 775 16:26:05 INFO - changing mode of build/scripts-2.7/rst2html4.py from 664 to 775 16:26:05 INFO - changing mode of build/scripts-2.7/rst2html5.py from 664 to 775 16:26:05 INFO - changing mode of build/scripts-2.7/rst2s5.py from 664 to 775 16:26:05 INFO - changing mode of build/scripts-2.7/rst2latex.py from 664 to 775 16:26:05 INFO - changing mode of build/scripts-2.7/rst2xetex.py from 664 to 775 16:26:05 INFO - changing mode of build/scripts-2.7/rst2man.py from 664 to 775 16:26:05 INFO - changing mode of build/scripts-2.7/rst2xml.py from 664 to 775 16:26:05 INFO - changing mode of build/scripts-2.7/rst2pseudoxml.py from 664 to 775 16:26:05 INFO - changing mode of build/scripts-2.7/rstpep2html.py from 664 to 775 16:26:05 INFO - changing mode of build/scripts-2.7/rst2odt.py from 664 to 775 16:26:05 INFO - changing mode of build/scripts-2.7/rst2odt_prepstyles.py from 664 to 775 16:26:05 INFO - warning: no files found matching 'MANIFEST' 16:26:05 INFO - warning: no previously-included files matching '*.pyc' found under directory '*' 16:26:05 INFO - warning: no previously-included files matching '*~' found under directory '*' 16:26:05 INFO - warning: no previously-included files matching '.DS_Store' found under directory '*' 16:26:05 INFO - changing mode of /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/rst2odt.py to 775 16:26:05 INFO - changing mode of /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/rst2xetex.py to 775 16:26:05 INFO - changing mode of /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/rst2html4.py to 775 16:26:05 INFO - changing mode of /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/rstpep2html.py to 775 16:26:06 INFO - changing mode of /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/rst2pseudoxml.py to 775 16:26:06 INFO - changing mode of /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/rst2man.py to 775 16:26:06 INFO - changing mode of /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/rst2xml.py to 775 16:26:06 INFO - changing mode of /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/rst2latex.py to 775 16:26:06 INFO - changing mode of /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/rst2html5.py to 775 16:26:06 INFO - changing mode of /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/rst2s5.py to 775 16:26:06 INFO - changing mode of /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/rst2html.py to 775 16:26:06 INFO - changing mode of /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/rst2odt_prepstyles.py to 775 16:26:06 INFO - Running setup.py install for futures 16:26:06 INFO - Running setup.py install for six 16:26:06 INFO - no previously-included directories found matching 'documentation/_build' 16:26:06 INFO - Successfully installed boto botocore jmespath s3transfer python-dateutil docutils futures six 16:26:06 INFO - Cleaning up... 16:26:06 INFO - Return code: 0 16:26:06 INFO - Installing redo into virtualenv /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv 16:26:06 INFO - retry: Calling run_command with args: [['/builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/pip', 'install', '--timeout', '120', 'redo']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2258d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232b228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f13e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24685e0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c4108>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2292be8>, 'level': 'critical'}], 'cwd': '/builds/slave/tb-rel-c-beta-av-0000000000000/build', 'env': {'LESSOPEN': '|/usr/bin/lesspipe.sh %s', 'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PATH': '/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin', 'HOME': '/home/cltbld', 'LANG': 'en_US.UTF-8', 'CCACHE_HASHDIR': '', 'TERM': 'linux', 'SHELL': '/bin/bash', 'SHLVL': '1', 'G_BROKEN_FILENAMES': '1', 'HISTSIZE': '1000', 'PROPERTIES_FILE': '/builds/slave/tb-rel-c-beta-av-0000000000000/buildprops.json', 'BUILDBOTCUSTOM': 'https://hg.mozilla.org/build/buildbotcustom', '_': '/tools/buildbot/bin/python', 'BUILDBOT_CONFIGS': 'https://hg.mozilla.org/build/buildbot-configs', 'HOSTNAME': 'av-linux64-spot-004.build.releng.usw2.mozilla.com', 'HISTCONTROL': 'ignoredups', 'PWD': '/builds/slave/tb-rel-c-beta-av-0000000000000', 'CLOBBERER_URL': 'https://api.pub.build.mozilla.org/clobberer/forceclobber', 'MAIL': '/var/spool/mail/cltbld'}}, attempt #1 16:26:06 INFO - Running command: ['/builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/pip', 'install', '--timeout', '120', 'redo'] in /builds/slave/tb-rel-c-beta-av-0000000000000/build 16:26:06 INFO - Copy/paste: /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/pip install --timeout 120 redo 16:26:06 INFO - Using env: {'BUILDBOTCUSTOM': 'https://hg.mozilla.org/build/buildbotcustom', 16:26:06 INFO - 'BUILDBOT_CONFIGS': 'https://hg.mozilla.org/build/buildbot-configs', 16:26:06 INFO - 'CCACHE_HASHDIR': '', 16:26:06 INFO - 'CLOBBERER_URL': 'https://api.pub.build.mozilla.org/clobberer/forceclobber', 16:26:06 INFO - 'G_BROKEN_FILENAMES': '1', 16:26:06 INFO - 'HISTCONTROL': 'ignoredups', 16:26:06 INFO - 'HISTSIZE': '1000', 16:26:06 INFO - 'HOME': '/home/cltbld', 16:26:06 INFO - 'HOSTNAME': 'av-linux64-spot-004.build.releng.usw2.mozilla.com', 16:26:06 INFO - 'LANG': 'en_US.UTF-8', 16:26:06 INFO - 'LESSOPEN': '|/usr/bin/lesspipe.sh %s', 16:26:06 INFO - 'LOGNAME': 'cltbld', 16:26:06 INFO - 'MAIL': '/var/spool/mail/cltbld', 16:26:06 INFO - 'PATH': '/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin', 16:26:06 INFO - 'PROPERTIES_FILE': '/builds/slave/tb-rel-c-beta-av-0000000000000/buildprops.json', 16:26:06 INFO - 'PWD': '/builds/slave/tb-rel-c-beta-av-0000000000000', 16:26:06 INFO - 'SHELL': '/bin/bash', 16:26:06 INFO - 'SHLVL': '1', 16:26:06 INFO - 'TERM': 'linux', 16:26:06 INFO - 'TMOUT': '86400', 16:26:06 INFO - 'USER': 'cltbld', 16:26:06 INFO - '_': '/tools/buildbot/bin/python'} 16:26:06 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:26:06 INFO - Downloading/unpacking redo 16:26:06 INFO - Downloading redo-1.5.tar.gz 16:26:06 INFO - Running setup.py egg_info for package redo 16:26:06 INFO - Installing collected packages: redo 16:26:06 INFO - Running setup.py install for redo 16:26:06 INFO - Installing retry script to /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin 16:26:06 INFO - Successfully installed redo 16:26:06 INFO - Cleaning up... 16:26:06 INFO - Return code: 0 16:26:06 INFO - Installing mar into virtualenv /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv 16:26:06 INFO - retry: Calling run_command with args: [['/builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/pip', 'install', '--timeout', '120', 'mar']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2258d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232b228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f13e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24685e0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23c4108>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2292be8>, 'level': 'critical'}], 'cwd': '/builds/slave/tb-rel-c-beta-av-0000000000000/build', 'env': {'LESSOPEN': '|/usr/bin/lesspipe.sh %s', 'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PATH': '/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin', 'HOME': '/home/cltbld', 'LANG': 'en_US.UTF-8', 'CCACHE_HASHDIR': '', 'TERM': 'linux', 'SHELL': '/bin/bash', 'SHLVL': '1', 'G_BROKEN_FILENAMES': '1', 'HISTSIZE': '1000', 'PROPERTIES_FILE': '/builds/slave/tb-rel-c-beta-av-0000000000000/buildprops.json', 'BUILDBOTCUSTOM': 'https://hg.mozilla.org/build/buildbotcustom', '_': '/tools/buildbot/bin/python', 'BUILDBOT_CONFIGS': 'https://hg.mozilla.org/build/buildbot-configs', 'HOSTNAME': 'av-linux64-spot-004.build.releng.usw2.mozilla.com', 'HISTCONTROL': 'ignoredups', 'PWD': '/builds/slave/tb-rel-c-beta-av-0000000000000', 'CLOBBERER_URL': 'https://api.pub.build.mozilla.org/clobberer/forceclobber', 'MAIL': '/var/spool/mail/cltbld'}}, attempt #1 16:26:06 INFO - Running command: ['/builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/pip', 'install', '--timeout', '120', 'mar'] in /builds/slave/tb-rel-c-beta-av-0000000000000/build 16:26:06 INFO - Copy/paste: /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/pip install --timeout 120 mar 16:26:06 INFO - Using env: {'BUILDBOTCUSTOM': 'https://hg.mozilla.org/build/buildbotcustom', 16:26:06 INFO - 'BUILDBOT_CONFIGS': 'https://hg.mozilla.org/build/buildbot-configs', 16:26:06 INFO - 'CCACHE_HASHDIR': '', 16:26:06 INFO - 'CLOBBERER_URL': 'https://api.pub.build.mozilla.org/clobberer/forceclobber', 16:26:06 INFO - 'G_BROKEN_FILENAMES': '1', 16:26:06 INFO - 'HISTCONTROL': 'ignoredups', 16:26:06 INFO - 'HISTSIZE': '1000', 16:26:06 INFO - 'HOME': '/home/cltbld', 16:26:06 INFO - 'HOSTNAME': 'av-linux64-spot-004.build.releng.usw2.mozilla.com', 16:26:06 INFO - 'LANG': 'en_US.UTF-8', 16:26:06 INFO - 'LESSOPEN': '|/usr/bin/lesspipe.sh %s', 16:26:06 INFO - 'LOGNAME': 'cltbld', 16:26:06 INFO - 'MAIL': '/var/spool/mail/cltbld', 16:26:06 INFO - 'PATH': '/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin', 16:26:06 INFO - 'PROPERTIES_FILE': '/builds/slave/tb-rel-c-beta-av-0000000000000/buildprops.json', 16:26:06 INFO - 'PWD': '/builds/slave/tb-rel-c-beta-av-0000000000000', 16:26:06 INFO - 'SHELL': '/bin/bash', 16:26:06 INFO - 'SHLVL': '1', 16:26:06 INFO - 'TERM': 'linux', 16:26:06 INFO - 'TMOUT': '86400', 16:26:06 INFO - 'USER': 'cltbld', 16:26:06 INFO - '_': '/tools/buildbot/bin/python'} 16:26:07 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:26:07 INFO - Downloading/unpacking mar 16:26:07 INFO - Downloading mar-1.2.tar.gz 16:26:07 INFO - Running setup.py egg_info for package mar 16:26:07 INFO - no previously-included directories found matching 'dist' 16:26:07 INFO - Installing collected packages: mar 16:26:07 INFO - Running setup.py install for mar 16:26:07 INFO - changing mode of build/scripts-2.7/mar.py from 664 to 775 16:26:07 INFO - no previously-included directories found matching 'dist' 16:26:07 INFO - changing mode of /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/mar.py to 775 16:26:07 INFO - Successfully installed mar 16:26:07 INFO - Cleaning up... 16:26:07 INFO - Return code: 0 16:26:07 INFO - Done creating virtualenv /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv. 16:26:07 INFO - Getting output from command: ['/builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/pip', 'freeze'] 16:26:07 INFO - Copy/paste: /builds/slave/tb-rel-c-beta-av-0000000000000/build/venv/bin/pip freeze 16:26:07 INFO - Reading from file tmpfile_stdout 16:26:07 INFO - Current package versions: 16:26:07 INFO - boto3 == 1.4.7 16:26:07 INFO - botocore == 1.7.7 16:26:07 INFO - distribute == 0.6.24 16:26:07 INFO - docutils == 0.14 16:26:07 INFO - futures == 3.1.1 16:26:07 INFO - jmespath == 0.9.3 16:26:07 INFO - mar == 1.2 16:26:07 INFO - python-dateutil == 2.6.0 16:26:07 INFO - redo == 1.5 16:26:07 INFO - s3transfer == 0.1.11 16:26:07 INFO - six == 1.10.0 16:26:07 INFO - wsgiref == 0.1.2 16:26:07 INFO - [mozharness: 2017-09-30 23:26:07.808240Z] Finished create-virtualenv step (success) 16:26:07 INFO - [mozharness: 2017-09-30 23:26:07.808425Z] Running activate-virtualenv step. 16:26:07 INFO - Running main action method: activate_virtualenv 16:26:07 INFO - [mozharness: 2017-09-30 23:26:07.809755Z] Finished activate-virtualenv step (success) 16:26:07 INFO - [mozharness: 2017-09-30 23:26:07.809938Z] Running get-extract-script step. 16:26:07 INFO - Running main action method: get_extract_script 16:26:07 INFO - Downloading https://hg.mozilla.org/build/tools/raw-file/THUNDERBIRD_56_0b4_RELEASE/stage/extract_and_run_command.py to extract_and_run_command.py 16:26:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/build/tools/raw-file/THUNDERBIRD_56_0b4_RELEASE/stage/extract_and_run_command.py', 'file_name': 'extract_and_run_command.py'}, attempt #1 16:26:08 INFO - Downloaded 6430 bytes. 16:26:08 INFO - [mozharness: 2017-09-30 23:26:08.046575Z] Finished get-extract-script step (success) 16:26:08 INFO - [mozharness: 2017-09-30 23:26:08.046774Z] Running get-files step. 16:26:08 INFO - Running main action method: get_files 16:26:08 INFO - [mozharness: 2017-09-30 23:26:08.047402Z] Finished get-files step (failed) 16:26:08 FATAL - Uncaught exception: Traceback (most recent call last): 16:26:08 FATAL - File "/builds/slave/tb-rel-c-beta-av-0000000000000/scripts/mozharness/base/script.py", line 2068, in run 16:26:08 FATAL - self.run_action(action) 16:26:08 FATAL - File "/builds/slave/tb-rel-c-beta-av-0000000000000/scripts/mozharness/base/script.py", line 2007, in run_action 16:26:08 FATAL - self._possibly_run_method(method_name, error_if_missing=True) 16:26:08 FATAL - File "/builds/slave/tb-rel-c-beta-av-0000000000000/scripts/mozharness/base/script.py", line 1947, in _possibly_run_method 16:26:08 FATAL - return getattr(self, method_name)() 16:26:08 FATAL - File "scripts/scripts/release/antivirus.py", line 131, in get_files 16:26:08 FATAL - from boto.s3.connection import S3Connection 16:26:08 FATAL - ImportError: No module named boto.s3.connection 16:26:08 FATAL - Running post_fatal callback... 16:26:08 FATAL - Exiting -1 16:26:08 INFO - Running post-run listener: copy_logs_to_upload_dir 16:26:08 INFO - Copying logs to upload dir... 16:26:08 INFO - mkdir: /builds/slave/tb-rel-c-beta-av-0000000000000/build/upload/logs program finished with exit code 255 elapsedTime=12.764070 ========= master_lag: 0.01 ========= ========= Finished 'python2.7 scripts/scripts/release/antivirus.py ...' failed (results: 2, elapsed: 12 secs) (at 2017-09-30 16:26:08.057455) ========= ========= Started no change (results: 0, elapsed: 0 secs) (at 2017-09-30 16:26:08.058925) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/tb-rel-c-beta-av-0000000000000/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: BUILDBOTCUSTOM=https://hg.mozilla.org/build/buildbotcustom BUILDBOT_CONFIGS=https://hg.mozilla.org/build/buildbot-configs CCACHE_HASHDIR= CLOBBERER_URL=https://api.pub.build.mozilla.org/clobberer/forceclobber G_BROKEN_FILENAMES=1 HISTCONTROL=ignoredups HISTSIZE=1000 HOME=/home/cltbld HOSTNAME=av-linux64-spot-004.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/tb-rel-c-beta-av-0000000000000/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.004785 ========= master_lag: 0.04 ========= ========= Finished no change (results: 0, elapsed: 0 secs) (at 2017-09-30 16:26:08.106940) ========= ========= Total master_lag: 0.12 =========