builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-059 starttime: 1468969689.81 results: success (0) buildid: 20160719133037 builduid: 0dfb97c2c35c4c9d994fad9affb772ea revision: 1ebb6348564337c00d889bd858eedf3c922e68c0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:09.806765) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:09.807258) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:09.807693) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:09.823605) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:09.823943) ========= 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=3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-19 16:08:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 22.9M=0.001s 2016-07-19 16:08:10 (22.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094253 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:09.934680) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:09.935016) ========= 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=3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022095 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:09.985498) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:09.985907) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1ebb6348564337c00d889bd858eedf3c922e68c0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1ebb6348564337c00d889bd858eedf3c922e68c0 --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=3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-19 16:08:10,602 truncating revision to first 12 chars 2016-07-19 16:08:10,602 Setting DEBUG logging. 2016-07-19 16:08:10,602 attempt 1/10 2016-07-19 16:08:10,602 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/1ebb63485643?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 16:08:10,907 unpacking tar archive at: mozilla-inbound-1ebb63485643/testing/mozharness/ program finished with exit code 0 elapsedTime=0.639022 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:10.640170) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:10.640523) ========= script_repo_revision: 1ebb6348564337c00d889bd858eedf3c922e68c0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:10.640941) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:10.641229) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 16:08:10.653917) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-07-19 16:08:10.654230) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:08:11 INFO - MultiFileLogger online at 20160719 16:08:11 in /builds/slave/test 16:08:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 16:08:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:08:11 INFO - {'append_to_log': False, 16:08:11 INFO - 'base_work_dir': '/builds/slave/test', 16:08:11 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 16:08:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:08:11 INFO - 'branch': 'Mozilla-Inbound', 16:08:11 INFO - 'buildbot_json_path': 'buildprops.json', 16:08:11 INFO - 'config_files': ('talos/linux_config.py',), 16:08:11 INFO - 'default_actions': ('clobber', 16:08:11 INFO - 'read-buildbot-config', 16:08:11 INFO - 'download-and-extract', 16:08:11 INFO - 'populate-webroot', 16:08:11 INFO - 'create-virtualenv', 16:08:11 INFO - 'install', 16:08:11 INFO - 'run-tests'), 16:08:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:08:11 INFO - 'download_minidump_stackwalk': True, 16:08:11 INFO - 'download_symbols': 'ondemand', 16:08:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:08:11 INFO - 'tooltool.py': '/tools/tooltool.py', 16:08:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:08:11 INFO - '/tools/misc-python/virtualenv.py')}, 16:08:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:08:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:08:11 INFO - 'installer_path': 'installer.exe', 16:08:11 INFO - 'log_level': 'info', 16:08:11 INFO - 'log_name': 'talos', 16:08:11 INFO - 'log_to_console': True, 16:08:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:08:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:08:11 INFO - 'opt_config_files': (), 16:08:11 INFO - 'pip_index': False, 16:08:11 INFO - 'sps_profile': False, 16:08:11 INFO - 'sps_profile_interval': 0, 16:08:11 INFO - 'suite': 'dromaeojs', 16:08:11 INFO - 'system_bits': '32', 16:08:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:08:11 INFO - 'title': 'talos-linux64-ix-059', 16:08:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:08:11 INFO - 'use_talos_json': True, 16:08:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:08:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:08:11 INFO - 'work_dir': 'build'} 16:08:11 INFO - ##### 16:08:11 INFO - ##### Running clobber step. 16:08:11 INFO - ##### 16:08:11 INFO - Running pre-action listener: _resource_record_pre_action 16:08:11 INFO - Running main action method: clobber 16:08:11 INFO - rmtree: /builds/slave/test/build 16:08:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:08:12 INFO - Running post-action listener: _resource_record_post_action 16:08:12 INFO - ##### 16:08:12 INFO - ##### Finished clobber step (success) 16:08:12 INFO - ##### 16:08:12 INFO - ##### 16:08:12 INFO - ##### Running read-buildbot-config step. 16:08:12 INFO - ##### 16:08:12 INFO - Running pre-action listener: _resource_record_pre_action 16:08:12 INFO - Running main action method: read_buildbot_config 16:08:12 INFO - Using buildbot properties: 16:08:12 INFO - { 16:08:12 INFO - "project": "", 16:08:12 INFO - "product": "firefox", 16:08:12 INFO - "script_repo_revision": "production", 16:08:12 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 16:08:12 INFO - "repository": "", 16:08:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 16:08:12 INFO - "buildid": "20160719133037", 16:08:12 INFO - "pgo_build": "True", 16:08:12 INFO - "basedir": "/builds/slave/test", 16:08:12 INFO - "buildnumber": 2088, 16:08:12 INFO - "slavename": "talos-linux64-ix-059", 16:08:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:08:12 INFO - "platform": "ubuntu64_hw", 16:08:12 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 16:08:12 INFO - "branch": "mozilla-inbound", 16:08:12 INFO - "repo_path": "integration/mozilla-inbound", 16:08:12 INFO - "revision": "1ebb6348564337c00d889bd858eedf3c922e68c0", 16:08:12 INFO - "stage_platform": "linux64-pgo", 16:08:12 INFO - "builduid": "0dfb97c2c35c4c9d994fad9affb772ea", 16:08:12 INFO - "slavebuilddir": "test" 16:08:12 INFO - } 16:08:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:08:12 INFO - Running post-action listener: _resource_record_post_action 16:08:12 INFO - ##### 16:08:12 INFO - ##### Finished read-buildbot-config step (success) 16:08:12 INFO - ##### 16:08:12 INFO - ##### 16:08:12 INFO - ##### Running download-and-extract step. 16:08:12 INFO - ##### 16:08:12 INFO - Running pre-action listener: _resource_record_pre_action 16:08:12 INFO - Running main action method: download_and_extract 16:08:12 INFO - mkdir: /builds/slave/test/build/tests 16:08: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')]} 16:08:12 INFO - https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:08:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:08:17 INFO - Downloaded 1361 bytes. 16:08:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:17 INFO - Using the following test package requirements: 16:08:17 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:08:17 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:08:17 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:08:17 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:17 INFO - u'jsshell-linux-x86_64.zip'], 16:08:17 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:08:17 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:08:17 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:08:17 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:08:17 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:08:17 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:08:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:08:17 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')]} 16:08:17 INFO - https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:08:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:08:18 INFO - Downloaded 24336238 bytes. 16:08:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:08:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:08:19 INFO - Return code: 0 16:08:19 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:08: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')]} 16:08:19 INFO - https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:08:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:08:25 INFO - Downloaded 24336238 bytes. 16:08:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:08:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:08:26 INFO - Return code: 0 16:08: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')]} 16:08:26 INFO - https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:08:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:08:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:08:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:08:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:08:27 INFO - Downloaded 14403317 bytes. 16:08:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:08:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:08:28 INFO - Return code: 0 16:08:28 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')]} 16:08:28 INFO - https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:08:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:08:29 INFO - Downloaded 60888591 bytes. 16:08:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:29 INFO - mkdir: /builds/slave/test/properties 16:08:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:08:29 INFO - Writing to file /builds/slave/test/properties/build_url 16:08:29 INFO - Contents: 16:08:29 INFO - build_url:https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:30 INFO - Running post-action listener: _resource_record_post_action 16:08:30 INFO - Running post-action listener: set_extra_try_arguments 16:08:30 INFO - ##### 16:08:30 INFO - ##### Finished download-and-extract step (success) 16:08:30 INFO - ##### 16:08:30 INFO - ##### 16:08:30 INFO - ##### Running populate-webroot step. 16:08:30 INFO - ##### 16:08:30 INFO - Running pre-action listener: _resource_record_pre_action 16:08:30 INFO - Running main action method: populate_webroot 16:08:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:08:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:08:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:08:30 INFO - '--tppagecycles', 16:08:30 INFO - '7'], 16:08:30 INFO - 'tests': ['tsvgm']}}, 16:08:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:08:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:08:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:08:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:08:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:30 INFO - 'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:08:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:08:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:30 INFO - 'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['damp', 'tps']}, 16:08:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:30 INFO - 'tests': ['damp', 'tps']}, 16:08:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['dromaeo_dom']}, 16:08:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:08:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['basic_compositor_video']}, 16:08:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:08:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['a11yr', 16:08:30 INFO - 'ts_paint', 16:08:30 INFO - 'tpaint', 16:08:30 INFO - 'sessionrestore', 16:08:30 INFO - 'sessionrestore_no_auto_restore', 16:08:30 INFO - 'tabpaint']}, 16:08:30 INFO - 'other-e10s': {'tests': ['a11yr', 16:08:30 INFO - 'ts_paint', 16:08:30 INFO - 'tpaint', 16:08:30 INFO - 'sessionrestore', 16:08:30 INFO - 'sessionrestore_no_auto_restore', 16:08:30 INFO - 'tabpaint']}, 16:08:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:08:30 INFO - 'ts_paint', 16:08:30 INFO - 'tpaint', 16:08:30 INFO - 'sessionrestore', 16:08:30 INFO - 'sessionrestore_no_auto_restore', 16:08:30 INFO - 'tabpaint']}, 16:08:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:08:30 INFO - 'ts_paint', 16:08:30 INFO - 'tpaint', 16:08:30 INFO - 'sessionrestore', 16:08:30 INFO - 'sessionrestore_no_auto_restore', 16:08:30 INFO - 'tabpaint']}, 16:08:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['a11yr', 16:08:30 INFO - 'ts_paint', 16:08:30 INFO - 'tpaint', 16:08:30 INFO - 'sessionrestore', 16:08:30 INFO - 'sessionrestore_no_auto_restore', 16:08:30 INFO - 'tabpaint']}, 16:08:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['a11yr', 16:08:30 INFO - 'ts_paint', 16:08:30 INFO - 'tpaint', 16:08:30 INFO - 'sessionrestore', 16:08:30 INFO - 'sessionrestore_no_auto_restore', 16:08:30 INFO - 'tabpaint']}, 16:08:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['tsvgx', 16:08:30 INFO - 'tsvgr_opacity', 16:08:30 INFO - 'tart', 16:08:30 INFO - 'tscrollx', 16:08:30 INFO - 'cart']}, 16:08:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:08:30 INFO - 'tsvgr_opacity', 16:08:30 INFO - 'tart', 16:08:30 INFO - 'tscrollx', 16:08:30 INFO - 'cart']}, 16:08:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:30 INFO - 'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['tp5o']}, 16:08:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:30 INFO - 'tests': ['tp5o']}, 16:08:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:30 INFO - 'talos_options': ['--disable-e10s', 16:08:30 INFO - '--xperf_path', 16:08:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:08:30 INFO - 'tests': ['tp5n']}, 16:08:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:30 INFO - 'talos_options': ['--xperf_path', 16:08:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:08:30 INFO - 'tests': ['tp5n']}}, 16:08:30 INFO - 'talos.zip': {'path': '', 16:08:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:08:30 INFO - Running post-action listener: _resource_record_post_action 16:08:30 INFO - ##### 16:08:30 INFO - ##### Finished populate-webroot step (success) 16:08:30 INFO - ##### 16:08:30 INFO - ##### 16:08:30 INFO - ##### Running create-virtualenv step. 16:08:30 INFO - ##### 16:08:30 INFO - Running pre-action listener: _resource_record_pre_action 16:08:30 INFO - Running main action method: create_virtualenv 16:08:30 INFO - Creating virtualenv /builds/slave/test/build/venv 16:08:30 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 16:08:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:08:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:08:30 INFO - Using real prefix '/usr' 16:08:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:08:30 INFO - Installing distribute.............................................................................................................................................................................................done. 16:08:32 INFO - Installing pip.................done. 16:08:32 INFO - Return code: 0 16:08:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:08: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')]} 16:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08: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')]} 16:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08: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 0x1a7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d40180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3e3b0>, '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 0x1d3f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d3f740>, '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': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', '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 16:08: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 16:08: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 16:08:32 INFO - Using env: {'DISPLAY': ':0', 16:08:32 INFO - 'HOME': '/home/cltbld', 16:08:32 INFO - 'LANG': 'en_US.UTF-8', 16:08:32 INFO - 'LANGUAGE': 'en_US:en', 16:08:32 INFO - 'LOGNAME': 'cltbld', 16:08:32 INFO - 'MAIL': '/var/mail/cltbld', 16:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:32 INFO - 'MOZ_NO_REMOTE': '1', 16:08:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:32 INFO - 'NO_EM_RESTART': '1', 16:08:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:32 INFO - 'PWD': '/builds/slave/test', 16:08:32 INFO - 'SHELL': '/bin/bash', 16:08:32 INFO - 'SHLVL': '1', 16:08:32 INFO - 'TERM': 'linux', 16:08:32 INFO - 'TMOUT': '86400', 16:08:32 INFO - 'USER': 'cltbld', 16:08:32 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', 16:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:32 INFO - '_': '/tools/buildbot/bin/python'} 16:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:33 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 16:08:33 INFO - Cleaning up... 16:08:33 INFO - Return code: 0 16:08:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:08:33 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')]} 16:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:33 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')]} 16:08:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:33 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 0x1a7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d40180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3e3b0>, '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 0x1d3f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d3f740>, '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': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', '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 16:08:33 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 16:08:33 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 16:08:33 INFO - Using env: {'DISPLAY': ':0', 16:08:33 INFO - 'HOME': '/home/cltbld', 16:08:33 INFO - 'LANG': 'en_US.UTF-8', 16:08:33 INFO - 'LANGUAGE': 'en_US:en', 16:08:33 INFO - 'LOGNAME': 'cltbld', 16:08:33 INFO - 'MAIL': '/var/mail/cltbld', 16:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:33 INFO - 'MOZ_NO_REMOTE': '1', 16:08:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:33 INFO - 'NO_EM_RESTART': '1', 16:08:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:33 INFO - 'PWD': '/builds/slave/test', 16:08:33 INFO - 'SHELL': '/bin/bash', 16:08:33 INFO - 'SHLVL': '1', 16:08:33 INFO - 'TERM': 'linux', 16:08:33 INFO - 'TMOUT': '86400', 16:08:33 INFO - 'USER': 'cltbld', 16:08:33 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', 16:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:33 INFO - '_': '/tools/buildbot/bin/python'} 16:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:33 INFO - Downloading/unpacking psutil>=3.1.1 16:08:33 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 16:08:33 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 16:08:33 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 16:08:33 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 16:08:33 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 16:08:33 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 16:08:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:08:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:08:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:08:35 INFO - Installing collected packages: psutil 16:08:35 INFO - Running setup.py install for psutil 16:08:35 INFO - building 'psutil._psutil_linux' extension 16:08:35 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 16:08:35 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 16:08:35 INFO - building 'psutil._psutil_posix' extension 16:08:35 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 16:08:35 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 16:08:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:08:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:08:35 INFO - Successfully installed psutil 16:08:35 INFO - Cleaning up... 16:08:35 INFO - Return code: 0 16:08:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d40180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3e3b0>, '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 0x1d3f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d3f740>, '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': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', '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 16:08:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:08:35 INFO - Using env: {'DISPLAY': ':0', 16:08:35 INFO - 'HOME': '/home/cltbld', 16:08:35 INFO - 'LANG': 'en_US.UTF-8', 16:08:35 INFO - 'LANGUAGE': 'en_US:en', 16:08:35 INFO - 'LOGNAME': 'cltbld', 16:08:35 INFO - 'MAIL': '/var/mail/cltbld', 16:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:35 INFO - 'MOZ_NO_REMOTE': '1', 16:08:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:35 INFO - 'NO_EM_RESTART': '1', 16:08:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:35 INFO - 'PWD': '/builds/slave/test', 16:08:35 INFO - 'SHELL': '/bin/bash', 16:08:35 INFO - 'SHLVL': '1', 16:08:35 INFO - 'TERM': 'linux', 16:08:35 INFO - 'TMOUT': '86400', 16:08:35 INFO - 'USER': 'cltbld', 16:08:35 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', 16:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:35 INFO - '_': '/tools/buildbot/bin/python'} 16:08:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:08:38 INFO - Installing collected packages: mozsystemmonitor 16:08:38 INFO - Running setup.py install for mozsystemmonitor 16:08:38 INFO - Successfully installed mozsystemmonitor 16:08:38 INFO - Cleaning up... 16:08:38 INFO - Return code: 0 16:08:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1a7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d40180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3e3b0>, '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 0x1d3f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d3f740>, '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': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', '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 16:08:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:08:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:08:38 INFO - Using env: {'DISPLAY': ':0', 16:08:38 INFO - 'HOME': '/home/cltbld', 16:08:38 INFO - 'LANG': 'en_US.UTF-8', 16:08:38 INFO - 'LANGUAGE': 'en_US:en', 16:08:38 INFO - 'LOGNAME': 'cltbld', 16:08:38 INFO - 'MAIL': '/var/mail/cltbld', 16:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:38 INFO - 'MOZ_NO_REMOTE': '1', 16:08:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:38 INFO - 'NO_EM_RESTART': '1', 16:08:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:38 INFO - 'PWD': '/builds/slave/test', 16:08:38 INFO - 'SHELL': '/bin/bash', 16:08:38 INFO - 'SHLVL': '1', 16:08:38 INFO - 'TERM': 'linux', 16:08:38 INFO - 'TMOUT': '86400', 16:08:38 INFO - 'USER': 'cltbld', 16:08:38 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', 16:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:38 INFO - '_': '/tools/buildbot/bin/python'} 16:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:38 INFO - Downloading/unpacking jsonschema==2.5.1 16:08:38 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 16:08:38 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 16:08:38 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 16:08:38 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 16:08:38 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 16:08:38 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 16:08:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:08:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:08:40 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 16:08:40 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 16:08:40 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 16:08:40 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 16:08:40 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 16:08:40 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 16:08:40 INFO - Downloading functools32-3.2.3-2.tar.gz 16:08:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:08:40 INFO - warning: no files found matching '*.txt' 16:08:40 INFO - no previously-included directories found matching 'build' 16:08:40 INFO - no previously-included directories found matching 'dist' 16:08:40 INFO - no previously-included directories found matching '.git*' 16:08:40 INFO - Installing collected packages: jsonschema, functools32 16:08:40 INFO - Running setup.py install for functools32 16:08:40 INFO - warning: no files found matching '*.txt' 16:08:40 INFO - no previously-included directories found matching 'build' 16:08:40 INFO - no previously-included directories found matching 'dist' 16:08:40 INFO - no previously-included directories found matching '.git*' 16:08:40 INFO - Successfully installed jsonschema functools32 16:08:40 INFO - Cleaning up... 16:08:40 INFO - Return code: 0 16:08:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:08:40 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')]} 16:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:40 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')]} 16:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:40 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 0x1a7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d40180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3e3b0>, '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 0x1d3f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d3f740>, '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': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', '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 16:08:40 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 16:08:40 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 16:08:40 INFO - Using env: {'DISPLAY': ':0', 16:08:40 INFO - 'HOME': '/home/cltbld', 16:08:40 INFO - 'LANG': 'en_US.UTF-8', 16:08:40 INFO - 'LANGUAGE': 'en_US:en', 16:08:40 INFO - 'LOGNAME': 'cltbld', 16:08:40 INFO - 'MAIL': '/var/mail/cltbld', 16:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:40 INFO - 'MOZ_NO_REMOTE': '1', 16:08:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:40 INFO - 'NO_EM_RESTART': '1', 16:08:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:40 INFO - 'PWD': '/builds/slave/test', 16:08:40 INFO - 'SHELL': '/bin/bash', 16:08:40 INFO - 'SHLVL': '1', 16:08:40 INFO - 'TERM': 'linux', 16:08:40 INFO - 'TMOUT': '86400', 16:08:40 INFO - 'USER': 'cltbld', 16:08:40 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', 16:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:40 INFO - '_': '/tools/buildbot/bin/python'} 16:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:41 INFO - Downloading/unpacking blobuploader==1.2.4 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08:42 INFO - Downloading blobuploader-1.2.4.tar.gz 16:08:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:08:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:08:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:43 INFO - Downloading docopt-0.6.1.tar.gz 16:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:08:43 INFO - Installing collected packages: blobuploader, requests, docopt 16:08:43 INFO - Running setup.py install for blobuploader 16:08:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:08:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:08:43 INFO - Running setup.py install for requests 16:08:44 INFO - Running setup.py install for docopt 16:08:44 INFO - Successfully installed blobuploader requests docopt 16:08:44 INFO - Cleaning up... 16:08:44 INFO - Return code: 0 16:08:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08:44 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')]} 16:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:44 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')]} 16:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:44 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 0x1a7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d40180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3e3b0>, '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 0x1d3f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d3f740>, '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': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', '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 16:08:44 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 16:08:44 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 16:08:44 INFO - Using env: {'DISPLAY': ':0', 16:08:44 INFO - 'HOME': '/home/cltbld', 16:08:44 INFO - 'LANG': 'en_US.UTF-8', 16:08:44 INFO - 'LANGUAGE': 'en_US:en', 16:08:44 INFO - 'LOGNAME': 'cltbld', 16:08:44 INFO - 'MAIL': '/var/mail/cltbld', 16:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:44 INFO - 'MOZ_NO_REMOTE': '1', 16:08:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:44 INFO - 'NO_EM_RESTART': '1', 16:08:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:44 INFO - 'PWD': '/builds/slave/test', 16:08:44 INFO - 'SHELL': '/bin/bash', 16:08:44 INFO - 'SHLVL': '1', 16:08:44 INFO - 'TERM': 'linux', 16:08:44 INFO - 'TMOUT': '86400', 16:08:44 INFO - 'USER': 'cltbld', 16:08:44 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', 16:08:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:44 INFO - '_': '/tools/buildbot/bin/python'} 16:08:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:08:44 INFO - Running setup.py (path:/tmp/pip-zTWSsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:08:44 INFO - Running setup.py (path:/tmp/pip-TWb8zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:08:44 INFO - Running setup.py (path:/tmp/pip-kmJyJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:08:44 INFO - Running setup.py (path:/tmp/pip-fOwVqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:08:44 INFO - Running setup.py (path:/tmp/pip-pGNqdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:08:44 INFO - Running setup.py (path:/tmp/pip-6MN5TI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:08:44 INFO - Running setup.py (path:/tmp/pip-v_bJlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:08:45 INFO - Running setup.py (path:/tmp/pip-SDghLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:08:45 INFO - Running setup.py (path:/tmp/pip-MyX2lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:08:45 INFO - Running setup.py (path:/tmp/pip-o8tkFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:08:45 INFO - Running setup.py (path:/tmp/pip-mUklA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:08:45 INFO - Running setup.py (path:/tmp/pip-SR_5Z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:08:45 INFO - Running setup.py (path:/tmp/pip-orAFBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:08:45 INFO - Running setup.py (path:/tmp/pip-P5FQwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:45 INFO - Running setup.py (path:/tmp/pip-yzM8O5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:08:45 INFO - Running setup.py (path:/tmp/pip-u3ElEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:08:45 INFO - Running setup.py (path:/tmp/pip-T2UGHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:08:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:08:45 INFO - Running setup.py install for manifestparser 16:08:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:08:45 INFO - Running setup.py install for mozcrash 16:08:45 INFO - Running setup.py install for mozdebug 16:08:46 INFO - Running setup.py install for mozdevice 16:08:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Running setup.py install for mozfile 16:08:46 INFO - Running setup.py install for mozhttpd 16:08:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Running setup.py install for mozinfo 16:08:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Running setup.py install for mozInstall 16:08:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Running setup.py install for mozleak 16:08:46 INFO - Running setup.py install for mozlog 16:08:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Running setup.py install for moznetwork 16:08:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:08:46 INFO - Running setup.py install for mozprocess 16:08:47 INFO - Running setup.py install for mozprofile 16:08:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:08:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:08:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:08:47 INFO - Running setup.py install for mozrunner 16:08:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:08:47 INFO - Running setup.py install for mozscreenshot 16:08:47 INFO - Running setup.py install for moztest 16:08:47 INFO - Running setup.py install for mozversion 16:08:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:08:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:08:47 INFO - Cleaning up... 16:08:47 INFO - Return code: 0 16:08:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:47 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 0x1a7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d40180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3e3b0>, '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 0x1d3f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d3f740>, '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': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', '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 16:08:47 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 16:08:47 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 16:08:47 INFO - Using env: {'DISPLAY': ':0', 16:08:47 INFO - 'HOME': '/home/cltbld', 16:08:47 INFO - 'LANG': 'en_US.UTF-8', 16:08:47 INFO - 'LANGUAGE': 'en_US:en', 16:08:47 INFO - 'LOGNAME': 'cltbld', 16:08:47 INFO - 'MAIL': '/var/mail/cltbld', 16:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:47 INFO - 'MOZ_NO_REMOTE': '1', 16:08:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:47 INFO - 'NO_EM_RESTART': '1', 16:08:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:47 INFO - 'PWD': '/builds/slave/test', 16:08:47 INFO - 'SHELL': '/bin/bash', 16:08:47 INFO - 'SHLVL': '1', 16:08:47 INFO - 'TERM': 'linux', 16:08:47 INFO - 'TMOUT': '86400', 16:08:47 INFO - 'USER': 'cltbld', 16:08:47 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', 16:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:47 INFO - '_': '/tools/buildbot/bin/python'} 16:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:08:47 INFO - Running setup.py (path:/tmp/pip-4mZh1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:08:47 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)) 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:08:47 INFO - Running setup.py (path:/tmp/pip-lWE46m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:08:47 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)) 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:08:47 INFO - Running setup.py (path:/tmp/pip-P9DS3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:08:48 INFO - Running setup.py (path:/tmp/pip-MwbcOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:08:48 INFO - Running setup.py (path:/tmp/pip-4m9vPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:08:48 INFO - Running setup.py (path:/tmp/pip-ZPhycW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:08:48 INFO - Running setup.py (path:/tmp/pip-IK1YQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:08:48 INFO - Running setup.py (path:/tmp/pip-qAwrQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:08:48 INFO - Running setup.py (path:/tmp/pip-nQssHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:08:48 INFO - Running setup.py (path:/tmp/pip-gpiN20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:08:48 INFO - Running setup.py (path:/tmp/pip-s2oQHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:08:48 INFO - Running setup.py (path:/tmp/pip-zljBxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:08:48 INFO - Running setup.py (path:/tmp/pip-kGVMXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:08:48 INFO - Running setup.py (path:/tmp/pip-U9bePn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:48 INFO - Running setup.py (path:/tmp/pip-1Cjq7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:08:48 INFO - Running setup.py (path:/tmp/pip-G93LZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:08:48 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)) 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:08:49 INFO - Running setup.py (path:/tmp/pip-hVG5Ct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:08:49 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)) 16:08:49 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)) 16:08:49 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)) 16:08:49 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)) 16:08:49 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)) 16:08:49 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)) 16:08:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:08:49 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 16:08:49 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 16:08:49 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 16:08:49 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 16:08:49 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 16:08:49 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 16:08:50 INFO - Downloading blessings-1.6.tar.gz 16:08:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:08:51 INFO - Installing collected packages: blessings 16:08:51 INFO - Running setup.py install for blessings 16:08:51 INFO - Successfully installed blessings 16:08:51 INFO - Cleaning up... 16:08:51 INFO - Return code: 0 16:08:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:08:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:08:51 INFO - Reading from file tmpfile_stdout 16:08:51 INFO - Current package versions: 16:08:51 INFO - argparse == 1.2.1 16:08:51 INFO - blessings == 1.6 16:08:51 INFO - blobuploader == 1.2.4 16:08:51 INFO - docopt == 0.6.1 16:08:51 INFO - functools32 == 3.2.3-2 16:08:51 INFO - jsonschema == 2.5.1 16:08:51 INFO - manifestparser == 1.1 16:08:51 INFO - mozInstall == 1.12 16:08:51 INFO - mozcrash == 0.17 16:08:51 INFO - mozdebug == 0.1 16:08:51 INFO - mozdevice == 0.48 16:08:51 INFO - mozfile == 1.2 16:08:51 INFO - mozhttpd == 0.7 16:08:51 INFO - mozinfo == 0.9 16:08:51 INFO - mozleak == 0.1 16:08:51 INFO - mozlog == 3.2 16:08:51 INFO - moznetwork == 0.27 16:08:51 INFO - mozprocess == 0.23 16:08:51 INFO - mozprofile == 0.28 16:08:51 INFO - mozrunner == 6.12 16:08:51 INFO - mozscreenshot == 0.1 16:08:51 INFO - mozsystemmonitor == 0.3 16:08:51 INFO - moztest == 0.7 16:08:51 INFO - mozversion == 1.4 16:08:51 INFO - psutil == 3.1.1 16:08:51 INFO - requests == 1.2.3 16:08:51 INFO - wsgiref == 0.1.2 16:08:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08:51 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')]} 16:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:51 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')]} 16:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:51 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 0x1a7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d40180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3e3b0>, '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 0x1d3f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d3f740>, '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': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', '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 16:08:51 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 16:08:51 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 16:08:51 INFO - Using env: {'DISPLAY': ':0', 16:08:51 INFO - 'HOME': '/home/cltbld', 16:08:51 INFO - 'LANG': 'en_US.UTF-8', 16:08:51 INFO - 'LANGUAGE': 'en_US:en', 16:08:51 INFO - 'LOGNAME': 'cltbld', 16:08:51 INFO - 'MAIL': '/var/mail/cltbld', 16:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:51 INFO - 'MOZ_NO_REMOTE': '1', 16:08:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:51 INFO - 'NO_EM_RESTART': '1', 16:08:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:51 INFO - 'PWD': '/builds/slave/test', 16:08:51 INFO - 'SHELL': '/bin/bash', 16:08:51 INFO - 'SHLVL': '1', 16:08:51 INFO - 'TERM': 'linux', 16:08:51 INFO - 'TMOUT': '86400', 16:08:51 INFO - 'USER': 'cltbld', 16:08:51 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', 16:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:51 INFO - '_': '/tools/buildbot/bin/python'} 16:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:51 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)) 16:08:51 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)) 16:08:51 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)) 16:08:51 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)) 16:08:51 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)) 16:08:51 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)) 16:08:51 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)) 16:08:51 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)) 16:08:51 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)) 16:08:51 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)) 16:08:51 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)) 16:08:51 INFO - Cleaning up... 16:08:51 INFO - Return code: 0 16:08:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:08:51 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')]} 16:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:51 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')]} 16:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:51 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 0x1a7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d40180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3e3b0>, '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 0x1d3f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d3f740>, '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': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', '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 16:08:51 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 16:08:51 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 16:08:51 INFO - Using env: {'DISPLAY': ':0', 16:08:51 INFO - 'HOME': '/home/cltbld', 16:08:51 INFO - 'LANG': 'en_US.UTF-8', 16:08:51 INFO - 'LANGUAGE': 'en_US:en', 16:08:51 INFO - 'LOGNAME': 'cltbld', 16:08:51 INFO - 'MAIL': '/var/mail/cltbld', 16:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:51 INFO - 'MOZ_NO_REMOTE': '1', 16:08:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:51 INFO - 'NO_EM_RESTART': '1', 16:08:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:51 INFO - 'PWD': '/builds/slave/test', 16:08:51 INFO - 'SHELL': '/bin/bash', 16:08:51 INFO - 'SHLVL': '1', 16:08:51 INFO - 'TERM': 'linux', 16:08:51 INFO - 'TMOUT': '86400', 16:08:51 INFO - 'USER': 'cltbld', 16:08:51 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', 16:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:51 INFO - '_': '/tools/buildbot/bin/python'} 16:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:08:51 INFO - Cleaning up... 16:08:51 INFO - Return code: 0 16:08:51 INFO - Running post-action listener: _resource_record_post_action 16:08:51 INFO - Running post-action listener: _start_resource_monitoring 16:08:51 INFO - Starting resource monitoring. 16:08:51 INFO - ##### 16:08:51 INFO - ##### Finished create-virtualenv step (success) 16:08:51 INFO - ##### 16:08:51 INFO - ##### 16:08:51 INFO - ##### Running install step. 16:08:51 INFO - ##### 16:08:51 INFO - Running pre-action listener: _resource_record_pre_action 16:08:51 INFO - Running main action method: install 16:08:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:08:52 INFO - Reading from file tmpfile_stdout 16:08:52 INFO - Detecting whether we're running mozinstall >=1.0... 16:08:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:08:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:08:52 INFO - Reading from file tmpfile_stdout 16:08:52 INFO - Output received: 16:08:52 INFO - Usage: mozinstall [options] installer 16:08:52 INFO - Options: 16:08:52 INFO - -h, --help show this help message and exit 16:08:52 INFO - -d DEST, --destination=DEST 16:08:52 INFO - Directory to install application into. [default: 16:08:52 INFO - "/builds/slave/test"] 16:08:52 INFO - --app=APP Application being installed. [default: firefox] 16:08:52 INFO - mkdir: /builds/slave/test/build/application 16:08:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:08:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:09:09 INFO - Reading from file tmpfile_stdout 16:09:09 INFO - Output received: 16:09:09 INFO - /builds/slave/test/build/application/firefox/firefox 16:09:09 INFO - Running post-action listener: _resource_record_post_action 16:09:09 INFO - ##### 16:09:09 INFO - ##### Finished install step (success) 16:09:09 INFO - ##### 16:09:09 INFO - ##### 16:09:09 INFO - ##### Running run-tests step. 16:09:09 INFO - ##### 16:09:09 INFO - Running pre-action listener: _resource_record_pre_action 16:09:09 INFO - Running main action method: run_tests 16:09:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:09:09 INFO - Python 2.7.3 16:09:09 INFO - Return code: 0 16:09:09 INFO - grabbing minidump binary from tooltool 16:09:09 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')]} 16:09:09 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 0x1d3e3b0>, '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 0x1d3f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d3f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:09:09 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 16:09:09 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 16:09:09 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 16:09:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:09:09 INFO - Return code: 0 16:09:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:09:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:09:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:09:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:09:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:09:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:09:09 INFO - Using env: {'DISPLAY': ':0', 16:09:09 INFO - 'HOME': '/home/cltbld', 16:09:09 INFO - 'LANG': 'en_US.UTF-8', 16:09:09 INFO - 'LANGUAGE': 'en_US:en', 16:09:09 INFO - 'LOGNAME': 'cltbld', 16:09:09 INFO - 'MAIL': '/var/mail/cltbld', 16:09:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:09:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:09 INFO - 'MOZ_NO_REMOTE': '1', 16:09:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:09:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:09 INFO - 'NO_EM_RESTART': '1', 16:09:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:09 INFO - 'PWD': '/builds/slave/test', 16:09:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:09:09 INFO - 'SHELL': '/bin/bash', 16:09:09 INFO - 'SHLVL': '1', 16:09:09 INFO - 'TERM': 'linux', 16:09:09 INFO - 'TMOUT': '86400', 16:09:09 INFO - 'USER': 'cltbld', 16:09:09 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623', 16:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:09 INFO - '_': '/tools/buildbot/bin/python'} 16:09:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:09:09 INFO - mozversion application_buildid: 20160719133037 16:09:09 INFO - mozversion application_changeset: 1ebb6348564337c00d889bd858eedf3c922e68c0 16:09:09 INFO - mozversion application_display_name: Nightly 16:09:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:09:09 INFO - mozversion application_name: Firefox 16:09:09 INFO - mozversion application_remotingname: firefox 16:09:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:09:09 INFO - mozversion application_vendor: Mozilla 16:09:09 INFO - mozversion application_version: 50.0a1 16:09:09 INFO - mozversion platform_buildid: 20160719133037 16:09:09 INFO - mozversion platform_changeset: 1ebb6348564337c00d889bd858eedf3c922e68c0 16:09:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:09:09 INFO - mozversion platform_version: 50.0a1 16:09:09 INFO - using testdate: 1468969749 16:09:09 INFO - actual date: 1468969749 16:09:09 INFO - starting webserver on 'localhost:40039' 16:09:09 INFO - SUITE-START | Running 2 tests 16:09:09 INFO - TEST-START | dromaeo_css 16:09:09 INFO - operating with platform_type : linux_ 16:09:09 INFO - Initialising browser for dromaeo_css test... 16:09:09 INFO - TEST-INFO | started process 2356 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpok81xh/profile http://localhost:40039/getInfo.html) 16:09:13 INFO - PROCESS | 2356 | __metrics Screen width/height:1600/1200 16:09:13 INFO - PROCESS | 2356 | colorDepth:24 16:09:13 INFO - PROCESS | 2356 | Browser inner width/height: 1024/697 16:09:13 INFO - PROCESS | 2356 | __metrics 16:09:14 INFO - PROCESS | 2356 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:09:14 INFO - PROCESS | 2356 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:09:14 INFO - PROCESS | 2356 | console.error: PushService: 16:09:14 INFO - PROCESS | 2356 | stateChangeProcessEnqueue: Error transitioning state 16:09:14 INFO - PROCESS | 2356 | UnknownError 16:09:15 INFO - TEST-INFO | 2356: exit 0 16:09:15 INFO - Browser initialized. 16:09:15 INFO - Running cycle 1/1 for dromaeo_css test... 16:09:15 INFO - TEST-INFO | started process 2516 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpok81xh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:09:27 INFO - PROCESS | 2516 | RSS: Main: 157192192 16:09:27 INFO - PROCESS | 2516 | 16:10:17 INFO - PROCESS | 2516 | 1468969817874 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] 16:12:26 INFO - PROCESS | 2516 | Cycle 1(1): loaded http://localhost:40039/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40039/tests/dromaeo/cssquery-ext.html) 16:12:27 INFO - PROCESS | 2516 | RSS: Main: 260075520 16:12:27 INFO - PROCESS | 2516 | 16:15:30 INFO - PROCESS | 2516 | Cycle 1(1): loaded http://localhost:40039/tests/dromaeo/cssquery-ext.html (next: http://localhost:40039/tests/dromaeo/cssquery-jquery.html) 16:15:31 INFO - PROCESS | 2516 | RSS: Main: 245309440 16:15:31 INFO - PROCESS | 2516 | 16:18:33 INFO - PROCESS | 2516 | Cycle 1(1): loaded http://localhost:40039/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40039/tests/dromaeo/cssquery-mootools.html) 16:18:33 INFO - PROCESS | 2516 | RSS: Main: 243765248 16:18:33 INFO - PROCESS | 2516 | 16:21:36 INFO - PROCESS | 2516 | Cycle 1(1): loaded http://localhost:40039/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40039/tests/dromaeo/cssquery-prototype.html) 16:21:36 INFO - PROCESS | 2516 | RSS: Main: 235466752 16:21:36 INFO - PROCESS | 2516 | 16:24:39 INFO - PROCESS | 2516 | Cycle 1(1): loaded http://localhost:40039/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40039/tests/dromaeo/cssquery-yui.html) 16:24:39 INFO - PROCESS | 2516 | RSS: Main: 186155008 16:24:39 INFO - PROCESS | 2516 | 16:27:42 INFO - PROCESS | 2516 | Cycle 1(1): loaded http://localhost:40039/tests/dromaeo/cssquery-yui.html (next: http://localhost:40039/tests/dromaeo/cssquery-dojo.html) 16:27:42 INFO - PROCESS | 2516 | RSS: Main: 266571776 16:27:42 INFO - PROCESS | 2516 | 16:27:42 INFO - PROCESS | 2516 | __start_tp_report 16:27:42 INFO - PROCESS | 2516 | _x_x_mozilla_page_load 16:27:42 INFO - PROCESS | 2516 | _x_x_mozilla_page_load_details 16:27:42 INFO - PROCESS | 2516 | |i|pagename|runs| 16:27:42 INFO - PROCESS | 2516 | |0;dojo.html;3054.801437906536;3074.4312302224102;3083.167544762914;3104.115327131768;3126.202818281338;5308.416074231832;5324.440933701959;5379.435159308275;5421.078416669166;5447.264619276401;5329.413764485904;5334.546563542094;5335.039692855285;5374.892502149954;5377.274068000819;4793.233082706767;4838.419389673227;4879.780409881546;4889.437714662806;5101.336826212587;5013.147764879979;5026.271190677349;5048.242763585473;5057.342545469084;5069.2142717878605;4855.878603034935;4887.828925987574;4901.975490122527;4947.678400903929;4948.035133149037;4897.2898929655;4900.975495122537;5008.974955125237;5029.622778291643;5034.295198672189;4931.211006238985;4951.925721114186;4962.15643340633;4965.801367945278;4968.701877887338;5247.029299579552;5476.342838859362;5498.257735205736;5515.889682206373;5526.696031718254;5704.771809127671;5752.568557358173;5755.539556835443;5793.5654825887805;5834.883302333929;5763.193152958589;5796;5854;5865.178874957509;5870.530357571384;5281.815136470205;5377;5406.59450541207;5456.045192091407;5468.070428027205;5926.585139040226;5983.401659833982;5989.9101513477335;6040.637561746309;6054.939450605526;5738.77044918207;5739.713014349266;5786.363500014995;5788.5369170466265;5791.391903850119;5546.556275498031;5661.405552417019;5816.41835816415;5879.588428809942;5885.823425297248;5697.772089116472;5714;5730.627509211888;5765.971170144123;5773.88452230953;5706.429357064343;5735.340435849847;5736.71316434185;5745.741441635053;5764.481196692318;4291.291936830388;4311.116221174651;4341.175176716414;4367.868963931023;4398.428204333416;3895.49358583388;3909.3745000800304;3920.941185882214;3937.7440466369594;3991.4611527444004;5517.944820551743;5528.530074943675;5528.9723551381985;5535.640183388067;5573.5819813513335;5575.581831362664;5833.183354330397;5844.649321040752;5846.883062338815;5890.263717035371;5434.8913021739345;5454.645448045864;5461.563074953994;5481.75332110056;5539.529140023144;5000.224965130411;5004.3994720633755;5009.849704508871;5010.549050585465;5047.823326183566;5807.738651760681;5933.991221492271;5963;5968.701564921824;5982.9401705983255;5740.770369185186;5801.796937107181;5801.8549536261935;5823.388544202967;5886.558508111823;5927.940720592739;5942.910856337072;5948.613340132878;5980.073088671263;5995.46040856325;5552.278203833475;5650.886982260414;5692.0608099663095;5696.487316141567;5701.572382071444;5728.914066288926;5775.566832487664;5784.1034639630925;5823.213866128019;5873.3832947541605;5589.133684278943;5669.206311116364;5669.688167150846;5669.780997085646;5678.176664383559;5493.725313734377;5665.688387138582;5668.291463567054;5670.631408958405;5677;5518.448155184449;5519.0893502573235;5574.888502229852;5607.607467477237;5652.4064973178865;5688.687122208318;5688.943110568841;5699.344575373885;5753.05074662676;5760.1359796029255;5304.920426193533;5600.523955463831;5601.943980560142;5603.635763675511;5610.18652295423 16:27:42 INFO - PROCESS | 2516 | |1;ext.html;595.7229888102032;602.7197353230747;620.6958590290756;637.6301744987904;637.9330170332112;43989;45359.54640453595;45391;45392.77303613477;48695.269570956465;46472.76763616186;46790.532094679045;46901.530984690144;47098.52901470984;47100.76449617756;8971.95514022429;9126.908730912672;9136.269098472107;9148.405353651991;9149.405288656217;8944.8658270126;9019.909800902005;9029.729108126767;9041.231495322905;9042.050584688612;9128.452292862416;9142.360034797566;9157.771055723593;9167.862482062774;9168.770780730501;9019.23336516397;9043.095690430937;9077.682281120162;9085.318601104911;9089.500077495735;8963.731088067369;9037.277017838556;9052;9052.8642070369;9053.411528250643;43149.784251078854;43412.34881476781;43463.565364346265;43509;43526.12947741059;3654.7258955578436;3735.9252814943547;3827.75119617224;3850.4609354690365;4300.849470268526;31322.843385782926;33932.49101263483;34403.655963440295;34433.65566344329;35729.46405803915;4435.645148388154;4580.358749775034;4592.7933243004145;4705.670603057787;4710.74090924999;413844;419078;420018;425448;425565;64246;64734.028989565195;64799.67600161969;64815;64820.35179648238;68757.31242687609;68774;69293;69362.65318673375;69464;18048;18207.817921820613;18236;18246.178921948544;18247.27010919575;13861.4455421782;14009.929950350386;14014.85985140156;14024.859751402559;14040.85959140416;18308.952430001915;20818.89590552068;20854.37436876866;21018.15927362919;21043.789562104488;7633.313001829862;7769.494982826098;7894.802629934183;7919.2872641462545;7947.483413578099;718.9996362485458;1018.567108978696;1094.6332978452149;1094.68801391603;1134.472470301314;32530.837345813594;33419.49870751941;33528.32943341119;33822;33857.32285354279;1120.2942146447685;1141.594733869481;1159.4260840883817;1194.6177223288464;1234.493857518413;17302.394416195373;17643;17657.823421765876;17881.7317740234;17944.551386215448;600238;601174;606211;606623;607029;59803.40196598065;60346.698266509266;60514.69742651346;60588.39411605916;60610.39389606136;1222.7982382906898;1328.0637150808657;1351.2770667692737;1355.098859258582;1363.2638375277238;32503.674963250065;33848.49227261642;34268.31463370768;34342.82828585841;34388.312233755685;50578.2413263794;51169.488305117964;51199;51296.74351628218;51306.74346628243;424755;427268;430605.1636901796;430997;431122;35745.642543574235;36497.63502365049;37308;37323.62676373201;40307;45687;48306.5169348302;48636.27045594389;48858.75570622124;50695;41241.793791030854;43045;43529.347059793494;43551.78224108859;43677.78161109174;41771;41852.37221441742;42371.1525769489;43421.56578434175;43452.56547434612;41483.37774933439;42366.36450453184;42784;42859.14281714411;43064;34355;36381.81809090938;37042.444363334034;37254.25491490209;37784.811075944446;27919.20652448373;27935.09228291837;27944.113754975024;27988.358533254992;28105.839306900125 16:27:42 INFO - PROCESS | 2516 | |2;jquery.html;386.2583839029064;389.4819889546903;401.62448111016204;401.6605967957076;415.5576883119453;12970.80543791844;19385.515362115955;19996.800031999675;20049;20088.69866951997;15970.441034563793;18661.440156795317;19332.420027399166;19455.31906383277;19531.70702439461;5856.736446859902;6046.939530604682;6048.727807248662;6053.424924632153;6065.878682426349;5893.469587737103;5896.292444906617;5963;6013.218281623383;6026.367231440701;5747.33905600855;5881.4118588141055;5883.911741323884;5886.087656413241;5928.970355148218;5858.326292476378;5881.5294776417995;6764.3235676432205;7057.223705392403;7096;6873.518853680244;6895.34494223048;6898.379145876845;6951.617661028641;6994.5803251805055;26705.465890682077;26905.73094269052;27253;27890.860545697346;28204.15387538377;2748.3816141368234;3200.7919485233383;3362.7982321060813;3436.3127374525193;3472.2187507810795;16089.839101609068;16155.434559790468;16365.509034728977;16459.58851028722;16493.505194844172;2085.895705214733;2104.2635077722825;2143.110609097223;2178.673199020144;2201.1965632544166;81598.59200703958;81801.59099204525;87088;88342;99714.50142749313;17254.309827606754;17271.56821079483;18253.908730456264;18405.26378944854;18411.72382414265;32939.341213175605;32977.67022329746;33014.17464563357;33047.17382065419;33180.50229246563;9954.502274886227;10160.034796694303;10178.23663225261;10307.639232626972;10401.53193106312;8527.957360213159;8538.78653033679;8556.315494760403;8791.34064945119;9006;7392.780191268488;8340.916590834015;9061.728148155566;9172.44965302084;9253.306002049872;3308.500439402411;3480.4431290993825;3527.730017193808;3572.214112895159;3601.6188843255186;934.509382574154;941.1294552538874;953.3850666320185;982.3958265666706;1000.3347773730533;18753.531161721065;20154.193832246838;20501.897490512452;20688.793112068688;20787.8960605196;968.7335982604699;1020.9846852297221;1021.5760459409262;1031.9845202321972;1057.751428414322;10756.78486430267;11441.313521188755;11589.536418543164;11641.359725215025;11666.241694289844;473511;479048.4428200739;492383.30466781644;493856;495360;82692.58653706693;85343;86817;90048.54975725079;91866.54066729624;952.9475878826731;960.865478832964;980.7940332530248;984.389678399397;987.7086259553449;16868.831311686725;17372.739408908623;17989.19048642788;18222.817771822643;18464.35374761877;40118.79940600395;40663.59336406598;41212.58787412087;42004.57995420129;42167;54301.72849135729;56019;56923;57633;57860.42139578719;7786;7843.254890785366;7973.561454119847;8332.375071869512;8341.24928756415;9196.678116265899;13090.869091309345;13136.60590182296;13420.261885595995;13446.46214151423;10308.072273495422;11813.173077884465;11837.881621184022;11965.461554230254;12336.753264934472;11697.824532631847;11738.589149380072;11780.998615117469;12002.21985570935;12106.273623582612;10744;11038.613648522263;11421.257618255118;12083.51665933388;12160.391980400767;7888;7889.717271020431;7986.161453047579;8157.632906519102;8189.092872030344;10618.309809862334;10981;11229.38238396896;11846.229995050639;11996.880031199576 16:27:42 INFO - PROCESS | 2516 | |3;mootools.html;1314.4085161677247;1472.1240861687293;1496.4313560846876;1646.1686848141687;1802.648483545709;8597.613107410167;8819.073997230287;9015.594298256587;9227.723168304945;9227.861582076257;9080.909190908089;9176.357654964155;9179.49512776797;9189.081091890808;9195.218406435444;2013.3859172952243;2298.7241531016302;2324.2446204983407;2353.399883029825;2359.457324815285;2333.195047708538;2347.746163682858;2350.9882450587725;2353.14110349722;2358.0803486640225;2303.0787684925995;2346.120204923154;2346.3547524430746;2365.089440565386;2365.219477572397;1974.1215159254139;2028.3205126282712;2253.1550668499312;2255.289583781112;2271.2618399020303;2227.6324406472913;2278.5556816420803;2291.209532711212;2292.209187830196;2294.564032833773;9192.172704456563;9195.90804091964;9240.168384845396;9247.953760231223;9252.676156334495;5200.791968321262;5314.441983591706;5316.38861530925;5339.733013349356;5343.305370301836;3275.0162625882103;3320.236345640516;3402.302527981758;3438.8452519636426;3455.3434847378917;1076.580133747839;1142.874283828778;1143.2397455691955;2177.945551361213;2221.8222542196745;12711.046671499675;12785.977121830232;12799.232046077264;12869.356532173448;12870.227786332756;4320.287152619845;4485.349624304457;4628.3288923105965;4643.582077613031;4660.300954856799;10070.546825392876;10132.392056476487;10133.290669653054;10146;10157.085862272283;5365.08793505105;5365.490278423544;5367.946320536745;5370.248165256867;5372.328458942632;4353.782310884443;4807.591354734886;4882.072406242803;4997.5002499749735;5000.949990500048;3802.8288727007352;3825.3305671507374;3903.2193561287854;3947.9605203947594;3994.840206391712;1154.6362895687832;1162.8255761635821;1164.342146687119;1171.9648410547697;1189.4587962477053;869.4479005831342;940.4780346907532;977.237754551451;997.9002099789963;1033.1476531861244;5973.671448070398;6151.84620384494;6332.271788744261;6348.269948955929;6591.736330546724;463.84924899407827;469.5140529551966;470.74814973988754;475.16371186710944;478.4665098415242;4321.891952701208;4449.822007119744;4450.643948484114;4460.130274596488;4462.888427789331;11093.778124437627;11619.302841829518;11683.123765717604;11708;11722.94138529302;10751.408672523086;11175.72060698489;11584.42077896102;11590.94204528972;11616.593419230287;7954.403419743542;8115.513069215748;8134.552599607078;8144.877826832602;8146.226108519562;6499.5125365598715;6508.251551071498;6587.440067594308;6649.833754156185;6832.965835170792;9832.754181145528;10471.371717696962;10527.736806579896;10628.681139565826;10702.946485267525;10368.740781480523;10537.051665350155;10543.42011189392;10620.09729173029;10632.149428045583;6990.510664253657;7659.387249020177;7867.763967080997;7983.560904150328;8115.634796434293;8852.114788521096;9032.14194651507;9032.548372581214;9047.411918225294;9049.004609493082;7831.373490120891;7869.3311068559815;8050.114487406498;8214.794630134294;8320.875186872081;7655.846883062196;7692.499987500794;7714.267144621252;7837.6864925404625;7850.725224617111;7010.859782804418;7098.929010709827;7188.676509557187;7224.422046236184;7224.89162662571;5509.586780991522;5572.4148964358155;5612.775488980558;5639.971800140973;5729.341125770423;5260.132078207206;5264.044554112113;5369.328833895763;5375.274337964324;5548.750306236309 16:27:42 INFO - PROCESS | 2516 | |4;prototype.html;318.6001568032119;334.03130920331034;339.9490076488526;340.2446568617669;344.6742828027515;5108.386993560774;5254.973725131374;5311.362636483623;5312.370376663557;5320.441353657859;5248.317718696574;5252.185911183773;5269.288646032794;5283.973580132104;5284.920726189111;4131.917361652765;4183.288840897055;4203.789810509477;4204.264253755596;4219.282721937278;3953.983468431092;4124.793760311987;4155.9817844628105;4159.916801663965;4183.45615070042;4119.8352065917325;4124.711270211086;4125.113100683352;4126.731762435447;4138.8137533810905;4123.3608790637345;4142.384470056681;4148.336266197424;4154.480689913761;4162.188373267214;3585.2112535242204;3670.9816450917842;3678.2275722098393;3682.7606205596817;3685.870994515179;5086.16078347076;5104;5138.743062846843;5153.510416510426;5166.4575219601775;4680.4149481314835;4704.670673052888;4721.220998535237;4722.669413141081;4731.858044258678;5206.609504287194;5390.6226564140525;5428.240046393508;5441.2654291670515;5499;3943.4084887266854;3957.3866050762176;3961.861334853295;3966.2662407454714;4015.357542793139;5423.972880135613;5520.420355862657;5677.489025987681;5699;5713;4012.6156476015726;4029.37544680575;4029.395590661424;4034.2334956358786;4046.716729828943;4069.552349241581;4087.509498860156;4112.074783173822;4115.403266526337;4119.814608342632;4465.285554311295;4520.276755719135;4672.042231342567;4706.364640773451;4715.127701375188;5451.64564303319;5452.345718513724;5476.288082549243;5480.945190548044;5516.9448305517235;1431.3058166789099;1450.9492167774076;1453.1789538910482;1453.3895763779237;1467.288365142905;912.431761545997;963.1909196275162;974.3325821812082;979.2459805949379;986.8125056239292;4704.50602686713;4741.38362012943;4794.640401969867;4818.469968303484;4820.9517904821205;5043.142665746832;5051.747412629354;5082.161443361878;5083.644144909895;5091.719955402415;4635.791389387486;4785.325136202371;4804.471508134103;4839.483468596311;4865.443058221406;2648.0731743889482;2682.262377846108;2686.2673575544054;2715.986420067887;2734.4277040701277;5196.844094677166;5214.374275087014;5226.738663066832;5236.816711415082;5242.239875218072;4930.630202734809;5091.669041512364;5154.252633368215;5160.561352285023;5160.638755287169;5061.519155680205;5097.515736005073;5119.6416250862085;5126.359205099363;5196.610254230872;5032.647714659939;5040.647154699136;5062.949370506395;5077.086124497626;5102.897942041212;4737.944026103075;4912.042151780369;4985.626078044089;5007.774650140679;5011.621804003928;5042.647014709082;5064.53128592704;5121.848823494216;5575;5612.943870561242;4122.381642753611;4141.461609990682;4171.666266698596;4175.853845115406;4178.35235538492;5645.435456454322;5657.377688454185;5666.498377929769;5706.914396283976;5725.312962444534;5243.89512209746;5536.141898005815;5547.916781248204;5550.333959924835;5561.221429000024;5466.480684334982;5512.5314348280845;5549.500544950974;5556.277683901067;5559.777608895761;5507.862303442446;5583.385827558884;5587.525060369914;5593.636413633101;5596.1605759136455;5367.4364191759305;5588.636738611977;5608.803691870765;5629.493345598916;5634.295713035871;5482.671039737628;5512.5589952804485;5519.586031047607;5558.471945165203;5571.415001424792 16:27:42 INFO - PROCESS | 2516 | |5;yui.html;745.2770812312057;760.3157158557301;785.9096203936546;804.9637766300516;818.6561644109473;15515.146666933315;17338.046407447586;18244.452666419995;18275;18278.72581911269;17109.315627374923;17278.395256166037;17279.30882764688;17280;17290.740638890427;817.6116344736262;836.3852568362249;837.5728378526934;838.4927119092922;839.7019058234331;682.3074579302013;815.9510429374257;830.3855147191096;834.2366734437982;846.1327139681814;873.5501216873283;876.369014309696;877.2893955895745;877.4384393987852;877.6313948141764;869.3783944479727;871.2115535440437;872.0755998641429;872.1801506583791;872.2367928062945;517.7359546631208;624.3350831364583;633.5438484291303;635.5265327331122;643.3309358267396;15546.844531554652;15590.454334098366;15632.2183890805;15637.765433518507;15637.765433518507;13444.943987679695;13859.930700346535;13976.161430314214;14292.857071429256;14440.783388249185;11512.481938312796;11999.64001079969;12313.69215769604;12888.677783055406;13240.801387979189;3979.383195604686;3985.6811455083525;4004.2992476316526;4025.496812898388;4087.3255912774357;81961.59019204926;82657.58671206726;82700.58649706712;83490;83601;11084.667459976214;11089.27919685234;11639;11652.94173529127;11995.400229988465;22238.332850014525;22310.88844555767;22320.10719571199;22346.664800027687;22346.776532234795;8483.575821209039;8522.616482258314;8754.69358572447;8918.821623567623;8974.326925480615;4347.739135651871;4431.158079964796;4623.86128416148;4629.976850115728;4687.343771871941;3504.281622267429;3574.982125089358;3591.8742844000335;3609.1338078860886;3611.1694310308776;563;564.8898464799324;569.5728203847115;599.3946114424375;608.2002167150183;626.0921663587725;653.4511010750897;688.8338744187683;716.5091912040269;733.1825015108116;10206.336588121749;10278.73867749094;10362.740931476774;10523.368597884151;10601.622127896908;617.8764247150588;631.6904716688789;631.9936800632026;703.6165289917008;725.7858931615186;4647.907041859212;4860.854174374775;5028.572571331405;5029.874253143627;5044.823431179964;69542.3045769546;70895;71259;116757;122964;73245.6337718308;98660;98660.50669746606;99848;100163;55383.723081384334;57206.7139664299;87741.56129219313;92032;93659.53170234105;9402.200812930978;9614.95192524033;9707;9722.854157187789;9730;53766.19350709664;53850.46149538611;54367;55150.44849551453;55535.44464555303;71170.64414677894;78024;79820.60089699514;80977.19022809697;81890.59054704689;12065.879341206475;12149;12395.380230988594;12506.74986500283;12788.552400665933;14169.149851008973;14209.928950355596;14513.201773902536;14523.34644940959;14728.116313021254;12233.510659573518;12667.556635517712;12712.618621441372;12827.422765975367;12979.675508112372;12457.750844983231;12627.621171364874;12846.950815754448;12860;13031.283279419724;12520.686982825502;13486.393112310167;13808.792868106786;14231.146131232159;14270.857291427368;10072.244581656258;10076.395416274754;10658.360498370123;10723.142148628247;10932.289401188897;10517.368957862553;11103;11119.777604448027;11213.439328033402;11301.491432885376 16:27:42 INFO - PROCESS | 2516 | __end_tp_report 16:27:42 INFO - PROCESS | 2516 | __start_cc_report 16:27:42 INFO - PROCESS | 2516 | _x_x_mozilla_cycle_collect,941 16:27:42 INFO - PROCESS | 2516 | __end_cc_report 16:27:42 INFO - PROCESS | 2516 | __startTimestamp1468970862854__endTimestamp 16:27:42 INFO - PROCESS | 2516 | 16:27:42 INFO - PROCESS | 2516 | ------- Summary: start ------- 16:27:42 INFO - PROCESS | 2516 | Number of tests: 6 16:27:42 INFO - PROCESS | 2516 | 16:27:42 INFO - PROCESS | 2516 | [#0] dojo.html Cycles:165 Average:5376.56 Median:5575.58 stddev:616.93 (11.1%) stddev-sans-first:591.32 16:27:42 INFO - PROCESS | 2516 | Values: 3054.8 3074.4 3083.2 3104.1 3126.2 5308.4 5324.4 5379.4 5421.1 5447.3 5329.4 5334.5 5335.0 5374.9 5377.3 4793.2 4838.4 4879.8 4889.4 5101.3 5013.1 5026.3 5048.2 5057.3 5069.2 4855.9 4887.8 4902.0 4947.7 4948.0 4897.3 4901.0 5009.0 5029.6 5034.3 4931.2 4951.9 4962.2 4965.8 4968.7 5247.0 5476.3 5498.3 5515.9 5526.7 5704.8 5752.6 5755.5 5793.6 5834.9 5763.2 5796.0 5854.0 5865.2 5870.5 5281.8 5377.0 5406.6 5456.0 5468.1 5926.6 5983.4 5989.9 6040.6 6054.9 5738.8 5739.7 5786.4 5788.5 5791.4 5546.6 5661.4 5816.4 5879.6 5885.8 5697.8 5714.0 5730.6 5766.0 5773.9 5706.4 5735.3 5736.7 5745.7 5764.5 4291.3 4311.1 4341.2 4367.9 4398.4 3895.5 3909.4 3920.9 3937.7 3991.5 5517.9 5528.5 5529.0 5535.6 5573.6 5575.6 5833.2 5844.6 5846.9 5890.3 5434.9 5454.6 5461.6 5481.8 5539.5 5000.2 5004.4 5009.8 5010.5 5047.8 5807.7 5934.0 5963.0 5968.7 5982.9 5740.8 5801.8 5801.9 5823.4 5886.6 5927.9 5942.9 5948.6 5980.1 5995.5 5552.3 5650.9 5692.1 5696.5 5701.6 5728.9 5775.6 5784.1 5823.2 5873.4 5589.1 5669.2 5669.7 5669.8 5678.2 5493.7 5665.7 5668.3 5670.6 5677.0 5518.4 5519.1 5574.9 5607.6 5652.4 5688.7 5688.9 5699.3 5753.1 5760.1 5304.9 5600.5 5601.9 5603.6 5610.2 16:27:42 INFO - PROCESS | 2516 | 16:27:42 INFO - PROCESS | 2516 | [#1] ext.html Cycles:180 Average:65311.30 Median:43416.96 stddev:130597.76 (300.8%) stddev-sans-first:130873.73 16:27:42 INFO - PROCESS | 2516 | Values: 595.7 602.7 620.7 637.6 637.9 43989.0 45359.5 45391.0 45392.8 48695.3 46472.8 46790.5 46901.5 47098.5 47100.8 8972.0 9126.9 9136.3 9148.4 9149.4 8944.9 9019.9 9029.7 9041.2 9042.1 9128.5 9142.4 9157.8 9167.9 9168.8 9019.2 9043.1 9077.7 9085.3 9089.5 8963.7 9037.3 9052.0 9052.9 9053.4 43149.8 43412.3 43463.6 43509.0 43526.1 3654.7 3735.9 3827.8 3850.5 4300.8 31322.8 33932.5 34403.7 34433.7 35729.5 4435.6 4580.4 4592.8 4705.7 4710.7 413844.0 419078.0 420018.0 425448.0 425565.0 64246.0 64734.0 64799.7 64815.0 64820.4 68757.3 68774.0 69293.0 69362.7 69464.0 18048.0 18207.8 18236.0 18246.2 18247.3 13861.4 14009.9 14014.9 14024.9 14040.9 18309.0 20818.9 20854.4 21018.2 21043.8 7633.3 7769.5 7894.8 7919.3 7947.5 719.0 1018.6 1094.6 1094.7 1134.5 32530.8 33419.5 33528.3 33822.0 33857.3 1120.3 1141.6 1159.4 1194.6 1234.5 17302.4 17643.0 17657.8 17881.7 17944.6 600238.0 601174.0 606211.0 606623.0 607029.0 59803.4 60346.7 60514.7 60588.4 60610.4 1222.8 1328.1 1351.3 1355.1 1363.3 32503.7 33848.5 34268.3 34342.8 34388.3 50578.2 51169.5 51199.0 51296.7 51306.7 424755.0 427268.0 430605.2 430997.0 431122.0 35745.6 36497.6 37308.0 37323.6 40307.0 45687.0 48306.5 48636.3 48858.8 50695.0 41241.8 43045.0 43529.3 43551.8 43677.8 41771.0 41852.4 42371.2 43421.6 43452.6 41483.4 42366.4 42784.0 42859.1 43064.0 34355.0 36381.8 37042.4 37254.3 37784.8 27919.2 27935.1 27944.1 27988.4 28105.8 16:27:42 INFO - PROCESS | 2516 | 16:27:42 INFO - PROCESS | 2516 | [#2] jquery.html Cycles:180 Average:30187.13 Median:3549.97 stddev:80126.39 (2257.1%) stddev-sans-first:80319.92 16:27:42 INFO - PROCESS | 2516 | Values: 386.3 389.5 401.6 401.7 415.6 12970.8 19385.5 19996.8 20049.0 20088.7 15970.4 18661.4 19332.4 19455.3 19531.7 5856.7 6046.9 6048.7 6053.4 6065.9 5893.5 5896.3 5963.0 6013.2 6026.4 5747.3 5881.4 5883.9 5886.1 5929.0 5858.3 5881.5 6764.3 7057.2 7096.0 6873.5 6895.3 6898.4 6951.6 6994.6 26705.5 26905.7 27253.0 27890.9 28204.2 2748.4 3200.8 3362.8 3436.3 3472.2 16089.8 16155.4 16365.5 16459.6 16493.5 2085.9 2104.3 2143.1 2178.7 2201.2 81598.6 81801.6 87088.0 88342.0 99714.5 17254.3 17271.6 18253.9 18405.3 18411.7 32939.3 32977.7 33014.2 33047.2 33180.5 9954.5 10160.0 10178.2 10307.6 10401.5 8528.0 8538.8 8556.3 8791.3 9006.0 7392.8 8340.9 9061.7 9172.4 9253.3 3308.5 3480.4 3527.7 3572.2 3601.6 934.5 941.1 953.4 982.4 1000.3 18753.5 20154.2 20501.9 20688.8 20787.9 968.7 1021.0 1021.6 1032.0 1057.8 10756.8 11441.3 11589.5 11641.4 11666.2 473511.0 479048.4 492383.3 493856.0 495360.0 82692.6 85343.0 86817.0 90048.5 91866.5 952.9 960.9 980.8 984.4 987.7 16868.8 17372.7 17989.2 18222.8 18464.4 40118.8 40663.6 41212.6 42004.6 42167.0 54301.7 56019.0 56923.0 57633.0 57860.4 7786.0 7843.3 7973.6 8332.4 8341.2 9196.7 13090.9 13136.6 13420.3 13446.5 10308.1 11813.2 11837.9 11965.5 12336.8 11697.8 11738.6 11781.0 12002.2 12106.3 10744.0 11038.6 11421.3 12083.5 12160.4 7888.0 7889.7 7986.2 8157.6 8189.1 10618.3 10981.0 11229.4 11846.2 11996.9 16:27:42 INFO - PROCESS | 2516 | 16:27:42 INFO - PROCESS | 2516 | [#3] mootools.html Cycles:180 Average:5962.98 Median:4651.94 stddev:3448.78 (74.1%) stddev-sans-first:3440.76 16:27:42 INFO - PROCESS | 2516 | Values: 1314.4 1472.1 1496.4 1646.2 1802.6 8597.6 8819.1 9015.6 9227.7 9227.9 9080.9 9176.4 9179.5 9189.1 9195.2 2013.4 2298.7 2324.2 2353.4 2359.5 2333.2 2347.7 2351.0 2353.1 2358.1 2303.1 2346.1 2346.4 2365.1 2365.2 1974.1 2028.3 2253.2 2255.3 2271.3 2227.6 2278.6 2291.2 2292.2 2294.6 9192.2 9195.9 9240.2 9248.0 9252.7 5200.8 5314.4 5316.4 5339.7 5343.3 3275.0 3320.2 3402.3 3438.8 3455.3 1076.6 1142.9 1143.2 2177.9 2221.8 12711.0 12786.0 12799.2 12869.4 12870.2 4320.3 4485.3 4628.3 4643.6 4660.3 10070.5 10132.4 10133.3 10146.0 10157.1 5365.1 5365.5 5367.9 5370.2 5372.3 4353.8 4807.6 4882.1 4997.5 5000.9 3802.8 3825.3 3903.2 3948.0 3994.8 1154.6 1162.8 1164.3 1172.0 1189.5 869.4 940.5 977.2 997.9 1033.1 5973.7 6151.8 6332.3 6348.3 6591.7 463.8 469.5 470.7 475.2 478.5 4321.9 4449.8 4450.6 4460.1 4462.9 11093.8 11619.3 11683.1 11708.0 11722.9 10751.4 11175.7 11584.4 11590.9 11616.6 7954.4 8115.5 8134.6 8144.9 8146.2 6499.5 6508.3 6587.4 6649.8 6833.0 9832.8 10471.4 10527.7 10628.7 10702.9 10368.7 10537.1 10543.4 10620.1 10632.1 6990.5 7659.4 7867.8 7983.6 8115.6 8852.1 9032.1 9032.5 9047.4 9049.0 7831.4 7869.3 8050.1 8214.8 8320.9 7655.8 7692.5 7714.3 7837.7 7850.7 7010.9 7098.9 7188.7 7224.4 7224.9 5509.6 5572.4 5612.8 5640.0 5729.3 5260.1 5264.0 5369.3 5375.3 5548.8 16:27:42 INFO - PROCESS | 2516 | 16:27:42 INFO - PROCESS | 2516 | [#4] prototype.html Cycles:180 Average:4503.36 Median:5056.63 stddev:1281.85 (25.3%) stddev-sans-first:1246.37 16:27:42 INFO - PROCESS | 2516 | Values: 318.6 334.0 339.9 340.2 344.7 5108.4 5255.0 5311.4 5312.4 5320.4 5248.3 5252.2 5269.3 5284.0 5284.9 4131.9 4183.3 4203.8 4204.3 4219.3 3954.0 4124.8 4156.0 4159.9 4183.5 4119.8 4124.7 4125.1 4126.7 4138.8 4123.4 4142.4 4148.3 4154.5 4162.2 3585.2 3671.0 3678.2 3682.8 3685.9 5086.2 5104.0 5138.7 5153.5 5166.5 4680.4 4704.7 4721.2 4722.7 4731.9 5206.6 5390.6 5428.2 5441.3 5499.0 3943.4 3957.4 3961.9 3966.3 4015.4 5424.0 5520.4 5677.5 5699.0 5713.0 4012.6 4029.4 4029.4 4034.2 4046.7 4069.6 4087.5 4112.1 4115.4 4119.8 4465.3 4520.3 4672.0 4706.4 4715.1 5451.6 5452.3 5476.3 5480.9 5516.9 1431.3 1450.9 1453.2 1453.4 1467.3 912.4 963.2 974.3 979.2 986.8 4704.5 4741.4 4794.6 4818.5 4821.0 5043.1 5051.7 5082.2 5083.6 5091.7 4635.8 4785.3 4804.5 4839.5 4865.4 2648.1 2682.3 2686.3 2716.0 2734.4 5196.8 5214.4 5226.7 5236.8 5242.2 4930.6 5091.7 5154.3 5160.6 5160.6 5061.5 5097.5 5119.6 5126.4 5196.6 5032.6 5040.6 5062.9 5077.1 5102.9 4737.9 4912.0 4985.6 5007.8 5011.6 5042.6 5064.5 5121.8 5575.0 5612.9 4122.4 4141.5 4171.7 4175.9 4178.4 5645.4 5657.4 5666.5 5706.9 5725.3 5243.9 5536.1 5547.9 5550.3 5561.2 5466.5 5512.5 5549.5 5556.3 5559.8 5507.9 5583.4 5587.5 5593.6 5596.2 5367.4 5588.6 5608.8 5629.5 5634.3 5482.7 5512.6 5519.6 5558.5 5571.4 16:27:42 INFO - PROCESS | 2516 | 16:27:42 INFO - PROCESS | 2516 | [#5] yui.html Cycles:180 Average:20221.50 Median:4626.92 stddev:28231.68 (610.2%) stddev-sans-first:28273.00 16:27:42 INFO - PROCESS | 2516 | Values: 745.3 760.3 785.9 805.0 818.7 15515.1 17338.0 18244.5 18275.0 18278.7 17109.3 17278.4 17279.3 17280.0 17290.7 817.6 836.4 837.6 838.5 839.7 682.3 816.0 830.4 834.2 846.1 873.6 876.4 877.3 877.4 877.6 869.4 871.2 872.1 872.2 872.2 517.7 624.3 633.5 635.5 643.3 15546.8 15590.5 15632.2 15637.8 15637.8 13444.9 13859.9 13976.2 14292.9 14440.8 11512.5 11999.6 12313.7 12888.7 13240.8 3979.4 3985.7 4004.3 4025.5 4087.3 81961.6 82657.6 82700.6 83490.0 83601.0 11084.7 11089.3 11639.0 11652.9 11995.4 22238.3 22310.9 22320.1 22346.7 22346.8 8483.6 8522.6 8754.7 8918.8 8974.3 4347.7 4431.2 4623.9 4630.0 4687.3 3504.3 3575.0 3591.9 3609.1 3611.2 563.0 564.9 569.6 599.4 608.2 626.1 653.5 688.8 716.5 733.2 10206.3 10278.7 10362.7 10523.4 10601.6 617.9 631.7 632.0 703.6 725.8 4647.9 4860.9 5028.6 5029.9 5044.8 69542.3 70895.0 71259.0 116757.0 122964.0 73245.6 98660.0 98660.5 99848.0 100163.0 55383.7 57206.7 87741.6 92032.0 93659.5 9402.2 9615.0 9707.0 9722.9 9730.0 53766.2 53850.5 54367.0 55150.4 55535.4 71170.6 78024.0 79820.6 80977.2 81890.6 12065.9 12149.0 12395.4 12506.7 12788.6 14169.1 14209.9 14513.2 14523.3 14728.1 12233.5 12667.6 12712.6 12827.4 12979.7 12457.8 12627.6 12847.0 12860.0 13031.3 12520.7 13486.4 13808.8 14231.1 14270.9 10072.2 10076.4 10658.4 10723.1 10932.3 10517.4 11103.0 11119.8 11213.4 11301.5 16:27:42 INFO - PROCESS | 2516 | -------- Summary: end -------- 16:27:42 INFO - PROCESS | 2516 | 16:27:43 INFO - TEST-INFO | 2516: exit 0 16:27:43 INFO - TEST-OK | dromaeo_css | took 1113823ms 16:27:43 INFO - TEST-START | kraken 16:27:43 INFO - operating with platform_type : linux_ 16:27:43 INFO - Initialising browser for kraken test... 16:27:43 INFO - TEST-INFO | started process 2759 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLYxV_4/profile http://localhost:40039/getInfo.html) 16:27:47 INFO - PROCESS | 2759 | __metrics Screen width/height:1600/1200 16:27:47 INFO - PROCESS | 2759 | colorDepth:24 16:27:47 INFO - PROCESS | 2759 | Browser inner width/height: 1024/697 16:27:47 INFO - PROCESS | 2759 | __metrics 16:27:47 INFO - PROCESS | 2759 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:27:47 INFO - PROCESS | 2759 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:27:48 INFO - TEST-INFO | 2759: exit 0 16:27:48 INFO - Browser initialized. 16:27:48 INFO - Running cycle 1/1 for kraken test... 16:27:48 INFO - TEST-INFO | started process 2904 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLYxV_4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:28:00 INFO - PROCESS | 2904 | RSS: Main: 156938240 16:28:00 INFO - PROCESS | 2904 | 16:28:03 INFO - PROCESS | 2904 | Cycle 1(1): loaded http://localhost:40039/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40039/tests/kraken/driver.html?testName=audio-beat-detection) 16:28:04 INFO - PROCESS | 2904 | RSS: Main: 213856256 16:28:04 INFO - PROCESS | 2904 | 16:28:06 INFO - PROCESS | 2904 | Cycle 1(1): loaded http://localhost:40039/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40039/tests/kraken/driver.html?testName=audio-dft) 16:28:07 INFO - PROCESS | 2904 | RSS: Main: 192053248 16:28:07 INFO - PROCESS | 2904 | 16:28:10 INFO - PROCESS | 2904 | Cycle 1(1): loaded http://localhost:40039/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40039/tests/kraken/driver.html?testName=audio-fft) 16:28:11 INFO - PROCESS | 2904 | RSS: Main: 269828096 16:28:11 INFO - PROCESS | 2904 | 16:28:13 INFO - PROCESS | 2904 | Cycle 1(1): loaded http://localhost:40039/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40039/tests/kraken/driver.html?testName=audio-oscillator) 16:28:13 INFO - PROCESS | 2904 | RSS: Main: 180785152 16:28:13 INFO - PROCESS | 2904 | 16:28:15 INFO - PROCESS | 2904 | Cycle 1(1): loaded http://localhost:40039/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40039/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:28:16 INFO - PROCESS | 2904 | RSS: Main: 185323520 16:28:16 INFO - PROCESS | 2904 | 16:28:19 INFO - PROCESS | 2904 | Cycle 1(1): loaded http://localhost:40039/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40039/tests/kraken/driver.html?testName=imaging-darkroom) 16:28:20 INFO - PROCESS | 2904 | RSS: Main: 220049408 16:28:20 INFO - PROCESS | 2904 | 16:28:23 INFO - PROCESS | 2904 | Cycle 1(1): loaded http://localhost:40039/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40039/tests/kraken/driver.html?testName=imaging-desaturate) 16:28:24 INFO - PROCESS | 2904 | RSS: Main: 219938816 16:28:24 INFO - PROCESS | 2904 | 16:28:26 INFO - PROCESS | 2904 | Cycle 1(1): loaded http://localhost:40039/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40039/tests/kraken/driver.html?testName=json-parse-financial) 16:28:27 INFO - PROCESS | 2904 | RSS: Main: 220499968 16:28:27 INFO - PROCESS | 2904 | 16:28:28 INFO - PROCESS | 2904 | Cycle 1(1): loaded http://localhost:40039/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40039/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:28:29 INFO - PROCESS | 2904 | RSS: Main: 184406016 16:28:29 INFO - PROCESS | 2904 | 16:28:31 INFO - PROCESS | 2904 | Cycle 1(1): loaded http://localhost:40039/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40039/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:28:31 INFO - PROCESS | 2904 | RSS: Main: 190279680 16:28:31 INFO - PROCESS | 2904 | 16:28:33 INFO - PROCESS | 2904 | Cycle 1(1): loaded http://localhost:40039/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40039/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:28:34 INFO - PROCESS | 2904 | RSS: Main: 194641920 16:28:34 INFO - PROCESS | 2904 | 16:28:37 INFO - PROCESS | 2904 | Cycle 1(1): loaded http://localhost:40039/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40039/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:28:37 INFO - PROCESS | 2904 | RSS: Main: 188669952 16:28:37 INFO - PROCESS | 2904 | 16:28:40 INFO - PROCESS | 2904 | Cycle 1(1): loaded http://localhost:40039/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40039/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:28:40 INFO - PROCESS | 2904 | RSS: Main: 192724992 16:28:40 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | Cycle 1(1): loaded http://localhost:40039/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40039/tests/kraken/driver.html?testName=ai-astar) 16:28:42 INFO - PROCESS | 2904 | RSS: Main: 243523584 16:28:42 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | __start_tp_report 16:28:42 INFO - PROCESS | 2904 | _x_x_mozilla_page_load 16:28:42 INFO - PROCESS | 2904 | _x_x_mozilla_page_load_details 16:28:42 INFO - PROCESS | 2904 | |i|pagename|runs| 16:28:42 INFO - PROCESS | 2904 | |0;ai-astar;100;112;113;110;110;111;111;110;110;112 16:28:42 INFO - PROCESS | 2904 | |1;audio-beat-detection;144;117;119;181;129;121;142;118;172;124 16:28:42 INFO - PROCESS | 2904 | |2;audio-dft;162;159;162;161;161;161;159;160;160;160 16:28:42 INFO - PROCESS | 2904 | |3;audio-fft;74;75;75;112;74;75;74;73;100;76 16:28:42 INFO - PROCESS | 2904 | |4;audio-oscillator;83;84;116;84;83;84;111;84;85;137 16:28:42 INFO - PROCESS | 2904 | |5;imaging-gaussian-blur;117;117;117;116;116;116;117;117;116;116 16:28:42 INFO - PROCESS | 2904 | |6;imaging-darkroom;146;146;146;147;147;146;145;146;146;146 16:28:42 INFO - PROCESS | 2904 | |7;imaging-desaturate;95;95;95;96;95;95;96;95;95;94 16:28:42 INFO - PROCESS | 2904 | |8;json-parse-financial;51;51;53;50;51;51;50;50;49;50 16:28:42 INFO - PROCESS | 2904 | |9;json-stringify-tinderbox;62;62;62;62;62;62;62;62;62;63 16:28:42 INFO - PROCESS | 2904 | |10;stanford-crypto-aes;77;76;76;75;76;75;80;75;84;75 16:28:42 INFO - PROCESS | 2904 | |11;stanford-crypto-ccm;122;121;120;118;118;119;119;130;193;121 16:28:42 INFO - PROCESS | 2904 | |12;stanford-crypto-pbkdf2;156;156;168;157;155;154;155;157;160;157 16:28:42 INFO - PROCESS | 2904 | |13;stanford-crypto-sha256-iterative;64;64;63;63;63;63;62;63;62;64 16:28:42 INFO - PROCESS | 2904 | __end_tp_report 16:28:42 INFO - PROCESS | 2904 | __start_cc_report 16:28:42 INFO - PROCESS | 2904 | _x_x_mozilla_cycle_collect,1657 16:28:42 INFO - PROCESS | 2904 | __end_cc_report 16:28:42 INFO - PROCESS | 2904 | __startTimestamp1468970922811__endTimestamp 16:28:42 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | ------- Summary: start ------- 16:28:42 INFO - PROCESS | 2904 | Number of tests: 14 16:28:42 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:3.63 (3.3%) stddev-sans-first:1.12 16:28:42 INFO - PROCESS | 2904 | Values: 100.0 112.0 113.0 110.0 110.0 111.0 111.0 110.0 110.0 112.0 16:28:42 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | [#1] audio-beat-detection Cycles:10 Average:136.70 Median:135.50 stddev:23.11 (17.1%) stddev-sans-first:24.36 16:28:42 INFO - PROCESS | 2904 | Values: 144.0 117.0 119.0 181.0 129.0 121.0 142.0 118.0 172.0 124.0 16:28:42 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:1.08 (0.7%) stddev-sans-first:1.00 16:28:42 INFO - PROCESS | 2904 | Values: 162.0 159.0 162.0 161.0 161.0 161.0 159.0 160.0 160.0 160.0 16:28:42 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | [#3] audio-fft Cycles:10 Average:80.80 Median:74.50 stddev:13.60 (18.3%) stddev-sans-first:14.20 16:28:42 INFO - PROCESS | 2904 | Values: 74.0 75.0 75.0 112.0 74.0 75.0 74.0 73.0 100.0 76.0 16:28:42 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:84.00 stddev:19.24 (22.9%) stddev-sans-first:19.91 16:28:42 INFO - PROCESS | 2904 | Values: 83.0 84.0 116.0 84.0 83.0 84.0 111.0 84.0 85.0 137.0 16:28:42 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 16:28:42 INFO - PROCESS | 2904 | Values: 117.0 117.0 117.0 116.0 116.0 116.0 117.0 117.0 116.0 116.0 16:28:42 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 16:28:42 INFO - PROCESS | 2904 | Values: 146.0 146.0 146.0 147.0 147.0 146.0 145.0 146.0 146.0 146.0 16:28:42 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:28:42 INFO - PROCESS | 2904 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 96.0 95.0 95.0 94.0 16:28:42 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:1.07 (2.1%) stddev-sans-first:1.13 16:28:42 INFO - PROCESS | 2904 | Values: 51.0 51.0 53.0 50.0 51.0 51.0 50.0 50.0 49.0 50.0 16:28:42 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 16:28:42 INFO - PROCESS | 2904 | Values: 62.0 62.0 62.0 62.0 62.0 62.0 62.0 62.0 62.0 63.0 16:28:42 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:76.00 stddev:2.92 (3.8%) stddev-sans-first:3.10 16:28:42 INFO - PROCESS | 2904 | Values: 77.0 76.0 76.0 75.0 76.0 75.0 80.0 75.0 84.0 75.0 16:28:42 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | [#11] stanford-crypto-ccm Cycles:10 Average:128.10 Median:121.00 stddev:23.07 (19.1%) stddev-sans-first:24.36 16:28:42 INFO - PROCESS | 2904 | Values: 122.0 121.0 120.0 118.0 118.0 119.0 119.0 130.0 193.0 121.0 16:28:42 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.50 Median:157.00 stddev:4.03 (2.6%) stddev-sans-first:4.24 16:28:42 INFO - PROCESS | 2904 | Values: 156.0 156.0 168.0 157.0 155.0 154.0 155.0 157.0 160.0 157.0 16:28:42 INFO - PROCESS | 2904 | 16:28:42 INFO - PROCESS | 2904 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 16:28:42 INFO - PROCESS | 2904 | Values: 64.0 64.0 63.0 63.0 63.0 63.0 62.0 63.0 62.0 64.0 16:28:42 INFO - PROCESS | 2904 | -------- Summary: end -------- 16:28:42 INFO - PROCESS | 2904 | 16:28:43 INFO - TEST-INFO | 2904: exit 0 16:28:43 INFO - TEST-OK | kraken | took 60233ms 16:28:43 INFO - SUITE-END | took 1174s 16:28:43 INFO - Completed test suite (00:19:34) 16:28:43 INFO - javascript benchmark 16:28:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3054.801437906536, 3074.4312302224102, 3083.167544762914, 3104.115327131768, 3126.202818281338, 5308.416074231832, 5324.440933701959, 5379.435159308275, 5421.078416669166, 5447.264619276401, 5329.413764485904, 5334.546563542094, 5335.039692855285, 5374.892502149954, 5377.274068000819, 4793.233082706767, 4838.419389673227, 4879.780409881546, 4889.437714662806, 5101.336826212587, 5013.147764879979, 5026.271190677349, 5048.242763585473, 5057.342545469084, 5069.2142717878605, 4855.878603034935, 4887.828925987574, 4901.975490122527, 4947.678400903929, 4948.035133149037, 4897.2898929655, 4900.975495122537, 5008.974955125237, 5029.622778291643, 5034.295198672189, 4931.211006238985, 4951.925721114186, 4962.15643340633, 4965.801367945278, 4968.701877887338, 5247.029299579552, 5476.342838859362, 5498.257735205736, 5515.889682206373, 5526.696031718254, 5704.771809127671, 5752.568557358173, 5755.539556835443, 5793.5654825887805, 5834.883302333929, 5763.193152958589, 5796.0, 5854.0, 5865.178874957509, 5870.530357571384, 5281.815136470205, 5377.0, 5406.59450541207, 5456.045192091407, 5468.070428027205, 5926.585139040226, 5983.401659833982, 5989.9101513477335, 6040.637561746309, 6054.939450605526, 5738.77044918207, 5739.713014349266, 5786.363500014995, 5788.5369170466265, 5791.391903850119, 5546.556275498031, 5661.405552417019, 5816.41835816415, 5879.588428809942, 5885.823425297248, 5697.772089116472, 5714.0, 5730.627509211888, 5765.971170144123, 5773.88452230953, 5706.429357064343, 5735.340435849847, 5736.71316434185, 5745.741441635053, 5764.481196692318, 4291.291936830388, 4311.116221174651, 4341.175176716414, 4367.868963931023, 4398.428204333416, 3895.49358583388, 3909.3745000800304, 3920.941185882214, 3937.7440466369594, 3991.4611527444004, 5517.944820551743, 5528.530074943675, 5528.9723551381985, 5535.640183388067, 5573.5819813513335, 5575.581831362664, 5833.183354330397, 5844.649321040752, 5846.883062338815, 5890.263717035371, 5434.8913021739345, 5454.645448045864, 5461.563074953994, 5481.75332110056, 5539.529140023144, 5000.224965130411, 5004.3994720633755, 5009.849704508871, 5010.549050585465, 5047.823326183566, 5807.738651760681, 5933.991221492271, 5963.0, 5968.701564921824, 5982.9401705983255, 5740.770369185186, 5801.796937107181, 5801.8549536261935, 5823.388544202967, 5886.558508111823, 5927.940720592739, 5942.910856337072, 5948.613340132878, 5980.073088671263, 5995.46040856325, 5552.278203833475, 5650.886982260414, 5692.0608099663095, 5696.487316141567, 5701.572382071444, 5728.914066288926, 5775.566832487664, 5784.1034639630925, 5823.213866128019, 5873.3832947541605, 5589.133684278943, 5669.206311116364, 5669.688167150846, 5669.780997085646, 5678.176664383559, 5493.725313734377, 5665.688387138582, 5668.291463567054, 5670.631408958405, 5677.0, 5518.448155184449, 5519.0893502573235, 5574.888502229852, 5607.607467477237, 5652.4064973178865, 5688.687122208318, 5688.943110568841, 5699.344575373885, 5753.05074662676, 5760.1359796029255, 5304.920426193533, 5600.523955463831, 5601.943980560142, 5603.635763675511, 5610.18652295423], "value": 5334.146715531572, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.7229888102032, 602.7197353230747, 620.6958590290756, 637.6301744987904, 637.9330170332112, 43989.0, 45359.54640453595, 45391.0, 45392.77303613477, 48695.269570956465, 46472.76763616186, 46790.532094679045, 46901.530984690144, 47098.52901470984, 47100.76449617756, 8971.95514022429, 9126.908730912672, 9136.269098472107, 9148.405353651991, 9149.405288656217, 8944.8658270126, 9019.909800902005, 9029.729108126767, 9041.231495322905, 9042.050584688612, 9128.452292862416, 9142.360034797566, 9157.771055723593, 9167.862482062774, 9168.770780730501, 9019.23336516397, 9043.095690430937, 9077.682281120162, 9085.318601104911, 9089.500077495735, 8963.731088067369, 9037.277017838556, 9052.0, 9052.8642070369, 9053.411528250643, 43149.784251078854, 43412.34881476781, 43463.565364346265, 43509.0, 43526.12947741059, 3654.7258955578436, 3735.9252814943547, 3827.75119617224, 3850.4609354690365, 4300.849470268526, 31322.843385782926, 33932.49101263483, 34403.655963440295, 34433.65566344329, 35729.46405803915, 4435.645148388154, 4580.358749775034, 4592.7933243004145, 4705.670603057787, 4710.74090924999, 413844.0, 419078.0, 420018.0, 425448.0, 425565.0, 64246.0, 64734.028989565195, 64799.67600161969, 64815.0, 64820.35179648238, 68757.31242687609, 68774.0, 69293.0, 69362.65318673375, 69464.0, 18048.0, 18207.817921820613, 18236.0, 18246.178921948544, 18247.27010919575, 13861.4455421782, 14009.929950350386, 14014.85985140156, 14024.859751402559, 14040.85959140416, 18308.952430001915, 20818.89590552068, 20854.37436876866, 21018.15927362919, 21043.789562104488, 7633.313001829862, 7769.494982826098, 7894.802629934183, 7919.2872641462545, 7947.483413578099, 718.9996362485458, 1018.567108978696, 1094.6332978452149, 1094.68801391603, 1134.472470301314, 32530.837345813594, 33419.49870751941, 33528.32943341119, 33822.0, 33857.32285354279, 1120.2942146447685, 1141.594733869481, 1159.4260840883817, 1194.6177223288464, 1234.493857518413, 17302.394416195373, 17643.0, 17657.823421765876, 17881.7317740234, 17944.551386215448, 600238.0, 601174.0, 606211.0, 606623.0, 607029.0, 59803.40196598065, 60346.698266509266, 60514.69742651346, 60588.39411605916, 60610.39389606136, 1222.7982382906898, 1328.0637150808657, 1351.2770667692737, 1355.098859258582, 1363.2638375277238, 32503.674963250065, 33848.49227261642, 34268.31463370768, 34342.82828585841, 34388.312233755685, 50578.2413263794, 51169.488305117964, 51199.0, 51296.74351628218, 51306.74346628243, 424755.0, 427268.0, 430605.1636901796, 430997.0, 431122.0, 35745.642543574235, 36497.63502365049, 37308.0, 37323.62676373201, 40307.0, 45687.0, 48306.5169348302, 48636.27045594389, 48858.75570622124, 50695.0, 41241.793791030854, 43045.0, 43529.347059793494, 43551.78224108859, 43677.78161109174, 41771.0, 41852.37221441742, 42371.1525769489, 43421.56578434175, 43452.56547434612, 41483.37774933439, 42366.36450453184, 42784.0, 42859.14281714411, 43064.0, 34355.0, 36381.81809090938, 37042.444363334034, 37254.25491490209, 37784.811075944446, 27919.20652448373, 27935.09228291837, 27944.113754975024, 27988.358533254992, 28105.839306900125], "value": 21240.080322913673, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.2583839029064, 389.4819889546903, 401.62448111016204, 401.6605967957076, 415.5576883119453, 12970.80543791844, 19385.515362115955, 19996.800031999675, 20049.0, 20088.69866951997, 15970.441034563793, 18661.440156795317, 19332.420027399166, 19455.31906383277, 19531.70702439461, 5856.736446859902, 6046.939530604682, 6048.727807248662, 6053.424924632153, 6065.878682426349, 5893.469587737103, 5896.292444906617, 5963.0, 6013.218281623383, 6026.367231440701, 5747.33905600855, 5881.4118588141055, 5883.911741323884, 5886.087656413241, 5928.970355148218, 5858.326292476378, 5881.5294776417995, 6764.3235676432205, 7057.223705392403, 7096.0, 6873.518853680244, 6895.34494223048, 6898.379145876845, 6951.617661028641, 6994.5803251805055, 26705.465890682077, 26905.73094269052, 27253.0, 27890.860545697346, 28204.15387538377, 2748.3816141368234, 3200.7919485233383, 3362.7982321060813, 3436.3127374525193, 3472.2187507810795, 16089.839101609068, 16155.434559790468, 16365.509034728977, 16459.58851028722, 16493.505194844172, 2085.895705214733, 2104.2635077722825, 2143.110609097223, 2178.673199020144, 2201.1965632544166, 81598.59200703958, 81801.59099204525, 87088.0, 88342.0, 99714.50142749313, 17254.309827606754, 17271.56821079483, 18253.908730456264, 18405.26378944854, 18411.72382414265, 32939.341213175605, 32977.67022329746, 33014.17464563357, 33047.17382065419, 33180.50229246563, 9954.502274886227, 10160.034796694303, 10178.23663225261, 10307.639232626972, 10401.53193106312, 8527.957360213159, 8538.78653033679, 8556.315494760403, 8791.34064945119, 9006.0, 7392.780191268488, 8340.916590834015, 9061.728148155566, 9172.44965302084, 9253.306002049872, 3308.500439402411, 3480.4431290993825, 3527.730017193808, 3572.214112895159, 3601.6188843255186, 934.509382574154, 941.1294552538874, 953.3850666320185, 982.3958265666706, 1000.3347773730533, 18753.531161721065, 20154.193832246838, 20501.897490512452, 20688.793112068688, 20787.8960605196, 968.7335982604699, 1020.9846852297221, 1021.5760459409262, 1031.9845202321972, 1057.751428414322, 10756.78486430267, 11441.313521188755, 11589.536418543164, 11641.359725215025, 11666.241694289844, 473511.0, 479048.4428200739, 492383.30466781644, 493856.0, 495360.0, 82692.58653706693, 85343.0, 86817.0, 90048.54975725079, 91866.54066729624, 952.9475878826731, 960.865478832964, 980.7940332530248, 984.389678399397, 987.7086259553449, 16868.831311686725, 17372.739408908623, 17989.19048642788, 18222.817771822643, 18464.35374761877, 40118.79940600395, 40663.59336406598, 41212.58787412087, 42004.57995420129, 42167.0, 54301.72849135729, 56019.0, 56923.0, 57633.0, 57860.42139578719, 7786.0, 7843.254890785366, 7973.561454119847, 8332.375071869512, 8341.24928756415, 9196.678116265899, 13090.869091309345, 13136.60590182296, 13420.261885595995, 13446.46214151423, 10308.072273495422, 11813.173077884465, 11837.881621184022, 11965.461554230254, 12336.753264934472, 11697.824532631847, 11738.589149380072, 11780.998615117469, 12002.21985570935, 12106.273623582612, 10744.0, 11038.613648522263, 11421.257618255118, 12083.51665933388, 12160.391980400767, 7888.0, 7889.717271020431, 7986.161453047579, 8157.632906519102, 8189.092872030344, 10618.309809862334, 10981.0, 11229.38238396896, 11846.229995050639, 11996.880031199576], "value": 10327.15842692732, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1314.4085161677247, 1472.1240861687293, 1496.4313560846876, 1646.1686848141687, 1802.648483545709, 8597.613107410167, 8819.073997230287, 9015.594298256587, 9227.723168304945, 9227.861582076257, 9080.909190908089, 9176.357654964155, 9179.49512776797, 9189.081091890808, 9195.218406435444, 2013.3859172952243, 2298.7241531016302, 2324.2446204983407, 2353.399883029825, 2359.457324815285, 2333.195047708538, 2347.746163682858, 2350.9882450587725, 2353.14110349722, 2358.0803486640225, 2303.0787684925995, 2346.120204923154, 2346.3547524430746, 2365.089440565386, 2365.219477572397, 1974.1215159254139, 2028.3205126282712, 2253.1550668499312, 2255.289583781112, 2271.2618399020303, 2227.6324406472913, 2278.5556816420803, 2291.209532711212, 2292.209187830196, 2294.564032833773, 9192.172704456563, 9195.90804091964, 9240.168384845396, 9247.953760231223, 9252.676156334495, 5200.791968321262, 5314.441983591706, 5316.38861530925, 5339.733013349356, 5343.305370301836, 3275.0162625882103, 3320.236345640516, 3402.302527981758, 3438.8452519636426, 3455.3434847378917, 1076.580133747839, 1142.874283828778, 1143.2397455691955, 2177.945551361213, 2221.8222542196745, 12711.046671499675, 12785.977121830232, 12799.232046077264, 12869.356532173448, 12870.227786332756, 4320.287152619845, 4485.349624304457, 4628.3288923105965, 4643.582077613031, 4660.300954856799, 10070.546825392876, 10132.392056476487, 10133.290669653054, 10146.0, 10157.085862272283, 5365.08793505105, 5365.490278423544, 5367.946320536745, 5370.248165256867, 5372.328458942632, 4353.782310884443, 4807.591354734886, 4882.072406242803, 4997.5002499749735, 5000.949990500048, 3802.8288727007352, 3825.3305671507374, 3903.2193561287854, 3947.9605203947594, 3994.840206391712, 1154.6362895687832, 1162.8255761635821, 1164.342146687119, 1171.9648410547697, 1189.4587962477053, 869.4479005831342, 940.4780346907532, 977.237754551451, 997.9002099789963, 1033.1476531861244, 5973.671448070398, 6151.84620384494, 6332.271788744261, 6348.269948955929, 6591.736330546724, 463.84924899407827, 469.5140529551966, 470.74814973988754, 475.16371186710944, 478.4665098415242, 4321.891952701208, 4449.822007119744, 4450.643948484114, 4460.130274596488, 4462.888427789331, 11093.778124437627, 11619.302841829518, 11683.123765717604, 11708.0, 11722.94138529302, 10751.408672523086, 11175.72060698489, 11584.42077896102, 11590.94204528972, 11616.593419230287, 7954.403419743542, 8115.513069215748, 8134.552599607078, 8144.877826832602, 8146.226108519562, 6499.5125365598715, 6508.251551071498, 6587.440067594308, 6649.833754156185, 6832.965835170792, 9832.754181145528, 10471.371717696962, 10527.736806579896, 10628.681139565826, 10702.946485267525, 10368.740781480523, 10537.051665350155, 10543.42011189392, 10620.09729173029, 10632.149428045583, 6990.510664253657, 7659.387249020177, 7867.763967080997, 7983.560904150328, 8115.634796434293, 8852.114788521096, 9032.14194651507, 9032.548372581214, 9047.411918225294, 9049.004609493082, 7831.373490120891, 7869.3311068559815, 8050.114487406498, 8214.794630134294, 8320.875186872081, 7655.846883062196, 7692.499987500794, 7714.267144621252, 7837.6864925404625, 7850.725224617111, 7010.859782804418, 7098.929010709827, 7188.676509557187, 7224.422046236184, 7224.89162662571, 5509.586780991522, 5572.4148964358155, 5612.775488980558, 5639.971800140973, 5729.341125770423, 5260.132078207206, 5264.044554112113, 5369.328833895763, 5375.274337964324, 5548.750306236309], "value": 4672.766392198039, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.6001568032119, 334.03130920331034, 339.9490076488526, 340.2446568617669, 344.6742828027515, 5108.386993560774, 5254.973725131374, 5311.362636483623, 5312.370376663557, 5320.441353657859, 5248.317718696574, 5252.185911183773, 5269.288646032794, 5283.973580132104, 5284.920726189111, 4131.917361652765, 4183.288840897055, 4203.789810509477, 4204.264253755596, 4219.282721937278, 3953.983468431092, 4124.793760311987, 4155.9817844628105, 4159.916801663965, 4183.45615070042, 4119.8352065917325, 4124.711270211086, 4125.113100683352, 4126.731762435447, 4138.8137533810905, 4123.3608790637345, 4142.384470056681, 4148.336266197424, 4154.480689913761, 4162.188373267214, 3585.2112535242204, 3670.9816450917842, 3678.2275722098393, 3682.7606205596817, 3685.870994515179, 5086.16078347076, 5104.0, 5138.743062846843, 5153.510416510426, 5166.4575219601775, 4680.4149481314835, 4704.670673052888, 4721.220998535237, 4722.669413141081, 4731.858044258678, 5206.609504287194, 5390.6226564140525, 5428.240046393508, 5441.2654291670515, 5499.0, 3943.4084887266854, 3957.3866050762176, 3961.861334853295, 3966.2662407454714, 4015.357542793139, 5423.972880135613, 5520.420355862657, 5677.489025987681, 5699.0, 5713.0, 4012.6156476015726, 4029.37544680575, 4029.395590661424, 4034.2334956358786, 4046.716729828943, 4069.552349241581, 4087.509498860156, 4112.074783173822, 4115.403266526337, 4119.814608342632, 4465.285554311295, 4520.276755719135, 4672.042231342567, 4706.364640773451, 4715.127701375188, 5451.64564303319, 5452.345718513724, 5476.288082549243, 5480.945190548044, 5516.9448305517235, 1431.3058166789099, 1450.9492167774076, 1453.1789538910482, 1453.3895763779237, 1467.288365142905, 912.431761545997, 963.1909196275162, 974.3325821812082, 979.2459805949379, 986.8125056239292, 4704.50602686713, 4741.38362012943, 4794.640401969867, 4818.469968303484, 4820.9517904821205, 5043.142665746832, 5051.747412629354, 5082.161443361878, 5083.644144909895, 5091.719955402415, 4635.791389387486, 4785.325136202371, 4804.471508134103, 4839.483468596311, 4865.443058221406, 2648.0731743889482, 2682.262377846108, 2686.2673575544054, 2715.986420067887, 2734.4277040701277, 5196.844094677166, 5214.374275087014, 5226.738663066832, 5236.816711415082, 5242.239875218072, 4930.630202734809, 5091.669041512364, 5154.252633368215, 5160.561352285023, 5160.638755287169, 5061.519155680205, 5097.515736005073, 5119.6416250862085, 5126.359205099363, 5196.610254230872, 5032.647714659939, 5040.647154699136, 5062.949370506395, 5077.086124497626, 5102.897942041212, 4737.944026103075, 4912.042151780369, 4985.626078044089, 5007.774650140679, 5011.621804003928, 5042.647014709082, 5064.53128592704, 5121.848823494216, 5575.0, 5612.943870561242, 4122.381642753611, 4141.461609990682, 4171.666266698596, 4175.853845115406, 4178.35235538492, 5645.435456454322, 5657.377688454185, 5666.498377929769, 5706.914396283976, 5725.312962444534, 5243.89512209746, 5536.141898005815, 5547.916781248204, 5550.333959924835, 5561.221429000024, 5466.480684334982, 5512.5314348280845, 5549.500544950974, 5556.277683901067, 5559.777608895761, 5507.862303442446, 5583.385827558884, 5587.525060369914, 5593.636413633101, 5596.1605759136455, 5367.4364191759305, 5588.636738611977, 5608.803691870765, 5629.493345598916, 5634.295713035871, 5482.671039737628, 5512.5589952804485, 5519.586031047607, 5558.471945165203, 5571.415001424792], "value": 4102.829387423375, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [745.2770812312057, 760.3157158557301, 785.9096203936546, 804.9637766300516, 818.6561644109473, 15515.146666933315, 17338.046407447586, 18244.452666419995, 18275.0, 18278.72581911269, 17109.315627374923, 17278.395256166037, 17279.30882764688, 17280.0, 17290.740638890427, 817.6116344736262, 836.3852568362249, 837.5728378526934, 838.4927119092922, 839.7019058234331, 682.3074579302013, 815.9510429374257, 830.3855147191096, 834.2366734437982, 846.1327139681814, 873.5501216873283, 876.369014309696, 877.2893955895745, 877.4384393987852, 877.6313948141764, 869.3783944479727, 871.2115535440437, 872.0755998641429, 872.1801506583791, 872.2367928062945, 517.7359546631208, 624.3350831364583, 633.5438484291303, 635.5265327331122, 643.3309358267396, 15546.844531554652, 15590.454334098366, 15632.2183890805, 15637.765433518507, 15637.765433518507, 13444.943987679695, 13859.930700346535, 13976.161430314214, 14292.857071429256, 14440.783388249185, 11512.481938312796, 11999.64001079969, 12313.69215769604, 12888.677783055406, 13240.801387979189, 3979.383195604686, 3985.6811455083525, 4004.2992476316526, 4025.496812898388, 4087.3255912774357, 81961.59019204926, 82657.58671206726, 82700.58649706712, 83490.0, 83601.0, 11084.667459976214, 11089.27919685234, 11639.0, 11652.94173529127, 11995.400229988465, 22238.332850014525, 22310.88844555767, 22320.10719571199, 22346.664800027687, 22346.776532234795, 8483.575821209039, 8522.616482258314, 8754.69358572447, 8918.821623567623, 8974.326925480615, 4347.739135651871, 4431.158079964796, 4623.86128416148, 4629.976850115728, 4687.343771871941, 3504.281622267429, 3574.982125089358, 3591.8742844000335, 3609.1338078860886, 3611.1694310308776, 563.0, 564.8898464799324, 569.5728203847115, 599.3946114424375, 608.2002167150183, 626.0921663587725, 653.4511010750897, 688.8338744187683, 716.5091912040269, 733.1825015108116, 10206.336588121749, 10278.73867749094, 10362.740931476774, 10523.368597884151, 10601.622127896908, 617.8764247150588, 631.6904716688789, 631.9936800632026, 703.6165289917008, 725.7858931615186, 4647.907041859212, 4860.854174374775, 5028.572571331405, 5029.874253143627, 5044.823431179964, 69542.3045769546, 70895.0, 71259.0, 116757.0, 122964.0, 73245.6337718308, 98660.0, 98660.50669746606, 99848.0, 100163.0, 55383.723081384334, 57206.7139664299, 87741.56129219313, 92032.0, 93659.53170234105, 9402.200812930978, 9614.95192524033, 9707.0, 9722.854157187789, 9730.0, 53766.19350709664, 53850.46149538611, 54367.0, 55150.44849551453, 55535.44464555303, 71170.64414677894, 78024.0, 79820.60089699514, 80977.19022809697, 81890.59054704689, 12065.879341206475, 12149.0, 12395.380230988594, 12506.74986500283, 12788.552400665933, 14169.149851008973, 14209.928950355596, 14513.201773902536, 14523.34644940959, 14728.116313021254, 12233.510659573518, 12667.556635517712, 12712.618621441372, 12827.422765975367, 12979.675508112372, 12457.750844983231, 12627.621171364874, 12846.950815754448, 12860.0, 13031.283279419724, 12520.686982825502, 13486.393112310167, 13808.792868106786, 14231.146131232159, 14270.857291427368, 10072.244581656258, 10076.395416274754, 10658.360498370123, 10723.142148628247, 10932.289401188897, 10517.368957862553, 11103.0, 11119.777604448027, 11213.439328033402, 11301.491432885376], "value": 7552.165591170576, "unit": "score"}], "name": "dromaeo_css", "value": 7438.696070941517, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 112.0, 113.0, 110.0, 110.0, 111.0, 111.0, 110.0, 110.0, 112.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 117.0, 119.0, 181.0, 129.0, 121.0, 142.0, 118.0, 172.0, 124.0], "value": 136.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 162.0, 161.0, 161.0, 161.0, 159.0, 160.0, 160.0, 160.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 112.0, 74.0, 75.0, 74.0, 73.0, 100.0, 76.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 116.0, 84.0, 83.0, 84.0, 111.0, 84.0, 85.0, 137.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 145.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 53.0, 50.0, 51.0, 51.0, 50.0, 50.0, 49.0, 50.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 63.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 75.0, 76.0, 75.0, 80.0, 75.0, 84.0, 75.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 121.0, 120.0, 118.0, 118.0, 119.0, 119.0, 130.0, 193.0, 121.0], "value": 128.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 156.0, 168.0, 157.0, 155.0, 154.0, 155.0, 157.0, 160.0, 157.0], "value": 157.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 63.0, 63.0, 63.0, 62.0, 63.0, 62.0, 64.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1479.0, "alertThreshold": 2.0}]} 16:28:44 INFO - Return code: 0 16:28:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:28:44 INFO - # TBPL SUCCESS # 16:28:44 INFO - Running post-action listener: _resource_record_post_action 16:28:44 INFO - ##### 16:28:44 INFO - ##### Finished run-tests step (success) 16:28:44 INFO - ##### 16:28:44 INFO - Running post-run listener: _resource_record_post_run 16:28:44 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 557019136; Read time: 12; Write time: 428832 16:28:44 INFO - TinderboxPrint: CPU usage
13.1% 16:28:44 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:28:44 INFO - TinderboxPrint: I/O write bytes / time
557,019,136 / 428,832 16:28:44 INFO - TinderboxPrint: CPU idle
8,269.5 (86.9%) 16:28:44 INFO - TinderboxPrint: CPU user
1,200.8 (12.6%) 16:28:44 INFO - TinderboxPrint: Swap in / out
0 / 0 16:28:44 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 257216512; Read time: 0; Write time: 252128 16:28:44 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 299802624; Read time: 0; Write time: 176704 16:28:44 INFO - Running post-run listener: _upload_blobber_files 16:28:44 INFO - Blob upload gear active. 16:28:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:28:44 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 16:28:44 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-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:28:44 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-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:28:45 INFO - (blobuploader) - INFO - Open directory for files ... 16:28:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:28:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:28:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:49 INFO - (blobuploader) - INFO - Done attempting. 16:28:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:28:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:28:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:50 INFO - (blobuploader) - INFO - Done attempting. 16:28:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:28:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:28:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:51 INFO - (blobuploader) - INFO - Done attempting. 16:28:51 INFO - (blobuploader) - INFO - Iteration through files over. 16:28:51 INFO - Return code: 0 16:28:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:28:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:28:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/951f3d97ce1742430feaeb56892c59ecc0e91a84600d63a5b88153f5fd528f2d66c7b2e2d2641c00815096676e8b13f16c3f1524b73c6f6e86ca744d45af9d93", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0ebaf0e59414b3565b932c744f6e5bb680e52b1d42ac5813c6f0f3aba369ab095e406e0a1e4990b4a31d7e7fe35d2cc00ba8d91feb9221fb2c84dfacbccfdc59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/21c1b495e0d8141e3f956ebdc585f33acf7539243ab3d47cfe020206bed62fc7bdc46ddfa82c2ea3d70d7a20ae1d36f3fbc5e774ecbbcb2ea64842625a572401"} 16:28:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:28:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:28:51 INFO - Contents: 16:28:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/951f3d97ce1742430feaeb56892c59ecc0e91a84600d63a5b88153f5fd528f2d66c7b2e2d2641c00815096676e8b13f16c3f1524b73c6f6e86ca744d45af9d93", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0ebaf0e59414b3565b932c744f6e5bb680e52b1d42ac5813c6f0f3aba369ab095e406e0a1e4990b4a31d7e7fe35d2cc00ba8d91feb9221fb2c84dfacbccfdc59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/21c1b495e0d8141e3f956ebdc585f33acf7539243ab3d47cfe020206bed62fc7bdc46ddfa82c2ea3d70d7a20ae1d36f3fbc5e774ecbbcb2ea64842625a572401"} 16:28:51 INFO - Running post-run listener: copy_logs_to_upload_dir 16:28:51 INFO - Copying logs to upload dir... 16:28:51 INFO - mkdir: /builds/slave/test/build/upload/logs 16:28:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.268643 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-07-19 16:28:50.918422) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 16:28:50.921779) ========= 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=3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/951f3d97ce1742430feaeb56892c59ecc0e91a84600d63a5b88153f5fd528f2d66c7b2e2d2641c00815096676e8b13f16c3f1524b73c6f6e86ca744d45af9d93", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0ebaf0e59414b3565b932c744f6e5bb680e52b1d42ac5813c6f0f3aba369ab095e406e0a1e4990b4a31d7e7fe35d2cc00ba8d91feb9221fb2c84dfacbccfdc59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/21c1b495e0d8141e3f956ebdc585f33acf7539243ab3d47cfe020206bed62fc7bdc46ddfa82c2ea3d70d7a20ae1d36f3fbc5e774ecbbcb2ea64842625a572401"} build_url:https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011315 build_url: 'https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/951f3d97ce1742430feaeb56892c59ecc0e91a84600d63a5b88153f5fd528f2d66c7b2e2d2641c00815096676e8b13f16c3f1524b73c6f6e86ca744d45af9d93", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0ebaf0e59414b3565b932c744f6e5bb680e52b1d42ac5813c6f0f3aba369ab095e406e0a1e4990b4a31d7e7fe35d2cc00ba8d91feb9221fb2c84dfacbccfdc59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/21c1b495e0d8141e3f956ebdc585f33acf7539243ab3d47cfe020206bed62fc7bdc46ddfa82c2ea3d70d7a20ae1d36f3fbc5e774ecbbcb2ea64842625a572401"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 16:28:50.947767) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:28:50.948098) ========= 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=3a3620fb28aea1604ab609ef000001d2-1468968687.954724-1984494623 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004684 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:28:51.030352) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 16:28:51.030674) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 16:28:51.030980) ========= ========= Total master_lag: 0.15 =========