builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-067 starttime: 1468796697.05 results: success (0) buildid: 20160717133003 builduid: 67f174f580b343638551d0a7bc846818 revision: 0066888031c6e3185a98c8f5a979adc29fce8d3b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 16:04:57.055204) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 16:04:57.055571) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 16:04:57.055838) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 16:04:57.103747) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 16:04:57.103995) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-17 16:04:58-- 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% 113M=0s 2016-07-17 16:04:58 (113 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114129 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 16:04:57.235155) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 16:04:57.235553) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019914 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 16:04:57.287062) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-17 16:04:57.287426) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 0066888031c6e3185a98c8f5a979adc29fce8d3b --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 0066888031c6e3185a98c8f5a979adc29fce8d3b --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=e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-17 16:04:58,568 truncating revision to first 12 chars 2016-07-17 16:04:58,568 Setting DEBUG logging. 2016-07-17 16:04:58,568 attempt 1/10 2016-07-17 16:04:58,568 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/0066888031c6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-17 16:04:59,763 unpacking tar archive at: mozilla-inbound-0066888031c6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.543552 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-17 16:04:58.846989) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 16:04:58.847588) ========= script_repo_revision: 0066888031c6e3185a98c8f5a979adc29fce8d3b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 16:04:58.848290) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 16:04:58.848823) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 16:04:58.861018) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-07-17 16:04:58.861421) ========= /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=e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:05:00 INFO - MultiFileLogger online at 20160717 16:05:00 in /builds/slave/test 16:05:00 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:05:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:05:00 INFO - {'append_to_log': False, 16:05:00 INFO - 'base_work_dir': '/builds/slave/test', 16:05:00 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 16:05:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:05:00 INFO - 'branch': 'Mozilla-Inbound', 16:05:00 INFO - 'buildbot_json_path': 'buildprops.json', 16:05:00 INFO - 'config_files': ('talos/linux_config.py',), 16:05:00 INFO - 'default_actions': ('clobber', 16:05:00 INFO - 'read-buildbot-config', 16:05:00 INFO - 'download-and-extract', 16:05:00 INFO - 'populate-webroot', 16:05:00 INFO - 'create-virtualenv', 16:05:00 INFO - 'install', 16:05:00 INFO - 'run-tests'), 16:05:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:05:00 INFO - 'download_minidump_stackwalk': True, 16:05:00 INFO - 'download_symbols': 'ondemand', 16:05:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:05:00 INFO - 'tooltool.py': '/tools/tooltool.py', 16:05:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:05:00 INFO - '/tools/misc-python/virtualenv.py')}, 16:05:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:05:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:05:00 INFO - 'installer_path': 'installer.exe', 16:05:00 INFO - 'log_level': 'info', 16:05:00 INFO - 'log_name': 'talos', 16:05:00 INFO - 'log_to_console': True, 16:05:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:05:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:05:00 INFO - 'opt_config_files': (), 16:05:00 INFO - 'pip_index': False, 16:05:00 INFO - 'sps_profile': False, 16:05:00 INFO - 'sps_profile_interval': 0, 16:05:00 INFO - 'suite': 'dromaeojs', 16:05:00 INFO - 'system_bits': '32', 16:05:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:05:00 INFO - 'title': 'talos-linux64-ix-067', 16:05:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:05:00 INFO - 'use_talos_json': True, 16:05:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:05:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:05:00 INFO - 'work_dir': 'build'} 16:05:00 INFO - ##### 16:05:00 INFO - ##### Running clobber step. 16:05:00 INFO - ##### 16:05:00 INFO - Running pre-action listener: _resource_record_pre_action 16:05:00 INFO - Running main action method: clobber 16:05:00 INFO - rmtree: /builds/slave/test/build 16:05:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:05:00 INFO - Running post-action listener: _resource_record_post_action 16:05:00 INFO - ##### 16:05:00 INFO - ##### Finished clobber step (success) 16:05:00 INFO - ##### 16:05:00 INFO - ##### 16:05:00 INFO - ##### Running read-buildbot-config step. 16:05:00 INFO - ##### 16:05:00 INFO - Running pre-action listener: _resource_record_pre_action 16:05:00 INFO - Running main action method: read_buildbot_config 16:05:00 INFO - Using buildbot properties: 16:05:00 INFO - { 16:05:00 INFO - "project": "", 16:05:00 INFO - "product": "firefox", 16:05:00 INFO - "script_repo_revision": "production", 16:05:00 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 16:05:00 INFO - "repository": "", 16:05:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 16:05:00 INFO - "buildid": "20160717133003", 16:05:00 INFO - "pgo_build": "True", 16:05:00 INFO - "basedir": "/builds/slave/test", 16:05:00 INFO - "buildnumber": 2075, 16:05:00 INFO - "slavename": "talos-linux64-ix-067", 16:05:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:05:00 INFO - "platform": "ubuntu64_hw", 16:05:00 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 16:05:00 INFO - "branch": "mozilla-inbound", 16:05:00 INFO - "repo_path": "integration/mozilla-inbound", 16:05:00 INFO - "revision": "0066888031c6e3185a98c8f5a979adc29fce8d3b", 16:05:00 INFO - "stage_platform": "linux64-pgo", 16:05:00 INFO - "builduid": "67f174f580b343638551d0a7bc846818", 16:05:00 INFO - "slavebuilddir": "test" 16:05:00 INFO - } 16:05:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:05:00 INFO - Running post-action listener: _resource_record_post_action 16:05:00 INFO - ##### 16:05:00 INFO - ##### Finished read-buildbot-config step (success) 16:05:00 INFO - ##### 16:05:00 INFO - ##### 16:05:00 INFO - ##### Running download-and-extract step. 16:05:00 INFO - ##### 16:05:00 INFO - Running pre-action listener: _resource_record_pre_action 16:05:00 INFO - Running main action method: download_and_extract 16:05:00 INFO - mkdir: /builds/slave/test/build/tests 16:05:00 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:05:00 INFO - https://queue.taskcluster.net/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:05:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:05:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:05:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/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:05:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/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:05:06 INFO - Downloaded 1361 bytes. 16:05:06 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:05:06 INFO - Using the following test package requirements: 16:05:06 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:05:06 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:05:06 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:05:06 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:05:06 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:05:06 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:05:06 INFO - u'jsshell-linux-x86_64.zip'], 16:05:06 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:05:06 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:05:06 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:05:06 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:05:06 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:05:06 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:05:06 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:05:06 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:05:06 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:05:06 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:05:06 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:05:06 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:05:06 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:05:06 INFO - https://queue.taskcluster.net/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:05:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:05:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:05:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/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:05:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/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:05:08 INFO - Downloaded 24334318 bytes. 16:05:08 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:05:08 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:05:09 INFO - Return code: 0 16:05:09 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:05: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:05:09 INFO - https://queue.taskcluster.net/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:05:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:05:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:05:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/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:05:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/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:05:15 INFO - Downloaded 24334318 bytes. 16:05:15 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:05:15 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:05:16 INFO - Return code: 0 16:05:16 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:05:16 INFO - https://queue.taskcluster.net/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:05:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:05:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:05:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/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:05:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/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:05:18 INFO - Downloaded 14403317 bytes. 16:05:18 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:05:18 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:05:18 INFO - Return code: 0 16:05:18 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:05:18 INFO - https://queue.taskcluster.net/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:05:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:05:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:05:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/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:05:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OcM55GpwRx-QD67a8dFGmg/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:05:20 INFO - Downloaded 60814338 bytes. 16:05:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:05:20 INFO - mkdir: /builds/slave/test/properties 16:05:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:05:20 INFO - Writing to file /builds/slave/test/properties/build_url 16:05:20 INFO - Contents: 16:05:20 INFO - build_url:https://queue.taskcluster.net/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:05:20 INFO - Running post-action listener: _resource_record_post_action 16:05:20 INFO - Running post-action listener: set_extra_try_arguments 16:05:20 INFO - ##### 16:05:20 INFO - ##### Finished download-and-extract step (success) 16:05:20 INFO - ##### 16:05:20 INFO - ##### 16:05:20 INFO - ##### Running populate-webroot step. 16:05:20 INFO - ##### 16:05:20 INFO - Running pre-action listener: _resource_record_pre_action 16:05:20 INFO - Running main action method: populate_webroot 16:05:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:05:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:05:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:05:20 INFO - '--tppagecycles', 16:05:20 INFO - '7'], 16:05:20 INFO - 'tests': ['tsvgm']}}, 16:05:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:05:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:05:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:05:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:05:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:05:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:05:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:20 INFO - 'talos_options': ['--disable-e10s'], 16:05:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:05:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:05:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:20 INFO - 'talos_options': ['--disable-e10s'], 16:05:20 INFO - 'tests': ['damp', 'tps']}, 16:05:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:20 INFO - 'tests': ['damp', 'tps']}, 16:05:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:05:20 INFO - 'tests': ['dromaeo_dom']}, 16:05:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:05:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:05:20 INFO - 'tests': ['basic_compositor_video']}, 16:05:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:05:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:05:20 INFO - 'tests': ['a11yr', 16:05:20 INFO - 'ts_paint', 16:05:20 INFO - 'tpaint', 16:05:20 INFO - 'sessionrestore', 16:05:20 INFO - 'sessionrestore_no_auto_restore', 16:05:20 INFO - 'tabpaint']}, 16:05:20 INFO - 'other-e10s': {'tests': ['a11yr', 16:05:20 INFO - 'ts_paint', 16:05:20 INFO - 'tpaint', 16:05:20 INFO - 'sessionrestore', 16:05:20 INFO - 'sessionrestore_no_auto_restore', 16:05:20 INFO - 'tabpaint']}, 16:05:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:05:20 INFO - 'ts_paint', 16:05:20 INFO - 'tpaint', 16:05:20 INFO - 'sessionrestore', 16:05:20 INFO - 'sessionrestore_no_auto_restore', 16:05:20 INFO - 'tabpaint']}, 16:05:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:05:20 INFO - 'ts_paint', 16:05:20 INFO - 'tpaint', 16:05:20 INFO - 'sessionrestore', 16:05:20 INFO - 'sessionrestore_no_auto_restore', 16:05:20 INFO - 'tabpaint']}, 16:05:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:05:20 INFO - 'tests': ['a11yr', 16:05:20 INFO - 'ts_paint', 16:05:20 INFO - 'tpaint', 16:05:20 INFO - 'sessionrestore', 16:05:20 INFO - 'sessionrestore_no_auto_restore', 16:05:20 INFO - 'tabpaint']}, 16:05:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:05:20 INFO - 'tests': ['a11yr', 16:05:20 INFO - 'ts_paint', 16:05:20 INFO - 'tpaint', 16:05:20 INFO - 'sessionrestore', 16:05:20 INFO - 'sessionrestore_no_auto_restore', 16:05:20 INFO - 'tabpaint']}, 16:05:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:05:20 INFO - 'tests': ['tsvgx', 16:05:20 INFO - 'tsvgr_opacity', 16:05:20 INFO - 'tart', 16:05:20 INFO - 'tscrollx', 16:05:20 INFO - 'cart']}, 16:05:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:05:20 INFO - 'tsvgr_opacity', 16:05:20 INFO - 'tart', 16:05:20 INFO - 'tscrollx', 16:05:20 INFO - 'cart']}, 16:05:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:20 INFO - 'talos_options': ['--disable-e10s'], 16:05:20 INFO - 'tests': ['tp5o']}, 16:05:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:20 INFO - 'tests': ['tp5o']}, 16:05:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:20 INFO - 'talos_options': ['--disable-e10s', 16:05:20 INFO - '--xperf_path', 16:05:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:05:20 INFO - 'tests': ['tp5n']}, 16:05:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:20 INFO - 'talos_options': ['--xperf_path', 16:05:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:05:20 INFO - 'tests': ['tp5n']}}, 16:05:20 INFO - 'talos.zip': {'path': '', 16:05:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:05:20 INFO - Running post-action listener: _resource_record_post_action 16:05:20 INFO - ##### 16:05:20 INFO - ##### Finished populate-webroot step (success) 16:05:20 INFO - ##### 16:05:20 INFO - ##### 16:05:20 INFO - ##### Running create-virtualenv step. 16:05:20 INFO - ##### 16:05:20 INFO - Running pre-action listener: _resource_record_pre_action 16:05:20 INFO - Running main action method: create_virtualenv 16:05:20 INFO - Creating virtualenv /builds/slave/test/build/venv 16:05:20 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:05:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:05:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:05:21 INFO - Using real prefix '/usr' 16:05:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:05:21 INFO - Installing distribute.............................................................................................................................................................................................done. 16:05:23 INFO - Installing pip.................done. 16:05:23 INFO - Return code: 0 16:05:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:05:23 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:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:23 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:05:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:23 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 0x2981030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c42180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c403b0>, '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 0x2c412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', '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:05:23 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:05:23 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:05:23 INFO - Using env: {'DISPLAY': ':0', 16:05:23 INFO - 'HOME': '/home/cltbld', 16:05:23 INFO - 'LANG': 'en_US.UTF-8', 16:05:23 INFO - 'LANGUAGE': 'en_US:en', 16:05:23 INFO - 'LOGNAME': 'cltbld', 16:05:23 INFO - 'MAIL': '/var/mail/cltbld', 16:05:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:23 INFO - 'MOZ_NO_REMOTE': '1', 16:05:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:23 INFO - 'NO_EM_RESTART': '1', 16:05:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:23 INFO - 'PWD': '/builds/slave/test', 16:05:23 INFO - 'SHELL': '/bin/bash', 16:05:23 INFO - 'SHLVL': '1', 16:05:23 INFO - 'TERM': 'linux', 16:05:23 INFO - 'TMOUT': '86400', 16:05:23 INFO - 'USER': 'cltbld', 16:05:23 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', 16:05:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:23 INFO - '_': '/tools/buildbot/bin/python'} 16:05:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:23 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:05:23 INFO - Cleaning up... 16:05:23 INFO - Return code: 0 16:05:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:05:23 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:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:23 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:05:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:23 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 0x2981030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c42180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c403b0>, '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 0x2c412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', '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:05:23 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:05:23 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:05:23 INFO - Using env: {'DISPLAY': ':0', 16:05:23 INFO - 'HOME': '/home/cltbld', 16:05:23 INFO - 'LANG': 'en_US.UTF-8', 16:05:23 INFO - 'LANGUAGE': 'en_US:en', 16:05:23 INFO - 'LOGNAME': 'cltbld', 16:05:23 INFO - 'MAIL': '/var/mail/cltbld', 16:05:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:23 INFO - 'MOZ_NO_REMOTE': '1', 16:05:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:23 INFO - 'NO_EM_RESTART': '1', 16:05:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:23 INFO - 'PWD': '/builds/slave/test', 16:05:23 INFO - 'SHELL': '/bin/bash', 16:05:23 INFO - 'SHLVL': '1', 16:05:23 INFO - 'TERM': 'linux', 16:05:23 INFO - 'TMOUT': '86400', 16:05:23 INFO - 'USER': 'cltbld', 16:05:23 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', 16:05:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:23 INFO - '_': '/tools/buildbot/bin/python'} 16:05:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:23 INFO - Downloading/unpacking psutil>=3.1.1 16:05:23 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:05:23 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:05:23 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:05:23 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:05:23 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:05:23 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:05:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:05:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:05:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:05:25 INFO - Installing collected packages: psutil 16:05:25 INFO - Running setup.py install for psutil 16:05:25 INFO - building 'psutil._psutil_linux' extension 16:05:25 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:05:26 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:05:26 INFO - building 'psutil._psutil_posix' extension 16:05:26 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:05:26 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:05:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:05:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:05:26 INFO - Successfully installed psutil 16:05:26 INFO - Cleaning up... 16:05:26 INFO - Return code: 0 16:05:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:05: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:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05: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:05:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:26 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 0x2981030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c42180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c403b0>, '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 0x2c412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', '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:05:26 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:05:26 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:05:26 INFO - Using env: {'DISPLAY': ':0', 16:05:26 INFO - 'HOME': '/home/cltbld', 16:05:26 INFO - 'LANG': 'en_US.UTF-8', 16:05:26 INFO - 'LANGUAGE': 'en_US:en', 16:05:26 INFO - 'LOGNAME': 'cltbld', 16:05:26 INFO - 'MAIL': '/var/mail/cltbld', 16:05:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:26 INFO - 'MOZ_NO_REMOTE': '1', 16:05:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:26 INFO - 'NO_EM_RESTART': '1', 16:05:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:26 INFO - 'PWD': '/builds/slave/test', 16:05:26 INFO - 'SHELL': '/bin/bash', 16:05:26 INFO - 'SHLVL': '1', 16:05:26 INFO - 'TERM': 'linux', 16:05:26 INFO - 'TMOUT': '86400', 16:05:26 INFO - 'USER': 'cltbld', 16:05:26 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', 16:05:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:26 INFO - '_': '/tools/buildbot/bin/python'} 16:05:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:05:26 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:05:26 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:05:26 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:05:26 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:05:26 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:05:26 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:05:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:05:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:05:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:05:28 INFO - Installing collected packages: mozsystemmonitor 16:05:28 INFO - Running setup.py install for mozsystemmonitor 16:05:28 INFO - Successfully installed mozsystemmonitor 16:05:28 INFO - Cleaning up... 16:05:28 INFO - Return code: 0 16:05:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:05: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:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05: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:05:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:28 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 0x2981030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c42180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c403b0>, '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 0x2c412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', '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:05:28 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:05:28 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:05:28 INFO - Using env: {'DISPLAY': ':0', 16:05:28 INFO - 'HOME': '/home/cltbld', 16:05:28 INFO - 'LANG': 'en_US.UTF-8', 16:05:28 INFO - 'LANGUAGE': 'en_US:en', 16:05:28 INFO - 'LOGNAME': 'cltbld', 16:05:28 INFO - 'MAIL': '/var/mail/cltbld', 16:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:28 INFO - 'MOZ_NO_REMOTE': '1', 16:05:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:28 INFO - 'NO_EM_RESTART': '1', 16:05:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:28 INFO - 'PWD': '/builds/slave/test', 16:05:28 INFO - 'SHELL': '/bin/bash', 16:05:28 INFO - 'SHLVL': '1', 16:05:28 INFO - 'TERM': 'linux', 16:05:28 INFO - 'TMOUT': '86400', 16:05:28 INFO - 'USER': 'cltbld', 16:05:28 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', 16:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:28 INFO - '_': '/tools/buildbot/bin/python'} 16:05:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:28 INFO - Downloading/unpacking jsonschema==2.5.1 16:05:28 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:05:28 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:05:28 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:05:28 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:05:28 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:05:28 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:05:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:05:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:05:30 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:05:30 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:05:30 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:05:30 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:05:30 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:05:30 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:05:31 INFO - Downloading functools32-3.2.3-2.tar.gz 16:05:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:05:31 INFO - warning: no files found matching '*.txt' 16:05:31 INFO - no previously-included directories found matching 'build' 16:05:31 INFO - no previously-included directories found matching 'dist' 16:05:31 INFO - no previously-included directories found matching '.git*' 16:05:31 INFO - Installing collected packages: jsonschema, functools32 16:05:31 INFO - Running setup.py install for functools32 16:05:31 INFO - warning: no files found matching '*.txt' 16:05:31 INFO - no previously-included directories found matching 'build' 16:05:31 INFO - no previously-included directories found matching 'dist' 16:05:31 INFO - no previously-included directories found matching '.git*' 16:05:31 INFO - Successfully installed jsonschema functools32 16:05:31 INFO - Cleaning up... 16:05:31 INFO - Return code: 0 16:05:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:05:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:31 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 0x2981030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c42180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c403b0>, '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 0x2c412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', '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:05:31 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:05:31 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:05:31 INFO - Using env: {'DISPLAY': ':0', 16:05:31 INFO - 'HOME': '/home/cltbld', 16:05:31 INFO - 'LANG': 'en_US.UTF-8', 16:05:31 INFO - 'LANGUAGE': 'en_US:en', 16:05:31 INFO - 'LOGNAME': 'cltbld', 16:05:31 INFO - 'MAIL': '/var/mail/cltbld', 16:05:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:31 INFO - 'MOZ_NO_REMOTE': '1', 16:05:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:31 INFO - 'NO_EM_RESTART': '1', 16:05:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:31 INFO - 'PWD': '/builds/slave/test', 16:05:31 INFO - 'SHELL': '/bin/bash', 16:05:31 INFO - 'SHLVL': '1', 16:05:31 INFO - 'TERM': 'linux', 16:05:31 INFO - 'TMOUT': '86400', 16:05:31 INFO - 'USER': 'cltbld', 16:05:31 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', 16:05:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:31 INFO - '_': '/tools/buildbot/bin/python'} 16:05:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:31 INFO - Downloading/unpacking blobuploader==1.2.4 16:05:31 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:05:31 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:05:31 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:05:31 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:05:31 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:05:31 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:05:33 INFO - Downloading blobuploader-1.2.4.tar.gz 16:05:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:05:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:05: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:05: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:05: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:05: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:05: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:05: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:05:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:05:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:05: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:05: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:05: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:05: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:05: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:05: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:05:34 INFO - Downloading docopt-0.6.1.tar.gz 16:05:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:05:34 INFO - Installing collected packages: blobuploader, requests, docopt 16:05:34 INFO - Running setup.py install for blobuploader 16:05:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:05:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:05:34 INFO - Running setup.py install for requests 16:05:34 INFO - Running setup.py install for docopt 16:05:34 INFO - Successfully installed blobuploader requests docopt 16:05:34 INFO - Cleaning up... 16:05:34 INFO - Return code: 0 16:05:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:05:34 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:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:34 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:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:34 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 0x2981030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c42180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c403b0>, '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 0x2c412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', '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:05:34 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:05:34 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:05:34 INFO - Using env: {'DISPLAY': ':0', 16:05:34 INFO - 'HOME': '/home/cltbld', 16:05:34 INFO - 'LANG': 'en_US.UTF-8', 16:05:34 INFO - 'LANGUAGE': 'en_US:en', 16:05:34 INFO - 'LOGNAME': 'cltbld', 16:05:34 INFO - 'MAIL': '/var/mail/cltbld', 16:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:34 INFO - 'MOZ_NO_REMOTE': '1', 16:05:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:34 INFO - 'NO_EM_RESTART': '1', 16:05:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:34 INFO - 'PWD': '/builds/slave/test', 16:05:34 INFO - 'SHELL': '/bin/bash', 16:05:34 INFO - 'SHLVL': '1', 16:05:34 INFO - 'TERM': 'linux', 16:05:34 INFO - 'TMOUT': '86400', 16:05:34 INFO - 'USER': 'cltbld', 16:05:34 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', 16:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:34 INFO - '_': '/tools/buildbot/bin/python'} 16:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:05:35 INFO - Running setup.py (path:/tmp/pip-cxoIXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:05:35 INFO - Running setup.py (path:/tmp/pip-s3kUty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:05:35 INFO - Running setup.py (path:/tmp/pip-RI_3A8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:05:35 INFO - Running setup.py (path:/tmp/pip-yNwBM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:05:35 INFO - Running setup.py (path:/tmp/pip-m_qG6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:05:35 INFO - Running setup.py (path:/tmp/pip-ZMIFYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:05:35 INFO - Running setup.py (path:/tmp/pip-VruMQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:05:35 INFO - Running setup.py (path:/tmp/pip-DeK5CD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:05:35 INFO - Running setup.py (path:/tmp/pip-lTaC5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:05:35 INFO - Running setup.py (path:/tmp/pip-9eMNKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:05:35 INFO - Running setup.py (path:/tmp/pip-nPAaqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:05:35 INFO - Running setup.py (path:/tmp/pip-k1TAhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:05:35 INFO - Running setup.py (path:/tmp/pip-6KEiRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:05:35 INFO - Running setup.py (path:/tmp/pip-qVcwu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:05:36 INFO - Running setup.py (path:/tmp/pip-sxS8fN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:05:36 INFO - Running setup.py (path:/tmp/pip-0OYUir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:05:36 INFO - Running setup.py (path:/tmp/pip-lLCJjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:05:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:05:36 INFO - Running setup.py install for manifestparser 16:05:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:05:36 INFO - Running setup.py install for mozcrash 16:05:36 INFO - Running setup.py install for mozdebug 16:05:36 INFO - Running setup.py install for mozdevice 16:05:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:05:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:05:36 INFO - Running setup.py install for mozfile 16:05:36 INFO - Running setup.py install for mozhttpd 16:05:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:05:36 INFO - Running setup.py install for mozinfo 16:05:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:05:37 INFO - Running setup.py install for mozInstall 16:05:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:05:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:05:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:05:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:05:37 INFO - Running setup.py install for mozleak 16:05:37 INFO - Running setup.py install for mozlog 16:05:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:05:37 INFO - Running setup.py install for moznetwork 16:05:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:05:37 INFO - Running setup.py install for mozprocess 16:05:37 INFO - Running setup.py install for mozprofile 16:05:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:05:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:05:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:05:37 INFO - Running setup.py install for mozrunner 16:05:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:05:37 INFO - Running setup.py install for mozscreenshot 16:05:37 INFO - Running setup.py install for moztest 16:05:37 INFO - Running setup.py install for mozversion 16:05:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:05:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:05:38 INFO - Cleaning up... 16:05:38 INFO - Return code: 0 16:05:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:05: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:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05: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:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:38 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 0x2981030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c42180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c403b0>, '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 0x2c412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', '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:05:38 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:05:38 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:05:38 INFO - Using env: {'DISPLAY': ':0', 16:05:38 INFO - 'HOME': '/home/cltbld', 16:05:38 INFO - 'LANG': 'en_US.UTF-8', 16:05:38 INFO - 'LANGUAGE': 'en_US:en', 16:05:38 INFO - 'LOGNAME': 'cltbld', 16:05:38 INFO - 'MAIL': '/var/mail/cltbld', 16:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:38 INFO - 'MOZ_NO_REMOTE': '1', 16:05:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:38 INFO - 'NO_EM_RESTART': '1', 16:05:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:38 INFO - 'PWD': '/builds/slave/test', 16:05:38 INFO - 'SHELL': '/bin/bash', 16:05:38 INFO - 'SHLVL': '1', 16:05:38 INFO - 'TERM': 'linux', 16:05:38 INFO - 'TMOUT': '86400', 16:05:38 INFO - 'USER': 'cltbld', 16:05:38 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', 16:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:38 INFO - '_': '/tools/buildbot/bin/python'} 16:05:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:05:38 INFO - Running setup.py (path:/tmp/pip-qmdM5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:05:38 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:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:05:38 INFO - Running setup.py (path:/tmp/pip-ENlOKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:05:38 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:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:05:38 INFO - Running setup.py (path:/tmp/pip-TJAbfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:05:38 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:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:05:38 INFO - Running setup.py (path:/tmp/pip-CrHGRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:05:38 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:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:05:38 INFO - Running setup.py (path:/tmp/pip-_lHJXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:05:38 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:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:05:38 INFO - Running setup.py (path:/tmp/pip-hm8Vqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:05:38 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:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:05:38 INFO - Running setup.py (path:/tmp/pip-_Nca5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:05:38 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:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:05:38 INFO - Running setup.py (path:/tmp/pip-FC4u9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:05:38 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:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:05:38 INFO - Running setup.py (path:/tmp/pip-EvdYsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:05:38 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:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:05:38 INFO - Running setup.py (path:/tmp/pip-wNBsGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:05:39 INFO - Running setup.py (path:/tmp/pip-bOhPZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:05:39 INFO - Running setup.py (path:/tmp/pip-OHIGoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:05:39 INFO - Running setup.py (path:/tmp/pip-k0bCTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:05:39 INFO - Running setup.py (path:/tmp/pip-DbLEBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:05:39 INFO - Running setup.py (path:/tmp/pip-fsvNL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:05:39 INFO - Running setup.py (path:/tmp/pip-0cqFx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:05:39 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:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:05:39 INFO - Running setup.py (path:/tmp/pip-0fJR_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:05:39 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:05:39 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:05:39 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:05:39 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:05:39 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:05:39 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:05:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:05:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:05:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:05:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:05:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:05:41 INFO - Downloading blessings-1.6.tar.gz 16:05:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:05:41 INFO - Installing collected packages: blessings 16:05:41 INFO - Running setup.py install for blessings 16:05:41 INFO - Successfully installed blessings 16:05:41 INFO - Cleaning up... 16:05:41 INFO - Return code: 0 16:05:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:05:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:05:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:05:41 INFO - Reading from file tmpfile_stdout 16:05:41 INFO - Current package versions: 16:05:41 INFO - argparse == 1.2.1 16:05:41 INFO - blessings == 1.6 16:05:41 INFO - blobuploader == 1.2.4 16:05:41 INFO - docopt == 0.6.1 16:05:41 INFO - functools32 == 3.2.3-2 16:05:41 INFO - jsonschema == 2.5.1 16:05:41 INFO - manifestparser == 1.1 16:05:41 INFO - mozInstall == 1.12 16:05:41 INFO - mozcrash == 0.17 16:05:41 INFO - mozdebug == 0.1 16:05:41 INFO - mozdevice == 0.48 16:05:41 INFO - mozfile == 1.2 16:05:41 INFO - mozhttpd == 0.7 16:05:41 INFO - mozinfo == 0.9 16:05:41 INFO - mozleak == 0.1 16:05:41 INFO - mozlog == 3.2 16:05:41 INFO - moznetwork == 0.27 16:05:41 INFO - mozprocess == 0.23 16:05:41 INFO - mozprofile == 0.28 16:05:41 INFO - mozrunner == 6.12 16:05:41 INFO - mozscreenshot == 0.1 16:05:41 INFO - mozsystemmonitor == 0.3 16:05:41 INFO - moztest == 0.7 16:05:41 INFO - mozversion == 1.4 16:05:41 INFO - psutil == 3.1.1 16:05:41 INFO - requests == 1.2.3 16:05:41 INFO - wsgiref == 0.1.2 16:05:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:05:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:41 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 0x2981030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c42180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c403b0>, '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 0x2c412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', '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:05:41 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:05:41 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:05:41 INFO - Using env: {'DISPLAY': ':0', 16:05:41 INFO - 'HOME': '/home/cltbld', 16:05:41 INFO - 'LANG': 'en_US.UTF-8', 16:05:41 INFO - 'LANGUAGE': 'en_US:en', 16:05:41 INFO - 'LOGNAME': 'cltbld', 16:05:41 INFO - 'MAIL': '/var/mail/cltbld', 16:05:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:41 INFO - 'MOZ_NO_REMOTE': '1', 16:05:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:41 INFO - 'NO_EM_RESTART': '1', 16:05:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:41 INFO - 'PWD': '/builds/slave/test', 16:05:41 INFO - 'SHELL': '/bin/bash', 16:05:41 INFO - 'SHLVL': '1', 16:05:41 INFO - 'TERM': 'linux', 16:05:41 INFO - 'TMOUT': '86400', 16:05:41 INFO - 'USER': 'cltbld', 16:05:41 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', 16:05:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:41 INFO - '_': '/tools/buildbot/bin/python'} 16:05:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:42 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:05:42 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:05:42 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:05:42 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:05:42 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:05:42 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:05:42 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:05:42 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:05:42 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:05:42 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:05:42 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:05:42 INFO - Cleaning up... 16:05:42 INFO - Return code: 0 16:05:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:05:42 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:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:42 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:05:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:42 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 0x2981030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c42180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c403b0>, '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 0x2c412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', '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:05:42 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:05:42 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:05:42 INFO - Using env: {'DISPLAY': ':0', 16:05:42 INFO - 'HOME': '/home/cltbld', 16:05:42 INFO - 'LANG': 'en_US.UTF-8', 16:05:42 INFO - 'LANGUAGE': 'en_US:en', 16:05:42 INFO - 'LOGNAME': 'cltbld', 16:05:42 INFO - 'MAIL': '/var/mail/cltbld', 16:05:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:42 INFO - 'MOZ_NO_REMOTE': '1', 16:05:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:42 INFO - 'NO_EM_RESTART': '1', 16:05:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:42 INFO - 'PWD': '/builds/slave/test', 16:05:42 INFO - 'SHELL': '/bin/bash', 16:05:42 INFO - 'SHLVL': '1', 16:05:42 INFO - 'TERM': 'linux', 16:05:42 INFO - 'TMOUT': '86400', 16:05:42 INFO - 'USER': 'cltbld', 16:05:42 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', 16:05:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:42 INFO - '_': '/tools/buildbot/bin/python'} 16:05:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:05:42 INFO - Cleaning up... 16:05:42 INFO - Return code: 0 16:05:42 INFO - Running post-action listener: _resource_record_post_action 16:05:42 INFO - Running post-action listener: _start_resource_monitoring 16:05:42 INFO - Starting resource monitoring. 16:05:42 INFO - ##### 16:05:42 INFO - ##### Finished create-virtualenv step (success) 16:05:42 INFO - ##### 16:05:42 INFO - ##### 16:05:42 INFO - ##### Running install step. 16:05:42 INFO - ##### 16:05:42 INFO - Running pre-action listener: _resource_record_pre_action 16:05:42 INFO - Running main action method: install 16:05:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:05:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:05:42 INFO - Reading from file tmpfile_stdout 16:05:42 INFO - Detecting whether we're running mozinstall >=1.0... 16:05:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:05:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:05:42 INFO - Reading from file tmpfile_stdout 16:05:42 INFO - Output received: 16:05:42 INFO - Usage: mozinstall [options] installer 16:05:42 INFO - Options: 16:05:42 INFO - -h, --help show this help message and exit 16:05:42 INFO - -d DEST, --destination=DEST 16:05:42 INFO - Directory to install application into. [default: 16:05:42 INFO - "/builds/slave/test"] 16:05:42 INFO - --app=APP Application being installed. [default: firefox] 16:05:42 INFO - mkdir: /builds/slave/test/build/application 16:05:42 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:05:42 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:05:59 INFO - Reading from file tmpfile_stdout 16:05:59 INFO - Output received: 16:05:59 INFO - /builds/slave/test/build/application/firefox/firefox 16:05:59 INFO - Running post-action listener: _resource_record_post_action 16:05:59 INFO - ##### 16:05:59 INFO - ##### Finished install step (success) 16:05:59 INFO - ##### 16:05:59 INFO - ##### 16:05:59 INFO - ##### Running run-tests step. 16:05:59 INFO - ##### 16:05:59 INFO - Running pre-action listener: _resource_record_pre_action 16:05:59 INFO - Running main action method: run_tests 16:05:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:05:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:05:59 INFO - Python 2.7.3 16:05:59 INFO - Return code: 0 16:05:59 INFO - grabbing minidump binary from tooltool 16:05:59 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:05:59 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 0x2c403b0>, '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 0x2c412b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:05:59 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:05:59 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:05:59 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:05:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:05:59 INFO - Return code: 0 16:05:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:05:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:05:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:05:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:05:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:05:59 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/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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:05:59 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/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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:05:59 INFO - Using env: {'DISPLAY': ':0', 16:05:59 INFO - 'HOME': '/home/cltbld', 16:05:59 INFO - 'LANG': 'en_US.UTF-8', 16:05:59 INFO - 'LANGUAGE': 'en_US:en', 16:05:59 INFO - 'LOGNAME': 'cltbld', 16:05:59 INFO - 'MAIL': '/var/mail/cltbld', 16:05:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:05:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:59 INFO - 'MOZ_NO_REMOTE': '1', 16:05:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:05:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:59 INFO - 'NO_EM_RESTART': '1', 16:05:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:59 INFO - 'PWD': '/builds/slave/test', 16:05:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:05:59 INFO - 'SHELL': '/bin/bash', 16:05:59 INFO - 'SHLVL': '1', 16:05:59 INFO - 'TERM': 'linux', 16:05:59 INFO - 'TMOUT': '86400', 16:05:59 INFO - 'USER': 'cltbld', 16:05:59 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652', 16:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:59 INFO - '_': '/tools/buildbot/bin/python'} 16:05:59 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/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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:06:00 INFO - mozversion application_buildid: 20160717133003 16:06:00 INFO - mozversion application_changeset: 0066888031c6e3185a98c8f5a979adc29fce8d3b 16:06:00 INFO - mozversion application_display_name: Nightly 16:06:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:06:00 INFO - mozversion application_name: Firefox 16:06:00 INFO - mozversion application_remotingname: firefox 16:06:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:06:00 INFO - mozversion application_vendor: Mozilla 16:06:00 INFO - mozversion application_version: 50.0a1 16:06:00 INFO - mozversion platform_buildid: 20160717133003 16:06:00 INFO - mozversion platform_changeset: 0066888031c6e3185a98c8f5a979adc29fce8d3b 16:06:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:06:00 INFO - mozversion platform_version: 50.0a1 16:06:00 INFO - using testdate: 1468796760 16:06:00 INFO - actual date: 1468796760 16:06:00 INFO - starting webserver on 'localhost:52088' 16:06:00 INFO - SUITE-START | Running 2 tests 16:06:00 INFO - TEST-START | dromaeo_css 16:06:00 INFO - operating with platform_type : linux_ 16:06:00 INFO - Initialising browser for dromaeo_css test... 16:06:00 INFO - TEST-INFO | started process 22238 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_FmXC6/profile http://localhost:52088/getInfo.html) 16:06:03 INFO - PROCESS | 22238 | JavaScript error: , line 0: uncaught exception: undefined 16:06:03 INFO - PROCESS | 22238 | __metrics Screen width/height:1600/1200 16:06:03 INFO - PROCESS | 22238 | colorDepth:24 16:06:03 INFO - PROCESS | 22238 | Browser inner width/height: 1024/697 16:06:03 INFO - PROCESS | 22238 | __metrics 16:06:04 INFO - PROCESS | 22238 | 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:06:04 INFO - PROCESS | 22238 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:06:05 INFO - TEST-INFO | 22238: exit 0 16:06:05 INFO - Browser initialized. 16:06:05 INFO - Running cycle 1/1 for dromaeo_css test... 16:06:05 INFO - TEST-INFO | started process 22319 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_FmXC6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:06:17 INFO - PROCESS | 22319 | RSS: Main: 157802496 16:06:17 INFO - PROCESS | 22319 | 16:07:09 INFO - PROCESS | 22319 | 1468796829068 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:09:16 INFO - PROCESS | 22319 | Cycle 1(1): loaded http://localhost:52088/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52088/tests/dromaeo/cssquery-ext.html) 16:09:17 INFO - PROCESS | 22319 | RSS: Main: 255934464 16:09:17 INFO - PROCESS | 22319 | 16:12:20 INFO - PROCESS | 22319 | Cycle 1(1): loaded http://localhost:52088/tests/dromaeo/cssquery-ext.html (next: http://localhost:52088/tests/dromaeo/cssquery-jquery.html) 16:12:20 INFO - PROCESS | 22319 | RSS: Main: 250064896 16:12:20 INFO - PROCESS | 22319 | 16:15:22 INFO - PROCESS | 22319 | Cycle 1(1): loaded http://localhost:52088/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52088/tests/dromaeo/cssquery-mootools.html) 16:15:23 INFO - PROCESS | 22319 | RSS: Main: 239960064 16:15:23 INFO - PROCESS | 22319 | 16:18:25 INFO - PROCESS | 22319 | Cycle 1(1): loaded http://localhost:52088/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52088/tests/dromaeo/cssquery-prototype.html) 16:18:25 INFO - PROCESS | 22319 | RSS: Main: 236077056 16:18:25 INFO - PROCESS | 22319 | 16:21:28 INFO - PROCESS | 22319 | Cycle 1(1): loaded http://localhost:52088/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52088/tests/dromaeo/cssquery-yui.html) 16:21:29 INFO - PROCESS | 22319 | RSS: Main: 226979840 16:21:29 INFO - PROCESS | 22319 | 16:24:32 INFO - PROCESS | 22319 | Cycle 1(1): loaded http://localhost:52088/tests/dromaeo/cssquery-yui.html (next: http://localhost:52088/tests/dromaeo/cssquery-dojo.html) 16:24:32 INFO - PROCESS | 22319 | RSS: Main: 271958016 16:24:32 INFO - PROCESS | 22319 | 16:24:32 INFO - PROCESS | 22319 | __start_tp_report 16:24:32 INFO - PROCESS | 22319 | _x_x_mozilla_page_load 16:24:32 INFO - PROCESS | 22319 | _x_x_mozilla_page_load_details 16:24:32 INFO - PROCESS | 22319 | |i|pagename|runs| 16:24:32 INFO - PROCESS | 22319 | |0;dojo.html;3077.246074711696;3085.182426656936;3099.9070027899165;3115.283484798495;3138.2781960149164;5315.627906046579;5339.466053394659;5418.756155972981;5440.047991601468;5486.972565137179;5390.568754499641;5393.757280922358;5397.514223719864;5435.918461223085;5457.263269458632;4819.831305904294;4918.213085906255;4918.385201849769;4920.089783390066;5090.211017292307;4994.126027945095;5006.1990081586955;5029.968856384432;5050.84847454577;5057.468965758598;4799.328094066834;4802.927956080662;4822.445418776851;4823.493533179001;4827.058723548909;4828.758562071917;4829.323894654751;4833.854984350475;4847.975760121212;4941.110600091982;4825.758712064383;4848.103100926304;4878.02439512099;4883.536064073907;4913.508649135094;5207.531322180984;5351.571874250089;5381.67709937405;5402.702851343174;5410.323709536308;5810.3899090595305;5812.244408226946;5812.854678633025;5858.238429004245;5869.8239052828485;5879.970600147015;5914.053751399799;5918.733656985446;5929.5256379489965;5932.169496270526;5249.711265880375;5389.353277606673;5442.482964118402;5449.155380915925;5526.640768350045;6054.697265136726;6059.21230240066;6062.454379105813;6063.514918806573;6064.818055458343;5524.530414914777;5809.099589563624;5812.418758124154;5819.6217245879725;5820.476157145878;5682.374938756733;5717.342505611908;5725.627834190765;5822.388649191774;5823.970880145657;5734.082546792493;5749.252597162425;5751.166080918243;5751.741171647286;5798.333191682926;5560.388357280696;5691.430856914276;5707.0868661014865;5777.104548794943;5778.682172480554;4380.627646650007;4410.29435290352;4410.360497727876;4450.910981780411;4452.777361131931;3920.5883382245024;3938.960610393917;3947.684185265172;3960.7821569813937;3960.861369852041;5309.814156504503;5418.02475554404;5470.7264636768;5505.807296744595;5527.695976721238;5847.736851841677;5857.15071314657;5907.2911250650195;5925.940740592625;5926.259217597801;5561.972190139023;5565.220869078333;5571.4150014248735;5572.804951826666;5576.386597474275;4887.65786394956;4902.4362198346935;4910.729909854921;4926.8768280793265;5076.543111120017;5883.529317654577;5894.17481552583;5897.056470964625;5897.852553686193;5906.409359064145;5711.885762284648;5717.656940583578;5725.685087320071;5726.455986681259;5729.083346664514;5789.681567513827;5934.732937017759;5963.4334738199805;5996.940030599638;6041.335453100068;5727.656340619576;5744.281964754406;5745.913811292918;5747.971260143672;5750.338711048283;5566.666000040011;5807.332156802022;5859.20900678403;5871.559633027455;5877.823665290048;5564.777408903598;5579.693116878449;5624.550035997192;5648.604597678123;5662.745176466987;5583.55331573465;5628;5628.324601047901;5628.85927851807;5634.802781902614;5422.240886275846;5512.035393806148;5546.861328466821;5586.217929489793;5598.356189038313;5624.803131890528;5675.347335056521;5742.397048310035;5743.74153163117;5745.396733343106;5572.275604171432;5572.331320241597;5573.944260557505;5577.6653400796085;5580.525655319181 16:24:32 INFO - PROCESS | 22319 | |1;ext.html;612.8712970276242;614.1340709599464;636.2333388267141;644.6969924135656;648.8702259548091;33845.153871153234;44168.55831441685;45157.7742111289;45162.54837451624;45171.54828451714;47480.050398991996;47800.28299575509;47909.7604511977;47935.24777026762;47983;9008.414453060546;9127.087291270886;9143.725688229331;9143.908560914373;9150.176484116428;9004.369694121415;9070.77323066922;9087.136722011432;9087.31845111616;9087.545622718872;9089.454632722025;9134.17792398684;9137.954310228439;9139.725808225763;9148.313876459259;9064.274858011344;9096.090390960851;9097.04481029489;9113.726588202364;9117.407368521059;9024.639014439415;9039.412438191563;9078.86381704275;9096.727098186999;9117.908820911838;43763.34354984678;44164.55835441637;44349.55650443486;44542.777286113684;44554;3430.639782822818;3440.415129428003;3554.0581745837376;3576.6065732769634;3587.587427445825;33729;33729.32541349159;34095.82952085249;35501.82249088738;35704.28591428183;4278.208531421666;4319.654427645781;4395.868123956287;4419.469663640351;4432.556744325574;261441;261519;262085;262340;262685;62147.37852621506;62173.134805955895;62433.68783156055;62720;62742.37257627457;36617;37445.25109497849;37558.62441375606;39462;65832.67083664551;17407.3037078518;17412.21645025977;17432.564185895455;17509.299628014738;17609.20758565842;13178.472861085449;13252.73494530104;13277.601671949664;13279.203247805162;13281.66795830112;16211.27049282785;16381.262843172086;16487.91756041236;16496.587585310463;16509.6698066038;7070.257622949619;7234;7344.412447004227;7428.9628551856895;7488.363489103487;963.5471328475605;1037.5227395398201;1076.978460430787;1099.6755956992706;1145.3814939932342;33921.32157356889;34239.31521369558;34333.20205590437;34365.59440839167;35081.1229719254;977.7555611097225;1108.7394462301352;1124.6344937895217;1136.670365593985;1149.5401839264362;17265.913670431568;17609.911950440423;17640.38258660941;17703.73444398335;17709;609111;618659;629886;632383;929981;63381.36618633847;63569;63951.68024159849;63956;63998;1236.2829558855842;1343.7648411527944;1386.9514566990106;1387.2370196391944;1388.2780953904116;31543.36913261676;35455.46816797802;35716.82141589379;35968.28063438769;35984.640153599175;52743.20885186648;53144;53277.46722532725;53280;53364.46635533595;482240;488372;488384;488386;488635;29795.1266335375;46186.307205392615;46640;47709.52290477051;47836;48719.26921096257;50346.244806328665;52647.47352526426;53163;54182;44252.336214957446;44510;44567.55432445634;45159.774201128785;46142.30786538137;44211.55788442203;44660.776696116314;44695.882602935184;45766.313505298116;45898.77050614859;43903;44609.33086003777;45640.771796140805;45748.54251457443;45785.542144578125;38556;39064.80467597644;39314;39982.80008600055;40311.59688403078;28921.805372301857;29088.41823163567;29578.556321654763;29694.55458168086;29905.401891961603 16:24:32 INFO - PROCESS | 22319 | |2;jquery.html;355.53602548674;386.92648396804606;389.58509187715083;398.364608449523;416.4211745673513;17230.138493075345;19272.710909336372;19325.613487730272;19327.130279137436;19824.306149284777;19272.903635481838;19275.614487710238;19571.72783769054;20262.797372026274;20302;5628.521575666074;5726.7422965966425;5858.355580886099;5949.315828679714;5951.791687290945;5777.913331300034;5826.038703613898;5842.444967728059;5873;5873.383294754053;5599.692016939067;5774.682392468412;5790.507806836424;5801;5812.854678633025;5751.3961034091235;5756.453136951983;5758.28021497315;5764.798232061858;5796.362400135981;5048;5063.417706963673;5177.326947496839;5182.300389447414;5189.299444574972;24230.87884560566;24627.630585541232;24660.630090548657;25032.624510632173;25064.749352506424;2422.140140250221;2533.7972962163167;2538.7207407185197;2845.573164025392;3166.2401023754364;14420.783688244685;14980.550583482513;15251.313690883937;15518.137823461144;15871.60320991973;1916.7029110487758;1996.8602197846158;2016.042379869565;2018.1927229108328;2036.745406824147;83829.16170838274;84543;88292.11707882903;93488.06511934861;94551.52724236334;10860.076893463985;11063.668089957315;11075.335479871233;11313.15151363651;11316.359127926404;15327.693446131016;15356.923215383851;15391.230438478031;15434.691306174038;15474.297827238996;7913.089994650573;7921.445498815027;8094.797630059178;8238.876416853753;8281.751547453587;7965.283124518821;8247.175282471704;8375.120612335617;8476.279516241066;8491.405601607952;4654.418197725285;4722.456917454537;4810.11012962603;4822.276658501253;4837.975810120927;1811.5652243461475;1889.5559543507266;1929.8166674165932;1931.951701207453;1947.9318223862097;920.5167287174287;987.5407935309971;987.5555999799975;990.232569758443;1027.5427434791595;18750;20755.68866467004;21852.23517176923;22243.555128897657;22423.32730018102;915.2403505090758;942.9528523573794;992.4789485520015;1007.4056306779036;1010.1262408017159;10127.430248311524;10758.193135514868;10758.73103172427;10832.620858269922;11916.880831191751;500485;503750;508942;509286;510901;81034;104024;107188.46405768077;107227.46386268175;107688;940.7365937537501;982.8967958364269;990.4750482244422;997.5560875410373;1036.6630844975414;17634.382796602058;18387.908060459613;18709.43871683852;18986.43040708781;19989;46884.2523596622;47021.529784701714;47180.292295614905;47326.763366182946;48203.27695084647;60345.396546033975;62919;62938;63296.05055924073;63639.6818015907;6738.797836064926;7526;8337.249647531751;8760.080191580048;8802.075782042793;11605;12277.079219058427;12300.876991229974;12526.937365313115;12694.451211326941;9954.55204515784;10066.094051535398;10406.687799366031;10573.62992295266;10877.891221087688;10218;10374.688759337232;10497.16022718196;10597.15222782191;10698.518566664363;10913.618023369145;10915.672529824118;10938.56245750161;11103.222774405715;11181.217314787888;8268.793280168044;8586.098459661895;8735.519546424754;8806.691765788166;8919.394508988185;8346.499210047417;10845.891541084487;11137.777244455228;11288.379139147426;11432.542698291974 16:24:32 INFO - PROCESS | 22319 | |3;mootools.html;1480.8519148085193;1509.0945432740355;1658.7097257979858;1802.783665960085;1821.0621529912105;8592.140785921405;9204.81590368194;9227.354085214038;9227.49248791318;9229.40008899421;7547.207543207953;8561.529115898624;8797.1202879712;8808.427452215601;8833.116688331163;2367.206985659802;2397.1729753235104;2397.724261709908;2399.9880000599974;2400.303911865557;2344.0975224538506;2370.9407264818346;2371.905123795046;2373.6439534069855;2380.1907351500486;1779.830916062972;1800.5588630785476;1802.4322338463408;2066.8036536529007;2132.6374516332185;2322.2336628912417;2333.7899589036983;2339.157903154863;2350.3066595354326;2354.3643216331666;2331.895064722092;2333.6849525314037;2334.684817549626;2335.708036495438;2336.3575016870327;8594.828103437896;8738.606762695694;8748.650053997833;8749;8751.649934002633;5219.42586315505;5344.759485823147;5353;5356.491133342365;5357.973210133924;3239.400710868497;3276.0663210984753;3410.9829450852835;3421.5209870618132;3422.6064002639764;1062.8512008318842;1074.371492676785;1084.5553323137476;1090.43297485308;1098.873629532598;12620.55828046023;12774.808377874338;12788.488460461478;12789.488420463264;12796.296203708793;4600.470945841204;4705.423683066143;4816.349792778;4824.589909857631;4841.927371089437;10632;10693.9465302673;10705.19711021661;10713.625023124154;10714.89285107139;5479.287692600016;5479.808206712746;5481;5483.835484935458;5484.725763711878;4927.571004408753;4990.451050384455;5016.122178618727;5016.949830501649;5028.924566131511;4018.7186896916937;4032.536258330271;4056.7565946043333;4114.773687447219;4151.816732231299;1166.7666466706692;1167.9065674746;1184.7334349771234;1188.89299963002;1243.2229856339789;721.844803367268;766.9118051424047;862.9956850215834;889.4307643108416;959.12240300126;5881;5957.910631340534;5985.132155837379;6003.21958145447;6096.055111457643;935.9812803743887;954.0983770337103;960.6013504395597;978.5205249427729;988.660365205151;4033.798310084484;4180.979095104505;4223.66210703143;4296.828126874952;4311.33174357975;10893.891061089445;11327.433628318551;11401;11416.42917854104;11421.714457138638;10748.355098694103;11195.664130076111;11203.607873724544;11213.495392707347;11256.437178141225;8128.9187108129345;8184.468009579358;8256.25693687571;8279.668813247523;8280;6320.051992201206;6338.461230795434;6424.678766061772;6482.805515834532;6551.082848401323;10345.948270258601;10496.317739346918;10547.208959328234;10651.946740266249;10652.520636571528;10312.123469504875;10758.085562726943;10772.138228941823;10795.784084318428;10954.616588419367;7796.805079873048;7845.725399610987;7880.369570434466;7902.644381002754;7911.169327220559;8337.916620833957;8661.177188167114;8792.60433280491;9086.045965173562;9100.271978241592;7773.92226077732;7804.453688241769;7868.803279918048;7995.880061799194;8129.227723366271;7805.804854878674;7814.296713295604;7847.136814950237;7876.527408355517;7949.960250198712;7221.31397517235;7229.49393542447;7275.272472752682;7292.562446253241;7298.343149116605;5265.183896496049;5445.646033007842;5539.612227144061;5621.381648018633;5665.008623490955;5429.239906413184;5462.453754624506;5505.256790333254;5576.860578485408;5681.715914204356 16:24:32 INFO - PROCESS | 22319 | |4;prototype.html;319.7649727450324;323.2548974613516;325.67269893756776;335.64421712984256;339.4077335050338;5421.295231619889;5458.536024437927;5462.945370546293;5467.371252305986;5506.119020956643;5113.846584602458;5209.817656382027;5313.468653134684;5342.412334643186;5397.190421436787;4173.415721798935;4208.758416267208;4219.755172224186;4239.109786944745;4249.9150016999565;4238.745675259479;4250.426192464024;4257.318828987362;4272.252355837732;4275.46556680415;3978.4430179774854;3995.2608767377983;4198.244316023115;4205.3481710334945;4222.923154595574;4183.581641835792;4192.769397683126;4208.831646734127;4231.322988321854;4240.434537105053;3715.3126671565847;3724.2365315110337;3728.7017038637114;3732.42147467143;3741.7942013189263;5175.534201921808;5244.527992480657;5252.1596544552685;5272.947270527284;5283;4830.6135509159185;4854.684445511066;4856.4172299323955;4875.512448755132;4880.681676203811;5598.356189038272;5602.355729091166;5620.4098569650005;5627.465390787868;5637.238972738669;4011.475639257093;4038.1318016626274;4067.2068946555432;4081.2041651877894;4100.2414553307735;5716.171155182476;5719.828405147852;5747.626404283708;5781.19063331134;5850.151727999459;4207.305794543866;4215.852445164404;4216.3464662977285;4216.873493795191;4220.556841531657;4151.522574903925;4199.9370009449885;4229.894252643647;4257.446531950888;4268.015372851577;4744.69159504638;4760.214564596872;4776.880577985508;4777.71333719971;4783.306420568998;5600.131979543095;5618;5659.462351076641;5659.773609055674;5663.377028526941;1381.4389739594394;1414.4483651375972;1428.5928510374492;1448.876845468126;1473.3369983507464;1006.9949650251701;1017.2116609627598;1017.7048655889857;1023.2223510132204;1024.8368102203904;5374.140137577969;5376.220448035013;5384.9461505385225;5385.084535628953;5434.91847622286;5659.434056594308;5661.688607126565;5677.148427735872;5695;5697.202391665171;5165.848832212348;5274.472552744694;5300.31095957523;5343.812966546152;5359.464053594687;2666.719994400599;2700.7164247754095;2750.7936904732223;2752.0367871245303;2759.7240275972645;5235.905637744856;5237.6333656643665;5245.606579506552;5322.467753224724;5346.331708536433;4915.21356582945;5141.53726164647;5166.483351664803;5168.870778230575;5210.254564720861;5083.949160508421;5132.153194722903;5134.383873935151;5175.249588809602;5214.270002199695;5020.16763881188;5024.819167495709;5040.353814247549;5060.696358218519;5081.745912704424;5362.222477740784;5796.913046304393;5822.301323841166;5858.7070646468355;5864.824055278348;6053.818385448443;6054.182685337423;6070.635761854126;6071.423214794588;6083.452489275986;3933.4689816874356;4110.23960567296;4111.835526578903;4178.937315940324;4179.811908464066;5595.384507704068;5814.883702326015;5818.592698511064;5822.94177058224;5862.530997520274;5527.972360138174;5641.746121424628;5645.209670646195;5662.518685911579;5720.542356611378;5573.944260557505;5656.773729050957;5668.348139963957;5683.488486036276;5712.200291959212;5325.893482130413;5587.273654424899;5606.579506536945;5610;5629.268195134607;5645.548356131418;5677.148427735872;5677.517411019944;5688.459596338341;5693.6299140557385;5449.891002179855;5596.104623260258;5596.776128954796;5627.409122042291;5632.690202038927 16:24:32 INFO - PROCESS | 22319 | |5;yui.html;765.3876898481213;780.3640033372802;794.9284564389204;824.843279776842;837.6565608100676;12304.753904921907;12778.936105319472;12789.3605319734;12807.103502754811;12865;13347.532836350729;14091.224982625952;14112.082714623568;14426.134431934091;14478.565643030699;604.876000419915;822.827206286679;837.2004735477605;842.7893026743315;843.0557775291682;790.2137373313533;827.2554700769296;831.3640065350012;834.1533343656193;835.1272919798824;852.7569642651846;854.1245223645751;854.8247609240121;855.2132038524542;856.3705676327894;700.3591713582066;727.316322656701;839.6179738219126;842.2882664148784;853.4196746212604;522.2479629333748;523.5314393617729;532.7868852459009;539.9811006614789;540.8404520666413;13851.584452466443;13895.722085558233;13924;13958.441662333495;13958.93020534901;14297;14659.706805863823;14721.779173312409;14768.335424905905;14784;13613.863861361358;13701.478283360939;13751.931240343834;13911.79132313016;13937.651558711113;4659.62722982164;4667.089917466095;4846.297286893416;4867.318575399411;4869.853904382839;91605;92002.5399873003;92281.99999999933;92766;92958;10284.640037598649;10681.305715128492;10689.412082335391;11073.280236784583;11558.133140014532;21320.466988325104;21652.35042948714;21667.56664866694;21704.240351587618;21704.89147554252;8197.303229225463;8426.789330266793;8551.957240213758;8854.380193386402;8873.068327825615;4626.330978206814;4650.720956742606;4663.27719203524;4746.196544522823;4779.450363208206;3495.9300813983577;3616.1863580694658;3617.7105831533413;3631.3463576556474;3639.6724294813594;588.5115354256043;603.6016229288648;607.3380015782818;638.4700698420301;638.4987784589075;733.2447578993645;736.8931504931755;762.5158024654385;765.6142382288076;773.628658244046;10052.173396027343;10289.639862604772;10349.379037257788;10538.947305263424;10620.681379558626;719.223238901995;727.1492353945896;732.9890051649229;737.7344156103799;750.9934499445519;4720.315554244616;4950.950490495049;4959.20652695567;4970.701757894538;4978.90042199154;69657.65171174111;69812;113672.43163784128;120250.3987480057;120622.39688801675;89045;90856.54571727231;91750;106470.46764766127;107157;81710.59144704476;84309;90511;98117.5094124539;100891;10441.738956526147;10531.196250966046;10686.625968091079;10837.078848297982;10882.347059176474;58381.70809146097;59001;59484.40515594788;60768;61786;73255.63372183318;86144;86363.5681821612;86628.56685716783;87575.56212218897;13560.254186019469;13873.236971966639;13911.165330080228;13930.95517836146;14294.428222870969;15258.465953691568;15444.22778861075;15654.843451565339;15688.294026768595;15756.527304180892;14427.711445771236;14607.269636518344;14618.85381146175;14847;15094.471693490676;14276.643083922985;14417.13497190172;14483.42709821857;14709.117452952856;15232.771508427604;14084.295785210903;14384.784228236376;14647.92676036613;14824.62938426548;14852.777208341668;11713.472893719632;11986.700332491757;11998.58004969822;12317.568885088978;12492.937535312265;12228.816567751313;12291.372344907712;12477.376131193585;12512.562060327844;12560.74878502443 16:24:32 INFO - PROCESS | 22319 | __end_tp_report 16:24:32 INFO - PROCESS | 22319 | __start_cc_report 16:24:32 INFO - PROCESS | 22319 | _x_x_mozilla_cycle_collect,806 16:24:32 INFO - PROCESS | 22319 | __end_cc_report 16:24:32 INFO - PROCESS | 22319 | __startTimestamp1468797872360__endTimestamp 16:24:32 INFO - PROCESS | 22319 | 16:24:32 INFO - PROCESS | 22319 | ------- Summary: start ------- 16:24:32 INFO - PROCESS | 22319 | Number of tests: 6 16:24:32 INFO - PROCESS | 22319 | 16:24:32 INFO - PROCESS | 22319 | [#0] dojo.html Cycles:165 Average:5373.75 Median:5572.80 stddev:622.43 (11.2%) stddev-sans-first:597.70 16:24:32 INFO - PROCESS | 22319 | Values: 3077.2 3085.2 3099.9 3115.3 3138.3 5315.6 5339.5 5418.8 5440.0 5487.0 5390.6 5393.8 5397.5 5435.9 5457.3 4819.8 4918.2 4918.4 4920.1 5090.2 4994.1 5006.2 5030.0 5050.8 5057.5 4799.3 4802.9 4822.4 4823.5 4827.1 4828.8 4829.3 4833.9 4848.0 4941.1 4825.8 4848.1 4878.0 4883.5 4913.5 5207.5 5351.6 5381.7 5402.7 5410.3 5810.4 5812.2 5812.9 5858.2 5869.8 5880.0 5914.1 5918.7 5929.5 5932.2 5249.7 5389.4 5442.5 5449.2 5526.6 6054.7 6059.2 6062.5 6063.5 6064.8 5524.5 5809.1 5812.4 5819.6 5820.5 5682.4 5717.3 5725.6 5822.4 5824.0 5734.1 5749.3 5751.2 5751.7 5798.3 5560.4 5691.4 5707.1 5777.1 5778.7 4380.6 4410.3 4410.4 4450.9 4452.8 3920.6 3939.0 3947.7 3960.8 3960.9 5309.8 5418.0 5470.7 5505.8 5527.7 5847.7 5857.2 5907.3 5925.9 5926.3 5562.0 5565.2 5571.4 5572.8 5576.4 4887.7 4902.4 4910.7 4926.9 5076.5 5883.5 5894.2 5897.1 5897.9 5906.4 5711.9 5717.7 5725.7 5726.5 5729.1 5789.7 5934.7 5963.4 5996.9 6041.3 5727.7 5744.3 5745.9 5748.0 5750.3 5566.7 5807.3 5859.2 5871.6 5877.8 5564.8 5579.7 5624.6 5648.6 5662.7 5583.6 5628.0 5628.3 5628.9 5634.8 5422.2 5512.0 5546.9 5586.2 5598.4 5624.8 5675.3 5742.4 5743.7 5745.4 5572.3 5572.3 5573.9 5577.7 5580.5 16:24:32 INFO - PROCESS | 22319 | 16:24:32 INFO - PROCESS | 22319 | [#1] ext.html Cycles:180 Average:64545.78 Median:44635.05 stddev:137445.32 (307.9%) stddev-sans-first:137747.07 16:24:32 INFO - PROCESS | 22319 | Values: 612.9 614.1 636.2 644.7 648.9 33845.2 44168.6 45157.8 45162.5 45171.5 47480.1 47800.3 47909.8 47935.2 47983.0 9008.4 9127.1 9143.7 9143.9 9150.2 9004.4 9070.8 9087.1 9087.3 9087.5 9089.5 9134.2 9138.0 9139.7 9148.3 9064.3 9096.1 9097.0 9113.7 9117.4 9024.6 9039.4 9078.9 9096.7 9117.9 43763.3 44164.6 44349.6 44542.8 44554.0 3430.6 3440.4 3554.1 3576.6 3587.6 33729.0 33729.3 34095.8 35501.8 35704.3 4278.2 4319.7 4395.9 4419.5 4432.6 261441.0 261519.0 262085.0 262340.0 262685.0 62147.4 62173.1 62433.7 62720.0 62742.4 36617.0 37445.3 37558.6 39462.0 65832.7 17407.3 17412.2 17432.6 17509.3 17609.2 13178.5 13252.7 13277.6 13279.2 13281.7 16211.3 16381.3 16487.9 16496.6 16509.7 7070.3 7234.0 7344.4 7429.0 7488.4 963.5 1037.5 1077.0 1099.7 1145.4 33921.3 34239.3 34333.2 34365.6 35081.1 977.8 1108.7 1124.6 1136.7 1149.5 17265.9 17609.9 17640.4 17703.7 17709.0 609111.0 618659.0 629886.0 632383.0 929981.0 63381.4 63569.0 63951.7 63956.0 63998.0 1236.3 1343.8 1387.0 1387.2 1388.3 31543.4 35455.5 35716.8 35968.3 35984.6 52743.2 53144.0 53277.5 53280.0 53364.5 482240.0 488372.0 488384.0 488386.0 488635.0 29795.1 46186.3 46640.0 47709.5 47836.0 48719.3 50346.2 52647.5 53163.0 54182.0 44252.3 44510.0 44567.6 45159.8 46142.3 44211.6 44660.8 44695.9 45766.3 45898.8 43903.0 44609.3 45640.8 45748.5 45785.5 38556.0 39064.8 39314.0 39982.8 40311.6 28921.8 29088.4 29578.6 29694.6 29905.4 16:24:32 INFO - PROCESS | 22319 | 16:24:32 INFO - PROCESS | 22319 | [#2] jquery.html Cycles:180 Average:30385.58 Median:1760.89 stddev:83889.27 (4764.0%) stddev-sans-first:84094.30 16:24:32 INFO - PROCESS | 22319 | Values: 355.5 386.9 389.6 398.4 416.4 17230.1 19272.7 19325.6 19327.1 19824.3 19272.9 19275.6 19571.7 20262.8 20302.0 5628.5 5726.7 5858.4 5949.3 5951.8 5777.9 5826.0 5842.4 5873.0 5873.4 5599.7 5774.7 5790.5 5801.0 5812.9 5751.4 5756.5 5758.3 5764.8 5796.4 5048.0 5063.4 5177.3 5182.3 5189.3 24230.9 24627.6 24660.6 25032.6 25064.7 2422.1 2533.8 2538.7 2845.6 3166.2 14420.8 14980.6 15251.3 15518.1 15871.6 1916.7 1996.9 2016.0 2018.2 2036.7 83829.2 84543.0 88292.1 93488.1 94551.5 10860.1 11063.7 11075.3 11313.2 11316.4 15327.7 15356.9 15391.2 15434.7 15474.3 7913.1 7921.4 8094.8 8238.9 8281.8 7965.3 8247.2 8375.1 8476.3 8491.4 4654.4 4722.5 4810.1 4822.3 4838.0 1811.6 1889.6 1929.8 1932.0 1947.9 920.5 987.5 987.6 990.2 1027.5 18750.0 20755.7 21852.2 22243.6 22423.3 915.2 943.0 992.5 1007.4 1010.1 10127.4 10758.2 10758.7 10832.6 11916.9 500485.0 503750.0 508942.0 509286.0 510901.0 81034.0 104024.0 107188.5 107227.5 107688.0 940.7 982.9 990.5 997.6 1036.7 17634.4 18387.9 18709.4 18986.4 19989.0 46884.3 47021.5 47180.3 47326.8 48203.3 60345.4 62919.0 62938.0 63296.1 63639.7 6738.8 7526.0 8337.2 8760.1 8802.1 11605.0 12277.1 12300.9 12526.9 12694.5 9954.6 10066.1 10406.7 10573.6 10877.9 10218.0 10374.7 10497.2 10597.2 10698.5 10913.6 10915.7 10938.6 11103.2 11181.2 8268.8 8586.1 8735.5 8806.7 8919.4 8346.5 10845.9 11137.8 11288.4 11432.5 16:24:32 INFO - PROCESS | 22319 | 16:24:32 INFO - PROCESS | 22319 | [#3] mootools.html Cycles:180 Average:5947.09 Median:4959.01 stddev:3422.33 (69.0%) stddev-sans-first:3415.47 16:24:32 INFO - PROCESS | 22319 | Values: 1480.9 1509.1 1658.7 1802.8 1821.1 8592.1 9204.8 9227.4 9227.5 9229.4 7547.2 8561.5 8797.1 8808.4 8833.1 2367.2 2397.2 2397.7 2400.0 2400.3 2344.1 2370.9 2371.9 2373.6 2380.2 1779.8 1800.6 1802.4 2066.8 2132.6 2322.2 2333.8 2339.2 2350.3 2354.4 2331.9 2333.7 2334.7 2335.7 2336.4 8594.8 8738.6 8748.7 8749.0 8751.6 5219.4 5344.8 5353.0 5356.5 5358.0 3239.4 3276.1 3411.0 3421.5 3422.6 1062.9 1074.4 1084.6 1090.4 1098.9 12620.6 12774.8 12788.5 12789.5 12796.3 4600.5 4705.4 4816.3 4824.6 4841.9 10632.0 10693.9 10705.2 10713.6 10714.9 5479.3 5479.8 5481.0 5483.8 5484.7 4927.6 4990.5 5016.1 5016.9 5028.9 4018.7 4032.5 4056.8 4114.8 4151.8 1166.8 1167.9 1184.7 1188.9 1243.2 721.8 766.9 863.0 889.4 959.1 5881.0 5957.9 5985.1 6003.2 6096.1 936.0 954.1 960.6 978.5 988.7 4033.8 4181.0 4223.7 4296.8 4311.3 10893.9 11327.4 11401.0 11416.4 11421.7 10748.4 11195.7 11203.6 11213.5 11256.4 8128.9 8184.5 8256.3 8279.7 8280.0 6320.1 6338.5 6424.7 6482.8 6551.1 10345.9 10496.3 10547.2 10651.9 10652.5 10312.1 10758.1 10772.1 10795.8 10954.6 7796.8 7845.7 7880.4 7902.6 7911.2 8337.9 8661.2 8792.6 9086.0 9100.3 7773.9 7804.5 7868.8 7995.9 8129.2 7805.8 7814.3 7847.1 7876.5 7950.0 7221.3 7229.5 7275.3 7292.6 7298.3 5265.2 5445.6 5539.6 5621.4 5665.0 5429.2 5462.5 5505.3 5576.9 5681.7 16:24:32 INFO - PROCESS | 22319 | 16:24:32 INFO - PROCESS | 22319 | [#4] prototype.html Cycles:180 Average:4664.40 Median:5192.68 stddev:1353.79 (26.1%) stddev-sans-first:1317.72 16:24:32 INFO - PROCESS | 22319 | Values: 319.8 323.3 325.7 335.6 339.4 5421.3 5458.5 5462.9 5467.4 5506.1 5113.8 5209.8 5313.5 5342.4 5397.2 4173.4 4208.8 4219.8 4239.1 4249.9 4238.7 4250.4 4257.3 4272.3 4275.5 3978.4 3995.3 4198.2 4205.3 4222.9 4183.6 4192.8 4208.8 4231.3 4240.4 3715.3 3724.2 3728.7 3732.4 3741.8 5175.5 5244.5 5252.2 5272.9 5283.0 4830.6 4854.7 4856.4 4875.5 4880.7 5598.4 5602.4 5620.4 5627.5 5637.2 4011.5 4038.1 4067.2 4081.2 4100.2 5716.2 5719.8 5747.6 5781.2 5850.2 4207.3 4215.9 4216.3 4216.9 4220.6 4151.5 4199.9 4229.9 4257.4 4268.0 4744.7 4760.2 4776.9 4777.7 4783.3 5600.1 5618.0 5659.5 5659.8 5663.4 1381.4 1414.4 1428.6 1448.9 1473.3 1007.0 1017.2 1017.7 1023.2 1024.8 5374.1 5376.2 5384.9 5385.1 5434.9 5659.4 5661.7 5677.1 5695.0 5697.2 5165.8 5274.5 5300.3 5343.8 5359.5 2666.7 2700.7 2750.8 2752.0 2759.7 5235.9 5237.6 5245.6 5322.5 5346.3 4915.2 5141.5 5166.5 5168.9 5210.3 5083.9 5132.2 5134.4 5175.2 5214.3 5020.2 5024.8 5040.4 5060.7 5081.7 5362.2 5796.9 5822.3 5858.7 5864.8 6053.8 6054.2 6070.6 6071.4 6083.5 3933.5 4110.2 4111.8 4178.9 4179.8 5595.4 5814.9 5818.6 5822.9 5862.5 5528.0 5641.7 5645.2 5662.5 5720.5 5573.9 5656.8 5668.3 5683.5 5712.2 5325.9 5587.3 5606.6 5610.0 5629.3 5645.5 5677.1 5677.5 5688.5 5693.6 5449.9 5596.1 5596.8 5627.4 5632.7 16:24:32 INFO - PROCESS | 22319 | 16:24:32 INFO - PROCESS | 22319 | [#5] yui.html Cycles:180 Average:21583.02 Median:4133.00 stddev:30773.51 (744.6%) stddev-sans-first:30820.14 16:24:32 INFO - PROCESS | 22319 | Values: 765.4 780.4 794.9 824.8 837.7 12304.8 12778.9 12789.4 12807.1 12865.0 13347.5 14091.2 14112.1 14426.1 14478.6 604.9 822.8 837.2 842.8 843.1 790.2 827.3 831.4 834.2 835.1 852.8 854.1 854.8 855.2 856.4 700.4 727.3 839.6 842.3 853.4 522.2 523.5 532.8 540.0 540.8 13851.6 13895.7 13924.0 13958.4 13958.9 14297.0 14659.7 14721.8 14768.3 14784.0 13613.9 13701.5 13751.9 13911.8 13937.7 4659.6 4667.1 4846.3 4867.3 4869.9 91605.0 92002.5 92282.0 92766.0 92958.0 10284.6 10681.3 10689.4 11073.3 11558.1 21320.5 21652.4 21667.6 21704.2 21704.9 8197.3 8426.8 8552.0 8854.4 8873.1 4626.3 4650.7 4663.3 4746.2 4779.5 3495.9 3616.2 3617.7 3631.3 3639.7 588.5 603.6 607.3 638.5 638.5 733.2 736.9 762.5 765.6 773.6 10052.2 10289.6 10349.4 10538.9 10620.7 719.2 727.1 733.0 737.7 751.0 4720.3 4951.0 4959.2 4970.7 4978.9 69657.7 69812.0 113672.4 120250.4 120622.4 89045.0 90856.5 91750.0 106470.5 107157.0 81710.6 84309.0 90511.0 98117.5 100891.0 10441.7 10531.2 10686.6 10837.1 10882.3 58381.7 59001.0 59484.4 60768.0 61786.0 73255.6 86144.0 86363.6 86628.6 87575.6 13560.3 13873.2 13911.2 13931.0 14294.4 15258.5 15444.2 15654.8 15688.3 15756.5 14427.7 14607.3 14618.9 14847.0 15094.5 14276.6 14417.1 14483.4 14709.1 15232.8 14084.3 14384.8 14647.9 14824.6 14852.8 11713.5 11986.7 11998.6 12317.6 12492.9 12228.8 12291.4 12477.4 12512.6 12560.7 16:24:32 INFO - PROCESS | 22319 | -------- Summary: end -------- 16:24:32 INFO - PROCESS | 22319 | 16:24:33 INFO - TEST-INFO | 22319: exit 0 16:24:33 INFO - TEST-OK | dromaeo_css | took 1112969ms 16:24:33 INFO - TEST-START | kraken 16:24:33 INFO - operating with platform_type : linux_ 16:24:33 INFO - Initialising browser for kraken test... 16:24:33 INFO - TEST-INFO | started process 22513 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7qdiTx/profile http://localhost:52088/getInfo.html) 16:24:36 INFO - PROCESS | 22513 | JavaScript error: , line 0: uncaught exception: undefined 16:24:36 INFO - PROCESS | 22513 | __metrics Screen width/height:1600/1200 16:24:36 INFO - PROCESS | 22513 | colorDepth:24 16:24:36 INFO - PROCESS | 22513 | Browser inner width/height: 1024/697 16:24:36 INFO - PROCESS | 22513 | __metrics 16:24:37 INFO - PROCESS | 22513 | 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:24:37 INFO - PROCESS | 22513 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:24:38 INFO - TEST-INFO | 22513: exit 0 16:24:38 INFO - Browser initialized. 16:24:38 INFO - Running cycle 1/1 for kraken test... 16:24:38 INFO - TEST-INFO | started process 22597 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7qdiTx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:24:50 INFO - PROCESS | 22597 | RSS: Main: 158085120 16:24:50 INFO - PROCESS | 22597 | 16:24:52 INFO - PROCESS | 22597 | Cycle 1(1): loaded http://localhost:52088/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52088/tests/kraken/driver.html?testName=audio-beat-detection) 16:24:53 INFO - PROCESS | 22597 | RSS: Main: 217546752 16:24:53 INFO - PROCESS | 22597 | 16:24:56 INFO - PROCESS | 22597 | Cycle 1(1): loaded http://localhost:52088/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52088/tests/kraken/driver.html?testName=audio-dft) 16:24:56 INFO - PROCESS | 22597 | RSS: Main: 204079104 16:24:56 INFO - PROCESS | 22597 | 16:25:00 INFO - PROCESS | 22597 | Cycle 1(1): loaded http://localhost:52088/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52088/tests/kraken/driver.html?testName=audio-fft) 16:25:00 INFO - PROCESS | 22597 | RSS: Main: 269459456 16:25:00 INFO - PROCESS | 22597 | 16:25:02 INFO - PROCESS | 22597 | Cycle 1(1): loaded http://localhost:52088/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52088/tests/kraken/driver.html?testName=audio-oscillator) 16:25:03 INFO - PROCESS | 22597 | RSS: Main: 181161984 16:25:03 INFO - PROCESS | 22597 | 16:25:05 INFO - PROCESS | 22597 | Cycle 1(1): loaded http://localhost:52088/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52088/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:25:06 INFO - PROCESS | 22597 | RSS: Main: 184918016 16:25:06 INFO - PROCESS | 22597 | 16:25:09 INFO - PROCESS | 22597 | Cycle 1(1): loaded http://localhost:52088/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52088/tests/kraken/driver.html?testName=imaging-darkroom) 16:25:09 INFO - PROCESS | 22597 | RSS: Main: 220041216 16:25:09 INFO - PROCESS | 22597 | 16:25:13 INFO - PROCESS | 22597 | Cycle 1(1): loaded http://localhost:52088/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52088/tests/kraken/driver.html?testName=imaging-desaturate) 16:25:13 INFO - PROCESS | 22597 | RSS: Main: 221908992 16:25:13 INFO - PROCESS | 22597 | 16:25:16 INFO - PROCESS | 22597 | Cycle 1(1): loaded http://localhost:52088/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52088/tests/kraken/driver.html?testName=json-parse-financial) 16:25:17 INFO - PROCESS | 22597 | RSS: Main: 222343168 16:25:17 INFO - PROCESS | 22597 | 16:25:18 INFO - PROCESS | 22597 | Cycle 1(1): loaded http://localhost:52088/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52088/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:25:19 INFO - PROCESS | 22597 | RSS: Main: 210632704 16:25:19 INFO - PROCESS | 22597 | 16:25:20 INFO - PROCESS | 22597 | Cycle 1(1): loaded http://localhost:52088/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52088/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:25:21 INFO - PROCESS | 22597 | RSS: Main: 214376448 16:25:21 INFO - PROCESS | 22597 | 16:25:23 INFO - PROCESS | 22597 | Cycle 1(1): loaded http://localhost:52088/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52088/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:25:24 INFO - PROCESS | 22597 | RSS: Main: 211976192 16:25:24 INFO - PROCESS | 22597 | 16:25:26 INFO - PROCESS | 22597 | Cycle 1(1): loaded http://localhost:52088/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52088/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:25:27 INFO - PROCESS | 22597 | RSS: Main: 220938240 16:25:27 INFO - PROCESS | 22597 | 16:25:30 INFO - PROCESS | 22597 | Cycle 1(1): loaded http://localhost:52088/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52088/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:25:30 INFO - PROCESS | 22597 | RSS: Main: 223973376 16:25:30 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | Cycle 1(1): loaded http://localhost:52088/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52088/tests/kraken/driver.html?testName=ai-astar) 16:25:32 INFO - PROCESS | 22597 | RSS: Main: 266809344 16:25:32 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | __start_tp_report 16:25:32 INFO - PROCESS | 22597 | _x_x_mozilla_page_load 16:25:32 INFO - PROCESS | 22597 | _x_x_mozilla_page_load_details 16:25:32 INFO - PROCESS | 22597 | |i|pagename|runs| 16:25:32 INFO - PROCESS | 22597 | |0;ai-astar;102;111;111;111;123;110;110;110;109;111 16:25:32 INFO - PROCESS | 22597 | |1;audio-beat-detection;151;118;119;170;119;116;141;119;158;120 16:25:32 INFO - PROCESS | 22597 | |2;audio-dft;161;163;161;161;159;159;161;162;161;161 16:25:32 INFO - PROCESS | 22597 | |3;audio-fft;74;76;74;108;75;76;75;76;102;75 16:25:32 INFO - PROCESS | 22597 | |4;audio-oscillator;84;83;117;83;83;84;114;82;84;120 16:25:32 INFO - PROCESS | 22597 | |5;imaging-gaussian-blur;116;117;117;116;117;117;117;117;116;117 16:25:32 INFO - PROCESS | 22597 | |6;imaging-darkroom;148;146;148;145;146;146;146;146;146;145 16:25:32 INFO - PROCESS | 22597 | |7;imaging-desaturate;95;95;94;95;95;95;95;94;96;95 16:25:32 INFO - PROCESS | 22597 | |8;json-parse-financial;52;54;48;49;49;51;51;50;50;50 16:25:32 INFO - PROCESS | 22597 | |9;json-stringify-tinderbox;64;63;62;63;63;62;63;62;63;63 16:25:32 INFO - PROCESS | 22597 | |10;stanford-crypto-aes;83;75;75;77;75;83;75;80;80;81 16:25:32 INFO - PROCESS | 22597 | |11;stanford-crypto-ccm;118;123;126;123;126;134;119;126;173;126 16:25:32 INFO - PROCESS | 22597 | |12;stanford-crypto-pbkdf2;160;156;155;156;155;156;155;154;156;155 16:25:32 INFO - PROCESS | 22597 | |13;stanford-crypto-sha256-iterative;63;63;63;61;61;64;62;62;65;59 16:25:32 INFO - PROCESS | 22597 | __end_tp_report 16:25:32 INFO - PROCESS | 22597 | __start_cc_report 16:25:32 INFO - PROCESS | 22597 | _x_x_mozilla_cycle_collect,1682 16:25:32 INFO - PROCESS | 22597 | __end_cc_report 16:25:32 INFO - PROCESS | 22597 | __startTimestamp1468797932510__endTimestamp 16:25:32 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | ------- Summary: start ------- 16:25:32 INFO - PROCESS | 22597 | Number of tests: 14 16:25:32 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.00 stddev:5.07 (4.6%) stddev-sans-first:4.27 16:25:32 INFO - PROCESS | 22597 | Values: 102.0 111.0 111.0 111.0 123.0 110.0 110.0 110.0 109.0 111.0 16:25:32 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | [#1] audio-beat-detection Cycles:10 Average:133.10 Median:130.50 stddev:20.15 (15.4%) stddev-sans-first:20.30 16:25:32 INFO - PROCESS | 22597 | Values: 151.0 118.0 119.0 170.0 119.0 116.0 141.0 119.0 158.0 120.0 16:25:32 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.20 (0.7%) stddev-sans-first:1.27 16:25:32 INFO - PROCESS | 22597 | Values: 161.0 163.0 161.0 161.0 159.0 159.0 161.0 162.0 161.0 161.0 16:25:32 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | [#3] audio-fft Cycles:10 Average:81.10 Median:75.00 stddev:12.70 (16.9%) stddev-sans-first:13.20 16:25:32 INFO - PROCESS | 22597 | Values: 74.0 76.0 74.0 108.0 75.0 76.0 75.0 76.0 102.0 75.0 16:25:32 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | [#4] audio-oscillator Cycles:10 Average:93.40 Median:83.00 stddev:16.36 (19.7%) stddev-sans-first:16.99 16:25:32 INFO - PROCESS | 22597 | Values: 84.0 83.0 117.0 83.0 83.0 84.0 114.0 82.0 84.0 120.0 16:25:32 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 16:25:32 INFO - PROCESS | 22597 | Values: 116.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 16:25:32 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:1.03 (0.7%) stddev-sans-first:0.87 16:25:32 INFO - PROCESS | 22597 | Values: 148.0 146.0 148.0 145.0 146.0 146.0 146.0 146.0 146.0 145.0 16:25:32 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:25:32 INFO - PROCESS | 22597 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 95.0 94.0 96.0 95.0 16:25:32 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:50.50 stddev:1.71 (3.4%) stddev-sans-first:1.72 16:25:32 INFO - PROCESS | 22597 | Values: 52.0 54.0 48.0 49.0 49.0 51.0 51.0 50.0 50.0 50.0 16:25:32 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | [#9] json-stringify-tinderbox Cycles:10 Average:62.80 Median:63.00 stddev:0.63 (1.0%) stddev-sans-first:0.50 16:25:32 INFO - PROCESS | 22597 | Values: 64.0 63.0 62.0 63.0 63.0 62.0 63.0 62.0 63.0 63.0 16:25:32 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:80.00 stddev:3.37 (4.2%) stddev-sans-first:3.14 16:25:32 INFO - PROCESS | 22597 | Values: 83.0 75.0 75.0 77.0 75.0 83.0 75.0 80.0 80.0 81.0 16:25:32 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | [#11] stanford-crypto-ccm Cycles:10 Average:129.40 Median:126.00 stddev:15.95 (12.7%) stddev-sans-first:16.37 16:25:32 INFO - PROCESS | 22597 | Values: 118.0 123.0 126.0 123.0 126.0 134.0 119.0 126.0 173.0 126.0 16:25:32 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.80 Median:156.00 stddev:1.62 (1.0%) stddev-sans-first:0.71 16:25:32 INFO - PROCESS | 22597 | Values: 160.0 156.0 155.0 156.0 155.0 156.0 155.0 154.0 156.0 155.0 16:25:32 INFO - PROCESS | 22597 | 16:25:32 INFO - PROCESS | 22597 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:63.00 stddev:1.70 (2.7%) stddev-sans-first:1.79 16:25:32 INFO - PROCESS | 22597 | Values: 63.0 63.0 63.0 61.0 61.0 64.0 62.0 62.0 65.0 59.0 16:25:32 INFO - PROCESS | 22597 | -------- Summary: end -------- 16:25:32 INFO - PROCESS | 22597 | 16:25:33 INFO - TEST-INFO | 22597: exit 0 16:25:33 INFO - TEST-OK | kraken | took 60413ms 16:25:33 INFO - SUITE-END | took 1173s 16:25:33 INFO - Completed test suite (00:19:33) 16:25:33 INFO - javascript benchmark 16:25:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3077.246074711696, 3085.182426656936, 3099.9070027899165, 3115.283484798495, 3138.2781960149164, 5315.627906046579, 5339.466053394659, 5418.756155972981, 5440.047991601468, 5486.972565137179, 5390.568754499641, 5393.757280922358, 5397.514223719864, 5435.918461223085, 5457.263269458632, 4819.831305904294, 4918.213085906255, 4918.385201849769, 4920.089783390066, 5090.211017292307, 4994.126027945095, 5006.1990081586955, 5029.968856384432, 5050.84847454577, 5057.468965758598, 4799.328094066834, 4802.927956080662, 4822.445418776851, 4823.493533179001, 4827.058723548909, 4828.758562071917, 4829.323894654751, 4833.854984350475, 4847.975760121212, 4941.110600091982, 4825.758712064383, 4848.103100926304, 4878.02439512099, 4883.536064073907, 4913.508649135094, 5207.531322180984, 5351.571874250089, 5381.67709937405, 5402.702851343174, 5410.323709536308, 5810.3899090595305, 5812.244408226946, 5812.854678633025, 5858.238429004245, 5869.8239052828485, 5879.970600147015, 5914.053751399799, 5918.733656985446, 5929.5256379489965, 5932.169496270526, 5249.711265880375, 5389.353277606673, 5442.482964118402, 5449.155380915925, 5526.640768350045, 6054.697265136726, 6059.21230240066, 6062.454379105813, 6063.514918806573, 6064.818055458343, 5524.530414914777, 5809.099589563624, 5812.418758124154, 5819.6217245879725, 5820.476157145878, 5682.374938756733, 5717.342505611908, 5725.627834190765, 5822.388649191774, 5823.970880145657, 5734.082546792493, 5749.252597162425, 5751.166080918243, 5751.741171647286, 5798.333191682926, 5560.388357280696, 5691.430856914276, 5707.0868661014865, 5777.104548794943, 5778.682172480554, 4380.627646650007, 4410.29435290352, 4410.360497727876, 4450.910981780411, 4452.777361131931, 3920.5883382245024, 3938.960610393917, 3947.684185265172, 3960.7821569813937, 3960.861369852041, 5309.814156504503, 5418.02475554404, 5470.7264636768, 5505.807296744595, 5527.695976721238, 5847.736851841677, 5857.15071314657, 5907.2911250650195, 5925.940740592625, 5926.259217597801, 5561.972190139023, 5565.220869078333, 5571.4150014248735, 5572.804951826666, 5576.386597474275, 4887.65786394956, 4902.4362198346935, 4910.729909854921, 4926.8768280793265, 5076.543111120017, 5883.529317654577, 5894.17481552583, 5897.056470964625, 5897.852553686193, 5906.409359064145, 5711.885762284648, 5717.656940583578, 5725.685087320071, 5726.455986681259, 5729.083346664514, 5789.681567513827, 5934.732937017759, 5963.4334738199805, 5996.940030599638, 6041.335453100068, 5727.656340619576, 5744.281964754406, 5745.913811292918, 5747.971260143672, 5750.338711048283, 5566.666000040011, 5807.332156802022, 5859.20900678403, 5871.559633027455, 5877.823665290048, 5564.777408903598, 5579.693116878449, 5624.550035997192, 5648.604597678123, 5662.745176466987, 5583.55331573465, 5628.0, 5628.324601047901, 5628.85927851807, 5634.802781902614, 5422.240886275846, 5512.035393806148, 5546.861328466821, 5586.217929489793, 5598.356189038313, 5624.803131890528, 5675.347335056521, 5742.397048310035, 5743.74153163117, 5745.396733343106, 5572.275604171432, 5572.331320241597, 5573.944260557505, 5577.6653400796085, 5580.525655319181], "value": 5330.922591470318, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [612.8712970276242, 614.1340709599464, 636.2333388267141, 644.6969924135656, 648.8702259548091, 33845.153871153234, 44168.55831441685, 45157.7742111289, 45162.54837451624, 45171.54828451714, 47480.050398991996, 47800.28299575509, 47909.7604511977, 47935.24777026762, 47983.0, 9008.414453060546, 9127.087291270886, 9143.725688229331, 9143.908560914373, 9150.176484116428, 9004.369694121415, 9070.77323066922, 9087.136722011432, 9087.31845111616, 9087.545622718872, 9089.454632722025, 9134.17792398684, 9137.954310228439, 9139.725808225763, 9148.313876459259, 9064.274858011344, 9096.090390960851, 9097.04481029489, 9113.726588202364, 9117.407368521059, 9024.639014439415, 9039.412438191563, 9078.86381704275, 9096.727098186999, 9117.908820911838, 43763.34354984678, 44164.55835441637, 44349.55650443486, 44542.777286113684, 44554.0, 3430.639782822818, 3440.415129428003, 3554.0581745837376, 3576.6065732769634, 3587.587427445825, 33729.0, 33729.32541349159, 34095.82952085249, 35501.82249088738, 35704.28591428183, 4278.208531421666, 4319.654427645781, 4395.868123956287, 4419.469663640351, 4432.556744325574, 261441.0, 261519.0, 262085.0, 262340.0, 262685.0, 62147.37852621506, 62173.134805955895, 62433.68783156055, 62720.0, 62742.37257627457, 36617.0, 37445.25109497849, 37558.62441375606, 39462.0, 65832.67083664551, 17407.3037078518, 17412.21645025977, 17432.564185895455, 17509.299628014738, 17609.20758565842, 13178.472861085449, 13252.73494530104, 13277.601671949664, 13279.203247805162, 13281.66795830112, 16211.27049282785, 16381.262843172086, 16487.91756041236, 16496.587585310463, 16509.6698066038, 7070.257622949619, 7234.0, 7344.412447004227, 7428.9628551856895, 7488.363489103487, 963.5471328475605, 1037.5227395398201, 1076.978460430787, 1099.6755956992706, 1145.3814939932342, 33921.32157356889, 34239.31521369558, 34333.20205590437, 34365.59440839167, 35081.1229719254, 977.7555611097225, 1108.7394462301352, 1124.6344937895217, 1136.670365593985, 1149.5401839264362, 17265.913670431568, 17609.911950440423, 17640.38258660941, 17703.73444398335, 17709.0, 609111.0, 618659.0, 629886.0, 632383.0, 929981.0, 63381.36618633847, 63569.0, 63951.68024159849, 63956.0, 63998.0, 1236.2829558855842, 1343.7648411527944, 1386.9514566990106, 1387.2370196391944, 1388.2780953904116, 31543.36913261676, 35455.46816797802, 35716.82141589379, 35968.28063438769, 35984.640153599175, 52743.20885186648, 53144.0, 53277.46722532725, 53280.0, 53364.46635533595, 482240.0, 488372.0, 488384.0, 488386.0, 488635.0, 29795.1266335375, 46186.307205392615, 46640.0, 47709.52290477051, 47836.0, 48719.26921096257, 50346.244806328665, 52647.47352526426, 53163.0, 54182.0, 44252.336214957446, 44510.0, 44567.55432445634, 45159.774201128785, 46142.30786538137, 44211.55788442203, 44660.776696116314, 44695.882602935184, 45766.313505298116, 45898.77050614859, 43903.0, 44609.33086003777, 45640.771796140805, 45748.54251457443, 45785.542144578125, 38556.0, 39064.80467597644, 39314.0, 39982.80008600055, 40311.59688403078, 28921.805372301857, 29088.41823163567, 29578.556321654763, 29694.55458168086, 29905.401891961603], "value": 20904.982351240218, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.53602548674, 386.92648396804606, 389.58509187715083, 398.364608449523, 416.4211745673513, 17230.138493075345, 19272.710909336372, 19325.613487730272, 19327.130279137436, 19824.306149284777, 19272.903635481838, 19275.614487710238, 19571.72783769054, 20262.797372026274, 20302.0, 5628.521575666074, 5726.7422965966425, 5858.355580886099, 5949.315828679714, 5951.791687290945, 5777.913331300034, 5826.038703613898, 5842.444967728059, 5873.0, 5873.383294754053, 5599.692016939067, 5774.682392468412, 5790.507806836424, 5801.0, 5812.854678633025, 5751.3961034091235, 5756.453136951983, 5758.28021497315, 5764.798232061858, 5796.362400135981, 5048.0, 5063.417706963673, 5177.326947496839, 5182.300389447414, 5189.299444574972, 24230.87884560566, 24627.630585541232, 24660.630090548657, 25032.624510632173, 25064.749352506424, 2422.140140250221, 2533.7972962163167, 2538.7207407185197, 2845.573164025392, 3166.2401023754364, 14420.783688244685, 14980.550583482513, 15251.313690883937, 15518.137823461144, 15871.60320991973, 1916.7029110487758, 1996.8602197846158, 2016.042379869565, 2018.1927229108328, 2036.745406824147, 83829.16170838274, 84543.0, 88292.11707882903, 93488.06511934861, 94551.52724236334, 10860.076893463985, 11063.668089957315, 11075.335479871233, 11313.15151363651, 11316.359127926404, 15327.693446131016, 15356.923215383851, 15391.230438478031, 15434.691306174038, 15474.297827238996, 7913.089994650573, 7921.445498815027, 8094.797630059178, 8238.876416853753, 8281.751547453587, 7965.283124518821, 8247.175282471704, 8375.120612335617, 8476.279516241066, 8491.405601607952, 4654.418197725285, 4722.456917454537, 4810.11012962603, 4822.276658501253, 4837.975810120927, 1811.5652243461475, 1889.5559543507266, 1929.8166674165932, 1931.951701207453, 1947.9318223862097, 920.5167287174287, 987.5407935309971, 987.5555999799975, 990.232569758443, 1027.5427434791595, 18750.0, 20755.68866467004, 21852.23517176923, 22243.555128897657, 22423.32730018102, 915.2403505090758, 942.9528523573794, 992.4789485520015, 1007.4056306779036, 1010.1262408017159, 10127.430248311524, 10758.193135514868, 10758.73103172427, 10832.620858269922, 11916.880831191751, 500485.0, 503750.0, 508942.0, 509286.0, 510901.0, 81034.0, 104024.0, 107188.46405768077, 107227.46386268175, 107688.0, 940.7365937537501, 982.8967958364269, 990.4750482244422, 997.5560875410373, 1036.6630844975414, 17634.382796602058, 18387.908060459613, 18709.43871683852, 18986.43040708781, 19989.0, 46884.2523596622, 47021.529784701714, 47180.292295614905, 47326.763366182946, 48203.27695084647, 60345.396546033975, 62919.0, 62938.0, 63296.05055924073, 63639.6818015907, 6738.797836064926, 7526.0, 8337.249647531751, 8760.080191580048, 8802.075782042793, 11605.0, 12277.079219058427, 12300.876991229974, 12526.937365313115, 12694.451211326941, 9954.55204515784, 10066.094051535398, 10406.687799366031, 10573.62992295266, 10877.891221087688, 10218.0, 10374.688759337232, 10497.16022718196, 10597.15222782191, 10698.518566664363, 10913.618023369145, 10915.672529824118, 10938.56245750161, 11103.222774405715, 11181.217314787888, 8268.793280168044, 8586.098459661895, 8735.519546424754, 8806.691765788166, 8919.394508988185, 8346.499210047417, 10845.891541084487, 11137.777244455228, 11288.379139147426, 11432.542698291974], "value": 9401.595780398211, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1480.8519148085193, 1509.0945432740355, 1658.7097257979858, 1802.783665960085, 1821.0621529912105, 8592.140785921405, 9204.81590368194, 9227.354085214038, 9227.49248791318, 9229.40008899421, 7547.207543207953, 8561.529115898624, 8797.1202879712, 8808.427452215601, 8833.116688331163, 2367.206985659802, 2397.1729753235104, 2397.724261709908, 2399.9880000599974, 2400.303911865557, 2344.0975224538506, 2370.9407264818346, 2371.905123795046, 2373.6439534069855, 2380.1907351500486, 1779.830916062972, 1800.5588630785476, 1802.4322338463408, 2066.8036536529007, 2132.6374516332185, 2322.2336628912417, 2333.7899589036983, 2339.157903154863, 2350.3066595354326, 2354.3643216331666, 2331.895064722092, 2333.6849525314037, 2334.684817549626, 2335.708036495438, 2336.3575016870327, 8594.828103437896, 8738.606762695694, 8748.650053997833, 8749.0, 8751.649934002633, 5219.42586315505, 5344.759485823147, 5353.0, 5356.491133342365, 5357.973210133924, 3239.400710868497, 3276.0663210984753, 3410.9829450852835, 3421.5209870618132, 3422.6064002639764, 1062.8512008318842, 1074.371492676785, 1084.5553323137476, 1090.43297485308, 1098.873629532598, 12620.55828046023, 12774.808377874338, 12788.488460461478, 12789.488420463264, 12796.296203708793, 4600.470945841204, 4705.423683066143, 4816.349792778, 4824.589909857631, 4841.927371089437, 10632.0, 10693.9465302673, 10705.19711021661, 10713.625023124154, 10714.89285107139, 5479.287692600016, 5479.808206712746, 5481.0, 5483.835484935458, 5484.725763711878, 4927.571004408753, 4990.451050384455, 5016.122178618727, 5016.949830501649, 5028.924566131511, 4018.7186896916937, 4032.536258330271, 4056.7565946043333, 4114.773687447219, 4151.816732231299, 1166.7666466706692, 1167.9065674746, 1184.7334349771234, 1188.89299963002, 1243.2229856339789, 721.844803367268, 766.9118051424047, 862.9956850215834, 889.4307643108416, 959.12240300126, 5881.0, 5957.910631340534, 5985.132155837379, 6003.21958145447, 6096.055111457643, 935.9812803743887, 954.0983770337103, 960.6013504395597, 978.5205249427729, 988.660365205151, 4033.798310084484, 4180.979095104505, 4223.66210703143, 4296.828126874952, 4311.33174357975, 10893.891061089445, 11327.433628318551, 11401.0, 11416.42917854104, 11421.714457138638, 10748.355098694103, 11195.664130076111, 11203.607873724544, 11213.495392707347, 11256.437178141225, 8128.9187108129345, 8184.468009579358, 8256.25693687571, 8279.668813247523, 8280.0, 6320.051992201206, 6338.461230795434, 6424.678766061772, 6482.805515834532, 6551.082848401323, 10345.948270258601, 10496.317739346918, 10547.208959328234, 10651.946740266249, 10652.520636571528, 10312.123469504875, 10758.085562726943, 10772.138228941823, 10795.784084318428, 10954.616588419367, 7796.805079873048, 7845.725399610987, 7880.369570434466, 7902.644381002754, 7911.169327220559, 8337.916620833957, 8661.177188167114, 8792.60433280491, 9086.045965173562, 9100.271978241592, 7773.92226077732, 7804.453688241769, 7868.803279918048, 7995.880061799194, 8129.227723366271, 7805.804854878674, 7814.296713295604, 7847.136814950237, 7876.527408355517, 7949.960250198712, 7221.31397517235, 7229.49393542447, 7275.272472752682, 7292.562446253241, 7298.343149116605, 5265.183896496049, 5445.646033007842, 5539.612227144061, 5621.381648018633, 5665.008623490955, 5429.239906413184, 5462.453754624506, 5505.256790333254, 5576.860578485408, 5681.715914204356], "value": 4703.4791981908975, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.7649727450324, 323.2548974613516, 325.67269893756776, 335.64421712984256, 339.4077335050338, 5421.295231619889, 5458.536024437927, 5462.945370546293, 5467.371252305986, 5506.119020956643, 5113.846584602458, 5209.817656382027, 5313.468653134684, 5342.412334643186, 5397.190421436787, 4173.415721798935, 4208.758416267208, 4219.755172224186, 4239.109786944745, 4249.9150016999565, 4238.745675259479, 4250.426192464024, 4257.318828987362, 4272.252355837732, 4275.46556680415, 3978.4430179774854, 3995.2608767377983, 4198.244316023115, 4205.3481710334945, 4222.923154595574, 4183.581641835792, 4192.769397683126, 4208.831646734127, 4231.322988321854, 4240.434537105053, 3715.3126671565847, 3724.2365315110337, 3728.7017038637114, 3732.42147467143, 3741.7942013189263, 5175.534201921808, 5244.527992480657, 5252.1596544552685, 5272.947270527284, 5283.0, 4830.6135509159185, 4854.684445511066, 4856.4172299323955, 4875.512448755132, 4880.681676203811, 5598.356189038272, 5602.355729091166, 5620.4098569650005, 5627.465390787868, 5637.238972738669, 4011.475639257093, 4038.1318016626274, 4067.2068946555432, 4081.2041651877894, 4100.2414553307735, 5716.171155182476, 5719.828405147852, 5747.626404283708, 5781.19063331134, 5850.151727999459, 4207.305794543866, 4215.852445164404, 4216.3464662977285, 4216.873493795191, 4220.556841531657, 4151.522574903925, 4199.9370009449885, 4229.894252643647, 4257.446531950888, 4268.015372851577, 4744.69159504638, 4760.214564596872, 4776.880577985508, 4777.71333719971, 4783.306420568998, 5600.131979543095, 5618.0, 5659.462351076641, 5659.773609055674, 5663.377028526941, 1381.4389739594394, 1414.4483651375972, 1428.5928510374492, 1448.876845468126, 1473.3369983507464, 1006.9949650251701, 1017.2116609627598, 1017.7048655889857, 1023.2223510132204, 1024.8368102203904, 5374.140137577969, 5376.220448035013, 5384.9461505385225, 5385.084535628953, 5434.91847622286, 5659.434056594308, 5661.688607126565, 5677.148427735872, 5695.0, 5697.202391665171, 5165.848832212348, 5274.472552744694, 5300.31095957523, 5343.812966546152, 5359.464053594687, 2666.719994400599, 2700.7164247754095, 2750.7936904732223, 2752.0367871245303, 2759.7240275972645, 5235.905637744856, 5237.6333656643665, 5245.606579506552, 5322.467753224724, 5346.331708536433, 4915.21356582945, 5141.53726164647, 5166.483351664803, 5168.870778230575, 5210.254564720861, 5083.949160508421, 5132.153194722903, 5134.383873935151, 5175.249588809602, 5214.270002199695, 5020.16763881188, 5024.819167495709, 5040.353814247549, 5060.696358218519, 5081.745912704424, 5362.222477740784, 5796.913046304393, 5822.301323841166, 5858.7070646468355, 5864.824055278348, 6053.818385448443, 6054.182685337423, 6070.635761854126, 6071.423214794588, 6083.452489275986, 3933.4689816874356, 4110.23960567296, 4111.835526578903, 4178.937315940324, 4179.811908464066, 5595.384507704068, 5814.883702326015, 5818.592698511064, 5822.94177058224, 5862.530997520274, 5527.972360138174, 5641.746121424628, 5645.209670646195, 5662.518685911579, 5720.542356611378, 5573.944260557505, 5656.773729050957, 5668.348139963957, 5683.488486036276, 5712.200291959212, 5325.893482130413, 5587.273654424899, 5606.579506536945, 5610.0, 5629.268195134607, 5645.548356131418, 5677.148427735872, 5677.517411019944, 5688.459596338341, 5693.6299140557385, 5449.891002179855, 5596.104623260258, 5596.776128954796, 5627.409122042291, 5632.690202038927], "value": 4235.466789117791, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.3876898481213, 780.3640033372802, 794.9284564389204, 824.843279776842, 837.6565608100676, 12304.753904921907, 12778.936105319472, 12789.3605319734, 12807.103502754811, 12865.0, 13347.532836350729, 14091.224982625952, 14112.082714623568, 14426.134431934091, 14478.565643030699, 604.876000419915, 822.827206286679, 837.2004735477605, 842.7893026743315, 843.0557775291682, 790.2137373313533, 827.2554700769296, 831.3640065350012, 834.1533343656193, 835.1272919798824, 852.7569642651846, 854.1245223645751, 854.8247609240121, 855.2132038524542, 856.3705676327894, 700.3591713582066, 727.316322656701, 839.6179738219126, 842.2882664148784, 853.4196746212604, 522.2479629333748, 523.5314393617729, 532.7868852459009, 539.9811006614789, 540.8404520666413, 13851.584452466443, 13895.722085558233, 13924.0, 13958.441662333495, 13958.93020534901, 14297.0, 14659.706805863823, 14721.779173312409, 14768.335424905905, 14784.0, 13613.863861361358, 13701.478283360939, 13751.931240343834, 13911.79132313016, 13937.651558711113, 4659.62722982164, 4667.089917466095, 4846.297286893416, 4867.318575399411, 4869.853904382839, 91605.0, 92002.5399873003, 92281.99999999933, 92766.0, 92958.0, 10284.640037598649, 10681.305715128492, 10689.412082335391, 11073.280236784583, 11558.133140014532, 21320.466988325104, 21652.35042948714, 21667.56664866694, 21704.240351587618, 21704.89147554252, 8197.303229225463, 8426.789330266793, 8551.957240213758, 8854.380193386402, 8873.068327825615, 4626.330978206814, 4650.720956742606, 4663.27719203524, 4746.196544522823, 4779.450363208206, 3495.9300813983577, 3616.1863580694658, 3617.7105831533413, 3631.3463576556474, 3639.6724294813594, 588.5115354256043, 603.6016229288648, 607.3380015782818, 638.4700698420301, 638.4987784589075, 733.2447578993645, 736.8931504931755, 762.5158024654385, 765.6142382288076, 773.628658244046, 10052.173396027343, 10289.639862604772, 10349.379037257788, 10538.947305263424, 10620.681379558626, 719.223238901995, 727.1492353945896, 732.9890051649229, 737.7344156103799, 750.9934499445519, 4720.315554244616, 4950.950490495049, 4959.20652695567, 4970.701757894538, 4978.90042199154, 69657.65171174111, 69812.0, 113672.43163784128, 120250.3987480057, 120622.39688801675, 89045.0, 90856.54571727231, 91750.0, 106470.46764766127, 107157.0, 81710.59144704476, 84309.0, 90511.0, 98117.5094124539, 100891.0, 10441.738956526147, 10531.196250966046, 10686.625968091079, 10837.078848297982, 10882.347059176474, 58381.70809146097, 59001.0, 59484.40515594788, 60768.0, 61786.0, 73255.63372183318, 86144.0, 86363.5681821612, 86628.56685716783, 87575.56212218897, 13560.254186019469, 13873.236971966639, 13911.165330080228, 13930.95517836146, 14294.428222870969, 15258.465953691568, 15444.22778861075, 15654.843451565339, 15688.294026768595, 15756.527304180892, 14427.711445771236, 14607.269636518344, 14618.85381146175, 14847.0, 15094.471693490676, 14276.643083922985, 14417.13497190172, 14483.42709821857, 14709.117452952856, 15232.771508427604, 14084.295785210903, 14384.784228236376, 14647.92676036613, 14824.62938426548, 14852.777208341668, 11713.472893719632, 11986.700332491757, 11998.58004969822, 12317.568885088978, 12492.937535312265, 12228.816567751313, 12291.372344907712, 12477.376131193585, 12512.562060327844, 12560.74878502443], "value": 7780.040323505657, "unit": "score"}], "name": "dromaeo_css", "value": 7386.4156365918425, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 111.0, 111.0, 123.0, 110.0, 110.0, 110.0, 109.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 118.0, 119.0, 170.0, 119.0, 116.0, 141.0, 119.0, 158.0, 120.0], "value": 133.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 161.0, 161.0, 159.0, 159.0, 161.0, 162.0, 161.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 108.0, 75.0, 76.0, 75.0, 76.0, 102.0, 75.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 117.0, 83.0, 83.0, 84.0, 114.0, 82.0, 84.0, 120.0], "value": 93.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 148.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 96.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 48.0, 49.0, 49.0, 51.0, 51.0, 50.0, 50.0, 50.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 62.0, 63.0, 63.0, 62.0, 63.0, 62.0, 63.0, 63.0], "value": 62.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 75.0, 75.0, 77.0, 75.0, 83.0, 75.0, 80.0, 80.0, 81.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 123.0, 126.0, 123.0, 126.0, 134.0, 119.0, 126.0, 173.0, 126.0], "value": 129.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 156.0, 155.0, 156.0, 155.0, 156.0, 155.0, 154.0, 156.0, 155.0], "value": 155.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 61.0, 61.0, 64.0, 62.0, 62.0, 65.0, 59.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1476.2, "alertThreshold": 2.0}]} 16:25:33 INFO - Return code: 0 16:25:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:25:33 INFO - # TBPL SUCCESS # 16:25:33 INFO - Running post-action listener: _resource_record_post_action 16:25:33 INFO - ##### 16:25:33 INFO - ##### Finished run-tests step (success) 16:25:33 INFO - ##### 16:25:33 INFO - Running post-run listener: _resource_record_post_run 16:25:33 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 555634688; Read time: 8; Write time: 345720 16:25:33 INFO - TinderboxPrint: CPU usage
13.0% 16:25:33 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 16:25:33 INFO - TinderboxPrint: I/O write bytes / time
555,634,688 / 345,720 16:25:33 INFO - TinderboxPrint: CPU idle
8,266.9 (87.0%) 16:25:33 INFO - TinderboxPrint: CPU user
1,198.8 (12.6%) 16:25:33 INFO - TinderboxPrint: Swap in / out
0 / 0 16:25:33 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 257384448; Read time: 0; Write time: 195844 16:25:33 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 298250240; Read time: 0; Write time: 149876 16:25:34 INFO - Running post-run listener: _upload_blobber_files 16:25:34 INFO - Blob upload gear active. 16:25:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:25:34 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:25:34 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:25:34 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:25:34 INFO - (blobuploader) - INFO - Open directory for files ... 16:25:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:25:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:25:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:25:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:25:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:25:38 INFO - (blobuploader) - INFO - Done attempting. 16:25:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:25:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:25:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:25:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:25:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:25:39 INFO - (blobuploader) - INFO - Done attempting. 16:25:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:25:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:25:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:25:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:25:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:25:40 INFO - (blobuploader) - INFO - Done attempting. 16:25:40 INFO - (blobuploader) - INFO - Iteration through files over. 16:25:40 INFO - Return code: 0 16:25:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:25:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:25:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2844de1e3c76bb6a94b6f7bd9242d53415753476a029f91f3ff7b5b80ecfd27b3b0851d68c51bbc41748266b0503a1671f9e224f17b684bf17a3fff81578f3af", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0b2606634ecfc34ee47e7b02cb8a10ef111aeed3488fe5189b40a18adcfbf157215426d7f64e52503dc2a0fb66704d252da359644a7aa17f76c270da4c2f7d42", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2e7fd25be041dec8b3b9ddf0ad9f34ddc5021255812d3d6e795aed531f4928e9e06f3b2868aa8ea94fd930014211ef028cae0752cc566d8de2a8ec80eaa2fffb"} 16:25:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:25:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:25:40 INFO - Contents: 16:25:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2844de1e3c76bb6a94b6f7bd9242d53415753476a029f91f3ff7b5b80ecfd27b3b0851d68c51bbc41748266b0503a1671f9e224f17b684bf17a3fff81578f3af", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0b2606634ecfc34ee47e7b02cb8a10ef111aeed3488fe5189b40a18adcfbf157215426d7f64e52503dc2a0fb66704d252da359644a7aa17f76c270da4c2f7d42", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2e7fd25be041dec8b3b9ddf0ad9f34ddc5021255812d3d6e795aed531f4928e9e06f3b2868aa8ea94fd930014211ef028cae0752cc566d8de2a8ec80eaa2fffb"} 16:25:40 INFO - Running post-run listener: copy_logs_to_upload_dir 16:25:40 INFO - Copying logs to upload dir... 16:25:40 INFO - mkdir: /builds/slave/test/build/upload/logs 16:25:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.527240 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-07-17 16:25:39.388136) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 16:25:39.392869) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652 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/2844de1e3c76bb6a94b6f7bd9242d53415753476a029f91f3ff7b5b80ecfd27b3b0851d68c51bbc41748266b0503a1671f9e224f17b684bf17a3fff81578f3af", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0b2606634ecfc34ee47e7b02cb8a10ef111aeed3488fe5189b40a18adcfbf157215426d7f64e52503dc2a0fb66704d252da359644a7aa17f76c270da4c2f7d42", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2e7fd25be041dec8b3b9ddf0ad9f34ddc5021255812d3d6e795aed531f4928e9e06f3b2868aa8ea94fd930014211ef028cae0752cc566d8de2a8ec80eaa2fffb"} build_url:https://queue.taskcluster.net/v1/task/OcM55GpwRx-QD67a8dFGmg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011185 build_url: 'https://queue.taskcluster.net/v1/task/OcM55GpwRx-QD67a8dFGmg/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/2844de1e3c76bb6a94b6f7bd9242d53415753476a029f91f3ff7b5b80ecfd27b3b0851d68c51bbc41748266b0503a1671f9e224f17b684bf17a3fff81578f3af", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0b2606634ecfc34ee47e7b02cb8a10ef111aeed3488fe5189b40a18adcfbf157215426d7f64e52503dc2a0fb66704d252da359644a7aa17f76c270da4c2f7d42", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2e7fd25be041dec8b3b9ddf0ad9f34ddc5021255812d3d6e795aed531f4928e9e06f3b2868aa8ea94fd930014211ef028cae0752cc566d8de2a8ec80eaa2fffb"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 16:25:39.418537) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 16:25:39.418888) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1468795431.975369-1919386652 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004465 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 16:25:39.504050) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 16:25:39.504367) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 16:25:39.504645) ========= ========= Total master_lag: 0.16 =========