builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-005 starttime: 1471884503.42 results: success (0) buildid: 20160822064441 builduid: 0bb9dc8637cb452b8470a0a23584a29a revision: 194fe275b4e60ded2af6b25173eec421f0dba8ad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 09:48:23.424352) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 09:48:23.424822) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 09:48:23.425115) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 09:48:23.440849) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 09:48:23.441330) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-22 09:48:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 67.2M=0s 2016-08-22 09:48:23 (67.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.161521 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 09:48:23.631495) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 09:48:23.631819) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.125682 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 09:48:23.766977) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 09:48:23.767342) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 194fe275b4e60ded2af6b25173eec421f0dba8ad --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 194fe275b4e60ded2af6b25173eec421f0dba8ad --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=7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-22 09:48:23,828 truncating revision to first 12 chars 2016-08-22 09:48:23,828 Setting DEBUG logging. 2016-08-22 09:48:23,828 attempt 1/10 2016-08-22 09:48:23,828 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/194fe275b4e6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 09:48:24,079 unpacking tar archive at: mozilla-central-194fe275b4e6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.565776 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 09:48:24.346946) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 09:48:24.347292) ========= script_repo_revision: 194fe275b4e60ded2af6b25173eec421f0dba8ad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 09:48:24.347716) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 09:48:24.348064) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 09:48:24.358114) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-08-22 09:48:24.358498) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:48:24 INFO - MultiFileLogger online at 20160822 09:48:24 in /builds/slave/test 09:48:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:48:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:48:24 INFO - {'append_to_log': False, 09:48:24 INFO - 'base_work_dir': '/builds/slave/test', 09:48:24 INFO - 'blob_upload_branch': 'Firefox', 09:48:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:48:24 INFO - 'branch': 'Firefox', 09:48:24 INFO - 'buildbot_json_path': 'buildprops.json', 09:48:24 INFO - 'config_files': ('talos/linux_config.py',), 09:48:24 INFO - 'default_actions': ('clobber', 09:48:24 INFO - 'read-buildbot-config', 09:48:24 INFO - 'download-and-extract', 09:48:24 INFO - 'populate-webroot', 09:48:24 INFO - 'create-virtualenv', 09:48:24 INFO - 'install', 09:48:24 INFO - 'run-tests'), 09:48:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:48:24 INFO - 'download_minidump_stackwalk': True, 09:48:24 INFO - 'download_symbols': 'ondemand', 09:48:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:48:24 INFO - 'tooltool.py': '/tools/tooltool.py', 09:48:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:48:24 INFO - '/tools/misc-python/virtualenv.py')}, 09:48:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:48:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:48:24 INFO - 'installer_path': 'installer.exe', 09:48:24 INFO - 'log_level': 'info', 09:48:24 INFO - 'log_name': 'talos', 09:48:24 INFO - 'log_to_console': True, 09:48:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:48:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:48:24 INFO - 'opt_config_files': (), 09:48:24 INFO - 'pip_index': False, 09:48:24 INFO - 'sps_profile': False, 09:48:24 INFO - 'sps_profile_interval': 0, 09:48:24 INFO - 'suite': 'dromaeojs', 09:48:24 INFO - 'system_bits': '32', 09:48:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:48:24 INFO - 'title': 'talos-linux64-ix-005', 09:48:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:48:24 INFO - 'use_talos_json': True, 09:48:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:48:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:48:24 INFO - 'work_dir': 'build'} 09:48:24 INFO - [mozharness: 2016-08-22 16:48:24.634511Z] Running clobber step. 09:48:24 INFO - Running pre-action listener: _resource_record_pre_action 09:48:24 INFO - Running main action method: clobber 09:48:24 INFO - rmtree: /builds/slave/test/build 09:48:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:48:29 INFO - Running post-action listener: _resource_record_post_action 09:48:29 INFO - [mozharness: 2016-08-22 16:48:29.910117Z] Finished clobber step (success) 09:48:29 INFO - [mozharness: 2016-08-22 16:48:29.910273Z] Running read-buildbot-config step. 09:48:29 INFO - Running pre-action listener: _resource_record_pre_action 09:48:29 INFO - Running main action method: read_buildbot_config 09:48:29 INFO - Using buildbot properties: 09:48:29 INFO - { 09:48:29 INFO - "project": "", 09:48:29 INFO - "product": "firefox", 09:48:29 INFO - "script_repo_revision": "production", 09:48:29 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:48:29 INFO - "repository": "", 09:48:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:48:29 INFO - "buildid": "20160822064441", 09:48:29 INFO - "stage_platform": "linux64-pgo", 09:48:29 INFO - "basedir": "/builds/slave/test", 09:48:29 INFO - "nightly_build": "True", 09:48:29 INFO - "buildnumber": 538, 09:48:29 INFO - "slavename": "talos-linux64-ix-005", 09:48:29 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:48:29 INFO - "platform": "ubuntu64_hw", 09:48:29 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:48:29 INFO - "branch": "mozilla-central", 09:48:29 INFO - "repo_path": "mozilla-central", 09:48:29 INFO - "revision": "194fe275b4e60ded2af6b25173eec421f0dba8ad", 09:48:29 INFO - "pgo_build": "True", 09:48:29 INFO - "builduid": "0bb9dc8637cb452b8470a0a23584a29a", 09:48:29 INFO - "slavebuilddir": "test" 09:48:29 INFO - } 09:48:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 09:48:29 INFO - Running post-action listener: _resource_record_post_action 09:48:29 INFO - [mozharness: 2016-08-22 16:48:29.914027Z] Finished read-buildbot-config step (success) 09:48:29 INFO - [mozharness: 2016-08-22 16:48:29.914156Z] Running download-and-extract step. 09:48:29 INFO - Running pre-action listener: _resource_record_pre_action 09:48:29 INFO - Running main action method: download_and_extract 09:48:29 INFO - mkdir: /builds/slave/test/build/tests 09:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:29 INFO - trying https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:48:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:48:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:48:30 INFO - Downloaded 1361 bytes. 09:48:30 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:48:30 INFO - Using the following test package requirements: 09:48:30 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:48:30 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:48:30 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:48:30 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:30 INFO - u'jsshell-linux-x86_64.zip'], 09:48:30 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:48:30 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:48:30 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:48:30 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:48:30 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:48:30 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:48:30 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:48:30 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:30 INFO - trying https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:48:30 INFO - Downloading https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:48:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:48:31 INFO - Downloaded 21921893 bytes. 09:48:31 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 09:48:35 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:35 INFO - trying https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:48:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:48:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:48:36 INFO - Downloaded 21921893 bytes. 09:48:36 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 09:48:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:43 INFO - trying https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:48:43 INFO - Downloading https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:48:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:48:44 INFO - Downloaded 14413981 bytes. 09:48:44 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 09:48:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:44 INFO - trying https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:48:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:48:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:48:45 INFO - Downloaded 59787637 bytes. 09:48:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:48:45 INFO - mkdir: /builds/slave/test/properties 09:48:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:48:45 INFO - Writing to file /builds/slave/test/properties/build_url 09:48:45 INFO - Contents: 09:48:45 INFO - build_url:https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:48:46 INFO - Running post-action listener: _resource_record_post_action 09:48:46 INFO - Running post-action listener: set_extra_try_arguments 09:48:46 INFO - [mozharness: 2016-08-22 16:48:46.300918Z] Finished download-and-extract step (success) 09:48:46 INFO - [mozharness: 2016-08-22 16:48:46.301142Z] Running populate-webroot step. 09:48:46 INFO - Running pre-action listener: _resource_record_pre_action 09:48:46 INFO - Running main action method: populate_webroot 09:48:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:48:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:48:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:48:46 INFO - '--tppagecycles', 09:48:46 INFO - '7'], 09:48:46 INFO - 'tests': ['tsvgm']}}, 09:48:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:48:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:48:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:48:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:48:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:48:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:48:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:46 INFO - 'talos_options': ['--disable-e10s'], 09:48:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:46 INFO - 'talos_options': ['--disable-e10s'], 09:48:46 INFO - 'tests': ['damp', 'tps']}, 09:48:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:46 INFO - 'tests': ['damp', 'tps']}, 09:48:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:48:46 INFO - 'tests': ['dromaeo_dom']}, 09:48:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:48:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:48:46 INFO - 'tests': ['basic_compositor_video']}, 09:48:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:48:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:48:46 INFO - 'tests': ['a11yr', 09:48:46 INFO - 'ts_paint', 09:48:46 INFO - 'tpaint', 09:48:46 INFO - 'sessionrestore', 09:48:46 INFO - 'sessionrestore_no_auto_restore', 09:48:46 INFO - 'tabpaint']}, 09:48:46 INFO - 'other-e10s': {'tests': ['a11yr', 09:48:46 INFO - 'ts_paint', 09:48:46 INFO - 'tpaint', 09:48:46 INFO - 'sessionrestore', 09:48:46 INFO - 'sessionrestore_no_auto_restore', 09:48:46 INFO - 'tabpaint']}, 09:48:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:48:46 INFO - 'ts_paint', 09:48:46 INFO - 'tpaint', 09:48:46 INFO - 'sessionrestore', 09:48:46 INFO - 'sessionrestore_no_auto_restore', 09:48:46 INFO - 'tabpaint']}, 09:48:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:48:46 INFO - 'ts_paint', 09:48:46 INFO - 'tpaint', 09:48:46 INFO - 'sessionrestore', 09:48:46 INFO - 'sessionrestore_no_auto_restore', 09:48:46 INFO - 'tabpaint']}, 09:48:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:48:46 INFO - 'tests': ['a11yr', 09:48:46 INFO - 'ts_paint', 09:48:46 INFO - 'tpaint', 09:48:46 INFO - 'sessionrestore', 09:48:46 INFO - 'sessionrestore_no_auto_restore', 09:48:46 INFO - 'tabpaint']}, 09:48:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:48:46 INFO - 'tests': ['a11yr', 09:48:46 INFO - 'ts_paint', 09:48:46 INFO - 'tpaint', 09:48:46 INFO - 'sessionrestore', 09:48:46 INFO - 'sessionrestore_no_auto_restore', 09:48:46 INFO - 'tabpaint']}, 09:48:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:48:46 INFO - 'tests': ['tsvgx', 09:48:46 INFO - 'tsvgr_opacity', 09:48:46 INFO - 'tart', 09:48:46 INFO - 'tscrollx', 09:48:46 INFO - 'cart']}, 09:48:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:48:46 INFO - 'tsvgr_opacity', 09:48:46 INFO - 'tart', 09:48:46 INFO - 'tscrollx', 09:48:46 INFO - 'cart']}, 09:48:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:46 INFO - 'talos_options': ['--disable-e10s'], 09:48:46 INFO - 'tests': ['tp5o']}, 09:48:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:46 INFO - 'tests': ['tp5o']}, 09:48:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:46 INFO - 'talos_options': ['--disable-e10s', 09:48:46 INFO - '--xperf_path', 09:48:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:46 INFO - 'tests': ['tp5n']}, 09:48:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:46 INFO - 'talos_options': ['--xperf_path', 09:48:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:46 INFO - 'tests': ['tp5n']}}, 09:48:46 INFO - 'talos.zip': {'path': '', 09:48:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:48:46 INFO - Running post-action listener: _resource_record_post_action 09:48:46 INFO - [mozharness: 2016-08-22 16:48:46.318388Z] Finished populate-webroot step (success) 09:48:46 INFO - [mozharness: 2016-08-22 16:48:46.318476Z] Running create-virtualenv step. 09:48:46 INFO - Running pre-action listener: _resource_record_pre_action 09:48:46 INFO - Running main action method: create_virtualenv 09:48:46 INFO - Creating virtualenv /builds/slave/test/build/venv 09:48:46 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 09:48:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:48:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:48:46 INFO - Using real prefix '/usr' 09:48:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:48:47 INFO - Installing distribute.............................................................................................................................................................................................done. 09:48:48 INFO - Installing pip.................done. 09:48:49 INFO - Return code: 0 09:48:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:48:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:49 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 0x7ff9220d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2745760>, '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 0x2746660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2746af0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', '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 09:48:49 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 09:48:49 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 09:48:49 INFO - Using env: {'DISPLAY': ':0', 09:48:49 INFO - 'HOME': '/home/cltbld', 09:48:49 INFO - 'LANG': 'en_US.UTF-8', 09:48:49 INFO - 'LANGUAGE': 'en_US:en', 09:48:49 INFO - 'LOGNAME': 'cltbld', 09:48:49 INFO - 'MAIL': '/var/mail/cltbld', 09:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:49 INFO - 'MOZ_NO_REMOTE': '1', 09:48:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:49 INFO - 'NO_EM_RESTART': '1', 09:48:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:49 INFO - 'PWD': '/builds/slave/test', 09:48:49 INFO - 'SHELL': '/bin/bash', 09:48:49 INFO - 'SHLVL': '1', 09:48:49 INFO - 'TERM': 'linux', 09:48:49 INFO - 'TMOUT': '86400', 09:48:49 INFO - 'USER': 'cltbld', 09:48:49 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', 09:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:49 INFO - '_': '/tools/buildbot/bin/python'} 09:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:49 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 09:48:49 INFO - Cleaning up... 09:48:49 INFO - Return code: 0 09:48:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:48:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:49 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 0x7ff9220d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2745760>, '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 0x2746660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2746af0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', '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 09:48:49 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 09:48:49 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 09:48:49 INFO - Using env: {'DISPLAY': ':0', 09:48:49 INFO - 'HOME': '/home/cltbld', 09:48:49 INFO - 'LANG': 'en_US.UTF-8', 09:48:49 INFO - 'LANGUAGE': 'en_US:en', 09:48:49 INFO - 'LOGNAME': 'cltbld', 09:48:49 INFO - 'MAIL': '/var/mail/cltbld', 09:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:49 INFO - 'MOZ_NO_REMOTE': '1', 09:48:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:49 INFO - 'NO_EM_RESTART': '1', 09:48:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:49 INFO - 'PWD': '/builds/slave/test', 09:48:49 INFO - 'SHELL': '/bin/bash', 09:48:49 INFO - 'SHLVL': '1', 09:48:49 INFO - 'TERM': 'linux', 09:48:49 INFO - 'TMOUT': '86400', 09:48:49 INFO - 'USER': 'cltbld', 09:48:49 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', 09:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:49 INFO - '_': '/tools/buildbot/bin/python'} 09:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:49 INFO - Downloading/unpacking psutil>=3.1.1 09:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:48:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:51 INFO - Installing collected packages: psutil 09:48:51 INFO - Running setup.py install for psutil 09:48:51 INFO - building 'psutil._psutil_linux' extension 09:48:51 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 09:48:52 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 09:48:52 INFO - building 'psutil._psutil_posix' extension 09:48:52 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 09:48:52 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 09:48:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:53 INFO - Successfully installed psutil 09:48:53 INFO - Cleaning up... 09:48:53 INFO - Return code: 0 09:48:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:48:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9220d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2745760>, '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 0x2746660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2746af0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', '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 09:48:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:48:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:48:53 INFO - Using env: {'DISPLAY': ':0', 09:48:53 INFO - 'HOME': '/home/cltbld', 09:48:53 INFO - 'LANG': 'en_US.UTF-8', 09:48:53 INFO - 'LANGUAGE': 'en_US:en', 09:48:53 INFO - 'LOGNAME': 'cltbld', 09:48:53 INFO - 'MAIL': '/var/mail/cltbld', 09:48:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:53 INFO - 'MOZ_NO_REMOTE': '1', 09:48:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:53 INFO - 'NO_EM_RESTART': '1', 09:48:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:53 INFO - 'PWD': '/builds/slave/test', 09:48:53 INFO - 'SHELL': '/bin/bash', 09:48:53 INFO - 'SHLVL': '1', 09:48:53 INFO - 'TERM': 'linux', 09:48:53 INFO - 'TMOUT': '86400', 09:48:53 INFO - 'USER': 'cltbld', 09:48:53 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', 09:48:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:53 INFO - '_': '/tools/buildbot/bin/python'} 09:48:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:48:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:48:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:48:55 INFO - Installing collected packages: mozsystemmonitor 09:48:55 INFO - Running setup.py install for mozsystemmonitor 09:48:55 INFO - Successfully installed mozsystemmonitor 09:48:55 INFO - Cleaning up... 09:48:55 INFO - Return code: 0 09:48:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:48:55 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:55 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:55 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 0x7ff9220d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2745760>, '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 0x2746660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2746af0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', '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 09:48:55 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 09:48:55 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 09:48:55 INFO - Using env: {'DISPLAY': ':0', 09:48:55 INFO - 'HOME': '/home/cltbld', 09:48:55 INFO - 'LANG': 'en_US.UTF-8', 09:48:55 INFO - 'LANGUAGE': 'en_US:en', 09:48:55 INFO - 'LOGNAME': 'cltbld', 09:48:55 INFO - 'MAIL': '/var/mail/cltbld', 09:48:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:55 INFO - 'MOZ_NO_REMOTE': '1', 09:48:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:55 INFO - 'NO_EM_RESTART': '1', 09:48:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:55 INFO - 'PWD': '/builds/slave/test', 09:48:55 INFO - 'SHELL': '/bin/bash', 09:48:55 INFO - 'SHLVL': '1', 09:48:55 INFO - 'TERM': 'linux', 09:48:55 INFO - 'TMOUT': '86400', 09:48:55 INFO - 'USER': 'cltbld', 09:48:55 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', 09:48:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:55 INFO - '_': '/tools/buildbot/bin/python'} 09:48:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:55 INFO - Downloading/unpacking jsonschema==2.5.1 09:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:48:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:57 INFO - Downloading functools32-3.2.3-2.tar.gz 09:48:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:48:57 INFO - warning: no files found matching '*.txt' 09:48:57 INFO - no previously-included directories found matching 'build' 09:48:57 INFO - no previously-included directories found matching 'dist' 09:48:57 INFO - no previously-included directories found matching '.git*' 09:48:57 INFO - Installing collected packages: jsonschema, functools32 09:48:57 INFO - Running setup.py install for functools32 09:48:57 INFO - warning: no files found matching '*.txt' 09:48:57 INFO - no previously-included directories found matching 'build' 09:48:57 INFO - no previously-included directories found matching 'dist' 09:48:57 INFO - no previously-included directories found matching '.git*' 09:48:57 INFO - Successfully installed jsonschema functools32 09:48:57 INFO - Cleaning up... 09:48:57 INFO - Return code: 0 09:48:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:48:57 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:57 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:57 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9220d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2745760>, '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 0x2746660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2746af0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', '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 09:48:57 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:48:57 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 functools32==3.2.3-2 09:48:57 INFO - Using env: {'DISPLAY': ':0', 09:48:57 INFO - 'HOME': '/home/cltbld', 09:48:57 INFO - 'LANG': 'en_US.UTF-8', 09:48:57 INFO - 'LANGUAGE': 'en_US:en', 09:48:57 INFO - 'LOGNAME': 'cltbld', 09:48:57 INFO - 'MAIL': '/var/mail/cltbld', 09:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:57 INFO - 'MOZ_NO_REMOTE': '1', 09:48:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:57 INFO - 'NO_EM_RESTART': '1', 09:48:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:57 INFO - 'PWD': '/builds/slave/test', 09:48:57 INFO - 'SHELL': '/bin/bash', 09:48:57 INFO - 'SHLVL': '1', 09:48:57 INFO - 'TERM': 'linux', 09:48:57 INFO - 'TMOUT': '86400', 09:48:57 INFO - 'USER': 'cltbld', 09:48:57 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', 09:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:57 INFO - '_': '/tools/buildbot/bin/python'} 09:48:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:48:58 INFO - Cleaning up... 09:48:58 INFO - Return code: 0 09:48:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:48:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:58 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 0x7ff9220d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2745760>, '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 0x2746660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2746af0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', '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 09:48:58 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 09:48:58 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 09:48:58 INFO - Using env: {'DISPLAY': ':0', 09:48:58 INFO - 'HOME': '/home/cltbld', 09:48:58 INFO - 'LANG': 'en_US.UTF-8', 09:48:58 INFO - 'LANGUAGE': 'en_US:en', 09:48:58 INFO - 'LOGNAME': 'cltbld', 09:48:58 INFO - 'MAIL': '/var/mail/cltbld', 09:48:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:58 INFO - 'MOZ_NO_REMOTE': '1', 09:48:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:58 INFO - 'NO_EM_RESTART': '1', 09:48:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:58 INFO - 'PWD': '/builds/slave/test', 09:48:58 INFO - 'SHELL': '/bin/bash', 09:48:58 INFO - 'SHLVL': '1', 09:48:58 INFO - 'TERM': 'linux', 09:48:58 INFO - 'TMOUT': '86400', 09:48:58 INFO - 'USER': 'cltbld', 09:48:58 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', 09:48:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:58 INFO - '_': '/tools/buildbot/bin/python'} 09:48:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:58 INFO - Downloading/unpacking blobuploader==1.2.4 09:48:58 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 09:48:58 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 09:48:58 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 09:48:58 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 09:48:58 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 09:48:58 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 09:49:00 INFO - Downloading blobuploader-1.2.4.tar.gz 09:49:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:49:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:49:00 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 09:49:00 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 09:49:00 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 09:49:00 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 09:49:00 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 09:49:00 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 09:49:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:49:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:49:00 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 09:49:00 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 09:49:00 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 09:49:00 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 09:49:00 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 09:49:00 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 09:49:00 INFO - Downloading docopt-0.6.1.tar.gz 09:49:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:49:01 INFO - Installing collected packages: blobuploader, requests, docopt 09:49:01 INFO - Running setup.py install for blobuploader 09:49:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:49:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:49:01 INFO - Running setup.py install for requests 09:49:01 INFO - Running setup.py install for docopt 09:49:01 INFO - Successfully installed blobuploader requests docopt 09:49:01 INFO - Cleaning up... 09:49:01 INFO - Return code: 0 09:49:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:01 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 0x7ff9220d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2745760>, '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 0x2746660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2746af0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', '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 09:49:01 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 09:49:01 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 09:49:01 INFO - Using env: {'DISPLAY': ':0', 09:49:01 INFO - 'HOME': '/home/cltbld', 09:49:01 INFO - 'LANG': 'en_US.UTF-8', 09:49:01 INFO - 'LANGUAGE': 'en_US:en', 09:49:01 INFO - 'LOGNAME': 'cltbld', 09:49:01 INFO - 'MAIL': '/var/mail/cltbld', 09:49:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:01 INFO - 'MOZ_NO_REMOTE': '1', 09:49:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:01 INFO - 'NO_EM_RESTART': '1', 09:49:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:01 INFO - 'PWD': '/builds/slave/test', 09:49:01 INFO - 'SHELL': '/bin/bash', 09:49:01 INFO - 'SHLVL': '1', 09:49:01 INFO - 'TERM': 'linux', 09:49:01 INFO - 'TMOUT': '86400', 09:49:01 INFO - 'USER': 'cltbld', 09:49:01 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', 09:49:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:01 INFO - '_': '/tools/buildbot/bin/python'} 09:49:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:49:01 INFO - Running setup.py (path:/tmp/pip-y1Q5NM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:49:01 INFO - Running setup.py (path:/tmp/pip-16RhvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:49:02 INFO - Running setup.py (path:/tmp/pip-JA8AVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:49:02 INFO - Running setup.py (path:/tmp/pip-9NBae2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:49:02 INFO - Running setup.py (path:/tmp/pip-eJB44Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:49:02 INFO - Running setup.py (path:/tmp/pip-D8qjjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:49:02 INFO - Running setup.py (path:/tmp/pip-D9qLXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:49:02 INFO - Running setup.py (path:/tmp/pip-JYNvY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:49:02 INFO - Running setup.py (path:/tmp/pip-JkfzM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:49:02 INFO - Running setup.py (path:/tmp/pip-uwvRoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:49:02 INFO - Running setup.py (path:/tmp/pip-1IYrkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:49:02 INFO - Running setup.py (path:/tmp/pip-bMDpnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:49:02 INFO - Running setup.py (path:/tmp/pip-c01XW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:49:02 INFO - Running setup.py (path:/tmp/pip-YyRrQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:02 INFO - Running setup.py (path:/tmp/pip-LXYI7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:49:02 INFO - Running setup.py (path:/tmp/pip-PNLK2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:49:03 INFO - Running setup.py (path:/tmp/pip-Ved6aG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:49:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:49:03 INFO - Running setup.py install for manifestparser 09:49:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Running setup.py install for mozcrash 09:49:03 INFO - Running setup.py install for mozdebug 09:49:03 INFO - Running setup.py install for mozdevice 09:49:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Running setup.py install for mozfile 09:49:03 INFO - Running setup.py install for mozhttpd 09:49:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Running setup.py install for mozinfo 09:49:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Running setup.py install for mozInstall 09:49:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Running setup.py install for mozleak 09:49:03 INFO - Running setup.py install for mozlog 09:49:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:49:04 INFO - Running setup.py install for moznetwork 09:49:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:49:04 INFO - Running setup.py install for mozprocess 09:49:04 INFO - Running setup.py install for mozprofile 09:49:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:49:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:49:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:49:04 INFO - Running setup.py install for mozrunner 09:49:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:49:04 INFO - Running setup.py install for mozscreenshot 09:49:04 INFO - Running setup.py install for moztest 09:49:04 INFO - Running setup.py install for mozversion 09:49:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:49:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:49:04 INFO - Cleaning up... 09:49:04 INFO - Return code: 0 09:49:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:04 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:04 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:04 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 0x7ff9220d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2745760>, '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 0x2746660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2746af0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', '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 09:49:04 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 09:49:04 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 09:49:04 INFO - Using env: {'DISPLAY': ':0', 09:49:04 INFO - 'HOME': '/home/cltbld', 09:49:04 INFO - 'LANG': 'en_US.UTF-8', 09:49:04 INFO - 'LANGUAGE': 'en_US:en', 09:49:04 INFO - 'LOGNAME': 'cltbld', 09:49:04 INFO - 'MAIL': '/var/mail/cltbld', 09:49:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:04 INFO - 'MOZ_NO_REMOTE': '1', 09:49:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:04 INFO - 'NO_EM_RESTART': '1', 09:49:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:04 INFO - 'PWD': '/builds/slave/test', 09:49:04 INFO - 'SHELL': '/bin/bash', 09:49:04 INFO - 'SHLVL': '1', 09:49:04 INFO - 'TERM': 'linux', 09:49:04 INFO - 'TMOUT': '86400', 09:49:04 INFO - 'USER': 'cltbld', 09:49:04 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', 09:49:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:04 INFO - '_': '/tools/buildbot/bin/python'} 09:49:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:49:05 INFO - Running setup.py (path:/tmp/pip-etC7EI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:49:05 INFO - Running setup.py (path:/tmp/pip-kswT38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:49:05 INFO - Running setup.py (path:/tmp/pip-WjuZQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:49:05 INFO - Running setup.py (path:/tmp/pip-5SLmTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:49:05 INFO - Running setup.py (path:/tmp/pip-aezXwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:49:05 INFO - Running setup.py (path:/tmp/pip-gh6YPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:49:05 INFO - Running setup.py (path:/tmp/pip-DsXHNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:49:05 INFO - Running setup.py (path:/tmp/pip-q49ifr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:49:05 INFO - Running setup.py (path:/tmp/pip-yl91zC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:49:05 INFO - Running setup.py (path:/tmp/pip-lXsnEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:49:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:49:05 INFO - Running setup.py (path:/tmp/pip-RAsl3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:49:05 INFO - Running setup.py (path:/tmp/pip-X1pVCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:49:05 INFO - Running setup.py (path:/tmp/pip-bY_E0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:49:06 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)) 09:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:49:06 INFO - Running setup.py (path:/tmp/pip-dj4T7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:49:06 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)) 09:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:06 INFO - Running setup.py (path:/tmp/pip-cBC1zH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:06 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)) 09:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:49:06 INFO - Running setup.py (path:/tmp/pip-JV69eC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:49:06 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)) 09:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:49:06 INFO - Running setup.py (path:/tmp/pip-ARw0eY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:49:06 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)) 09:49:06 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)) 09:49:06 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)) 09:49:06 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)) 09:49:06 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)) 09:49:06 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)) 09:49:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:49:06 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 09:49:06 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 09:49:06 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 09:49:06 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 09:49:06 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 09:49:06 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 09:49:08 INFO - Downloading blessings-1.6.tar.gz 09:49:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:49:08 INFO - Installing collected packages: blessings 09:49:08 INFO - Running setup.py install for blessings 09:49:08 INFO - Successfully installed blessings 09:49:08 INFO - Cleaning up... 09:49:08 INFO - Return code: 0 09:49:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:49:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:49:08 INFO - Reading from file tmpfile_stdout 09:49:08 INFO - Current package versions: 09:49:08 INFO - argparse == 1.2.1 09:49:08 INFO - blessings == 1.6 09:49:08 INFO - blobuploader == 1.2.4 09:49:08 INFO - docopt == 0.6.1 09:49:08 INFO - functools32 == 3.2.3-2 09:49:08 INFO - jsonschema == 2.5.1 09:49:08 INFO - manifestparser == 1.1 09:49:08 INFO - mozInstall == 1.12 09:49:08 INFO - mozcrash == 0.17 09:49:08 INFO - mozdebug == 0.1 09:49:08 INFO - mozdevice == 0.48 09:49:08 INFO - mozfile == 1.2 09:49:08 INFO - mozhttpd == 0.7 09:49:08 INFO - mozinfo == 0.9 09:49:08 INFO - mozleak == 0.1 09:49:08 INFO - mozlog == 3.3 09:49:08 INFO - moznetwork == 0.27 09:49:08 INFO - mozprocess == 0.23 09:49:08 INFO - mozprofile == 0.28 09:49:08 INFO - mozrunner == 6.12 09:49:08 INFO - mozscreenshot == 0.1 09:49:08 INFO - mozsystemmonitor == 0.3 09:49:08 INFO - moztest == 0.7 09:49:08 INFO - mozversion == 1.4 09:49:08 INFO - psutil == 3.1.1 09:49:08 INFO - requests == 1.2.3 09:49:08 INFO - wsgiref == 0.1.2 09:49:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:08 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:08 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:08 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 0x7ff9220d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2745760>, '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 0x2746660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2746af0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', '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 09:49:08 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 09:49:08 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 09:49:08 INFO - Using env: {'DISPLAY': ':0', 09:49:08 INFO - 'HOME': '/home/cltbld', 09:49:08 INFO - 'LANG': 'en_US.UTF-8', 09:49:08 INFO - 'LANGUAGE': 'en_US:en', 09:49:08 INFO - 'LOGNAME': 'cltbld', 09:49:08 INFO - 'MAIL': '/var/mail/cltbld', 09:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:08 INFO - 'MOZ_NO_REMOTE': '1', 09:49:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:08 INFO - 'NO_EM_RESTART': '1', 09:49:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:08 INFO - 'PWD': '/builds/slave/test', 09:49:08 INFO - 'SHELL': '/bin/bash', 09:49:08 INFO - 'SHLVL': '1', 09:49:08 INFO - 'TERM': 'linux', 09:49:08 INFO - 'TMOUT': '86400', 09:49:08 INFO - 'USER': 'cltbld', 09:49:08 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', 09:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:08 INFO - '_': '/tools/buildbot/bin/python'} 09:49:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:09 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)) 09:49:09 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)) 09:49:09 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)) 09:49:09 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)) 09:49:09 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)) 09:49:09 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)) 09:49:09 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)) 09:49:09 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)) 09:49:09 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)) 09:49:09 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)) 09:49:09 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)) 09:49:09 INFO - Cleaning up... 09:49:09 INFO - Return code: 0 09:49:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:09 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 0x7ff9220d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b68d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2745760>, '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 0x2746660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2746af0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', '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 09:49:09 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 09:49:09 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 09:49:09 INFO - Using env: {'DISPLAY': ':0', 09:49:09 INFO - 'HOME': '/home/cltbld', 09:49:09 INFO - 'LANG': 'en_US.UTF-8', 09:49:09 INFO - 'LANGUAGE': 'en_US:en', 09:49:09 INFO - 'LOGNAME': 'cltbld', 09:49:09 INFO - 'MAIL': '/var/mail/cltbld', 09:49:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:09 INFO - 'MOZ_NO_REMOTE': '1', 09:49:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:09 INFO - 'NO_EM_RESTART': '1', 09:49:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:09 INFO - 'PWD': '/builds/slave/test', 09:49:09 INFO - 'SHELL': '/bin/bash', 09:49:09 INFO - 'SHLVL': '1', 09:49:09 INFO - 'TERM': 'linux', 09:49:09 INFO - 'TMOUT': '86400', 09:49:09 INFO - 'USER': 'cltbld', 09:49:09 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', 09:49:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:09 INFO - '_': '/tools/buildbot/bin/python'} 09:49:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:49:09 INFO - Cleaning up... 09:49:09 INFO - Return code: 0 09:49:09 INFO - Running post-action listener: _resource_record_post_action 09:49:09 INFO - Running post-action listener: _start_resource_monitoring 09:49:09 INFO - Starting resource monitoring. 09:49:09 INFO - [mozharness: 2016-08-22 16:49:09.269355Z] Finished create-virtualenv step (success) 09:49:09 INFO - [mozharness: 2016-08-22 16:49:09.269806Z] Running install step. 09:49:09 INFO - Running pre-action listener: _resource_record_pre_action 09:49:09 INFO - Running main action method: install 09:49:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:49:09 INFO - Reading from file tmpfile_stdout 09:49:09 INFO - Detecting whether we're running mozinstall >=1.0... 09:49:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:49:09 INFO - Reading from file tmpfile_stdout 09:49:09 INFO - Output received: 09:49:09 INFO - Usage: mozinstall [options] installer 09:49:09 INFO - Options: 09:49:09 INFO - -h, --help show this help message and exit 09:49:09 INFO - -d DEST, --destination=DEST 09:49:09 INFO - Directory to install application into. [default: 09:49:09 INFO - "/builds/slave/test"] 09:49:09 INFO - --app=APP Application being installed. [default: firefox] 09:49:09 INFO - mkdir: /builds/slave/test/build/application 09:49:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:49:26 INFO - Reading from file tmpfile_stdout 09:49:26 INFO - Output received: 09:49:26 INFO - /builds/slave/test/build/application/firefox/firefox 09:49:26 INFO - Running post-action listener: _resource_record_post_action 09:49:26 INFO - [mozharness: 2016-08-22 16:49:26.550553Z] Finished install step (success) 09:49:26 INFO - [mozharness: 2016-08-22 16:49:26.550887Z] Running run-tests step. 09:49:26 INFO - Running pre-action listener: _resource_record_pre_action 09:49:26 INFO - Running main action method: run_tests 09:49:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:49:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:49:26 INFO - Python 2.7.3 09:49:26 INFO - Return code: 0 09:49:26 INFO - grabbing minidump binary from tooltool 09:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:26 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 0x2745760>, '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 0x2746660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2746af0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:49:26 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 09:49:26 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 09:49:26 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 09:49:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:49:26 INFO - Return code: 0 09:49:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:49:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:49:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:49:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:49:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:49:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 09:49:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --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 09:49:26 INFO - Using env: {'DISPLAY': ':0', 09:49:26 INFO - 'HOME': '/home/cltbld', 09:49:26 INFO - 'LANG': 'en_US.UTF-8', 09:49:26 INFO - 'LANGUAGE': 'en_US:en', 09:49:26 INFO - 'LOGNAME': 'cltbld', 09:49:26 INFO - 'MAIL': '/var/mail/cltbld', 09:49:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:49:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:49:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:26 INFO - 'MOZ_NO_REMOTE': '1', 09:49:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:49:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:26 INFO - 'NO_EM_RESTART': '1', 09:49:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:26 INFO - 'PWD': '/builds/slave/test', 09:49:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:49:26 INFO - 'SHELL': '/bin/bash', 09:49:26 INFO - 'SHLVL': '1', 09:49:26 INFO - 'TERM': 'linux', 09:49:26 INFO - 'TMOUT': '86400', 09:49:26 INFO - 'USER': 'cltbld', 09:49:26 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304', 09:49:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:26 INFO - '_': '/tools/buildbot/bin/python'} 09:49:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 09:49:26 INFO - mozversion application_buildid: 20160822064441 09:49:26 INFO - mozversion application_changeset: 194fe275b4e60ded2af6b25173eec421f0dba8ad 09:49:26 INFO - mozversion application_display_name: Nightly 09:49:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:49:26 INFO - mozversion application_name: Firefox 09:49:26 INFO - mozversion application_remotingname: firefox 09:49:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:49:26 INFO - mozversion application_vendor: Mozilla 09:49:26 INFO - mozversion application_version: 51.0a1 09:49:26 INFO - mozversion platform_buildid: 20160822064441 09:49:26 INFO - mozversion platform_changeset: 194fe275b4e60ded2af6b25173eec421f0dba8ad 09:49:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:49:26 INFO - mozversion platform_version: 51.0a1 09:49:26 INFO - using testdate: 1471884566 09:49:26 INFO - actual date: 1471884566 09:49:26 INFO - starting webserver on 'localhost:48642' 09:49:26 INFO - SUITE-START | Running 2 tests 09:49:26 INFO - TEST-START | dromaeo_css 09:49:26 INFO - operating with platform_type : linux_ 09:49:26 INFO - Initialising browser for dromaeo_css test... 09:49:26 INFO - TEST-INFO | started process 3305 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbN7R6Q/profile http://localhost:48642/getInfo.html) 09:49:32 INFO - PROCESS | 3305 | __metrics Screen width/height:1600/1200 09:49:32 INFO - PROCESS | 3305 | colorDepth:24 09:49:32 INFO - PROCESS | 3305 | Browser inner width/height: 1024/697 09:49:32 INFO - PROCESS | 3305 | __metrics 09:49:33 INFO - PROCESS | 3305 | 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" 09:49:33 INFO - PROCESS | 3305 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:49:34 INFO - TEST-INFO | 3305: exit 0 09:49:34 INFO - Browser initialized. 09:49:34 INFO - Running cycle 1/1 for dromaeo_css test... 09:49:34 INFO - TEST-INFO | started process 3387 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbN7R6Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:46 INFO - PROCESS | 3387 | RSS: Main: 174366720 09:49:46 INFO - PROCESS | 3387 | 09:50:37 INFO - PROCESS | 3387 | 1471884637731 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] 09:52:46 INFO - PROCESS | 3387 | Cycle 1(1): loaded http://localhost:48642/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48642/tests/dromaeo/cssquery-ext.html) 09:52:47 INFO - PROCESS | 3387 | RSS: Main: 297693184 09:52:47 INFO - PROCESS | 3387 | 09:55:50 INFO - PROCESS | 3387 | Cycle 1(1): loaded http://localhost:48642/tests/dromaeo/cssquery-ext.html (next: http://localhost:48642/tests/dromaeo/cssquery-jquery.html) 09:55:51 INFO - PROCESS | 3387 | RSS: Main: 269094912 09:55:51 INFO - PROCESS | 3387 | 09:58:53 INFO - PROCESS | 3387 | Cycle 1(1): loaded http://localhost:48642/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48642/tests/dromaeo/cssquery-mootools.html) 09:58:53 INFO - PROCESS | 3387 | RSS: Main: 215486464 09:58:53 INFO - PROCESS | 3387 | 10:01:56 INFO - PROCESS | 3387 | Cycle 1(1): loaded http://localhost:48642/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48642/tests/dromaeo/cssquery-prototype.html) 10:01:56 INFO - PROCESS | 3387 | RSS: Main: 274870272 10:01:56 INFO - PROCESS | 3387 | 10:04:59 INFO - PROCESS | 3387 | Cycle 1(1): loaded http://localhost:48642/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48642/tests/dromaeo/cssquery-yui.html) 10:05:00 INFO - PROCESS | 3387 | RSS: Main: 214945792 10:05:00 INFO - PROCESS | 3387 | 10:08:02 INFO - PROCESS | 3387 | Cycle 1(1): loaded http://localhost:48642/tests/dromaeo/cssquery-yui.html (next: http://localhost:48642/tests/dromaeo/cssquery-dojo.html) 10:08:03 INFO - PROCESS | 3387 | RSS: Main: 308117504 10:08:03 INFO - PROCESS | 3387 | 10:08:03 INFO - PROCESS | 3387 | __start_tp_report 10:08:03 INFO - PROCESS | 3387 | _x_x_mozilla_page_load 10:08:03 INFO - PROCESS | 3387 | _x_x_mozilla_page_load_details 10:08:03 INFO - PROCESS | 3387 | |i|pagename|runs| 10:08:03 INFO - PROCESS | 3387 | |0;dojo.html;3007.473692103881;3008.939821203576;3014.065639651707;3017.939641207176;3027.9545806812903;5184.53339199472;5186.792528298873;5260.316158899343;5262.894742105161;5319.335083114611;5351.304330437048;5360.65155764875;5366.838994830152;5382.300300960869;5438.7280635968245;4853.708777473345;4885.0718363511;4954.033963377143;4996.9500304996845;5035.1188542005;4970.875728106808;4985.152524070917;5008.4240312364;5024.874378140539;5028;4758.024604955993;4800.279958006292;4814.109389762905;4846.103470857867;4941.925871111936;4865.732384718839;4884.047610715912;4887.462379138292;4930.235813448921;4944;4874.610031197496;4906.582940450066;4907.975460122712;4917.631177661654;4918.311436398907;5191.714455704935;5206.271122042917;5318.175682769177;5329.600279979017;5371.865703357447;5726.3414707308775;5750.281214848144;5756.942430575683;5764.337101233328;5766.682832444214;5534.529564986981;5785.4214578541805;5792.623479473821;5800.854978625526;5810.157527158581;5288.444713305086;5304.734763261821;5306.310179676618;5373.838784836461;5387.757550910218;5890.204822349014;5890.793822216202;5892.616979896293;5892.970535147297;5907.350191478934;5447.918281225785;5664.433556644302;5668.546516278688;5679.5172410345585;5694.9430505695245;5567.053600887859;5577.27495425592;5578.41426650204;5671.546276297886;5696.287964004499;5654.264945557134;5684.744186511617;5685.204071430003;5685.971570142123;5686.0333743263745;5486.807961721321;5643.887122257532;5694.60137790359;5705.686187259657;5708.600397972102;4366.868993930188;4404.273294906368;4408.162449134654;4453.421055262795;4479.955200448019;3958.2083583283456;3963.365861462152;3965.4844870166694;3966.682665386762;3973.2848087344555;5271.578273738091;5449.972750136303;5480.451954804567;5485.945140548623;5493.2034854945805;5683.658980461186;5732.914006289909;5746.540276777848;5813;5817.7091145442555;5501.559875209973;5544.306961629796;5544.77820887168;5553.083741182741;5554.944450555443;4818.855434336975;5037.370328708911;5048.091343558195;5054.21659642756;5054.342935418372;5894.17481552583;5933.554983376264;5944.494717948936;6048.546359023003;6055.9091613626715;5759.596828222152;5774.855628609318;5791.276090488689;5793.681347525927;5824.854378640568;5779.768809247583;5982.491488223375;6029.065494848305;6039;6044;5602.607817452902;5619.494245517923;5623.943760562342;5715.028445164416;5719.056355701263;5790.826275211751;5796.797112101056;5797.681127538026;5799.072148456226;5800.100984347433;5444.047291723853;5644.407337229532;5652.039153343859;5655.321361436654;5655.971720141373;5508.724563771875;5540.279763630702;5557.861053473695;5644.181593668813;5648.2092507049865;5578.442155784552;5583.497485226349;5590.552755779447;5595.776168953196;5598.580106491948;5638.436156384329;5702.287214098238;5706.229658996149;5711.086226203787;5730.08318669011;5507.173923911285;5575.832725018256;5612.298462692163;5616.578756593353;5622.915656265078 10:08:03 INFO - PROCESS | 3387 | |1;ext.html;563.3493315220921;565.501562944018;571.1632458448378;573.8852229554088;600.1867469578721;42535.36196957044;43511;44338.33492497611;44402.111957760826;44408.55591444085;45708.3143752844;46340;46379.76810115945;46452.76773616135;46518.022296655516;8164.510129392226;9013.774655633613;9194.540272986358;10758.892411075867;11061.225714200009;11015.83476247853;11125.944370278137;11128.443577821117;11160.274582152175;11163.38601376928;11186;11222.887771122307;11245.943770281137;11252.099832013419;11252.099832013419;11220.326780393161;11280.887191128066;11282.266652667551;11283.717907052307;11287.097032237483;11048.281861678965;11122.721931951684;11128.944355278252;11129.499172537255;11135.71939226983;42953;43544.564554354365;43590.78204608988;43595;43625.127497449874;3330.350581636582;3427.845746941394;3499.5800503939436;3600.495930569696;3816.809159542012;32427.18932026718;32690.673093269;32765.6723432765;32884;33617.6638233617;4169.353750168729;4276.935845962313;4375.868723938287;4384.101259241879;4504.054148628792;373341;373348;374627;374955;375181;60629;61097.69451152805;61106.69446652827;61123.38876611177;61368.69315653393;56782;56834;56856;56976;57068;16757.91621041887;17490.125493725263;17511.562210944827;17526.561835953948;17547.73678394825;11798.52805887755;11937.820932686016;11945.940270298766;11954.880451195377;11969.880301196876;18422.211992060224;19104.426867194008;19113;19149.425517234504;19311.806881931;6976.6860491277;7138.109065828347;7241.674124664402;7322.048133742579;7488.176300606929;949.2643201518878;992.7915137820994;1058.5501162006117;1075.8762742284737;1133.960311389114;32896.506552401734;32948.50577241343;33391.57714989196;33435.66257349679;33715.325693486;1093.4860615510697;1105.4307031878589;1118.692359601116;1125.499152876962;1204.8493938257718;16501.91749041247;17231.913840430967;17403.865859571455;17596.38412655551;17658.293668253125;595096;599185;599781;601255;601953;60103.69948150319;60692.48268793315;60731.39268607258;60753;60759;1132.9490172942074;1330.8868746156686;1332.68015676237;1372.1561239837627;1392.0812263905773;31686.841565792023;33723.66276337205;34176;34494.82752586221;34615.40373041159;50381;50916.74541627268;50981.490185097675;50998.49001509937;51221.48778512167;409877;411740;412526;413380;415100;25092.247232583053;26133.994590622322;26142.73857261403;26771.598426024015;28720.569191462564;48593.5140648589;48882.75558622184;48969.75515122402;49032.264516033;49323.75338123286;40023;40778.38832417576;41111.38332924949;41467.37798933079;42265;41184.79407602943;41862.372064419666;42003.159936801705;42994;43099.353509698005;41462.58537414708;41532.58467415287;42644;42950.35574466323;43031.35452968271;33936.490952635235;36079;37206.069848252926;37571.43071408212;37673;27885.581716273868;28192.053112564667;28445.715542844307;28769.712302876702;29182.708172918 10:08:03 INFO - PROCESS | 3387 | |2;jquery.html;391.42851436902123;399.2912580170199;402.9133736246706;409.844259181511;417.14901600734504;12337.568185113523;18274;19952.20191192354;19975.500612484695;19989.100490477926;12821.294828784414;18466.16902239399;18953.43139705811;19050.523736906584;19299.324523641677;5564.360098588653;5746.569007324468;5751.1085781703905;5754.424557544233;5780.624259423145;5457.481539253765;5647.717614119299;5696.857578560528;5707.486326230638;5723.599348045638;5622.775088996435;5650.915236271459;5652.123920792264;5653.575981801361;5666.093425051992;5445.37378201504;5578.63738856977;5649.6327738697055;5652.463016013472;5653.858653533674;5034.370703662043;5048.1670524363435;5052.040112378636;5058.620603454756;5059.418166910816;24296.878515607485;24327.635085473732;24409.87795061031;24470.877645611657;24778.5044299113;2101.989490052555;2295.8622482651094;2311.8034967027825;2333.8249631277554;2339.5554844579474;10412.895871041268;10611.628592999208;10651.78696426075;10896.836547451794;11154.386508742016;1916.7508223930938;1921.750172477583;1931.2468137426415;2000.949976250591;2051.446109550428;84870.575647122;86133.56933215294;87661;87970;90322;9621.759456013515;9744.805103897883;9806.31355805101;10250.692479225636;10421.791564168674;13395.464181432828;13469.595912122651;13559.86440135586;13601.86398136006;13639.522616708566;7526.548407095592;7741.61291935401;7814.413918955987;8041.517508949481;8043.11525732169;7733.574653394117;7765.611719414007;7810.218978102144;7990.6004699765945;7998.84002319962;4135.069609337875;4316.330968699856;4537.659675524283;4725.480197178307;4806.086843499724;1951.4535929939639;2016.84873634478;2027.5438026443933;2034.5523984723336;2036.3381900882273;898.4743924804063;918.9908100918905;968.5544649461327;1023.9334443261164;1056.1286938275953;19170.424887253404;19423.514412139808;19780.50548736293;19836.60326793456;19904.50238744043;958.7219706285239;976.2677991506371;986.2504496582649;989.0900371658092;1041.6614600254948;10329.225308101923;10794.190435717353;10815.729606759736;11086.22396432242;11098.44507774608;476267;489721;497597;507567;511891;58452.707736461896;69273.65363173252;96775;97357;102667;887.924526415262;890.2299510923134;917.312015988009;918.912703293186;998.7553049502773;16737.665246694753;16847.91576042161;17447.82552174462;17657.35139729051;18048.63902721912;43356.56643433651;44038.339424909296;44610.55389446064;45053;45145;59102.40897590969;59169.408305918114;60364.09453858108;60569.394306056376;62956;7706.460547761603;8417.789555261168;8428.283595894418;8533.359998000298;9239.861402079108;11671.708207294885;13416.932915335361;13884.946730896258;14152.363143658355;14504.709905802036;11448.255863368855;11705.88294117048;11845;12463.06527010496;12656.557020504239;5393.99251055684;8770.736877893672;11646.301221926711;11688.88311116912;11748.295102293549;11159.442027898735;11694.649160525198;12275.69310767238;12411.503539858304;12600.432980516081;7957.641906114123;8031;8037.23646253605;8199.71301004462;8307.584620768817;10675.306105103144;11903.464344104363;12077.57728479499;12135.817962730389;12327.753444930871 10:08:03 INFO - PROCESS | 3387 | |3;mootools.html;1243.2726854789948;1412.0468683638544;1424.5868698077559;1547.9922600386997;1765.4438851761702;8493.150684931505;8828;8832.425892316995;8841.204291613754;8938.195562399384;8437;8539.829203415944;8545.529995850226;8552.786180345494;8555.31557475402;2086;2347.4248809041765;2360;2382.404398900275;2383.4518060846017;2111.6938043983614;2129.9041543130597;2131.030381176561;2384.833061685683;2460.3357093584723;2380.7262164851004;2381.166591692911;2383.7854593086618;2385.141349114326;2399.6280576510667;2251.8311126665485;2272.3523795718143;2285.6342985122383;2308.4113551044543;2312.8034117100065;2258.9774102259094;2328.4062564046058;2329.97670023301;2356.29311206639;2372.039324073753;9378.437293762396;9432.95283523578;9457.810843783154;9468.668596599086;9474.573644186028;4994.700317980896;5120.769565369567;5122.436531981479;5123.7438128093445;5137.023965446553;3239.093053944899;3326.334733053399;3384.8307584620916;3408.625051244361;3409.5226668266464;1150.4822829726656;1170.2978213072174;1178.787818192725;1203.3261373630708;1208.09996552568;12404.875951240463;12561.120721549587;12566.434510447052;12567.497300107985;12584.748305033849;4349.673774466928;4534.730275522859;4655.883602909955;4701.8354357598;4754.07295577366;9681.854772178423;9696.0788725071;9711.66009189689;9719.56261968213;9721.416714997124;5241.9737901310245;5242.239875218072;5244.895102097937;5244.947550524522;5245.737713114406;4813.422389313305;4838.298446725205;4838.612910967114;4847.781849816767;4934.087193869074;3431.6911477966605;3718.7396882218527;3727.9068023299633;3829.6553310202216;3893.7274390792372;1129.7629096139715;1133.0538999934981;1140.8745038045975;1141.9143564232716;1158.5481662151767;793.5318162284279;877.7147427086222;886.1802832380023;905.4929239625831;905.5562774240707;5446.039425806706;5486.341639003265;5621;5684.8294551163535;5703.230063941398;944.3672739264572;959.5250351076262;986.2849434160177;994.9602015919427;1006.3559322033905;3610.8736194233074;3848.5766565677754;3998.8400463981698;4027.7583344998807;4089.1208390196252;11216.66350009501;11660.650180494598;11677.824832627517;11685.824712629317;11687.766244675058;11063.778724425467;11235.83146252807;11444.771104578029;11476.713082172846;11496.712582185512;7900.538400395945;7976.481528700616;8007.279344858991;8308.252257296872;8524.104968978292;5594.13290939905;5648.181013752901;5667.631603945731;5702;5833.941660583339;10078.748031299276;10450.059494645215;10469.162467002468;10492.370457772558;10509.106725928381;10601.363918164934;10627.681169564925;10819.72950676208;10889.727756806144;10922.126229901747;7781.883271751041;7859.410544209321;7888.013998250219;7891.289783919475;7960.084590272193;7642.847143057219;7937.801554961172;7941.245581669732;8144.30773384269;8478.660853565789;7269.418446524372;7809.882851757342;7882.5270483771155;8005.3995950305125;8059.597020148852;7526;7911.723089691834;7912.723054693058;7961.16407777175;8164.020317561931;6964.233934267125;7082.822929426806;7085.185203701641;7091.11361079865;7095.787126386217;4744.667873248839;4775.952240477551;4798.928016079692;4880.707157570557;4893.266010098514;4696.718196908197;4835.734034627989;4850.902981940412;4943.085529177114;5054.241863720471 10:08:03 INFO - PROCESS | 3387 | |4;prototype.html;316.4162120886963;317.89689776475626;324.34967889381784;325.34118410219304;342.17706416069365;5089.236614507826;5227.013734780783;5265.4997775211295;5334.519448456462;5335.33308336458;5226.843194704165;5227.2159176123605;5242.292290540776;5243.764030618622;5244.318238628983;4109.773962432065;4117.711760176789;4128.442660240874;4129.256733787909;4153.0863210093885;4018.7588744675268;4111.198316328318;4135.043443937584;4135.069609337905;4139.489086483427;4076.5515793262716;4100.712950093494;4110.815013324408;4111.938320925188;4121.5672354402805;4073.6333729964445;4089.8159582818844;4110.671146308288;4112.588741125894;4113.8971525711795;3625.9456108158397;3650.6166852480374;3655.469956856241;3665.771966391262;3684.281565094808;4873.098476781771;5041.1682072458;5063.468265867066;5112.667676601009;5219.42586315505;4585.701929374613;4593.104344652794;4628.907421851544;4629.65277604181;4645.6980296280635;5379.919301210485;5398.352197736258;5433.402325744204;5460.672359658433;5480.150576660624;3769.6230376962358;3791.938257287964;3810.237952409529;3812.942805857914;3831.4444405561317;5321.973390133063;5378.139497680391;5593.300837395325;5614.1859430382365;5652.123920792284;3941.8620348287673;3971.185906889081;3972.0864201233876;3977.3636218204947;3982.8008599569907;3841.1357444574746;3960.20795840833;4006.1386801837743;4023.7183397161916;4048.5101662477364;4606;4610.538946105363;4616.8614941551805;4649.953500464952;4676.158291507561;5418.241446197536;5428.809991650273;5450.454954504517;5477.123660214346;5484.561235101182;1379.2689874366602;1380.9930950345183;1395.7278330725615;1401.1733077484332;1420.31824724133;895.3642913531336;913.5477938420524;922.8938672052666;924.3852837862746;931.4737173496952;4591.701539399928;4658.3711198988385;4663.836765713184;4700.247960326331;4719.7404142772475;4848.903021939612;4852.514748525119;4857.441394239637;4915.238138088602;4934.1611925972675;4608.041582327497;4681.4018219888285;4762.78567464458;4784.779881130359;4788.736619485892;2641.6697912760906;2648.854313012803;2669.666291713536;2673.46530693862;2677.710557473371;5116.232565115188;5116.462771408948;5131.409887862869;5154.768035438415;5170.543991603958;4771.5467030632035;4951.851444456672;4996.875078123076;5003.774830132653;5032.220005899091;5038.874028149326;5054.797808087709;5063.468335824686;5068.896407990643;5128.512791284822;4843.612510999182;4864.854054378375;4908;4937.814924418042;4971.627127965345;4887.409672873383;5164;5292.603054770831;5470.617056805985;5492.80775172883;5724.513416359656;5725.19847221381;5764.567657425794;5773.393793651606;5774.94225057744;3935.606439356156;3945.6843452523153;3949.842006319715;3955.545112312121;3956.8021598920513;5322.308099946982;5487.66774120989;5536.252605898152;5552.167174923632;5553.139263414178;5418.620696551204;5420.674759514442;5425.321834770653;5432.7555260012605;5435.320584926884;5257.8159764408065;5363.490468405495;5396.163594642837;5429.348478182485;5435.402105768441;5315.681059136464;5330.147176451749;5358.866028349323;5360.624756267024;5376.784928602812;5443;5457.918131227955;5459.317585301837;5495.807646732345;5519.475649813261;5086;5343.519083282367;5347.946520534745;5349.652272602269;5385.19595935366 10:08:03 INFO - PROCESS | 3387 | |5;yui.html;752.6124046116252;757.2314101187294;778.4395235430488;797.210761346267;857.2199298638236;14957.850421495781;16888.83111168888;16906.408275710353;16916.57708557285;16919.661606767873;15009;15134.09195448274;15176.468823591176;15190.31643576039;15196.240187990612;555.7026990560051;792.8889955406248;803.1085495100434;806.6007326373453;825.5211977053325;805.9153788852174;807.0557447786105;808.8180159464102;810.432697112021;812.9878051829228;866.4974314897345;867.6355930509201;870.2037635563483;870.9695160669378;874.8294082653885;532.9040772660919;812.3947658994058;844.4257904624852;844.5861527851308;847.1570787066908;619.2692622705206;622.6606499457761;622.8723111762077;623.0529595015596;623.1431781300712;14191.148531088065;14207;14253;14264.072835265675;14367;12616.116871818977;12697;12737.235765853986;13004.739905201843;13610.659733506642;11915.582954596633;12053.698657533545;12187.93906030473;12191.878081219164;12309.630711078593;4302.892427689333;4351.477822661269;4368.344748287751;4372.453443319585;4463.553644635511;79851;79999.20000799975;81788;84505;84567.99999999939;10312.484375781181;11181.32912025281;11207.943960280147;11296.322220666787;11644.184907056424;21512.677309840365;21513.13947442116;21533.89233053825;21555.67666485004;21572.676409853866;8051.315638170821;8172.591370431454;8334.66661333552;8517.23344898962;8568.614412351335;4551.6813823032735;4657.6273898088075;4697.107549565571;4698.929516057262;4721;3188.1870123118456;3280.852361643684;3298.356820419995;3323.418401779679;3324.5844269466315;611.0406661541426;612.2744547710977;619.0466681310731;624.687656171914;636.6021236727046;617.7251123250111;653.9803805885736;665.6971078159426;669.718718138383;691.778630838127;8404;8897.599608017656;9338.906610933804;9385.06149385056;9698.70903872885;542.4683809866353;563.3296377310987;680.2721088435426;712.6864179760888;721.1310371002929;4400.779961001937;4426;4519.344695019204;4551.089782043605;4661.324108004321;113554;114602.42698786453;118288;119827;123196.84015799174;102569;103205.48397257966;105595.4720226394;109735;110608.4469577647;91509.08490915138;92092;92536;93019;94347;8590.871136932827;8680.392372533863;8746.825063498822;8795.692150774948;8833.07252738479;49095.26357104718;49125;50107.49892501028;51917.221241682164;52089.47910520846;44098.11803763843;44382;44415.11169776651;48147;73094.63452682702;11204.607838725931;11428.371439570903;11446.885531144582;11608.24546404481;11859.644210673694;13012.8048079277;13076.411561479566;13342.733145337234;13991.720165596835;14033.228172450614;12248.203866748632;12497.500099995897;12574;13078.673033174247;13620.046596738133;11912.76174476523;12105.75788484243;12267.141300108906;12805.87194128084;12899.161554498927;11761;11767.764644706887;11807;11893.167478276437;12334.03983044081;9333.160015598629;9391.81216375655;9560.808783824423;9602.423854568748;9731.951340243253;9946.254030947852;10022.649207278002;10194.796104078025;10234.948825255826;10273.640422585173 10:08:03 INFO - PROCESS | 3387 | __end_tp_report 10:08:03 INFO - PROCESS | 3387 | __start_cc_report 10:08:03 INFO - PROCESS | 3387 | _x_x_mozilla_cycle_collect,1070 10:08:03 INFO - PROCESS | 3387 | __end_cc_report 10:08:03 INFO - PROCESS | 3387 | __startTimestamp1471885683180__endTimestamp 10:08:03 INFO - PROCESS | 3387 | 10:08:03 INFO - PROCESS | 3387 | ------- Summary: start ------- 10:08:03 INFO - PROCESS | 3387 | Number of tests: 6 10:08:03 INFO - PROCESS | 3387 | 10:08:03 INFO - PROCESS | 3387 | [#0] dojo.html Cycles:165 Average:5339.14 Median:5557.86 stddev:610.12 (11.0%) stddev-sans-first:583.92 10:08:03 INFO - PROCESS | 3387 | Values: 3007.5 3008.9 3014.1 3017.9 3028.0 5184.5 5186.8 5260.3 5262.9 5319.3 5351.3 5360.7 5366.8 5382.3 5438.7 4853.7 4885.1 4954.0 4997.0 5035.1 4970.9 4985.2 5008.4 5024.9 5028.0 4758.0 4800.3 4814.1 4846.1 4941.9 4865.7 4884.0 4887.5 4930.2 4944.0 4874.6 4906.6 4908.0 4917.6 4918.3 5191.7 5206.3 5318.2 5329.6 5371.9 5726.3 5750.3 5756.9 5764.3 5766.7 5534.5 5785.4 5792.6 5800.9 5810.2 5288.4 5304.7 5306.3 5373.8 5387.8 5890.2 5890.8 5892.6 5893.0 5907.4 5447.9 5664.4 5668.5 5679.5 5694.9 5567.1 5577.3 5578.4 5671.5 5696.3 5654.3 5684.7 5685.2 5686.0 5686.0 5486.8 5643.9 5694.6 5705.7 5708.6 4366.9 4404.3 4408.2 4453.4 4480.0 3958.2 3963.4 3965.5 3966.7 3973.3 5271.6 5450.0 5480.5 5485.9 5493.2 5683.7 5732.9 5746.5 5813.0 5817.7 5501.6 5544.3 5544.8 5553.1 5554.9 4818.9 5037.4 5048.1 5054.2 5054.3 5894.2 5933.6 5944.5 6048.5 6055.9 5759.6 5774.9 5791.3 5793.7 5824.9 5779.8 5982.5 6029.1 6039.0 6044.0 5602.6 5619.5 5623.9 5715.0 5719.1 5790.8 5796.8 5797.7 5799.1 5800.1 5444.0 5644.4 5652.0 5655.3 5656.0 5508.7 5540.3 5557.9 5644.2 5648.2 5578.4 5583.5 5590.6 5595.8 5598.6 5638.4 5702.3 5706.2 5711.1 5730.1 5507.2 5575.8 5612.3 5616.6 5622.9 10:08:03 INFO - PROCESS | 3387 | 10:08:03 INFO - PROCESS | 3387 | [#1] ext.html Cycles:180 Average:62626.43 Median:375068.00 stddev:125431.96 (33.4%) stddev-sans-first:125697.27 10:08:03 INFO - PROCESS | 3387 | Values: 563.3 565.5 571.2 573.9 600.2 42535.4 43511.0 44338.3 44402.1 44408.6 45708.3 46340.0 46379.8 46452.8 46518.0 8164.5 9013.8 9194.5 10758.9 11061.2 11015.8 11125.9 11128.4 11160.3 11163.4 11186.0 11222.9 11245.9 11252.1 11252.1 11220.3 11280.9 11282.3 11283.7 11287.1 11048.3 11122.7 11128.9 11129.5 11135.7 42953.0 43544.6 43590.8 43595.0 43625.1 3330.4 3427.8 3499.6 3600.5 3816.8 32427.2 32690.7 32765.7 32884.0 33617.7 4169.4 4276.9 4375.9 4384.1 4504.1 373341.0 373348.0 374627.0 374955.0 375181.0 60629.0 61097.7 61106.7 61123.4 61368.7 56782.0 56834.0 56856.0 56976.0 57068.0 16757.9 17490.1 17511.6 17526.6 17547.7 11798.5 11937.8 11945.9 11954.9 11969.9 18422.2 19104.4 19113.0 19149.4 19311.8 6976.7 7138.1 7241.7 7322.0 7488.2 949.3 992.8 1058.6 1075.9 1134.0 32896.5 32948.5 33391.6 33435.7 33715.3 1093.5 1105.4 1118.7 1125.5 1204.8 16501.9 17231.9 17403.9 17596.4 17658.3 595096.0 599185.0 599781.0 601255.0 601953.0 60103.7 60692.5 60731.4 60753.0 60759.0 1132.9 1330.9 1332.7 1372.2 1392.1 31686.8 33723.7 34176.0 34494.8 34615.4 50381.0 50916.7 50981.5 50998.5 51221.5 409877.0 411740.0 412526.0 413380.0 415100.0 25092.2 26134.0 26142.7 26771.6 28720.6 48593.5 48882.8 48969.8 49032.3 49323.8 40023.0 40778.4 41111.4 41467.4 42265.0 41184.8 41862.4 42003.2 42994.0 43099.4 41462.6 41532.6 42644.0 42950.4 43031.4 33936.5 36079.0 37206.1 37571.4 37673.0 27885.6 28192.1 28445.7 28769.7 29182.7 10:08:03 INFO - PROCESS | 3387 | 10:08:03 INFO - PROCESS | 3387 | [#2] jquery.html Cycles:180 Average:29148.04 Median:23836.45 stddev:81981.67 (343.9%) stddev-sans-first:82183.21 10:08:03 INFO - PROCESS | 3387 | Values: 391.4 399.3 402.9 409.8 417.1 12337.6 18274.0 19952.2 19975.5 19989.1 12821.3 18466.2 18953.4 19050.5 19299.3 5564.4 5746.6 5751.1 5754.4 5780.6 5457.5 5647.7 5696.9 5707.5 5723.6 5622.8 5650.9 5652.1 5653.6 5666.1 5445.4 5578.6 5649.6 5652.5 5653.9 5034.4 5048.2 5052.0 5058.6 5059.4 24296.9 24327.6 24409.9 24470.9 24778.5 2102.0 2295.9 2311.8 2333.8 2339.6 10412.9 10611.6 10651.8 10896.8 11154.4 1916.8 1921.8 1931.2 2000.9 2051.4 84870.6 86133.6 87661.0 87970.0 90322.0 9621.8 9744.8 9806.3 10250.7 10421.8 13395.5 13469.6 13559.9 13601.9 13639.5 7526.5 7741.6 7814.4 8041.5 8043.1 7733.6 7765.6 7810.2 7990.6 7998.8 4135.1 4316.3 4537.7 4725.5 4806.1 1951.5 2016.8 2027.5 2034.6 2036.3 898.5 919.0 968.6 1023.9 1056.1 19170.4 19423.5 19780.5 19836.6 19904.5 958.7 976.3 986.3 989.1 1041.7 10329.2 10794.2 10815.7 11086.2 11098.4 476267.0 489721.0 497597.0 507567.0 511891.0 58452.7 69273.7 96775.0 97357.0 102667.0 887.9 890.2 917.3 918.9 998.8 16737.7 16847.9 17447.8 17657.4 18048.6 43356.6 44038.3 44610.6 45053.0 45145.0 59102.4 59169.4 60364.1 60569.4 62956.0 7706.5 8417.8 8428.3 8533.4 9239.9 11671.7 13416.9 13884.9 14152.4 14504.7 11448.3 11705.9 11845.0 12463.1 12656.6 5394.0 8770.7 11646.3 11688.9 11748.3 11159.4 11694.6 12275.7 12411.5 12600.4 7957.6 8031.0 8037.2 8199.7 8307.6 10675.3 11903.5 12077.6 12135.8 12327.8 10:08:03 INFO - PROCESS | 3387 | 10:08:03 INFO - PROCESS | 3387 | [#3] mootools.html Cycles:180 Average:5802.27 Median:4824.58 stddev:3403.38 (70.5%) stddev-sans-first:3395.69 10:08:03 INFO - PROCESS | 3387 | Values: 1243.3 1412.0 1424.6 1548.0 1765.4 8493.2 8828.0 8832.4 8841.2 8938.2 8437.0 8539.8 8545.5 8552.8 8555.3 2086.0 2347.4 2360.0 2382.4 2383.5 2111.7 2129.9 2131.0 2384.8 2460.3 2380.7 2381.2 2383.8 2385.1 2399.6 2251.8 2272.4 2285.6 2308.4 2312.8 2259.0 2328.4 2330.0 2356.3 2372.0 9378.4 9433.0 9457.8 9468.7 9474.6 4994.7 5120.8 5122.4 5123.7 5137.0 3239.1 3326.3 3384.8 3408.6 3409.5 1150.5 1170.3 1178.8 1203.3 1208.1 12404.9 12561.1 12566.4 12567.5 12584.7 4349.7 4534.7 4655.9 4701.8 4754.1 9681.9 9696.1 9711.7 9719.6 9721.4 5242.0 5242.2 5244.9 5244.9 5245.7 4813.4 4838.3 4838.6 4847.8 4934.1 3431.7 3718.7 3727.9 3829.7 3893.7 1129.8 1133.1 1140.9 1141.9 1158.5 793.5 877.7 886.2 905.5 905.6 5446.0 5486.3 5621.0 5684.8 5703.2 944.4 959.5 986.3 995.0 1006.4 3610.9 3848.6 3998.8 4027.8 4089.1 11216.7 11660.7 11677.8 11685.8 11687.8 11063.8 11235.8 11444.8 11476.7 11496.7 7900.5 7976.5 8007.3 8308.3 8524.1 5594.1 5648.2 5667.6 5702.0 5833.9 10078.7 10450.1 10469.2 10492.4 10509.1 10601.4 10627.7 10819.7 10889.7 10922.1 7781.9 7859.4 7888.0 7891.3 7960.1 7642.8 7937.8 7941.2 8144.3 8478.7 7269.4 7809.9 7882.5 8005.4 8059.6 7526.0 7911.7 7912.7 7961.2 8164.0 6964.2 7082.8 7085.2 7091.1 7095.8 4744.7 4776.0 4798.9 4880.7 4893.3 4696.7 4835.7 4850.9 4943.1 5054.2 10:08:03 INFO - PROCESS | 3387 | 10:08:03 INFO - PROCESS | 3387 | [#4] prototype.html Cycles:180 Average:4446.05 Median:4935.99 stddev:1279.75 (25.9%) stddev-sans-first:1245.23 10:08:03 INFO - PROCESS | 3387 | Values: 316.4 317.9 324.3 325.3 342.2 5089.2 5227.0 5265.5 5334.5 5335.3 5226.8 5227.2 5242.3 5243.8 5244.3 4109.8 4117.7 4128.4 4129.3 4153.1 4018.8 4111.2 4135.0 4135.1 4139.5 4076.6 4100.7 4110.8 4111.9 4121.6 4073.6 4089.8 4110.7 4112.6 4113.9 3625.9 3650.6 3655.5 3665.8 3684.3 4873.1 5041.2 5063.5 5112.7 5219.4 4585.7 4593.1 4628.9 4629.7 4645.7 5379.9 5398.4 5433.4 5460.7 5480.2 3769.6 3791.9 3810.2 3812.9 3831.4 5322.0 5378.1 5593.3 5614.2 5652.1 3941.9 3971.2 3972.1 3977.4 3982.8 3841.1 3960.2 4006.1 4023.7 4048.5 4606.0 4610.5 4616.9 4650.0 4676.2 5418.2 5428.8 5450.5 5477.1 5484.6 1379.3 1381.0 1395.7 1401.2 1420.3 895.4 913.5 922.9 924.4 931.5 4591.7 4658.4 4663.8 4700.2 4719.7 4848.9 4852.5 4857.4 4915.2 4934.2 4608.0 4681.4 4762.8 4784.8 4788.7 2641.7 2648.9 2669.7 2673.5 2677.7 5116.2 5116.5 5131.4 5154.8 5170.5 4771.5 4951.9 4996.9 5003.8 5032.2 5038.9 5054.8 5063.5 5068.9 5128.5 4843.6 4864.9 4908.0 4937.8 4971.6 4887.4 5164.0 5292.6 5470.6 5492.8 5724.5 5725.2 5764.6 5773.4 5774.9 3935.6 3945.7 3949.8 3955.5 3956.8 5322.3 5487.7 5536.3 5552.2 5553.1 5418.6 5420.7 5425.3 5432.8 5435.3 5257.8 5363.5 5396.2 5429.3 5435.4 5315.7 5330.1 5358.9 5360.6 5376.8 5443.0 5457.9 5459.3 5495.8 5519.5 5086.0 5343.5 5347.9 5349.7 5385.2 10:08:03 INFO - PROCESS | 3387 | 10:08:03 INFO - PROCESS | 3387 | [#5] yui.html Cycles:180 Average:20427.11 Median:4551.39 stddev:30679.66 (674.1%) stddev-sans-first:30730.16 10:08:03 INFO - PROCESS | 3387 | Values: 752.6 757.2 778.4 797.2 857.2 14957.9 16888.8 16906.4 16916.6 16919.7 15009.0 15134.1 15176.5 15190.3 15196.2 555.7 792.9 803.1 806.6 825.5 805.9 807.1 808.8 810.4 813.0 866.5 867.6 870.2 871.0 874.8 532.9 812.4 844.4 844.6 847.2 619.3 622.7 622.9 623.1 623.1 14191.1 14207.0 14253.0 14264.1 14367.0 12616.1 12697.0 12737.2 13004.7 13610.7 11915.6 12053.7 12187.9 12191.9 12309.6 4302.9 4351.5 4368.3 4372.5 4463.6 79851.0 79999.2 81788.0 84505.0 84568.0 10312.5 11181.3 11207.9 11296.3 11644.2 21512.7 21513.1 21533.9 21555.7 21572.7 8051.3 8172.6 8334.7 8517.2 8568.6 4551.7 4657.6 4697.1 4698.9 4721.0 3188.2 3280.9 3298.4 3323.4 3324.6 611.0 612.3 619.0 624.7 636.6 617.7 654.0 665.7 669.7 691.8 8404.0 8897.6 9338.9 9385.1 9698.7 542.5 563.3 680.3 712.7 721.1 4400.8 4426.0 4519.3 4551.1 4661.3 113554.0 114602.4 118288.0 119827.0 123196.8 102569.0 103205.5 105595.5 109735.0 110608.4 91509.1 92092.0 92536.0 93019.0 94347.0 8590.9 8680.4 8746.8 8795.7 8833.1 49095.3 49125.0 50107.5 51917.2 52089.5 44098.1 44382.0 44415.1 48147.0 73094.6 11204.6 11428.4 11446.9 11608.2 11859.6 13012.8 13076.4 13342.7 13991.7 14033.2 12248.2 12497.5 12574.0 13078.7 13620.0 11912.8 12105.8 12267.1 12805.9 12899.2 11761.0 11767.8 11807.0 11893.2 12334.0 9333.2 9391.8 9560.8 9602.4 9732.0 9946.3 10022.6 10194.8 10234.9 10273.6 10:08:03 INFO - PROCESS | 3387 | -------- Summary: end -------- 10:08:03 INFO - PROCESS | 3387 | 10:08:04 INFO - TEST-INFO | 3387: exit 0 10:08:04 INFO - TEST-OK | dromaeo_css | took 1117157ms 10:08:04 INFO - TEST-START | kraken 10:08:04 INFO - operating with platform_type : linux_ 10:08:04 INFO - Initialising browser for kraken test... 10:08:04 INFO - TEST-INFO | started process 3696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpn1j95U/profile http://localhost:48642/getInfo.html) 10:08:08 INFO - PROCESS | 3696 | __metrics Screen width/height:1600/1200 10:08:08 INFO - PROCESS | 3696 | colorDepth:24 10:08:08 INFO - PROCESS | 3696 | Browser inner width/height: 1024/697 10:08:08 INFO - PROCESS | 3696 | __metrics 10:08:08 INFO - PROCESS | 3696 | 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" 10:08:08 INFO - PROCESS | 3696 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:08:09 INFO - PROCESS | 3696 | console.error: PushService: 10:08:09 INFO - PROCESS | 3696 | stateChangeProcessEnqueue: Error transitioning state 10:08:09 INFO - PROCESS | 3696 | UnknownError 10:08:10 INFO - TEST-INFO | 3696: exit 0 10:08:10 INFO - Browser initialized. 10:08:10 INFO - Running cycle 1/1 for kraken test... 10:08:10 INFO - TEST-INFO | started process 3775 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpn1j95U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:08:22 INFO - PROCESS | 3775 | RSS: Main: 174448640 10:08:22 INFO - PROCESS | 3775 | 10:08:24 INFO - PROCESS | 3775 | Cycle 1(1): loaded http://localhost:48642/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48642/tests/kraken/driver.html?testName=audio-beat-detection) 10:08:25 INFO - PROCESS | 3775 | RSS: Main: 243970048 10:08:25 INFO - PROCESS | 3775 | 10:08:28 INFO - PROCESS | 3775 | Cycle 1(1): loaded http://localhost:48642/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48642/tests/kraken/driver.html?testName=audio-dft) 10:08:28 INFO - PROCESS | 3775 | RSS: Main: 226500608 10:08:28 INFO - PROCESS | 3775 | 10:08:32 INFO - PROCESS | 3775 | Cycle 1(1): loaded http://localhost:48642/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48642/tests/kraken/driver.html?testName=audio-fft) 10:08:32 INFO - PROCESS | 3775 | RSS: Main: 302747648 10:08:32 INFO - PROCESS | 3775 | 10:08:34 INFO - PROCESS | 3775 | Cycle 1(1): loaded http://localhost:48642/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48642/tests/kraken/driver.html?testName=audio-oscillator) 10:08:35 INFO - PROCESS | 3775 | RSS: Main: 209686528 10:08:35 INFO - PROCESS | 3775 | 10:08:37 INFO - PROCESS | 3775 | Cycle 1(1): loaded http://localhost:48642/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48642/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:08:38 INFO - PROCESS | 3775 | RSS: Main: 213835776 10:08:38 INFO - PROCESS | 3775 | 10:08:41 INFO - PROCESS | 3775 | Cycle 1(1): loaded http://localhost:48642/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48642/tests/kraken/driver.html?testName=imaging-darkroom) 10:08:41 INFO - PROCESS | 3775 | RSS: Main: 250368000 10:08:41 INFO - PROCESS | 3775 | 10:08:45 INFO - PROCESS | 3775 | Cycle 1(1): loaded http://localhost:48642/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48642/tests/kraken/driver.html?testName=imaging-desaturate) 10:08:45 INFO - PROCESS | 3775 | RSS: Main: 248877056 10:08:45 INFO - PROCESS | 3775 | 10:08:48 INFO - PROCESS | 3775 | Cycle 1(1): loaded http://localhost:48642/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48642/tests/kraken/driver.html?testName=json-parse-financial) 10:08:49 INFO - PROCESS | 3775 | RSS: Main: 248631296 10:08:49 INFO - PROCESS | 3775 | 10:08:50 INFO - PROCESS | 3775 | Cycle 1(1): loaded http://localhost:48642/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48642/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:08:51 INFO - PROCESS | 3775 | RSS: Main: 211542016 10:08:51 INFO - PROCESS | 3775 | 10:08:53 INFO - PROCESS | 3775 | Cycle 1(1): loaded http://localhost:48642/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48642/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:08:53 INFO - PROCESS | 3775 | RSS: Main: 218275840 10:08:53 INFO - PROCESS | 3775 | 10:08:55 INFO - PROCESS | 3775 | Cycle 1(1): loaded http://localhost:48642/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48642/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:08:56 INFO - PROCESS | 3775 | RSS: Main: 223625216 10:08:56 INFO - PROCESS | 3775 | 10:08:59 INFO - PROCESS | 3775 | Cycle 1(1): loaded http://localhost:48642/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48642/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:08:59 INFO - PROCESS | 3775 | RSS: Main: 218222592 10:08:59 INFO - PROCESS | 3775 | 10:09:02 INFO - PROCESS | 3775 | Cycle 1(1): loaded http://localhost:48642/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48642/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:09:03 INFO - PROCESS | 3775 | RSS: Main: 221470720 10:09:03 INFO - PROCESS | 3775 | 10:09:04 INFO - PROCESS | 3775 | Cycle 1(1): loaded http://localhost:48642/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48642/tests/kraken/driver.html?testName=ai-astar) 10:09:05 INFO - PROCESS | 3775 | RSS: Main: 272461824 10:09:05 INFO - PROCESS | 3775 | 10:09:05 INFO - PROCESS | 3775 | __start_tp_report 10:09:05 INFO - PROCESS | 3775 | _x_x_mozilla_page_load 10:09:05 INFO - PROCESS | 3775 | _x_x_mozilla_page_load_details 10:09:05 INFO - PROCESS | 3775 | |i|pagename|runs| 10:09:05 INFO - PROCESS | 3775 | |0;ai-astar;102;115;116;112;113;111;117;113;112;112 10:09:05 INFO - PROCESS | 3775 | |1;audio-beat-detection;146;117;140;187;119;143;143;122;177;117 10:09:05 INFO - PROCESS | 3775 | |2;audio-dft;161;162;160;163;162;162;161;164;162;161 10:09:05 INFO - PROCESS | 3775 | |3;audio-fft;74;75;75;167;74;77;74;76;164;76 10:09:05 INFO - PROCESS | 3775 | |4;audio-oscillator;86;86;166;87;85;85;149;84;87;143 10:09:05 INFO - PROCESS | 3775 | |5;imaging-gaussian-blur;117;117;116;116;116;117;116;116;117;116 10:09:05 INFO - PROCESS | 3775 | |6;imaging-darkroom;147;146;147;147;147;146;147;147;147;147 10:09:05 INFO - PROCESS | 3775 | |7;imaging-desaturate;96;96;96;96;96;96;96;96;96;95 10:09:05 INFO - PROCESS | 3775 | |8;json-parse-financial;50;50;53;51;51;52;51;51;51;51 10:09:05 INFO - PROCESS | 3775 | |9;json-stringify-tinderbox;64;62;63;63;63;63;62;63;62;63 10:09:05 INFO - PROCESS | 3775 | |10;stanford-crypto-aes;81;78;86;81;86;82;78;79;79;84 10:09:05 INFO - PROCESS | 3775 | |11;stanford-crypto-ccm;126;123;127;125;122;123;121;122;139;129 10:09:05 INFO - PROCESS | 3775 | |12;stanford-crypto-pbkdf2;165;163;169;168;161;165;163;166;163;162 10:09:05 INFO - PROCESS | 3775 | |13;stanford-crypto-sha256-iterative;67;64;67;64;68;68;64;66;64;63 10:09:05 INFO - PROCESS | 3775 | __end_tp_report 10:09:05 INFO - PROCESS | 3775 | __start_cc_report 10:09:05 INFO - PROCESS | 3775 | _x_x_mozilla_cycle_collect,1728 10:09:05 INFO - PROCESS | 3775 | __end_cc_report 10:09:05 INFO - PROCESS | 3775 | __startTimestamp1471885745182__endTimestamp 10:09:05 INFO - PROCESS | 3775 | 10:09:05 INFO - PROCESS | 3775 | ------- Summary: start ------- 10:09:05 INFO - PROCESS | 3775 | Number of tests: 14 10:09:05 INFO - PROCESS | 3775 | 10:09:05 INFO - PROCESS | 3775 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.00 stddev:4.11 (3.6%) stddev-sans-first:2.07 10:09:05 INFO - PROCESS | 3775 | Values: 102.0 115.0 116.0 112.0 113.0 111.0 117.0 113.0 112.0 112.0 10:09:05 INFO - PROCESS | 3775 | 10:09:05 INFO - PROCESS | 3775 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:143.00 stddev:24.59 (17.2%) stddev-sans-first:26.02 10:09:05 INFO - PROCESS | 3775 | Values: 146.0 117.0 140.0 187.0 119.0 143.0 143.0 122.0 177.0 117.0 10:09:05 INFO - PROCESS | 3775 | 10:09:05 INFO - PROCESS | 3775 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.14 (0.7%) stddev-sans-first:1.17 10:09:05 INFO - PROCESS | 3775 | Values: 161.0 162.0 160.0 163.0 162.0 162.0 161.0 164.0 162.0 161.0 10:09:05 INFO - PROCESS | 3775 | 10:09:05 INFO - PROCESS | 3775 | [#3] audio-fft Cycles:10 Average:93.20 Median:75.00 stddev:38.12 (50.8%) stddev-sans-first:39.80 10:09:05 INFO - PROCESS | 3775 | Values: 74.0 75.0 75.0 167.0 74.0 77.0 74.0 76.0 164.0 76.0 10:09:05 INFO - PROCESS | 3775 | 10:09:05 INFO - PROCESS | 3775 | [#4] audio-oscillator Cycles:10 Average:105.80 Median:85.50 stddev:32.84 (38.4%) stddev-sans-first:34.04 10:09:05 INFO - PROCESS | 3775 | Values: 86.0 86.0 166.0 87.0 85.0 85.0 149.0 84.0 87.0 143.0 10:09:05 INFO - PROCESS | 3775 | 10:09:05 INFO - PROCESS | 3775 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 10:09:05 INFO - PROCESS | 3775 | Values: 117.0 117.0 116.0 116.0 116.0 117.0 116.0 116.0 117.0 116.0 10:09:05 INFO - PROCESS | 3775 | 10:09:05 INFO - PROCESS | 3775 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 10:09:05 INFO - PROCESS | 3775 | Values: 147.0 146.0 147.0 147.0 147.0 146.0 147.0 147.0 147.0 147.0 10:09:05 INFO - PROCESS | 3775 | 10:09:05 INFO - PROCESS | 3775 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 10:09:05 INFO - PROCESS | 3775 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 10:09:05 INFO - PROCESS | 3775 | 10:09:05 INFO - PROCESS | 3775 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.00 stddev:0.88 (1.7%) stddev-sans-first:0.83 10:09:05 INFO - PROCESS | 3775 | Values: 50.0 50.0 53.0 51.0 51.0 52.0 51.0 51.0 51.0 51.0 10:09:05 INFO - PROCESS | 3775 | 10:09:05 INFO - PROCESS | 3775 | [#9] json-stringify-tinderbox Cycles:10 Average:62.80 Median:63.00 stddev:0.63 (1.0%) stddev-sans-first:0.50 10:09:05 INFO - PROCESS | 3775 | Values: 64.0 62.0 63.0 63.0 63.0 63.0 62.0 63.0 62.0 63.0 10:09:05 INFO - PROCESS | 3775 | 10:09:05 INFO - PROCESS | 3775 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.50 stddev:3.06 (3.8%) stddev-sans-first:3.24 10:09:05 INFO - PROCESS | 3775 | Values: 81.0 78.0 86.0 81.0 86.0 82.0 78.0 79.0 79.0 84.0 10:09:05 INFO - PROCESS | 3775 | 10:09:05 INFO - PROCESS | 3775 | [#11] stanford-crypto-ccm Cycles:10 Average:125.70 Median:125.50 stddev:5.31 (4.2%) stddev-sans-first:5.63 10:09:05 INFO - PROCESS | 3775 | Values: 126.0 123.0 127.0 125.0 122.0 123.0 121.0 122.0 139.0 129.0 10:09:05 INFO - PROCESS | 3775 | 10:09:05 INFO - PROCESS | 3775 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.50 Median:165.00 stddev:2.59 (1.6%) stddev-sans-first:2.74 10:09:05 INFO - PROCESS | 3775 | Values: 165.0 163.0 169.0 168.0 161.0 165.0 163.0 166.0 163.0 162.0 10:09:05 INFO - PROCESS | 3775 | 10:09:05 INFO - PROCESS | 3775 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.50 stddev:1.90 (2.9%) stddev-sans-first:1.94 10:09:05 INFO - PROCESS | 3775 | Values: 67.0 64.0 67.0 64.0 68.0 68.0 64.0 66.0 64.0 63.0 10:09:05 INFO - PROCESS | 3775 | -------- Summary: end -------- 10:09:05 INFO - PROCESS | 3775 | 10:09:06 INFO - TEST-INFO | 3775: exit 0 10:09:06 INFO - TEST-OK | kraken | took 62204ms 10:09:06 INFO - SUITE-END | took 1179s 10:09:06 INFO - Completed test suite (00:19:39) 10:09:06 INFO - javascript benchmark 10:09:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3007.473692103881, 3008.939821203576, 3014.065639651707, 3017.939641207176, 3027.9545806812903, 5184.53339199472, 5186.792528298873, 5260.316158899343, 5262.894742105161, 5319.335083114611, 5351.304330437048, 5360.65155764875, 5366.838994830152, 5382.300300960869, 5438.7280635968245, 4853.708777473345, 4885.0718363511, 4954.033963377143, 4996.9500304996845, 5035.1188542005, 4970.875728106808, 4985.152524070917, 5008.4240312364, 5024.874378140539, 5028.0, 4758.024604955993, 4800.279958006292, 4814.109389762905, 4846.103470857867, 4941.925871111936, 4865.732384718839, 4884.047610715912, 4887.462379138292, 4930.235813448921, 4944.0, 4874.610031197496, 4906.582940450066, 4907.975460122712, 4917.631177661654, 4918.311436398907, 5191.714455704935, 5206.271122042917, 5318.175682769177, 5329.600279979017, 5371.865703357447, 5726.3414707308775, 5750.281214848144, 5756.942430575683, 5764.337101233328, 5766.682832444214, 5534.529564986981, 5785.4214578541805, 5792.623479473821, 5800.854978625526, 5810.157527158581, 5288.444713305086, 5304.734763261821, 5306.310179676618, 5373.838784836461, 5387.757550910218, 5890.204822349014, 5890.793822216202, 5892.616979896293, 5892.970535147297, 5907.350191478934, 5447.918281225785, 5664.433556644302, 5668.546516278688, 5679.5172410345585, 5694.9430505695245, 5567.053600887859, 5577.27495425592, 5578.41426650204, 5671.546276297886, 5696.287964004499, 5654.264945557134, 5684.744186511617, 5685.204071430003, 5685.971570142123, 5686.0333743263745, 5486.807961721321, 5643.887122257532, 5694.60137790359, 5705.686187259657, 5708.600397972102, 4366.868993930188, 4404.273294906368, 4408.162449134654, 4453.421055262795, 4479.955200448019, 3958.2083583283456, 3963.365861462152, 3965.4844870166694, 3966.682665386762, 3973.2848087344555, 5271.578273738091, 5449.972750136303, 5480.451954804567, 5485.945140548623, 5493.2034854945805, 5683.658980461186, 5732.914006289909, 5746.540276777848, 5813.0, 5817.7091145442555, 5501.559875209973, 5544.306961629796, 5544.77820887168, 5553.083741182741, 5554.944450555443, 4818.855434336975, 5037.370328708911, 5048.091343558195, 5054.21659642756, 5054.342935418372, 5894.17481552583, 5933.554983376264, 5944.494717948936, 6048.546359023003, 6055.9091613626715, 5759.596828222152, 5774.855628609318, 5791.276090488689, 5793.681347525927, 5824.854378640568, 5779.768809247583, 5982.491488223375, 6029.065494848305, 6039.0, 6044.0, 5602.607817452902, 5619.494245517923, 5623.943760562342, 5715.028445164416, 5719.056355701263, 5790.826275211751, 5796.797112101056, 5797.681127538026, 5799.072148456226, 5800.100984347433, 5444.047291723853, 5644.407337229532, 5652.039153343859, 5655.321361436654, 5655.971720141373, 5508.724563771875, 5540.279763630702, 5557.861053473695, 5644.181593668813, 5648.2092507049865, 5578.442155784552, 5583.497485226349, 5590.552755779447, 5595.776168953196, 5598.580106491948, 5638.436156384329, 5702.287214098238, 5706.229658996149, 5711.086226203787, 5730.08318669011, 5507.173923911285, 5575.832725018256, 5612.298462692163, 5616.578756593353, 5622.915656265078], "value": 5297.087562747597, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.3493315220921, 565.501562944018, 571.1632458448378, 573.8852229554088, 600.1867469578721, 42535.36196957044, 43511.0, 44338.33492497611, 44402.111957760826, 44408.55591444085, 45708.3143752844, 46340.0, 46379.76810115945, 46452.76773616135, 46518.022296655516, 8164.510129392226, 9013.774655633613, 9194.540272986358, 10758.892411075867, 11061.225714200009, 11015.83476247853, 11125.944370278137, 11128.443577821117, 11160.274582152175, 11163.38601376928, 11186.0, 11222.887771122307, 11245.943770281137, 11252.099832013419, 11252.099832013419, 11220.326780393161, 11280.887191128066, 11282.266652667551, 11283.717907052307, 11287.097032237483, 11048.281861678965, 11122.721931951684, 11128.944355278252, 11129.499172537255, 11135.71939226983, 42953.0, 43544.564554354365, 43590.78204608988, 43595.0, 43625.127497449874, 3330.350581636582, 3427.845746941394, 3499.5800503939436, 3600.495930569696, 3816.809159542012, 32427.18932026718, 32690.673093269, 32765.6723432765, 32884.0, 33617.6638233617, 4169.353750168729, 4276.935845962313, 4375.868723938287, 4384.101259241879, 4504.054148628792, 373341.0, 373348.0, 374627.0, 374955.0, 375181.0, 60629.0, 61097.69451152805, 61106.69446652827, 61123.38876611177, 61368.69315653393, 56782.0, 56834.0, 56856.0, 56976.0, 57068.0, 16757.91621041887, 17490.125493725263, 17511.562210944827, 17526.561835953948, 17547.73678394825, 11798.52805887755, 11937.820932686016, 11945.940270298766, 11954.880451195377, 11969.880301196876, 18422.211992060224, 19104.426867194008, 19113.0, 19149.425517234504, 19311.806881931, 6976.6860491277, 7138.109065828347, 7241.674124664402, 7322.048133742579, 7488.176300606929, 949.2643201518878, 992.7915137820994, 1058.5501162006117, 1075.8762742284737, 1133.960311389114, 32896.506552401734, 32948.50577241343, 33391.57714989196, 33435.66257349679, 33715.325693486, 1093.4860615510697, 1105.4307031878589, 1118.692359601116, 1125.499152876962, 1204.8493938257718, 16501.91749041247, 17231.913840430967, 17403.865859571455, 17596.38412655551, 17658.293668253125, 595096.0, 599185.0, 599781.0, 601255.0, 601953.0, 60103.69948150319, 60692.48268793315, 60731.39268607258, 60753.0, 60759.0, 1132.9490172942074, 1330.8868746156686, 1332.68015676237, 1372.1561239837627, 1392.0812263905773, 31686.841565792023, 33723.66276337205, 34176.0, 34494.82752586221, 34615.40373041159, 50381.0, 50916.74541627268, 50981.490185097675, 50998.49001509937, 51221.48778512167, 409877.0, 411740.0, 412526.0, 413380.0, 415100.0, 25092.247232583053, 26133.994590622322, 26142.73857261403, 26771.598426024015, 28720.569191462564, 48593.5140648589, 48882.75558622184, 48969.75515122402, 49032.264516033, 49323.75338123286, 40023.0, 40778.38832417576, 41111.38332924949, 41467.37798933079, 42265.0, 41184.79407602943, 41862.372064419666, 42003.159936801705, 42994.0, 43099.353509698005, 41462.58537414708, 41532.58467415287, 42644.0, 42950.35574466323, 43031.35452968271, 33936.490952635235, 36079.0, 37206.069848252926, 37571.43071408212, 37673.0, 27885.581716273868, 28192.053112564667, 28445.715542844307, 28769.712302876702, 29182.708172918], "value": 20884.261927583353, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.42851436902123, 399.2912580170199, 402.9133736246706, 409.844259181511, 417.14901600734504, 12337.568185113523, 18274.0, 19952.20191192354, 19975.500612484695, 19989.100490477926, 12821.294828784414, 18466.16902239399, 18953.43139705811, 19050.523736906584, 19299.324523641677, 5564.360098588653, 5746.569007324468, 5751.1085781703905, 5754.424557544233, 5780.624259423145, 5457.481539253765, 5647.717614119299, 5696.857578560528, 5707.486326230638, 5723.599348045638, 5622.775088996435, 5650.915236271459, 5652.123920792264, 5653.575981801361, 5666.093425051992, 5445.37378201504, 5578.63738856977, 5649.6327738697055, 5652.463016013472, 5653.858653533674, 5034.370703662043, 5048.1670524363435, 5052.040112378636, 5058.620603454756, 5059.418166910816, 24296.878515607485, 24327.635085473732, 24409.87795061031, 24470.877645611657, 24778.5044299113, 2101.989490052555, 2295.8622482651094, 2311.8034967027825, 2333.8249631277554, 2339.5554844579474, 10412.895871041268, 10611.628592999208, 10651.78696426075, 10896.836547451794, 11154.386508742016, 1916.7508223930938, 1921.750172477583, 1931.2468137426415, 2000.949976250591, 2051.446109550428, 84870.575647122, 86133.56933215294, 87661.0, 87970.0, 90322.0, 9621.759456013515, 9744.805103897883, 9806.31355805101, 10250.692479225636, 10421.791564168674, 13395.464181432828, 13469.595912122651, 13559.86440135586, 13601.86398136006, 13639.522616708566, 7526.548407095592, 7741.61291935401, 7814.413918955987, 8041.517508949481, 8043.11525732169, 7733.574653394117, 7765.611719414007, 7810.218978102144, 7990.6004699765945, 7998.84002319962, 4135.069609337875, 4316.330968699856, 4537.659675524283, 4725.480197178307, 4806.086843499724, 1951.4535929939639, 2016.84873634478, 2027.5438026443933, 2034.5523984723336, 2036.3381900882273, 898.4743924804063, 918.9908100918905, 968.5544649461327, 1023.9334443261164, 1056.1286938275953, 19170.424887253404, 19423.514412139808, 19780.50548736293, 19836.60326793456, 19904.50238744043, 958.7219706285239, 976.2677991506371, 986.2504496582649, 989.0900371658092, 1041.6614600254948, 10329.225308101923, 10794.190435717353, 10815.729606759736, 11086.22396432242, 11098.44507774608, 476267.0, 489721.0, 497597.0, 507567.0, 511891.0, 58452.707736461896, 69273.65363173252, 96775.0, 97357.0, 102667.0, 887.924526415262, 890.2299510923134, 917.312015988009, 918.912703293186, 998.7553049502773, 16737.665246694753, 16847.91576042161, 17447.82552174462, 17657.35139729051, 18048.63902721912, 43356.56643433651, 44038.339424909296, 44610.55389446064, 45053.0, 45145.0, 59102.40897590969, 59169.408305918114, 60364.09453858108, 60569.394306056376, 62956.0, 7706.460547761603, 8417.789555261168, 8428.283595894418, 8533.359998000298, 9239.861402079108, 11671.708207294885, 13416.932915335361, 13884.946730896258, 14152.363143658355, 14504.709905802036, 11448.255863368855, 11705.88294117048, 11845.0, 12463.06527010496, 12656.557020504239, 5393.99251055684, 8770.736877893672, 11646.301221926711, 11688.88311116912, 11748.295102293549, 11159.442027898735, 11694.649160525198, 12275.69310767238, 12411.503539858304, 12600.432980516081, 7957.641906114123, 8031.0, 8037.23646253605, 8199.71301004462, 8307.584620768817, 10675.306105103144, 11903.464344104363, 12077.57728479499, 12135.817962730389, 12327.753444930871], "value": 9073.401983760567, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1243.2726854789948, 1412.0468683638544, 1424.5868698077559, 1547.9922600386997, 1765.4438851761702, 8493.150684931505, 8828.0, 8832.425892316995, 8841.204291613754, 8938.195562399384, 8437.0, 8539.829203415944, 8545.529995850226, 8552.786180345494, 8555.31557475402, 2086.0, 2347.4248809041765, 2360.0, 2382.404398900275, 2383.4518060846017, 2111.6938043983614, 2129.9041543130597, 2131.030381176561, 2384.833061685683, 2460.3357093584723, 2380.7262164851004, 2381.166591692911, 2383.7854593086618, 2385.141349114326, 2399.6280576510667, 2251.8311126665485, 2272.3523795718143, 2285.6342985122383, 2308.4113551044543, 2312.8034117100065, 2258.9774102259094, 2328.4062564046058, 2329.97670023301, 2356.29311206639, 2372.039324073753, 9378.437293762396, 9432.95283523578, 9457.810843783154, 9468.668596599086, 9474.573644186028, 4994.700317980896, 5120.769565369567, 5122.436531981479, 5123.7438128093445, 5137.023965446553, 3239.093053944899, 3326.334733053399, 3384.8307584620916, 3408.625051244361, 3409.5226668266464, 1150.4822829726656, 1170.2978213072174, 1178.787818192725, 1203.3261373630708, 1208.09996552568, 12404.875951240463, 12561.120721549587, 12566.434510447052, 12567.497300107985, 12584.748305033849, 4349.673774466928, 4534.730275522859, 4655.883602909955, 4701.8354357598, 4754.07295577366, 9681.854772178423, 9696.0788725071, 9711.66009189689, 9719.56261968213, 9721.416714997124, 5241.9737901310245, 5242.239875218072, 5244.895102097937, 5244.947550524522, 5245.737713114406, 4813.422389313305, 4838.298446725205, 4838.612910967114, 4847.781849816767, 4934.087193869074, 3431.6911477966605, 3718.7396882218527, 3727.9068023299633, 3829.6553310202216, 3893.7274390792372, 1129.7629096139715, 1133.0538999934981, 1140.8745038045975, 1141.9143564232716, 1158.5481662151767, 793.5318162284279, 877.7147427086222, 886.1802832380023, 905.4929239625831, 905.5562774240707, 5446.039425806706, 5486.341639003265, 5621.0, 5684.8294551163535, 5703.230063941398, 944.3672739264572, 959.5250351076262, 986.2849434160177, 994.9602015919427, 1006.3559322033905, 3610.8736194233074, 3848.5766565677754, 3998.8400463981698, 4027.7583344998807, 4089.1208390196252, 11216.66350009501, 11660.650180494598, 11677.824832627517, 11685.824712629317, 11687.766244675058, 11063.778724425467, 11235.83146252807, 11444.771104578029, 11476.713082172846, 11496.712582185512, 7900.538400395945, 7976.481528700616, 8007.279344858991, 8308.252257296872, 8524.104968978292, 5594.13290939905, 5648.181013752901, 5667.631603945731, 5702.0, 5833.941660583339, 10078.748031299276, 10450.059494645215, 10469.162467002468, 10492.370457772558, 10509.106725928381, 10601.363918164934, 10627.681169564925, 10819.72950676208, 10889.727756806144, 10922.126229901747, 7781.883271751041, 7859.410544209321, 7888.013998250219, 7891.289783919475, 7960.084590272193, 7642.847143057219, 7937.801554961172, 7941.245581669732, 8144.30773384269, 8478.660853565789, 7269.418446524372, 7809.882851757342, 7882.5270483771155, 8005.3995950305125, 8059.597020148852, 7526.0, 7911.723089691834, 7912.723054693058, 7961.16407777175, 8164.020317561931, 6964.233934267125, 7082.822929426806, 7085.185203701641, 7091.11361079865, 7095.787126386217, 4744.667873248839, 4775.952240477551, 4798.928016079692, 4880.707157570557, 4893.266010098514, 4696.718196908197, 4835.734034627989, 4850.902981940412, 4943.085529177114, 5054.241863720471], "value": 4595.347820200757, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.4162120886963, 317.89689776475626, 324.34967889381784, 325.34118410219304, 342.17706416069365, 5089.236614507826, 5227.013734780783, 5265.4997775211295, 5334.519448456462, 5335.33308336458, 5226.843194704165, 5227.2159176123605, 5242.292290540776, 5243.764030618622, 5244.318238628983, 4109.773962432065, 4117.711760176789, 4128.442660240874, 4129.256733787909, 4153.0863210093885, 4018.7588744675268, 4111.198316328318, 4135.043443937584, 4135.069609337905, 4139.489086483427, 4076.5515793262716, 4100.712950093494, 4110.815013324408, 4111.938320925188, 4121.5672354402805, 4073.6333729964445, 4089.8159582818844, 4110.671146308288, 4112.588741125894, 4113.8971525711795, 3625.9456108158397, 3650.6166852480374, 3655.469956856241, 3665.771966391262, 3684.281565094808, 4873.098476781771, 5041.1682072458, 5063.468265867066, 5112.667676601009, 5219.42586315505, 4585.701929374613, 4593.104344652794, 4628.907421851544, 4629.65277604181, 4645.6980296280635, 5379.919301210485, 5398.352197736258, 5433.402325744204, 5460.672359658433, 5480.150576660624, 3769.6230376962358, 3791.938257287964, 3810.237952409529, 3812.942805857914, 3831.4444405561317, 5321.973390133063, 5378.139497680391, 5593.300837395325, 5614.1859430382365, 5652.123920792284, 3941.8620348287673, 3971.185906889081, 3972.0864201233876, 3977.3636218204947, 3982.8008599569907, 3841.1357444574746, 3960.20795840833, 4006.1386801837743, 4023.7183397161916, 4048.5101662477364, 4606.0, 4610.538946105363, 4616.8614941551805, 4649.953500464952, 4676.158291507561, 5418.241446197536, 5428.809991650273, 5450.454954504517, 5477.123660214346, 5484.561235101182, 1379.2689874366602, 1380.9930950345183, 1395.7278330725615, 1401.1733077484332, 1420.31824724133, 895.3642913531336, 913.5477938420524, 922.8938672052666, 924.3852837862746, 931.4737173496952, 4591.701539399928, 4658.3711198988385, 4663.836765713184, 4700.247960326331, 4719.7404142772475, 4848.903021939612, 4852.514748525119, 4857.441394239637, 4915.238138088602, 4934.1611925972675, 4608.041582327497, 4681.4018219888285, 4762.78567464458, 4784.779881130359, 4788.736619485892, 2641.6697912760906, 2648.854313012803, 2669.666291713536, 2673.46530693862, 2677.710557473371, 5116.232565115188, 5116.462771408948, 5131.409887862869, 5154.768035438415, 5170.543991603958, 4771.5467030632035, 4951.851444456672, 4996.875078123076, 5003.774830132653, 5032.220005899091, 5038.874028149326, 5054.797808087709, 5063.468335824686, 5068.896407990643, 5128.512791284822, 4843.612510999182, 4864.854054378375, 4908.0, 4937.814924418042, 4971.627127965345, 4887.409672873383, 5164.0, 5292.603054770831, 5470.617056805985, 5492.80775172883, 5724.513416359656, 5725.19847221381, 5764.567657425794, 5773.393793651606, 5774.94225057744, 3935.606439356156, 3945.6843452523153, 3949.842006319715, 3955.545112312121, 3956.8021598920513, 5322.308099946982, 5487.66774120989, 5536.252605898152, 5552.167174923632, 5553.139263414178, 5418.620696551204, 5420.674759514442, 5425.321834770653, 5432.7555260012605, 5435.320584926884, 5257.8159764408065, 5363.490468405495, 5396.163594642837, 5429.348478182485, 5435.402105768441, 5315.681059136464, 5330.147176451749, 5358.866028349323, 5360.624756267024, 5376.784928602812, 5443.0, 5457.918131227955, 5459.317585301837, 5495.807646732345, 5519.475649813261, 5086.0, 5343.519083282367, 5347.946520534745, 5349.652272602269, 5385.19595935366], "value": 4041.5057379152413, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [752.6124046116252, 757.2314101187294, 778.4395235430488, 797.210761346267, 857.2199298638236, 14957.850421495781, 16888.83111168888, 16906.408275710353, 16916.57708557285, 16919.661606767873, 15009.0, 15134.09195448274, 15176.468823591176, 15190.31643576039, 15196.240187990612, 555.7026990560051, 792.8889955406248, 803.1085495100434, 806.6007326373453, 825.5211977053325, 805.9153788852174, 807.0557447786105, 808.8180159464102, 810.432697112021, 812.9878051829228, 866.4974314897345, 867.6355930509201, 870.2037635563483, 870.9695160669378, 874.8294082653885, 532.9040772660919, 812.3947658994058, 844.4257904624852, 844.5861527851308, 847.1570787066908, 619.2692622705206, 622.6606499457761, 622.8723111762077, 623.0529595015596, 623.1431781300712, 14191.148531088065, 14207.0, 14253.0, 14264.072835265675, 14367.0, 12616.116871818977, 12697.0, 12737.235765853986, 13004.739905201843, 13610.659733506642, 11915.582954596633, 12053.698657533545, 12187.93906030473, 12191.878081219164, 12309.630711078593, 4302.892427689333, 4351.477822661269, 4368.344748287751, 4372.453443319585, 4463.553644635511, 79851.0, 79999.20000799975, 81788.0, 84505.0, 84567.99999999939, 10312.484375781181, 11181.32912025281, 11207.943960280147, 11296.322220666787, 11644.184907056424, 21512.677309840365, 21513.13947442116, 21533.89233053825, 21555.67666485004, 21572.676409853866, 8051.315638170821, 8172.591370431454, 8334.66661333552, 8517.23344898962, 8568.614412351335, 4551.6813823032735, 4657.6273898088075, 4697.107549565571, 4698.929516057262, 4721.0, 3188.1870123118456, 3280.852361643684, 3298.356820419995, 3323.418401779679, 3324.5844269466315, 611.0406661541426, 612.2744547710977, 619.0466681310731, 624.687656171914, 636.6021236727046, 617.7251123250111, 653.9803805885736, 665.6971078159426, 669.718718138383, 691.778630838127, 8404.0, 8897.599608017656, 9338.906610933804, 9385.06149385056, 9698.70903872885, 542.4683809866353, 563.3296377310987, 680.2721088435426, 712.6864179760888, 721.1310371002929, 4400.779961001937, 4426.0, 4519.344695019204, 4551.089782043605, 4661.324108004321, 113554.0, 114602.42698786453, 118288.0, 119827.0, 123196.84015799174, 102569.0, 103205.48397257966, 105595.4720226394, 109735.0, 110608.4469577647, 91509.08490915138, 92092.0, 92536.0, 93019.0, 94347.0, 8590.871136932827, 8680.392372533863, 8746.825063498822, 8795.692150774948, 8833.07252738479, 49095.26357104718, 49125.0, 50107.49892501028, 51917.221241682164, 52089.47910520846, 44098.11803763843, 44382.0, 44415.11169776651, 48147.0, 73094.63452682702, 11204.607838725931, 11428.371439570903, 11446.885531144582, 11608.24546404481, 11859.644210673694, 13012.8048079277, 13076.411561479566, 13342.733145337234, 13991.720165596835, 14033.228172450614, 12248.203866748632, 12497.500099995897, 12574.0, 13078.673033174247, 13620.046596738133, 11912.76174476523, 12105.75788484243, 12267.141300108906, 12805.87194128084, 12899.161554498927, 11761.0, 11767.764644706887, 11807.0, 11893.167478276437, 12334.03983044081, 9333.160015598629, 9391.81216375655, 9560.808783824423, 9602.423854568748, 9731.951340243253, 9946.254030947852, 10022.649207278002, 10194.796104078025, 10234.948825255826, 10273.640422585173], "value": 7264.993288322961, "unit": "score"}], "name": "dromaeo_css", "value": 7166.30576781162, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 116.0, 112.0, 113.0, 111.0, 117.0, 113.0, 112.0, 112.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 117.0, 140.0, 187.0, 119.0, 143.0, 143.0, 122.0, 177.0, 117.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 163.0, 162.0, 162.0, 161.0, 164.0, 162.0, 161.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 167.0, 74.0, 77.0, 74.0, 76.0, 164.0, 76.0], "value": 93.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 166.0, 87.0, 85.0, 85.0, 149.0, 84.0, 87.0, 143.0], "value": 105.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 147.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 53.0, 51.0, 51.0, 52.0, 51.0, 51.0, 51.0, 51.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 63.0, 63.0, 63.0, 63.0, 62.0, 63.0, 62.0, 63.0], "value": 62.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 78.0, 86.0, 81.0, 86.0, 82.0, 78.0, 79.0, 79.0, 84.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 123.0, 127.0, 125.0, 122.0, 123.0, 121.0, 122.0, 139.0, 129.0], "value": 125.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 169.0, 168.0, 161.0, 165.0, 163.0, 166.0, 163.0, 162.0], "value": 164.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 67.0, 64.0, 68.0, 68.0, 64.0, 66.0, 64.0, 63.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1524.3, "alertThreshold": 2.0}]} 10:09:06 INFO - Return code: 0 10:09:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:09:06 INFO - # TBPL SUCCESS # 10:09:06 INFO - Running post-action listener: _resource_record_post_action 10:09:06 INFO - [mozharness: 2016-08-22 17:09:06.480628Z] Finished run-tests step (success) 10:09:06 INFO - Running post-run listener: _resource_record_post_run 10:09:06 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 7847936; Write bytes: 429010944; Read time: 1304; Write time: 306476 10:09:06 INFO - TinderboxPrint: CPU usage
13.3% 10:09:06 INFO - TinderboxPrint: I/O read bytes / time
7,847,936 / 1,304 10:09:06 INFO - TinderboxPrint: I/O write bytes / time
429,010,944 / 306,476 10:09:06 INFO - TinderboxPrint: CPU idle
8,281.1 (86.7%) 10:09:06 INFO - TinderboxPrint: CPU user
1,200.3 (12.6%) 10:09:06 INFO - TinderboxPrint: Swap in / out
0 / 0 10:09:06 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 81432576; Read time: 0; Write time: 49036 10:09:06 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 5472256; Write bytes: 347578368; Read time: 1000; Write time: 257440 10:09:07 INFO - Running post-run listener: _upload_blobber_files 10:09:07 INFO - Blob upload gear active. 10:09:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:09:07 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 10:09:07 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:09:07 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:09:07 INFO - (blobuploader) - INFO - Open directory for files ... 10:09:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:09:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:09:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:08 INFO - (blobuploader) - INFO - Done attempting. 10:09:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:09:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:09:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:09 INFO - (blobuploader) - INFO - Done attempting. 10:09:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:09:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:09:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:13 INFO - (blobuploader) - INFO - Done attempting. 10:09:13 INFO - (blobuploader) - INFO - Iteration through files over. 10:09:13 INFO - Return code: 0 10:09:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:09:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:09:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c3d80800af797f5faa5d6ede58e195db54810d79396d9d053d360d1169ab03acb011c0289d96d347f8917b8ea39d9d96e30d5d417ba136fb3ca590ce224eeb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa369809e0ed5e347fd2eb808f4ec347f75a6152f7154fa3d6674b585cdf2e3aca0a7480843d133706717c5c9a29810b83024779ef8e5f97f160de23eb9e108d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab8c6be730f0f209a244d2d06daf76f7a799a126571588fe3b0e809def13427830bf6c201403371eda2f93dd4da1c6d49c2744bd335a2a817fa3aa4bae5b377a"} 10:09:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:09:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:09:13 INFO - Contents: 10:09:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c3d80800af797f5faa5d6ede58e195db54810d79396d9d053d360d1169ab03acb011c0289d96d347f8917b8ea39d9d96e30d5d417ba136fb3ca590ce224eeb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa369809e0ed5e347fd2eb808f4ec347f75a6152f7154fa3d6674b585cdf2e3aca0a7480843d133706717c5c9a29810b83024779ef8e5f97f160de23eb9e108d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab8c6be730f0f209a244d2d06daf76f7a799a126571588fe3b0e809def13427830bf6c201403371eda2f93dd4da1c6d49c2744bd335a2a817fa3aa4bae5b377a"} 10:09:13 INFO - Running post-run listener: copy_logs_to_upload_dir 10:09:13 INFO - Copying logs to upload dir... 10:09:13 INFO - mkdir: /builds/slave/test/build/upload/logs 10:09:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.567580 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-08-22 10:09:13.942290) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 10:09:13.945573) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c3d80800af797f5faa5d6ede58e195db54810d79396d9d053d360d1169ab03acb011c0289d96d347f8917b8ea39d9d96e30d5d417ba136fb3ca590ce224eeb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa369809e0ed5e347fd2eb808f4ec347f75a6152f7154fa3d6674b585cdf2e3aca0a7480843d133706717c5c9a29810b83024779ef8e5f97f160de23eb9e108d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab8c6be730f0f209a244d2d06daf76f7a799a126571588fe3b0e809def13427830bf6c201403371eda2f93dd4da1c6d49c2744bd335a2a817fa3aa4bae5b377a"} build_url:https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011142 build_url: 'https://queue.taskcluster.net/v1/task/Tv6_1dQ4QbyRkjVV1fHxxw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c3d80800af797f5faa5d6ede58e195db54810d79396d9d053d360d1169ab03acb011c0289d96d347f8917b8ea39d9d96e30d5d417ba136fb3ca590ce224eeb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa369809e0ed5e347fd2eb808f4ec347f75a6152f7154fa3d6674b585cdf2e3aca0a7480843d133706717c5c9a29810b83024779ef8e5f97f160de23eb9e108d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab8c6be730f0f209a244d2d06daf76f7a799a126571588fe3b0e809def13427830bf6c201403371eda2f93dd4da1c6d49c2744bd335a2a817fa3aa4bae5b377a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 10:09:13.972573) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 10:09:13.972970) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1471884502.258718-1872171304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004397 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 10:09:14.054247) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 10:09:14.054591) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 10:09:14.054934) ========= ========= Total master_lag: 0.16 =========