builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-033 starttime: 1469214135.65 results: success (0) buildid: 20160722101926 builduid: 9a63f52501e045aa9c17f8fef3f49fe0 revision: 3ecc86179a9af4ddb53ab1fe478511a9832e9801 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 12:02:15.653373) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 12:02:15.653939) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 12:02:15.654217) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 12:02:15.696050) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:02:15.696627) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-22 12:02:15-- 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% 46.7M=0s 2016-07-22 12:02:15 (46.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.231793 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:02:15.977129) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:02:15.977722) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.109325 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:02:16.100214) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:02:16.100804) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3ecc86179a9af4ddb53ab1fe478511a9832e9801 --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 3ecc86179a9af4ddb53ab1fe478511a9832e9801 --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=edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-22 12:02:16,163 truncating revision to first 12 chars 2016-07-22 12:02:16,163 Setting DEBUG logging. 2016-07-22 12:02:16,163 attempt 1/10 2016-07-22 12:02:16,163 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3ecc86179a9a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-22 12:02:16,493 unpacking tar archive at: mozilla-aurora-3ecc86179a9a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.670171 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:02:16.838255) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 12:02:16.838593) ========= script_repo_revision: 3ecc86179a9af4ddb53ab1fe478511a9832e9801 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 12:02:16.839008) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 12:02:16.839273) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 12:02:16.850249) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-07-22 12:02:16.850636) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:02:17 INFO - MultiFileLogger online at 20160722 12:02:17 in /builds/slave/test 12:02:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:02:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:02:17 INFO - {'append_to_log': False, 12:02:17 INFO - 'base_work_dir': '/builds/slave/test', 12:02:17 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:02:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:02:17 INFO - 'branch': 'Mozilla-Aurora', 12:02:17 INFO - 'buildbot_json_path': 'buildprops.json', 12:02:17 INFO - 'config_files': ('talos/linux_config.py',), 12:02:17 INFO - 'default_actions': ('clobber', 12:02:17 INFO - 'read-buildbot-config', 12:02:17 INFO - 'download-and-extract', 12:02:17 INFO - 'populate-webroot', 12:02:17 INFO - 'create-virtualenv', 12:02:17 INFO - 'install', 12:02:17 INFO - 'run-tests'), 12:02:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:02:17 INFO - 'download_minidump_stackwalk': True, 12:02:17 INFO - 'download_symbols': 'ondemand', 12:02:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:02:17 INFO - 'tooltool.py': '/tools/tooltool.py', 12:02:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:02:17 INFO - '/tools/misc-python/virtualenv.py')}, 12:02:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:02:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:02:17 INFO - 'installer_path': 'installer.exe', 12:02:17 INFO - 'log_level': 'info', 12:02:17 INFO - 'log_name': 'talos', 12:02:17 INFO - 'log_to_console': True, 12:02:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:02:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:02:17 INFO - 'opt_config_files': (), 12:02:17 INFO - 'pip_index': False, 12:02:17 INFO - 'sps_profile': False, 12:02:17 INFO - 'sps_profile_interval': 0, 12:02:17 INFO - 'suite': 'dromaeojs-e10s', 12:02:17 INFO - 'system_bits': '32', 12:02:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:02:17 INFO - 'title': 'talos-linux64-ix-033', 12:02:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:02:17 INFO - 'use_talos_json': True, 12:02:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:02:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:02:17 INFO - 'work_dir': 'build'} 12:02:17 INFO - ##### 12:02:17 INFO - ##### Running clobber step. 12:02:17 INFO - ##### 12:02:17 INFO - Running pre-action listener: _resource_record_pre_action 12:02:17 INFO - Running main action method: clobber 12:02:17 INFO - rmtree: /builds/slave/test/build 12:02:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:02:19 INFO - Running post-action listener: _resource_record_post_action 12:02:19 INFO - ##### 12:02:19 INFO - ##### Finished clobber step (success) 12:02:19 INFO - ##### 12:02:19 INFO - ##### 12:02:19 INFO - ##### Running read-buildbot-config step. 12:02:19 INFO - ##### 12:02:19 INFO - Running pre-action listener: _resource_record_pre_action 12:02:19 INFO - Running main action method: read_buildbot_config 12:02:19 INFO - Using buildbot properties: 12:02:19 INFO - { 12:02:19 INFO - "project": "", 12:02:19 INFO - "product": "firefox", 12:02:19 INFO - "script_repo_revision": "production", 12:02:19 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 12:02:19 INFO - "repository": "", 12:02:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 12:02:19 INFO - "buildid": "20160722101926", 12:02:19 INFO - "pgo_build": "True", 12:02:19 INFO - "basedir": "/builds/slave/test", 12:02:19 INFO - "buildnumber": 404, 12:02:19 INFO - "slavename": "talos-linux64-ix-033", 12:02:19 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:02:19 INFO - "platform": "ubuntu64_hw", 12:02:19 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 12:02:19 INFO - "branch": "mozilla-aurora", 12:02:19 INFO - "repo_path": "releases/mozilla-aurora", 12:02:19 INFO - "revision": "3ecc86179a9af4ddb53ab1fe478511a9832e9801", 12:02:19 INFO - "stage_platform": "linux64-pgo", 12:02:19 INFO - "builduid": "9a63f52501e045aa9c17f8fef3f49fe0", 12:02:19 INFO - "slavebuilddir": "test" 12:02:19 INFO - } 12:02:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 12:02:19 INFO - Running post-action listener: _resource_record_post_action 12:02:19 INFO - ##### 12:02:19 INFO - ##### Finished read-buildbot-config step (success) 12:02:19 INFO - ##### 12:02:19 INFO - ##### 12:02:19 INFO - ##### Running download-and-extract step. 12:02:19 INFO - ##### 12:02:19 INFO - Running pre-action listener: _resource_record_pre_action 12:02:19 INFO - Running main action method: download_and_extract 12:02:19 INFO - mkdir: /builds/slave/test/build/tests 12:02:19 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')]} 12:02:19 INFO - https://queue.taskcluster.net/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:02:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 12:02:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 12:02:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/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 12:02:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/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 12:02:20 INFO - Downloaded 1361 bytes. 12:02:20 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 12:02:20 INFO - Using the following test package requirements: 12:02:20 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 12:02:20 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 12:02:20 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 12:02:20 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 12:02:20 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 12:02:20 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 12:02:20 INFO - u'jsshell-linux-x86_64.zip'], 12:02:20 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 12:02:20 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 12:02:20 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 12:02:20 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 12:02:20 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 12:02:20 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 12:02:20 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 12:02:20 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 12:02:20 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 12:02:20 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 12:02:20 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 12:02:20 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:02:20 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')]} 12:02:20 INFO - https://queue.taskcluster.net/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:02:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 12:02:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 12:02:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/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 12:02:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/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 12:02:21 INFO - Downloaded 24494047 bytes. 12:02:21 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'] 12:02:21 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 12:02:22 INFO - Return code: 0 12:02:22 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 12:02:22 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')]} 12:02:22 INFO - https://queue.taskcluster.net/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:02:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 12:02:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 12:02:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/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 12:02:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/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 12:02:23 INFO - Downloaded 24494047 bytes. 12:02:23 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'] 12:02:23 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 12:02:24 INFO - Return code: 0 12:02: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')]} 12:02:24 INFO - https://queue.taskcluster.net/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:02:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 12:02:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 12:02:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/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 12:02:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/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 12:02:25 INFO - Downloaded 10833369 bytes. 12:02:25 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'] 12:02:25 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 12:02:26 INFO - Return code: 0 12:02:26 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')]} 12:02:26 INFO - https://queue.taskcluster.net/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:02:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 12:02:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 12:02:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/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 12:02:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ueggh7SMTK6VlhOHZjCs-w/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 12:02:27 INFO - Downloaded 63641090 bytes. 12:02:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 12:02:27 INFO - mkdir: /builds/slave/test/properties 12:02:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:02:27 INFO - Writing to file /builds/slave/test/properties/build_url 12:02:27 INFO - Contents: 12:02:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 12:02:28 INFO - Running post-action listener: _resource_record_post_action 12:02:28 INFO - Running post-action listener: set_extra_try_arguments 12:02:28 INFO - ##### 12:02:28 INFO - ##### Finished download-and-extract step (success) 12:02:28 INFO - ##### 12:02:28 INFO - ##### 12:02:28 INFO - ##### Running populate-webroot step. 12:02:28 INFO - ##### 12:02:28 INFO - Running pre-action listener: _resource_record_pre_action 12:02:28 INFO - Running main action method: populate_webroot 12:02:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:02:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:02:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:02:28 INFO - '--tppagecycles', 12:02:28 INFO - '7'], 12:02:28 INFO - 'tests': ['tsvgm']}}, 12:02:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:02:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:02:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:02:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:02:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:02:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:02:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:02:28 INFO - 'talos_options': ['--disable-e10s'], 12:02:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:02:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:02:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:02:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:02:28 INFO - 'talos_options': ['--disable-e10s'], 12:02:28 INFO - 'tests': ['damp', 'tps']}, 12:02:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:02:28 INFO - 'tests': ['damp', 'tps']}, 12:02:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:02:28 INFO - 'tests': ['dromaeo_dom']}, 12:02:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:02:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:02:28 INFO - 'tests': ['a11yr', 12:02:28 INFO - 'ts_paint', 12:02:28 INFO - 'tpaint', 12:02:28 INFO - 'sessionrestore', 12:02:28 INFO - 'sessionrestore_no_auto_restore', 12:02:28 INFO - 'tabpaint']}, 12:02:28 INFO - 'other-e10s': {'tests': ['a11yr', 12:02:28 INFO - 'ts_paint', 12:02:28 INFO - 'tpaint', 12:02:28 INFO - 'sessionrestore', 12:02:28 INFO - 'sessionrestore_no_auto_restore', 12:02:28 INFO - 'tabpaint']}, 12:02:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:02:28 INFO - 'ts_paint', 12:02:28 INFO - 'tpaint', 12:02:28 INFO - 'sessionrestore', 12:02:28 INFO - 'sessionrestore_no_auto_restore', 12:02:28 INFO - 'tabpaint']}, 12:02:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:02:28 INFO - 'ts_paint', 12:02:28 INFO - 'tpaint', 12:02:28 INFO - 'sessionrestore', 12:02:28 INFO - 'sessionrestore_no_auto_restore', 12:02:28 INFO - 'tabpaint']}, 12:02:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:02:28 INFO - 'tests': ['a11yr', 12:02:28 INFO - 'ts_paint', 12:02:28 INFO - 'tpaint', 12:02:28 INFO - 'sessionrestore', 12:02:28 INFO - 'sessionrestore_no_auto_restore', 12:02:28 INFO - 'tabpaint']}, 12:02:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:02:28 INFO - 'tests': ['a11yr', 12:02:28 INFO - 'ts_paint', 12:02:28 INFO - 'tpaint', 12:02:28 INFO - 'sessionrestore', 12:02:28 INFO - 'sessionrestore_no_auto_restore', 12:02:28 INFO - 'tabpaint']}, 12:02:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:02:28 INFO - 'tests': ['tsvgx', 12:02:28 INFO - 'tsvgr_opacity', 12:02:28 INFO - 'tart', 12:02:28 INFO - 'tscrollx', 12:02:28 INFO - 'cart']}, 12:02:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:02:28 INFO - 'tsvgr_opacity', 12:02:28 INFO - 'tart', 12:02:28 INFO - 'tscrollx', 12:02:28 INFO - 'cart']}, 12:02:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:02:28 INFO - 'talos_options': ['--disable-e10s'], 12:02:28 INFO - 'tests': ['tp5o']}, 12:02:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:02:28 INFO - 'tests': ['tp5o']}, 12:02:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:02:28 INFO - 'talos_options': ['--disable-e10s', 12:02:28 INFO - '--xperf_path', 12:02:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:02:28 INFO - 'tests': ['tp5n']}, 12:02:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:02:28 INFO - 'talos_options': ['--xperf_path', 12:02:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:02:28 INFO - 'tests': ['tp5n']}}, 12:02:28 INFO - 'talos.zip': {'path': '', 12:02:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:02:28 INFO - Running post-action listener: _resource_record_post_action 12:02:28 INFO - ##### 12:02:28 INFO - ##### Finished populate-webroot step (success) 12:02:28 INFO - ##### 12:02:28 INFO - ##### 12:02:28 INFO - ##### Running create-virtualenv step. 12:02:28 INFO - ##### 12:02:28 INFO - Running pre-action listener: _resource_record_pre_action 12:02:28 INFO - Running main action method: create_virtualenv 12:02:28 INFO - Creating virtualenv /builds/slave/test/build/venv 12:02:28 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 12:02:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:02:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:02:28 INFO - Using real prefix '/usr' 12:02:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:02:29 INFO - Installing distribute.............................................................................................................................................................................................done. 12:02:32 INFO - Installing pip.................done. 12:02:32 INFO - Return code: 0 12:02:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:02:32 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')]} 12:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:32 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')]} 12:02:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:32 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 0x7fe5da968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188c1c0>, '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 0x188d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x188d550>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', '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 12:02:32 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 12:02:32 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 12:02:32 INFO - Using env: {'DISPLAY': ':0', 12:02:32 INFO - 'HOME': '/home/cltbld', 12:02:32 INFO - 'LANG': 'en_US.UTF-8', 12:02:32 INFO - 'LANGUAGE': 'en_US:en', 12:02:32 INFO - 'LOGNAME': 'cltbld', 12:02:32 INFO - 'MAIL': '/var/mail/cltbld', 12:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:32 INFO - 'MOZ_NO_REMOTE': '1', 12:02:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:32 INFO - 'NO_EM_RESTART': '1', 12:02:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:32 INFO - 'PWD': '/builds/slave/test', 12:02:32 INFO - 'SHELL': '/bin/bash', 12:02:32 INFO - 'SHLVL': '1', 12:02:32 INFO - 'TERM': 'linux', 12:02:32 INFO - 'TMOUT': '86400', 12:02:32 INFO - 'USER': 'cltbld', 12:02:32 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', 12:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:32 INFO - '_': '/tools/buildbot/bin/python'} 12:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:32 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 12:02:32 INFO - Cleaning up... 12:02:32 INFO - Return code: 0 12:02:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:02:32 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')]} 12:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:32 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')]} 12:02:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:32 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 0x7fe5da968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188c1c0>, '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 0x188d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x188d550>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', '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 12:02:32 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 12:02:32 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 12:02:32 INFO - Using env: {'DISPLAY': ':0', 12:02:32 INFO - 'HOME': '/home/cltbld', 12:02:32 INFO - 'LANG': 'en_US.UTF-8', 12:02:32 INFO - 'LANGUAGE': 'en_US:en', 12:02:32 INFO - 'LOGNAME': 'cltbld', 12:02:32 INFO - 'MAIL': '/var/mail/cltbld', 12:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:32 INFO - 'MOZ_NO_REMOTE': '1', 12:02:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:32 INFO - 'NO_EM_RESTART': '1', 12:02:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:32 INFO - 'PWD': '/builds/slave/test', 12:02:32 INFO - 'SHELL': '/bin/bash', 12:02:32 INFO - 'SHLVL': '1', 12:02:32 INFO - 'TERM': 'linux', 12:02:32 INFO - 'TMOUT': '86400', 12:02:32 INFO - 'USER': 'cltbld', 12:02:32 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', 12:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:32 INFO - '_': '/tools/buildbot/bin/python'} 12:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:32 INFO - Downloading/unpacking psutil>=3.1.1 12:02:32 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 12:02:32 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 12:02:32 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 12:02:32 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 12:02:32 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 12:02:32 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 12:02:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:02:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:02:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:02:34 INFO - Installing collected packages: psutil 12:02:34 INFO - Running setup.py install for psutil 12:02:34 INFO - building 'psutil._psutil_linux' extension 12:02:34 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 12:02:36 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 12:02:36 INFO - building 'psutil._psutil_posix' extension 12:02:36 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 12:02:36 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 12:02:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:02:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:02:37 INFO - Successfully installed psutil 12:02:37 INFO - Cleaning up... 12:02:37 INFO - Return code: 0 12:02:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:02:37 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')]} 12:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:37 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')]} 12:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:37 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 0x7fe5da968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188c1c0>, '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 0x188d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x188d550>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', '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 12:02:37 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 12:02:37 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 12:02:37 INFO - Using env: {'DISPLAY': ':0', 12:02:37 INFO - 'HOME': '/home/cltbld', 12:02:37 INFO - 'LANG': 'en_US.UTF-8', 12:02:37 INFO - 'LANGUAGE': 'en_US:en', 12:02:37 INFO - 'LOGNAME': 'cltbld', 12:02:37 INFO - 'MAIL': '/var/mail/cltbld', 12:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:37 INFO - 'MOZ_NO_REMOTE': '1', 12:02:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:37 INFO - 'NO_EM_RESTART': '1', 12:02:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:37 INFO - 'PWD': '/builds/slave/test', 12:02:37 INFO - 'SHELL': '/bin/bash', 12:02:37 INFO - 'SHLVL': '1', 12:02:37 INFO - 'TERM': 'linux', 12:02:37 INFO - 'TMOUT': '86400', 12:02:37 INFO - 'USER': 'cltbld', 12:02:37 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', 12:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:37 INFO - '_': '/tools/buildbot/bin/python'} 12:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:02:37 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 12:02:37 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 12:02:37 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 12:02:37 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 12:02:37 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 12:02:37 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 12:02:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:02:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:02:39 INFO - Installing collected packages: mozsystemmonitor 12:02:39 INFO - Running setup.py install for mozsystemmonitor 12:02:39 INFO - Successfully installed mozsystemmonitor 12:02:39 INFO - Cleaning up... 12:02:39 INFO - Return code: 0 12:02:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:02:39 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')]} 12:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:39 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')]} 12:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:39 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 0x7fe5da968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188c1c0>, '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 0x188d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x188d550>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', '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 12:02:39 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 12:02:39 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 12:02:39 INFO - Using env: {'DISPLAY': ':0', 12:02:39 INFO - 'HOME': '/home/cltbld', 12:02:39 INFO - 'LANG': 'en_US.UTF-8', 12:02:39 INFO - 'LANGUAGE': 'en_US:en', 12:02:39 INFO - 'LOGNAME': 'cltbld', 12:02:39 INFO - 'MAIL': '/var/mail/cltbld', 12:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:39 INFO - 'MOZ_NO_REMOTE': '1', 12:02:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:39 INFO - 'NO_EM_RESTART': '1', 12:02:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:39 INFO - 'PWD': '/builds/slave/test', 12:02:39 INFO - 'SHELL': '/bin/bash', 12:02:39 INFO - 'SHLVL': '1', 12:02:39 INFO - 'TERM': 'linux', 12:02:39 INFO - 'TMOUT': '86400', 12:02:39 INFO - 'USER': 'cltbld', 12:02:39 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', 12:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:39 INFO - '_': '/tools/buildbot/bin/python'} 12:02:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:39 INFO - Downloading/unpacking jsonschema==2.5.1 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:02:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02:41 INFO - Downloading functools32-3.2.3-2.tar.gz 12:02:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:02:41 INFO - warning: no files found matching '*.txt' 12:02:41 INFO - no previously-included directories found matching 'build' 12:02:41 INFO - no previously-included directories found matching 'dist' 12:02:41 INFO - no previously-included directories found matching '.git*' 12:02:41 INFO - Installing collected packages: jsonschema, functools32 12:02:41 INFO - Running setup.py install for functools32 12:02:41 INFO - warning: no files found matching '*.txt' 12:02:41 INFO - no previously-included directories found matching 'build' 12:02:41 INFO - no previously-included directories found matching 'dist' 12:02:41 INFO - no previously-included directories found matching '.git*' 12:02:41 INFO - Successfully installed jsonschema functools32 12:02:41 INFO - Cleaning up... 12:02:41 INFO - Return code: 0 12:02:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:02: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')]} 12:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02: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')]} 12:02:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02: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', '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 0x7fe5da968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188c1c0>, '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 0x188d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x188d550>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', '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 12:02: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:02: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 blobuploader==1.2.4 12:02:42 INFO - Using env: {'DISPLAY': ':0', 12:02:42 INFO - 'HOME': '/home/cltbld', 12:02:42 INFO - 'LANG': 'en_US.UTF-8', 12:02:42 INFO - 'LANGUAGE': 'en_US:en', 12:02:42 INFO - 'LOGNAME': 'cltbld', 12:02:42 INFO - 'MAIL': '/var/mail/cltbld', 12:02:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:42 INFO - 'MOZ_NO_REMOTE': '1', 12:02:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:42 INFO - 'NO_EM_RESTART': '1', 12:02:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:42 INFO - 'PWD': '/builds/slave/test', 12:02:42 INFO - 'SHELL': '/bin/bash', 12:02:42 INFO - 'SHLVL': '1', 12:02:42 INFO - 'TERM': 'linux', 12:02:42 INFO - 'TMOUT': '86400', 12:02:42 INFO - 'USER': 'cltbld', 12:02:42 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', 12:02:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:42 INFO - '_': '/tools/buildbot/bin/python'} 12:02:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:42 INFO - Downloading/unpacking blobuploader==1.2.4 12:02:42 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 12:02:42 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 12:02:42 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 12:02:42 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 12:02:42 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 12:02:42 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 12:02:44 INFO - Downloading blobuploader-1.2.4.tar.gz 12:02:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:02:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:02:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02:44 INFO - Downloading docopt-0.6.1.tar.gz 12:02:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:02:44 INFO - Installing collected packages: blobuploader, requests, docopt 12:02:44 INFO - Running setup.py install for blobuploader 12:02:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:02:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:02:44 INFO - Running setup.py install for requests 12:02:45 INFO - Running setup.py install for docopt 12:02:45 INFO - Successfully installed blobuploader requests docopt 12:02:45 INFO - Cleaning up... 12:02:45 INFO - Return code: 0 12:02:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:45 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')]} 12:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:45 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')]} 12:02:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:45 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 0x7fe5da968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188c1c0>, '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 0x188d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x188d550>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', '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 12:02:45 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 12:02:45 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 12:02:45 INFO - Using env: {'DISPLAY': ':0', 12:02:45 INFO - 'HOME': '/home/cltbld', 12:02:45 INFO - 'LANG': 'en_US.UTF-8', 12:02:45 INFO - 'LANGUAGE': 'en_US:en', 12:02:45 INFO - 'LOGNAME': 'cltbld', 12:02:45 INFO - 'MAIL': '/var/mail/cltbld', 12:02:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:45 INFO - 'MOZ_NO_REMOTE': '1', 12:02:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:45 INFO - 'NO_EM_RESTART': '1', 12:02:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:45 INFO - 'PWD': '/builds/slave/test', 12:02:45 INFO - 'SHELL': '/bin/bash', 12:02:45 INFO - 'SHLVL': '1', 12:02:45 INFO - 'TERM': 'linux', 12:02:45 INFO - 'TMOUT': '86400', 12:02:45 INFO - 'USER': 'cltbld', 12:02:45 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', 12:02:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:45 INFO - '_': '/tools/buildbot/bin/python'} 12:02:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:02:45 INFO - Running setup.py (path:/tmp/pip-xQRhDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:02:45 INFO - Running setup.py (path:/tmp/pip-37orcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:02:45 INFO - Running setup.py (path:/tmp/pip-JSztkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:02:45 INFO - Running setup.py (path:/tmp/pip-rceh2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:02:45 INFO - Running setup.py (path:/tmp/pip-I5__ei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:02:46 INFO - Running setup.py (path:/tmp/pip-OHAnvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:02:46 INFO - Running setup.py (path:/tmp/pip-hKQoTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:02:46 INFO - Running setup.py (path:/tmp/pip-dmxrDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:02:46 INFO - Running setup.py (path:/tmp/pip-_2E90F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:02:46 INFO - Running setup.py (path:/tmp/pip-6haRs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:02:46 INFO - Running setup.py (path:/tmp/pip-AukkqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:02:46 INFO - Running setup.py (path:/tmp/pip-8nkQdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:02:46 INFO - Running setup.py (path:/tmp/pip-j8Uqmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:02:46 INFO - Running setup.py (path:/tmp/pip-QiCEWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:46 INFO - Running setup.py (path:/tmp/pip-DbwfUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:02:46 INFO - Running setup.py (path:/tmp/pip-RbW4AU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:02:46 INFO - Running setup.py (path:/tmp/pip-47QNQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:02:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:02:46 INFO - Running setup.py install for manifestparser 12:02:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:02:47 INFO - Running setup.py install for mozcrash 12:02:47 INFO - Running setup.py install for mozdebug 12:02:47 INFO - Running setup.py install for mozdevice 12:02:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:02:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:02:47 INFO - Running setup.py install for mozfile 12:02:47 INFO - Running setup.py install for mozhttpd 12:02:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:02:47 INFO - Running setup.py install for mozinfo 12:02:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:02:47 INFO - Running setup.py install for mozInstall 12:02:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:02:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:02:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:02:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:02:47 INFO - Running setup.py install for mozleak 12:02:47 INFO - Running setup.py install for mozlog 12:02:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:02:47 INFO - Running setup.py install for moznetwork 12:02:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:02:48 INFO - Running setup.py install for mozprocess 12:02:48 INFO - Running setup.py install for mozprofile 12:02:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:02:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:02:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:02:48 INFO - Running setup.py install for mozrunner 12:02:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:02:48 INFO - Running setup.py install for mozscreenshot 12:02:48 INFO - Running setup.py install for moztest 12:02:48 INFO - Running setup.py install for mozversion 12:02:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:02:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:02:48 INFO - Cleaning up... 12:02:48 INFO - Return code: 0 12:02:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:48 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')]} 12:02:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:48 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')]} 12:02:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:48 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 0x7fe5da968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188c1c0>, '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 0x188d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x188d550>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', '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 12:02:48 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 12:02:48 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 12:02:48 INFO - Using env: {'DISPLAY': ':0', 12:02:48 INFO - 'HOME': '/home/cltbld', 12:02:48 INFO - 'LANG': 'en_US.UTF-8', 12:02:48 INFO - 'LANGUAGE': 'en_US:en', 12:02:48 INFO - 'LOGNAME': 'cltbld', 12:02:48 INFO - 'MAIL': '/var/mail/cltbld', 12:02:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:48 INFO - 'MOZ_NO_REMOTE': '1', 12:02:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:48 INFO - 'NO_EM_RESTART': '1', 12:02:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:48 INFO - 'PWD': '/builds/slave/test', 12:02:48 INFO - 'SHELL': '/bin/bash', 12:02:48 INFO - 'SHLVL': '1', 12:02:48 INFO - 'TERM': 'linux', 12:02:48 INFO - 'TMOUT': '86400', 12:02:48 INFO - 'USER': 'cltbld', 12:02:48 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', 12:02:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:48 INFO - '_': '/tools/buildbot/bin/python'} 12:02:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:02:48 INFO - Running setup.py (path:/tmp/pip-BuIEua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:02:49 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)) 12:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:02:49 INFO - Running setup.py (path:/tmp/pip-bmQlQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:02:49 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)) 12:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:02:49 INFO - Running setup.py (path:/tmp/pip-O0Vlyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:02:49 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)) 12:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:02:49 INFO - Running setup.py (path:/tmp/pip-VuVNgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:02:49 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)) 12:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:02:49 INFO - Running setup.py (path:/tmp/pip-pJ796R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:02:49 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)) 12:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:02:49 INFO - Running setup.py (path:/tmp/pip-e86j2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:02:49 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)) 12:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:02:49 INFO - Running setup.py (path:/tmp/pip-CtHfUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:02:49 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)) 12:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:02:49 INFO - Running setup.py (path:/tmp/pip-GEfWw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:02:49 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)) 12:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:02:49 INFO - Running setup.py (path:/tmp/pip-wXv_qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:02:49 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)) 12:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:02:49 INFO - Running setup.py (path:/tmp/pip-e5y77i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:02:49 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)) 12:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:02:49 INFO - Running setup.py (path:/tmp/pip-4HYHRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:02:49 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)) 12:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:02:49 INFO - Running setup.py (path:/tmp/pip-kj7adA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:02:49 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)) 12:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:02:49 INFO - Running setup.py (path:/tmp/pip-ce5igx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:02:49 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)) 12:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:02:49 INFO - Running setup.py (path:/tmp/pip-g39Eyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:02:49 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)) 12:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:49 INFO - Running setup.py (path:/tmp/pip-CrgJoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:50 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)) 12:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:02:50 INFO - Running setup.py (path:/tmp/pip-5f0KaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:02:50 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)) 12:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:02:50 INFO - Running setup.py (path:/tmp/pip-CCdXX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:02:50 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)) 12:02:50 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)) 12:02:50 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)) 12:02:50 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)) 12:02:50 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)) 12:02:50 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)) 12:02:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:02:50 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 12:02:50 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 12:02:50 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 12:02:50 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 12:02:50 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 12:02:50 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 12:02:52 INFO - Downloading blessings-1.6.tar.gz 12:02:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:02:52 INFO - Installing collected packages: blessings 12:02:52 INFO - Running setup.py install for blessings 12:02:52 INFO - Successfully installed blessings 12:02:52 INFO - Cleaning up... 12:02:52 INFO - Return code: 0 12:02:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:02:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:02:52 INFO - Reading from file tmpfile_stdout 12:02:52 INFO - Current package versions: 12:02:52 INFO - argparse == 1.2.1 12:02:52 INFO - blessings == 1.6 12:02:52 INFO - blobuploader == 1.2.4 12:02:52 INFO - docopt == 0.6.1 12:02:52 INFO - functools32 == 3.2.3-2 12:02:52 INFO - jsonschema == 2.5.1 12:02:52 INFO - manifestparser == 1.1 12:02:52 INFO - mozInstall == 1.12 12:02:52 INFO - mozcrash == 0.17 12:02:52 INFO - mozdebug == 0.1 12:02:52 INFO - mozdevice == 0.48 12:02:52 INFO - mozfile == 1.2 12:02:52 INFO - mozhttpd == 0.7 12:02:52 INFO - mozinfo == 0.9 12:02:52 INFO - mozleak == 0.1 12:02:52 INFO - mozlog == 3.2 12:02:52 INFO - moznetwork == 0.27 12:02:52 INFO - mozprocess == 0.23 12:02:52 INFO - mozprofile == 0.28 12:02:52 INFO - mozrunner == 6.12 12:02:52 INFO - mozscreenshot == 0.1 12:02:52 INFO - mozsystemmonitor == 0.3 12:02:52 INFO - moztest == 0.7 12:02:52 INFO - mozversion == 1.4 12:02:52 INFO - psutil == 3.1.1 12:02:52 INFO - requests == 1.2.3 12:02:52 INFO - wsgiref == 0.1.2 12:02:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:52 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')]} 12:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:52 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')]} 12:02:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:52 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 0x7fe5da968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188c1c0>, '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 0x188d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x188d550>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', '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 12:02:52 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 12:02:52 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 12:02:52 INFO - Using env: {'DISPLAY': ':0', 12:02:52 INFO - 'HOME': '/home/cltbld', 12:02:52 INFO - 'LANG': 'en_US.UTF-8', 12:02:52 INFO - 'LANGUAGE': 'en_US:en', 12:02:52 INFO - 'LOGNAME': 'cltbld', 12:02:52 INFO - 'MAIL': '/var/mail/cltbld', 12:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:52 INFO - 'MOZ_NO_REMOTE': '1', 12:02:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:52 INFO - 'NO_EM_RESTART': '1', 12:02:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:52 INFO - 'PWD': '/builds/slave/test', 12:02:52 INFO - 'SHELL': '/bin/bash', 12:02:52 INFO - 'SHLVL': '1', 12:02:52 INFO - 'TERM': 'linux', 12:02:52 INFO - 'TMOUT': '86400', 12:02:52 INFO - 'USER': 'cltbld', 12:02:52 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', 12:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:52 INFO - '_': '/tools/buildbot/bin/python'} 12:02:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:52 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)) 12:02:52 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)) 12:02:52 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)) 12:02:52 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)) 12:02:52 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)) 12:02:52 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)) 12:02:52 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)) 12:02:52 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)) 12:02:52 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)) 12:02:52 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)) 12:02:52 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)) 12:02:52 INFO - Cleaning up... 12:02:52 INFO - Return code: 0 12:02:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:02:52 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')]} 12:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:52 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')]} 12:02:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:52 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 0x7fe5da968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188c1c0>, '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 0x188d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x188d550>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', '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 12:02:52 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 12:02:52 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 12:02:52 INFO - Using env: {'DISPLAY': ':0', 12:02:52 INFO - 'HOME': '/home/cltbld', 12:02:52 INFO - 'LANG': 'en_US.UTF-8', 12:02:52 INFO - 'LANGUAGE': 'en_US:en', 12:02:52 INFO - 'LOGNAME': 'cltbld', 12:02:52 INFO - 'MAIL': '/var/mail/cltbld', 12:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:52 INFO - 'MOZ_NO_REMOTE': '1', 12:02:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:52 INFO - 'NO_EM_RESTART': '1', 12:02:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:52 INFO - 'PWD': '/builds/slave/test', 12:02:52 INFO - 'SHELL': '/bin/bash', 12:02:52 INFO - 'SHLVL': '1', 12:02:52 INFO - 'TERM': 'linux', 12:02:52 INFO - 'TMOUT': '86400', 12:02:52 INFO - 'USER': 'cltbld', 12:02:52 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', 12:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:52 INFO - '_': '/tools/buildbot/bin/python'} 12:02:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:02:53 INFO - Cleaning up... 12:02:53 INFO - Return code: 0 12:02:53 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:02:53 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')]} 12:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:53 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')]} 12:02:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:53 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 0x7fe5da968f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188c1c0>, '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 0x188d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x188d550>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', '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 12:02:53 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 12:02:53 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 12:02:53 INFO - Using env: {'DISPLAY': ':0', 12:02:53 INFO - 'HOME': '/home/cltbld', 12:02:53 INFO - 'LANG': 'en_US.UTF-8', 12:02:53 INFO - 'LANGUAGE': 'en_US:en', 12:02:53 INFO - 'LOGNAME': 'cltbld', 12:02:53 INFO - 'MAIL': '/var/mail/cltbld', 12:02:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:53 INFO - 'MOZ_NO_REMOTE': '1', 12:02:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:53 INFO - 'NO_EM_RESTART': '1', 12:02:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:53 INFO - 'PWD': '/builds/slave/test', 12:02:53 INFO - 'SHELL': '/bin/bash', 12:02:53 INFO - 'SHLVL': '1', 12:02:53 INFO - 'TERM': 'linux', 12:02:53 INFO - 'TMOUT': '86400', 12:02:53 INFO - 'USER': 'cltbld', 12:02:53 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', 12:02:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:53 INFO - '_': '/tools/buildbot/bin/python'} 12:02:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:53 INFO - Downloading/unpacking flake8 12:02:53 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 12:02:53 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 12:02:53 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 12:02:53 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 12:02:53 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 12:02:53 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 12:02:55 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:02:55 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:02:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:02:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:02:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:02:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:02:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:02:55 INFO - no previously-included directories found matching 'docs/_build' 12:02:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:02:55 INFO - Running setup.py install for pep8 12:02:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:02:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:02:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:02:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:02:56 INFO - no previously-included directories found matching 'docs/_build' 12:02:56 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:02:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:02:56 INFO - Cleaning up... 12:02:56 INFO - Return code: 0 12:02:56 INFO - Running post-action listener: _resource_record_post_action 12:02:56 INFO - Running post-action listener: _start_resource_monitoring 12:02:56 INFO - Starting resource monitoring. 12:02:56 INFO - ##### 12:02:56 INFO - ##### Finished create-virtualenv step (success) 12:02:56 INFO - ##### 12:02:56 INFO - ##### 12:02:56 INFO - ##### Running install step. 12:02:56 INFO - ##### 12:02:56 INFO - Running pre-action listener: _resource_record_pre_action 12:02:56 INFO - Running main action method: install 12:02:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:02:56 INFO - Reading from file tmpfile_stdout 12:02:56 INFO - Detecting whether we're running mozinstall >=1.0... 12:02:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:02:56 INFO - Reading from file tmpfile_stdout 12:02:56 INFO - Output received: 12:02:56 INFO - Usage: mozinstall [options] installer 12:02:56 INFO - Options: 12:02:56 INFO - -h, --help show this help message and exit 12:02:56 INFO - -d DEST, --destination=DEST 12:02:56 INFO - Directory to install application into. [default: 12:02:56 INFO - "/builds/slave/test"] 12:02:56 INFO - --app=APP Application being installed. [default: firefox] 12:02:56 INFO - mkdir: /builds/slave/test/build/application 12:02:56 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'] 12:02:56 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 12:03:12 INFO - Reading from file tmpfile_stdout 12:03:12 INFO - Output received: 12:03:12 INFO - /builds/slave/test/build/application/firefox/firefox 12:03:12 INFO - Running post-action listener: _resource_record_post_action 12:03:12 INFO - ##### 12:03:12 INFO - ##### Finished install step (success) 12:03:12 INFO - ##### 12:03:12 INFO - ##### 12:03:12 INFO - ##### Running run-tests step. 12:03:12 INFO - ##### 12:03:12 INFO - Running pre-action listener: _resource_record_pre_action 12:03:12 INFO - Running main action method: run_tests 12:03:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:03:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:03:12 INFO - Python 2.7.3 12:03:12 INFO - Return code: 0 12:03:12 INFO - grabbing minidump binary from tooltool 12:03:12 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')]} 12:03:12 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 0x188c1c0>, '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 0x188d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x188d550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:03:12 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 12:03:12 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 12:03:12 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 12:03:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:03:12 INFO - Return code: 0 12:03:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:03:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:03:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:03:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:03:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:03:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:03:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:03:13 INFO - Return code: 0 12:03:13 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:03:13 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:03:13 INFO - Using env: {'DISPLAY': ':0', 12:03:13 INFO - 'HOME': '/home/cltbld', 12:03:13 INFO - 'LANG': 'en_US.UTF-8', 12:03:13 INFO - 'LANGUAGE': 'en_US:en', 12:03:13 INFO - 'LOGNAME': 'cltbld', 12:03:13 INFO - 'MAIL': '/var/mail/cltbld', 12:03:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:03:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:03:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:13 INFO - 'MOZ_NO_REMOTE': '1', 12:03:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:03:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:03:13 INFO - 'NO_EM_RESTART': '1', 12:03:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:03:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:13 INFO - 'PWD': '/builds/slave/test', 12:03:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:03:13 INFO - 'SHELL': '/bin/bash', 12:03:13 INFO - 'SHLVL': '1', 12:03:13 INFO - 'TERM': 'linux', 12:03:13 INFO - 'TMOUT': '86400', 12:03:13 INFO - 'USER': 'cltbld', 12:03:13 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961', 12:03:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:13 INFO - '_': '/tools/buildbot/bin/python'} 12:03:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:03:13 INFO - mozversion application_buildid: 20160722101926 12:03:13 INFO - mozversion application_changeset: 3ecc86179a9af4ddb53ab1fe478511a9832e9801 12:03:13 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:03:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:03:13 INFO - mozversion application_name: Firefox 12:03:13 INFO - mozversion application_remotingname: firefox-dev 12:03:13 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:03:13 INFO - mozversion application_vendor: Mozilla 12:03:13 INFO - mozversion application_version: 49.0a2 12:03:13 INFO - mozversion platform_buildid: 20160722101926 12:03:13 INFO - mozversion platform_changeset: 3ecc86179a9af4ddb53ab1fe478511a9832e9801 12:03:13 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:03:13 INFO - mozversion platform_version: 49.0a2 12:03:13 INFO - using testdate: 1469214193 12:03:13 INFO - actual date: 1469214193 12:03:13 INFO - starting webserver on 'localhost:59422' 12:03:13 INFO - SUITE-START | Running 2 tests 12:03:13 INFO - TEST-START | dromaeo_css 12:03:13 INFO - operating with platform_type : linux_ 12:03:13 INFO - Initialising browser for dromaeo_css test... 12:03:13 INFO - TEST-INFO | started process 3334 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx_qUET/profile http://localhost:59422/getInfo.html) 12:03:19 INFO - PROCESS | 3334 | __metrics Screen width/height:1600/1200 12:03:19 INFO - PROCESS | 3334 | colorDepth:24 12:03:19 INFO - PROCESS | 3334 | Browser inner width/height: 1024/697 12:03:19 INFO - PROCESS | 3334 | __metrics 12:03:22 ERROR - PROCESS | 3334 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 12:03:23 INFO - TEST-INFO | 3334: exit 0 12:03:23 INFO - Browser initialized. 12:03:23 INFO - Running cycle 1/1 for dromaeo_css test... 12:03:23 INFO - TEST-INFO | started process 3468 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx_qUET/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:03:35 INFO - PROCESS | 3468 | RSS: Main: 156217344 12:03:35 INFO - PROCESS | 3468 | 12:04:24 INFO - PROCESS | 3468 | 1469214264757 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] 12:06:32 INFO - PROCESS | 3468 | Cycle 1(1): loaded http://localhost:59422/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59422/tests/dromaeo/cssquery-ext.html) 12:06:33 INFO - PROCESS | 3468 | RSS: Main: 160407552 12:06:33 INFO - PROCESS | 3468 | 12:09:35 INFO - PROCESS | 3468 | Cycle 1(1): loaded http://localhost:59422/tests/dromaeo/cssquery-ext.html (next: http://localhost:59422/tests/dromaeo/cssquery-jquery.html) 12:09:36 INFO - PROCESS | 3468 | RSS: Main: 157577216 12:09:36 INFO - PROCESS | 3468 | 12:12:38 INFO - PROCESS | 3468 | Cycle 1(1): loaded http://localhost:59422/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59422/tests/dromaeo/cssquery-mootools.html) 12:12:38 INFO - PROCESS | 3468 | RSS: Main: 157446144 12:12:38 INFO - PROCESS | 3468 | 12:15:40 INFO - PROCESS | 3468 | Cycle 1(1): loaded http://localhost:59422/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59422/tests/dromaeo/cssquery-prototype.html) 12:15:41 INFO - PROCESS | 3468 | RSS: Main: 157454336 12:15:41 INFO - PROCESS | 3468 | 12:18:43 INFO - PROCESS | 3468 | Cycle 1(1): loaded http://localhost:59422/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59422/tests/dromaeo/cssquery-yui.html) 12:18:44 INFO - PROCESS | 3468 | RSS: Main: 159096832 12:18:44 INFO - PROCESS | 3468 | 12:21:46 INFO - PROCESS | 3468 | Cycle 1(1): loaded http://localhost:59422/tests/dromaeo/cssquery-yui.html (next: http://localhost:59422/tests/dromaeo/cssquery-dojo.html) 12:21:46 INFO - PROCESS | 3468 | RSS: Main: 159772672 12:21:46 INFO - PROCESS | 3468 | 12:21:46 INFO - PROCESS | 3468 | __start_tp_report 12:21:46 INFO - PROCESS | 3468 | _x_x_mozilla_page_load 12:21:46 INFO - PROCESS | 3468 | _x_x_mozilla_page_load_details 12:21:46 INFO - PROCESS | 3468 | |i|pagename|runs| 12:21:46 INFO - PROCESS | 3468 | |0;dojo.html;3360.495925611158;3396.847141878616;3411.710004649605;3425.777324473909;3429.7770644908096;5665;5738.340090889549;5742.425757424256;5760.913586296209;5775.7689692412305;5706.629069110515;5756.453136951983;5769.365369809318;5788.6816225107605;5790.392008839064;5193.68837869729;5214.843554693346;5279.683219006853;5302.416734159239;5354;5102.591792656579;5350.117230656937;5360.678359298455;5373.355197376321;5386.596005299618;5053.570446512051;5175.171972484403;5211.5309622134;5263.105272103751;5297.284866543006;5210.661307015051;5239.502247286521;5270.341207349082;5271.657342272779;5280.392754833167;5192.299039629639;5257.08001099806;5287.629865909388;5288.471132930211;5291.894162116774;5448.809291674773;5465.562754979592;5563.109902415594;5597.3003374578175;5658.830235092953;6106.328303886613;6134.447899689049;6145.293291271516;6148.1392605035335;6174.228221472316;6160.2915664698685;6182.876342473171;6187.3503282155625;6190.721417536221;6197.938020619827;5523.088690366085;5625.8593535161535;5641.605087643867;5673.1490276458035;5684.772609095672;6167.876642467126;6172.321044685081;6178.227721534809;6188.907166392508;6220.34686357935;5752.884942301131;5976.73104710289;5989.041753319448;5997.070454079625;5999.730012149464;6051.515878729691;6070.908936365958;6088.086786981989;6124.153566208261;6186.381361863868;6076.878462430726;6108.365567233795;6111.6525128775675;6112.559453919505;6141.6007959482495;5846.181534585162;5952.494038006818;6115.4190351916495;6136.846578835565;6152.507799376129;4540.364348991144;4587.220172570671;4605.447346318463;4616.3537104805355;4617.045091141672;4133.132042271097;4135.81388837497;4143.1713657268665;4154.813033413504;4159.9376009359885;5688.317401911797;5744.856378590485;5763.394843541451;5782.826515204551;5817.941820581825;5858;5986.2217911671205;6065.332813390523;6112.837854638657;6116.296625887991;5788.363280039192;5820.272465941757;5820.796272130455;5826.6795326256615;5834.649921004753;5073.213651883964;5250.711210883438;5258.132408152688;5285.127953887642;5292.973535132377;6328.11406403104;6372.7132279047555;6381.361863813674;6397.936020639734;6400.23197216337;6103.694815259308;6131.908021379593;6134.631922084778;6143.754249829957;6153.4154255345675;6125.449118208347;6147.0779383092895;6183.84540386494;6211.3478084800445;6369.777057802955;5950.166976623363;5991.3409524953095;6004.249468816398;6006.849828754316;6015.969920150371;6100.176476175659;6102.0541816018585;6103.69481525913;6107.206063211754;6124.479419249414;5802.897449484584;5922.141289512911;5940;5980.5215582754145;6068.757249709962;5940.702964851826;5959.255093113361;5976.641401515749;5981.102834574848;6019.969900150471;5638.407967163389;5847.590668653153;5901.645901245939;5901.7639294427745;5906.822795316148;5909.911351329648;5931.6441013539325;5956.106584012433;6060.454559089703;6075.605085669594;5837.532997360286;5890.057590785454;5912.763489460545;5918.112283157561;5918.496927761188 12:21:46 INFO - PROCESS | 3468 | |1;ext.html;649.3474058571136;655.9537537627485;659.5284371674252;669.7488441834312;683.9589624622527;37969.24061518768;39483.2103357933;42753.14493710124;42865.35701964465;42956.78521607388;44643.10713785722;45379.77310113453;45444.54555454444;45719.77140114295;45772.3134152988;8704.390692651501;8839.646414143459;8883.067277935821;8884.822303553925;8912.598933048028;8902.777430564256;8944.418612790181;9003.594838232295;9010.684626038057;9011.27909767217;8908.777280568005;8928.642854285821;8951.641934322619;8959.5968181432;9008.054154313768;8343.249107580317;8353.248207661309;8953.328500362499;8964.462132272085;8974.865377019383;8711.651533938635;8776.824463510695;8803.339749518811;8808.64765409383;8827.867581986275;35967;40461.39307910384;40755;41030;41090.58909410927;3136.639286482061;3713.8885833425043;3724.553053633554;3753.512043434336;4014.45804816349;32439.189020274447;32578;32791.50812737811;32795.83602081998;33169.170770730685;4481.268653392125;4512.52618475062;4549.499555048942;4572.428446444194;4676.766161691936;323902;324039;326600;327221.3638931814;328411;38157.42763858545;38356.232875342335;38475.807620961714;38680;38728.225435491695;38666.80666596649;40409.19181616351;40538.59461405348;40791.388129178085;40855.591444085774;16815.2433140509;16816.327346905986;17019.40432084896;17026.659466810594;17043.488695339158;14057.648558786112;14057.929710351384;14084.929575352058;14087.647808804863;14091.295435228198;9113.407628504126;9216.216621587106;9230.400023998418;9246.121618446237;9264.768380790534;3486.4247399179358;3506.1935754776546;3527.276908233806;3557.2529768748855;3606.062423769839;1071.3732466507024;1144.822552504363;1170.584442522892;1215.8176273559036;1283.6213317071486;34352.31295374128;34523.13692157716;34560.135996600286;34732.30535389329;34829.6613119807;1189.655000049993;1198.0595232742255;1215.1858254969193;1246.4391024039219;1265.8923991460645;16827.57931051709;16987.660246794996;17070.317187312616;17103.657926841395;17204.053777042132;597422;599649;602907;632344;670275;60587.69706151441;67429;67781.32218677849;67842.66078669575;67921;1523.1546491697216;1575.59822245329;1577.6213708709827;1579.7156511827752;1582.9920850395674;34105.31789364248;34346.313073738886;34733;34900.301993960486;35131.47302790409;56610.433895662165;56944.430555693914;57068.429315706315;57247;57427.71286143542;497080;504611;505758;507918;508958;46871;46951.765241173576;47505;47955;48199.75900120477;51766.48233517767;52468;53045.469545304055;53784.73107634437;53844.46155538395;44238;44454;45077.32384014308;45546.31680524728;45747.54252457433;43530;43756.12487750291;44176.77911610421;44784.328235077155;45458;44160.558394415646;44167.77916110399;44269.55730442654;45276.320855187856;45308.32037519374;37833.62166378411;38813.611863881;39170.4124438128;39661.2067758649;40013.599864000986;29858.068512792062;30038.399232015676;30401.239969000068;30571.54142687906;30934 12:21:46 INFO - PROCESS | 3468 | |2;jquery.html;428.1394397261507;434.1924021320345;435.34045920430543;438.3889207163673;444.4311281559605;19360.51598710033;19826.70259946102;20161.19355225791;21277.57444851102;21295.893520532376;19220.231190752354;19809.801901980976;20412.795872041275;20414.183432662714;20444.693329600068;5784.739686714087;6029.336772954972;6064.332923378425;6069.78755743549;6078;5750.741216645261;5866.794662186824;5884.882302353951;5887.4112588741;5889.322727886283;5760.36635970043;5803.303603567557;5808.883822323551;5829.154772557998;5836.387179346171;5632.577556683265;5875.764969401219;5906.232189815318;5917.526597872203;5925.022371308729;5231.163013917755;5283.128283833163;5307.071262529042;5318.069337865858;5340.225667278223;23971.640425393634;24271.393215169763;24413.87793061041;24455.144069957467;24726.752732472625;2405.6632071510003;2427.320350301901;2428.3322086426197;2535.746425357468;2550.5281522918135;14687.265636718228;14978;15053.623659408493;15078.396864125532;15244.161571113695;2052.8563000589966;2061.2064355223197;2110.799474049963;2138.315738963532;2194.199117322182;70299.64850175717;72501.63749181273;72829.90755138677;83060.58469707673;87372;10933.507992140372;11859.229150105215;12022.218555793845;12168.634940951786;12207.633770986884;16858.915705421394;17029.82970170307;17086.48740537786;17146.22841972114;17158.48524544266;8556.74329770108;8777.736667899973;8832;8969.103089690978;9016.459012459274;8489.405741598152;8503.74488765338;8575.61409736551;8609.784755381166;8651.351148663749;5010.298558201855;5045.949540504548;5114.28400023997;5127.564157046618;5228.712420816815;2109.651907435256;2111.1766411099684;2125.3730149605904;2157.492989147549;2186.2457452178974;1059.2849826367326;1064.2656566968767;1080.9675709728563;1136.7612801311652;1144.4449442020614;19269;19815.504612384804;20054.49863753418;20074.197032118846;20514.58970820605;1017.3133135133755;1048.5910494906993;1066.3548553125402;1082.334364365925;1101.4492753623188;10914.617988370368;11271.943640281746;11452.484638191301;11513.194076414571;11527.942360288145;532588;555553;568849;571450;578937;82218.58890705508;89378.10621893828;95859.52070239604;98297;98688;1048.8846226915034;1082.1937656445998;1090.2695194219891;1091.1870656360902;1093.5461783359817;18578.535536611693;18612.162452689932;18790.248390064244;18962.715559266897;18981.810181898003;38273.808630956664;43590;44416.33375499305;45419.09161816811;46361.76819115883;63342.36657633365;63840.36159638344;64399;65290.673546631966;65559.72629716573;7617.200193979553;8371.121032291521;8896.110388961051;9045.638174472948;9079.59141838632;11527.423628818358;13348.733025339245;13407.195568265935;13439.328033598476;13672.794908076587;11881.524739010689;11908.285502869856;12103.878961210627;12153.696157595778;12286.938565307117;11613.709657258636;11790.587329443428;11835;12119.697007574881;12176.086793490347;11627.53489860396;11748.353840538852;11904.345261010727;11991.76016479683;12174;8073.83852322962;8080.63637136342;8195.795105122419;8317;8384.622691978753;11111.166662500184;11421.428928553705;11499.367534785668;11543.36511491876;11762.529498819951 12:21:46 INFO - PROCESS | 3468 | |3;mootools.html;1576.708308962842;1611.86299164571;1834.2846289946915;1890.5179179309273;1931.063434234396;8573.485590864553;9000.549972501365;9234.722958311253;9933.850992235122;9949;9285.396449230795;9534.90465095349;9586.664466743665;9588.76028099298;9596.56815443305;2341.8594884306954;2376.298991797415;2386.677798497198;2387.295747754408;2390.53384590005;2331.6036273833406;2402.231285988484;2411.1560953666253;2425.163318655061;2432.4891772727747;2386.331827088409;2396.8921398537022;2399.040383846458;2411.3127758588807;2422.600270955299;2291.7708229177115;2293.81649468043;2297.827662925279;2301.620232661609;2311.699479067727;2309.3187509684685;2331.347222777625;2341.3678306857055;2364.8581085134774;2373.2880135959144;9501.477418741966;9569.665061722806;9577.185939195177;9644.18024467921;9656.565454554562;5523.392426833045;5691.9146212806845;5723.255976723041;5726.856828579277;5733.627314224604;3573.94639080414;3627.6553727396117;3634.273145370936;3701.352263353929;3716.163863130801;1155.3760969076689;1160.8432861563665;1177.7526719388939;1179.710970812148;1194.0268681024936;13297.202167869958;13300.933495332558;13302.401391937387;13348.199108053548;13391.129576577492;4750.263351420135;4786.736729479912;4832.251001094765;4899.363082799213;4903.632227582945;10515.579376824995;10554.2084343673;10558.524866380878;10592.57629694819;10594.31136976094;5566.332040155207;5629.099344104923;5656.462636049569;5659.151127330851;5659.377468478464;4683.227267500893;4913.582345500672;5081.441041485434;5100.38795344561;5300.31095957523;4053.8783836484954;4061.268971585084;4126.896827579274;4147.419361289422;4154.709170358106;1150.9712257193469;1166.8016437205695;1172.7947609168361;1172.976540469203;1180.2859270141612;758.4577027425418;896.7489103051156;896.8296023755465;908.3823000359687;912.9680461183826;6498.187726534184;6638.800835974928;6656.667166641745;6819.147606549182;6897.793066208022;1044.5612842606124;1070.6252811515908;1087.6573879228176;1088.7495875948575;1096.0847692177122;4539.568740969603;4671.813127474863;4704.482506924235;4720.268358404453;4729.030548737501;11776.234544754563;11880;12082.275063496218;12216.328101954477;12223.205491643015;11602.88397116018;11978.52085916571;12098.274103553815;12114.697132571757;12192.81710774339;8776;8955.194032537103;8962.910370896338;8966.730998070068;8999.910000899907;6481.8703625928165;6586.80239592813;6591.670416479253;6617.371349721961;6623.635700036351;10871.945640271748;10928.508217130408;10933.617323393642;11221.66335009951;11234.719132021764;11146.386948717574;11236.775264494501;11239.325640461599;11362.431878406212;11458;7758.146603873456;7826.099998500245;8096.959515202387;8117.391195660467;8153.592320383838;8909.599068042084;8975.910240897507;9009.954950225207;9015.864762028443;9086.818263634823;8146.389020823581;8313.501189928624;8459.95770021146;8544.102869198645;8547.444416112932;8051.677932882619;8056.556889371141;8099.271065604124;8111.878321825059;8182.059063207807;7543.132539757778;7708.229177082471;7772.067351917806;7937.722179723682;7980.680772769024;5664.546836253007;5711.257536520226;5748.45389687979;5808.041673123887;5859.20900678403;5466.125419932791;5515.448455154453;5713.542916566582;5738.684372359393;5783.392743761844 12:21:46 INFO - PROCESS | 3468 | |4;prototype.html;342.8902751119643;352.16712474996626;354.2737388353874;371.64507894960315;372.70742467163285;5633.577481688874;5719.684472571306;5735.885282294357;5742.79900203493;5765.6540607563475;5686.4597863203035;5690.146478028298;5699.914501282474;5716.57125715572;5726.799562015331;4169.457970463835;4423.955760442403;4531.207038768219;4541.886452838672;4555.498895121534;4450.488193857699;4464.812242001412;4466.33005049242;4469.7988590513505;4481.260592002316;4460.843870464534;4474.552544745516;4498.460184777831;4512.842050528232;4515.932261016071;4343.087951530151;4501.5273396293405;4505.819767209307;4509.368688383629;4513.2101882170655;3884.281407939512;3885.4560361549406;3889.533256009297;3895.162540053902;3898.649121579043;5511.421300763443;5540.529055030287;5552.695631313;5574.498295153456;5599.776008959637;4896.762491614268;5063.873403164914;5084.288199652052;5086.92369614456;5122.513361230677;5982.880342393171;5994.580379373446;6013;6014.488768454687;6019.157317975443;4284.807183676742;4301.956980430218;4307.181635489247;4309.698321117523;4316.072044510414;5852.590318677694;6191.597546159485;6206.844828879269;6233.470155036827;6256.343083976162;4349.586789255016;4385.846495372647;4387.780610969438;4388.4733831940375;4394.08857147142;4449.310356894686;4458.08609235106;4462.7768611569945;4477.462704475483;4485.192665320274;4915.852524424273;5030.195168772979;5116.641835071584;5123.87190320245;5125.282460455539;5947.524198064145;5957.4042595740075;5977.731002104916;5979.611325263844;6035.6076855004285;1511.6598765277724;1522.87055600273;1531.4639876043295;1532.287486318867;1532.417681281106;940.6566603189787;968.6293894139855;985.142925654685;992.7071513903416;992.9573947355248;5132.8442054337265;5153.711572106973;5182.481751824787;5196.558292545176;5218.895622087537;5430.8913821723345;5459.444058443324;5465.699386533779;5466.590005749585;5526.47498487643;5009.624278179152;5169.870753231199;5182.455842136596;5186.729251333448;5212.817551385683;2910.5738188287587;2941.5145351597307;2965.6144701189137;2977.865996030184;2980.5529170624145;5698.715064246854;5721.799737009268;5751.8562035949435;5776.971115144397;5805.010419633892;5211.661242019257;5545.890821835649;5566.248556444828;5567.995521612216;5637.46444087811;5613.410591887875;5631.043702056453;5677.261955945701;5746.827595172152;5776.364599894008;5129.666571672829;5421.457854214547;5424.593155513273;5450.672959622277;5556.221889055472;5416.69999200197;5419.512243898068;5455.399906010256;5481.794005318823;5945.821625351246;6446.548741588043;6502.837429064311;6503.674816259263;6507.154069970874;6519.641419721961;4339.327404252346;4372.6939114261695;4423.756693381895;4430.778461076998;4454.153710794939;6121.816345509642;6147.66187859672;6170.197874276315;6188.628682279078;6256.718447669957;5899.911501327398;6026.969865150646;6042.607230530001;6046.790641871556;6063.242094738158;5926.525877929842;6042.1843051187525;6043.576949613484;6055.818325450244;6072.479920832805;5818.359980402068;5922.200502932049;5961.374055724086;5968.343482216866;5994.400559943971;5981.252343457068;6025.487833534024;6073.696315184135;6120.969395152996;6139.3246742857355;5785.4214578541805;5857.560682948711;5885.087811389241;5897.262842144732;5904.3210030845285 12:21:46 INFO - PROCESS | 3468 | |5;yui.html;785.9900028463426;798.2456578533288;851.4891065360785;881.5195718333509;896.8923729152494;13991.650208744786;17168.484945451626;17464.388746393877;17513.124343782823;17571.56071098223;15086.396544138248;15319.69360612787;15328.693426131471;15348.155851428168;15362.615934601641;864.8702694595813;870.952097634633;875.0068672057197;877.2280393253927;882.9823403531925;877.4647465046316;877.8990416102134;878.6529320918233;879.3712495565669;882.660175832306;912;913.6573784830689;913.9817203655923;916.3493919317259;916.4959272400187;907.0430695616122;907.3331101640289;911.4667919267237;913.9131782480654;914.0448231597929;660.54092405778;663.6880666086931;671.6238906212536;672.3276723276723;673.3468535520586;14095.859041409558;14169.574912752634;14301.499447519287;14308.141511509342;14327.856721432756;13974.58076257714;14173.71652566943;14258.857411425857;14306.64233394163;14336.498222562264;12248.81626775599;12347;12452.626421207378;12518.311492867888;12570.057245706608;4059.127287633173;4088.2028004539125;4140.792960352001;4167.4790651168605;4186.497620285585;53471.19793203105;63097.36902630961;65637.67181164064;65655.67172164157;71211.64394178048;13087;13220.405081771343;13322.333883305795;13383.799243011363;13403.061785675105;20973.475663108544;21694.34916952494;23832.64251036236;23847.761522384553;23902.64146037811;9470.052994700474;9613.990530994284;9616.423014619146;9654.951725241328;9684.95157524208;5025.673331233458;5066.341375621146;5168.9224661630105;5178;5284.233786100994;3964.385520244367;4099.97950010254;4112.09533902541;4117.732347397409;4125.422440858282;716.6989864257024;727.5598263050883;730.8647900138492;736.8268456912622;754.8075240813668;700.2892064554482;855.9486430814171;871.3029576338904;872.7469033980202;873.4453621950107;11475.426228688531;11610.76778464426;11638.41807909601;11756.706082347839;11831.822522662167;765.7319938021759;812.0134037144779;830.6594296338473;840.369722707969;861.6165806216173;5060.696358218519;5258.158694608845;5259.342582177228;5269.552088072557;5281.12861377876;74956.25043749492;113759;118255;118315;118873.40563297128;59269.40730592725;62657;62792;85576.14423855806;99830.50084749529;57780.71109644425;58111.70944145252;83765;89928;90310.54844725734;10712;10882.945585272022;10900.291481053706;11405.600803971822;11410;50753.746231268604;51040.234396484826;51706.22440663468;53579.73210133924;53604;74379.62810185914;82626.58686706529;82992;84175;84911.5754421224;14267.714645707236;14398.280085995868;14490.855091449372;14575.85424145745;14610.853891460949;15483.84516154824;15501.302441389938;15527.689446211238;15781.28984195691;16561.17194140264;15624.453144139901;15632.921835391206;15881.444149454714;15904.284307205971;16168.838311616733;14650.120992740469;15138;15434.765218782246;15649.608759781097;15760.60598485047;15230.23848807533;15910.363585456453;16026.759598606264;16351.345946162495;16464.34142634329;12593.748125037631;12767.680807979503;12937.223766574036;13029.218246905217;13091.607251782461;12354;12598.748025039631;12745.61763147107;13245;13315.334233288491 12:21:46 INFO - PROCESS | 3468 | __end_tp_report 12:21:46 INFO - PROCESS | 3468 | __start_cc_report 12:21:46 INFO - PROCESS | 3468 | _x_x_mozilla_cycle_collect,350 12:21:46 INFO - PROCESS | 3468 | __end_cc_report 12:21:46 INFO - PROCESS | 3468 | __startTimestamp1469215306843__endTimestamp 12:21:46 INFO - PROCESS | 3468 | 12:21:46 INFO - PROCESS | 3468 | ------- Summary: start ------- 12:21:46 INFO - PROCESS | 3468 | Number of tests: 6 12:21:46 INFO - PROCESS | 3468 | 12:21:46 INFO - PROCESS | 3468 | [#0] dojo.html Cycles:165 Average:5670.46 Median:5890.06 stddev:628.31 (10.7%) stddev-sans-first:603.54 12:21:46 INFO - PROCESS | 3468 | Values: 3360.5 3396.8 3411.7 3425.8 3429.8 5665.0 5738.3 5742.4 5760.9 5775.8 5706.6 5756.5 5769.4 5788.7 5790.4 5193.7 5214.8 5279.7 5302.4 5354.0 5102.6 5350.1 5360.7 5373.4 5386.6 5053.6 5175.2 5211.5 5263.1 5297.3 5210.7 5239.5 5270.3 5271.7 5280.4 5192.3 5257.1 5287.6 5288.5 5291.9 5448.8 5465.6 5563.1 5597.3 5658.8 6106.3 6134.4 6145.3 6148.1 6174.2 6160.3 6182.9 6187.4 6190.7 6197.9 5523.1 5625.9 5641.6 5673.1 5684.8 6167.9 6172.3 6178.2 6188.9 6220.3 5752.9 5976.7 5989.0 5997.1 5999.7 6051.5 6070.9 6088.1 6124.2 6186.4 6076.9 6108.4 6111.7 6112.6 6141.6 5846.2 5952.5 6115.4 6136.8 6152.5 4540.4 4587.2 4605.4 4616.4 4617.0 4133.1 4135.8 4143.2 4154.8 4159.9 5688.3 5744.9 5763.4 5782.8 5817.9 5858.0 5986.2 6065.3 6112.8 6116.3 5788.4 5820.3 5820.8 5826.7 5834.6 5073.2 5250.7 5258.1 5285.1 5293.0 6328.1 6372.7 6381.4 6397.9 6400.2 6103.7 6131.9 6134.6 6143.8 6153.4 6125.4 6147.1 6183.8 6211.3 6369.8 5950.2 5991.3 6004.2 6006.8 6016.0 6100.2 6102.1 6103.7 6107.2 6124.5 5802.9 5922.1 5940.0 5980.5 6068.8 5940.7 5959.3 5976.6 5981.1 6020.0 5638.4 5847.6 5901.6 5901.8 5906.8 5909.9 5931.6 5956.1 6060.5 6075.6 5837.5 5890.1 5912.8 5918.1 5918.5 12:21:46 INFO - PROCESS | 3468 | 12:21:46 INFO - PROCESS | 3468 | [#1] ext.html Cycles:180 Average:63995.81 Median:43643.06 stddev:133075.95 (304.9%) stddev-sans-first:133364.27 12:21:46 INFO - PROCESS | 3468 | Values: 649.3 656.0 659.5 669.7 684.0 37969.2 39483.2 42753.1 42865.4 42956.8 44643.1 45379.8 45444.5 45719.8 45772.3 8704.4 8839.6 8883.1 8884.8 8912.6 8902.8 8944.4 9003.6 9010.7 9011.3 8908.8 8928.6 8951.6 8959.6 9008.1 8343.2 8353.2 8953.3 8964.5 8974.9 8711.7 8776.8 8803.3 8808.6 8827.9 35967.0 40461.4 40755.0 41030.0 41090.6 3136.6 3713.9 3724.6 3753.5 4014.5 32439.2 32578.0 32791.5 32795.8 33169.2 4481.3 4512.5 4549.5 4572.4 4676.8 323902.0 324039.0 326600.0 327221.4 328411.0 38157.4 38356.2 38475.8 38680.0 38728.2 38666.8 40409.2 40538.6 40791.4 40855.6 16815.2 16816.3 17019.4 17026.7 17043.5 14057.6 14057.9 14084.9 14087.6 14091.3 9113.4 9216.2 9230.4 9246.1 9264.8 3486.4 3506.2 3527.3 3557.3 3606.1 1071.4 1144.8 1170.6 1215.8 1283.6 34352.3 34523.1 34560.1 34732.3 34829.7 1189.7 1198.1 1215.2 1246.4 1265.9 16827.6 16987.7 17070.3 17103.7 17204.1 597422.0 599649.0 602907.0 632344.0 670275.0 60587.7 67429.0 67781.3 67842.7 67921.0 1523.2 1575.6 1577.6 1579.7 1583.0 34105.3 34346.3 34733.0 34900.3 35131.5 56610.4 56944.4 57068.4 57247.0 57427.7 497080.0 504611.0 505758.0 507918.0 508958.0 46871.0 46951.8 47505.0 47955.0 48199.8 51766.5 52468.0 53045.5 53784.7 53844.5 44238.0 44454.0 45077.3 45546.3 45747.5 43530.0 43756.1 44176.8 44784.3 45458.0 44160.6 44167.8 44269.6 45276.3 45308.3 37833.6 38813.6 39170.4 39661.2 40013.6 29858.1 30038.4 30401.2 30571.5 30934.0 12:21:46 INFO - PROCESS | 3468 | 12:21:46 INFO - PROCESS | 3468 | [#2] jquery.html Cycles:180 Average:31594.26 Median:13188.65 stddev:92227.93 (699.3%) stddev-sans-first:92456.96 12:21:46 INFO - PROCESS | 3468 | Values: 428.1 434.2 435.3 438.4 444.4 19360.5 19826.7 20161.2 21277.6 21295.9 19220.2 19809.8 20412.8 20414.2 20444.7 5784.7 6029.3 6064.3 6069.8 6078.0 5750.7 5866.8 5884.9 5887.4 5889.3 5760.4 5803.3 5808.9 5829.2 5836.4 5632.6 5875.8 5906.2 5917.5 5925.0 5231.2 5283.1 5307.1 5318.1 5340.2 23971.6 24271.4 24413.9 24455.1 24726.8 2405.7 2427.3 2428.3 2535.7 2550.5 14687.3 14978.0 15053.6 15078.4 15244.2 2052.9 2061.2 2110.8 2138.3 2194.2 70299.6 72501.6 72829.9 83060.6 87372.0 10933.5 11859.2 12022.2 12168.6 12207.6 16858.9 17029.8 17086.5 17146.2 17158.5 8556.7 8777.7 8832.0 8969.1 9016.5 8489.4 8503.7 8575.6 8609.8 8651.4 5010.3 5045.9 5114.3 5127.6 5228.7 2109.7 2111.2 2125.4 2157.5 2186.2 1059.3 1064.3 1081.0 1136.8 1144.4 19269.0 19815.5 20054.5 20074.2 20514.6 1017.3 1048.6 1066.4 1082.3 1101.4 10914.6 11271.9 11452.5 11513.2 11527.9 532588.0 555553.0 568849.0 571450.0 578937.0 82218.6 89378.1 95859.5 98297.0 98688.0 1048.9 1082.2 1090.3 1091.2 1093.5 18578.5 18612.2 18790.2 18962.7 18981.8 38273.8 43590.0 44416.3 45419.1 46361.8 63342.4 63840.4 64399.0 65290.7 65559.7 7617.2 8371.1 8896.1 9045.6 9079.6 11527.4 13348.7 13407.2 13439.3 13672.8 11881.5 11908.3 12103.9 12153.7 12286.9 11613.7 11790.6 11835.0 12119.7 12176.1 11627.5 11748.4 11904.3 11991.8 12174.0 8073.8 8080.6 8195.8 8317.0 8384.6 11111.2 11421.4 11499.4 11543.4 11762.5 12:21:46 INFO - PROCESS | 3468 | 12:21:46 INFO - PROCESS | 3468 | [#3] mootools.html Cycles:180 Average:6252.18 Median:4739.65 stddev:3606.04 (76.1%) stddev-sans-first:3599.04 12:21:46 INFO - PROCESS | 3468 | Values: 1576.7 1611.9 1834.3 1890.5 1931.1 8573.5 9000.5 9234.7 9933.9 9949.0 9285.4 9534.9 9586.7 9588.8 9596.6 2341.9 2376.3 2386.7 2387.3 2390.5 2331.6 2402.2 2411.2 2425.2 2432.5 2386.3 2396.9 2399.0 2411.3 2422.6 2291.8 2293.8 2297.8 2301.6 2311.7 2309.3 2331.3 2341.4 2364.9 2373.3 9501.5 9569.7 9577.2 9644.2 9656.6 5523.4 5691.9 5723.3 5726.9 5733.6 3573.9 3627.7 3634.3 3701.4 3716.2 1155.4 1160.8 1177.8 1179.7 1194.0 13297.2 13300.9 13302.4 13348.2 13391.1 4750.3 4786.7 4832.3 4899.4 4903.6 10515.6 10554.2 10558.5 10592.6 10594.3 5566.3 5629.1 5656.5 5659.2 5659.4 4683.2 4913.6 5081.4 5100.4 5300.3 4053.9 4061.3 4126.9 4147.4 4154.7 1151.0 1166.8 1172.8 1173.0 1180.3 758.5 896.7 896.8 908.4 913.0 6498.2 6638.8 6656.7 6819.1 6897.8 1044.6 1070.6 1087.7 1088.7 1096.1 4539.6 4671.8 4704.5 4720.3 4729.0 11776.2 11880.0 12082.3 12216.3 12223.2 11602.9 11978.5 12098.3 12114.7 12192.8 8776.0 8955.2 8962.9 8966.7 8999.9 6481.9 6586.8 6591.7 6617.4 6623.6 10871.9 10928.5 10933.6 11221.7 11234.7 11146.4 11236.8 11239.3 11362.4 11458.0 7758.1 7826.1 8097.0 8117.4 8153.6 8909.6 8975.9 9010.0 9015.9 9086.8 8146.4 8313.5 8460.0 8544.1 8547.4 8051.7 8056.6 8099.3 8111.9 8182.1 7543.1 7708.2 7772.1 7937.7 7980.7 5664.5 5711.3 5748.5 5808.0 5859.2 5466.1 5515.4 5713.5 5738.7 5783.4 12:21:46 INFO - PROCESS | 3468 | 12:21:46 INFO - PROCESS | 3468 | [#4] prototype.html Cycles:180 Average:4906.52 Median:5466.14 stddev:1426.30 (26.1%) stddev-sans-first:1388.56 12:21:46 INFO - PROCESS | 3468 | Values: 342.9 352.2 354.3 371.6 372.7 5633.6 5719.7 5735.9 5742.8 5765.7 5686.5 5690.1 5699.9 5716.6 5726.8 4169.5 4424.0 4531.2 4541.9 4555.5 4450.5 4464.8 4466.3 4469.8 4481.3 4460.8 4474.6 4498.5 4512.8 4515.9 4343.1 4501.5 4505.8 4509.4 4513.2 3884.3 3885.5 3889.5 3895.2 3898.6 5511.4 5540.5 5552.7 5574.5 5599.8 4896.8 5063.9 5084.3 5086.9 5122.5 5982.9 5994.6 6013.0 6014.5 6019.2 4284.8 4302.0 4307.2 4309.7 4316.1 5852.6 6191.6 6206.8 6233.5 6256.3 4349.6 4385.8 4387.8 4388.5 4394.1 4449.3 4458.1 4462.8 4477.5 4485.2 4915.9 5030.2 5116.6 5123.9 5125.3 5947.5 5957.4 5977.7 5979.6 6035.6 1511.7 1522.9 1531.5 1532.3 1532.4 940.7 968.6 985.1 992.7 993.0 5132.8 5153.7 5182.5 5196.6 5218.9 5430.9 5459.4 5465.7 5466.6 5526.5 5009.6 5169.9 5182.5 5186.7 5212.8 2910.6 2941.5 2965.6 2977.9 2980.6 5698.7 5721.8 5751.9 5777.0 5805.0 5211.7 5545.9 5566.2 5568.0 5637.5 5613.4 5631.0 5677.3 5746.8 5776.4 5129.7 5421.5 5424.6 5450.7 5556.2 5416.7 5419.5 5455.4 5481.8 5945.8 6446.5 6502.8 6503.7 6507.2 6519.6 4339.3 4372.7 4423.8 4430.8 4454.2 6121.8 6147.7 6170.2 6188.6 6256.7 5899.9 6027.0 6042.6 6046.8 6063.2 5926.5 6042.2 6043.6 6055.8 6072.5 5818.4 5922.2 5961.4 5968.3 5994.4 5981.3 6025.5 6073.7 6121.0 6139.3 5785.4 5857.6 5885.1 5897.3 5904.3 12:21:46 INFO - PROCESS | 3468 | 12:21:46 INFO - PROCESS | 3468 | [#5] yui.html Cycles:180 Average:20199.54 Median:4133.11 stddev:27187.10 (657.8%) stddev-sans-first:27224.29 12:21:46 INFO - PROCESS | 3468 | Values: 786.0 798.2 851.5 881.5 896.9 13991.7 17168.5 17464.4 17513.1 17571.6 15086.4 15319.7 15328.7 15348.2 15362.6 864.9 871.0 875.0 877.2 883.0 877.5 877.9 878.7 879.4 882.7 912.0 913.7 914.0 916.3 916.5 907.0 907.3 911.5 913.9 914.0 660.5 663.7 671.6 672.3 673.3 14095.9 14169.6 14301.5 14308.1 14327.9 13974.6 14173.7 14258.9 14306.6 14336.5 12248.8 12347.0 12452.6 12518.3 12570.1 4059.1 4088.2 4140.8 4167.5 4186.5 53471.2 63097.4 65637.7 65655.7 71211.6 13087.0 13220.4 13322.3 13383.8 13403.1 20973.5 21694.3 23832.6 23847.8 23902.6 9470.1 9614.0 9616.4 9655.0 9685.0 5025.7 5066.3 5168.9 5178.0 5284.2 3964.4 4100.0 4112.1 4117.7 4125.4 716.7 727.6 730.9 736.8 754.8 700.3 855.9 871.3 872.7 873.4 11475.4 11610.8 11638.4 11756.7 11831.8 765.7 812.0 830.7 840.4 861.6 5060.7 5258.2 5259.3 5269.6 5281.1 74956.3 113759.0 118255.0 118315.0 118873.4 59269.4 62657.0 62792.0 85576.1 99830.5 57780.7 58111.7 83765.0 89928.0 90310.5 10712.0 10882.9 10900.3 11405.6 11410.0 50753.7 51040.2 51706.2 53579.7 53604.0 74379.6 82626.6 82992.0 84175.0 84911.6 14267.7 14398.3 14490.9 14575.9 14610.9 15483.8 15501.3 15527.7 15781.3 16561.2 15624.5 15632.9 15881.4 15904.3 16168.8 14650.1 15138.0 15434.8 15649.6 15760.6 15230.2 15910.4 16026.8 16351.3 16464.3 12593.7 12767.7 12937.2 13029.2 13091.6 12354.0 12598.7 12745.6 13245.0 13315.3 12:21:46 INFO - PROCESS | 3468 | -------- Summary: end -------- 12:21:46 INFO - PROCESS | 3468 | 12:21:47 INFO - TEST-INFO | 3468: exit 0 12:21:47 INFO - TEST-OK | dromaeo_css | took 1113954ms 12:21:47 INFO - TEST-START | kraken 12:21:47 INFO - operating with platform_type : linux_ 12:21:47 INFO - Initialising browser for kraken test... 12:21:47 INFO - TEST-INFO | started process 3715 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIlNdzR/profile http://localhost:59422/getInfo.html) 12:21:52 INFO - PROCESS | 3715 | __metrics Screen width/height:1600/1200 12:21:52 INFO - PROCESS | 3715 | colorDepth:24 12:21:52 INFO - PROCESS | 3715 | Browser inner width/height: 1024/697 12:21:52 INFO - PROCESS | 3715 | __metrics 12:21:53 ERROR - PROCESS | 3715 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 12:21:54 INFO - TEST-INFO | 3715: exit 0 12:21:54 INFO - Browser initialized. 12:21:54 INFO - Running cycle 1/1 for kraken test... 12:21:54 INFO - TEST-INFO | started process 3844 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIlNdzR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:22:06 INFO - PROCESS | 3844 | RSS: Main: 156094464 12:22:06 INFO - PROCESS | 3844 | 12:22:08 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:59422/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59422/tests/kraken/driver.html?testName=audio-beat-detection) 12:22:09 INFO - PROCESS | 3844 | RSS: Main: 161374208 12:22:09 INFO - PROCESS | 3844 | 12:22:12 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:59422/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59422/tests/kraken/driver.html?testName=audio-dft) 12:22:12 INFO - PROCESS | 3844 | RSS: Main: 160296960 12:22:12 INFO - PROCESS | 3844 | 12:22:15 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:59422/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59422/tests/kraken/driver.html?testName=audio-fft) 12:22:16 INFO - PROCESS | 3844 | RSS: Main: 161026048 12:22:16 INFO - PROCESS | 3844 | 12:22:18 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:59422/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59422/tests/kraken/driver.html?testName=audio-oscillator) 12:22:18 INFO - PROCESS | 3844 | RSS: Main: 160686080 12:22:18 INFO - PROCESS | 3844 | 12:22:21 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:59422/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59422/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:22:21 INFO - PROCESS | 3844 | RSS: Main: 160681984 12:22:21 INFO - PROCESS | 3844 | 12:22:24 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:59422/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59422/tests/kraken/driver.html?testName=imaging-darkroom) 12:22:25 INFO - PROCESS | 3844 | RSS: Main: 160587776 12:22:25 INFO - PROCESS | 3844 | 12:22:28 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:59422/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59422/tests/kraken/driver.html?testName=imaging-desaturate) 12:22:29 INFO - PROCESS | 3844 | RSS: Main: 161271808 12:22:29 INFO - PROCESS | 3844 | 12:22:32 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:59422/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59422/tests/kraken/driver.html?testName=json-parse-financial) 12:22:32 INFO - PROCESS | 3844 | RSS: Main: 161005568 12:22:32 INFO - PROCESS | 3844 | 12:22:34 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:59422/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59422/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:22:35 INFO - PROCESS | 3844 | RSS: Main: 160808960 12:22:35 INFO - PROCESS | 3844 | 12:22:36 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:59422/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59422/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:22:37 INFO - PROCESS | 3844 | RSS: Main: 160735232 12:22:37 INFO - PROCESS | 3844 | 12:22:40 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:59422/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59422/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:22:40 INFO - PROCESS | 3844 | RSS: Main: 161280000 12:22:40 INFO - PROCESS | 3844 | 12:22:43 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:59422/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59422/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:22:43 INFO - PROCESS | 3844 | RSS: Main: 161312768 12:22:43 INFO - PROCESS | 3844 | 12:22:46 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:59422/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59422/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:22:47 INFO - PROCESS | 3844 | RSS: Main: 161472512 12:22:47 INFO - PROCESS | 3844 | 12:22:48 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:59422/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59422/tests/kraken/driver.html?testName=ai-astar) 12:22:49 INFO - PROCESS | 3844 | RSS: Main: 162095104 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - PROCESS | 3844 | __start_tp_report 12:22:49 INFO - PROCESS | 3844 | _x_x_mozilla_page_load 12:22:49 INFO - PROCESS | 3844 | _x_x_mozilla_page_load_details 12:22:49 INFO - PROCESS | 3844 | |i|pagename|runs| 12:22:49 INFO - PROCESS | 3844 | |0;ai-astar;111;102;111;109;111;111;112;109;113;108 12:22:49 INFO - PROCESS | 3844 | |1;audio-beat-detection;157;127;159;167;127;161;163;126;195;123 12:22:49 INFO - PROCESS | 3844 | |2;audio-dft;162;162;162;160;162;162;165;161;162;162 12:22:49 INFO - PROCESS | 3844 | |3;audio-fft;75;75;76;163;84;74;75;75;159;75 12:22:49 INFO - PROCESS | 3844 | |4;audio-oscillator;84;83;144;84;86;83;142;84;84;142 12:22:49 INFO - PROCESS | 3844 | |5;imaging-gaussian-blur;117;120;119;117;118;117;117;118;120;118 12:22:49 INFO - PROCESS | 3844 | |6;imaging-darkroom;146;147;147;146;147;148;147;147;147;147 12:22:49 INFO - PROCESS | 3844 | |7;imaging-desaturate;97;96;106;95;96;100;96;96;96;99 12:22:49 INFO - PROCESS | 3844 | |8;json-parse-financial;53;55;53;59;55;54;54;55;53;53 12:22:49 INFO - PROCESS | 3844 | |9;json-stringify-tinderbox;60;60;60;59;61;60;60;60;60;61 12:22:49 INFO - PROCESS | 3844 | |10;stanford-crypto-aes;76;77;76;76;78;76;77;78;107;77 12:22:49 INFO - PROCESS | 3844 | |11;stanford-crypto-ccm;126;120;121;125;117;118;117;121;121;124 12:22:49 INFO - PROCESS | 3844 | |12;stanford-crypto-pbkdf2;153;158;154;154;159;156;160;161;160;151 12:22:49 INFO - PROCESS | 3844 | |13;stanford-crypto-sha256-iterative;63;67;62;61;60;65;67;61;62;59 12:22:49 INFO - PROCESS | 3844 | __end_tp_report 12:22:49 INFO - PROCESS | 3844 | __start_cc_report 12:22:49 INFO - PROCESS | 3844 | _x_x_mozilla_cycle_collect,889 12:22:49 INFO - PROCESS | 3844 | __end_cc_report 12:22:49 INFO - PROCESS | 3844 | __startTimestamp1469215369043__endTimestamp 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - PROCESS | 3844 | ------- Summary: start ------- 12:22:49 INFO - PROCESS | 3844 | Number of tests: 14 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - PROCESS | 3844 | [#0] ai-astar Cycles:10 Average:109.70 Median:111.00 stddev:3.09 (2.8%) stddev-sans-first:3.24 12:22:49 INFO - PROCESS | 3844 | Values: 111.0 102.0 111.0 109.0 111.0 111.0 112.0 109.0 113.0 108.0 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - PROCESS | 3844 | [#1] audio-beat-detection Cycles:10 Average:150.50 Median:160.00 stddev:23.79 (14.9%) stddev-sans-first:25.12 12:22:49 INFO - PROCESS | 3844 | Values: 157.0 127.0 159.0 167.0 127.0 161.0 163.0 126.0 195.0 123.0 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - PROCESS | 3844 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.25 (0.8%) stddev-sans-first:1.32 12:22:49 INFO - PROCESS | 3844 | Values: 162.0 162.0 162.0 160.0 162.0 162.0 165.0 161.0 162.0 162.0 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - PROCESS | 3844 | [#3] audio-fft Cycles:10 Average:93.10 Median:75.00 stddev:35.91 (47.9%) stddev-sans-first:37.49 12:22:49 INFO - PROCESS | 3844 | Values: 75.0 75.0 76.0 163.0 84.0 74.0 75.0 75.0 159.0 75.0 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - PROCESS | 3844 | [#4] audio-oscillator Cycles:10 Average:101.60 Median:84.00 stddev:28.36 (33.8%) stddev-sans-first:29.35 12:22:49 INFO - PROCESS | 3844 | Values: 84.0 83.0 144.0 84.0 86.0 83.0 142.0 84.0 84.0 142.0 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - PROCESS | 3844 | [#5] imaging-gaussian-blur Cycles:10 Average:118.10 Median:118.00 stddev:1.20 (1.0%) stddev-sans-first:1.20 12:22:49 INFO - PROCESS | 3844 | Values: 117.0 120.0 119.0 117.0 118.0 117.0 117.0 118.0 120.0 118.0 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - PROCESS | 3844 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 12:22:49 INFO - PROCESS | 3844 | Values: 146.0 147.0 147.0 146.0 147.0 148.0 147.0 147.0 147.0 147.0 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - PROCESS | 3844 | [#7] imaging-desaturate Cycles:10 Average:97.70 Median:96.00 stddev:3.30 (3.4%) stddev-sans-first:3.49 12:22:49 INFO - PROCESS | 3844 | Values: 97.0 96.0 106.0 95.0 96.0 100.0 96.0 96.0 96.0 99.0 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - PROCESS | 3844 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:1.84 (3.4%) stddev-sans-first:1.88 12:22:49 INFO - PROCESS | 3844 | Values: 53.0 55.0 53.0 59.0 55.0 54.0 54.0 55.0 53.0 53.0 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - PROCESS | 3844 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 12:22:49 INFO - PROCESS | 3844 | Values: 60.0 60.0 60.0 59.0 61.0 60.0 60.0 60.0 60.0 61.0 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - PROCESS | 3844 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:77.00 stddev:9.59 (12.5%) stddev-sans-first:10.07 12:22:49 INFO - PROCESS | 3844 | Values: 76.0 77.0 76.0 76.0 78.0 76.0 77.0 78.0 107.0 77.0 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - PROCESS | 3844 | [#11] stanford-crypto-ccm Cycles:10 Average:121.00 Median:121.00 stddev:3.20 (2.6%) stddev-sans-first:2.83 12:22:49 INFO - PROCESS | 3844 | Values: 126.0 120.0 121.0 125.0 117.0 118.0 117.0 121.0 121.0 124.0 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - PROCESS | 3844 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.60 Median:158.50 stddev:3.47 (2.2%) stddev-sans-first:3.43 12:22:49 INFO - PROCESS | 3844 | Values: 153.0 158.0 154.0 154.0 159.0 156.0 160.0 161.0 160.0 151.0 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - PROCESS | 3844 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:62.50 stddev:2.79 (4.5%) stddev-sans-first:2.96 12:22:49 INFO - PROCESS | 3844 | Values: 63.0 67.0 62.0 61.0 60.0 65.0 67.0 61.0 62.0 59.0 12:22:49 INFO - PROCESS | 3844 | -------- Summary: end -------- 12:22:49 INFO - PROCESS | 3844 | 12:22:49 INFO - TEST-INFO | 3844: exit 0 12:22:50 INFO - TEST-OK | kraken | took 62489ms 12:22:50 INFO - SUITE-END | took 1176s 12:22:50 INFO - Completed test suite (00:19:36) 12:22:50 INFO - javascript benchmark 12:22:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7625.894775028799, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3360.495925611158, 3396.847141878616, 3411.710004649605, 3425.777324473909, 3429.7770644908096, 5665.0, 5738.340090889549, 5742.425757424256, 5760.913586296209, 5775.7689692412305, 5706.629069110515, 5756.453136951983, 5769.365369809318, 5788.6816225107605, 5790.392008839064, 5193.68837869729, 5214.843554693346, 5279.683219006853, 5302.416734159239, 5354.0, 5102.591792656579, 5350.117230656937, 5360.678359298455, 5373.355197376321, 5386.596005299618, 5053.570446512051, 5175.171972484403, 5211.5309622134, 5263.105272103751, 5297.284866543006, 5210.661307015051, 5239.502247286521, 5270.341207349082, 5271.657342272779, 5280.392754833167, 5192.299039629639, 5257.08001099806, 5287.629865909388, 5288.471132930211, 5291.894162116774, 5448.809291674773, 5465.562754979592, 5563.109902415594, 5597.3003374578175, 5658.830235092953, 6106.328303886613, 6134.447899689049, 6145.293291271516, 6148.1392605035335, 6174.228221472316, 6160.2915664698685, 6182.876342473171, 6187.3503282155625, 6190.721417536221, 6197.938020619827, 5523.088690366085, 5625.8593535161535, 5641.605087643867, 5673.1490276458035, 5684.772609095672, 6167.876642467126, 6172.321044685081, 6178.227721534809, 6188.907166392508, 6220.34686357935, 5752.884942301131, 5976.73104710289, 5989.041753319448, 5997.070454079625, 5999.730012149464, 6051.515878729691, 6070.908936365958, 6088.086786981989, 6124.153566208261, 6186.381361863868, 6076.878462430726, 6108.365567233795, 6111.6525128775675, 6112.559453919505, 6141.6007959482495, 5846.181534585162, 5952.494038006818, 6115.4190351916495, 6136.846578835565, 6152.507799376129, 4540.364348991144, 4587.220172570671, 4605.447346318463, 4616.3537104805355, 4617.045091141672, 4133.132042271097, 4135.81388837497, 4143.1713657268665, 4154.813033413504, 4159.9376009359885, 5688.317401911797, 5744.856378590485, 5763.394843541451, 5782.826515204551, 5817.941820581825, 5858.0, 5986.2217911671205, 6065.332813390523, 6112.837854638657, 6116.296625887991, 5788.363280039192, 5820.272465941757, 5820.796272130455, 5826.6795326256615, 5834.649921004753, 5073.213651883964, 5250.711210883438, 5258.132408152688, 5285.127953887642, 5292.973535132377, 6328.11406403104, 6372.7132279047555, 6381.361863813674, 6397.936020639734, 6400.23197216337, 6103.694815259308, 6131.908021379593, 6134.631922084778, 6143.754249829957, 6153.4154255345675, 6125.449118208347, 6147.0779383092895, 6183.84540386494, 6211.3478084800445, 6369.777057802955, 5950.166976623363, 5991.3409524953095, 6004.249468816398, 6006.849828754316, 6015.969920150371, 6100.176476175659, 6102.0541816018585, 6103.69481525913, 6107.206063211754, 6124.479419249414, 5802.897449484584, 5922.141289512911, 5940.0, 5980.5215582754145, 6068.757249709962, 5940.702964851826, 5959.255093113361, 5976.641401515749, 5981.102834574848, 6019.969900150471, 5638.407967163389, 5847.590668653153, 5901.645901245939, 5901.7639294427745, 5906.822795316148, 5909.911351329648, 5931.6441013539325, 5956.106584012433, 6060.454559089703, 6075.605085669594, 5837.532997360286, 5890.057590785454, 5912.763489460545, 5918.112283157561, 5918.496927761188], "value": 5629.486040432116, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [649.3474058571136, 655.9537537627485, 659.5284371674252, 669.7488441834312, 683.9589624622527, 37969.24061518768, 39483.2103357933, 42753.14493710124, 42865.35701964465, 42956.78521607388, 44643.10713785722, 45379.77310113453, 45444.54555454444, 45719.77140114295, 45772.3134152988, 8704.390692651501, 8839.646414143459, 8883.067277935821, 8884.822303553925, 8912.598933048028, 8902.777430564256, 8944.418612790181, 9003.594838232295, 9010.684626038057, 9011.27909767217, 8908.777280568005, 8928.642854285821, 8951.641934322619, 8959.5968181432, 9008.054154313768, 8343.249107580317, 8353.248207661309, 8953.328500362499, 8964.462132272085, 8974.865377019383, 8711.651533938635, 8776.824463510695, 8803.339749518811, 8808.64765409383, 8827.867581986275, 35967.0, 40461.39307910384, 40755.0, 41030.0, 41090.58909410927, 3136.639286482061, 3713.8885833425043, 3724.553053633554, 3753.512043434336, 4014.45804816349, 32439.189020274447, 32578.0, 32791.50812737811, 32795.83602081998, 33169.170770730685, 4481.268653392125, 4512.52618475062, 4549.499555048942, 4572.428446444194, 4676.766161691936, 323902.0, 324039.0, 326600.0, 327221.3638931814, 328411.0, 38157.42763858545, 38356.232875342335, 38475.807620961714, 38680.0, 38728.225435491695, 38666.80666596649, 40409.19181616351, 40538.59461405348, 40791.388129178085, 40855.591444085774, 16815.2433140509, 16816.327346905986, 17019.40432084896, 17026.659466810594, 17043.488695339158, 14057.648558786112, 14057.929710351384, 14084.929575352058, 14087.647808804863, 14091.295435228198, 9113.407628504126, 9216.216621587106, 9230.400023998418, 9246.121618446237, 9264.768380790534, 3486.4247399179358, 3506.1935754776546, 3527.276908233806, 3557.2529768748855, 3606.062423769839, 1071.3732466507024, 1144.822552504363, 1170.584442522892, 1215.8176273559036, 1283.6213317071486, 34352.31295374128, 34523.13692157716, 34560.135996600286, 34732.30535389329, 34829.6613119807, 1189.655000049993, 1198.0595232742255, 1215.1858254969193, 1246.4391024039219, 1265.8923991460645, 16827.57931051709, 16987.660246794996, 17070.317187312616, 17103.657926841395, 17204.053777042132, 597422.0, 599649.0, 602907.0, 632344.0, 670275.0, 60587.69706151441, 67429.0, 67781.32218677849, 67842.66078669575, 67921.0, 1523.1546491697216, 1575.59822245329, 1577.6213708709827, 1579.7156511827752, 1582.9920850395674, 34105.31789364248, 34346.313073738886, 34733.0, 34900.301993960486, 35131.47302790409, 56610.433895662165, 56944.430555693914, 57068.429315706315, 57247.0, 57427.71286143542, 497080.0, 504611.0, 505758.0, 507918.0, 508958.0, 46871.0, 46951.765241173576, 47505.0, 47955.0, 48199.75900120477, 51766.48233517767, 52468.0, 53045.469545304055, 53784.73107634437, 53844.46155538395, 44238.0, 44454.0, 45077.32384014308, 45546.31680524728, 45747.54252457433, 43530.0, 43756.12487750291, 44176.77911610421, 44784.328235077155, 45458.0, 44160.558394415646, 44167.77916110399, 44269.55730442654, 45276.320855187856, 45308.32037519374, 37833.62166378411, 38813.611863881, 39170.4124438128, 39661.2067758649, 40013.599864000986, 29858.068512792062, 30038.399232015676, 30401.239969000068, 30571.54142687906, 30934.0], "value": 20115.49055083602, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.1394397261507, 434.1924021320345, 435.34045920430543, 438.3889207163673, 444.4311281559605, 19360.51598710033, 19826.70259946102, 20161.19355225791, 21277.57444851102, 21295.893520532376, 19220.231190752354, 19809.801901980976, 20412.795872041275, 20414.183432662714, 20444.693329600068, 5784.739686714087, 6029.336772954972, 6064.332923378425, 6069.78755743549, 6078.0, 5750.741216645261, 5866.794662186824, 5884.882302353951, 5887.4112588741, 5889.322727886283, 5760.36635970043, 5803.303603567557, 5808.883822323551, 5829.154772557998, 5836.387179346171, 5632.577556683265, 5875.764969401219, 5906.232189815318, 5917.526597872203, 5925.022371308729, 5231.163013917755, 5283.128283833163, 5307.071262529042, 5318.069337865858, 5340.225667278223, 23971.640425393634, 24271.393215169763, 24413.87793061041, 24455.144069957467, 24726.752732472625, 2405.6632071510003, 2427.320350301901, 2428.3322086426197, 2535.746425357468, 2550.5281522918135, 14687.265636718228, 14978.0, 15053.623659408493, 15078.396864125532, 15244.161571113695, 2052.8563000589966, 2061.2064355223197, 2110.799474049963, 2138.315738963532, 2194.199117322182, 70299.64850175717, 72501.63749181273, 72829.90755138677, 83060.58469707673, 87372.0, 10933.507992140372, 11859.229150105215, 12022.218555793845, 12168.634940951786, 12207.633770986884, 16858.915705421394, 17029.82970170307, 17086.48740537786, 17146.22841972114, 17158.48524544266, 8556.74329770108, 8777.736667899973, 8832.0, 8969.103089690978, 9016.459012459274, 8489.405741598152, 8503.74488765338, 8575.61409736551, 8609.784755381166, 8651.351148663749, 5010.298558201855, 5045.949540504548, 5114.28400023997, 5127.564157046618, 5228.712420816815, 2109.651907435256, 2111.1766411099684, 2125.3730149605904, 2157.492989147549, 2186.2457452178974, 1059.2849826367326, 1064.2656566968767, 1080.9675709728563, 1136.7612801311652, 1144.4449442020614, 19269.0, 19815.504612384804, 20054.49863753418, 20074.197032118846, 20514.58970820605, 1017.3133135133755, 1048.5910494906993, 1066.3548553125402, 1082.334364365925, 1101.4492753623188, 10914.617988370368, 11271.943640281746, 11452.484638191301, 11513.194076414571, 11527.942360288145, 532588.0, 555553.0, 568849.0, 571450.0, 578937.0, 82218.58890705508, 89378.10621893828, 95859.52070239604, 98297.0, 98688.0, 1048.8846226915034, 1082.1937656445998, 1090.2695194219891, 1091.1870656360902, 1093.5461783359817, 18578.535536611693, 18612.162452689932, 18790.248390064244, 18962.715559266897, 18981.810181898003, 38273.808630956664, 43590.0, 44416.33375499305, 45419.09161816811, 46361.76819115883, 63342.36657633365, 63840.36159638344, 64399.0, 65290.673546631966, 65559.72629716573, 7617.200193979553, 8371.121032291521, 8896.110388961051, 9045.638174472948, 9079.59141838632, 11527.423628818358, 13348.733025339245, 13407.195568265935, 13439.328033598476, 13672.794908076587, 11881.524739010689, 11908.285502869856, 12103.878961210627, 12153.696157595778, 12286.938565307117, 11613.709657258636, 11790.587329443428, 11835.0, 12119.697007574881, 12176.086793490347, 11627.53489860396, 11748.353840538852, 11904.345261010727, 11991.76016479683, 12174.0, 8073.83852322962, 8080.63637136342, 8195.795105122419, 8317.0, 8384.622691978753, 11111.166662500184, 11421.428928553705, 11499.367534785668, 11543.36511491876, 11762.529498819951], "value": 9745.943736533081, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1576.708308962842, 1611.86299164571, 1834.2846289946915, 1890.5179179309273, 1931.063434234396, 8573.485590864553, 9000.549972501365, 9234.722958311253, 9933.850992235122, 9949.0, 9285.396449230795, 9534.90465095349, 9586.664466743665, 9588.76028099298, 9596.56815443305, 2341.8594884306954, 2376.298991797415, 2386.677798497198, 2387.295747754408, 2390.53384590005, 2331.6036273833406, 2402.231285988484, 2411.1560953666253, 2425.163318655061, 2432.4891772727747, 2386.331827088409, 2396.8921398537022, 2399.040383846458, 2411.3127758588807, 2422.600270955299, 2291.7708229177115, 2293.81649468043, 2297.827662925279, 2301.620232661609, 2311.699479067727, 2309.3187509684685, 2331.347222777625, 2341.3678306857055, 2364.8581085134774, 2373.2880135959144, 9501.477418741966, 9569.665061722806, 9577.185939195177, 9644.18024467921, 9656.565454554562, 5523.392426833045, 5691.9146212806845, 5723.255976723041, 5726.856828579277, 5733.627314224604, 3573.94639080414, 3627.6553727396117, 3634.273145370936, 3701.352263353929, 3716.163863130801, 1155.3760969076689, 1160.8432861563665, 1177.7526719388939, 1179.710970812148, 1194.0268681024936, 13297.202167869958, 13300.933495332558, 13302.401391937387, 13348.199108053548, 13391.129576577492, 4750.263351420135, 4786.736729479912, 4832.251001094765, 4899.363082799213, 4903.632227582945, 10515.579376824995, 10554.2084343673, 10558.524866380878, 10592.57629694819, 10594.31136976094, 5566.332040155207, 5629.099344104923, 5656.462636049569, 5659.151127330851, 5659.377468478464, 4683.227267500893, 4913.582345500672, 5081.441041485434, 5100.38795344561, 5300.31095957523, 4053.8783836484954, 4061.268971585084, 4126.896827579274, 4147.419361289422, 4154.709170358106, 1150.9712257193469, 1166.8016437205695, 1172.7947609168361, 1172.976540469203, 1180.2859270141612, 758.4577027425418, 896.7489103051156, 896.8296023755465, 908.3823000359687, 912.9680461183826, 6498.187726534184, 6638.800835974928, 6656.667166641745, 6819.147606549182, 6897.793066208022, 1044.5612842606124, 1070.6252811515908, 1087.6573879228176, 1088.7495875948575, 1096.0847692177122, 4539.568740969603, 4671.813127474863, 4704.482506924235, 4720.268358404453, 4729.030548737501, 11776.234544754563, 11880.0, 12082.275063496218, 12216.328101954477, 12223.205491643015, 11602.88397116018, 11978.52085916571, 12098.274103553815, 12114.697132571757, 12192.81710774339, 8776.0, 8955.194032537103, 8962.910370896338, 8966.730998070068, 8999.910000899907, 6481.8703625928165, 6586.80239592813, 6591.670416479253, 6617.371349721961, 6623.635700036351, 10871.945640271748, 10928.508217130408, 10933.617323393642, 11221.66335009951, 11234.719132021764, 11146.386948717574, 11236.775264494501, 11239.325640461599, 11362.431878406212, 11458.0, 7758.146603873456, 7826.099998500245, 8096.959515202387, 8117.391195660467, 8153.592320383838, 8909.599068042084, 8975.910240897507, 9009.954950225207, 9015.864762028443, 9086.818263634823, 8146.389020823581, 8313.501189928624, 8459.95770021146, 8544.102869198645, 8547.444416112932, 8051.677932882619, 8056.556889371141, 8099.271065604124, 8111.878321825059, 8182.059063207807, 7543.132539757778, 7708.229177082471, 7772.067351917806, 7937.722179723682, 7980.680772769024, 5664.546836253007, 5711.257536520226, 5748.45389687979, 5808.041673123887, 5859.20900678403, 5466.125419932791, 5515.448455154453, 5713.542916566582, 5738.684372359393, 5783.392743761844], "value": 4944.144226027357, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.8902751119643, 352.16712474996626, 354.2737388353874, 371.64507894960315, 372.70742467163285, 5633.577481688874, 5719.684472571306, 5735.885282294357, 5742.79900203493, 5765.6540607563475, 5686.4597863203035, 5690.146478028298, 5699.914501282474, 5716.57125715572, 5726.799562015331, 4169.457970463835, 4423.955760442403, 4531.207038768219, 4541.886452838672, 4555.498895121534, 4450.488193857699, 4464.812242001412, 4466.33005049242, 4469.7988590513505, 4481.260592002316, 4460.843870464534, 4474.552544745516, 4498.460184777831, 4512.842050528232, 4515.932261016071, 4343.087951530151, 4501.5273396293405, 4505.819767209307, 4509.368688383629, 4513.2101882170655, 3884.281407939512, 3885.4560361549406, 3889.533256009297, 3895.162540053902, 3898.649121579043, 5511.421300763443, 5540.529055030287, 5552.695631313, 5574.498295153456, 5599.776008959637, 4896.762491614268, 5063.873403164914, 5084.288199652052, 5086.92369614456, 5122.513361230677, 5982.880342393171, 5994.580379373446, 6013.0, 6014.488768454687, 6019.157317975443, 4284.807183676742, 4301.956980430218, 4307.181635489247, 4309.698321117523, 4316.072044510414, 5852.590318677694, 6191.597546159485, 6206.844828879269, 6233.470155036827, 6256.343083976162, 4349.586789255016, 4385.846495372647, 4387.780610969438, 4388.4733831940375, 4394.08857147142, 4449.310356894686, 4458.08609235106, 4462.7768611569945, 4477.462704475483, 4485.192665320274, 4915.852524424273, 5030.195168772979, 5116.641835071584, 5123.87190320245, 5125.282460455539, 5947.524198064145, 5957.4042595740075, 5977.731002104916, 5979.611325263844, 6035.6076855004285, 1511.6598765277724, 1522.87055600273, 1531.4639876043295, 1532.287486318867, 1532.417681281106, 940.6566603189787, 968.6293894139855, 985.142925654685, 992.7071513903416, 992.9573947355248, 5132.8442054337265, 5153.711572106973, 5182.481751824787, 5196.558292545176, 5218.895622087537, 5430.8913821723345, 5459.444058443324, 5465.699386533779, 5466.590005749585, 5526.47498487643, 5009.624278179152, 5169.870753231199, 5182.455842136596, 5186.729251333448, 5212.817551385683, 2910.5738188287587, 2941.5145351597307, 2965.6144701189137, 2977.865996030184, 2980.5529170624145, 5698.715064246854, 5721.799737009268, 5751.8562035949435, 5776.971115144397, 5805.010419633892, 5211.661242019257, 5545.890821835649, 5566.248556444828, 5567.995521612216, 5637.46444087811, 5613.410591887875, 5631.043702056453, 5677.261955945701, 5746.827595172152, 5776.364599894008, 5129.666571672829, 5421.457854214547, 5424.593155513273, 5450.672959622277, 5556.221889055472, 5416.69999200197, 5419.512243898068, 5455.399906010256, 5481.794005318823, 5945.821625351246, 6446.548741588043, 6502.837429064311, 6503.674816259263, 6507.154069970874, 6519.641419721961, 4339.327404252346, 4372.6939114261695, 4423.756693381895, 4430.778461076998, 4454.153710794939, 6121.816345509642, 6147.66187859672, 6170.197874276315, 6188.628682279078, 6256.718447669957, 5899.911501327398, 6026.969865150646, 6042.607230530001, 6046.790641871556, 6063.242094738158, 5926.525877929842, 6042.1843051187525, 6043.576949613484, 6055.818325450244, 6072.479920832805, 5818.359980402068, 5922.200502932049, 5961.374055724086, 5968.343482216866, 5994.400559943971, 5981.252343457068, 6025.487833534024, 6073.696315184135, 6120.969395152996, 6139.3246742857355, 5785.4214578541805, 5857.560682948711, 5885.087811389241, 5897.262842144732, 5904.3210030845285], "value": 4453.436782966319, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [785.9900028463426, 798.2456578533288, 851.4891065360785, 881.5195718333509, 896.8923729152494, 13991.650208744786, 17168.484945451626, 17464.388746393877, 17513.124343782823, 17571.56071098223, 15086.396544138248, 15319.69360612787, 15328.693426131471, 15348.155851428168, 15362.615934601641, 864.8702694595813, 870.952097634633, 875.0068672057197, 877.2280393253927, 882.9823403531925, 877.4647465046316, 877.8990416102134, 878.6529320918233, 879.3712495565669, 882.660175832306, 912.0, 913.6573784830689, 913.9817203655923, 916.3493919317259, 916.4959272400187, 907.0430695616122, 907.3331101640289, 911.4667919267237, 913.9131782480654, 914.0448231597929, 660.54092405778, 663.6880666086931, 671.6238906212536, 672.3276723276723, 673.3468535520586, 14095.859041409558, 14169.574912752634, 14301.499447519287, 14308.141511509342, 14327.856721432756, 13974.58076257714, 14173.71652566943, 14258.857411425857, 14306.64233394163, 14336.498222562264, 12248.81626775599, 12347.0, 12452.626421207378, 12518.311492867888, 12570.057245706608, 4059.127287633173, 4088.2028004539125, 4140.792960352001, 4167.4790651168605, 4186.497620285585, 53471.19793203105, 63097.36902630961, 65637.67181164064, 65655.67172164157, 71211.64394178048, 13087.0, 13220.405081771343, 13322.333883305795, 13383.799243011363, 13403.061785675105, 20973.475663108544, 21694.34916952494, 23832.64251036236, 23847.761522384553, 23902.64146037811, 9470.052994700474, 9613.990530994284, 9616.423014619146, 9654.951725241328, 9684.95157524208, 5025.673331233458, 5066.341375621146, 5168.9224661630105, 5178.0, 5284.233786100994, 3964.385520244367, 4099.97950010254, 4112.09533902541, 4117.732347397409, 4125.422440858282, 716.6989864257024, 727.5598263050883, 730.8647900138492, 736.8268456912622, 754.8075240813668, 700.2892064554482, 855.9486430814171, 871.3029576338904, 872.7469033980202, 873.4453621950107, 11475.426228688531, 11610.76778464426, 11638.41807909601, 11756.706082347839, 11831.822522662167, 765.7319938021759, 812.0134037144779, 830.6594296338473, 840.369722707969, 861.6165806216173, 5060.696358218519, 5258.158694608845, 5259.342582177228, 5269.552088072557, 5281.12861377876, 74956.25043749492, 113759.0, 118255.0, 118315.0, 118873.40563297128, 59269.40730592725, 62657.0, 62792.0, 85576.14423855806, 99830.50084749529, 57780.71109644425, 58111.70944145252, 83765.0, 89928.0, 90310.54844725734, 10712.0, 10882.945585272022, 10900.291481053706, 11405.600803971822, 11410.0, 50753.746231268604, 51040.234396484826, 51706.22440663468, 53579.73210133924, 53604.0, 74379.62810185914, 82626.58686706529, 82992.0, 84175.0, 84911.5754421224, 14267.714645707236, 14398.280085995868, 14490.855091449372, 14575.85424145745, 14610.853891460949, 15483.84516154824, 15501.302441389938, 15527.689446211238, 15781.28984195691, 16561.17194140264, 15624.453144139901, 15632.921835391206, 15881.444149454714, 15904.284307205971, 16168.838311616733, 14650.120992740469, 15138.0, 15434.765218782246, 15649.608759781097, 15760.60598485047, 15230.23848807533, 15910.363585456453, 16026.759598606264, 16351.345946162495, 16464.34142634329, 12593.748125037631, 12767.680807979503, 12937.223766574036, 13029.218246905217, 13091.607251782461, 12354.0, 12598.748025039631, 12745.61763147107, 13245.0, 13315.334233288491], "value": 8092.72050142161, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1514.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 102.0, 111.0, 109.0, 111.0, 111.0, 112.0, 109.0, 113.0, 108.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 127.0, 159.0, 167.0, 127.0, 161.0, 163.0, 126.0, 195.0, 123.0], "value": 150.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 160.0, 162.0, 162.0, 165.0, 161.0, 162.0, 162.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 163.0, 84.0, 74.0, 75.0, 75.0, 159.0, 75.0], "value": 93.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 144.0, 84.0, 86.0, 83.0, 142.0, 84.0, 84.0, 142.0], "value": 101.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 120.0, 119.0, 117.0, 118.0, 117.0, 117.0, 118.0, 120.0, 118.0], "value": 118.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 146.0, 147.0, 148.0, 147.0, 147.0, 147.0, 147.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 106.0, 95.0, 96.0, 100.0, 96.0, 96.0, 96.0, 99.0], "value": 97.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 53.0, 59.0, 55.0, 54.0, 54.0, 55.0, 53.0, 53.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 59.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 76.0, 78.0, 76.0, 77.0, 78.0, 107.0, 77.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 120.0, 121.0, 125.0, 117.0, 118.0, 117.0, 121.0, 121.0, 124.0], "value": 121.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 158.0, 154.0, 154.0, 159.0, 156.0, 160.0, 161.0, 160.0, 151.0], "value": 156.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 62.0, 61.0, 60.0, 65.0, 67.0, 61.0, 62.0, 59.0], "value": 62.7, "unit": "score"}]}]} 12:22:50 INFO - Return code: 0 12:22:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:22:50 ERROR - # TBPL SUCCESS # 12:22:50 INFO - Running post-action listener: _resource_record_post_action 12:22:50 INFO - ##### 12:22:50 INFO - ##### Finished run-tests step (success) 12:22:50 INFO - ##### 12:22:50 INFO - Running post-run listener: _resource_record_post_run 12:22:50 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 8167424; Write bytes: 386547712; Read time: 2564; Write time: 387628 12:22:50 INFO - TinderboxPrint: CPU usage
13.4% 12:22:50 INFO - TinderboxPrint: I/O read bytes / time
8,167,424 / 2,564 12:22:50 INFO - TinderboxPrint: I/O write bytes / time
386,547,712 / 387,628 12:22:50 INFO - TinderboxPrint: CPU idle
8,246.3 (86.6%) 12:22:50 INFO - TinderboxPrint: CPU user
1,216.2 (12.8%) 12:22:50 INFO - TinderboxPrint: Swap in / out
0 / 0 12:22:50 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 20578304; Read time: 0; Write time: 13612 12:22:50 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 6406144; Write bytes: 365969408; Read time: 2508; Write time: 374016 12:22:50 INFO - Running post-run listener: _upload_blobber_files 12:22:50 INFO - Blob upload gear active. 12:22:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:22:50 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 12:22:50 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'] 12:22:50 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 12:22:51 INFO - (blobuploader) - INFO - Open directory for files ... 12:22:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:22:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:22:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:52 INFO - (blobuploader) - INFO - Done attempting. 12:22:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:22:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:22:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:59 INFO - (blobuploader) - INFO - Done attempting. 12:22:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:22:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:23:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:23:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:23:00 INFO - (blobuploader) - INFO - Done attempting. 12:23:00 INFO - (blobuploader) - INFO - Iteration through files over. 12:23:00 INFO - Return code: 0 12:23:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:23:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:23:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc01fc0fe1c5afbe0bde85b0c80c02fc40858441a1a01a2c0c9980f34375f69d8bbeb5b3acf2af6cfde8def070e405218af0c7d93d55e2c017a5e7de646f1ec5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0d3f94ba7b42254b08feebced099df54840790c2e94891fa0eee2abb69c0aed5ce840f1adf5f91b5db0f87bb0b69be6637c273ba4e75d9ad7fabd1ce9f6a291", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb768cb25d80c0e2d7192d04b099a2ec2a8a49e1c8a212906eaa0a7a628da770d93de9bf2c05cd718f8662b774a726902f5bd2ae90e344a6bb00ca956f146f66"} 12:23:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:23:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:23:00 INFO - Contents: 12:23:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc01fc0fe1c5afbe0bde85b0c80c02fc40858441a1a01a2c0c9980f34375f69d8bbeb5b3acf2af6cfde8def070e405218af0c7d93d55e2c017a5e7de646f1ec5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0d3f94ba7b42254b08feebced099df54840790c2e94891fa0eee2abb69c0aed5ce840f1adf5f91b5db0f87bb0b69be6637c273ba4e75d9ad7fabd1ce9f6a291", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb768cb25d80c0e2d7192d04b099a2ec2a8a49e1c8a212906eaa0a7a628da770d93de9bf2c05cd718f8662b774a726902f5bd2ae90e344a6bb00ca956f146f66"} 12:23:00 INFO - Running post-run listener: copy_logs_to_upload_dir 12:23:00 INFO - Copying logs to upload dir... 12:23:00 INFO - mkdir: /builds/slave/test/build/upload/logs 12:23:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.683963 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-07-22 12:23:00.561354) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 12:23:00.565484) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc01fc0fe1c5afbe0bde85b0c80c02fc40858441a1a01a2c0c9980f34375f69d8bbeb5b3acf2af6cfde8def070e405218af0c7d93d55e2c017a5e7de646f1ec5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0d3f94ba7b42254b08feebced099df54840790c2e94891fa0eee2abb69c0aed5ce840f1adf5f91b5db0f87bb0b69be6637c273ba4e75d9ad7fabd1ce9f6a291", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb768cb25d80c0e2d7192d04b099a2ec2a8a49e1c8a212906eaa0a7a628da770d93de9bf2c05cd718f8662b774a726902f5bd2ae90e344a6bb00ca956f146f66"} build_url:https://queue.taskcluster.net/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011109 build_url: 'https://queue.taskcluster.net/v1/task/Ueggh7SMTK6VlhOHZjCs-w/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc01fc0fe1c5afbe0bde85b0c80c02fc40858441a1a01a2c0c9980f34375f69d8bbeb5b3acf2af6cfde8def070e405218af0c7d93d55e2c017a5e7de646f1ec5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0d3f94ba7b42254b08feebced099df54840790c2e94891fa0eee2abb69c0aed5ce840f1adf5f91b5db0f87bb0b69be6637c273ba4e75d9ad7fabd1ce9f6a291", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb768cb25d80c0e2d7192d04b099a2ec2a8a49e1c8a212906eaa0a7a628da770d93de9bf2c05cd718f8662b774a726902f5bd2ae90e344a6bb00ca956f146f66"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 12:23:00.591089) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:23:00.591457) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1469214129.602678-256762961 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004374 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 12:23:00.676396) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 12:23:00.676793) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 12:23:00.677277) ========= ========= Total master_lag: 0.25 =========