builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-005 starttime: 1471411428.15 results: success (0) buildid: 20160816193054 builduid: f8c8cf1a081b4ec1ad92caa0d0007f57 revision: 457289ddb7c94246e73b10200572494efc7e01c3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 22:23:48.145606) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 22:23:48.146013) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 22:23:48.146321) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 22:23:48.162491) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 22:23:48.162757) ========= 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-1471411348.780732-21964959 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-16 22:23:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 218M=0s 2016-08-16 22:23:48 (218 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133315 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 22:23:48.323637) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 22:23:48.323915) ========= 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-1471411348.780732-21964959 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019765 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 22:23:48.375580) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 22:23:48.375940) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 457289ddb7c94246e73b10200572494efc7e01c3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 457289ddb7c94246e73b10200572494efc7e01c3 --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-1471411348.780732-21964959 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-16 22:23:48,514 truncating revision to first 12 chars 2016-08-16 22:23:48,514 Setting DEBUG logging. 2016-08-16 22:23:48,514 attempt 1/10 2016-08-16 22:23:48,514 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/457289ddb7c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 22:23:48,826 unpacking tar archive at: mozilla-inbound-457289ddb7c9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.640701 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 22:23:49.032271) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 22:23:49.032573) ========= script_repo_revision: 457289ddb7c94246e73b10200572494efc7e01c3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 22:23:49.033009) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 22:23:49.033333) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 22:23:49.042342) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-16 22:23:49.042669) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1471411348.780732-21964959 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:23:49 INFO - MultiFileLogger online at 20160816 22:23:49 in /builds/slave/test 22:23:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:23:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:23:49 INFO - {'append_to_log': False, 22:23:49 INFO - 'base_work_dir': '/builds/slave/test', 22:23:49 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:23:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:23:49 INFO - 'branch': 'Mozilla-Inbound', 22:23:49 INFO - 'buildbot_json_path': 'buildprops.json', 22:23:49 INFO - 'config_files': ('talos/linux_config.py',), 22:23:49 INFO - 'default_actions': ('clobber', 22:23:49 INFO - 'read-buildbot-config', 22:23:49 INFO - 'download-and-extract', 22:23:49 INFO - 'populate-webroot', 22:23:49 INFO - 'create-virtualenv', 22:23:49 INFO - 'install', 22:23:49 INFO - 'run-tests'), 22:23:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:23:49 INFO - 'download_minidump_stackwalk': True, 22:23:49 INFO - 'download_symbols': 'ondemand', 22:23:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:23:49 INFO - 'tooltool.py': '/tools/tooltool.py', 22:23:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:23:49 INFO - '/tools/misc-python/virtualenv.py')}, 22:23:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:23:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:23:49 INFO - 'installer_path': 'installer.exe', 22:23:49 INFO - 'log_level': 'info', 22:23:49 INFO - 'log_name': 'talos', 22:23:49 INFO - 'log_to_console': True, 22:23:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:23:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:23:49 INFO - 'opt_config_files': (), 22:23:49 INFO - 'pip_index': False, 22:23:49 INFO - 'sps_profile': False, 22:23:49 INFO - 'sps_profile_interval': 0, 22:23:49 INFO - 'suite': 'dromaeojs-e10s', 22:23:49 INFO - 'system_bits': '32', 22:23:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:23:49 INFO - 'title': 'talos-linux64-ix-005', 22:23:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:23:49 INFO - 'use_talos_json': True, 22:23:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:23:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:23:49 INFO - 'work_dir': 'build'} 22:23:49 INFO - [mozharness: 2016-08-17 05:23:49.276992Z] Running clobber step. 22:23:49 INFO - Running pre-action listener: _resource_record_pre_action 22:23:49 INFO - Running main action method: clobber 22:23:49 INFO - rmtree: /builds/slave/test/build 22:23:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:23:49 INFO - Running post-action listener: _resource_record_post_action 22:23:49 INFO - [mozharness: 2016-08-17 05:23:49.726884Z] Finished clobber step (success) 22:23:49 INFO - [mozharness: 2016-08-17 05:23:49.726986Z] Running read-buildbot-config step. 22:23:49 INFO - Running pre-action listener: _resource_record_pre_action 22:23:49 INFO - Running main action method: read_buildbot_config 22:23:49 INFO - Using buildbot properties: 22:23:49 INFO - { 22:23:49 INFO - "project": "", 22:23:49 INFO - "product": "firefox", 22:23:49 INFO - "script_repo_revision": "production", 22:23:49 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 22:23:49 INFO - "repository": "", 22:23:49 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 22:23:49 INFO - "buildid": "20160816193054", 22:23:49 INFO - "pgo_build": "True", 22:23:49 INFO - "basedir": "/builds/slave/test", 22:23:49 INFO - "buildnumber": 1213, 22:23:49 INFO - "slavename": "talos-linux64-ix-005", 22:23:49 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:23:49 INFO - "platform": "ubuntu64_hw", 22:23:49 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:23:49 INFO - "branch": "mozilla-inbound", 22:23:49 INFO - "repo_path": "integration/mozilla-inbound", 22:23:49 INFO - "revision": "457289ddb7c94246e73b10200572494efc7e01c3", 22:23:49 INFO - "stage_platform": "linux64-pgo", 22:23:49 INFO - "builduid": "f8c8cf1a081b4ec1ad92caa0d0007f57", 22:23:49 INFO - "slavebuilddir": "test" 22:23:49 INFO - } 22:23:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 22:23:49 INFO - Running post-action listener: _resource_record_post_action 22:23:49 INFO - [mozharness: 2016-08-17 05:23:49.729417Z] Finished read-buildbot-config step (success) 22:23:49 INFO - [mozharness: 2016-08-17 05:23:49.729506Z] Running download-and-extract step. 22:23:49 INFO - Running pre-action listener: _resource_record_pre_action 22:23:49 INFO - Running main action method: download_and_extract 22:23:49 INFO - mkdir: /builds/slave/test/build/tests 22:23: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')]} 22:23:49 INFO - trying https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:23:49 INFO - Downloading https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/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 22:23:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/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 22:23:50 INFO - Downloaded 1361 bytes. 22:23:50 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:23:50 INFO - Using the following test package requirements: 22:23:50 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:23:50 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:23:50 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:23:50 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:23:50 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:23:50 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:23:50 INFO - u'jsshell-linux-x86_64.zip'], 22:23:50 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:23:50 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:23:50 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:23:50 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:23:50 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:23:50 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:23:50 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:23:50 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:23:50 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:23:50 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:23:50 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:23:50 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:23:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:50 INFO - trying https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:23:50 INFO - Downloading https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/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 22:23:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/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 22:23:51 INFO - Downloaded 21903107 bytes. 22:23:51 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 22:23:55 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 22:23: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')]} 22:23:55 INFO - trying https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:23:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/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 22:23:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/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 22:23:56 INFO - Downloaded 21903107 bytes. 22:23:56 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 22:24:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:00 INFO - trying https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:24:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/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 22:24:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/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 22:24:02 INFO - Downloaded 14413719 bytes. 22:24:02 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 22:24:02 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')]} 22:24:02 INFO - trying https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:24:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/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 22:24:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/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 22:24:03 INFO - Downloaded 59555049 bytes. 22:24:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:24:03 INFO - mkdir: /builds/slave/test/properties 22:24:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:24:03 INFO - Writing to file /builds/slave/test/properties/build_url 22:24:03 INFO - Contents: 22:24:03 INFO - build_url:https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:24:04 INFO - Running post-action listener: _resource_record_post_action 22:24:04 INFO - Running post-action listener: set_extra_try_arguments 22:24:04 INFO - [mozharness: 2016-08-17 05:24:04.645461Z] Finished download-and-extract step (success) 22:24:04 INFO - [mozharness: 2016-08-17 05:24:04.645686Z] Running populate-webroot step. 22:24:04 INFO - Running pre-action listener: _resource_record_pre_action 22:24:04 INFO - Running main action method: populate_webroot 22:24:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:24:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:24:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:24:04 INFO - '--tppagecycles', 22:24:04 INFO - '7'], 22:24:04 INFO - 'tests': ['tsvgm']}}, 22:24:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:24:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:24:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:24:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:24:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:24:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:24:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:04 INFO - 'talos_options': ['--disable-e10s'], 22:24:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:24:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:24:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:04 INFO - 'talos_options': ['--disable-e10s'], 22:24:04 INFO - 'tests': ['damp', 'tps']}, 22:24:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:04 INFO - 'tests': ['damp', 'tps']}, 22:24:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:24:04 INFO - 'tests': ['dromaeo_dom']}, 22:24:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:24:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:24:04 INFO - 'tests': ['basic_compositor_video']}, 22:24:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:24:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:24:04 INFO - 'tests': ['a11yr', 22:24:04 INFO - 'ts_paint', 22:24:04 INFO - 'tpaint', 22:24:04 INFO - 'sessionrestore', 22:24:04 INFO - 'sessionrestore_no_auto_restore', 22:24:04 INFO - 'tabpaint']}, 22:24:04 INFO - 'other-e10s': {'tests': ['a11yr', 22:24:04 INFO - 'ts_paint', 22:24:04 INFO - 'tpaint', 22:24:04 INFO - 'sessionrestore', 22:24:04 INFO - 'sessionrestore_no_auto_restore', 22:24:04 INFO - 'tabpaint']}, 22:24:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:24:04 INFO - 'ts_paint', 22:24:04 INFO - 'tpaint', 22:24:04 INFO - 'sessionrestore', 22:24:04 INFO - 'sessionrestore_no_auto_restore', 22:24:04 INFO - 'tabpaint']}, 22:24:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:24:04 INFO - 'ts_paint', 22:24:04 INFO - 'tpaint', 22:24:04 INFO - 'sessionrestore', 22:24:04 INFO - 'sessionrestore_no_auto_restore', 22:24:04 INFO - 'tabpaint']}, 22:24:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:24:04 INFO - 'tests': ['a11yr', 22:24:04 INFO - 'ts_paint', 22:24:04 INFO - 'tpaint', 22:24:04 INFO - 'sessionrestore', 22:24:04 INFO - 'sessionrestore_no_auto_restore', 22:24:04 INFO - 'tabpaint']}, 22:24:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:24:04 INFO - 'tests': ['a11yr', 22:24:04 INFO - 'ts_paint', 22:24:04 INFO - 'tpaint', 22:24:04 INFO - 'sessionrestore', 22:24:04 INFO - 'sessionrestore_no_auto_restore', 22:24:04 INFO - 'tabpaint']}, 22:24:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:24:04 INFO - 'tests': ['tsvgx', 22:24:04 INFO - 'tsvgr_opacity', 22:24:04 INFO - 'tart', 22:24:04 INFO - 'tscrollx', 22:24:04 INFO - 'cart']}, 22:24:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:24:04 INFO - 'tsvgr_opacity', 22:24:04 INFO - 'tart', 22:24:04 INFO - 'tscrollx', 22:24:04 INFO - 'cart']}, 22:24:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:04 INFO - 'talos_options': ['--disable-e10s'], 22:24:04 INFO - 'tests': ['tp5o']}, 22:24:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:04 INFO - 'tests': ['tp5o']}, 22:24:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:04 INFO - 'talos_options': ['--disable-e10s', 22:24:04 INFO - '--xperf_path', 22:24:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:24:04 INFO - 'tests': ['tp5n']}, 22:24:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:04 INFO - 'talos_options': ['--xperf_path', 22:24:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:24:04 INFO - 'tests': ['tp5n']}}, 22:24:04 INFO - 'talos.zip': {'path': '', 22:24:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:24:04 INFO - Running post-action listener: _resource_record_post_action 22:24:04 INFO - [mozharness: 2016-08-17 05:24:04.663825Z] Finished populate-webroot step (success) 22:24:04 INFO - [mozharness: 2016-08-17 05:24:04.663913Z] Running create-virtualenv step. 22:24:04 INFO - Running pre-action listener: _resource_record_pre_action 22:24:04 INFO - Running main action method: create_virtualenv 22:24:04 INFO - Creating virtualenv /builds/slave/test/build/venv 22:24:04 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 22:24:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:24:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:24:04 INFO - Using real prefix '/usr' 22:24:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:24:05 INFO - Installing distribute.............................................................................................................................................................................................done. 22:24:07 INFO - Installing pip.................done. 22:24:07 INFO - Return code: 0 22:24:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:24:07 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')]} 22:24:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:07 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')]} 22:24:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:07 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 0x7fb71648af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1feb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5090>, '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 0x1fe89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe8e40>, '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-1471411348.780732-21964959', '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 22:24:07 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 22:24:07 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 22:24:07 INFO - Using env: {'DISPLAY': ':0', 22:24:07 INFO - 'HOME': '/home/cltbld', 22:24:07 INFO - 'LANG': 'en_US.UTF-8', 22:24:07 INFO - 'LANGUAGE': 'en_US:en', 22:24:07 INFO - 'LOGNAME': 'cltbld', 22:24:07 INFO - 'MAIL': '/var/mail/cltbld', 22:24:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:07 INFO - 'MOZ_NO_REMOTE': '1', 22:24:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:24:07 INFO - 'NO_EM_RESTART': '1', 22:24:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:24:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:07 INFO - 'PWD': '/builds/slave/test', 22:24:07 INFO - 'SHELL': '/bin/bash', 22:24:07 INFO - 'SHLVL': '1', 22:24:07 INFO - 'TERM': 'linux', 22:24:07 INFO - 'TMOUT': '86400', 22:24:07 INFO - 'USER': 'cltbld', 22:24:07 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471411348.780732-21964959', 22:24:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:07 INFO - '_': '/tools/buildbot/bin/python'} 22:24:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:07 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 22:24:07 INFO - Cleaning up... 22:24:07 INFO - Return code: 0 22:24:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:24:07 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')]} 22:24:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:07 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')]} 22:24:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:07 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 0x7fb71648af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1feb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5090>, '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 0x1fe89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe8e40>, '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-1471411348.780732-21964959', '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 22:24:07 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 22:24:07 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 22:24:07 INFO - Using env: {'DISPLAY': ':0', 22:24:07 INFO - 'HOME': '/home/cltbld', 22:24:07 INFO - 'LANG': 'en_US.UTF-8', 22:24:07 INFO - 'LANGUAGE': 'en_US:en', 22:24:07 INFO - 'LOGNAME': 'cltbld', 22:24:07 INFO - 'MAIL': '/var/mail/cltbld', 22:24:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:07 INFO - 'MOZ_NO_REMOTE': '1', 22:24:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:24:07 INFO - 'NO_EM_RESTART': '1', 22:24:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:24:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:07 INFO - 'PWD': '/builds/slave/test', 22:24:07 INFO - 'SHELL': '/bin/bash', 22:24:07 INFO - 'SHLVL': '1', 22:24:07 INFO - 'TERM': 'linux', 22:24:07 INFO - 'TMOUT': '86400', 22:24:07 INFO - 'USER': 'cltbld', 22:24:07 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471411348.780732-21964959', 22:24:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:07 INFO - '_': '/tools/buildbot/bin/python'} 22:24:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:07 INFO - Downloading/unpacking psutil>=3.1.1 22:24:07 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 22:24:07 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 22:24:07 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 22:24:07 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 22:24:07 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 22:24:07 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 22:24:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:24:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:24:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:24:09 INFO - Installing collected packages: psutil 22:24:09 INFO - Running setup.py install for psutil 22:24:09 INFO - building 'psutil._psutil_linux' extension 22:24:09 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 22:24:09 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 22:24:09 INFO - building 'psutil._psutil_posix' extension 22:24:09 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 22:24:10 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 22:24:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:24:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:24:10 INFO - Successfully installed psutil 22:24:10 INFO - Cleaning up... 22:24:10 INFO - Return code: 0 22:24:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:24:10 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')]} 22:24:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:10 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')]} 22:24:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:10 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 0x7fb71648af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1feb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5090>, '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 0x1fe89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe8e40>, '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-1471411348.780732-21964959', '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 22:24:10 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 22:24:10 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 22:24:10 INFO - Using env: {'DISPLAY': ':0', 22:24:10 INFO - 'HOME': '/home/cltbld', 22:24:10 INFO - 'LANG': 'en_US.UTF-8', 22:24:10 INFO - 'LANGUAGE': 'en_US:en', 22:24:10 INFO - 'LOGNAME': 'cltbld', 22:24:10 INFO - 'MAIL': '/var/mail/cltbld', 22:24:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:10 INFO - 'MOZ_NO_REMOTE': '1', 22:24:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:24:10 INFO - 'NO_EM_RESTART': '1', 22:24:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:24:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:10 INFO - 'PWD': '/builds/slave/test', 22:24:10 INFO - 'SHELL': '/bin/bash', 22:24:10 INFO - 'SHLVL': '1', 22:24:10 INFO - 'TERM': 'linux', 22:24:10 INFO - 'TMOUT': '86400', 22:24:10 INFO - 'USER': 'cltbld', 22:24:10 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471411348.780732-21964959', 22:24:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:10 INFO - '_': '/tools/buildbot/bin/python'} 22:24:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:24:10 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 22:24:10 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 22:24:10 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 22:24:10 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 22:24:10 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 22:24:10 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 22:24:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:24:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:24:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:24:12 INFO - Installing collected packages: mozsystemmonitor 22:24:12 INFO - Running setup.py install for mozsystemmonitor 22:24:12 INFO - Successfully installed mozsystemmonitor 22:24:12 INFO - Cleaning up... 22:24:12 INFO - Return code: 0 22:24:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:24:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:12 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 0x7fb71648af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1feb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5090>, '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 0x1fe89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe8e40>, '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-1471411348.780732-21964959', '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 22:24:12 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 22:24:12 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 22:24:12 INFO - Using env: {'DISPLAY': ':0', 22:24:12 INFO - 'HOME': '/home/cltbld', 22:24:12 INFO - 'LANG': 'en_US.UTF-8', 22:24:12 INFO - 'LANGUAGE': 'en_US:en', 22:24:12 INFO - 'LOGNAME': 'cltbld', 22:24:12 INFO - 'MAIL': '/var/mail/cltbld', 22:24:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:12 INFO - 'MOZ_NO_REMOTE': '1', 22:24:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:24:12 INFO - 'NO_EM_RESTART': '1', 22:24:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:24:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:12 INFO - 'PWD': '/builds/slave/test', 22:24:12 INFO - 'SHELL': '/bin/bash', 22:24:12 INFO - 'SHLVL': '1', 22:24:12 INFO - 'TERM': 'linux', 22:24:12 INFO - 'TMOUT': '86400', 22:24:12 INFO - 'USER': 'cltbld', 22:24:12 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471411348.780732-21964959', 22:24:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:12 INFO - '_': '/tools/buildbot/bin/python'} 22:24:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:12 INFO - Downloading/unpacking jsonschema==2.5.1 22:24:12 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 22:24:12 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 22:24:12 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 22:24:12 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 22:24:12 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 22:24:12 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 22:24:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:24:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:24:14 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 22:24:14 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 22:24:14 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 22:24:14 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 22:24:14 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 22:24:14 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 22:24:14 INFO - Downloading functools32-3.2.3-2.tar.gz 22:24:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:24:14 INFO - warning: no files found matching '*.txt' 22:24:14 INFO - no previously-included directories found matching 'build' 22:24:14 INFO - no previously-included directories found matching 'dist' 22:24:14 INFO - no previously-included directories found matching '.git*' 22:24:14 INFO - Installing collected packages: jsonschema, functools32 22:24:14 INFO - Running setup.py install for functools32 22:24:14 INFO - warning: no files found matching '*.txt' 22:24:14 INFO - no previously-included directories found matching 'build' 22:24:14 INFO - no previously-included directories found matching 'dist' 22:24:14 INFO - no previously-included directories found matching '.git*' 22:24:14 INFO - Successfully installed jsonschema functools32 22:24:14 INFO - Cleaning up... 22:24:15 INFO - Return code: 0 22:24:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:24:15 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')]} 22:24:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:15 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')]} 22:24:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:15 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 0x7fb71648af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1feb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5090>, '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 0x1fe89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe8e40>, '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-1471411348.780732-21964959', '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 22:24:15 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 22:24:15 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 22:24:15 INFO - Using env: {'DISPLAY': ':0', 22:24:15 INFO - 'HOME': '/home/cltbld', 22:24:15 INFO - 'LANG': 'en_US.UTF-8', 22:24:15 INFO - 'LANGUAGE': 'en_US:en', 22:24:15 INFO - 'LOGNAME': 'cltbld', 22:24:15 INFO - 'MAIL': '/var/mail/cltbld', 22:24:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:15 INFO - 'MOZ_NO_REMOTE': '1', 22:24:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:24:15 INFO - 'NO_EM_RESTART': '1', 22:24:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:24:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:15 INFO - 'PWD': '/builds/slave/test', 22:24:15 INFO - 'SHELL': '/bin/bash', 22:24:15 INFO - 'SHLVL': '1', 22:24:15 INFO - 'TERM': 'linux', 22:24:15 INFO - 'TMOUT': '86400', 22:24:15 INFO - 'USER': 'cltbld', 22:24:15 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471411348.780732-21964959', 22:24:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:15 INFO - '_': '/tools/buildbot/bin/python'} 22:24:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:24:15 INFO - Cleaning up... 22:24:15 INFO - Return code: 0 22:24:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:24:15 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')]} 22:24:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:15 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')]} 22:24:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:15 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 0x7fb71648af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1feb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5090>, '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 0x1fe89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe8e40>, '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-1471411348.780732-21964959', '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 22:24:15 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 22:24:15 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 22:24:15 INFO - Using env: {'DISPLAY': ':0', 22:24:15 INFO - 'HOME': '/home/cltbld', 22:24:15 INFO - 'LANG': 'en_US.UTF-8', 22:24:15 INFO - 'LANGUAGE': 'en_US:en', 22:24:15 INFO - 'LOGNAME': 'cltbld', 22:24:15 INFO - 'MAIL': '/var/mail/cltbld', 22:24:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:15 INFO - 'MOZ_NO_REMOTE': '1', 22:24:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:24:15 INFO - 'NO_EM_RESTART': '1', 22:24:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:24:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:15 INFO - 'PWD': '/builds/slave/test', 22:24:15 INFO - 'SHELL': '/bin/bash', 22:24:15 INFO - 'SHLVL': '1', 22:24:15 INFO - 'TERM': 'linux', 22:24:15 INFO - 'TMOUT': '86400', 22:24:15 INFO - 'USER': 'cltbld', 22:24:15 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471411348.780732-21964959', 22:24:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:15 INFO - '_': '/tools/buildbot/bin/python'} 22:24:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:15 INFO - Downloading/unpacking blobuploader==1.2.4 22:24:15 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 22:24:15 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 22:24:15 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 22:24:15 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 22:24:15 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 22:24:15 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 22:24:17 INFO - Downloading blobuploader-1.2.4.tar.gz 22:24:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:24:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:24:17 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 22:24:17 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 22:24:17 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 22:24:17 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 22:24:17 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 22:24:17 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 22:24:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:24:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:24:17 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 22:24:17 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 22:24:17 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 22:24:17 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 22:24:17 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 22:24:17 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 22:24:17 INFO - Downloading docopt-0.6.1.tar.gz 22:24:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:24:18 INFO - Installing collected packages: blobuploader, requests, docopt 22:24:18 INFO - Running setup.py install for blobuploader 22:24:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:24:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:24:18 INFO - Running setup.py install for requests 22:24:18 INFO - Running setup.py install for docopt 22:24:18 INFO - Successfully installed blobuploader requests docopt 22:24:18 INFO - Cleaning up... 22:24:18 INFO - Return code: 0 22:24:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:18 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 0x7fb71648af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1feb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5090>, '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 0x1fe89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe8e40>, '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-1471411348.780732-21964959', '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 22:24:18 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 22:24:18 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 22:24:18 INFO - Using env: {'DISPLAY': ':0', 22:24:18 INFO - 'HOME': '/home/cltbld', 22:24:18 INFO - 'LANG': 'en_US.UTF-8', 22:24:18 INFO - 'LANGUAGE': 'en_US:en', 22:24:18 INFO - 'LOGNAME': 'cltbld', 22:24:18 INFO - 'MAIL': '/var/mail/cltbld', 22:24:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:18 INFO - 'MOZ_NO_REMOTE': '1', 22:24:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:24:18 INFO - 'NO_EM_RESTART': '1', 22:24:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:24:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:18 INFO - 'PWD': '/builds/slave/test', 22:24:18 INFO - 'SHELL': '/bin/bash', 22:24:18 INFO - 'SHLVL': '1', 22:24:18 INFO - 'TERM': 'linux', 22:24:18 INFO - 'TMOUT': '86400', 22:24:18 INFO - 'USER': 'cltbld', 22:24:18 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471411348.780732-21964959', 22:24:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:18 INFO - '_': '/tools/buildbot/bin/python'} 22:24:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:24:18 INFO - Running setup.py (path:/tmp/pip-wy_gcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:24:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:24:18 INFO - Running setup.py (path:/tmp/pip-BAw_eK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:24:19 INFO - Running setup.py (path:/tmp/pip-EA7sJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:24:19 INFO - Running setup.py (path:/tmp/pip-NABhkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:24:19 INFO - Running setup.py (path:/tmp/pip-kxIyqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:24:19 INFO - Running setup.py (path:/tmp/pip-2EE4Fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:24:19 INFO - Running setup.py (path:/tmp/pip-Lv2l1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:24:19 INFO - Running setup.py (path:/tmp/pip-dlFSzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:24:19 INFO - Running setup.py (path:/tmp/pip-jpWdyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:24:19 INFO - Running setup.py (path:/tmp/pip-UJ2DwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:24:19 INFO - Running setup.py (path:/tmp/pip-BHoPo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:24:19 INFO - Running setup.py (path:/tmp/pip-wlTsrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:24:19 INFO - Running setup.py (path:/tmp/pip-G4ituR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:24:19 INFO - Running setup.py (path:/tmp/pip-bKsJzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:19 INFO - Running setup.py (path:/tmp/pip-ZcUBJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:24:19 INFO - Running setup.py (path:/tmp/pip-FPqReS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:24:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:24:20 INFO - Running setup.py (path:/tmp/pip-gXuPww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:24:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:24:20 INFO - Running setup.py install for manifestparser 22:24:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:24:20 INFO - Running setup.py install for mozcrash 22:24:20 INFO - Running setup.py install for mozdebug 22:24:20 INFO - Running setup.py install for mozdevice 22:24:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:24:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:24:20 INFO - Running setup.py install for mozfile 22:24:20 INFO - Running setup.py install for mozhttpd 22:24:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:24:20 INFO - Running setup.py install for mozinfo 22:24:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:24:20 INFO - Running setup.py install for mozInstall 22:24:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:24:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:24:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:24:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:24:20 INFO - Running setup.py install for mozleak 22:24:20 INFO - Running setup.py install for mozlog 22:24:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:24:21 INFO - Running setup.py install for moznetwork 22:24:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:24:21 INFO - Running setup.py install for mozprocess 22:24:21 INFO - Running setup.py install for mozprofile 22:24:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:24:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:24:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:24:21 INFO - Running setup.py install for mozrunner 22:24:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:24:21 INFO - Running setup.py install for mozscreenshot 22:24:21 INFO - Running setup.py install for moztest 22:24:21 INFO - Running setup.py install for mozversion 22:24:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:24:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:24:21 INFO - Cleaning up... 22:24:21 INFO - Return code: 0 22:24:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24:21 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')]} 22:24:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:21 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')]} 22:24:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:21 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 0x7fb71648af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1feb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5090>, '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 0x1fe89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe8e40>, '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-1471411348.780732-21964959', '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 22:24:21 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 22:24:21 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 22:24:21 INFO - Using env: {'DISPLAY': ':0', 22:24:21 INFO - 'HOME': '/home/cltbld', 22:24:21 INFO - 'LANG': 'en_US.UTF-8', 22:24:21 INFO - 'LANGUAGE': 'en_US:en', 22:24:21 INFO - 'LOGNAME': 'cltbld', 22:24:21 INFO - 'MAIL': '/var/mail/cltbld', 22:24:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:21 INFO - 'MOZ_NO_REMOTE': '1', 22:24:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:24:21 INFO - 'NO_EM_RESTART': '1', 22:24:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:24:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:21 INFO - 'PWD': '/builds/slave/test', 22:24:21 INFO - 'SHELL': '/bin/bash', 22:24:21 INFO - 'SHLVL': '1', 22:24:21 INFO - 'TERM': 'linux', 22:24:21 INFO - 'TMOUT': '86400', 22:24:21 INFO - 'USER': 'cltbld', 22:24:21 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471411348.780732-21964959', 22:24:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:21 INFO - '_': '/tools/buildbot/bin/python'} 22:24:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:24:22 INFO - Running setup.py (path:/tmp/pip-fMIWch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:24:22 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)) 22:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:24:22 INFO - Running setup.py (path:/tmp/pip-xsnb1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:24:22 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)) 22:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:24:22 INFO - Running setup.py (path:/tmp/pip-p8i6VW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:24:22 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)) 22:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:24:22 INFO - Running setup.py (path:/tmp/pip-QuF0i1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:24:22 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)) 22:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:24:22 INFO - Running setup.py (path:/tmp/pip-VOlhWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:24:22 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)) 22:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:24:22 INFO - Running setup.py (path:/tmp/pip-sQae_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:24:22 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)) 22:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:24:22 INFO - Running setup.py (path:/tmp/pip-8_Ll5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:24:22 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)) 22:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:24:22 INFO - Running setup.py (path:/tmp/pip-r9CBXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:24:22 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)) 22:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:24:22 INFO - Running setup.py (path:/tmp/pip-ntx77C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:24:22 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)) 22:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:24:22 INFO - Running setup.py (path:/tmp/pip-v8XBOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:24:22 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)) 22:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:24:22 INFO - Running setup.py (path:/tmp/pip-KAlr2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:24:22 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)) 22:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:24:22 INFO - Running setup.py (path:/tmp/pip-glysL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:24:22 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)) 22:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:24:22 INFO - Running setup.py (path:/tmp/pip-1LCR9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:24:23 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)) 22:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:24:23 INFO - Running setup.py (path:/tmp/pip-2FlW1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:24:23 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)) 22:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:23 INFO - Running setup.py (path:/tmp/pip-wetMrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:23 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)) 22:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:24:23 INFO - Running setup.py (path:/tmp/pip-9Po7pN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:24:23 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)) 22:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:24:23 INFO - Running setup.py (path:/tmp/pip-iI6tPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:24:23 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)) 22:24:23 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)) 22:24:23 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)) 22:24:23 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)) 22:24:23 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)) 22:24:23 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)) 22:24:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:24:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:25 INFO - Downloading blessings-1.6.tar.gz 22:24:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:24:25 INFO - Installing collected packages: blessings 22:24:25 INFO - Running setup.py install for blessings 22:24:25 INFO - Successfully installed blessings 22:24:25 INFO - Cleaning up... 22:24:25 INFO - Return code: 0 22:24:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:24:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:24:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:24:25 INFO - Reading from file tmpfile_stdout 22:24:25 INFO - Current package versions: 22:24:25 INFO - argparse == 1.2.1 22:24:25 INFO - blessings == 1.6 22:24:25 INFO - blobuploader == 1.2.4 22:24:25 INFO - docopt == 0.6.1 22:24:25 INFO - functools32 == 3.2.3-2 22:24:25 INFO - jsonschema == 2.5.1 22:24:25 INFO - manifestparser == 1.1 22:24:25 INFO - mozInstall == 1.12 22:24:25 INFO - mozcrash == 0.17 22:24:25 INFO - mozdebug == 0.1 22:24:25 INFO - mozdevice == 0.48 22:24:25 INFO - mozfile == 1.2 22:24:25 INFO - mozhttpd == 0.7 22:24:25 INFO - mozinfo == 0.9 22:24:25 INFO - mozleak == 0.1 22:24:25 INFO - mozlog == 3.3 22:24:25 INFO - moznetwork == 0.27 22:24:25 INFO - mozprocess == 0.23 22:24:25 INFO - mozprofile == 0.28 22:24:25 INFO - mozrunner == 6.12 22:24:25 INFO - mozscreenshot == 0.1 22:24:25 INFO - mozsystemmonitor == 0.3 22:24:25 INFO - moztest == 0.7 22:24:25 INFO - mozversion == 1.4 22:24:25 INFO - psutil == 3.1.1 22:24:25 INFO - requests == 1.2.3 22:24:25 INFO - wsgiref == 0.1.2 22:24:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24:25 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')]} 22:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:25 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')]} 22:24:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:25 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 0x7fb71648af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1feb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5090>, '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 0x1fe89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe8e40>, '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-1471411348.780732-21964959', '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 22:24:25 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 22:24:25 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 22:24:25 INFO - Using env: {'DISPLAY': ':0', 22:24:25 INFO - 'HOME': '/home/cltbld', 22:24:25 INFO - 'LANG': 'en_US.UTF-8', 22:24:25 INFO - 'LANGUAGE': 'en_US:en', 22:24:25 INFO - 'LOGNAME': 'cltbld', 22:24:25 INFO - 'MAIL': '/var/mail/cltbld', 22:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:25 INFO - 'MOZ_NO_REMOTE': '1', 22:24:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:24:25 INFO - 'NO_EM_RESTART': '1', 22:24:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:25 INFO - 'PWD': '/builds/slave/test', 22:24:25 INFO - 'SHELL': '/bin/bash', 22:24:25 INFO - 'SHLVL': '1', 22:24:25 INFO - 'TERM': 'linux', 22:24:25 INFO - 'TMOUT': '86400', 22:24:25 INFO - 'USER': 'cltbld', 22:24:25 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471411348.780732-21964959', 22:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:25 INFO - '_': '/tools/buildbot/bin/python'} 22:24:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:25 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)) 22:24:25 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)) 22:24:25 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)) 22:24:25 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)) 22:24:25 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)) 22:24:25 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)) 22:24:25 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)) 22:24:25 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)) 22:24:25 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)) 22:24:25 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)) 22:24:25 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)) 22:24:25 INFO - Cleaning up... 22:24:25 INFO - Return code: 0 22:24:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:24:25 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')]} 22:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:25 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')]} 22:24:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:25 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 0x7fb71648af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1feb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe5090>, '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 0x1fe89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe8e40>, '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-1471411348.780732-21964959', '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 22:24:25 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 22:24:25 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 22:24:25 INFO - Using env: {'DISPLAY': ':0', 22:24:25 INFO - 'HOME': '/home/cltbld', 22:24:25 INFO - 'LANG': 'en_US.UTF-8', 22:24:25 INFO - 'LANGUAGE': 'en_US:en', 22:24:25 INFO - 'LOGNAME': 'cltbld', 22:24:25 INFO - 'MAIL': '/var/mail/cltbld', 22:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:25 INFO - 'MOZ_NO_REMOTE': '1', 22:24:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:24:25 INFO - 'NO_EM_RESTART': '1', 22:24:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:25 INFO - 'PWD': '/builds/slave/test', 22:24:25 INFO - 'SHELL': '/bin/bash', 22:24:25 INFO - 'SHLVL': '1', 22:24:25 INFO - 'TERM': 'linux', 22:24:25 INFO - 'TMOUT': '86400', 22:24:25 INFO - 'USER': 'cltbld', 22:24:25 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471411348.780732-21964959', 22:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:25 INFO - '_': '/tools/buildbot/bin/python'} 22:24:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:24:26 INFO - Cleaning up... 22:24:26 INFO - Return code: 0 22:24:26 INFO - Running post-action listener: _resource_record_post_action 22:24:26 INFO - Running post-action listener: _start_resource_monitoring 22:24:26 INFO - Starting resource monitoring. 22:24:26 INFO - [mozharness: 2016-08-17 05:24:26.146512Z] Finished create-virtualenv step (success) 22:24:26 INFO - [mozharness: 2016-08-17 05:24:26.146981Z] Running install step. 22:24:26 INFO - Running pre-action listener: _resource_record_pre_action 22:24:26 INFO - Running main action method: install 22:24:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:24:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:24:26 INFO - Reading from file tmpfile_stdout 22:24:26 INFO - Detecting whether we're running mozinstall >=1.0... 22:24:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:24:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:24:26 INFO - Reading from file tmpfile_stdout 22:24:26 INFO - Output received: 22:24:26 INFO - Usage: mozinstall [options] installer 22:24:26 INFO - Options: 22:24:26 INFO - -h, --help show this help message and exit 22:24:26 INFO - -d DEST, --destination=DEST 22:24:26 INFO - Directory to install application into. [default: 22:24:26 INFO - "/builds/slave/test"] 22:24:26 INFO - --app=APP Application being installed. [default: firefox] 22:24:26 INFO - mkdir: /builds/slave/test/build/application 22:24:26 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'] 22:24:26 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 22:24:43 INFO - Reading from file tmpfile_stdout 22:24:43 INFO - Output received: 22:24:43 INFO - /builds/slave/test/build/application/firefox/firefox 22:24:43 INFO - Running post-action listener: _resource_record_post_action 22:24:43 INFO - [mozharness: 2016-08-17 05:24:43.376950Z] Finished install step (success) 22:24:43 INFO - [mozharness: 2016-08-17 05:24:43.377199Z] Running run-tests step. 22:24:43 INFO - Running pre-action listener: _resource_record_pre_action 22:24:43 INFO - Running main action method: run_tests 22:24:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:24:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:24:43 INFO - Python 2.7.3 22:24:43 INFO - Return code: 0 22:24:43 INFO - grabbing minidump binary from tooltool 22:24: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')]} 22:24:43 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 0x1fe5090>, '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 0x1fe89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe8e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:24:43 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 22:24:43 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 22:24:43 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 22:24:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:24:43 INFO - Return code: 0 22:24:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:24:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:24:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:24:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:24:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:24:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/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-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:24:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/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-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:24:43 INFO - Using env: {'DISPLAY': ':0', 22:24:43 INFO - 'HOME': '/home/cltbld', 22:24:43 INFO - 'LANG': 'en_US.UTF-8', 22:24:43 INFO - 'LANGUAGE': 'en_US:en', 22:24:43 INFO - 'LOGNAME': 'cltbld', 22:24:43 INFO - 'MAIL': '/var/mail/cltbld', 22:24:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:24:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:24:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:43 INFO - 'MOZ_NO_REMOTE': '1', 22:24:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:24:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:24:43 INFO - 'NO_EM_RESTART': '1', 22:24:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:24:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:43 INFO - 'PWD': '/builds/slave/test', 22:24:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:24:43 INFO - 'SHELL': '/bin/bash', 22:24:43 INFO - 'SHLVL': '1', 22:24:43 INFO - 'TERM': 'linux', 22:24:43 INFO - 'TMOUT': '86400', 22:24:43 INFO - 'USER': 'cltbld', 22:24:43 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471411348.780732-21964959', 22:24:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:43 INFO - '_': '/tools/buildbot/bin/python'} 22:24:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/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-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:24:43 INFO - mozversion application_buildid: 20160816193054 22:24:43 INFO - mozversion application_changeset: 457289ddb7c94246e73b10200572494efc7e01c3 22:24:43 INFO - mozversion application_display_name: Nightly 22:24:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:24:43 INFO - mozversion application_name: Firefox 22:24:43 INFO - mozversion application_remotingname: firefox 22:24:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:24:43 INFO - mozversion application_vendor: Mozilla 22:24:43 INFO - mozversion application_version: 51.0a1 22:24:43 INFO - mozversion platform_buildid: 20160816193054 22:24:43 INFO - mozversion platform_changeset: 457289ddb7c94246e73b10200572494efc7e01c3 22:24:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:24:43 INFO - mozversion platform_version: 51.0a1 22:24:43 INFO - using testdate: 1471411483 22:24:43 INFO - actual date: 1471411483 22:24:43 INFO - starting webserver on 'localhost:52157' 22:24:43 INFO - SUITE-START | Running 2 tests 22:24:43 INFO - TEST-START | dromaeo_css 22:24:43 INFO - operating with platform_type : linux_ 22:24:43 INFO - Initialising browser for dromaeo_css test... 22:24:43 INFO - TEST-INFO | started process 5043 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJjD12v/profile http://localhost:52157/getInfo.html) 22:24:48 INFO - PROCESS | 5043 | __metrics Screen width/height:1600/1200 22:24:48 INFO - PROCESS | 5043 | colorDepth:24 22:24:48 INFO - PROCESS | 5043 | Browser inner width/height: 1024/697 22:24:48 INFO - PROCESS | 5043 | __metrics 22:24:50 ERROR - PROCESS | 5043 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2983: TypeError: this.tabs is undefined 22:24:50 INFO - TEST-INFO | 5043: exit 0 22:24:50 INFO - Browser initialized. 22:24:50 INFO - Running cycle 1/1 for dromaeo_css test... 22:24:50 INFO - TEST-INFO | started process 5164 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJjD12v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:25:02 INFO - PROCESS | 5164 | RSS: Main: 163041280 22:25:02 INFO - PROCESS | 5164 | 22:25:52 INFO - PROCESS | 5164 | 1471411552309 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] 22:28:02 INFO - PROCESS | 5164 | Cycle 1(1): loaded http://localhost:52157/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52157/tests/dromaeo/cssquery-ext.html) 22:28:03 INFO - PROCESS | 5164 | RSS: Main: 164388864 22:28:03 INFO - PROCESS | 5164 | 22:31:05 INFO - PROCESS | 5164 | Cycle 1(1): loaded http://localhost:52157/tests/dromaeo/cssquery-ext.html (next: http://localhost:52157/tests/dromaeo/cssquery-jquery.html) 22:31:06 INFO - PROCESS | 5164 | RSS: Main: 164560896 22:31:06 INFO - PROCESS | 5164 | 22:34:07 INFO - PROCESS | 5164 | Cycle 1(1): loaded http://localhost:52157/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52157/tests/dromaeo/cssquery-mootools.html) 22:34:08 INFO - PROCESS | 5164 | RSS: Main: 166445056 22:34:08 INFO - PROCESS | 5164 | 22:37:10 INFO - PROCESS | 5164 | Cycle 1(1): loaded http://localhost:52157/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52157/tests/dromaeo/cssquery-prototype.html) 22:37:10 INFO - PROCESS | 5164 | RSS: Main: 164085760 22:37:10 INFO - PROCESS | 5164 | 22:40:13 INFO - PROCESS | 5164 | Cycle 1(1): loaded http://localhost:52157/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52157/tests/dromaeo/cssquery-yui.html) 22:40:14 INFO - PROCESS | 5164 | RSS: Main: 165695488 22:40:14 INFO - PROCESS | 5164 | 22:43:17 INFO - PROCESS | 5164 | Cycle 1(1): loaded http://localhost:52157/tests/dromaeo/cssquery-yui.html (next: http://localhost:52157/tests/dromaeo/cssquery-dojo.html) 22:43:17 INFO - PROCESS | 5164 | RSS: Main: 166563840 22:43:17 INFO - PROCESS | 5164 | 22:43:17 INFO - PROCESS | 5164 | __start_tp_report 22:43:17 INFO - PROCESS | 5164 | _x_x_mozilla_page_load 22:43:17 INFO - PROCESS | 5164 | _x_x_mozilla_page_load_details 22:43:17 INFO - PROCESS | 5164 | |i|pagename|runs| 22:43:17 INFO - PROCESS | 5164 | |0;dojo.html;3040.9239769005776;3049.4358543669437;3055.923601909952;3058.2660161561216;3065.2949821541047;5206.661566998143;5270.235815806711;5328.626996110269;5351.652142610727;5374.785008599656;5248.921266181011;5303.496167864057;5306.548943339821;5324.680519168853;5342.519173274404;4845.394325709278;4883.145449546315;4901.313816065754;4937.086638971802;4952.579030782388;4872.342233798445;4946.5795407390415;4957.3059771632;4997.900041999158;5020.748962551857;4983.252512123175;5002.849914502571;5006.148954677696;5021.246812978046;5034.3455350804525;4711.057788442291;4810.879728006793;4814.494478079786;4819.783109760069;4828.324034635154;4801.5438533339275;4803.447603525605;4816.56650901417;4898.289747986522;4908.7300198489065;5367.570594352443;5404.891902161974;5406.513413792738;5421.918671219935;5423.29497165366;5584.609077364546;5636.408177141382;5645.294338207724;5762.962666719989;5773.769049238025;5666.178404131378;5668.348139963916;5676.205331253628;5678.886422271573;5678.914816277759;5294.629375943686;5306.46935306474;5309.070912590281;5316.601254905859;5349.063913815066;5613.971930140405;5754.856128596819;5768.884622307531;5801.361850196475;5841.415858414124;5603.7758489659955;5607.551395888319;5618.775248989995;5622.0161471742285;5622.437756224426;5679.716014199274;5682.971585142048;5693.0891057431445;5697.373288938214;5716.399778023331;5445.53712934405;5565.2486914266865;5614.9157762633595;5630.268065151586;5702.401247868914;5407.513323800877;5606.55147588192;5636.2391077204875;5655.462731040545;5686.630369026;4305.547917468683;4307.763073031013;4309.310510318334;4327.675424343124;4346.065595896897;3733.51464309638;3794.449804778347;3816.790076545761;3841.4621952926614;3892.474515940312;5399.595030372738;5427.83716488506;5431.918521222185;5455.099908515051;5462.016836969304;5667.093265077568;5709.857253568611;5733.684647344436;5742.655440673573;5750.6262092963825;5272;5383.031054410166;5428.6471379361;5454.209139674805;5482.725863706878;5001.924971125436;5007.4491805901325;5007.724575148329;5042.445331013586;5053.090443720165;6004.639721616717;6016.789412370546;6032.336442991267;6032.638041717423;6039.939600603938;5557.916631250454;5575.884823035328;5637.915431268616;5771.047777116729;5780.682062486436;5942.970285148546;5947.732352044082;5949.940500594938;5971.104334349709;6003.609765365237;5549.084401073777;5589.776408943597;5593.692346920959;5597.216389705363;5611.971940140274;5464.64479808827;5542.889142217053;5586.63686860369;5686.886262274814;5711.3431955324;5471.863203419946;5474.260974768515;5495.560355171497;5514.531264842533;5527.088030474926;5447.155690867922;5457.072297709478;5458.235846981346;5478.808241711521;5494.340679118372;5296.867578310573;5396.784128634968;5417.945820541901;5448.073827449422;5461.9453805461435;5590.217369568182;5595.0208713475795;5606.439356064524;5608.355039170548;5610.13042978339;5390.541803946551;5426.755795989269;5442.455754424526;5445.918311225414;5541 22:43:17 INFO - PROCESS | 5164 | |1;ext.html;536.4313827343017;573.4638113364005;582.3506789929229;582.3564960718403;596.8328867916985;42876.78561607192;44443.33334999974;44634;44648.33027504586;48245;46003;46376.30435543461;46424;46435.76782116093;46492.302615460794;9015.774605634862;9113.908860911371;9167.358284920057;9172.08279172084;9179.49512776797;9071.092890710943;9083.22792562633;9085.000649928503;9117.908820911805;9120.726378208665;9097.545122743837;9134.634614615408;9138;9144.308302963973;9151.862722059175;9093.318001149908;9133.862992055125;9137.360384773068;9142.131497507726;9147.54262286883;8916;8925.776855578597;8974.551272436418;9007.234385077276;9019.143181397756;42267.57732422667;42571.787141064095;42785;42843.35734963978;42844.143117137486;3668.394714872043;3780.981095094507;3786.9621303786885;3833.156705524808;4051.0480037191237;32447.675523244703;32647.510287345707;32919.34161316784;33171.83414082914;33991.660083399096;4279.40088387626;4312.1160162167;4318.9004259054245;4380.448194881161;4521.84173553924;369744;370354.9999999973;370425;370878;373028.4045739316;58869;58920;59219.40780592225;59264.70367648134;59332.406675933555;36244.637553624125;37290.44064339037;37312.06719831972;61375.69312153411;61430.54277828837;15501;15613.141277229633;15723.606909827346;15763.448279310398;15785.447509337118;11452.255603385753;11500.367479788525;11510.36692981894;11523;11537.250078744855;19304;19327.80672193288;19365.12856921442;19427.4171774847;19447.902760486108;7176.533525320838;7191.316824901626;7232.385247253937;7240.710371585183;7438.256174382518;949.3876449689911;966.1980556138388;1001.3691374434205;1052.4422056310168;1079.5681727308981;33849.32301353959;34035.82982085074;34173.82913085468;34311.82844085764;34681.65318346835;1113.8050841102774;1132.1848269246295;1158.4439469054732;1198.598469512723;1201.6094769200045;16345.673086538203;17328.628125281204;17382.130893455276;17597.296108155788;17726.556836078944;555358;560076;565066;566761;568442;59889;59963.70018149881;60055.699721501114;60072.69963650241;60200.69899650561;1268.5940499040219;1331.7669407853587;1331.9067665263337;1340.3499302838115;1357.8981576381811;30665.846670766503;31982.36035279328;32451.35097298088;32735.83632081824;33183.50224746679;52257.47742522526;52612.47387526076;52615.21077183769;52705;52731.73634131805;359312;360064;439843;440501;442969;28865.567016495188;29394.265143371587;29813.869543697598;30090;30104.78533005425;45708.31437528506;46490;47547.76226118847;47598.52401475941;47806.28290575575;39558;40299.3955090668;40954.79522602487;41384.172316554104;41721.79139104285;40126.1974760509;40162.39756403715;40272;40653.39019914763;41215.587844121175;39852.402213966234;42050;43103;43402.565974341116;45703.77148114371;33396.66603333935;37672.811635941645;38321.61678383292;38431.423528646534;39319.41020884746;29132.126036218946;29168.854155729085;29266.7073329264;29617.851910740308;30030.24924376908 22:43:17 INFO - PROCESS | 5164 | |2;jquery.html;386.1080903113809;390.7225869632559;404.13313442665475;406.06807377069623;425.12212281638415;16071.437499687514;18085.728714069282;19506.80493195071;19774.901125494387;19901.3034543791;12701.93649031756;13036.73926521469;13054.151480153807;17245.310187592513;19163.041847907618;5309.73451327434;5449.56403487722;5508.9724551377185;5510.614257002011;5512.6968016759065;5317.574594032488;5419.105847535152;5428.4842939920845;5433.918491222635;5434.728263586825;5308.867278318035;5393.1101368274185;5396.64921780085;5417.377001644822;5466.808661696841;5254.474552544753;5365.812196573121;5395.136778115522;5403.540699040586;5404.648697834628;5074.949250507485;5206.088934436459;5209.973950130225;5248.107821670325;5263.105272103713;13703.657408564766;14618.488352907703;14624.488142915054;14639.70720585593;20452.693209601868;2254.6730724044924;2414.082648593541;2472.43134079161;2499.3751562109474;2962.525995840677;10113.797724045478;10360.740981475523;10637.680869573926;10661.78676426467;10761.62334318303;1908.8282054615152;1919.1555715485142;2012.255465477768;2019.919203231869;2023.716679664848;79981.60009199916;84482;84598.5770071152;84745.57627211887;85621.57189214077;11053.28153670009;11217.270877392943;11306.265092768943;11475.483603237873;11564.653060408202;15123.924380378028;15530.844691553166;15569.377224910877;15611.687766244839;15784.605384865241;7575.507592006502;7651.961740191264;7763.767086987399;7782.766517004499;7841.411894107965;7177.461690373243;7213.747518836815;7234.674439650228;7446;7474.701011959461;4702.224133018014;4741.668083234141;4820.650217938983;4873.171560834667;4972.801087956514;1857.322077441724;1903.5907279934881;2015.8588898776945;2090.341542414135;2094.1937354118772;796.8366484870587;995.5669283861544;997.546116516983;999.260547195085;1047.4919663962971;20002;20168.294109706385;20248.392548223575;20275.797242027686;20338;940.8118376324762;984.6258421799813;1027.712240572641;1043.9112675422675;1044.3525014491065;9069.682561110329;11392.886071139348;11526.654200374001;11549.36478493674;11553.595624153115;413380.79572837986;414319;414828;443679;458009;87631.56184219166;89661;90766.54616726874;91915;92085.53957230171;952.7094236258008;964.4165280005636;982.3271059324386;983.1201075037801;988.6935050134481;17035.31858725637;17455.912720436318;18210;18248.817511824713;18388;33839.492407614394;40906;41250.1749965005;41661.58338416577;42337.57662423336;52972;56585.151222732515;56801.431985679614;57294.14058789205;57805.421945780006;8624.655013799378;8833.558322083998;8864.822703545764;9016.2786977043;9075.455472671661;9799.314048016571;12924.289164096064;13104.737905242033;13331.66670833237;13685.931570342484;5495.093309603871;6885.690143943434;11556.711082222675;11642.301461912313;11921.569411670655;11613.883861161617;11624;11848.28910265387;11848.466818992994;12310.938445307716;11497.367644779617;11623;11698.35659038761;12133.332666703416;12412.62762117138;8675.609597568251;8706.956465217887;8746.413970272159;8882.555872206234;9391.906080939103;9180.403273787182;11387.601433949772;11464.656060318204;11692.35692036946;11754.588589399329 22:43:17 INFO - PROCESS | 5164 | |3;mootools.html;1519.0354125130539;1529.380600856653;1706;1763.5591102224444;1834.3671433355496;7931.682732690685;8597.65609375625;8716.389852710312;9297.860532092016;9324.72025839225;8825;9150.359474836763;9160.221381182591;9180.357374983754;9181.2654987601;2393.3657580741074;2417.601095819188;2424.9393765155924;2425.805935525154;2428.1986944308337;2367.7040369953756;2401.4836810085826;2421.9394515137174;2437.70747510299;2439.0487709793197;2412.300432874464;2417.987910060447;2437.5368679950843;2443.022790883643;2443.4135807406183;2356.0929042318658;2371.9288421347387;2372.822038347122;2373.299876536426;2404.3989002749313;2346.307839187427;2357.7170739511366;2363.9645405318934;2366.857988520694;2367.3844800351862;9181.678641247525;9204.355695101345;9238.676646317414;9259.212966897821;9296.767580810467;5308.681479111227;5319.202119682079;5372.489613486712;5375.193720941851;5384.380796208447;3521.5421995140723;3621.167131559756;3692.833822477995;3736.962630373716;3745.662890339855;1100.6312885183472;1108.0692218536467;1211.715246916974;1212.078820096724;1221.822835688829;12670.303133327663;12814.231146131262;12906.354682265943;12963.67590810228;12972.675683107904;4380.255356589388;4480.103979204172;4650.046740418274;4650.348951146842;4660.091282200006;9996.300258981946;10042.397456152654;10103.090721834924;10112.393256404639;10115.39307641544;5374.435684253097;5378.2739330189925;5378.650387724785;5386.973065134649;5390;4465.486469056035;4860.076585448754;4966.850994470172;5005.474425185376;5025.020121076428;3785.716071294664;3899.6100389960775;3930.6462418382484;3967.2263908537557;4000.319945609195;1133.2237417369129;1138.2203190814319;1143.1426430177366;1145.387217838469;1153.9538418463335;940.8071345374182;986.0829428631298;986.3490096536395;1012.3268026762267;1019.0115587879744;6107.114468402056;6113.602615829956;6147.569670123138;6207.534434917399;6402.103705481236;985.1822986920838;985.4136788610765;999.715081201854;1019.9847002294972;1050.5167622893402;4046.109855831712;4071.572484889103;4181.226473102425;4222.472190976128;4259.23333799919;10748.946255268673;11036.392998385167;11072.612458564072;11077.390743509184;11084.279521831055;10798.190135739851;10843.51204195813;10937.4531273436;11001.504932278067;11039.613613523648;8277.420580559303;8338.416310858182;8417.747467575982;8443.915560844436;8482.660693572188;6001.459868611846;6112.969435152796;6114.938850611615;6116.785912493041;6160.815175544741;10128.84806727885;10141.239407044353;10148.543315550965;10260.230482713678;10363.948180259353;10225.744356390853;10388.688339349832;10636.468176591294;10794.406307653155;10802.513886874953;7303.634818259172;7571.621418929141;7607.771766847003;7767.805804854924;7779.299863012356;9132.954335228282;9191.11582125676;9206.539673016456;9214.216791572791;9215.032421595904;8015.559144246889;8095.797605059921;8124.146964568871;8208.917910820815;8344.956880389951;7918.208179182036;7968.203179681986;8029.076656184614;8087;8142.552159631277;7039.718411263697;7306.123265208209;7326.120865495961;7426.554406735613;7439.776806695808;5070.4929507049;5120.462351453044;5221.451747566451;5293.682379057269;5323.866903327293;5439.48324909133;5442.12925931849;5466.125419932791;5497.615166938276;5568.944310557004 22:43:17 INFO - PROCESS | 5164 | |4;prototype.html;325.4548631043004;336.94777309517025;340.9488576713493;341.9111031131907;346.34886413542534;5424.5117939385445;5440.646357986728;5592.804251851186;5665.405132461094;5683.403242659518;5479.205515200293;5493.615446918718;5530.949119667261;5568.3596386415575;5572.38703742589;4368.825246990133;4369.694121411495;4376.102898905717;4388.473383194005;4388.539203383647;4088.284550203717;4281.892952676177;4282.272013757653;4363.498197707257;4389.934150987738;4251.829926802924;4254.723442976197;4257.6168144867115;4262.849030761708;4275.636570891478;4253.29820579604;4272.423222864904;4290.849820256276;4303.419038429804;4308.763018034007;3607.531020967284;3767.17122233111;3771.7925514096714;3780;3780.9054773630605;5339.946600534023;5362.195670649395;5365.624406291561;5378.650387724824;5398.78404863805;4875.975620121877;4882.584980276681;4930.506949305077;4934.790976210816;4939.5554400104165;5503.449655034505;5790.449907258803;5818.1563673267565;5818.505427038707;5821.4385670305355;4082.5713300103357;4092.3247664135383;4092.5907409259135;4095.0139182496464;4100.200460910124;6025.3372129065765;6027.367126451703;6040.57715959883;6045.909311360333;6092.842359951595;4162.146759914211;4168.915542228886;4216.3464662977285;4257.340112282601;4271.928114864289;4286.2284788738325;4296.591823776736;4310.52584215736;4312.310030395121;4314.482262128502;4915.139850526144;4918.458969513351;4933.925991110136;4936.160852655057;4957.90084198314;5516.91724624131;5798.797042103506;5873.353931067579;5893.6463812171405;5907.438793314628;1467.2956980649349;1485.0273071138422;1485.9331330090172;1486.7472529669765;1487.0928733472574;915.3821170709745;942.854431865287;952.9237660987104;964.2334344196381;967.1102585621245;4940.505949405031;4945.258211268267;4966.726830024311;4969.602431805447;5002.674826136289;5197.454267301954;5240.3973543042275;5274.815381461631;5358.9368093284365;5366.356037275474;4733.479317275097;4872.6832755870755;4893.363862697826;4942.8764280893265;5020.59835213182;2762.6822915364587;2777.916662500128;2786.7352601502826;2793.3156376687434;2803.5934789455823;5431.511163995259;5445.727713614304;5458.426865179178;5472.315960504937;5491.2312276281355;5160.92258616121;5262.657927234717;5283.762230699628;5311.787528498894;5362.678239305654;5421.701806400607;5467.57842960833;5469.589780766459;5470.234167216593;5543.002259593312;5254.211868219798;5260.368755749335;5300.787968481218;5326.680399176062;5374;4828.2998965150555;5119.411267704261;5139.717315547531;5191.299174611379;5196.714180720097;5443.727813609383;5473.233747275305;5480.862978425571;5570.498655121059;5597.244372009726;4051.3058815888285;4088.427620133243;4177.70756047086;4180.352045432963;4204.978975105105;5925.881482370414;5939.940600593939;5947.256592925884;5965.70171491415;6020.608660437058;5679.290088738908;5702.37273899879;5778.8266352009505;5791.739371728367;5851.824445266649;5676.460736230051;5702.258706368146;5728.914066289092;5737.426257374396;5763.5965482417505;5666.34836993751;5730.226419433323;5778.179873339885;5785.508231800344;5812.73842677089;5548.805791797267;5707.601637598815;5744.827655170351;5772.365039845529;5813.534917206529;5659.547236221175;5692.003899317686;5719.857003574777;5723.399043100415;5733.827985160452 22:43:17 INFO - PROCESS | 5164 | |5;yui.html;723.5007844587235;745.9254074592538;747.8728616135256;786.5713186313459;797.0395673213775;13393.464261429543;14691.412343506261;14699.485518006872;14735.410583576671;14738.778918316233;15328.693426131498;15440.84559154408;15465.690686186297;15498.302576384061;15524.84475155248;644.1272076336567;772.8492943875947;779.4115442840647;795.6379847169553;796.2276591706036;792.5165648954162;808.1635507249997;808.6482380164647;808.8907997420362;809.4778867630375;848.8344772769312;852.377764232111;853.4154104438448;854.0690647194557;854.3549620036863;849.150849150846;850.0437008365589;851.0553285852699;852.9488230706115;853.2278288149204;618.712298781069;624.6002558362652;624.6470744029654;626.3892704613012;628.1833616298838;14117;14136.575902722934;14150.929245353707;14152.080114792609;14190.574282771533;15477.22613869302;15613.297401616954;15644.061356318543;15786.842131578767;15955.920220398939;12198.268103913704;12294.508219671203;12339.938300308531;12451.688707782378;12541.686457838536;3983.601639836022;3992.4210989406665;4124.13393187431;4157.729747566398;4163.937540936889;86970.13029869746;87764.56117719434;89995.80033597362;92638;95219;12073.939630301793;12104.213226140273;12136.211146275464;12164.939175304067;12199.93900030512;23736.881315593313;23757.16849910245;23850.284491465285;23864.522709545712;23922.88038559796;8889.955550222208;9007.644993400876;9171.724848254564;9420;9428.764280893032;4927.90144197114;5018.447970723218;5048.671836330626;5118.769655365518;5221.086309895829;3567.6789088981595;3623.293457775761;3628.1111127773866;3658.2683463307444;3661.3043521730788;645.602954183174;663.9302873198342;700.5866538742166;714.1465948191861;717.1967396515831;664.5149041199951;765.9885101723479;772.6291380137566;787.2875048081495;787.3858390455454;9996.400215987063;10093.809190019543;10513.158947284199;10884.292520986111;11004.213167272263;562.3055526424862;687.4466054825913;744.430510659346;760.2473551184288;771.6488997506121;5024.271480635361;5024.572911302507;5052.747362631853;5090.541851233407;5122.871928201825;109661.45169274103;117201;122045;125654;129750.35124824315;90719;114380;116282;117617;121613;81971.5901420501;91757;93264.53367733254;100418.49790751;104158;9816.705498835046;10079.445630490394;10143.391396516232;10213.438260895722;10214.285000049926;55008.44991550194;55795;56344;57199.14201286901;58820.294376448925;70223;72360.27639723747;81095.59452202701;82749;83330;14423.855761442672;14572;14602.546223401965;14998.400063997318;15009.849901500846;15526.611834704223;15719.842801572295;15951.361945522049;16207.351705931631;16235.756463653292;15114;15377.047714162314;15418.614534636275;15575.299111540235;15648;15117.924410377878;15499.457518986781;15996.280167392728;15999.48224962536;16179.271932762818;14683.192424416333;14906.25468726581;15229.466968656045;15343.769843452134;15667.509808998495;11561.479733411848;12278.81581776292;12310.26138431697;12321.383930803604;12448.25310481374;11395.943020285176;11511.827322590001;11564.768704625692;12109.212901161396;13389.464421423034 22:43:17 INFO - PROCESS | 5164 | __end_tp_report 22:43:17 INFO - PROCESS | 5164 | __start_cc_report 22:43:17 INFO - PROCESS | 5164 | _x_x_mozilla_cycle_collect,324 22:43:17 INFO - PROCESS | 5164 | __end_cc_report 22:43:17 INFO - PROCESS | 5164 | __startTimestamp1471412597530__endTimestamp 22:43:17 INFO - PROCESS | 5164 | 22:43:17 INFO - PROCESS | 5164 | ------- Summary: start ------- 22:43:17 INFO - PROCESS | 5164 | Number of tests: 6 22:43:17 INFO - PROCESS | 5164 | 22:43:17 INFO - PROCESS | 5164 | [#0] dojo.html Cycles:165 Average:5287.49 Median:5454.21 stddev:599.21 (11.0%) stddev-sans-first:574.55 22:43:17 INFO - PROCESS | 5164 | Values: 3040.9 3049.4 3055.9 3058.3 3065.3 5206.7 5270.2 5328.6 5351.7 5374.8 5248.9 5303.5 5306.5 5324.7 5342.5 4845.4 4883.1 4901.3 4937.1 4952.6 4872.3 4946.6 4957.3 4997.9 5020.7 4983.3 5002.8 5006.1 5021.2 5034.3 4711.1 4810.9 4814.5 4819.8 4828.3 4801.5 4803.4 4816.6 4898.3 4908.7 5367.6 5404.9 5406.5 5421.9 5423.3 5584.6 5636.4 5645.3 5763.0 5773.8 5666.2 5668.3 5676.2 5678.9 5678.9 5294.6 5306.5 5309.1 5316.6 5349.1 5614.0 5754.9 5768.9 5801.4 5841.4 5603.8 5607.6 5618.8 5622.0 5622.4 5679.7 5683.0 5693.1 5697.4 5716.4 5445.5 5565.2 5614.9 5630.3 5702.4 5407.5 5606.6 5636.2 5655.5 5686.6 4305.5 4307.8 4309.3 4327.7 4346.1 3733.5 3794.4 3816.8 3841.5 3892.5 5399.6 5427.8 5431.9 5455.1 5462.0 5667.1 5709.9 5733.7 5742.7 5750.6 5272.0 5383.0 5428.6 5454.2 5482.7 5001.9 5007.4 5007.7 5042.4 5053.1 6004.6 6016.8 6032.3 6032.6 6039.9 5557.9 5575.9 5637.9 5771.0 5780.7 5943.0 5947.7 5949.9 5971.1 6003.6 5549.1 5589.8 5593.7 5597.2 5612.0 5464.6 5542.9 5586.6 5686.9 5711.3 5471.9 5474.3 5495.6 5514.5 5527.1 5447.2 5457.1 5458.2 5478.8 5494.3 5296.9 5396.8 5417.9 5448.1 5461.9 5590.2 5595.0 5606.4 5608.4 5610.1 5390.5 5426.8 5442.5 5445.9 5541.0 22:43:17 INFO - PROCESS | 5164 | 22:43:17 INFO - PROCESS | 5164 | [#1] ext.html Cycles:180 Average:60858.79 Median:42419.68 stddev:120915.22 (285.0%) stddev-sans-first:121169.60 22:43:17 INFO - PROCESS | 5164 | Values: 536.4 573.5 582.4 582.4 596.8 42876.8 44443.3 44634.0 44648.3 48245.0 46003.0 46376.3 46424.0 46435.8 46492.3 9015.8 9113.9 9167.4 9172.1 9179.5 9071.1 9083.2 9085.0 9117.9 9120.7 9097.5 9134.6 9138.0 9144.3 9151.9 9093.3 9133.9 9137.4 9142.1 9147.5 8916.0 8925.8 8974.6 9007.2 9019.1 42267.6 42571.8 42785.0 42843.4 42844.1 3668.4 3781.0 3787.0 3833.2 4051.0 32447.7 32647.5 32919.3 33171.8 33991.7 4279.4 4312.1 4318.9 4380.4 4521.8 369744.0 370355.0 370425.0 370878.0 373028.4 58869.0 58920.0 59219.4 59264.7 59332.4 36244.6 37290.4 37312.1 61375.7 61430.5 15501.0 15613.1 15723.6 15763.4 15785.4 11452.3 11500.4 11510.4 11523.0 11537.3 19304.0 19327.8 19365.1 19427.4 19447.9 7176.5 7191.3 7232.4 7240.7 7438.3 949.4 966.2 1001.4 1052.4 1079.6 33849.3 34035.8 34173.8 34311.8 34681.7 1113.8 1132.2 1158.4 1198.6 1201.6 16345.7 17328.6 17382.1 17597.3 17726.6 555358.0 560076.0 565066.0 566761.0 568442.0 59889.0 59963.7 60055.7 60072.7 60200.7 1268.6 1331.8 1331.9 1340.3 1357.9 30665.8 31982.4 32451.4 32735.8 33183.5 52257.5 52612.5 52615.2 52705.0 52731.7 359312.0 360064.0 439843.0 440501.0 442969.0 28865.6 29394.3 29813.9 30090.0 30104.8 45708.3 46490.0 47547.8 47598.5 47806.3 39558.0 40299.4 40954.8 41384.2 41721.8 40126.2 40162.4 40272.0 40653.4 41215.6 39852.4 42050.0 43103.0 43402.6 45703.8 33396.7 37672.8 38321.6 38431.4 39319.4 29132.1 29168.9 29266.7 29617.9 30030.2 22:43:17 INFO - PROCESS | 5164 | 22:43:17 INFO - PROCESS | 5164 | [#2] jquery.html Cycles:180 Average:26843.59 Median:227315.49 stddev:71220.52 (31.3%) stddev-sans-first:71392.61 22:43:17 INFO - PROCESS | 5164 | Values: 386.1 390.7 404.1 406.1 425.1 16071.4 18085.7 19506.8 19774.9 19901.3 12701.9 13036.7 13054.2 17245.3 19163.0 5309.7 5449.6 5509.0 5510.6 5512.7 5317.6 5419.1 5428.5 5433.9 5434.7 5308.9 5393.1 5396.6 5417.4 5466.8 5254.5 5365.8 5395.1 5403.5 5404.6 5074.9 5206.1 5210.0 5248.1 5263.1 13703.7 14618.5 14624.5 14639.7 20452.7 2254.7 2414.1 2472.4 2499.4 2962.5 10113.8 10360.7 10637.7 10661.8 10761.6 1908.8 1919.2 2012.3 2019.9 2023.7 79981.6 84482.0 84598.6 84745.6 85621.6 11053.3 11217.3 11306.3 11475.5 11564.7 15123.9 15530.8 15569.4 15611.7 15784.6 7575.5 7652.0 7763.8 7782.8 7841.4 7177.5 7213.7 7234.7 7446.0 7474.7 4702.2 4741.7 4820.7 4873.2 4972.8 1857.3 1903.6 2015.9 2090.3 2094.2 796.8 995.6 997.5 999.3 1047.5 20002.0 20168.3 20248.4 20275.8 20338.0 940.8 984.6 1027.7 1043.9 1044.4 9069.7 11392.9 11526.7 11549.4 11553.6 413380.8 414319.0 414828.0 443679.0 458009.0 87631.6 89661.0 90766.5 91915.0 92085.5 952.7 964.4 982.3 983.1 988.7 17035.3 17455.9 18210.0 18248.8 18388.0 33839.5 40906.0 41250.2 41661.6 42337.6 52972.0 56585.2 56801.4 57294.1 57805.4 8624.7 8833.6 8864.8 9016.3 9075.5 9799.3 12924.3 13104.7 13331.7 13685.9 5495.1 6885.7 11556.7 11642.3 11921.6 11613.9 11624.0 11848.3 11848.5 12310.9 11497.4 11623.0 11698.4 12133.3 12412.6 8675.6 8707.0 8746.4 8882.6 9391.9 9180.4 11387.6 11464.7 11692.4 11754.6 22:43:17 INFO - PROCESS | 5164 | 22:43:17 INFO - PROCESS | 5164 | [#3] mootools.html Cycles:180 Average:5952.88 Median:4565.08 stddev:3374.49 (73.9%) stddev-sans-first:3367.50 22:43:17 INFO - PROCESS | 5164 | Values: 1519.0 1529.4 1706.0 1763.6 1834.4 7931.7 8597.7 8716.4 9297.9 9324.7 8825.0 9150.4 9160.2 9180.4 9181.3 2393.4 2417.6 2424.9 2425.8 2428.2 2367.7 2401.5 2421.9 2437.7 2439.0 2412.3 2418.0 2437.5 2443.0 2443.4 2356.1 2371.9 2372.8 2373.3 2404.4 2346.3 2357.7 2364.0 2366.9 2367.4 9181.7 9204.4 9238.7 9259.2 9296.8 5308.7 5319.2 5372.5 5375.2 5384.4 3521.5 3621.2 3692.8 3737.0 3745.7 1100.6 1108.1 1211.7 1212.1 1221.8 12670.3 12814.2 12906.4 12963.7 12972.7 4380.3 4480.1 4650.0 4650.3 4660.1 9996.3 10042.4 10103.1 10112.4 10115.4 5374.4 5378.3 5378.7 5387.0 5390.0 4465.5 4860.1 4966.9 5005.5 5025.0 3785.7 3899.6 3930.6 3967.2 4000.3 1133.2 1138.2 1143.1 1145.4 1154.0 940.8 986.1 986.3 1012.3 1019.0 6107.1 6113.6 6147.6 6207.5 6402.1 985.2 985.4 999.7 1020.0 1050.5 4046.1 4071.6 4181.2 4222.5 4259.2 10748.9 11036.4 11072.6 11077.4 11084.3 10798.2 10843.5 10937.5 11001.5 11039.6 8277.4 8338.4 8417.7 8443.9 8482.7 6001.5 6113.0 6114.9 6116.8 6160.8 10128.8 10141.2 10148.5 10260.2 10363.9 10225.7 10388.7 10636.5 10794.4 10802.5 7303.6 7571.6 7607.8 7767.8 7779.3 9133.0 9191.1 9206.5 9214.2 9215.0 8015.6 8095.8 8124.1 8208.9 8345.0 7918.2 7968.2 8029.1 8087.0 8142.6 7039.7 7306.1 7326.1 7426.6 7439.8 5070.5 5120.5 5221.5 5293.7 5323.9 5439.5 5442.1 5466.1 5497.6 5568.9 22:43:17 INFO - PROCESS | 5164 | 22:43:17 INFO - PROCESS | 5164 | [#4] prototype.html Cycles:180 Average:4695.56 Median:5268.74 stddev:1358.26 (25.8%) stddev-sans-first:1321.87 22:43:17 INFO - PROCESS | 5164 | Values: 325.5 336.9 340.9 341.9 346.3 5424.5 5440.6 5592.8 5665.4 5683.4 5479.2 5493.6 5530.9 5568.4 5572.4 4368.8 4369.7 4376.1 4388.5 4388.5 4088.3 4281.9 4282.3 4363.5 4389.9 4251.8 4254.7 4257.6 4262.8 4275.6 4253.3 4272.4 4290.8 4303.4 4308.8 3607.5 3767.2 3771.8 3780.0 3780.9 5339.9 5362.2 5365.6 5378.7 5398.8 4876.0 4882.6 4930.5 4934.8 4939.6 5503.4 5790.4 5818.2 5818.5 5821.4 4082.6 4092.3 4092.6 4095.0 4100.2 6025.3 6027.4 6040.6 6045.9 6092.8 4162.1 4168.9 4216.3 4257.3 4271.9 4286.2 4296.6 4310.5 4312.3 4314.5 4915.1 4918.5 4933.9 4936.2 4957.9 5516.9 5798.8 5873.4 5893.6 5907.4 1467.3 1485.0 1485.9 1486.7 1487.1 915.4 942.9 952.9 964.2 967.1 4940.5 4945.3 4966.7 4969.6 5002.7 5197.5 5240.4 5274.8 5358.9 5366.4 4733.5 4872.7 4893.4 4942.9 5020.6 2762.7 2777.9 2786.7 2793.3 2803.6 5431.5 5445.7 5458.4 5472.3 5491.2 5160.9 5262.7 5283.8 5311.8 5362.7 5421.7 5467.6 5469.6 5470.2 5543.0 5254.2 5260.4 5300.8 5326.7 5374.0 4828.3 5119.4 5139.7 5191.3 5196.7 5443.7 5473.2 5480.9 5570.5 5597.2 4051.3 4088.4 4177.7 4180.4 4205.0 5925.9 5939.9 5947.3 5965.7 6020.6 5679.3 5702.4 5778.8 5791.7 5851.8 5676.5 5702.3 5728.9 5737.4 5763.6 5666.3 5730.2 5778.2 5785.5 5812.7 5548.8 5707.6 5744.8 5772.4 5813.5 5659.5 5692.0 5719.9 5723.4 5733.8 22:43:17 INFO - PROCESS | 5164 | 22:43:17 INFO - PROCESS | 5164 | [#5] yui.html Cycles:180 Average:22668.72 Median:3625.70 stddev:32983.28 (909.7%) stddev-sans-first:33034.65 22:43:17 INFO - PROCESS | 5164 | Values: 723.5 745.9 747.9 786.6 797.0 13393.5 14691.4 14699.5 14735.4 14738.8 15328.7 15440.8 15465.7 15498.3 15524.8 644.1 772.8 779.4 795.6 796.2 792.5 808.2 808.6 808.9 809.5 848.8 852.4 853.4 854.1 854.4 849.2 850.0 851.1 852.9 853.2 618.7 624.6 624.6 626.4 628.2 14117.0 14136.6 14150.9 14152.1 14190.6 15477.2 15613.3 15644.1 15786.8 15955.9 12198.3 12294.5 12339.9 12451.7 12541.7 3983.6 3992.4 4124.1 4157.7 4163.9 86970.1 87764.6 89995.8 92638.0 95219.0 12073.9 12104.2 12136.2 12164.9 12199.9 23736.9 23757.2 23850.3 23864.5 23922.9 8890.0 9007.6 9171.7 9420.0 9428.8 4927.9 5018.4 5048.7 5118.8 5221.1 3567.7 3623.3 3628.1 3658.3 3661.3 645.6 663.9 700.6 714.1 717.2 664.5 766.0 772.6 787.3 787.4 9996.4 10093.8 10513.2 10884.3 11004.2 562.3 687.4 744.4 760.2 771.6 5024.3 5024.6 5052.7 5090.5 5122.9 109661.5 117201.0 122045.0 125654.0 129750.4 90719.0 114380.0 116282.0 117617.0 121613.0 81971.6 91757.0 93264.5 100418.5 104158.0 9816.7 10079.4 10143.4 10213.4 10214.3 55008.4 55795.0 56344.0 57199.1 58820.3 70223.0 72360.3 81095.6 82749.0 83330.0 14423.9 14572.0 14602.5 14998.4 15009.8 15526.6 15719.8 15951.4 16207.4 16235.8 15114.0 15377.0 15418.6 15575.3 15648.0 15117.9 15499.5 15996.3 15999.5 16179.3 14683.2 14906.3 15229.5 15343.8 15667.5 11561.5 12278.8 12310.3 12321.4 12448.3 11395.9 11511.8 11564.8 12109.2 13389.5 22:43:17 INFO - PROCESS | 5164 | -------- Summary: end -------- 22:43:17 INFO - PROCESS | 5164 | 22:43:18 INFO - TEST-INFO | 5164: exit 0 22:43:18 INFO - TEST-OK | dromaeo_css | took 1114600ms 22:43:18 INFO - TEST-START | kraken 22:43:18 INFO - operating with platform_type : linux_ 22:43:18 INFO - Initialising browser for kraken test... 22:43:18 INFO - TEST-INFO | started process 5528 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK_boW2/profile http://localhost:52157/getInfo.html) 22:43:22 INFO - PROCESS | 5528 | __metrics Screen width/height:1600/1200 22:43:22 INFO - PROCESS | 5528 | colorDepth:24 22:43:22 INFO - PROCESS | 5528 | Browser inner width/height: 1024/697 22:43:22 INFO - PROCESS | 5528 | __metrics 22:43:23 ERROR - PROCESS | 5528 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2983: TypeError: this.tabs is undefined 22:43:24 INFO - TEST-INFO | 5528: exit 0 22:43:24 INFO - Browser initialized. 22:43:24 INFO - Running cycle 1/1 for kraken test... 22:43:24 INFO - TEST-INFO | started process 5648 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK_boW2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:43:35 INFO - PROCESS | 5648 | RSS: Main: 163590144 22:43:35 INFO - PROCESS | 5648 | 22:43:38 INFO - PROCESS | 5648 | Cycle 1(1): loaded http://localhost:52157/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52157/tests/kraken/driver.html?testName=audio-beat-detection) 22:43:39 INFO - PROCESS | 5648 | RSS: Main: 169091072 22:43:39 INFO - PROCESS | 5648 | 22:43:41 INFO - PROCESS | 5648 | Cycle 1(1): loaded http://localhost:52157/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52157/tests/kraken/driver.html?testName=audio-dft) 22:43:42 INFO - PROCESS | 5648 | RSS: Main: 166649856 22:43:42 INFO - PROCESS | 5648 | 22:43:45 INFO - PROCESS | 5648 | Cycle 1(1): loaded http://localhost:52157/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52157/tests/kraken/driver.html?testName=audio-fft) 22:43:46 INFO - PROCESS | 5648 | RSS: Main: 162852864 22:43:46 INFO - PROCESS | 5648 | 22:43:48 INFO - PROCESS | 5648 | Cycle 1(1): loaded http://localhost:52157/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52157/tests/kraken/driver.html?testName=audio-oscillator) 22:43:48 INFO - PROCESS | 5648 | RSS: Main: 164077568 22:43:48 INFO - PROCESS | 5648 | 22:43:51 INFO - PROCESS | 5648 | Cycle 1(1): loaded http://localhost:52157/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52157/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:43:51 INFO - PROCESS | 5648 | RSS: Main: 164372480 22:43:51 INFO - PROCESS | 5648 | 22:43:54 INFO - PROCESS | 5648 | Cycle 1(1): loaded http://localhost:52157/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52157/tests/kraken/driver.html?testName=imaging-darkroom) 22:43:55 INFO - PROCESS | 5648 | RSS: Main: 165019648 22:43:55 INFO - PROCESS | 5648 | 22:43:58 INFO - PROCESS | 5648 | Cycle 1(1): loaded http://localhost:52157/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52157/tests/kraken/driver.html?testName=imaging-desaturate) 22:43:59 INFO - PROCESS | 5648 | RSS: Main: 162291712 22:43:59 INFO - PROCESS | 5648 | 22:44:02 INFO - PROCESS | 5648 | Cycle 1(1): loaded http://localhost:52157/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52157/tests/kraken/driver.html?testName=json-parse-financial) 22:44:03 INFO - PROCESS | 5648 | RSS: Main: 164438016 22:44:03 INFO - PROCESS | 5648 | 22:44:04 INFO - PROCESS | 5648 | Cycle 1(1): loaded http://localhost:52157/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52157/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:44:05 INFO - PROCESS | 5648 | RSS: Main: 163987456 22:44:05 INFO - PROCESS | 5648 | 22:44:07 INFO - PROCESS | 5648 | Cycle 1(1): loaded http://localhost:52157/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52157/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:44:07 INFO - PROCESS | 5648 | RSS: Main: 164339712 22:44:07 INFO - PROCESS | 5648 | 22:44:09 INFO - PROCESS | 5648 | Cycle 1(1): loaded http://localhost:52157/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52157/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:44:10 INFO - PROCESS | 5648 | RSS: Main: 164929536 22:44:10 INFO - PROCESS | 5648 | 22:44:13 INFO - PROCESS | 5648 | Cycle 1(1): loaded http://localhost:52157/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52157/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:44:13 INFO - PROCESS | 5648 | RSS: Main: 164859904 22:44:13 INFO - PROCESS | 5648 | 22:44:16 INFO - PROCESS | 5648 | Cycle 1(1): loaded http://localhost:52157/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52157/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:44:17 INFO - PROCESS | 5648 | RSS: Main: 164933632 22:44:17 INFO - PROCESS | 5648 | 22:44:18 INFO - PROCESS | 5648 | Cycle 1(1): loaded http://localhost:52157/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52157/tests/kraken/driver.html?testName=ai-astar) 22:44:19 INFO - PROCESS | 5648 | RSS: Main: 165961728 22:44:19 INFO - PROCESS | 5648 | 22:44:19 INFO - PROCESS | 5648 | __start_tp_report 22:44:19 INFO - PROCESS | 5648 | _x_x_mozilla_page_load 22:44:19 INFO - PROCESS | 5648 | _x_x_mozilla_page_load_details 22:44:19 INFO - PROCESS | 5648 | |i|pagename|runs| 22:44:19 INFO - PROCESS | 5648 | |0;ai-astar;103;113;109;113;112;112;116;114;112;113 22:44:19 INFO - PROCESS | 5648 | |1;audio-beat-detection;148;118;144;184;118;146;150;119;179;123 22:44:19 INFO - PROCESS | 5648 | |2;audio-dft;163;163;163;161;162;165;170;161;164;161 22:44:19 INFO - PROCESS | 5648 | |3;audio-fft;75;75;77;184;78;76;76;76;162;86 22:44:19 INFO - PROCESS | 5648 | |4;audio-oscillator;85;85;152;91;88;86;142;85;85;160 22:44:19 INFO - PROCESS | 5648 | |5;imaging-gaussian-blur;117;117;118;118;117;118;118;118;117;118 22:44:19 INFO - PROCESS | 5648 | |6;imaging-darkroom;147;147;149;148;146;146;148;147;148;147 22:44:19 INFO - PROCESS | 5648 | |7;imaging-desaturate;98;95;96;96;96;96;96;97;96;96 22:44:19 INFO - PROCESS | 5648 | |8;json-parse-financial;51;52;54;52;53;54;53;52;51;55 22:44:19 INFO - PROCESS | 5648 | |9;json-stringify-tinderbox;61;62;63;64;61;61;63;62;61;63 22:44:19 INFO - PROCESS | 5648 | |10;stanford-crypto-aes;101;86;84;79;85;79;88;80;88;78 22:44:19 INFO - PROCESS | 5648 | |11;stanford-crypto-ccm;128;139;127;137;129;128;133;130;143;128 22:44:19 INFO - PROCESS | 5648 | |12;stanford-crypto-pbkdf2;164;164;165;164;167;168;163;166;170;165 22:44:19 INFO - PROCESS | 5648 | |13;stanford-crypto-sha256-iterative;68;69;67;65;64;71;68;68;64;64 22:44:19 INFO - PROCESS | 5648 | __end_tp_report 22:44:19 INFO - PROCESS | 5648 | __start_cc_report 22:44:19 INFO - PROCESS | 5648 | _x_x_mozilla_cycle_collect,857 22:44:19 INFO - PROCESS | 5648 | __end_cc_report 22:44:19 INFO - PROCESS | 5648 | __startTimestamp1471412659199__endTimestamp 22:44:19 INFO - PROCESS | 5648 | 22:44:19 INFO - PROCESS | 5648 | ------- Summary: start ------- 22:44:19 INFO - PROCESS | 5648 | Number of tests: 14 22:44:19 INFO - PROCESS | 5648 | 22:44:19 INFO - PROCESS | 5648 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:3.53 (3.1%) stddev-sans-first:1.87 22:44:19 INFO - PROCESS | 5648 | Values: 103.0 113.0 109.0 113.0 112.0 112.0 116.0 114.0 112.0 113.0 22:44:19 INFO - PROCESS | 5648 | 22:44:19 INFO - PROCESS | 5648 | [#1] audio-beat-detection Cycles:10 Average:142.90 Median:147.00 stddev:24.24 (16.5%) stddev-sans-first:25.64 22:44:19 INFO - PROCESS | 5648 | Values: 148.0 118.0 144.0 184.0 118.0 146.0 150.0 119.0 179.0 123.0 22:44:19 INFO - PROCESS | 5648 | 22:44:19 INFO - PROCESS | 5648 | [#2] audio-dft Cycles:10 Average:163.30 Median:163.00 stddev:2.71 (1.7%) stddev-sans-first:2.87 22:44:19 INFO - PROCESS | 5648 | Values: 163.0 163.0 163.0 161.0 162.0 165.0 170.0 161.0 164.0 161.0 22:44:19 INFO - PROCESS | 5648 | 22:44:19 INFO - PROCESS | 5648 | [#3] audio-fft Cycles:10 Average:96.50 Median:76.00 stddev:40.78 (53.7%) stddev-sans-first:42.50 22:44:19 INFO - PROCESS | 5648 | Values: 75.0 75.0 77.0 184.0 78.0 76.0 76.0 76.0 162.0 86.0 22:44:19 INFO - PROCESS | 5648 | 22:44:19 INFO - PROCESS | 5648 | [#4] audio-oscillator Cycles:10 Average:105.90 Median:85.00 stddev:31.69 (37.3%) stddev-sans-first:32.70 22:44:19 INFO - PROCESS | 5648 | Values: 85.0 85.0 152.0 91.0 88.0 86.0 142.0 85.0 85.0 160.0 22:44:19 INFO - PROCESS | 5648 | 22:44:19 INFO - PROCESS | 5648 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 22:44:19 INFO - PROCESS | 5648 | Values: 117.0 117.0 118.0 118.0 117.0 118.0 118.0 118.0 117.0 118.0 22:44:19 INFO - PROCESS | 5648 | 22:44:19 INFO - PROCESS | 5648 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.50 stddev:0.95 (0.6%) stddev-sans-first:1.00 22:44:19 INFO - PROCESS | 5648 | Values: 147.0 147.0 149.0 148.0 146.0 146.0 148.0 147.0 148.0 147.0 22:44:19 INFO - PROCESS | 5648 | 22:44:19 INFO - PROCESS | 5648 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.79 (0.8%) stddev-sans-first:0.50 22:44:19 INFO - PROCESS | 5648 | Values: 98.0 95.0 96.0 96.0 96.0 96.0 96.0 97.0 96.0 96.0 22:44:19 INFO - PROCESS | 5648 | 22:44:19 INFO - PROCESS | 5648 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:1.34 (2.5%) stddev-sans-first:1.27 22:44:19 INFO - PROCESS | 5648 | Values: 51.0 52.0 54.0 52.0 53.0 54.0 53.0 52.0 51.0 55.0 22:44:19 INFO - PROCESS | 5648 | 22:44:19 INFO - PROCESS | 5648 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.50 stddev:1.10 (1.8%) stddev-sans-first:1.09 22:44:19 INFO - PROCESS | 5648 | Values: 61.0 62.0 63.0 64.0 61.0 61.0 63.0 62.0 61.0 63.0 22:44:19 INFO - PROCESS | 5648 | 22:44:19 INFO - PROCESS | 5648 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:84.50 stddev:6.84 (8.1%) stddev-sans-first:4.03 22:44:19 INFO - PROCESS | 5648 | Values: 101.0 86.0 84.0 79.0 85.0 79.0 88.0 80.0 88.0 78.0 22:44:19 INFO - PROCESS | 5648 | 22:44:19 INFO - PROCESS | 5648 | [#11] stanford-crypto-ccm Cycles:10 Average:132.20 Median:131.50 stddev:5.59 (4.3%) stddev-sans-first:5.72 22:44:19 INFO - PROCESS | 5648 | Values: 128.0 139.0 127.0 137.0 129.0 128.0 133.0 130.0 143.0 128.0 22:44:19 INFO - PROCESS | 5648 | 22:44:19 INFO - PROCESS | 5648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.60 Median:165.50 stddev:2.17 (1.3%) stddev-sans-first:2.22 22:44:19 INFO - PROCESS | 5648 | Values: 164.0 164.0 165.0 164.0 167.0 168.0 163.0 166.0 170.0 165.0 22:44:19 INFO - PROCESS | 5648 | 22:44:19 INFO - PROCESS | 5648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:68.00 stddev:2.44 (3.6%) stddev-sans-first:2.55 22:44:19 INFO - PROCESS | 5648 | Values: 68.0 69.0 67.0 65.0 64.0 71.0 68.0 68.0 64.0 64.0 22:44:19 INFO - PROCESS | 5648 | -------- Summary: end -------- 22:44:19 INFO - PROCESS | 5648 | 22:44:20 INFO - TEST-INFO | 5648: exit 0 22:44:20 INFO - TEST-OK | kraken | took 61939ms 22:44:20 INFO - SUITE-END | took 1176s 22:44:20 INFO - Completed test suite (00:19:36) 22:44:20 INFO - javascript benchmark 22:44:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7320.802474260797, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3040.9239769005776, 3049.4358543669437, 3055.923601909952, 3058.2660161561216, 3065.2949821541047, 5206.661566998143, 5270.235815806711, 5328.626996110269, 5351.652142610727, 5374.785008599656, 5248.921266181011, 5303.496167864057, 5306.548943339821, 5324.680519168853, 5342.519173274404, 4845.394325709278, 4883.145449546315, 4901.313816065754, 4937.086638971802, 4952.579030782388, 4872.342233798445, 4946.5795407390415, 4957.3059771632, 4997.900041999158, 5020.748962551857, 4983.252512123175, 5002.849914502571, 5006.148954677696, 5021.246812978046, 5034.3455350804525, 4711.057788442291, 4810.879728006793, 4814.494478079786, 4819.783109760069, 4828.324034635154, 4801.5438533339275, 4803.447603525605, 4816.56650901417, 4898.289747986522, 4908.7300198489065, 5367.570594352443, 5404.891902161974, 5406.513413792738, 5421.918671219935, 5423.29497165366, 5584.609077364546, 5636.408177141382, 5645.294338207724, 5762.962666719989, 5773.769049238025, 5666.178404131378, 5668.348139963916, 5676.205331253628, 5678.886422271573, 5678.914816277759, 5294.629375943686, 5306.46935306474, 5309.070912590281, 5316.601254905859, 5349.063913815066, 5613.971930140405, 5754.856128596819, 5768.884622307531, 5801.361850196475, 5841.415858414124, 5603.7758489659955, 5607.551395888319, 5618.775248989995, 5622.0161471742285, 5622.437756224426, 5679.716014199274, 5682.971585142048, 5693.0891057431445, 5697.373288938214, 5716.399778023331, 5445.53712934405, 5565.2486914266865, 5614.9157762633595, 5630.268065151586, 5702.401247868914, 5407.513323800877, 5606.55147588192, 5636.2391077204875, 5655.462731040545, 5686.630369026, 4305.547917468683, 4307.763073031013, 4309.310510318334, 4327.675424343124, 4346.065595896897, 3733.51464309638, 3794.449804778347, 3816.790076545761, 3841.4621952926614, 3892.474515940312, 5399.595030372738, 5427.83716488506, 5431.918521222185, 5455.099908515051, 5462.016836969304, 5667.093265077568, 5709.857253568611, 5733.684647344436, 5742.655440673573, 5750.6262092963825, 5272.0, 5383.031054410166, 5428.6471379361, 5454.209139674805, 5482.725863706878, 5001.924971125436, 5007.4491805901325, 5007.724575148329, 5042.445331013586, 5053.090443720165, 6004.639721616717, 6016.789412370546, 6032.336442991267, 6032.638041717423, 6039.939600603938, 5557.916631250454, 5575.884823035328, 5637.915431268616, 5771.047777116729, 5780.682062486436, 5942.970285148546, 5947.732352044082, 5949.940500594938, 5971.104334349709, 6003.609765365237, 5549.084401073777, 5589.776408943597, 5593.692346920959, 5597.216389705363, 5611.971940140274, 5464.64479808827, 5542.889142217053, 5586.63686860369, 5686.886262274814, 5711.3431955324, 5471.863203419946, 5474.260974768515, 5495.560355171497, 5514.531264842533, 5527.088030474926, 5447.155690867922, 5457.072297709478, 5458.235846981346, 5478.808241711521, 5494.340679118372, 5296.867578310573, 5396.784128634968, 5417.945820541901, 5448.073827449422, 5461.9453805461435, 5590.217369568182, 5595.0208713475795, 5606.439356064524, 5608.355039170548, 5610.13042978339, 5390.541803946551, 5426.755795989269, 5442.455754424526, 5445.918311225414, 5541.0], "value": 5246.640421986348, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [536.4313827343017, 573.4638113364005, 582.3506789929229, 582.3564960718403, 596.8328867916985, 42876.78561607192, 44443.33334999974, 44634.0, 44648.33027504586, 48245.0, 46003.0, 46376.30435543461, 46424.0, 46435.76782116093, 46492.302615460794, 9015.774605634862, 9113.908860911371, 9167.358284920057, 9172.08279172084, 9179.49512776797, 9071.092890710943, 9083.22792562633, 9085.000649928503, 9117.908820911805, 9120.726378208665, 9097.545122743837, 9134.634614615408, 9138.0, 9144.308302963973, 9151.862722059175, 9093.318001149908, 9133.862992055125, 9137.360384773068, 9142.131497507726, 9147.54262286883, 8916.0, 8925.776855578597, 8974.551272436418, 9007.234385077276, 9019.143181397756, 42267.57732422667, 42571.787141064095, 42785.0, 42843.35734963978, 42844.143117137486, 3668.394714872043, 3780.981095094507, 3786.9621303786885, 3833.156705524808, 4051.0480037191237, 32447.675523244703, 32647.510287345707, 32919.34161316784, 33171.83414082914, 33991.660083399096, 4279.40088387626, 4312.1160162167, 4318.9004259054245, 4380.448194881161, 4521.84173553924, 369744.0, 370354.9999999973, 370425.0, 370878.0, 373028.4045739316, 58869.0, 58920.0, 59219.40780592225, 59264.70367648134, 59332.406675933555, 36244.637553624125, 37290.44064339037, 37312.06719831972, 61375.69312153411, 61430.54277828837, 15501.0, 15613.141277229633, 15723.606909827346, 15763.448279310398, 15785.447509337118, 11452.255603385753, 11500.367479788525, 11510.36692981894, 11523.0, 11537.250078744855, 19304.0, 19327.80672193288, 19365.12856921442, 19427.4171774847, 19447.902760486108, 7176.533525320838, 7191.316824901626, 7232.385247253937, 7240.710371585183, 7438.256174382518, 949.3876449689911, 966.1980556138388, 1001.3691374434205, 1052.4422056310168, 1079.5681727308981, 33849.32301353959, 34035.82982085074, 34173.82913085468, 34311.82844085764, 34681.65318346835, 1113.8050841102774, 1132.1848269246295, 1158.4439469054732, 1198.598469512723, 1201.6094769200045, 16345.673086538203, 17328.628125281204, 17382.130893455276, 17597.296108155788, 17726.556836078944, 555358.0, 560076.0, 565066.0, 566761.0, 568442.0, 59889.0, 59963.70018149881, 60055.699721501114, 60072.69963650241, 60200.69899650561, 1268.5940499040219, 1331.7669407853587, 1331.9067665263337, 1340.3499302838115, 1357.8981576381811, 30665.846670766503, 31982.36035279328, 32451.35097298088, 32735.83632081824, 33183.50224746679, 52257.47742522526, 52612.47387526076, 52615.21077183769, 52705.0, 52731.73634131805, 359312.0, 360064.0, 439843.0, 440501.0, 442969.0, 28865.567016495188, 29394.265143371587, 29813.869543697598, 30090.0, 30104.78533005425, 45708.31437528506, 46490.0, 47547.76226118847, 47598.52401475941, 47806.28290575575, 39558.0, 40299.3955090668, 40954.79522602487, 41384.172316554104, 41721.79139104285, 40126.1974760509, 40162.39756403715, 40272.0, 40653.39019914763, 41215.587844121175, 39852.402213966234, 42050.0, 43103.0, 43402.565974341116, 45703.77148114371, 33396.66603333935, 37672.811635941645, 38321.61678383292, 38431.423528646534, 39319.41020884746, 29132.126036218946, 29168.854155729085, 29266.7073329264, 29617.851910740308, 30030.24924376908], "value": 20244.981928447483, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.1080903113809, 390.7225869632559, 404.13313442665475, 406.06807377069623, 425.12212281638415, 16071.437499687514, 18085.728714069282, 19506.80493195071, 19774.901125494387, 19901.3034543791, 12701.93649031756, 13036.73926521469, 13054.151480153807, 17245.310187592513, 19163.041847907618, 5309.73451327434, 5449.56403487722, 5508.9724551377185, 5510.614257002011, 5512.6968016759065, 5317.574594032488, 5419.105847535152, 5428.4842939920845, 5433.918491222635, 5434.728263586825, 5308.867278318035, 5393.1101368274185, 5396.64921780085, 5417.377001644822, 5466.808661696841, 5254.474552544753, 5365.812196573121, 5395.136778115522, 5403.540699040586, 5404.648697834628, 5074.949250507485, 5206.088934436459, 5209.973950130225, 5248.107821670325, 5263.105272103713, 13703.657408564766, 14618.488352907703, 14624.488142915054, 14639.70720585593, 20452.693209601868, 2254.6730724044924, 2414.082648593541, 2472.43134079161, 2499.3751562109474, 2962.525995840677, 10113.797724045478, 10360.740981475523, 10637.680869573926, 10661.78676426467, 10761.62334318303, 1908.8282054615152, 1919.1555715485142, 2012.255465477768, 2019.919203231869, 2023.716679664848, 79981.60009199916, 84482.0, 84598.5770071152, 84745.57627211887, 85621.57189214077, 11053.28153670009, 11217.270877392943, 11306.265092768943, 11475.483603237873, 11564.653060408202, 15123.924380378028, 15530.844691553166, 15569.377224910877, 15611.687766244839, 15784.605384865241, 7575.507592006502, 7651.961740191264, 7763.767086987399, 7782.766517004499, 7841.411894107965, 7177.461690373243, 7213.747518836815, 7234.674439650228, 7446.0, 7474.701011959461, 4702.224133018014, 4741.668083234141, 4820.650217938983, 4873.171560834667, 4972.801087956514, 1857.322077441724, 1903.5907279934881, 2015.8588898776945, 2090.341542414135, 2094.1937354118772, 796.8366484870587, 995.5669283861544, 997.546116516983, 999.260547195085, 1047.4919663962971, 20002.0, 20168.294109706385, 20248.392548223575, 20275.797242027686, 20338.0, 940.8118376324762, 984.6258421799813, 1027.712240572641, 1043.9112675422675, 1044.3525014491065, 9069.682561110329, 11392.886071139348, 11526.654200374001, 11549.36478493674, 11553.595624153115, 413380.79572837986, 414319.0, 414828.0, 443679.0, 458009.0, 87631.56184219166, 89661.0, 90766.54616726874, 91915.0, 92085.53957230171, 952.7094236258008, 964.4165280005636, 982.3271059324386, 983.1201075037801, 988.6935050134481, 17035.31858725637, 17455.912720436318, 18210.0, 18248.817511824713, 18388.0, 33839.492407614394, 40906.0, 41250.1749965005, 41661.58338416577, 42337.57662423336, 52972.0, 56585.151222732515, 56801.431985679614, 57294.14058789205, 57805.421945780006, 8624.655013799378, 8833.558322083998, 8864.822703545764, 9016.2786977043, 9075.455472671661, 9799.314048016571, 12924.289164096064, 13104.737905242033, 13331.66670833237, 13685.931570342484, 5495.093309603871, 6885.690143943434, 11556.711082222675, 11642.301461912313, 11921.569411670655, 11613.883861161617, 11624.0, 11848.28910265387, 11848.466818992994, 12310.938445307716, 11497.367644779617, 11623.0, 11698.35659038761, 12133.332666703416, 12412.62762117138, 8675.609597568251, 8706.956465217887, 8746.413970272159, 8882.555872206234, 9391.906080939103, 9180.403273787182, 11387.601433949772, 11464.656060318204, 11692.35692036946, 11754.588589399329], "value": 8915.01906391198, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1519.0354125130539, 1529.380600856653, 1706.0, 1763.5591102224444, 1834.3671433355496, 7931.682732690685, 8597.65609375625, 8716.389852710312, 9297.860532092016, 9324.72025839225, 8825.0, 9150.359474836763, 9160.221381182591, 9180.357374983754, 9181.2654987601, 2393.3657580741074, 2417.601095819188, 2424.9393765155924, 2425.805935525154, 2428.1986944308337, 2367.7040369953756, 2401.4836810085826, 2421.9394515137174, 2437.70747510299, 2439.0487709793197, 2412.300432874464, 2417.987910060447, 2437.5368679950843, 2443.022790883643, 2443.4135807406183, 2356.0929042318658, 2371.9288421347387, 2372.822038347122, 2373.299876536426, 2404.3989002749313, 2346.307839187427, 2357.7170739511366, 2363.9645405318934, 2366.857988520694, 2367.3844800351862, 9181.678641247525, 9204.355695101345, 9238.676646317414, 9259.212966897821, 9296.767580810467, 5308.681479111227, 5319.202119682079, 5372.489613486712, 5375.193720941851, 5384.380796208447, 3521.5421995140723, 3621.167131559756, 3692.833822477995, 3736.962630373716, 3745.662890339855, 1100.6312885183472, 1108.0692218536467, 1211.715246916974, 1212.078820096724, 1221.822835688829, 12670.303133327663, 12814.231146131262, 12906.354682265943, 12963.67590810228, 12972.675683107904, 4380.255356589388, 4480.103979204172, 4650.046740418274, 4650.348951146842, 4660.091282200006, 9996.300258981946, 10042.397456152654, 10103.090721834924, 10112.393256404639, 10115.39307641544, 5374.435684253097, 5378.2739330189925, 5378.650387724785, 5386.973065134649, 5390.0, 4465.486469056035, 4860.076585448754, 4966.850994470172, 5005.474425185376, 5025.020121076428, 3785.716071294664, 3899.6100389960775, 3930.6462418382484, 3967.2263908537557, 4000.319945609195, 1133.2237417369129, 1138.2203190814319, 1143.1426430177366, 1145.387217838469, 1153.9538418463335, 940.8071345374182, 986.0829428631298, 986.3490096536395, 1012.3268026762267, 1019.0115587879744, 6107.114468402056, 6113.602615829956, 6147.569670123138, 6207.534434917399, 6402.103705481236, 985.1822986920838, 985.4136788610765, 999.715081201854, 1019.9847002294972, 1050.5167622893402, 4046.109855831712, 4071.572484889103, 4181.226473102425, 4222.472190976128, 4259.23333799919, 10748.946255268673, 11036.392998385167, 11072.612458564072, 11077.390743509184, 11084.279521831055, 10798.190135739851, 10843.51204195813, 10937.4531273436, 11001.504932278067, 11039.613613523648, 8277.420580559303, 8338.416310858182, 8417.747467575982, 8443.915560844436, 8482.660693572188, 6001.459868611846, 6112.969435152796, 6114.938850611615, 6116.785912493041, 6160.815175544741, 10128.84806727885, 10141.239407044353, 10148.543315550965, 10260.230482713678, 10363.948180259353, 10225.744356390853, 10388.688339349832, 10636.468176591294, 10794.406307653155, 10802.513886874953, 7303.634818259172, 7571.621418929141, 7607.771766847003, 7767.805804854924, 7779.299863012356, 9132.954335228282, 9191.11582125676, 9206.539673016456, 9214.216791572791, 9215.032421595904, 8015.559144246889, 8095.797605059921, 8124.146964568871, 8208.917910820815, 8344.956880389951, 7918.208179182036, 7968.203179681986, 8029.076656184614, 8087.0, 8142.552159631277, 7039.718411263697, 7306.123265208209, 7326.120865495961, 7426.554406735613, 7439.776806695808, 5070.4929507049, 5120.462351453044, 5221.451747566451, 5293.682379057269, 5323.866903327293, 5439.48324909133, 5442.12925931849, 5466.125419932791, 5497.615166938276, 5568.944310557004], "value": 4763.684793480592, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.4548631043004, 336.94777309517025, 340.9488576713493, 341.9111031131907, 346.34886413542534, 5424.5117939385445, 5440.646357986728, 5592.804251851186, 5665.405132461094, 5683.403242659518, 5479.205515200293, 5493.615446918718, 5530.949119667261, 5568.3596386415575, 5572.38703742589, 4368.825246990133, 4369.694121411495, 4376.102898905717, 4388.473383194005, 4388.539203383647, 4088.284550203717, 4281.892952676177, 4282.272013757653, 4363.498197707257, 4389.934150987738, 4251.829926802924, 4254.723442976197, 4257.6168144867115, 4262.849030761708, 4275.636570891478, 4253.29820579604, 4272.423222864904, 4290.849820256276, 4303.419038429804, 4308.763018034007, 3607.531020967284, 3767.17122233111, 3771.7925514096714, 3780.0, 3780.9054773630605, 5339.946600534023, 5362.195670649395, 5365.624406291561, 5378.650387724824, 5398.78404863805, 4875.975620121877, 4882.584980276681, 4930.506949305077, 4934.790976210816, 4939.5554400104165, 5503.449655034505, 5790.449907258803, 5818.1563673267565, 5818.505427038707, 5821.4385670305355, 4082.5713300103357, 4092.3247664135383, 4092.5907409259135, 4095.0139182496464, 4100.200460910124, 6025.3372129065765, 6027.367126451703, 6040.57715959883, 6045.909311360333, 6092.842359951595, 4162.146759914211, 4168.915542228886, 4216.3464662977285, 4257.340112282601, 4271.928114864289, 4286.2284788738325, 4296.591823776736, 4310.52584215736, 4312.310030395121, 4314.482262128502, 4915.139850526144, 4918.458969513351, 4933.925991110136, 4936.160852655057, 4957.90084198314, 5516.91724624131, 5798.797042103506, 5873.353931067579, 5893.6463812171405, 5907.438793314628, 1467.2956980649349, 1485.0273071138422, 1485.9331330090172, 1486.7472529669765, 1487.0928733472574, 915.3821170709745, 942.854431865287, 952.9237660987104, 964.2334344196381, 967.1102585621245, 4940.505949405031, 4945.258211268267, 4966.726830024311, 4969.602431805447, 5002.674826136289, 5197.454267301954, 5240.3973543042275, 5274.815381461631, 5358.9368093284365, 5366.356037275474, 4733.479317275097, 4872.6832755870755, 4893.363862697826, 4942.8764280893265, 5020.59835213182, 2762.6822915364587, 2777.916662500128, 2786.7352601502826, 2793.3156376687434, 2803.5934789455823, 5431.511163995259, 5445.727713614304, 5458.426865179178, 5472.315960504937, 5491.2312276281355, 5160.92258616121, 5262.657927234717, 5283.762230699628, 5311.787528498894, 5362.678239305654, 5421.701806400607, 5467.57842960833, 5469.589780766459, 5470.234167216593, 5543.002259593312, 5254.211868219798, 5260.368755749335, 5300.787968481218, 5326.680399176062, 5374.0, 4828.2998965150555, 5119.411267704261, 5139.717315547531, 5191.299174611379, 5196.714180720097, 5443.727813609383, 5473.233747275305, 5480.862978425571, 5570.498655121059, 5597.244372009726, 4051.3058815888285, 4088.427620133243, 4177.70756047086, 4180.352045432963, 4204.978975105105, 5925.881482370414, 5939.940600593939, 5947.256592925884, 5965.70171491415, 6020.608660437058, 5679.290088738908, 5702.37273899879, 5778.8266352009505, 5791.739371728367, 5851.824445266649, 5676.460736230051, 5702.258706368146, 5728.914066289092, 5737.426257374396, 5763.5965482417505, 5666.34836993751, 5730.226419433323, 5778.179873339885, 5785.508231800344, 5812.73842677089, 5548.805791797267, 5707.601637598815, 5744.827655170351, 5772.365039845529, 5813.534917206529, 5659.547236221175, 5692.003899317686, 5719.857003574777, 5723.399043100415, 5733.827985160452], "value": 4263.499792596459, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.5007844587235, 745.9254074592538, 747.8728616135256, 786.5713186313459, 797.0395673213775, 13393.464261429543, 14691.412343506261, 14699.485518006872, 14735.410583576671, 14738.778918316233, 15328.693426131498, 15440.84559154408, 15465.690686186297, 15498.302576384061, 15524.84475155248, 644.1272076336567, 772.8492943875947, 779.4115442840647, 795.6379847169553, 796.2276591706036, 792.5165648954162, 808.1635507249997, 808.6482380164647, 808.8907997420362, 809.4778867630375, 848.8344772769312, 852.377764232111, 853.4154104438448, 854.0690647194557, 854.3549620036863, 849.150849150846, 850.0437008365589, 851.0553285852699, 852.9488230706115, 853.2278288149204, 618.712298781069, 624.6002558362652, 624.6470744029654, 626.3892704613012, 628.1833616298838, 14117.0, 14136.575902722934, 14150.929245353707, 14152.080114792609, 14190.574282771533, 15477.22613869302, 15613.297401616954, 15644.061356318543, 15786.842131578767, 15955.920220398939, 12198.268103913704, 12294.508219671203, 12339.938300308531, 12451.688707782378, 12541.686457838536, 3983.601639836022, 3992.4210989406665, 4124.13393187431, 4157.729747566398, 4163.937540936889, 86970.13029869746, 87764.56117719434, 89995.80033597362, 92638.0, 95219.0, 12073.939630301793, 12104.213226140273, 12136.211146275464, 12164.939175304067, 12199.93900030512, 23736.881315593313, 23757.16849910245, 23850.284491465285, 23864.522709545712, 23922.88038559796, 8889.955550222208, 9007.644993400876, 9171.724848254564, 9420.0, 9428.764280893032, 4927.90144197114, 5018.447970723218, 5048.671836330626, 5118.769655365518, 5221.086309895829, 3567.6789088981595, 3623.293457775761, 3628.1111127773866, 3658.2683463307444, 3661.3043521730788, 645.602954183174, 663.9302873198342, 700.5866538742166, 714.1465948191861, 717.1967396515831, 664.5149041199951, 765.9885101723479, 772.6291380137566, 787.2875048081495, 787.3858390455454, 9996.400215987063, 10093.809190019543, 10513.158947284199, 10884.292520986111, 11004.213167272263, 562.3055526424862, 687.4466054825913, 744.430510659346, 760.2473551184288, 771.6488997506121, 5024.271480635361, 5024.572911302507, 5052.747362631853, 5090.541851233407, 5122.871928201825, 109661.45169274103, 117201.0, 122045.0, 125654.0, 129750.35124824315, 90719.0, 114380.0, 116282.0, 117617.0, 121613.0, 81971.5901420501, 91757.0, 93264.53367733254, 100418.49790751, 104158.0, 9816.705498835046, 10079.445630490394, 10143.391396516232, 10213.438260895722, 10214.285000049926, 55008.44991550194, 55795.0, 56344.0, 57199.14201286901, 58820.294376448925, 70223.0, 72360.27639723747, 81095.59452202701, 82749.0, 83330.0, 14423.855761442672, 14572.0, 14602.546223401965, 14998.400063997318, 15009.849901500846, 15526.611834704223, 15719.842801572295, 15951.361945522049, 16207.351705931631, 16235.756463653292, 15114.0, 15377.047714162314, 15418.614534636275, 15575.299111540235, 15648.0, 15117.924410377878, 15499.457518986781, 15996.280167392728, 15999.48224962536, 16179.271932762818, 14683.192424416333, 14906.25468726581, 15229.466968656045, 15343.769843452134, 15667.509808998495, 11561.479733411848, 12278.81581776292, 12310.26138431697, 12321.383930803604, 12448.25310481374, 11395.943020285176, 11511.827322590001, 11564.768704625692, 12109.212901161396, 13389.464421423034], "value": 8003.456993947043, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 109.0, 113.0, 112.0, 112.0, 116.0, 114.0, 112.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 118.0, 144.0, 184.0, 118.0, 146.0, 150.0, 119.0, 179.0, 123.0], "value": 142.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 161.0, 162.0, 165.0, 170.0, 161.0, 164.0, 161.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 184.0, 78.0, 76.0, 76.0, 76.0, 162.0, 86.0], "value": 96.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 152.0, 91.0, 88.0, 86.0, 142.0, 85.0, 85.0, 160.0], "value": 105.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 118.0, 117.0, 118.0, 118.0, 118.0, 117.0, 118.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 149.0, 148.0, 146.0, 146.0, 148.0, 147.0, 148.0, 147.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 54.0, 52.0, 53.0, 54.0, 53.0, 52.0, 51.0, 55.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 63.0, 64.0, 61.0, 61.0, 63.0, 62.0, 61.0, 63.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 86.0, 84.0, 79.0, 85.0, 79.0, 88.0, 80.0, 88.0, 78.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 139.0, 127.0, 137.0, 129.0, 128.0, 133.0, 130.0, 143.0, 128.0], "value": 132.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 165.0, 164.0, 167.0, 168.0, 163.0, 166.0, 170.0, 165.0], "value": 165.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 65.0, 64.0, 71.0, 68.0, 68.0, 64.0, 64.0], "value": 66.8, "unit": "score"}]}]} 22:44:20 INFO - Return code: 0 22:44:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:44:20 ERROR - # TBPL SUCCESS # 22:44:20 INFO - Running post-action listener: _resource_record_post_action 22:44:20 INFO - [mozharness: 2016-08-17 05:44:20.507822Z] Finished run-tests step (success) 22:44:20 INFO - Running post-run listener: _resource_record_post_run 22:44:20 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 630784; Write bytes: 421830656; Read time: 428; Write time: 223408 22:44:20 INFO - TinderboxPrint: CPU usage
13.4% 22:44:20 INFO - TinderboxPrint: I/O read bytes / time
630,784 / 428 22:44:20 INFO - TinderboxPrint: I/O write bytes / time
421,830,656 / 223,408 22:44:20 INFO - TinderboxPrint: CPU idle
8,245.1 (86.5%) 22:44:20 INFO - TinderboxPrint: CPU user
1,221.4 (12.8%) 22:44:20 INFO - TinderboxPrint: Swap in / out
0 / 0 22:44:20 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 128638976; Read time: 92; Write time: 65908 22:44:20 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 622592; Write bytes: 211656704; Read time: 216; Write time: 113624 22:44:21 INFO - Running post-run listener: _upload_blobber_files 22:44:21 INFO - Blob upload gear active. 22:44:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:44:21 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 22:44:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:44:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:44:21 INFO - (blobuploader) - INFO - Open directory for files ... 22:44:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:44:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:44:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:22 INFO - (blobuploader) - INFO - Done attempting. 22:44:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:44:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:44:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:23 INFO - (blobuploader) - INFO - Done attempting. 22:44:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:44:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:44:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:26 INFO - (blobuploader) - INFO - Done attempting. 22:44:26 INFO - (blobuploader) - INFO - Iteration through files over. 22:44:26 INFO - Return code: 0 22:44:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:44:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:44:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f12d89241492f8ae71dea8cf9379fd53096c52ce7549c9b06a20d5b6318e0b1f8f20a4e5ecfe643c4fd557c9ffccd71dcd17f05c315ab94e781c6dadb9968cb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8a6ab5654bd49276ac852456e0a626591df5559d9a52e4c9d8b3ef88ac95544cd664011ad98b08353198b567e8c57db1613580e8a29f313524fb83665aa48886", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/725bdc8a1be5985821fb676a9cd21f33dff8fd0d90b3326443e391ef3c50e5f8ed793adf8f6214b77b2c19be2b702d0d17b29827b4f9b1d0fd630f790e19d474"} 22:44:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:44:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:44:26 INFO - Contents: 22:44:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f12d89241492f8ae71dea8cf9379fd53096c52ce7549c9b06a20d5b6318e0b1f8f20a4e5ecfe643c4fd557c9ffccd71dcd17f05c315ab94e781c6dadb9968cb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8a6ab5654bd49276ac852456e0a626591df5559d9a52e4c9d8b3ef88ac95544cd664011ad98b08353198b567e8c57db1613580e8a29f313524fb83665aa48886", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/725bdc8a1be5985821fb676a9cd21f33dff8fd0d90b3326443e391ef3c50e5f8ed793adf8f6214b77b2c19be2b702d0d17b29827b4f9b1d0fd630f790e19d474"} 22:44:26 INFO - Running post-run listener: copy_logs_to_upload_dir 22:44:26 INFO - Copying logs to upload dir... 22:44:26 INFO - mkdir: /builds/slave/test/build/upload/logs 22:44:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.844752 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-16 22:44:26.902966) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 22:44:26.906630) ========= 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-1471411348.780732-21964959 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f12d89241492f8ae71dea8cf9379fd53096c52ce7549c9b06a20d5b6318e0b1f8f20a4e5ecfe643c4fd557c9ffccd71dcd17f05c315ab94e781c6dadb9968cb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8a6ab5654bd49276ac852456e0a626591df5559d9a52e4c9d8b3ef88ac95544cd664011ad98b08353198b567e8c57db1613580e8a29f313524fb83665aa48886", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/725bdc8a1be5985821fb676a9cd21f33dff8fd0d90b3326443e391ef3c50e5f8ed793adf8f6214b77b2c19be2b702d0d17b29827b4f9b1d0fd630f790e19d474"} build_url:https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011183 build_url: 'https://queue.taskcluster.net/v1/task/Pi8u61CUSyKBkXXMbWF4rA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f12d89241492f8ae71dea8cf9379fd53096c52ce7549c9b06a20d5b6318e0b1f8f20a4e5ecfe643c4fd557c9ffccd71dcd17f05c315ab94e781c6dadb9968cb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8a6ab5654bd49276ac852456e0a626591df5559d9a52e4c9d8b3ef88ac95544cd664011ad98b08353198b567e8c57db1613580e8a29f313524fb83665aa48886", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/725bdc8a1be5985821fb676a9cd21f33dff8fd0d90b3326443e391ef3c50e5f8ed793adf8f6214b77b2c19be2b702d0d17b29827b4f9b1d0fd630f790e19d474"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 22:44:26.932938) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 22:44:26.933291) ========= 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-1471411348.780732-21964959 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004364 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 22:44:27.018225) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 22:44:27.018554) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 22:44:27.018849) ========= ========= Total master_lag: 0.19 =========