builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-051 starttime: 1468504582.38 results: success (0) buildid: 20160714033953 builduid: 3105cf7378654c57ae3a47189abad7a4 revision: 3ad93af112afba86d68faf787d984e10bb4d9971 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-14 06:56:22.377917) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-14 06:56:22.378512) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-14 06:56:22.378818) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-14 06:56:22.396789) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 06:56:22.397196) ========= 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/test/. (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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-14 06:56:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.6M=0s 2016-07-14 06:56:22 (35.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111485 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 06:56:22.524872) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 06:56:22.525213) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020669 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 06:56:22.575681) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 06:56:22.576102) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3ad93af112afba86d68faf787d984e10bb4d9971 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3ad93af112afba86d68faf787d984e10bb4d9971 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-14 06:56:22,969 truncating revision to first 12 chars 2016-07-14 06:56:22,969 Setting DEBUG logging. 2016-07-14 06:56:22,969 attempt 1/10 2016-07-14 06:56:22,969 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3ad93af112af?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-14 06:56:23,280 unpacking tar archive at: mozilla-aurora-3ad93af112af/testing/mozharness/ program finished with exit code 0 elapsedTime=0.693198 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 06:56:23.283929) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-14 06:56:23.284327) ========= script_repo_revision: 3ad93af112afba86d68faf787d984e10bb4d9971 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-14 06:56:23.284769) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-14 06:56:23.285071) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-14 06:56:23.295181) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-07-14 06:56:23.295515) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:56:23 INFO - MultiFileLogger online at 20160714 06:56:23 in /builds/slave/test 06:56:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 06:56:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:56:23 INFO - {'append_to_log': False, 06:56:23 INFO - 'base_work_dir': '/builds/slave/test', 06:56:23 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:56:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:56:23 INFO - 'branch': 'Mozilla-Aurora', 06:56:23 INFO - 'buildbot_json_path': 'buildprops.json', 06:56:23 INFO - 'config_files': ('talos/linux_config.py',), 06:56:23 INFO - 'default_actions': ('clobber', 06:56:23 INFO - 'read-buildbot-config', 06:56:23 INFO - 'download-and-extract', 06:56:23 INFO - 'populate-webroot', 06:56:23 INFO - 'create-virtualenv', 06:56:23 INFO - 'install', 06:56:23 INFO - 'run-tests'), 06:56:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:56:23 INFO - 'download_minidump_stackwalk': True, 06:56:23 INFO - 'download_symbols': 'ondemand', 06:56:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:56:23 INFO - 'tooltool.py': '/tools/tooltool.py', 06:56:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:56:23 INFO - '/tools/misc-python/virtualenv.py')}, 06:56:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:56:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:56:23 INFO - 'installer_path': 'installer.exe', 06:56:23 INFO - 'log_level': 'info', 06:56:23 INFO - 'log_name': 'talos', 06:56:23 INFO - 'log_to_console': True, 06:56:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:56:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:56:23 INFO - 'opt_config_files': (), 06:56:23 INFO - 'pip_index': False, 06:56:23 INFO - 'sps_profile': False, 06:56:23 INFO - 'sps_profile_interval': 0, 06:56:23 INFO - 'suite': 'dromaeojs', 06:56:23 INFO - 'system_bits': '32', 06:56:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:56:23 INFO - 'title': 'talos-linux64-ix-051', 06:56:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:56:23 INFO - 'use_talos_json': True, 06:56:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:56:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:56:23 INFO - 'work_dir': 'build'} 06:56:23 INFO - ##### 06:56:23 INFO - ##### Running clobber step. 06:56:23 INFO - ##### 06:56:23 INFO - Running pre-action listener: _resource_record_pre_action 06:56:23 INFO - Running main action method: clobber 06:56:23 INFO - rmtree: /builds/slave/test/build 06:56:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:56:24 INFO - Running post-action listener: _resource_record_post_action 06:56:24 INFO - ##### 06:56:24 INFO - ##### Finished clobber step (success) 06:56:24 INFO - ##### 06:56:24 INFO - ##### 06:56:24 INFO - ##### Running read-buildbot-config step. 06:56:24 INFO - ##### 06:56:24 INFO - Running pre-action listener: _resource_record_pre_action 06:56:24 INFO - Running main action method: read_buildbot_config 06:56:24 INFO - Using buildbot properties: 06:56:24 INFO - { 06:56:24 INFO - "project": "", 06:56:24 INFO - "product": "firefox", 06:56:24 INFO - "script_repo_revision": "production", 06:56:24 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 06:56:24 INFO - "repository": "", 06:56:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 06:56:24 INFO - "buildid": "20160714033953", 06:56:24 INFO - "pgo_build": "True", 06:56:24 INFO - "basedir": "/builds/slave/test", 06:56:24 INFO - "buildnumber": 608, 06:56:24 INFO - "slavename": "talos-linux64-ix-051", 06:56:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:56:24 INFO - "platform": "ubuntu64_hw", 06:56:24 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 06:56:24 INFO - "branch": "mozilla-aurora", 06:56:24 INFO - "repo_path": "releases/mozilla-aurora", 06:56:24 INFO - "revision": "3ad93af112afba86d68faf787d984e10bb4d9971", 06:56:24 INFO - "stage_platform": "linux64-pgo", 06:56:24 INFO - "builduid": "3105cf7378654c57ae3a47189abad7a4", 06:56:24 INFO - "slavebuilddir": "test" 06:56:24 INFO - } 06:56:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 06:56:24 INFO - Running post-action listener: _resource_record_post_action 06:56:24 INFO - ##### 06:56:24 INFO - ##### Finished read-buildbot-config step (success) 06:56:24 INFO - ##### 06:56:24 INFO - ##### 06:56:24 INFO - ##### Running download-and-extract step. 06:56:24 INFO - ##### 06:56:24 INFO - Running pre-action listener: _resource_record_pre_action 06:56:24 INFO - Running main action method: download_and_extract 06:56:24 INFO - mkdir: /builds/slave/test/build/tests 06:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:24 INFO - https://queue.taskcluster.net/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:56:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 06:56:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 06:56:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 06:56:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 06:56:24 INFO - Downloaded 1361 bytes. 06:56:24 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 06:56:24 INFO - Using the following test package requirements: 06:56:24 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 06:56:24 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 06:56:24 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 06:56:24 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 06:56:24 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 06:56:24 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 06:56:24 INFO - u'jsshell-linux-x86_64.zip'], 06:56:24 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 06:56:24 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 06:56:24 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 06:56:24 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 06:56:24 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 06:56:24 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 06:56:24 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 06:56:24 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 06:56:24 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 06:56:24 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 06:56:24 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 06:56:24 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:24 INFO - https://queue.taskcluster.net/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:56:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 06:56:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 06:56:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 06:56:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:56:26 INFO - Downloaded 24490909 bytes. 06:56:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:56:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 06:56:27 INFO - Return code: 0 06:56:27 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 06:56:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:27 INFO - https://queue.taskcluster.net/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:56:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 06:56:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 06:56:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 06:56:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:56:28 INFO - Downloaded 24490909 bytes. 06:56:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:56:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 06:56:29 INFO - Return code: 0 06:56:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:29 INFO - https://queue.taskcluster.net/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:56:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 06:56:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 06:56:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 06:56:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:56:30 INFO - Downloaded 10833369 bytes. 06:56:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:56:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 06:56:31 INFO - Return code: 0 06:56:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:31 INFO - https://queue.taskcluster.net/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:56:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 06:56:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 06:56:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 06:56:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 06:56:32 INFO - Downloaded 63633130 bytes. 06:56:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 06:56:32 INFO - mkdir: /builds/slave/test/properties 06:56:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:56:32 INFO - Writing to file /builds/slave/test/properties/build_url 06:56:32 INFO - Contents: 06:56:32 INFO - build_url:https://queue.taskcluster.net/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 06:56:33 INFO - Running post-action listener: _resource_record_post_action 06:56:33 INFO - Running post-action listener: set_extra_try_arguments 06:56:33 INFO - ##### 06:56:33 INFO - ##### Finished download-and-extract step (success) 06:56:33 INFO - ##### 06:56:33 INFO - ##### 06:56:33 INFO - ##### Running populate-webroot step. 06:56:33 INFO - ##### 06:56:33 INFO - Running pre-action listener: _resource_record_pre_action 06:56:33 INFO - Running main action method: populate_webroot 06:56:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:56:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:56:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:56:33 INFO - '--tppagecycles', 06:56:33 INFO - '7'], 06:56:33 INFO - 'tests': ['tsvgm']}}, 06:56:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:56:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:56:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:56:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:56:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:56:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:56:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:33 INFO - 'talos_options': ['--disable-e10s'], 06:56:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:56:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:56:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:33 INFO - 'talos_options': ['--disable-e10s'], 06:56:33 INFO - 'tests': ['damp', 'tps']}, 06:56:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:33 INFO - 'tests': ['damp', 'tps']}, 06:56:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:56:33 INFO - 'tests': ['dromaeo_dom']}, 06:56:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:56:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:56:33 INFO - 'tests': ['a11yr', 06:56:33 INFO - 'ts_paint', 06:56:33 INFO - 'tpaint', 06:56:33 INFO - 'sessionrestore', 06:56:33 INFO - 'sessionrestore_no_auto_restore', 06:56:33 INFO - 'tabpaint']}, 06:56:33 INFO - 'other-e10s': {'tests': ['a11yr', 06:56:33 INFO - 'ts_paint', 06:56:33 INFO - 'tpaint', 06:56:33 INFO - 'sessionrestore', 06:56:33 INFO - 'sessionrestore_no_auto_restore', 06:56:33 INFO - 'tabpaint']}, 06:56:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:56:33 INFO - 'ts_paint', 06:56:33 INFO - 'tpaint', 06:56:33 INFO - 'sessionrestore', 06:56:33 INFO - 'sessionrestore_no_auto_restore', 06:56:33 INFO - 'tabpaint']}, 06:56:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:56:33 INFO - 'ts_paint', 06:56:33 INFO - 'tpaint', 06:56:33 INFO - 'sessionrestore', 06:56:33 INFO - 'sessionrestore_no_auto_restore', 06:56:33 INFO - 'tabpaint']}, 06:56:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:56:33 INFO - 'tests': ['a11yr', 06:56:33 INFO - 'ts_paint', 06:56:33 INFO - 'tpaint', 06:56:33 INFO - 'sessionrestore', 06:56:33 INFO - 'sessionrestore_no_auto_restore', 06:56:33 INFO - 'tabpaint']}, 06:56:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:56:33 INFO - 'tests': ['a11yr', 06:56:33 INFO - 'ts_paint', 06:56:33 INFO - 'tpaint', 06:56:33 INFO - 'sessionrestore', 06:56:33 INFO - 'sessionrestore_no_auto_restore', 06:56:33 INFO - 'tabpaint']}, 06:56:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:56:33 INFO - 'tests': ['tsvgx', 06:56:33 INFO - 'tsvgr_opacity', 06:56:33 INFO - 'tart', 06:56:33 INFO - 'tscrollx', 06:56:33 INFO - 'cart']}, 06:56:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:56:33 INFO - 'tsvgr_opacity', 06:56:33 INFO - 'tart', 06:56:33 INFO - 'tscrollx', 06:56:33 INFO - 'cart']}, 06:56:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:33 INFO - 'talos_options': ['--disable-e10s'], 06:56:33 INFO - 'tests': ['tp5o']}, 06:56:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:33 INFO - 'tests': ['tp5o']}, 06:56:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:33 INFO - 'talos_options': ['--disable-e10s', 06:56:33 INFO - '--xperf_path', 06:56:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:56:33 INFO - 'tests': ['tp5n']}, 06:56:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:33 INFO - 'talos_options': ['--xperf_path', 06:56:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:56:33 INFO - 'tests': ['tp5n']}}, 06:56:33 INFO - 'talos.zip': {'path': '', 06:56:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:56:33 INFO - Running post-action listener: _resource_record_post_action 06:56:33 INFO - ##### 06:56:33 INFO - ##### Finished populate-webroot step (success) 06:56:33 INFO - ##### 06:56:33 INFO - ##### 06:56:33 INFO - ##### Running create-virtualenv step. 06:56:33 INFO - ##### 06:56:33 INFO - Running pre-action listener: _resource_record_pre_action 06:56:33 INFO - Running main action method: create_virtualenv 06:56:33 INFO - Creating virtualenv /builds/slave/test/build/venv 06:56:33 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:56:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:56:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:56:33 INFO - Using real prefix '/usr' 06:56:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:56:33 INFO - Installing distribute.............................................................................................................................................................................................done. 06:56:35 INFO - Installing pip.................done. 06:56:35 INFO - Return code: 0 06:56:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940c964f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14043b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1496990>, '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 0x1402470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1402900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:56:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:56:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:56:35 INFO - Using env: {'DISPLAY': ':0', 06:56:35 INFO - 'HOME': '/home/cltbld', 06:56:35 INFO - 'LANG': 'en_US.UTF-8', 06:56:35 INFO - 'LANGUAGE': 'en_US:en', 06:56:35 INFO - 'LOGNAME': 'cltbld', 06:56:35 INFO - 'MAIL': '/var/mail/cltbld', 06:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:35 INFO - 'MOZ_NO_REMOTE': '1', 06:56:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:56:35 INFO - 'NO_EM_RESTART': '1', 06:56:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:56:35 INFO - 'PWD': '/builds/slave/test', 06:56:35 INFO - 'SHELL': '/bin/bash', 06:56:35 INFO - 'SHLVL': '1', 06:56:35 INFO - 'TERM': 'linux', 06:56:35 INFO - 'TMOUT': '86400', 06:56:35 INFO - 'USER': 'cltbld', 06:56:35 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 06:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:56:35 INFO - '_': '/tools/buildbot/bin/python'} 06:56:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 06:56:36 INFO - Cleaning up... 06:56:36 INFO - Return code: 0 06:56:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:56:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940c964f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14043b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1496990>, '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 0x1402470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1402900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:56:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:56:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:56:36 INFO - Using env: {'DISPLAY': ':0', 06:56:36 INFO - 'HOME': '/home/cltbld', 06:56:36 INFO - 'LANG': 'en_US.UTF-8', 06:56:36 INFO - 'LANGUAGE': 'en_US:en', 06:56:36 INFO - 'LOGNAME': 'cltbld', 06:56:36 INFO - 'MAIL': '/var/mail/cltbld', 06:56:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:36 INFO - 'MOZ_NO_REMOTE': '1', 06:56:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:56:36 INFO - 'NO_EM_RESTART': '1', 06:56:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:56:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:56:36 INFO - 'PWD': '/builds/slave/test', 06:56:36 INFO - 'SHELL': '/bin/bash', 06:56:36 INFO - 'SHLVL': '1', 06:56:36 INFO - 'TERM': 'linux', 06:56:36 INFO - 'TMOUT': '86400', 06:56:36 INFO - 'USER': 'cltbld', 06:56:36 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 06:56:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:56:36 INFO - '_': '/tools/buildbot/bin/python'} 06:56:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:36 INFO - Downloading/unpacking psutil>=3.1.1 06:56:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:56:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:56:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:56:38 INFO - Installing collected packages: psutil 06:56:38 INFO - Running setup.py install for psutil 06:56:38 INFO - building 'psutil._psutil_linux' extension 06:56:38 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 06:56:38 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 06:56:38 INFO - building 'psutil._psutil_posix' extension 06:56:38 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 06:56:38 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 06:56:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:56:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:56:38 INFO - Successfully installed psutil 06:56:38 INFO - Cleaning up... 06:56:38 INFO - Return code: 0 06:56:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940c964f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14043b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1496990>, '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 0x1402470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1402900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:56:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:56:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:56:38 INFO - Using env: {'DISPLAY': ':0', 06:56:38 INFO - 'HOME': '/home/cltbld', 06:56:38 INFO - 'LANG': 'en_US.UTF-8', 06:56:38 INFO - 'LANGUAGE': 'en_US:en', 06:56:38 INFO - 'LOGNAME': 'cltbld', 06:56:38 INFO - 'MAIL': '/var/mail/cltbld', 06:56:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:38 INFO - 'MOZ_NO_REMOTE': '1', 06:56:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:56:38 INFO - 'NO_EM_RESTART': '1', 06:56:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:56:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:56:38 INFO - 'PWD': '/builds/slave/test', 06:56:38 INFO - 'SHELL': '/bin/bash', 06:56:38 INFO - 'SHLVL': '1', 06:56:38 INFO - 'TERM': 'linux', 06:56:38 INFO - 'TMOUT': '86400', 06:56:38 INFO - 'USER': 'cltbld', 06:56:38 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 06:56:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:56:38 INFO - '_': '/tools/buildbot/bin/python'} 06:56:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:56:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:56:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:56:41 INFO - Installing collected packages: mozsystemmonitor 06:56:41 INFO - Running setup.py install for mozsystemmonitor 06:56:41 INFO - Successfully installed mozsystemmonitor 06:56:41 INFO - Cleaning up... 06:56:41 INFO - Return code: 0 06:56:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:56:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940c964f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14043b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1496990>, '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 0x1402470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1402900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:56:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:56:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:56:41 INFO - Using env: {'DISPLAY': ':0', 06:56:41 INFO - 'HOME': '/home/cltbld', 06:56:41 INFO - 'LANG': 'en_US.UTF-8', 06:56:41 INFO - 'LANGUAGE': 'en_US:en', 06:56:41 INFO - 'LOGNAME': 'cltbld', 06:56:41 INFO - 'MAIL': '/var/mail/cltbld', 06:56:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:41 INFO - 'MOZ_NO_REMOTE': '1', 06:56:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:56:41 INFO - 'NO_EM_RESTART': '1', 06:56:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:56:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:56:41 INFO - 'PWD': '/builds/slave/test', 06:56:41 INFO - 'SHELL': '/bin/bash', 06:56:41 INFO - 'SHLVL': '1', 06:56:41 INFO - 'TERM': 'linux', 06:56:41 INFO - 'TMOUT': '86400', 06:56:41 INFO - 'USER': 'cltbld', 06:56:41 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 06:56:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:56:41 INFO - '_': '/tools/buildbot/bin/python'} 06:56:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:41 INFO - Downloading/unpacking jsonschema==2.5.1 06:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:56:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:43 INFO - Downloading functools32-3.2.3-2.tar.gz 06:56:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:56:43 INFO - warning: no files found matching '*.txt' 06:56:43 INFO - no previously-included directories found matching 'build' 06:56:43 INFO - no previously-included directories found matching 'dist' 06:56:43 INFO - no previously-included directories found matching '.git*' 06:56:43 INFO - Installing collected packages: jsonschema, functools32 06:56:43 INFO - Running setup.py install for functools32 06:56:43 INFO - warning: no files found matching '*.txt' 06:56:43 INFO - no previously-included directories found matching 'build' 06:56:43 INFO - no previously-included directories found matching 'dist' 06:56:43 INFO - no previously-included directories found matching '.git*' 06:56:43 INFO - Successfully installed jsonschema functools32 06:56:43 INFO - Cleaning up... 06:56:43 INFO - Return code: 0 06:56:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940c964f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14043b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1496990>, '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 0x1402470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1402900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:56:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:56:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:56:43 INFO - Using env: {'DISPLAY': ':0', 06:56:43 INFO - 'HOME': '/home/cltbld', 06:56:43 INFO - 'LANG': 'en_US.UTF-8', 06:56:43 INFO - 'LANGUAGE': 'en_US:en', 06:56:43 INFO - 'LOGNAME': 'cltbld', 06:56:43 INFO - 'MAIL': '/var/mail/cltbld', 06:56:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:43 INFO - 'MOZ_NO_REMOTE': '1', 06:56:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:56:43 INFO - 'NO_EM_RESTART': '1', 06:56:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:56:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:56:43 INFO - 'PWD': '/builds/slave/test', 06:56:43 INFO - 'SHELL': '/bin/bash', 06:56:43 INFO - 'SHLVL': '1', 06:56:43 INFO - 'TERM': 'linux', 06:56:43 INFO - 'TMOUT': '86400', 06:56:43 INFO - 'USER': 'cltbld', 06:56:43 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 06:56:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:56:43 INFO - '_': '/tools/buildbot/bin/python'} 06:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:44 INFO - Downloading/unpacking blobuploader==1.2.4 06:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:45 INFO - Downloading blobuploader-1.2.4.tar.gz 06:56:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:56:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:56:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:46 INFO - Downloading docopt-0.6.1.tar.gz 06:56:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:56:46 INFO - Installing collected packages: blobuploader, requests, docopt 06:56:46 INFO - Running setup.py install for blobuploader 06:56:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:56:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:56:46 INFO - Running setup.py install for requests 06:56:47 INFO - Running setup.py install for docopt 06:56:47 INFO - Successfully installed blobuploader requests docopt 06:56:47 INFO - Cleaning up... 06:56:47 INFO - Return code: 0 06:56:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:56:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940c964f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14043b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1496990>, '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 0x1402470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1402900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:56:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:56:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:56:47 INFO - Using env: {'DISPLAY': ':0', 06:56:47 INFO - 'HOME': '/home/cltbld', 06:56:47 INFO - 'LANG': 'en_US.UTF-8', 06:56:47 INFO - 'LANGUAGE': 'en_US:en', 06:56:47 INFO - 'LOGNAME': 'cltbld', 06:56:47 INFO - 'MAIL': '/var/mail/cltbld', 06:56:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:47 INFO - 'MOZ_NO_REMOTE': '1', 06:56:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:56:47 INFO - 'NO_EM_RESTART': '1', 06:56:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:56:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:56:47 INFO - 'PWD': '/builds/slave/test', 06:56:47 INFO - 'SHELL': '/bin/bash', 06:56:47 INFO - 'SHLVL': '1', 06:56:47 INFO - 'TERM': 'linux', 06:56:47 INFO - 'TMOUT': '86400', 06:56:47 INFO - 'USER': 'cltbld', 06:56:47 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 06:56:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:56:47 INFO - '_': '/tools/buildbot/bin/python'} 06:56:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:56:47 INFO - Running setup.py (path:/tmp/pip-z9TIrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:56:47 INFO - Running setup.py (path:/tmp/pip-k_4nmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:56:47 INFO - Running setup.py (path:/tmp/pip-viCe5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:56:47 INFO - Running setup.py (path:/tmp/pip-lPO94v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:56:47 INFO - Running setup.py (path:/tmp/pip-vZ99e_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:56:47 INFO - Running setup.py (path:/tmp/pip-EecxVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:56:47 INFO - Running setup.py (path:/tmp/pip-WgjhAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:56:48 INFO - Running setup.py (path:/tmp/pip-puFaFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:56:48 INFO - Running setup.py (path:/tmp/pip-L02y9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:56:48 INFO - Running setup.py (path:/tmp/pip-nRiD0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:56:48 INFO - Running setup.py (path:/tmp/pip-k0yrKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:56:48 INFO - Running setup.py (path:/tmp/pip-mlfH5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:56:48 INFO - Running setup.py (path:/tmp/pip-gU4bTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:56:48 INFO - Running setup.py (path:/tmp/pip-Xd9A8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:56:48 INFO - Running setup.py (path:/tmp/pip-nxEl82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:56:48 INFO - Running setup.py (path:/tmp/pip-relXI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:56:48 INFO - Running setup.py (path:/tmp/pip-quNaqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:56:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:56:48 INFO - Running setup.py install for manifestparser 06:56:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:56:48 INFO - Running setup.py install for mozcrash 06:56:48 INFO - Running setup.py install for mozdebug 06:56:49 INFO - Running setup.py install for mozdevice 06:56:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:56:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:56:49 INFO - Running setup.py install for mozfile 06:56:49 INFO - Running setup.py install for mozhttpd 06:56:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:56:49 INFO - Running setup.py install for mozinfo 06:56:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:56:49 INFO - Running setup.py install for mozInstall 06:56:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:56:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:56:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:56:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:56:49 INFO - Running setup.py install for mozleak 06:56:49 INFO - Running setup.py install for mozlog 06:56:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:56:49 INFO - Running setup.py install for moznetwork 06:56:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:56:49 INFO - Running setup.py install for mozprocess 06:56:50 INFO - Running setup.py install for mozprofile 06:56:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:56:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:56:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:56:50 INFO - Running setup.py install for mozrunner 06:56:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:56:50 INFO - Running setup.py install for mozscreenshot 06:56:50 INFO - Running setup.py install for moztest 06:56:50 INFO - Running setup.py install for mozversion 06:56:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:56:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:56:50 INFO - Cleaning up... 06:56:50 INFO - Return code: 0 06:56:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:56:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940c964f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14043b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1496990>, '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 0x1402470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1402900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:56:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:56:50 INFO - Using env: {'DISPLAY': ':0', 06:56:50 INFO - 'HOME': '/home/cltbld', 06:56:50 INFO - 'LANG': 'en_US.UTF-8', 06:56:50 INFO - 'LANGUAGE': 'en_US:en', 06:56:50 INFO - 'LOGNAME': 'cltbld', 06:56:50 INFO - 'MAIL': '/var/mail/cltbld', 06:56:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:50 INFO - 'MOZ_NO_REMOTE': '1', 06:56:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:56:50 INFO - 'NO_EM_RESTART': '1', 06:56:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:56:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:56:50 INFO - 'PWD': '/builds/slave/test', 06:56:50 INFO - 'SHELL': '/bin/bash', 06:56:50 INFO - 'SHLVL': '1', 06:56:50 INFO - 'TERM': 'linux', 06:56:50 INFO - 'TMOUT': '86400', 06:56:50 INFO - 'USER': 'cltbld', 06:56:50 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 06:56:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:56:50 INFO - '_': '/tools/buildbot/bin/python'} 06:56:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:56:50 INFO - Running setup.py (path:/tmp/pip-vfshXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:56:50 INFO - Running setup.py (path:/tmp/pip-JeHq30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:56:50 INFO - Running setup.py (path:/tmp/pip-zKgFKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:56:51 INFO - Running setup.py (path:/tmp/pip-cixYyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:56:51 INFO - Running setup.py (path:/tmp/pip-EWuLDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:56:51 INFO - Running setup.py (path:/tmp/pip-D74riG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:56:51 INFO - Running setup.py (path:/tmp/pip-YhcpNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:56:51 INFO - Running setup.py (path:/tmp/pip-jJ6Eup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:56:51 INFO - Running setup.py (path:/tmp/pip-XMLovj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:56:51 INFO - Running setup.py (path:/tmp/pip-5Oul0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:56:51 INFO - Running setup.py (path:/tmp/pip-7G1DIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:56:51 INFO - Running setup.py (path:/tmp/pip-bd5NAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:56:51 INFO - Running setup.py (path:/tmp/pip-iNOFKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:56:51 INFO - Running setup.py (path:/tmp/pip-uyOue9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:56:51 INFO - Running setup.py (path:/tmp/pip-amtAG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:56:51 INFO - Running setup.py (path:/tmp/pip-BjmWOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:56:51 INFO - Running setup.py (path:/tmp/pip-fbYL2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:56:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:56:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:56:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:56:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:56:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:56:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:56:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:56:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:53 INFO - Downloading blessings-1.6.tar.gz 06:56:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:56:54 INFO - Installing collected packages: blessings 06:56:54 INFO - Running setup.py install for blessings 06:56:54 INFO - Successfully installed blessings 06:56:54 INFO - Cleaning up... 06:56:54 INFO - Return code: 0 06:56:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:56:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:56:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:56:54 INFO - Reading from file tmpfile_stdout 06:56:54 INFO - Current package versions: 06:56:54 INFO - argparse == 1.2.1 06:56:54 INFO - blessings == 1.6 06:56:54 INFO - blobuploader == 1.2.4 06:56:54 INFO - docopt == 0.6.1 06:56:54 INFO - functools32 == 3.2.3-2 06:56:54 INFO - jsonschema == 2.5.1 06:56:54 INFO - manifestparser == 1.1 06:56:54 INFO - mozInstall == 1.12 06:56:54 INFO - mozcrash == 0.17 06:56:54 INFO - mozdebug == 0.1 06:56:54 INFO - mozdevice == 0.48 06:56:54 INFO - mozfile == 1.2 06:56:54 INFO - mozhttpd == 0.7 06:56:54 INFO - mozinfo == 0.9 06:56:54 INFO - mozleak == 0.1 06:56:54 INFO - mozlog == 3.2 06:56:54 INFO - moznetwork == 0.27 06:56:54 INFO - mozprocess == 0.23 06:56:54 INFO - mozprofile == 0.28 06:56:54 INFO - mozrunner == 6.12 06:56:54 INFO - mozscreenshot == 0.1 06:56:54 INFO - mozsystemmonitor == 0.3 06:56:54 INFO - moztest == 0.7 06:56:54 INFO - mozversion == 1.4 06:56:54 INFO - psutil == 3.1.1 06:56:54 INFO - requests == 1.2.3 06:56:54 INFO - wsgiref == 0.1.2 06:56:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940c964f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14043b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1496990>, '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 0x1402470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1402900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:56:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:56:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:56:54 INFO - Using env: {'DISPLAY': ':0', 06:56:54 INFO - 'HOME': '/home/cltbld', 06:56:54 INFO - 'LANG': 'en_US.UTF-8', 06:56:54 INFO - 'LANGUAGE': 'en_US:en', 06:56:54 INFO - 'LOGNAME': 'cltbld', 06:56:54 INFO - 'MAIL': '/var/mail/cltbld', 06:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:54 INFO - 'MOZ_NO_REMOTE': '1', 06:56:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:56:54 INFO - 'NO_EM_RESTART': '1', 06:56:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:56:54 INFO - 'PWD': '/builds/slave/test', 06:56:54 INFO - 'SHELL': '/bin/bash', 06:56:54 INFO - 'SHLVL': '1', 06:56:54 INFO - 'TERM': 'linux', 06:56:54 INFO - 'TMOUT': '86400', 06:56:54 INFO - 'USER': 'cltbld', 06:56:54 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 06:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:56:54 INFO - '_': '/tools/buildbot/bin/python'} 06:56:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:56:54 INFO - Cleaning up... 06:56:54 INFO - Return code: 0 06:56:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940c964f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14043b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1496990>, '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 0x1402470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1402900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:56:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:56:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:56:54 INFO - Using env: {'DISPLAY': ':0', 06:56:54 INFO - 'HOME': '/home/cltbld', 06:56:54 INFO - 'LANG': 'en_US.UTF-8', 06:56:54 INFO - 'LANGUAGE': 'en_US:en', 06:56:54 INFO - 'LOGNAME': 'cltbld', 06:56:54 INFO - 'MAIL': '/var/mail/cltbld', 06:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:54 INFO - 'MOZ_NO_REMOTE': '1', 06:56:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:56:54 INFO - 'NO_EM_RESTART': '1', 06:56:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:56:54 INFO - 'PWD': '/builds/slave/test', 06:56:54 INFO - 'SHELL': '/bin/bash', 06:56:54 INFO - 'SHLVL': '1', 06:56:54 INFO - 'TERM': 'linux', 06:56:54 INFO - 'TMOUT': '86400', 06:56:54 INFO - 'USER': 'cltbld', 06:56:54 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 06:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:56:54 INFO - '_': '/tools/buildbot/bin/python'} 06:56:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:56:54 INFO - Cleaning up... 06:56:54 INFO - Return code: 0 06:56:54 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940c964f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14043b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1496990>, '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 0x1402470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1402900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:56:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 06:56:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 06:56:54 INFO - Using env: {'DISPLAY': ':0', 06:56:54 INFO - 'HOME': '/home/cltbld', 06:56:54 INFO - 'LANG': 'en_US.UTF-8', 06:56:54 INFO - 'LANGUAGE': 'en_US:en', 06:56:54 INFO - 'LOGNAME': 'cltbld', 06:56:54 INFO - 'MAIL': '/var/mail/cltbld', 06:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:54 INFO - 'MOZ_NO_REMOTE': '1', 06:56:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:56:54 INFO - 'NO_EM_RESTART': '1', 06:56:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:56:54 INFO - 'PWD': '/builds/slave/test', 06:56:54 INFO - 'SHELL': '/bin/bash', 06:56:54 INFO - 'SHLVL': '1', 06:56:54 INFO - 'TERM': 'linux', 06:56:54 INFO - 'TMOUT': '86400', 06:56:54 INFO - 'USER': 'cltbld', 06:56:54 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 06:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:56:54 INFO - '_': '/tools/buildbot/bin/python'} 06:56:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:55 INFO - Downloading/unpacking flake8 06:56:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:56:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:56:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:56:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:56:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:56:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:56:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:56:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:56:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:56:57 INFO - no previously-included directories found matching 'docs/_build' 06:56:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:56:57 INFO - Running setup.py install for pep8 06:56:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:56:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:56:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:56:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:56:58 INFO - no previously-included directories found matching 'docs/_build' 06:56:58 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:56:58 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:56:58 INFO - Cleaning up... 06:56:58 INFO - Return code: 0 06:56:58 INFO - Running post-action listener: _resource_record_post_action 06:56:58 INFO - Running post-action listener: _start_resource_monitoring 06:56:58 INFO - Starting resource monitoring. 06:56:58 INFO - ##### 06:56:58 INFO - ##### Finished create-virtualenv step (success) 06:56:58 INFO - ##### 06:56:58 INFO - ##### 06:56:58 INFO - ##### Running install step. 06:56:58 INFO - ##### 06:56:58 INFO - Running pre-action listener: _resource_record_pre_action 06:56:58 INFO - Running main action method: install 06:56:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:56:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:56:58 INFO - Reading from file tmpfile_stdout 06:56:58 INFO - Detecting whether we're running mozinstall >=1.0... 06:56:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:56:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:56:58 INFO - Reading from file tmpfile_stdout 06:56:58 INFO - Output received: 06:56:58 INFO - Usage: mozinstall [options] installer 06:56:58 INFO - Options: 06:56:58 INFO - -h, --help show this help message and exit 06:56:58 INFO - -d DEST, --destination=DEST 06:56:58 INFO - Directory to install application into. [default: 06:56:58 INFO - "/builds/slave/test"] 06:56:58 INFO - --app=APP Application being installed. [default: firefox] 06:56:58 INFO - mkdir: /builds/slave/test/build/application 06:56:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 06:56:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 06:57:14 INFO - Reading from file tmpfile_stdout 06:57:14 INFO - Output received: 06:57:14 INFO - /builds/slave/test/build/application/firefox/firefox 06:57:14 INFO - Running post-action listener: _resource_record_post_action 06:57:14 INFO - ##### 06:57:14 INFO - ##### Finished install step (success) 06:57:14 INFO - ##### 06:57:14 INFO - ##### 06:57:14 INFO - ##### Running run-tests step. 06:57:14 INFO - ##### 06:57:14 INFO - Running pre-action listener: _resource_record_pre_action 06:57:14 INFO - Running main action method: run_tests 06:57:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:57:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:57:14 INFO - Python 2.7.3 06:57:14 INFO - Return code: 0 06:57:14 INFO - grabbing minidump binary from tooltool 06:57:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:14 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1496990>, '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 0x1402470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1402900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:57:14 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:57:14 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 06:57:14 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:57:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:57:14 INFO - Return code: 0 06:57:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:57:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:57:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:57:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:57:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:57:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:57:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:57:15 INFO - Return code: 0 06:57:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 06:57:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 06:57:15 INFO - Using env: {'DISPLAY': ':0', 06:57:15 INFO - 'HOME': '/home/cltbld', 06:57:15 INFO - 'LANG': 'en_US.UTF-8', 06:57:15 INFO - 'LANGUAGE': 'en_US:en', 06:57:15 INFO - 'LOGNAME': 'cltbld', 06:57:15 INFO - 'MAIL': '/var/mail/cltbld', 06:57:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:57:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:57:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:15 INFO - 'MOZ_NO_REMOTE': '1', 06:57:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:57:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:57:15 INFO - 'NO_EM_RESTART': '1', 06:57:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:57:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:15 INFO - 'PWD': '/builds/slave/test', 06:57:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:57:15 INFO - 'SHELL': '/bin/bash', 06:57:15 INFO - 'SHLVL': '1', 06:57:15 INFO - 'TERM': 'linux', 06:57:15 INFO - 'TMOUT': '86400', 06:57:15 INFO - 'USER': 'cltbld', 06:57:15 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126', 06:57:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:15 INFO - '_': '/tools/buildbot/bin/python'} 06:57:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 06:57:15 INFO - mozversion application_buildid: 20160714033953 06:57:15 INFO - mozversion application_changeset: 3ad93af112afba86d68faf787d984e10bb4d9971 06:57:15 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:57:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:57:15 INFO - mozversion application_name: Firefox 06:57:15 INFO - mozversion application_remotingname: firefox-dev 06:57:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:57:15 INFO - mozversion application_vendor: Mozilla 06:57:15 INFO - mozversion application_version: 49.0a2 06:57:15 INFO - mozversion platform_buildid: 20160714033953 06:57:15 INFO - mozversion platform_changeset: 3ad93af112afba86d68faf787d984e10bb4d9971 06:57:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:57:15 INFO - mozversion platform_version: 49.0a2 06:57:15 INFO - using testdate: 1468504635 06:57:15 INFO - actual date: 1468504635 06:57:15 INFO - starting webserver on 'localhost:57486' 06:57:15 INFO - SUITE-START | Running 2 tests 06:57:15 INFO - TEST-START | dromaeo_css 06:57:15 INFO - operating with platform_type : linux_ 06:57:15 INFO - Initialising browser for dromaeo_css test... 06:57:15 INFO - TEST-INFO | started process 11278 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpS4yho0/profile http://localhost:57486/getInfo.html) 06:57:19 INFO - PROCESS | 11278 | __metrics Screen width/height:1600/1200 06:57:19 INFO - PROCESS | 11278 | colorDepth:24 06:57:19 INFO - PROCESS | 11278 | Browser inner width/height: 1024/697 06:57:19 INFO - PROCESS | 11278 | __metrics 06:57:20 ERROR - PROCESS | 11278 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:57:21 INFO - TEST-INFO | 11278: exit 0 06:57:21 INFO - Browser initialized. 06:57:21 INFO - Running cycle 1/1 for dromaeo_css test... 06:57:21 INFO - TEST-INFO | started process 11374 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpS4yho0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:57:33 INFO - PROCESS | 11374 | RSS: Main: 149499904 06:57:33 INFO - PROCESS | 11374 | 06:58:24 INFO - PROCESS | 11374 | 1468504704306 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:00:32 INFO - PROCESS | 11374 | Cycle 1(1): loaded http://localhost:57486/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57486/tests/dromaeo/cssquery-ext.html) 07:00:33 INFO - PROCESS | 11374 | RSS: Main: 254377984 07:00:33 INFO - PROCESS | 11374 | 07:03:35 INFO - PROCESS | 11374 | Cycle 1(1): loaded http://localhost:57486/tests/dromaeo/cssquery-ext.html (next: http://localhost:57486/tests/dromaeo/cssquery-jquery.html) 07:03:36 INFO - PROCESS | 11374 | RSS: Main: 230625280 07:03:36 INFO - PROCESS | 11374 | 07:06:38 INFO - PROCESS | 11374 | Cycle 1(1): loaded http://localhost:57486/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57486/tests/dromaeo/cssquery-mootools.html) 07:06:38 INFO - PROCESS | 11374 | RSS: Main: 225148928 07:06:38 INFO - PROCESS | 11374 | 07:09:40 INFO - PROCESS | 11374 | Cycle 1(1): loaded http://localhost:57486/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57486/tests/dromaeo/cssquery-prototype.html) 07:09:41 INFO - PROCESS | 11374 | RSS: Main: 221478912 07:09:41 INFO - PROCESS | 11374 | 07:12:43 INFO - PROCESS | 11374 | Cycle 1(1): loaded http://localhost:57486/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57486/tests/dromaeo/cssquery-yui.html) 07:12:44 INFO - PROCESS | 11374 | RSS: Main: 213372928 07:12:44 INFO - PROCESS | 11374 | 07:15:47 INFO - PROCESS | 11374 | Cycle 1(1): loaded http://localhost:57486/tests/dromaeo/cssquery-yui.html (next: http://localhost:57486/tests/dromaeo/cssquery-dojo.html) 07:15:47 INFO - PROCESS | 11374 | RSS: Main: 259190784 07:15:47 INFO - PROCESS | 11374 | 07:15:47 INFO - PROCESS | 11374 | __start_tp_report 07:15:47 INFO - PROCESS | 11374 | _x_x_mozilla_page_load 07:15:47 INFO - PROCESS | 11374 | _x_x_mozilla_page_load_details 07:15:47 INFO - PROCESS | 11374 | |i|pagename|runs| 07:15:47 INFO - PROCESS | 11374 | |0;dojo.html;3264.918377040574;3274.4106060909025;3276.9508457373136;3320.767546271762;3344.4147274227003;5325;5429.59278054146;5442.809501667442;5446.509814116729;5455.5908306876945;5405.810796622119;5470.589705772063;5471.343438787341;5481.397046324911;5492.917606235909;4850.175470170079;4931.901361972758;4969.2049272116465;4996.425411077718;5076.162433198536;4827.8551643450755;4968.40379154502;5000.649954503187;5008.3238762767105;5009.874753131165;4927.827526036589;4962.330085438456;4969;4976.651634385595;5011.398632164145;5040.571551418134;5049.823256186034;5054.469280725544;5059.038782631288;5070.264811602316;4781.306710526989;4870.829520966749;4907.239377896396;4965.031818795336;5000.149974504343;5242.056429842627;5247.134222853225;5257.0274499217385;5257.080010998098;5279.5512381447625;5758.88482230353;5761.971190144022;5783.5951483396175;5796.88406231873;5802.564807639402;5745.368009518949;5770.682612456313;5779.479846813807;5801.883962320772;5813.563982701315;5447.645903016291;5465.562754979592;5467.589930755209;5497.45025497451;5500.119980803092;5776.913346299809;5922.911156332659;5942.257217847769;5959.851003724942;5962.940370596239;5640.520555752725;5661.377248502661;5666.490015898589;5710.628809127394;5724.198612194296;5784.768609255667;5794.217780599565;5802.564807639444;5819;5819.912701309484;5600.775968961278;5646.20953066571;5764.596478246483;5764.942350576524;5777.595568310178;5670.291213598301;5750.856228594319;5763.049096899049;5769.971150144306;5786.07422812348;4464.866054018385;4466;4466.441694788152;4479.46246450428;4488.281874900001;3953.920921581552;3963.504561929759;3978.3634618460906;4007.6593489553134;4031.879043628696;5487.176923461513;5506.889862202814;5519.116941289455;5520.309961254843;5543.113101903676;5824.417558244227;5833.7374818133285;5836.737346819318;5839.970800146057;5861.267341582302;5446.2375267462585;5546.084895992116;5546.889062218733;5574.554035677136;5593.216949627055;5060.443351231362;5063.924041139386;5064.848054558369;5072.797088116434;5073.670211436245;6058.242719660043;6111.786087487006;6125.4487096160665;6136.846578835565;6139.846503837351;5709.172170035238;5720.656760594377;5765.5675824314185;5772.6536407815665;5838.386969368155;6039.456448919618;6041;6042.969785151222;6044.637321760709;6053.243344581927;5582.609217354746;5679.829605111853;5687.3459552152035;5688.345840228261;5705.4579814917515;5716.685582292847;5723.084306510938;5731.541476681939;5796.449337312949;5805.709714514342;5600.103983362643;5630.23991761107;5644.887102258014;5652.604317697722;5657.462541058593;5535.806246781343;5569.359523654832;5655.632383895034;5659.377468478382;5667.829965101054;5530.55755539564;5585.2180694701965;5599.216109744558;5605.719714014202;5622.88754224905;5701.657900525982;5712.057510510719;5753.827385178452;5756.68338241384;5766;5491.643043202179;5528.170774383714;5555.3333599966645;5598.964191624563;5627.718614069362 07:15:47 INFO - PROCESS | 11374 | |1;ext.html;604.0697326117779;650.0606623429147;650.5771248688352;664.1067763857611;674.5817593092283;33823.49264761028;47398.2890256646;48015.51984480154;48072;48159.51840481594;48990.755046224716;49370;49395;49409;49616.2557561636;8426.325893928471;9052.321075919315;9209.493477858716;9211;10790.190735694816;10591.046805787477;10949.343039417661;10950.780984380308;10951.178661600372;10962.287451315678;10895.673129806119;10930.398828064453;10955.397453140115;11021.504032318524;11027.503762330714;8365.539895305756;8478.915210847936;8545.2309292164;10749.892501075046;10782.676519704382;10525.21060920434;10631.84052239217;10897.727556811064;10903.400312982783;10917.635295588052;45320.3201951971;45897;45961;45982;46377;3632.636736326346;3656.707463402948;3672.3573374659595;3726.9440958385644;3798.734088613798;35284.117897052776;35297.470537941954;35752.64247357519;35803.28393432143;36011;4540.841070562515;4668.629752690982;4857.902841943177;4865.148598995197;4959.181735013718;293560;371167;373854;374927.12536437414;379264;71713.28286717067;72281;72328;72353.27646723465;72370;41502.754917352526;42437;42517.57482425198;43999.78000109979;44024.779876100416;20027.49931251701;20047.097880595407;20222.191112355635;20234.988250587412;20250.594988100154;14117.929410352883;15298.694026119638;15573.377064917277;15579.688406232039;15981.680366392608;23062.192823251105;23323;23333.41666458352;23362.883185584302;23368.182113625942;8128.512289262663;8257.710980115668;8337.624806883705;8437.409381343246;8511.489310641382;1092.1972350322508;1140.5779861451317;1168.199783148529;1174.9542906812944;1208.3897631695938;34368.65631343654;35668.904429370865;35972;36034.81982590123;36059.27881442357;1223.7797196504714;1267.6894160930447;1284.107545256038;1294.7734146524322;1311.2263764379063;18455.723164152547;18639.720404193948;18835.152418141217;19111.52221194481;19140.90429547871;643039;654185;658705;659496;660610;63725.362746371946;64769.67615161894;64951.67524162349;65485.345146548876;65547.67226163839;1360.8298962629672;1465.7874608181753;1482.9851701482846;1490.7465730825786;1506.5254444849836;35193.64806351904;36002.63997360098;36889.44665830069;36907;37287.81356093202;55448.44551554433;55660.443395565526;55877;56000.439995599525;56250.718746406004;413355;414982;415291;416627;417255;28090.2977425566;31185.688143118277;31721.682783171873;31728.524072138476;33308.50037249492;52377.7381113092;53326.73336633292;53346.466535335705;53780.46219537754;54451.72774136262;44234.55765442433;44833;45105.774471127435;45725;46117.07765844731;44092;44278.11443771171;44340.55659443494;44747.32879006882;45650.77174614106;44346.55653443424;44686.3297050551;45091.32363014623;45279.54720452753;45398.54601453943;38032.42951355787;38378;39413.408798867466;39557;39943.60056399515;29386.853065734533;29951.400971980875;30160.5475917857;30338;30473 07:15:47 INFO - PROCESS | 11374 | |2;jquery.html;426.4456206930991;434.3289617540899;439.0365036006953;442.55081092690915;444.2159588326602;18724.15741291642;19639.214431422726;20643.793562064377;20778.68831967518;20827.791722082773;19456;20342.89828550859;20431.897840510814;20578.588428231425;20614.484637884023;5666.773329066832;5893.20441740366;5905.704714764266;5906.232189815318;5914.615549989258;5794.188813566104;5821.09772985188;5836.562257830658;5860.97069514654;5889.617174883619;5798.797042103527;5810.3027636683655;5817.447342502476;5818.505427038707;5835.503982161521;5657.830265092033;5750.856228594277;5813.09896965971;5813.476787089161;5817.621854579481;5021.799128034874;5156.278121063054;5170.095233334151;5172.689638621657;5184.222366644995;23865.40336491584;23937.40156496084;24489.142879999294;24599.508009839705;24611.63082553763;2479.1075212923333;2501.6497690323367;2806.494830930432;2837.4608824323313;2920.2407374082677;14263.78604320936;14313.499027533986;14481.710365792731;15335.156566388845;15611.687766244611;2040.897955102239;2065.4320061983076;2094.706741056253;2196.198387588535;2216.7229096362953;60608.69695651538;83324.58337708273;84551.5772421134;85303.57348213281;86165.99999999937;11097.278676885977;11623.535058597561;11946.581869634694;11976.101792365607;12072.5566320519;16374.83625163757;16732.916335418246;16790.412335568195;16813.327466901188;16816.159192040348;8359;8415;8569.143085691381;8578.485290882567;8779.385443019055;8761.167689069529;8820.11798820113;8941.821163576822;9042.095790420904;9069.909300907038;4798.256270278112;4980.427250866124;4984.152694042022;5134.948650513447;5159.896802063938;2030.5329774152028;2048.702938074001;2060.6908963655574;2077.4390914453015;2109.7468303803644;813.6745301879296;1017.531935309751;1049.984250236232;1066.1630619963291;1127.2954403497813;19265.51836204078;19723.211071557263;19856.10647520865;19863.60272794536;20077.799222007594;1051.085555566662;1079.0666073846223;1081.4808891732014;1087.3095584304024;1123.1071298317856;10829.133669306595;11355.54577816878;11414.315141091562;11415.828762568402;11453.370064646524;425462;425948;426297;535942;555769.105772359;62596.687016564625;78308;90939.54530227307;102118.4894075525;103108;1082.870055593318;1117.5920788912151;1120.0815331428314;1129.4409267412684;1131.106425923511;17939.551511212325;18150.5462363442;18210.817891820912;18297.26810927548;18338.541536461697;43963.14239574417;44107.55892441163;44400.33399498946;44795.77602112099;44994.77502612466;60930.69534652298;61636.38363616486;61711;62175.06737399033;62192.37807622047;7679.155292917663;8536.829263414822;8545.74362769118;8731.432456890281;8922.330825188006;10719.624813131502;13893.444262229801;14072.718545629235;14327.212003339917;14562.635934101732;12505.93747031259;12511.812322815347;12554.623361299176;12713.618591442271;12771.425285862344;12432.564860229848;12604.11771176009;12705.682357941125;12831.935840320739;12884.162529435556;7967.800804979922;9423.811523769624;12768.233905965672;12811.167274127152;12850.742985140057;8177.918220817715;8292.33661307106;8526.78683032929;8586.098459661895;8636.26591739684;11006.284591501526;12340.938295308466;12388.876111239133;12476.376181190722;12520.123591348518 07:15:47 INFO - PROCESS | 11374 | |3;mootools.html;1397.797319388688;1574.4804214609176;1626.1706529669868;1841.3463220556703;1965.1451618545934;8848.646054157834;9267.721968340953;9275.118863707945;9448.149666530011;9460.81078378432;9286.535673216345;9412.670556530522;9414.152726254635;9425.57584908679;9435.57539910704;2088.550961543268;2414.674019007438;2478.5910324796387;2481.7146028206807;2507.0974449198275;2481.131603938616;2497.862617556034;2498.800095992325;2508.7240403555593;2512.8869200886006;2478.962815557768;2502.749725027501;2508.3102146909655;2508.8871000804916;2509.5859183234834;2108.8523803333774;2420.455397535558;2493.9750602493923;2494.4262819551604;2495.6755621769234;2389.595427390966;2398.424378149249;2409.831311808174;2429.769171928664;2432.0028788196933;9451.480168590724;9460.810783784285;9506.334556581041;9510.334276600572;9542.570584323723;5428.61999660024;5555.861103472405;5563.36021357541;5573.888522229533;5574.498295153456;3584.229390681016;3609.2781443711365;3675.7978311192874;3760.774353538769;3778.4899038629983;1267.9809802852965;1301.2777908260912;1311.3639884655934;1319.9076064675476;1349.6828245362333;13653.044286899922;13672.863271367258;13683.726325473534;13750.449982000708;13774.93112534441;4617;4622.722636641813;4700.649675162419;4712.586224132747;4740.3837501124635;11322.150838687132;11330.773384532264;11339.829902551468;11352.772944541062;11432.199746017699;4882.170031094723;5357.303550538405;5358.490943360374;5359.651622644516;5368.302120724281;4942.085714142895;5049.6212784041345;5075.213341932006;5117.41149767774;5124.077666020077;3955.5904892906156;4005.839766409369;4032.6370626643743;4042.211768705133;4043.110515686544;1358.6331690443526;1386.5008596905107;1390.3812803302433;1400.4468235047302;1400.6428360767939;1046.3198920701452;1073.6510634043811;1079.6653037558383;1080.5029686344212;1089.9727506812235;6536.934630653727;6704.430123439464;6809.863802723918;6825.249222585511;6825.69284382194;461.9030003699261;466.4262956563378;466.55210997442833;482.45482604656513;498.9376327959005;4538.682292239578;4672.229082201467;4729.527047295243;4733.408323959505;4799.160146974265;11300.773984520263;11553.13351498624;11860.76278474443;11871.347075910908;11900.226485278428;11013;11433.714157146138;11475.368854712904;11776.175667703179;11804.232724872856;8674.913250867537;8796.956015219883;8809.471431714117;8861.468311901306;8881.822363552692;6227.501799856092;6561.212654481493;6618.470522358296;6622.933770662232;6658.400743933069;10409.68770936873;10426.530806113895;10449;10465.319754215952;10776.461176941279;10767.730806729895;11043;11052.613158539734;11075.72310692239;11192.21654484211;8337.333013358795;8880;9125.726228213165;9244.306676999118;9379.765505862408;9523.904760952302;9535.523223838642;9552.761180970532;9606.903930960601;9614.086661767122;8447.239748422671;8596.183362580545;8611.784705382415;8691.695790647296;8729.127087291221;8127.2279133482225;8144.226298501633;8403.41176117666;8409.747707568782;8509.531975741394;7549.207333230152;7624.313811756969;7639.388848891964;7642.312191902755;7643.4267429941865;5239.816606418757;5355.25026496283;5426.945730542644;5434.021876062347;5601.131824567035;5523.364813046551;5585.69278689676;5771.018926782541;5866.0027795275755;5912.290525137011 07:15:47 INFO - PROCESS | 11374 | |4;prototype.html;348.79246848125365;353.08726940857883;353.5086230140106;354.10411658503995;355.6585677749362;5359.517643412092;5410.404855465896;5479.986202552531;5483.7532311046;5512.980098681736;5399.244105825188;5400.405955344909;5411;5417.702026388547;5448.400675925645;3918.2163567286516;4070.3690927906223;4085.2442298174783;4173.833046678129;4289.713086074181;4206.137741762931;4208.957910420902;4246.40550322955;4254.957535403831;4281.400603915439;4211.115665710205;4229.555896630855;4233.470816147981;4235.470566179228;4266.317389217726;4077.694172937027;4179.561146079648;4230.70385073042;4249.978750106261;4259.1268789897995;3780.2250538639514;3799.2781371539595;3801.9924719949236;3808.2383523295175;3818.9618103818884;5166.328377310963;5192.506711862405;5227.268182454422;5233.528982391603;5243.292155559027;4879.56083952446;4912.992836468516;4916.237983112624;4925.876853078666;4929.433115191763;5423.403425623178;5580.86047848803;5683.46546432465;5693.146028095777;5696.2594862668;4028.6575641070544;4117.814698338552;4129.483814523184;4142.233686767958;4209.621134097946;5732.426757324276;5817.38917413674;5865.912011319833;5873.236479257712;5879.882402351929;4165.541790403053;4215.241256573846;4226.85206017794;4237.809298581571;4275.5724427557;4322.69741118125;4329.761863097497;4330.783460826946;4340.696151269381;4348.5216626171095;4861.805527778849;4875.609951203895;4875.707937396593;4881.877953051131;4890.1931181355385;5415.783368665288;5642.843217140477;5672.12082127271;5691.544676425876;5705;1475.6163397516723;1476.904001239916;1488.848613753138;1488.9404423823141;1493.7759336099673;958.7075941837667;977.3891317926297;986.5264672957021;989.851522271665;994.1947022911465;4890.090396214318;4900.191468407673;4921.606271498272;4950.430700469492;4994.9750251248515;5093.770780314895;5127.9230811537855;5135.15269980449;5156.530388839163;5188;4570.382998295254;4920.261960705922;4961.801527938915;4976.975115124401;5036.84889453317;2765.9446811063667;2780.4161126163326;2786.4148528808773;2813.3669924267288;2821.4357128573956;5369.892602147936;5394.8921021579345;5409.702466364388;5455.972720136374;5456.6725996439545;5206.609504287194;5325.920111198335;5500.944990550123;5991.760329586855;5995.100734889801;5805.738741756547;6101.60339577926;6111.57218994675;6137.649717062239;6148.200733904564;5755.3381361144;5851.590388672752;5870.354261031198;5876.471117599265;5880.294364676266;5789.536837052941;5793.768249270151;5797.623154494944;5800;5801.708325211603;5789.3921138279875;6004.249468816398;6034.668093254738;6051.152838602511;6092.177556029879;4228.471441069866;4256.404103425461;4258.190943720683;4262.445882035439;4264.296391095435;5845.3570107289015;5861;5885.234919460443;5914.2311499504785;5924.992751232215;5378.865528361822;5686.402927692533;5745.74144163522;5749.482546570829;5763.164341170591;5708.345619286885;5710.5146062585145;5744.281964754406;5748.45389687979;5750.25246717924;5350.759215835219;5576.888462230651;5580.190872323572;5583.022970980143;5627.5497960162265;5655.547556195576;5679.432056794288;5681.517071049007;5719.8570035749435;5745;5581.214011516276;5614.185943038155;5619.185218143428;5645.887082258249;5653.660780353192 07:15:47 INFO - PROCESS | 11374 | |5;yui.html;758.302361827119;770.645503068588;834.7036801935309;898.2499612823293;914;15514.767278490817;15556.688866222668;15618.141002244858;15632;16007.199640017996;16084.276207570658;16091.678166436664;16135.83864161358;16170.75743863843;16197.271122799473;864.6757465950284;889.9466032038066;890.2699786175339;892.8794612727296;894.2801045158644;884.9026607073217;893.1381217125472;894.3962825093068;894.5080205886736;894.6332003878412;934.2245935873243;938.061938061938;939.0609390609391;939.178219058324;939.2345238630494;940.9952950235273;941.9199368053723;942.0814705661993;942.6323733743845;942.6983365323099;690.520088538466;691.2223748283182;691.2568988337557;691.4330249195662;691.8270432391902;16313.755293670605;16532.917335413367;16534.173291335384;16559.503214903572;16566.83433165665;14405.423783048665;14718.63203419923;14721.631959200999;14805.481808136663;14965.551033469013;14157.504487342894;14382.568522944328;14436.566902992927;14465.421383144661;14476.782848257284;4758.262469317261;4813.687110337852;4814.205656066745;4839.782209800533;4941.209406494943;50816.74591627055;75370;78679.60660196662;78687.60656196739;79498;12742.36288185587;12777.23336599807;12840.678983025498;12918.547850825176;13015.739685206243;22537.098516059174;22563.548729025326;22589.661155082686;22606.0957561699;22606.886965565067;9217.907820921839;9276.582553785096;9363.531823408803;9500.904990950003;9692.22462203022;5250.7374631268285;5260.605454590921;5306.681599104066;5378.139497680352;5440.483154100354;3892.591277915778;3966.0679740261016;4001.8399264029695;4002.779847108379;4007.0984028593916;639.7760783725752;644.3781750610683;651.1860174781523;651.843557546195;654.9312322206194;745.6085555083624;798.1380109481746;826.9462484938459;846.8348672008899;852.1776485691338;10695.090917272119;10745.737259016374;10761.354318740743;10906.127509799355;10989.505472253768;798.3453568074124;808.9421984897099;814.0800894988625;818.7830224990382;835.5195762436501;4828.251620998751;4845.127876982177;4890.486498917634;4932.90134197314;5032.723200224023;73523.26476735165;100304.49847750715;113141.86858131477;119523.40238298752;123281;97307.51346243365;99265;100748.49625751971;102005.48997255115;102950.48524757328;91721.54139229395;92020;92997.5350123245;93601;94364.52817735868;9591.280653950842;9740.269479788902;9845.113939745457;9958.900410996088;10005.899941000787;53973;54226;54582.45417545774;55644.44355556393;55845.16232256601;74928.62535687287;79456.60271698605;79762.60118699369;80291;80786.59606701929;13047.347632618521;13052.673683157996;13204.71022583477;13321.33393330349;13655.522056727967;14427.567172984827;15005.699886002438;15121.546353609408;15173;16045.8395416049;14407.78388324197;15274.77087843661;15788.68422631564;16548.669026619642;16621.66756664884;15088.698226035638;15387.61530961735;15989.440369587008;16005.599860003593;16402.09788461646;14310.85689143137;14350.928245358706;14586.562403127924;15241.390344386544;16276.43032493857;12113.515459381526;12242.510299587917;12307.015438765056;12411.813822792847;12769.61691149267;12563.87436125627;12582.937085314516;12737.93631031839;12747.681307967005;12964.481420743441 07:15:47 INFO - PROCESS | 11374 | __end_tp_report 07:15:47 INFO - PROCESS | 11374 | __start_cc_report 07:15:47 INFO - PROCESS | 11374 | _x_x_mozilla_cycle_collect,922 07:15:47 INFO - PROCESS | 11374 | __end_cc_report 07:15:47 INFO - PROCESS | 11374 | __startTimestamp1468505747358__endTimestamp 07:15:47 INFO - PROCESS | 11374 | 07:15:47 INFO - PROCESS | 11374 | ------- Summary: start ------- 07:15:47 INFO - PROCESS | 11374 | Number of tests: 6 07:15:47 INFO - PROCESS | 11374 | 07:15:47 INFO - PROCESS | 11374 | [#0] dojo.html Cycles:165 Average:5396.90 Median:5593.22 stddev:583.81 (10.4%) stddev-sans-first:561.13 07:15:47 INFO - PROCESS | 11374 | Values: 3264.9 3274.4 3277.0 3320.8 3344.4 5325.0 5429.6 5442.8 5446.5 5455.6 5405.8 5470.6 5471.3 5481.4 5492.9 4850.2 4931.9 4969.2 4996.4 5076.2 4827.9 4968.4 5000.6 5008.3 5009.9 4927.8 4962.3 4969.0 4976.7 5011.4 5040.6 5049.8 5054.5 5059.0 5070.3 4781.3 4870.8 4907.2 4965.0 5000.1 5242.1 5247.1 5257.0 5257.1 5279.6 5758.9 5762.0 5783.6 5796.9 5802.6 5745.4 5770.7 5779.5 5801.9 5813.6 5447.6 5465.6 5467.6 5497.5 5500.1 5776.9 5922.9 5942.3 5959.9 5962.9 5640.5 5661.4 5666.5 5710.6 5724.2 5784.8 5794.2 5802.6 5819.0 5819.9 5600.8 5646.2 5764.6 5764.9 5777.6 5670.3 5750.9 5763.0 5770.0 5786.1 4464.9 4466.0 4466.4 4479.5 4488.3 3953.9 3963.5 3978.4 4007.7 4031.9 5487.2 5506.9 5519.1 5520.3 5543.1 5824.4 5833.7 5836.7 5840.0 5861.3 5446.2 5546.1 5546.9 5574.6 5593.2 5060.4 5063.9 5064.8 5072.8 5073.7 6058.2 6111.8 6125.4 6136.8 6139.8 5709.2 5720.7 5765.6 5772.7 5838.4 6039.5 6041.0 6043.0 6044.6 6053.2 5582.6 5679.8 5687.3 5688.3 5705.5 5716.7 5723.1 5731.5 5796.4 5805.7 5600.1 5630.2 5644.9 5652.6 5657.5 5535.8 5569.4 5655.6 5659.4 5667.8 5530.6 5585.2 5599.2 5605.7 5622.9 5701.7 5712.1 5753.8 5756.7 5766.0 5491.6 5528.2 5555.3 5599.0 5627.7 07:15:47 INFO - PROCESS | 11374 | 07:15:47 INFO - PROCESS | 11374 | [#1] ext.html Cycles:180 Average:65334.54 Median:38895.70 stddev:131313.08 (337.6%) stddev-sans-first:131591.51 07:15:47 INFO - PROCESS | 11374 | Values: 604.1 650.1 650.6 664.1 674.6 33823.5 47398.3 48015.5 48072.0 48159.5 48990.8 49370.0 49395.0 49409.0 49616.3 8426.3 9052.3 9209.5 9211.0 10790.2 10591.0 10949.3 10950.8 10951.2 10962.3 10895.7 10930.4 10955.4 11021.5 11027.5 8365.5 8478.9 8545.2 10749.9 10782.7 10525.2 10631.8 10897.7 10903.4 10917.6 45320.3 45897.0 45961.0 45982.0 46377.0 3632.6 3656.7 3672.4 3726.9 3798.7 35284.1 35297.5 35752.6 35803.3 36011.0 4540.8 4668.6 4857.9 4865.1 4959.2 293560.0 371167.0 373854.0 374927.1 379264.0 71713.3 72281.0 72328.0 72353.3 72370.0 41502.8 42437.0 42517.6 43999.8 44024.8 20027.5 20047.1 20222.2 20235.0 20250.6 14117.9 15298.7 15573.4 15579.7 15981.7 23062.2 23323.0 23333.4 23362.9 23368.2 8128.5 8257.7 8337.6 8437.4 8511.5 1092.2 1140.6 1168.2 1175.0 1208.4 34368.7 35668.9 35972.0 36034.8 36059.3 1223.8 1267.7 1284.1 1294.8 1311.2 18455.7 18639.7 18835.2 19111.5 19140.9 643039.0 654185.0 658705.0 659496.0 660610.0 63725.4 64769.7 64951.7 65485.3 65547.7 1360.8 1465.8 1483.0 1490.7 1506.5 35193.6 36002.6 36889.4 36907.0 37287.8 55448.4 55660.4 55877.0 56000.4 56250.7 413355.0 414982.0 415291.0 416627.0 417255.0 28090.3 31185.7 31721.7 31728.5 33308.5 52377.7 53326.7 53346.5 53780.5 54451.7 44234.6 44833.0 45105.8 45725.0 46117.1 44092.0 44278.1 44340.6 44747.3 45650.8 44346.6 44686.3 45091.3 45279.5 45398.5 38032.4 38378.0 39413.4 39557.0 39943.6 29386.9 29951.4 30160.5 30338.0 30473.0 07:15:47 INFO - PROCESS | 11374 | 07:15:47 INFO - PROCESS | 11374 | [#2] jquery.html Cycles:180 Average:29032.69 Median:24213.27 stddev:78755.72 (325.3%) stddev-sans-first:78947.36 07:15:47 INFO - PROCESS | 11374 | Values: 426.4 434.3 439.0 442.6 444.2 18724.2 19639.2 20643.8 20778.7 20827.8 19456.0 20342.9 20431.9 20578.6 20614.5 5666.8 5893.2 5905.7 5906.2 5914.6 5794.2 5821.1 5836.6 5861.0 5889.6 5798.8 5810.3 5817.4 5818.5 5835.5 5657.8 5750.9 5813.1 5813.5 5817.6 5021.8 5156.3 5170.1 5172.7 5184.2 23865.4 23937.4 24489.1 24599.5 24611.6 2479.1 2501.6 2806.5 2837.5 2920.2 14263.8 14313.5 14481.7 15335.2 15611.7 2040.9 2065.4 2094.7 2196.2 2216.7 60608.7 83324.6 84551.6 85303.6 86166.0 11097.3 11623.5 11946.6 11976.1 12072.6 16374.8 16732.9 16790.4 16813.3 16816.2 8359.0 8415.0 8569.1 8578.5 8779.4 8761.2 8820.1 8941.8 9042.1 9069.9 4798.3 4980.4 4984.2 5134.9 5159.9 2030.5 2048.7 2060.7 2077.4 2109.7 813.7 1017.5 1050.0 1066.2 1127.3 19265.5 19723.2 19856.1 19863.6 20077.8 1051.1 1079.1 1081.5 1087.3 1123.1 10829.1 11355.5 11414.3 11415.8 11453.4 425462.0 425948.0 426297.0 535942.0 555769.1 62596.7 78308.0 90939.5 102118.5 103108.0 1082.9 1117.6 1120.1 1129.4 1131.1 17939.6 18150.5 18210.8 18297.3 18338.5 43963.1 44107.6 44400.3 44795.8 44994.8 60930.7 61636.4 61711.0 62175.1 62192.4 7679.2 8536.8 8545.7 8731.4 8922.3 10719.6 13893.4 14072.7 14327.2 14562.6 12505.9 12511.8 12554.6 12713.6 12771.4 12432.6 12604.1 12705.7 12831.9 12884.2 7967.8 9423.8 12768.2 12811.2 12850.7 8177.9 8292.3 8526.8 8586.1 8636.3 11006.3 12340.9 12388.9 12476.4 12520.1 07:15:47 INFO - PROCESS | 11374 | 07:15:47 INFO - PROCESS | 11374 | [#3] mootools.html Cycles:180 Average:6259.89 Median:2569.39 stddev:3619.39 (140.9%) stddev-sans-first:3611.10 07:15:47 INFO - PROCESS | 11374 | Values: 1397.8 1574.5 1626.2 1841.3 1965.1 8848.6 9267.7 9275.1 9448.1 9460.8 9286.5 9412.7 9414.2 9425.6 9435.6 2088.6 2414.7 2478.6 2481.7 2507.1 2481.1 2497.9 2498.8 2508.7 2512.9 2479.0 2502.7 2508.3 2508.9 2509.6 2108.9 2420.5 2494.0 2494.4 2495.7 2389.6 2398.4 2409.8 2429.8 2432.0 9451.5 9460.8 9506.3 9510.3 9542.6 5428.6 5555.9 5563.4 5573.9 5574.5 3584.2 3609.3 3675.8 3760.8 3778.5 1268.0 1301.3 1311.4 1319.9 1349.7 13653.0 13672.9 13683.7 13750.4 13774.9 4617.0 4622.7 4700.6 4712.6 4740.4 11322.2 11330.8 11339.8 11352.8 11432.2 4882.2 5357.3 5358.5 5359.7 5368.3 4942.1 5049.6 5075.2 5117.4 5124.1 3955.6 4005.8 4032.6 4042.2 4043.1 1358.6 1386.5 1390.4 1400.4 1400.6 1046.3 1073.7 1079.7 1080.5 1090.0 6536.9 6704.4 6809.9 6825.2 6825.7 461.9 466.4 466.6 482.5 498.9 4538.7 4672.2 4729.5 4733.4 4799.2 11300.8 11553.1 11860.8 11871.3 11900.2 11013.0 11433.7 11475.4 11776.2 11804.2 8674.9 8797.0 8809.5 8861.5 8881.8 6227.5 6561.2 6618.5 6622.9 6658.4 10409.7 10426.5 10449.0 10465.3 10776.5 10767.7 11043.0 11052.6 11075.7 11192.2 8337.3 8880.0 9125.7 9244.3 9379.8 9523.9 9535.5 9552.8 9606.9 9614.1 8447.2 8596.2 8611.8 8691.7 8729.1 8127.2 8144.2 8403.4 8409.7 8509.5 7549.2 7624.3 7639.4 7642.3 7643.4 5239.8 5355.3 5426.9 5434.0 5601.1 5523.4 5585.7 5771.0 5866.0 5912.3 07:15:47 INFO - PROCESS | 11374 | 07:15:47 INFO - PROCESS | 11374 | [#4] prototype.html Cycles:180 Average:4719.65 Median:5284.61 stddev:1374.99 (26.0%) stddev-sans-first:1339.14 07:15:47 INFO - PROCESS | 11374 | Values: 348.8 353.1 353.5 354.1 355.7 5359.5 5410.4 5480.0 5483.8 5513.0 5399.2 5400.4 5411.0 5417.7 5448.4 3918.2 4070.4 4085.2 4173.8 4289.7 4206.1 4209.0 4246.4 4255.0 4281.4 4211.1 4229.6 4233.5 4235.5 4266.3 4077.7 4179.6 4230.7 4250.0 4259.1 3780.2 3799.3 3802.0 3808.2 3819.0 5166.3 5192.5 5227.3 5233.5 5243.3 4879.6 4913.0 4916.2 4925.9 4929.4 5423.4 5580.9 5683.5 5693.1 5696.3 4028.7 4117.8 4129.5 4142.2 4209.6 5732.4 5817.4 5865.9 5873.2 5879.9 4165.5 4215.2 4226.9 4237.8 4275.6 4322.7 4329.8 4330.8 4340.7 4348.5 4861.8 4875.6 4875.7 4881.9 4890.2 5415.8 5642.8 5672.1 5691.5 5705.0 1475.6 1476.9 1488.8 1488.9 1493.8 958.7 977.4 986.5 989.9 994.2 4890.1 4900.2 4921.6 4950.4 4995.0 5093.8 5127.9 5135.2 5156.5 5188.0 4570.4 4920.3 4961.8 4977.0 5036.8 2765.9 2780.4 2786.4 2813.4 2821.4 5369.9 5394.9 5409.7 5456.0 5456.7 5206.6 5325.9 5500.9 5991.8 5995.1 5805.7 6101.6 6111.6 6137.6 6148.2 5755.3 5851.6 5870.4 5876.5 5880.3 5789.5 5793.8 5797.6 5800.0 5801.7 5789.4 6004.2 6034.7 6051.2 6092.2 4228.5 4256.4 4258.2 4262.4 4264.3 5845.4 5861.0 5885.2 5914.2 5925.0 5378.9 5686.4 5745.7 5749.5 5763.2 5708.3 5710.5 5744.3 5748.5 5750.3 5350.8 5576.9 5580.2 5583.0 5627.5 5655.5 5679.4 5681.5 5719.9 5745.0 5581.2 5614.2 5619.2 5645.9 5653.7 07:15:47 INFO - PROCESS | 11374 | 07:15:47 INFO - PROCESS | 11374 | [#5] yui.html Cycles:180 Average:21534.10 Median:4821.23 stddev:29881.07 (619.8%) stddev-sans-first:29924.17 07:15:47 INFO - PROCESS | 11374 | Values: 758.3 770.6 834.7 898.2 914.0 15514.8 15556.7 15618.1 15632.0 16007.2 16084.3 16091.7 16135.8 16170.8 16197.3 864.7 889.9 890.3 892.9 894.3 884.9 893.1 894.4 894.5 894.6 934.2 938.1 939.1 939.2 939.2 941.0 941.9 942.1 942.6 942.7 690.5 691.2 691.3 691.4 691.8 16313.8 16532.9 16534.2 16559.5 16566.8 14405.4 14718.6 14721.6 14805.5 14965.6 14157.5 14382.6 14436.6 14465.4 14476.8 4758.3 4813.7 4814.2 4839.8 4941.2 50816.7 75370.0 78679.6 78687.6 79498.0 12742.4 12777.2 12840.7 12918.5 13015.7 22537.1 22563.5 22589.7 22606.1 22606.9 9217.9 9276.6 9363.5 9500.9 9692.2 5250.7 5260.6 5306.7 5378.1 5440.5 3892.6 3966.1 4001.8 4002.8 4007.1 639.8 644.4 651.2 651.8 654.9 745.6 798.1 826.9 846.8 852.2 10695.1 10745.7 10761.4 10906.1 10989.5 798.3 808.9 814.1 818.8 835.5 4828.3 4845.1 4890.5 4932.9 5032.7 73523.3 100304.5 113141.9 119523.4 123281.0 97307.5 99265.0 100748.5 102005.5 102950.5 91721.5 92020.0 92997.5 93601.0 94364.5 9591.3 9740.3 9845.1 9958.9 10005.9 53973.0 54226.0 54582.5 55644.4 55845.2 74928.6 79456.6 79762.6 80291.0 80786.6 13047.3 13052.7 13204.7 13321.3 13655.5 14427.6 15005.7 15121.5 15173.0 16045.8 14407.8 15274.8 15788.7 16548.7 16621.7 15088.7 15387.6 15989.4 16005.6 16402.1 14310.9 14350.9 14586.6 15241.4 16276.4 12113.5 12242.5 12307.0 12411.8 12769.6 12563.9 12582.9 12737.9 12747.7 12964.5 07:15:47 INFO - PROCESS | 11374 | -------- Summary: end -------- 07:15:47 INFO - PROCESS | 11374 | 07:15:48 INFO - TEST-INFO | 11374: exit 0 07:15:48 INFO - TEST-OK | dromaeo_css | took 1112459ms 07:15:48 INFO - TEST-START | kraken 07:15:48 INFO - operating with platform_type : linux_ 07:15:48 INFO - Initialising browser for kraken test... 07:15:48 INFO - TEST-INFO | started process 11563 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf_Ws3H/profile http://localhost:57486/getInfo.html) 07:15:51 INFO - PROCESS | 11563 | __metrics Screen width/height:1600/1200 07:15:51 INFO - PROCESS | 11563 | colorDepth:24 07:15:51 INFO - PROCESS | 11563 | Browser inner width/height: 1024/697 07:15:51 INFO - PROCESS | 11563 | __metrics 07:15:52 ERROR - PROCESS | 11563 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:15:53 INFO - TEST-INFO | 11563: exit 0 07:15:53 INFO - Browser initialized. 07:15:53 INFO - Running cycle 1/1 for kraken test... 07:15:53 INFO - TEST-INFO | started process 11652 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf_Ws3H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:16:05 INFO - PROCESS | 11652 | RSS: Main: 145108992 07:16:05 INFO - PROCESS | 11652 | 07:16:07 INFO - PROCESS | 11652 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57486/tests/kraken/driver.html?testName=audio-beat-detection) 07:16:08 INFO - PROCESS | 11652 | RSS: Main: 194605056 07:16:08 INFO - PROCESS | 11652 | 07:16:11 INFO - PROCESS | 11652 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57486/tests/kraken/driver.html?testName=audio-dft) 07:16:12 INFO - PROCESS | 11652 | RSS: Main: 192864256 07:16:12 INFO - PROCESS | 11652 | 07:16:14 INFO - PROCESS | 11652 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57486/tests/kraken/driver.html?testName=audio-fft) 07:16:15 INFO - PROCESS | 11652 | RSS: Main: 278208512 07:16:15 INFO - PROCESS | 11652 | 07:16:17 INFO - PROCESS | 11652 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57486/tests/kraken/driver.html?testName=audio-oscillator) 07:16:18 INFO - PROCESS | 11652 | RSS: Main: 187420672 07:16:18 INFO - PROCESS | 11652 | 07:16:20 INFO - PROCESS | 11652 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57486/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:16:20 INFO - PROCESS | 11652 | RSS: Main: 188030976 07:16:20 INFO - PROCESS | 11652 | 07:16:23 INFO - PROCESS | 11652 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57486/tests/kraken/driver.html?testName=imaging-darkroom) 07:16:24 INFO - PROCESS | 11652 | RSS: Main: 223059968 07:16:24 INFO - PROCESS | 11652 | 07:16:27 INFO - PROCESS | 11652 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57486/tests/kraken/driver.html?testName=imaging-desaturate) 07:16:28 INFO - PROCESS | 11652 | RSS: Main: 224100352 07:16:28 INFO - PROCESS | 11652 | 07:16:31 INFO - PROCESS | 11652 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57486/tests/kraken/driver.html?testName=json-parse-financial) 07:16:31 INFO - PROCESS | 11652 | RSS: Main: 223920128 07:16:31 INFO - PROCESS | 11652 | 07:16:33 INFO - PROCESS | 11652 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57486/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:16:33 INFO - PROCESS | 11652 | RSS: Main: 201498624 07:16:33 INFO - PROCESS | 11652 | 07:16:35 INFO - PROCESS | 11652 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57486/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:16:36 INFO - PROCESS | 11652 | RSS: Main: 203546624 07:16:36 INFO - PROCESS | 11652 | 07:16:38 INFO - PROCESS | 11652 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57486/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:16:38 INFO - PROCESS | 11652 | RSS: Main: 209215488 07:16:38 INFO - PROCESS | 11652 | 07:16:41 INFO - PROCESS | 11652 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57486/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:16:41 INFO - PROCESS | 11652 | RSS: Main: 214245376 07:16:41 INFO - PROCESS | 11652 | 07:16:44 INFO - PROCESS | 11652 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57486/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:16:45 INFO - PROCESS | 11652 | RSS: Main: 214945792 07:16:45 INFO - PROCESS | 11652 | 07:16:46 INFO - PROCESS | 11652 | Cycle 1(1): loaded http://localhost:57486/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57486/tests/kraken/driver.html?testName=ai-astar) 07:16:47 INFO - PROCESS | 11652 | RSS: Main: 256458752 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - PROCESS | 11652 | __start_tp_report 07:16:47 INFO - PROCESS | 11652 | _x_x_mozilla_page_load 07:16:47 INFO - PROCESS | 11652 | _x_x_mozilla_page_load_details 07:16:47 INFO - PROCESS | 11652 | |i|pagename|runs| 07:16:47 INFO - PROCESS | 11652 | |0;ai-astar;112;104;111;110;111;110;108;110;110;109 07:16:47 INFO - PROCESS | 11652 | |1;audio-beat-detection;156;124;126;185;124;133;148;128;167;130 07:16:47 INFO - PROCESS | 11652 | |2;audio-dft;161;161;160;160;160;159;161;161;160;161 07:16:47 INFO - PROCESS | 11652 | |3;audio-fft;76;75;74;127;75;79;76;75;97;74 07:16:47 INFO - PROCESS | 11652 | |4;audio-oscillator;84;85;112;84;85;85;118;84;85;125 07:16:47 INFO - PROCESS | 11652 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;116;117;117 07:16:47 INFO - PROCESS | 11652 | |6;imaging-darkroom;145;145;146;145;145;145;147;145;145;145 07:16:47 INFO - PROCESS | 11652 | |7;imaging-desaturate;95;95;94;95;95;95;95;94;95;95 07:16:47 INFO - PROCESS | 11652 | |8;json-parse-financial;52;51;50;51;52;52;52;52;53;55 07:16:47 INFO - PROCESS | 11652 | |9;json-stringify-tinderbox;59;59;59;60;59;60;66;59;59;61 07:16:47 INFO - PROCESS | 11652 | |10;stanford-crypto-aes;76;75;76;76;76;76;77;76;105;82 07:16:47 INFO - PROCESS | 11652 | |11;stanford-crypto-ccm;129;117;117;115;121;121;119;117;125;118 07:16:47 INFO - PROCESS | 11652 | |12;stanford-crypto-pbkdf2;159;153;151;152;153;155;162;152;150;149 07:16:47 INFO - PROCESS | 11652 | |13;stanford-crypto-sha256-iterative;69;64;61;61;63;61;61;61;61;59 07:16:47 INFO - PROCESS | 11652 | __end_tp_report 07:16:47 INFO - PROCESS | 11652 | __start_cc_report 07:16:47 INFO - PROCESS | 11652 | _x_x_mozilla_cycle_collect,1567 07:16:47 INFO - PROCESS | 11652 | __end_cc_report 07:16:47 INFO - PROCESS | 11652 | __startTimestamp1468505807001__endTimestamp 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - PROCESS | 11652 | ------- Summary: start ------- 07:16:47 INFO - PROCESS | 11652 | Number of tests: 14 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - PROCESS | 11652 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.00 stddev:2.22 (2.0%) stddev-sans-first:2.17 07:16:47 INFO - PROCESS | 11652 | Values: 112.0 104.0 111.0 110.0 111.0 110.0 108.0 110.0 110.0 109.0 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - PROCESS | 11652 | [#1] audio-beat-detection Cycles:10 Average:142.10 Median:140.50 stddev:21.16 (15.1%) stddev-sans-first:21.84 07:16:47 INFO - PROCESS | 11652 | Values: 156.0 124.0 126.0 185.0 124.0 133.0 148.0 128.0 167.0 130.0 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - PROCESS | 11652 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:0.70 (0.4%) stddev-sans-first:0.71 07:16:47 INFO - PROCESS | 11652 | Values: 161.0 161.0 160.0 160.0 160.0 159.0 161.0 161.0 160.0 161.0 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - PROCESS | 11652 | [#3] audio-fft Cycles:10 Average:82.80 Median:75.50 stddev:17.00 (22.5%) stddev-sans-first:17.85 07:16:47 INFO - PROCESS | 11652 | Values: 76.0 75.0 74.0 127.0 75.0 79.0 76.0 75.0 97.0 74.0 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - PROCESS | 11652 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:84.50 stddev:16.60 (19.6%) stddev-sans-first:17.15 07:16:47 INFO - PROCESS | 11652 | Values: 84.0 85.0 112.0 84.0 85.0 85.0 118.0 84.0 85.0 125.0 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - PROCESS | 11652 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:16:47 INFO - PROCESS | 11652 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - PROCESS | 11652 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 07:16:47 INFO - PROCESS | 11652 | Values: 145.0 145.0 146.0 145.0 145.0 145.0 147.0 145.0 145.0 145.0 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - PROCESS | 11652 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:16:47 INFO - PROCESS | 11652 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - PROCESS | 11652 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:52.00 stddev:1.33 (2.6%) stddev-sans-first:1.41 07:16:47 INFO - PROCESS | 11652 | Values: 52.0 51.0 50.0 51.0 52.0 52.0 52.0 52.0 53.0 55.0 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - PROCESS | 11652 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:59.50 stddev:2.18 (3.7%) stddev-sans-first:2.28 07:16:47 INFO - PROCESS | 11652 | Values: 59.0 59.0 59.0 60.0 59.0 60.0 66.0 59.0 59.0 61.0 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - PROCESS | 11652 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:76.00 stddev:9.17 (12.1%) stddev-sans-first:9.64 07:16:47 INFO - PROCESS | 11652 | Values: 76.0 75.0 76.0 76.0 76.0 76.0 77.0 76.0 105.0 82.0 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - PROCESS | 11652 | [#11] stanford-crypto-ccm Cycles:10 Average:119.90 Median:120.00 stddev:4.28 (3.6%) stddev-sans-first:3.02 07:16:47 INFO - PROCESS | 11652 | Values: 129.0 117.0 117.0 115.0 121.0 121.0 119.0 117.0 125.0 118.0 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - PROCESS | 11652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.60 Median:153.00 stddev:4.06 (2.7%) stddev-sans-first:3.81 07:16:47 INFO - PROCESS | 11652 | Values: 159.0 153.0 151.0 152.0 153.0 155.0 162.0 152.0 150.0 149.0 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - PROCESS | 11652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:61.00 stddev:2.77 (4.5%) stddev-sans-first:1.41 07:16:47 INFO - PROCESS | 11652 | Values: 69.0 64.0 61.0 61.0 63.0 61.0 61.0 61.0 61.0 59.0 07:16:47 INFO - PROCESS | 11652 | -------- Summary: end -------- 07:16:47 INFO - PROCESS | 11652 | 07:16:47 INFO - TEST-INFO | 11652: exit 0 07:16:47 INFO - TEST-OK | kraken | took 59891ms 07:16:47 INFO - SUITE-END | took 1172s 07:16:48 INFO - Completed test suite (00:19:32) 07:16:48 INFO - javascript benchmark 07:16:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3264.918377040574, 3274.4106060909025, 3276.9508457373136, 3320.767546271762, 3344.4147274227003, 5325.0, 5429.59278054146, 5442.809501667442, 5446.509814116729, 5455.5908306876945, 5405.810796622119, 5470.589705772063, 5471.343438787341, 5481.397046324911, 5492.917606235909, 4850.175470170079, 4931.901361972758, 4969.2049272116465, 4996.425411077718, 5076.162433198536, 4827.8551643450755, 4968.40379154502, 5000.649954503187, 5008.3238762767105, 5009.874753131165, 4927.827526036589, 4962.330085438456, 4969.0, 4976.651634385595, 5011.398632164145, 5040.571551418134, 5049.823256186034, 5054.469280725544, 5059.038782631288, 5070.264811602316, 4781.306710526989, 4870.829520966749, 4907.239377896396, 4965.031818795336, 5000.149974504343, 5242.056429842627, 5247.134222853225, 5257.0274499217385, 5257.080010998098, 5279.5512381447625, 5758.88482230353, 5761.971190144022, 5783.5951483396175, 5796.88406231873, 5802.564807639402, 5745.368009518949, 5770.682612456313, 5779.479846813807, 5801.883962320772, 5813.563982701315, 5447.645903016291, 5465.562754979592, 5467.589930755209, 5497.45025497451, 5500.119980803092, 5776.913346299809, 5922.911156332659, 5942.257217847769, 5959.851003724942, 5962.940370596239, 5640.520555752725, 5661.377248502661, 5666.490015898589, 5710.628809127394, 5724.198612194296, 5784.768609255667, 5794.217780599565, 5802.564807639444, 5819.0, 5819.912701309484, 5600.775968961278, 5646.20953066571, 5764.596478246483, 5764.942350576524, 5777.595568310178, 5670.291213598301, 5750.856228594319, 5763.049096899049, 5769.971150144306, 5786.07422812348, 4464.866054018385, 4466.0, 4466.441694788152, 4479.46246450428, 4488.281874900001, 3953.920921581552, 3963.504561929759, 3978.3634618460906, 4007.6593489553134, 4031.879043628696, 5487.176923461513, 5506.889862202814, 5519.116941289455, 5520.309961254843, 5543.113101903676, 5824.417558244227, 5833.7374818133285, 5836.737346819318, 5839.970800146057, 5861.267341582302, 5446.2375267462585, 5546.084895992116, 5546.889062218733, 5574.554035677136, 5593.216949627055, 5060.443351231362, 5063.924041139386, 5064.848054558369, 5072.797088116434, 5073.670211436245, 6058.242719660043, 6111.786087487006, 6125.4487096160665, 6136.846578835565, 6139.846503837351, 5709.172170035238, 5720.656760594377, 5765.5675824314185, 5772.6536407815665, 5838.386969368155, 6039.456448919618, 6041.0, 6042.969785151222, 6044.637321760709, 6053.243344581927, 5582.609217354746, 5679.829605111853, 5687.3459552152035, 5688.345840228261, 5705.4579814917515, 5716.685582292847, 5723.084306510938, 5731.541476681939, 5796.449337312949, 5805.709714514342, 5600.103983362643, 5630.23991761107, 5644.887102258014, 5652.604317697722, 5657.462541058593, 5535.806246781343, 5569.359523654832, 5655.632383895034, 5659.377468478382, 5667.829965101054, 5530.55755539564, 5585.2180694701965, 5599.216109744558, 5605.719714014202, 5622.88754224905, 5701.657900525982, 5712.057510510719, 5753.827385178452, 5756.68338241384, 5766.0, 5491.643043202179, 5528.170774383714, 5555.3333599966645, 5598.964191624563, 5627.718614069362], "value": 5359.956146422007, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.0697326117779, 650.0606623429147, 650.5771248688352, 664.1067763857611, 674.5817593092283, 33823.49264761028, 47398.2890256646, 48015.51984480154, 48072.0, 48159.51840481594, 48990.755046224716, 49370.0, 49395.0, 49409.0, 49616.2557561636, 8426.325893928471, 9052.321075919315, 9209.493477858716, 9211.0, 10790.190735694816, 10591.046805787477, 10949.343039417661, 10950.780984380308, 10951.178661600372, 10962.287451315678, 10895.673129806119, 10930.398828064453, 10955.397453140115, 11021.504032318524, 11027.503762330714, 8365.539895305756, 8478.915210847936, 8545.2309292164, 10749.892501075046, 10782.676519704382, 10525.21060920434, 10631.84052239217, 10897.727556811064, 10903.400312982783, 10917.635295588052, 45320.3201951971, 45897.0, 45961.0, 45982.0, 46377.0, 3632.636736326346, 3656.707463402948, 3672.3573374659595, 3726.9440958385644, 3798.734088613798, 35284.117897052776, 35297.470537941954, 35752.64247357519, 35803.28393432143, 36011.0, 4540.841070562515, 4668.629752690982, 4857.902841943177, 4865.148598995197, 4959.181735013718, 293560.0, 371167.0, 373854.0, 374927.12536437414, 379264.0, 71713.28286717067, 72281.0, 72328.0, 72353.27646723465, 72370.0, 41502.754917352526, 42437.0, 42517.57482425198, 43999.78000109979, 44024.779876100416, 20027.49931251701, 20047.097880595407, 20222.191112355635, 20234.988250587412, 20250.594988100154, 14117.929410352883, 15298.694026119638, 15573.377064917277, 15579.688406232039, 15981.680366392608, 23062.192823251105, 23323.0, 23333.41666458352, 23362.883185584302, 23368.182113625942, 8128.512289262663, 8257.710980115668, 8337.624806883705, 8437.409381343246, 8511.489310641382, 1092.1972350322508, 1140.5779861451317, 1168.199783148529, 1174.9542906812944, 1208.3897631695938, 34368.65631343654, 35668.904429370865, 35972.0, 36034.81982590123, 36059.27881442357, 1223.7797196504714, 1267.6894160930447, 1284.107545256038, 1294.7734146524322, 1311.2263764379063, 18455.723164152547, 18639.720404193948, 18835.152418141217, 19111.52221194481, 19140.90429547871, 643039.0, 654185.0, 658705.0, 659496.0, 660610.0, 63725.362746371946, 64769.67615161894, 64951.67524162349, 65485.345146548876, 65547.67226163839, 1360.8298962629672, 1465.7874608181753, 1482.9851701482846, 1490.7465730825786, 1506.5254444849836, 35193.64806351904, 36002.63997360098, 36889.44665830069, 36907.0, 37287.81356093202, 55448.44551554433, 55660.443395565526, 55877.0, 56000.439995599525, 56250.718746406004, 413355.0, 414982.0, 415291.0, 416627.0, 417255.0, 28090.2977425566, 31185.688143118277, 31721.682783171873, 31728.524072138476, 33308.50037249492, 52377.7381113092, 53326.73336633292, 53346.466535335705, 53780.46219537754, 54451.72774136262, 44234.55765442433, 44833.0, 45105.774471127435, 45725.0, 46117.07765844731, 44092.0, 44278.11443771171, 44340.55659443494, 44747.32879006882, 45650.77174614106, 44346.55653443424, 44686.3297050551, 45091.32363014623, 45279.54720452753, 45398.54601453943, 38032.42951355787, 38378.0, 39413.408798867466, 39557.0, 39943.60056399515, 29386.853065734533, 29951.400971980875, 30160.5475917857, 30338.0, 30473.0], "value": 22192.107825187162, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.4456206930991, 434.3289617540899, 439.0365036006953, 442.55081092690915, 444.2159588326602, 18724.15741291642, 19639.214431422726, 20643.793562064377, 20778.68831967518, 20827.791722082773, 19456.0, 20342.89828550859, 20431.897840510814, 20578.588428231425, 20614.484637884023, 5666.773329066832, 5893.20441740366, 5905.704714764266, 5906.232189815318, 5914.615549989258, 5794.188813566104, 5821.09772985188, 5836.562257830658, 5860.97069514654, 5889.617174883619, 5798.797042103527, 5810.3027636683655, 5817.447342502476, 5818.505427038707, 5835.503982161521, 5657.830265092033, 5750.856228594277, 5813.09896965971, 5813.476787089161, 5817.621854579481, 5021.799128034874, 5156.278121063054, 5170.095233334151, 5172.689638621657, 5184.222366644995, 23865.40336491584, 23937.40156496084, 24489.142879999294, 24599.508009839705, 24611.63082553763, 2479.1075212923333, 2501.6497690323367, 2806.494830930432, 2837.4608824323313, 2920.2407374082677, 14263.78604320936, 14313.499027533986, 14481.710365792731, 15335.156566388845, 15611.687766244611, 2040.897955102239, 2065.4320061983076, 2094.706741056253, 2196.198387588535, 2216.7229096362953, 60608.69695651538, 83324.58337708273, 84551.5772421134, 85303.57348213281, 86165.99999999937, 11097.278676885977, 11623.535058597561, 11946.581869634694, 11976.101792365607, 12072.5566320519, 16374.83625163757, 16732.916335418246, 16790.412335568195, 16813.327466901188, 16816.159192040348, 8359.0, 8415.0, 8569.143085691381, 8578.485290882567, 8779.385443019055, 8761.167689069529, 8820.11798820113, 8941.821163576822, 9042.095790420904, 9069.909300907038, 4798.256270278112, 4980.427250866124, 4984.152694042022, 5134.948650513447, 5159.896802063938, 2030.5329774152028, 2048.702938074001, 2060.6908963655574, 2077.4390914453015, 2109.7468303803644, 813.6745301879296, 1017.531935309751, 1049.984250236232, 1066.1630619963291, 1127.2954403497813, 19265.51836204078, 19723.211071557263, 19856.10647520865, 19863.60272794536, 20077.799222007594, 1051.085555566662, 1079.0666073846223, 1081.4808891732014, 1087.3095584304024, 1123.1071298317856, 10829.133669306595, 11355.54577816878, 11414.315141091562, 11415.828762568402, 11453.370064646524, 425462.0, 425948.0, 426297.0, 535942.0, 555769.105772359, 62596.687016564625, 78308.0, 90939.54530227307, 102118.4894075525, 103108.0, 1082.870055593318, 1117.5920788912151, 1120.0815331428314, 1129.4409267412684, 1131.106425923511, 17939.551511212325, 18150.5462363442, 18210.817891820912, 18297.26810927548, 18338.541536461697, 43963.14239574417, 44107.55892441163, 44400.33399498946, 44795.77602112099, 44994.77502612466, 60930.69534652298, 61636.38363616486, 61711.0, 62175.06737399033, 62192.37807622047, 7679.155292917663, 8536.829263414822, 8545.74362769118, 8731.432456890281, 8922.330825188006, 10719.624813131502, 13893.444262229801, 14072.718545629235, 14327.212003339917, 14562.635934101732, 12505.93747031259, 12511.812322815347, 12554.623361299176, 12713.618591442271, 12771.425285862344, 12432.564860229848, 12604.11771176009, 12705.682357941125, 12831.935840320739, 12884.162529435556, 7967.800804979922, 9423.811523769624, 12768.233905965672, 12811.167274127152, 12850.742985140057, 8177.918220817715, 8292.33661307106, 8526.78683032929, 8586.098459661895, 8636.26591739684, 11006.284591501526, 12340.938295308466, 12388.876111239133, 12476.376181190722, 12520.123591348518], "value": 9691.011311758677, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1397.797319388688, 1574.4804214609176, 1626.1706529669868, 1841.3463220556703, 1965.1451618545934, 8848.646054157834, 9267.721968340953, 9275.118863707945, 9448.149666530011, 9460.81078378432, 9286.535673216345, 9412.670556530522, 9414.152726254635, 9425.57584908679, 9435.57539910704, 2088.550961543268, 2414.674019007438, 2478.5910324796387, 2481.7146028206807, 2507.0974449198275, 2481.131603938616, 2497.862617556034, 2498.800095992325, 2508.7240403555593, 2512.8869200886006, 2478.962815557768, 2502.749725027501, 2508.3102146909655, 2508.8871000804916, 2509.5859183234834, 2108.8523803333774, 2420.455397535558, 2493.9750602493923, 2494.4262819551604, 2495.6755621769234, 2389.595427390966, 2398.424378149249, 2409.831311808174, 2429.769171928664, 2432.0028788196933, 9451.480168590724, 9460.810783784285, 9506.334556581041, 9510.334276600572, 9542.570584323723, 5428.61999660024, 5555.861103472405, 5563.36021357541, 5573.888522229533, 5574.498295153456, 3584.229390681016, 3609.2781443711365, 3675.7978311192874, 3760.774353538769, 3778.4899038629983, 1267.9809802852965, 1301.2777908260912, 1311.3639884655934, 1319.9076064675476, 1349.6828245362333, 13653.044286899922, 13672.863271367258, 13683.726325473534, 13750.449982000708, 13774.93112534441, 4617.0, 4622.722636641813, 4700.649675162419, 4712.586224132747, 4740.3837501124635, 11322.150838687132, 11330.773384532264, 11339.829902551468, 11352.772944541062, 11432.199746017699, 4882.170031094723, 5357.303550538405, 5358.490943360374, 5359.651622644516, 5368.302120724281, 4942.085714142895, 5049.6212784041345, 5075.213341932006, 5117.41149767774, 5124.077666020077, 3955.5904892906156, 4005.839766409369, 4032.6370626643743, 4042.211768705133, 4043.110515686544, 1358.6331690443526, 1386.5008596905107, 1390.3812803302433, 1400.4468235047302, 1400.6428360767939, 1046.3198920701452, 1073.6510634043811, 1079.6653037558383, 1080.5029686344212, 1089.9727506812235, 6536.934630653727, 6704.430123439464, 6809.863802723918, 6825.249222585511, 6825.69284382194, 461.9030003699261, 466.4262956563378, 466.55210997442833, 482.45482604656513, 498.9376327959005, 4538.682292239578, 4672.229082201467, 4729.527047295243, 4733.408323959505, 4799.160146974265, 11300.773984520263, 11553.13351498624, 11860.76278474443, 11871.347075910908, 11900.226485278428, 11013.0, 11433.714157146138, 11475.368854712904, 11776.175667703179, 11804.232724872856, 8674.913250867537, 8796.956015219883, 8809.471431714117, 8861.468311901306, 8881.822363552692, 6227.501799856092, 6561.212654481493, 6618.470522358296, 6622.933770662232, 6658.400743933069, 10409.68770936873, 10426.530806113895, 10449.0, 10465.319754215952, 10776.461176941279, 10767.730806729895, 11043.0, 11052.613158539734, 11075.72310692239, 11192.21654484211, 8337.333013358795, 8880.0, 9125.726228213165, 9244.306676999118, 9379.765505862408, 9523.904760952302, 9535.523223838642, 9552.761180970532, 9606.903930960601, 9614.086661767122, 8447.239748422671, 8596.183362580545, 8611.784705382415, 8691.695790647296, 8729.127087291221, 8127.2279133482225, 8144.226298501633, 8403.41176117666, 8409.747707568782, 8509.531975741394, 7549.207333230152, 7624.313811756969, 7639.388848891964, 7642.312191902755, 7643.4267429941865, 5239.816606418757, 5355.25026496283, 5426.945730542644, 5434.021876062347, 5601.131824567035, 5523.364813046551, 5585.69278689676, 5771.018926782541, 5866.0027795275755, 5912.290525137011], "value": 4906.479401640385, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.79246848125365, 353.08726940857883, 353.5086230140106, 354.10411658503995, 355.6585677749362, 5359.517643412092, 5410.404855465896, 5479.986202552531, 5483.7532311046, 5512.980098681736, 5399.244105825188, 5400.405955344909, 5411.0, 5417.702026388547, 5448.400675925645, 3918.2163567286516, 4070.3690927906223, 4085.2442298174783, 4173.833046678129, 4289.713086074181, 4206.137741762931, 4208.957910420902, 4246.40550322955, 4254.957535403831, 4281.400603915439, 4211.115665710205, 4229.555896630855, 4233.470816147981, 4235.470566179228, 4266.317389217726, 4077.694172937027, 4179.561146079648, 4230.70385073042, 4249.978750106261, 4259.1268789897995, 3780.2250538639514, 3799.2781371539595, 3801.9924719949236, 3808.2383523295175, 3818.9618103818884, 5166.328377310963, 5192.506711862405, 5227.268182454422, 5233.528982391603, 5243.292155559027, 4879.56083952446, 4912.992836468516, 4916.237983112624, 4925.876853078666, 4929.433115191763, 5423.403425623178, 5580.86047848803, 5683.46546432465, 5693.146028095777, 5696.2594862668, 4028.6575641070544, 4117.814698338552, 4129.483814523184, 4142.233686767958, 4209.621134097946, 5732.426757324276, 5817.38917413674, 5865.912011319833, 5873.236479257712, 5879.882402351929, 4165.541790403053, 4215.241256573846, 4226.85206017794, 4237.809298581571, 4275.5724427557, 4322.69741118125, 4329.761863097497, 4330.783460826946, 4340.696151269381, 4348.5216626171095, 4861.805527778849, 4875.609951203895, 4875.707937396593, 4881.877953051131, 4890.1931181355385, 5415.783368665288, 5642.843217140477, 5672.12082127271, 5691.544676425876, 5705.0, 1475.6163397516723, 1476.904001239916, 1488.848613753138, 1488.9404423823141, 1493.7759336099673, 958.7075941837667, 977.3891317926297, 986.5264672957021, 989.851522271665, 994.1947022911465, 4890.090396214318, 4900.191468407673, 4921.606271498272, 4950.430700469492, 4994.9750251248515, 5093.770780314895, 5127.9230811537855, 5135.15269980449, 5156.530388839163, 5188.0, 4570.382998295254, 4920.261960705922, 4961.801527938915, 4976.975115124401, 5036.84889453317, 2765.9446811063667, 2780.4161126163326, 2786.4148528808773, 2813.3669924267288, 2821.4357128573956, 5369.892602147936, 5394.8921021579345, 5409.702466364388, 5455.972720136374, 5456.6725996439545, 5206.609504287194, 5325.920111198335, 5500.944990550123, 5991.760329586855, 5995.100734889801, 5805.738741756547, 6101.60339577926, 6111.57218994675, 6137.649717062239, 6148.200733904564, 5755.3381361144, 5851.590388672752, 5870.354261031198, 5876.471117599265, 5880.294364676266, 5789.536837052941, 5793.768249270151, 5797.623154494944, 5800.0, 5801.708325211603, 5789.3921138279875, 6004.249468816398, 6034.668093254738, 6051.152838602511, 6092.177556029879, 4228.471441069866, 4256.404103425461, 4258.190943720683, 4262.445882035439, 4264.296391095435, 5845.3570107289015, 5861.0, 5885.234919460443, 5914.2311499504785, 5924.992751232215, 5378.865528361822, 5686.402927692533, 5745.74144163522, 5749.482546570829, 5763.164341170591, 5708.345619286885, 5710.5146062585145, 5744.281964754406, 5748.45389687979, 5750.25246717924, 5350.759215835219, 5576.888462230651, 5580.190872323572, 5583.022970980143, 5627.5497960162265, 5655.547556195576, 5679.432056794288, 5681.517071049007, 5719.8570035749435, 5745.0, 5581.214011516276, 5614.185943038155, 5619.185218143428, 5645.887082258249, 5653.660780353192], "value": 4289.186785823747, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.302361827119, 770.645503068588, 834.7036801935309, 898.2499612823293, 914.0, 15514.767278490817, 15556.688866222668, 15618.141002244858, 15632.0, 16007.199640017996, 16084.276207570658, 16091.678166436664, 16135.83864161358, 16170.75743863843, 16197.271122799473, 864.6757465950284, 889.9466032038066, 890.2699786175339, 892.8794612727296, 894.2801045158644, 884.9026607073217, 893.1381217125472, 894.3962825093068, 894.5080205886736, 894.6332003878412, 934.2245935873243, 938.061938061938, 939.0609390609391, 939.178219058324, 939.2345238630494, 940.9952950235273, 941.9199368053723, 942.0814705661993, 942.6323733743845, 942.6983365323099, 690.520088538466, 691.2223748283182, 691.2568988337557, 691.4330249195662, 691.8270432391902, 16313.755293670605, 16532.917335413367, 16534.173291335384, 16559.503214903572, 16566.83433165665, 14405.423783048665, 14718.63203419923, 14721.631959200999, 14805.481808136663, 14965.551033469013, 14157.504487342894, 14382.568522944328, 14436.566902992927, 14465.421383144661, 14476.782848257284, 4758.262469317261, 4813.687110337852, 4814.205656066745, 4839.782209800533, 4941.209406494943, 50816.74591627055, 75370.0, 78679.60660196662, 78687.60656196739, 79498.0, 12742.36288185587, 12777.23336599807, 12840.678983025498, 12918.547850825176, 13015.739685206243, 22537.098516059174, 22563.548729025326, 22589.661155082686, 22606.0957561699, 22606.886965565067, 9217.907820921839, 9276.582553785096, 9363.531823408803, 9500.904990950003, 9692.22462203022, 5250.7374631268285, 5260.605454590921, 5306.681599104066, 5378.139497680352, 5440.483154100354, 3892.591277915778, 3966.0679740261016, 4001.8399264029695, 4002.779847108379, 4007.0984028593916, 639.7760783725752, 644.3781750610683, 651.1860174781523, 651.843557546195, 654.9312322206194, 745.6085555083624, 798.1380109481746, 826.9462484938459, 846.8348672008899, 852.1776485691338, 10695.090917272119, 10745.737259016374, 10761.354318740743, 10906.127509799355, 10989.505472253768, 798.3453568074124, 808.9421984897099, 814.0800894988625, 818.7830224990382, 835.5195762436501, 4828.251620998751, 4845.127876982177, 4890.486498917634, 4932.90134197314, 5032.723200224023, 73523.26476735165, 100304.49847750715, 113141.86858131477, 119523.40238298752, 123281.0, 97307.51346243365, 99265.0, 100748.49625751971, 102005.48997255115, 102950.48524757328, 91721.54139229395, 92020.0, 92997.5350123245, 93601.0, 94364.52817735868, 9591.280653950842, 9740.269479788902, 9845.113939745457, 9958.900410996088, 10005.899941000787, 53973.0, 54226.0, 54582.45417545774, 55644.44355556393, 55845.16232256601, 74928.62535687287, 79456.60271698605, 79762.60118699369, 80291.0, 80786.59606701929, 13047.347632618521, 13052.673683157996, 13204.71022583477, 13321.33393330349, 13655.522056727967, 14427.567172984827, 15005.699886002438, 15121.546353609408, 15173.0, 16045.8395416049, 14407.78388324197, 15274.77087843661, 15788.68422631564, 16548.669026619642, 16621.66756664884, 15088.698226035638, 15387.61530961735, 15989.440369587008, 16005.599860003593, 16402.09788461646, 14310.85689143137, 14350.928245358706, 14586.562403127924, 15241.390344386544, 16276.43032493857, 12113.515459381526, 12242.510299587917, 12307.015438765056, 12411.813822792847, 12769.61691149267, 12563.87436125627, 12582.937085314516, 12737.93631031839, 12747.681307967005, 12964.481420743441], "value": 8209.79170207698, "unit": "score"}], "name": "dromaeo_css", "value": 7642.031835023607, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 104.0, 111.0, 110.0, 111.0, 110.0, 108.0, 110.0, 110.0, 109.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 124.0, 126.0, 185.0, 124.0, 133.0, 148.0, 128.0, 167.0, 130.0], "value": 142.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 160.0, 160.0, 159.0, 161.0, 161.0, 160.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 74.0, 127.0, 75.0, 79.0, 76.0, 75.0, 97.0, 74.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 112.0, 84.0, 85.0, 85.0, 118.0, 84.0, 85.0, 125.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 147.0, 145.0, 145.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 50.0, 51.0, 52.0, 52.0, 52.0, 52.0, 53.0, 55.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 60.0, 59.0, 60.0, 66.0, 59.0, 59.0, 61.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 76.0, 76.0, 76.0, 77.0, 76.0, 105.0, 82.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 117.0, 117.0, 115.0, 121.0, 121.0, 119.0, 117.0, 125.0, 118.0], "value": 119.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 153.0, 151.0, 152.0, 153.0, 155.0, 162.0, 152.0, 150.0, 149.0], "value": 153.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 64.0, 61.0, 61.0, 63.0, 61.0, 61.0, 61.0, 61.0, 59.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1473.5999999999997, "alertThreshold": 2.0}]} 07:16:48 INFO - Return code: 0 07:16:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:16:48 ERROR - # TBPL SUCCESS # 07:16:48 INFO - Running post-action listener: _resource_record_post_action 07:16:48 INFO - ##### 07:16:48 INFO - ##### Finished run-tests step (success) 07:16:48 INFO - ##### 07:16:48 INFO - Running post-run listener: _resource_record_post_run 07:16:48 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 514781184; Read time: 12; Write time: 407500 07:16:48 INFO - TinderboxPrint: CPU usage
13.1% 07:16:48 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 07:16:48 INFO - TinderboxPrint: I/O write bytes / time
514,781,184 / 407,500 07:16:48 INFO - TinderboxPrint: CPU idle
8,255.0 (86.9%) 07:16:48 INFO - TinderboxPrint: CPU user
1,197.7 (12.6%) 07:16:48 INFO - TinderboxPrint: Swap in / out
0 / 0 07:16:48 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 259821568; Read time: 0; Write time: 252476 07:16:48 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 254373888; Read time: 12; Write time: 154996 07:16:48 INFO - Running post-run listener: _upload_blobber_files 07:16:48 INFO - Blob upload gear active. 07:16:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:16:48 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:16:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:16:49 INFO - (blobuploader) - INFO - Open directory for files ... 07:16:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:16:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:16:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:16:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:16:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:16:49 INFO - (blobuploader) - INFO - Done attempting. 07:16:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:16:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:16:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:16:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:16:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:16:50 INFO - (blobuploader) - INFO - Done attempting. 07:16:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:16:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:16:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:16:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:16:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:16:54 INFO - (blobuploader) - INFO - Done attempting. 07:16:54 INFO - (blobuploader) - INFO - Iteration through files over. 07:16:54 INFO - Return code: 0 07:16:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:16:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:16:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d0e53efcb3249793bc8dcb7f206ec610dfe00b9d11258a8da9751b6cc1b9e823f47325c6cf373de495f81280e2f01f971f87c294cb1a051bb22084c384c1bc0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22e95998c6c2c4d24cc0439099120c691fdd993fda248f549b311f4c45b79e7ed5f7fbacbacd1fd3b7c4a3fc84c410bba8cfa981e5efbd51e45d3a90e9f9565b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54d437b702ea1ed2cb2ad568e301624dc578c4893204551c032dfbc9ef77c4a4c4d6217af34cd1bfb1fcafca8870283fc5aa0b1ccff353a3c28732fe392850b5"} 07:16:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:16:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:16:54 INFO - Contents: 07:16:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d0e53efcb3249793bc8dcb7f206ec610dfe00b9d11258a8da9751b6cc1b9e823f47325c6cf373de495f81280e2f01f971f87c294cb1a051bb22084c384c1bc0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22e95998c6c2c4d24cc0439099120c691fdd993fda248f549b311f4c45b79e7ed5f7fbacbacd1fd3b7c4a3fc84c410bba8cfa981e5efbd51e45d3a90e9f9565b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54d437b702ea1ed2cb2ad568e301624dc578c4893204551c032dfbc9ef77c4a4c4d6217af34cd1bfb1fcafca8870283fc5aa0b1ccff353a3c28732fe392850b5"} 07:16:54 INFO - Running post-run listener: copy_logs_to_upload_dir 07:16:54 INFO - Copying logs to upload dir... 07:16:54 INFO - mkdir: /builds/slave/test/build/upload/logs 07:16:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.739238 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-07-14 07:16:54.059525) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-14 07:16:54.063886) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d0e53efcb3249793bc8dcb7f206ec610dfe00b9d11258a8da9751b6cc1b9e823f47325c6cf373de495f81280e2f01f971f87c294cb1a051bb22084c384c1bc0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22e95998c6c2c4d24cc0439099120c691fdd993fda248f549b311f4c45b79e7ed5f7fbacbacd1fd3b7c4a3fc84c410bba8cfa981e5efbd51e45d3a90e9f9565b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54d437b702ea1ed2cb2ad568e301624dc578c4893204551c032dfbc9ef77c4a4c4d6217af34cd1bfb1fcafca8870283fc5aa0b1ccff353a3c28732fe392850b5"} build_url:https://queue.taskcluster.net/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011379 build_url: 'https://queue.taskcluster.net/v1/task/Ejy1w4DbQR6q4iS2vkVw4g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d0e53efcb3249793bc8dcb7f206ec610dfe00b9d11258a8da9751b6cc1b9e823f47325c6cf373de495f81280e2f01f971f87c294cb1a051bb22084c384c1bc0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22e95998c6c2c4d24cc0439099120c691fdd993fda248f549b311f4c45b79e7ed5f7fbacbacd1fd3b7c4a3fc84c410bba8cfa981e5efbd51e45d3a90e9f9565b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54d437b702ea1ed2cb2ad568e301624dc578c4893204551c032dfbc9ef77c4a4c4d6217af34cd1bfb1fcafca8870283fc5aa0b1ccff353a3c28732fe392850b5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-14 07:16:54.096706) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 07:16:54.097334) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5ac5dba3e01a12f1ff77ab15000001b1-1468504581.690084-19511126 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004476 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 07:16:54.180520) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-14 07:16:54.180851) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-14 07:16:54.181188) ========= ========= Total master_lag: 0.19 =========