builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-045 starttime: 1471345251.8 results: success (0) buildid: 20160816024725 builduid: e70aa9dbe01a48a28c26a15dff073ca0 revision: 94d311a8474cf412d6b4ea1b952d8f08d62b46c2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 04:00:51.800768) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 04:00:51.801168) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 04:00:51.801446) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 04:00:51.883205) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 04:00:51.883528) ========= 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=4300f881736b422476174874000001bc-1471344373.543666-1475995205 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-16 04:00:52-- 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% 14.1M=0.001s 2016-08-16 04:00:52 (14.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138509 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 04:00:52.036419) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 04:00:52.036773) ========= 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=4300f881736b422476174874000001bc-1471344373.543666-1475995205 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023398 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 04:00:52.088187) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 04:00:52.088551) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 94d311a8474cf412d6b4ea1b952d8f08d62b46c2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 94d311a8474cf412d6b4ea1b952d8f08d62b46c2 --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=4300f881736b422476174874000001bc-1471344373.543666-1475995205 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-16 04:00:52,395 truncating revision to first 12 chars 2016-08-16 04:00:52,395 Setting DEBUG logging. 2016-08-16 04:00:52,395 attempt 1/10 2016-08-16 04:00:52,395 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/94d311a8474c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 04:00:53,019 unpacking tar archive at: autoland-94d311a8474c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.926563 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 04:00:53.029668) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 04:00:53.029996) ========= script_repo_revision: 94d311a8474cf412d6b4ea1b952d8f08d62b46c2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 04:00:53.030410) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 04:00:53.030683) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 04:00:53.040317) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-08-16 04:00:53.040591) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] 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=4300f881736b422476174874000001bc-1471344373.543666-1475995205 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:00:53 INFO - MultiFileLogger online at 20160816 04:00:53 in /builds/slave/test 04:00:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 04:00:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:00:53 INFO - {'append_to_log': False, 04:00:53 INFO - 'base_work_dir': '/builds/slave/test', 04:00:53 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 04:00:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:00:53 INFO - 'branch': 'Autoland-Non-PGO', 04:00:53 INFO - 'buildbot_json_path': 'buildprops.json', 04:00:53 INFO - 'config_files': ('talos/linux_config.py',), 04:00:53 INFO - 'default_actions': ('clobber', 04:00:53 INFO - 'read-buildbot-config', 04:00:53 INFO - 'download-and-extract', 04:00:53 INFO - 'populate-webroot', 04:00:53 INFO - 'create-virtualenv', 04:00:53 INFO - 'install', 04:00:53 INFO - 'run-tests'), 04:00:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:00:53 INFO - 'download_minidump_stackwalk': True, 04:00:53 INFO - 'download_symbols': 'ondemand', 04:00:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:00:53 INFO - 'tooltool.py': '/tools/tooltool.py', 04:00:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:00:53 INFO - '/tools/misc-python/virtualenv.py')}, 04:00:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:00:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:00:53 INFO - 'installer_path': 'installer.exe', 04:00:53 INFO - 'log_level': 'info', 04:00:53 INFO - 'log_name': 'talos', 04:00:53 INFO - 'log_to_console': True, 04:00:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:00:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:00:53 INFO - 'opt_config_files': (), 04:00:53 INFO - 'pip_index': False, 04:00:53 INFO - 'sps_profile': False, 04:00:53 INFO - 'sps_profile_interval': 0, 04:00:53 INFO - 'suite': 'dromaeojs', 04:00:53 INFO - 'system_bits': '32', 04:00:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:00:53 INFO - 'title': 'talos-linux64-ix-045', 04:00:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:00:53 INFO - 'use_talos_json': True, 04:00:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:00:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:00:53 INFO - 'work_dir': 'build'} 04:00:53 INFO - [mozharness: 2016-08-16 11:00:53.437124Z] Running clobber step. 04:00:53 INFO - Running pre-action listener: _resource_record_pre_action 04:00:53 INFO - Running main action method: clobber 04:00:53 INFO - rmtree: /builds/slave/test/build 04:00:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:00:53 INFO - Running post-action listener: _resource_record_post_action 04:00:53 INFO - [mozharness: 2016-08-16 11:00:53.897217Z] Finished clobber step (success) 04:00:53 INFO - [mozharness: 2016-08-16 11:00:53.897341Z] Running read-buildbot-config step. 04:00:53 INFO - Running pre-action listener: _resource_record_pre_action 04:00:53 INFO - Running main action method: read_buildbot_config 04:00:53 INFO - Using buildbot properties: 04:00:53 INFO - { 04:00:53 INFO - "project": "", 04:00:53 INFO - "product": "firefox", 04:00:53 INFO - "script_repo_revision": "production", 04:00:53 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 04:00:53 INFO - "repository": "", 04:00:53 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 04:00:53 INFO - "buildid": "20160816024725", 04:00:53 INFO - "pgo_build": "False", 04:00:53 INFO - "basedir": "/builds/slave/test", 04:00:53 INFO - "buildnumber": 401, 04:00:53 INFO - "slavename": "talos-linux64-ix-045", 04:00:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:00:53 INFO - "platform": "ubuntu64_hw", 04:00:53 INFO - "scheduler": "tests-autoland-linux64-talos", 04:00:53 INFO - "branch": "autoland", 04:00:53 INFO - "repo_path": "integration/autoland", 04:00:53 INFO - "revision": "94d311a8474cf412d6b4ea1b952d8f08d62b46c2", 04:00:53 INFO - "stage_platform": "linux64", 04:00:53 INFO - "builduid": "e70aa9dbe01a48a28c26a15dff073ca0", 04:00:53 INFO - "slavebuilddir": "test" 04:00:53 INFO - } 04:00:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 04:00:53 INFO - Running post-action listener: _resource_record_post_action 04:00:53 INFO - [mozharness: 2016-08-16 11:00:53.899745Z] Finished read-buildbot-config step (success) 04:00:53 INFO - [mozharness: 2016-08-16 11:00:53.899831Z] Running download-and-extract step. 04:00:53 INFO - Running pre-action listener: _resource_record_pre_action 04:00:53 INFO - Running main action method: download_and_extract 04:00:53 INFO - mkdir: /builds/slave/test/build/tests 04:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:53 INFO - trying https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 04:00:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/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 04:00:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/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 04:00:54 INFO - Downloaded 1361 bytes. 04:00:54 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 04:00:54 INFO - Using the following test package requirements: 04:00:54 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 04:00:54 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:00:54 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:00:54 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:54 INFO - u'jsshell-linux-x86_64.zip'], 04:00:54 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:00:54 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 04:00:54 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:00:54 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:00:54 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:00:54 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:00:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:00:54 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')]} 04:00:54 INFO - trying https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 04:00:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/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 04:00:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/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 04:00:55 INFO - Downloaded 20266745 bytes. 04:00:55 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 04:00:59 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 04:00:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:59 INFO - trying https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 04:00:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/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 04:00:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/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 04:01:00 INFO - Downloaded 20266745 bytes. 04:01:00 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 04:01:05 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')]} 04:01:05 INFO - trying https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 04:01:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/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 04:01:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/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 04:01:07 INFO - Downloaded 14413719 bytes. 04:01:07 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 04:01: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')]} 04:01:07 INFO - trying https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:01:07 INFO - Downloading https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/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 04:01:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/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 04:01:10 INFO - Downloaded 55633208 bytes. 04:01:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:01:10 INFO - mkdir: /builds/slave/test/properties 04:01:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:01:10 INFO - Writing to file /builds/slave/test/properties/build_url 04:01:10 INFO - Contents: 04:01:10 INFO - build_url:https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:01:10 INFO - Running post-action listener: _resource_record_post_action 04:01:10 INFO - Running post-action listener: set_extra_try_arguments 04:01:10 INFO - [mozharness: 2016-08-16 11:01:10.877429Z] Finished download-and-extract step (success) 04:01:10 INFO - [mozharness: 2016-08-16 11:01:10.877657Z] Running populate-webroot step. 04:01:10 INFO - Running pre-action listener: _resource_record_pre_action 04:01:10 INFO - Running main action method: populate_webroot 04:01:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:01:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:01:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:01:10 INFO - '--tppagecycles', 04:01:10 INFO - '7'], 04:01:10 INFO - 'tests': ['tsvgm']}}, 04:01:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:01:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:01:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:01:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:01:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:01:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:01:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:10 INFO - 'talos_options': ['--disable-e10s'], 04:01:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:01:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:01:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:10 INFO - 'talos_options': ['--disable-e10s'], 04:01:10 INFO - 'tests': ['damp', 'tps']}, 04:01:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:10 INFO - 'tests': ['damp', 'tps']}, 04:01:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:01:10 INFO - 'tests': ['dromaeo_dom']}, 04:01:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:01:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:01:10 INFO - 'tests': ['basic_compositor_video']}, 04:01:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:01:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:01:10 INFO - 'tests': ['a11yr', 04:01:10 INFO - 'ts_paint', 04:01:10 INFO - 'tpaint', 04:01:10 INFO - 'sessionrestore', 04:01:10 INFO - 'sessionrestore_no_auto_restore', 04:01:10 INFO - 'tabpaint']}, 04:01:10 INFO - 'other-e10s': {'tests': ['a11yr', 04:01:10 INFO - 'ts_paint', 04:01:10 INFO - 'tpaint', 04:01:10 INFO - 'sessionrestore', 04:01:10 INFO - 'sessionrestore_no_auto_restore', 04:01:10 INFO - 'tabpaint']}, 04:01:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:01:10 INFO - 'ts_paint', 04:01:10 INFO - 'tpaint', 04:01:10 INFO - 'sessionrestore', 04:01:10 INFO - 'sessionrestore_no_auto_restore', 04:01:10 INFO - 'tabpaint']}, 04:01:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:01:10 INFO - 'ts_paint', 04:01:10 INFO - 'tpaint', 04:01:10 INFO - 'sessionrestore', 04:01:10 INFO - 'sessionrestore_no_auto_restore', 04:01:10 INFO - 'tabpaint']}, 04:01:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:01:10 INFO - 'tests': ['a11yr', 04:01:10 INFO - 'ts_paint', 04:01:10 INFO - 'tpaint', 04:01:10 INFO - 'sessionrestore', 04:01:10 INFO - 'sessionrestore_no_auto_restore', 04:01:10 INFO - 'tabpaint']}, 04:01:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:01:10 INFO - 'tests': ['a11yr', 04:01:10 INFO - 'ts_paint', 04:01:10 INFO - 'tpaint', 04:01:10 INFO - 'sessionrestore', 04:01:10 INFO - 'sessionrestore_no_auto_restore', 04:01:10 INFO - 'tabpaint']}, 04:01:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:01:10 INFO - 'tests': ['tsvgx', 04:01:10 INFO - 'tsvgr_opacity', 04:01:10 INFO - 'tart', 04:01:10 INFO - 'tscrollx', 04:01:10 INFO - 'cart']}, 04:01:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:01:10 INFO - 'tsvgr_opacity', 04:01:10 INFO - 'tart', 04:01:10 INFO - 'tscrollx', 04:01:10 INFO - 'cart']}, 04:01:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:10 INFO - 'talos_options': ['--disable-e10s'], 04:01:10 INFO - 'tests': ['tp5o']}, 04:01:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:10 INFO - 'tests': ['tp5o']}, 04:01:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:10 INFO - 'talos_options': ['--disable-e10s', 04:01:10 INFO - '--xperf_path', 04:01:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:01:10 INFO - 'tests': ['tp5n']}, 04:01:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:10 INFO - 'talos_options': ['--xperf_path', 04:01:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:01:10 INFO - 'tests': ['tp5n']}}, 04:01:10 INFO - 'talos.zip': {'path': '', 04:01:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:01:10 INFO - Running post-action listener: _resource_record_post_action 04:01:10 INFO - [mozharness: 2016-08-16 11:01:10.898271Z] Finished populate-webroot step (success) 04:01:10 INFO - [mozharness: 2016-08-16 11:01:10.898359Z] Running create-virtualenv step. 04:01:10 INFO - Running pre-action listener: _resource_record_pre_action 04:01:10 INFO - Running main action method: create_virtualenv 04:01:10 INFO - Creating virtualenv /builds/slave/test/build/venv 04:01:10 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 04:01:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:01:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:01:11 INFO - Using real prefix '/usr' 04:01:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:01:11 INFO - Installing distribute.............................................................................................................................................................................................done. 04:01:13 INFO - Installing pip.................done. 04:01:13 INFO - Return code: 0 04:01:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:01:13 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')]} 04:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:13 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')]} 04:01:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:13 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 0x7fd271c5ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d6230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d0090>, '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 0x26d39b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d3e40>, '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': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', '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 04:01:13 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 04:01:13 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 04:01:13 INFO - Using env: {'DISPLAY': ':0', 04:01:13 INFO - 'HOME': '/home/cltbld', 04:01:13 INFO - 'LANG': 'en_US.UTF-8', 04:01:13 INFO - 'LANGUAGE': 'en_US:en', 04:01:13 INFO - 'LOGNAME': 'cltbld', 04:01:13 INFO - 'MAIL': '/var/mail/cltbld', 04:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:13 INFO - 'MOZ_NO_REMOTE': '1', 04:01:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:13 INFO - 'NO_EM_RESTART': '1', 04:01:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:13 INFO - 'PWD': '/builds/slave/test', 04:01:13 INFO - 'SHELL': '/bin/bash', 04:01:13 INFO - 'SHLVL': '1', 04:01:13 INFO - 'TERM': 'linux', 04:01:13 INFO - 'TMOUT': '86400', 04:01:13 INFO - 'USER': 'cltbld', 04:01:13 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', 04:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:13 INFO - '_': '/tools/buildbot/bin/python'} 04:01:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:13 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 04:01:13 INFO - Cleaning up... 04:01:13 INFO - Return code: 0 04:01:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:01:13 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')]} 04:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:13 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')]} 04:01:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:13 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 0x7fd271c5ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d6230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d0090>, '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 0x26d39b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d3e40>, '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': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', '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 04:01:13 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 04:01:13 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 04:01:13 INFO - Using env: {'DISPLAY': ':0', 04:01:13 INFO - 'HOME': '/home/cltbld', 04:01:13 INFO - 'LANG': 'en_US.UTF-8', 04:01:13 INFO - 'LANGUAGE': 'en_US:en', 04:01:13 INFO - 'LOGNAME': 'cltbld', 04:01:13 INFO - 'MAIL': '/var/mail/cltbld', 04:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:13 INFO - 'MOZ_NO_REMOTE': '1', 04:01:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:13 INFO - 'NO_EM_RESTART': '1', 04:01:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:13 INFO - 'PWD': '/builds/slave/test', 04:01:13 INFO - 'SHELL': '/bin/bash', 04:01:13 INFO - 'SHLVL': '1', 04:01:13 INFO - 'TERM': 'linux', 04:01:13 INFO - 'TMOUT': '86400', 04:01:13 INFO - 'USER': 'cltbld', 04:01:13 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', 04:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:13 INFO - '_': '/tools/buildbot/bin/python'} 04:01:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:13 INFO - Downloading/unpacking psutil>=3.1.1 04:01:13 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 04:01:13 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 04:01:13 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 04:01:13 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 04:01:13 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 04:01:13 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 04:01:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:01:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:01:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:01:15 INFO - Installing collected packages: psutil 04:01:15 INFO - Running setup.py install for psutil 04:01:15 INFO - building 'psutil._psutil_linux' extension 04:01:15 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 04:01:16 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 04:01:16 INFO - building 'psutil._psutil_posix' extension 04:01:16 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 04:01:16 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 04:01:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:01:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:01:16 INFO - Successfully installed psutil 04:01:16 INFO - Cleaning up... 04:01:16 INFO - Return code: 0 04:01:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:16 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 0x7fd271c5ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d6230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d0090>, '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 0x26d39b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d3e40>, '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': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', '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 04:01:16 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 04:01:16 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 04:01:16 INFO - Using env: {'DISPLAY': ':0', 04:01:16 INFO - 'HOME': '/home/cltbld', 04:01:16 INFO - 'LANG': 'en_US.UTF-8', 04:01:16 INFO - 'LANGUAGE': 'en_US:en', 04:01:16 INFO - 'LOGNAME': 'cltbld', 04:01:16 INFO - 'MAIL': '/var/mail/cltbld', 04:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:16 INFO - 'MOZ_NO_REMOTE': '1', 04:01:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:16 INFO - 'NO_EM_RESTART': '1', 04:01:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:16 INFO - 'PWD': '/builds/slave/test', 04:01:16 INFO - 'SHELL': '/bin/bash', 04:01:16 INFO - 'SHLVL': '1', 04:01:16 INFO - 'TERM': 'linux', 04:01:16 INFO - 'TMOUT': '86400', 04:01:16 INFO - 'USER': 'cltbld', 04:01:16 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', 04:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:16 INFO - '_': '/tools/buildbot/bin/python'} 04:01:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:01:16 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 04:01:16 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 04:01:16 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 04:01:16 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 04:01:16 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 04:01:16 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 04:01:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:01:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:01:18 INFO - Installing collected packages: mozsystemmonitor 04:01:18 INFO - Running setup.py install for mozsystemmonitor 04:01:18 INFO - Successfully installed mozsystemmonitor 04:01:18 INFO - Cleaning up... 04:01:18 INFO - Return code: 0 04:01:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:01: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')]} 04:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01: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')]} 04:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:18 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 0x7fd271c5ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d6230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d0090>, '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 0x26d39b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d3e40>, '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': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', '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 04:01:18 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 04:01:18 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 04:01:18 INFO - Using env: {'DISPLAY': ':0', 04:01:18 INFO - 'HOME': '/home/cltbld', 04:01:18 INFO - 'LANG': 'en_US.UTF-8', 04:01:18 INFO - 'LANGUAGE': 'en_US:en', 04:01:18 INFO - 'LOGNAME': 'cltbld', 04:01:18 INFO - 'MAIL': '/var/mail/cltbld', 04:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:18 INFO - 'MOZ_NO_REMOTE': '1', 04:01:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:18 INFO - 'NO_EM_RESTART': '1', 04:01:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:18 INFO - 'PWD': '/builds/slave/test', 04:01:18 INFO - 'SHELL': '/bin/bash', 04:01:18 INFO - 'SHLVL': '1', 04:01:18 INFO - 'TERM': 'linux', 04:01:18 INFO - 'TMOUT': '86400', 04:01:18 INFO - 'USER': 'cltbld', 04:01:18 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', 04:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:18 INFO - '_': '/tools/buildbot/bin/python'} 04:01:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:18 INFO - Downloading/unpacking jsonschema==2.5.1 04:01:18 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 04:01:18 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 04:01:19 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 04:01:19 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 04:01:19 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 04:01:19 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 04:01:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:01:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:01:20 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 04:01:20 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 04:01:20 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 04:01:20 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 04:01:20 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 04:01:20 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 04:01:21 INFO - Downloading functools32-3.2.3-2.tar.gz 04:01:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:01:21 INFO - warning: no files found matching '*.txt' 04:01:21 INFO - no previously-included directories found matching 'build' 04:01:21 INFO - no previously-included directories found matching 'dist' 04:01:21 INFO - no previously-included directories found matching '.git*' 04:01:21 INFO - Installing collected packages: jsonschema, functools32 04:01:21 INFO - Running setup.py install for functools32 04:01:21 INFO - warning: no files found matching '*.txt' 04:01:21 INFO - no previously-included directories found matching 'build' 04:01:21 INFO - no previously-included directories found matching 'dist' 04:01:21 INFO - no previously-included directories found matching '.git*' 04:01:21 INFO - Successfully installed jsonschema functools32 04:01:21 INFO - Cleaning up... 04:01:21 INFO - Return code: 0 04:01:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:01: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')]} 04:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01: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')]} 04:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:21 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 0x7fd271c5ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d6230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d0090>, '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 0x26d39b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d3e40>, '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': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', '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 04:01:21 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 04:01:21 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 04:01:21 INFO - Using env: {'DISPLAY': ':0', 04:01:21 INFO - 'HOME': '/home/cltbld', 04:01:21 INFO - 'LANG': 'en_US.UTF-8', 04:01:21 INFO - 'LANGUAGE': 'en_US:en', 04:01:21 INFO - 'LOGNAME': 'cltbld', 04:01:21 INFO - 'MAIL': '/var/mail/cltbld', 04:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:21 INFO - 'MOZ_NO_REMOTE': '1', 04:01:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:21 INFO - 'NO_EM_RESTART': '1', 04:01:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:21 INFO - 'PWD': '/builds/slave/test', 04:01:21 INFO - 'SHELL': '/bin/bash', 04:01:21 INFO - 'SHLVL': '1', 04:01:21 INFO - 'TERM': 'linux', 04:01:21 INFO - 'TMOUT': '86400', 04:01:21 INFO - 'USER': 'cltbld', 04:01:21 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', 04:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:21 INFO - '_': '/tools/buildbot/bin/python'} 04:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:01:21 INFO - Cleaning up... 04:01:21 INFO - Return code: 0 04:01:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:01: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')]} 04:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01: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')]} 04:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:21 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 0x7fd271c5ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d6230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d0090>, '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 0x26d39b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d3e40>, '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': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', '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 04:01:21 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 04:01:21 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 04:01:21 INFO - Using env: {'DISPLAY': ':0', 04:01:21 INFO - 'HOME': '/home/cltbld', 04:01:21 INFO - 'LANG': 'en_US.UTF-8', 04:01:21 INFO - 'LANGUAGE': 'en_US:en', 04:01:21 INFO - 'LOGNAME': 'cltbld', 04:01:21 INFO - 'MAIL': '/var/mail/cltbld', 04:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:21 INFO - 'MOZ_NO_REMOTE': '1', 04:01:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:21 INFO - 'NO_EM_RESTART': '1', 04:01:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:21 INFO - 'PWD': '/builds/slave/test', 04:01:21 INFO - 'SHELL': '/bin/bash', 04:01:21 INFO - 'SHLVL': '1', 04:01:21 INFO - 'TERM': 'linux', 04:01:21 INFO - 'TMOUT': '86400', 04:01:21 INFO - 'USER': 'cltbld', 04:01:21 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', 04:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:21 INFO - '_': '/tools/buildbot/bin/python'} 04:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:21 INFO - Downloading/unpacking blobuploader==1.2.4 04:01:21 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 04:01:21 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 04:01:21 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 04:01:21 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 04:01:21 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 04:01:21 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 04:01:23 INFO - Downloading blobuploader-1.2.4.tar.gz 04:01:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:01:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:01: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 04:01: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 04:01: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 04:01: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 04:01: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 04:01: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 04:01:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:01:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:01:24 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 04:01:24 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 04:01:24 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 04:01:24 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 04:01:24 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 04:01:24 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 04:01:24 INFO - Downloading docopt-0.6.1.tar.gz 04:01:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:01:24 INFO - Installing collected packages: blobuploader, requests, docopt 04:01:24 INFO - Running setup.py install for blobuploader 04:01:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:01:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:01:24 INFO - Running setup.py install for requests 04:01:24 INFO - Running setup.py install for docopt 04:01:24 INFO - Successfully installed blobuploader requests docopt 04:01:24 INFO - Cleaning up... 04:01:25 INFO - Return code: 0 04:01:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:01: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')]} 04:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01: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')]} 04:01:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:25 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 0x7fd271c5ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d6230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d0090>, '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 0x26d39b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d3e40>, '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': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', '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 04:01:25 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 04:01:25 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 04:01:25 INFO - Using env: {'DISPLAY': ':0', 04:01:25 INFO - 'HOME': '/home/cltbld', 04:01:25 INFO - 'LANG': 'en_US.UTF-8', 04:01:25 INFO - 'LANGUAGE': 'en_US:en', 04:01:25 INFO - 'LOGNAME': 'cltbld', 04:01:25 INFO - 'MAIL': '/var/mail/cltbld', 04:01:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:25 INFO - 'MOZ_NO_REMOTE': '1', 04:01:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:25 INFO - 'NO_EM_RESTART': '1', 04:01:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:25 INFO - 'PWD': '/builds/slave/test', 04:01:25 INFO - 'SHELL': '/bin/bash', 04:01:25 INFO - 'SHLVL': '1', 04:01:25 INFO - 'TERM': 'linux', 04:01:25 INFO - 'TMOUT': '86400', 04:01:25 INFO - 'USER': 'cltbld', 04:01:25 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', 04:01:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:25 INFO - '_': '/tools/buildbot/bin/python'} 04:01:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:01:25 INFO - Running setup.py (path:/tmp/pip-KHQ00a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:01:25 INFO - Running setup.py (path:/tmp/pip-3uopNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:01:25 INFO - Running setup.py (path:/tmp/pip-0FQU4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:01:25 INFO - Running setup.py (path:/tmp/pip-_Xjnr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:01:25 INFO - Running setup.py (path:/tmp/pip-hS0ZHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:01:25 INFO - Running setup.py (path:/tmp/pip-pDPfdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:01:25 INFO - Running setup.py (path:/tmp/pip-nPReZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:01:25 INFO - Running setup.py (path:/tmp/pip-nqqHua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:01:25 INFO - Running setup.py (path:/tmp/pip-uklaPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:01:25 INFO - Running setup.py (path:/tmp/pip-PuKeHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:01:25 INFO - Running setup.py (path:/tmp/pip-Nbk6i5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:01:26 INFO - Running setup.py (path:/tmp/pip-h6TjkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:01:26 INFO - Running setup.py (path:/tmp/pip-SyRcVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:01:26 INFO - Running setup.py (path:/tmp/pip-eZ6wQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:26 INFO - Running setup.py (path:/tmp/pip-S6E75A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:01:26 INFO - Running setup.py (path:/tmp/pip-Pz4O14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:01:26 INFO - Running setup.py (path:/tmp/pip-1mcz6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:01:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:01:26 INFO - Running setup.py install for manifestparser 04:01:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:01:26 INFO - Running setup.py install for mozcrash 04:01:26 INFO - Running setup.py install for mozdebug 04:01:26 INFO - Running setup.py install for mozdevice 04:01:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:01:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:01:26 INFO - Running setup.py install for mozfile 04:01:26 INFO - Running setup.py install for mozhttpd 04:01:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:01:27 INFO - Running setup.py install for mozinfo 04:01:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:01:27 INFO - Running setup.py install for mozInstall 04:01:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:01:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:01:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:01:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:01:27 INFO - Running setup.py install for mozleak 04:01:27 INFO - Running setup.py install for mozlog 04:01:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:01:27 INFO - Running setup.py install for moznetwork 04:01:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:01:27 INFO - Running setup.py install for mozprocess 04:01:27 INFO - Running setup.py install for mozprofile 04:01:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:01:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:01:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:01:27 INFO - Running setup.py install for mozrunner 04:01:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:01:27 INFO - Running setup.py install for mozscreenshot 04:01:28 INFO - Running setup.py install for moztest 04:01:28 INFO - Running setup.py install for mozversion 04:01:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:01:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:01:28 INFO - Cleaning up... 04:01:28 INFO - Return code: 0 04:01:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:01:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:28 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 0x7fd271c5ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d6230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d0090>, '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 0x26d39b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d3e40>, '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': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', '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 04:01:28 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 04:01:28 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 04:01:28 INFO - Using env: {'DISPLAY': ':0', 04:01:28 INFO - 'HOME': '/home/cltbld', 04:01:28 INFO - 'LANG': 'en_US.UTF-8', 04:01:28 INFO - 'LANGUAGE': 'en_US:en', 04:01:28 INFO - 'LOGNAME': 'cltbld', 04:01:28 INFO - 'MAIL': '/var/mail/cltbld', 04:01:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:28 INFO - 'MOZ_NO_REMOTE': '1', 04:01:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:28 INFO - 'NO_EM_RESTART': '1', 04:01:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:28 INFO - 'PWD': '/builds/slave/test', 04:01:28 INFO - 'SHELL': '/bin/bash', 04:01:28 INFO - 'SHLVL': '1', 04:01:28 INFO - 'TERM': 'linux', 04:01:28 INFO - 'TMOUT': '86400', 04:01:28 INFO - 'USER': 'cltbld', 04:01:28 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', 04:01:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:28 INFO - '_': '/tools/buildbot/bin/python'} 04:01:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:01:28 INFO - Running setup.py (path:/tmp/pip-f3rx0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:01:28 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)) 04:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:01:28 INFO - Running setup.py (path:/tmp/pip-fqgBZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:01:28 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)) 04:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:01:28 INFO - Running setup.py (path:/tmp/pip-z5_U9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:01:28 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)) 04:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:01:28 INFO - Running setup.py (path:/tmp/pip-05KO6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:01:28 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)) 04:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:01:28 INFO - Running setup.py (path:/tmp/pip-VC82iL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:01:28 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)) 04:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:01:28 INFO - Running setup.py (path:/tmp/pip-QloalG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:01:28 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)) 04:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:01:28 INFO - Running setup.py (path:/tmp/pip-OrNVFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:01:28 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)) 04:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:01:28 INFO - Running setup.py (path:/tmp/pip-a2MAuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:01:29 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)) 04:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:01:29 INFO - Running setup.py (path:/tmp/pip-BitVsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:01:29 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)) 04:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:01:29 INFO - Running setup.py (path:/tmp/pip-Y8NAjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:01:29 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)) 04:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:01:29 INFO - Running setup.py (path:/tmp/pip-LvoLiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:01:29 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)) 04:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:01:29 INFO - Running setup.py (path:/tmp/pip-CxmLqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:01:29 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)) 04:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:01:29 INFO - Running setup.py (path:/tmp/pip-zVu7SR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:01:29 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)) 04:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:01:29 INFO - Running setup.py (path:/tmp/pip-6Ii4LL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:01:29 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)) 04:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:29 INFO - Running setup.py (path:/tmp/pip-YDc8g1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:29 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)) 04:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:01:29 INFO - Running setup.py (path:/tmp/pip-5rkzE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:01:29 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)) 04:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:01:29 INFO - Running setup.py (path:/tmp/pip-aBydqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:01:29 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)) 04:01:29 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)) 04:01:29 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)) 04:01:29 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)) 04:01:29 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)) 04:01:29 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)) 04:01:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:01:29 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 04:01:29 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 04:01:29 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 04:01:29 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 04:01:29 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 04:01:29 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 04:01:31 INFO - Downloading blessings-1.6.tar.gz 04:01:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:01:31 INFO - Installing collected packages: blessings 04:01:31 INFO - Running setup.py install for blessings 04:01:31 INFO - Successfully installed blessings 04:01:31 INFO - Cleaning up... 04:01:31 INFO - Return code: 0 04:01:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:01:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:01:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:01:32 INFO - Reading from file tmpfile_stdout 04:01:32 INFO - Current package versions: 04:01:32 INFO - argparse == 1.2.1 04:01:32 INFO - blessings == 1.6 04:01:32 INFO - blobuploader == 1.2.4 04:01:32 INFO - docopt == 0.6.1 04:01:32 INFO - functools32 == 3.2.3-2 04:01:32 INFO - jsonschema == 2.5.1 04:01:32 INFO - manifestparser == 1.1 04:01:32 INFO - mozInstall == 1.12 04:01:32 INFO - mozcrash == 0.17 04:01:32 INFO - mozdebug == 0.1 04:01:32 INFO - mozdevice == 0.48 04:01:32 INFO - mozfile == 1.2 04:01:32 INFO - mozhttpd == 0.7 04:01:32 INFO - mozinfo == 0.9 04:01:32 INFO - mozleak == 0.1 04:01:32 INFO - mozlog == 3.3 04:01:32 INFO - moznetwork == 0.27 04:01:32 INFO - mozprocess == 0.23 04:01:32 INFO - mozprofile == 0.28 04:01:32 INFO - mozrunner == 6.12 04:01:32 INFO - mozscreenshot == 0.1 04:01:32 INFO - mozsystemmonitor == 0.3 04:01:32 INFO - moztest == 0.7 04:01:32 INFO - mozversion == 1.4 04:01:32 INFO - psutil == 3.1.1 04:01:32 INFO - requests == 1.2.3 04:01:32 INFO - wsgiref == 0.1.2 04:01:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:01:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:32 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 0x7fd271c5ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d6230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d0090>, '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 0x26d39b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d3e40>, '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': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', '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 04:01:32 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 04:01:32 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 04:01:32 INFO - Using env: {'DISPLAY': ':0', 04:01:32 INFO - 'HOME': '/home/cltbld', 04:01:32 INFO - 'LANG': 'en_US.UTF-8', 04:01:32 INFO - 'LANGUAGE': 'en_US:en', 04:01:32 INFO - 'LOGNAME': 'cltbld', 04:01:32 INFO - 'MAIL': '/var/mail/cltbld', 04:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:32 INFO - 'MOZ_NO_REMOTE': '1', 04:01:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:32 INFO - 'NO_EM_RESTART': '1', 04:01:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:32 INFO - 'PWD': '/builds/slave/test', 04:01:32 INFO - 'SHELL': '/bin/bash', 04:01:32 INFO - 'SHLVL': '1', 04:01:32 INFO - 'TERM': 'linux', 04:01:32 INFO - 'TMOUT': '86400', 04:01:32 INFO - 'USER': 'cltbld', 04:01:32 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', 04:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:32 INFO - '_': '/tools/buildbot/bin/python'} 04:01:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:32 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)) 04:01:32 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)) 04:01:32 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)) 04:01:32 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)) 04:01:32 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)) 04:01:32 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)) 04:01:32 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)) 04:01:32 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)) 04:01:32 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)) 04:01:32 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)) 04:01:32 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)) 04:01:32 INFO - Cleaning up... 04:01:32 INFO - Return code: 0 04:01:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:01:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd271c5ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d6230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26d0090>, '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 0x26d39b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d3e40>, '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': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', '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 04:01:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:01:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:01:32 INFO - Using env: {'DISPLAY': ':0', 04:01:32 INFO - 'HOME': '/home/cltbld', 04:01:32 INFO - 'LANG': 'en_US.UTF-8', 04:01:32 INFO - 'LANGUAGE': 'en_US:en', 04:01:32 INFO - 'LOGNAME': 'cltbld', 04:01:32 INFO - 'MAIL': '/var/mail/cltbld', 04:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:32 INFO - 'MOZ_NO_REMOTE': '1', 04:01:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:32 INFO - 'NO_EM_RESTART': '1', 04:01:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:32 INFO - 'PWD': '/builds/slave/test', 04:01:32 INFO - 'SHELL': '/bin/bash', 04:01:32 INFO - 'SHLVL': '1', 04:01:32 INFO - 'TERM': 'linux', 04:01:32 INFO - 'TMOUT': '86400', 04:01:32 INFO - 'USER': 'cltbld', 04:01:32 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', 04:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:32 INFO - '_': '/tools/buildbot/bin/python'} 04:01:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:01:32 INFO - Cleaning up... 04:01:32 INFO - Return code: 0 04:01:32 INFO - Running post-action listener: _resource_record_post_action 04:01:32 INFO - Running post-action listener: _start_resource_monitoring 04:01:32 INFO - Starting resource monitoring. 04:01:32 INFO - [mozharness: 2016-08-16 11:01:32.495547Z] Finished create-virtualenv step (success) 04:01:32 INFO - [mozharness: 2016-08-16 11:01:32.496050Z] Running install step. 04:01:32 INFO - Running pre-action listener: _resource_record_pre_action 04:01:32 INFO - Running main action method: install 04:01:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:01:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:01:32 INFO - Reading from file tmpfile_stdout 04:01:32 INFO - Detecting whether we're running mozinstall >=1.0... 04:01:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:01:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:01:32 INFO - Reading from file tmpfile_stdout 04:01:32 INFO - Output received: 04:01:32 INFO - Usage: mozinstall [options] installer 04:01:32 INFO - Options: 04:01:32 INFO - -h, --help show this help message and exit 04:01:32 INFO - -d DEST, --destination=DEST 04:01:32 INFO - Directory to install application into. [default: 04:01:32 INFO - "/builds/slave/test"] 04:01:32 INFO - --app=APP Application being installed. [default: firefox] 04:01:32 INFO - mkdir: /builds/slave/test/build/application 04:01:32 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'] 04:01:32 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 04:01:48 INFO - Reading from file tmpfile_stdout 04:01:48 INFO - Output received: 04:01:48 INFO - /builds/slave/test/build/application/firefox/firefox 04:01:48 INFO - Running post-action listener: _resource_record_post_action 04:01:48 INFO - [mozharness: 2016-08-16 11:01:48.829019Z] Finished install step (success) 04:01:48 INFO - [mozharness: 2016-08-16 11:01:48.829320Z] Running run-tests step. 04:01:48 INFO - Running pre-action listener: _resource_record_pre_action 04:01:48 INFO - Running main action method: run_tests 04:01:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:01:48 INFO - Python 2.7.3 04:01:48 INFO - Return code: 0 04:01:48 INFO - grabbing minidump binary from tooltool 04:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:48 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 0x26d0090>, '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 0x26d39b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d3e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:01:48 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 04:01:48 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 04:01:48 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 04:01:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:01:48 INFO - Return code: 0 04:01:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:01:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:01:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:01:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:01:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:01:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-045 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:01:48 INFO - Using env: {'DISPLAY': ':0', 04:01:48 INFO - 'HOME': '/home/cltbld', 04:01:48 INFO - 'LANG': 'en_US.UTF-8', 04:01:48 INFO - 'LANGUAGE': 'en_US:en', 04:01:48 INFO - 'LOGNAME': 'cltbld', 04:01:48 INFO - 'MAIL': '/var/mail/cltbld', 04:01:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:01:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:48 INFO - 'MOZ_NO_REMOTE': '1', 04:01:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:01:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:48 INFO - 'NO_EM_RESTART': '1', 04:01:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:48 INFO - 'PWD': '/builds/slave/test', 04:01:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:01:48 INFO - 'SHELL': '/bin/bash', 04:01:48 INFO - 'SHLVL': '1', 04:01:48 INFO - 'TERM': 'linux', 04:01:48 INFO - 'TMOUT': '86400', 04:01:48 INFO - 'USER': 'cltbld', 04:01:48 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1471344373.543666-1475995205', 04:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:48 INFO - '_': '/tools/buildbot/bin/python'} 04:01:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:01:49 INFO - mozversion application_buildid: 20160816024725 04:01:49 INFO - mozversion application_changeset: 94d311a8474cf412d6b4ea1b952d8f08d62b46c2 04:01:49 INFO - mozversion application_display_name: Nightly 04:01:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:01:49 INFO - mozversion application_name: Firefox 04:01:49 INFO - mozversion application_remotingname: firefox 04:01:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 04:01:49 INFO - mozversion application_vendor: Mozilla 04:01:49 INFO - mozversion application_version: 51.0a1 04:01:49 INFO - mozversion platform_buildid: 20160816024725 04:01:49 INFO - mozversion platform_changeset: 94d311a8474cf412d6b4ea1b952d8f08d62b46c2 04:01:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 04:01:49 INFO - mozversion platform_version: 51.0a1 04:01:49 INFO - using testdate: 1471345309 04:01:49 INFO - actual date: 1471345309 04:01:49 INFO - starting webserver on 'localhost:57649' 04:01:49 INFO - SUITE-START | Running 2 tests 04:01:49 INFO - TEST-START | dromaeo_css 04:01:49 INFO - operating with platform_type : linux_ 04:01:49 INFO - Initialising browser for dromaeo_css test... 04:01:49 INFO - TEST-INFO | started process 1854 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFiwPam/profile http://localhost:57649/getInfo.html) 04:01:53 INFO - PROCESS | 1854 | __metrics Screen width/height:1600/1200 04:01:53 INFO - PROCESS | 1854 | colorDepth:24 04:01:53 INFO - PROCESS | 1854 | Browser inner width/height: 1024/697 04:01:53 INFO - PROCESS | 1854 | __metrics 04:01:54 INFO - PROCESS | 1854 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:01:54 INFO - PROCESS | 1854 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:01:55 INFO - TEST-INFO | 1854: exit 0 04:01:55 INFO - Browser initialized. 04:01:55 INFO - Running cycle 1/1 for dromaeo_css test... 04:01:55 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFiwPam/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:02:07 INFO - PROCESS | 1935 | RSS: Main: 157560832 04:02:07 INFO - PROCESS | 1935 | 04:02:58 INFO - PROCESS | 1935 | 1471345378584 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] 04:05:12 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:57649/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57649/tests/dromaeo/cssquery-ext.html) 04:05:13 INFO - PROCESS | 1935 | RSS: Main: 270491648 04:05:13 INFO - PROCESS | 1935 | 04:08:16 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:57649/tests/dromaeo/cssquery-ext.html (next: http://localhost:57649/tests/dromaeo/cssquery-jquery.html) 04:08:16 INFO - PROCESS | 1935 | RSS: Main: 247934976 04:08:16 INFO - PROCESS | 1935 | 04:11:18 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:57649/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57649/tests/dromaeo/cssquery-mootools.html) 04:11:19 INFO - PROCESS | 1935 | RSS: Main: 190349312 04:11:19 INFO - PROCESS | 1935 | 04:14:21 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:57649/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57649/tests/dromaeo/cssquery-prototype.html) 04:14:22 INFO - PROCESS | 1935 | RSS: Main: 190480384 04:14:22 INFO - PROCESS | 1935 | 04:17:25 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:57649/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57649/tests/dromaeo/cssquery-yui.html) 04:17:26 INFO - PROCESS | 1935 | RSS: Main: 190476288 04:17:26 INFO - PROCESS | 1935 | 04:20:29 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:57649/tests/dromaeo/cssquery-yui.html (next: http://localhost:57649/tests/dromaeo/cssquery-dojo.html) 04:20:29 INFO - PROCESS | 1935 | RSS: Main: 281751552 04:20:29 INFO - PROCESS | 1935 | 04:20:29 INFO - PROCESS | 1935 | __start_tp_report 04:20:29 INFO - PROCESS | 1935 | _x_x_mozilla_page_load 04:20:29 INFO - PROCESS | 1935 | _x_x_mozilla_page_load_details 04:20:29 INFO - PROCESS | 1935 | |i|pagename|runs| 04:20:29 INFO - PROCESS | 1935 | |0;dojo.html;2642.0224516928733;2663.2542887991344;2682.1953413975803;2701.75684188423;2709.634199383083;4666.253399456088;4731.361266229058;4794.137055330039;4832.782524786384;4847.612191024723;4772.04559088182;4794.9760251198695;4801;4808.591269742067;4808.7114773113635;4386.517483076859;4407.9779601101945;4460.973975985526;4481.955180448203;4482.663800214997;4428.6014258716705;4462;4467.3299005149165;4476.082403107355;4483.193025255453;4366.148601022786;4482.125985432842;4528.230200865861;4529.660275479352;4530.841420550281;4317.784110794448;4388;4465.1739428205565;4497.595216430521;4526.3210518422175;4359.629431498295;4407.955920440787;4413.139437809628;4419.4033805436175;4484.439445069366;4734.526547345273;4762.261849413347;4821.783019764084;4876.317315575822;4915.262710593403;5174.715390653512;5178.663386879878;5198.324217851655;5218.269442278084;5223.71269580173;5026.472220416877;5055.216441451544;5140.177571588528;5256.789728410898;5258.3689957205;4843.104025755247;4844.709317440965;4878.121938051185;4888.902221955541;4890;5334.14653655421;5382.946170538245;5388;5388.030154572136;5390.326209223847;4903.8528844134735;5023.045621331935;5075.974620126875;5091.312672789126;5152.201408781645;5203.167493201146;5204.7918083277;5226.947730522722;5237.659552129099;5247.868803279948;5132.5124113209185;5144;5146.253793199965;5149.562287205629;5195.506426889439;4893.681910675795;4956.5786908112495;4978.751062446863;5065.39215294167;5100.979804039207;4408.603225709701;4409.647228221798;4437.09039646878;4450.265706158448;4455.977720111378;3811.8475260989803;3819.312523745697;3829.329867273216;3833.21419109082;3864.942025869614;4312.202242585132;4363.563643635611;4431.8005689743395;4452.554744525586;4485.663575231871;5168.715720635326;5197.558207552324;5198.298229738937;5222.477752224747;5239.476052394806;4441.71128876622;4447.355133505624;4449.199144154083;4449.755263460541;4497.730136191839;4521.253993091169;4575.58819706228;4627.676062675648;4656.580907718254;4694.366260554782;4839.588634966068;4861.708297502161;4863;4889.242167464049;4908.141075311734;5159.896802063862;5170.069387510284;5174.223866420067;5176.896462070813;5224;4723.362346083235;4801.759912004456;4838.709677419366;4841.95158048415;4898.730569818694;5091.66904151229;5094.312267843942;5111.3355263815465;5116.130257856099;5117.820876269313;5159.2003239497935;5178.456262092426;5179.378474583074;5180.145276029413;5222.634415590872;4808.831690890802;4966;5015.874603134805;5017.874553136201;5037.168867136881;5034.697918124924;5050.04049230645;5050.368703912011;5051.19180931064;5096.980603879165;4941.036497882878;5000.949990500048;5006.549410553068;5009.198528235464;5011.123053465702;4833.782479788348;4873.707577545359;4909.95090049095;4928.704277743347;5033.094043072282;4798.256270278112;4832.323474713473;4840.34655321527;4865.318855360181;4870.756462176948 04:20:29 INFO - PROCESS | 1935 | |1;ext.html;558.4108765252658;570.2842932120188;573.1374281706037;573.8737477754894;573.9799107031255;28653.283667908312;38010.61989380106;39267.41098883515;39334.60665393345;42160.78919605405;39215;39591;39676;39686.8015659922;39693.40459893104;7536.284053014982;7669.923300767005;7672.578008209546;7673;7676.654550545211;7612.162662107164;7689.923100768977;7692.884606730903;7696.615169241515;7699.769006929801;7706.922930770676;7752.496087754306;7753.612319384037;7754.689812407497;7755.457118001742;7678.5392876427595;7717.421193410489;7721.498102623312;7726.7681969541;7731.342835858959;7452.143003554606;7499.850002999964;7501.8874716878745;7503.174650788409;7505.512141710771;34949.65050349515;35198;35256.29487410237;35261.11847203815;35264.471032934525;3152.7793054486197;3196.31279274957;3611.151379425859;3645.9817700911326;3662.688671462929;28374.858125709445;28808.279793005135;28831;28865.711342886512;28973.56539651907;3439.1917899293644;3574.070741607175;3607.1703508193264;3661.835217415223;3738.9065273368383;237672;238593;238810;238812;239154.99999999825;48263;48348.75825620849;48617;48683.26975095376;48732.26901596479;50130;50535.49464505308;50604.74697626488;50898.74550627223;50981.74509127505;12719.554815581596;12932.353382331034;12963.740725185444;12978.480860765463;12979.286139262242;8909;9148.451092934445;9151.267898568098;9317.11487408695;9319.953400232955;14502;14998.100113993196;15006.974058501963;15464.072155670696;15491.767623485657;4349.326889886082;5416.729163541807;5734.770609175586;5742.569307301886;5788.305403351625;774.3340726974798;895.3016647015338;937.5218638494281;961.5096300886598;1002.0981116994617;26580.348439023095;26950;27012.594811077848;27234.1829745104;27288.181354559394;956.5026186383042;964.6671898195111;981.9509024548744;1015.2943704125562;1046.5238316565944;14299.714005719827;14518.709625807636;14611.926940365443;14826.184559849096;14842.554723358317;377935;378586;381985;383439;383999;47680.28479572809;47844.76077619589;47875;47918.760406198446;48003.75998119987;1106.4633652678444;1113.259682311254;1118.7203199200037;1159.9826002609968;1162.7093226693328;25798.87100564485;26594.33514162084;27052.72947270502;27581.586276206275;27615.30961725973;41720.79139604283;42086.57913420827;42090.7895460533;42099;42132;341500;342736;343271;343961;345601;23782.405439864142;35719.46420803638;36025.09937251485;36081.27837443289;36090.8195459021;37860.810695947446;39899.40150897797;40035.1992960145;41052.79473602613;41457.58542414537;34441.65558344385;34612.82693586516;34845.303093938484;34910.65089349074;35115.82442087875;34648;34717.305653887284;35188.64811351854;35234.64765352416;35236;34012.31975360528;34390.31219375548;34933.8253308742;35045.824770877;35370.29259414849;29780.404391912474;31935;32052;32236.83881580577;32468.350632986734;24588.6311705321;24590.877045614656;24794.380140496633;24809.284247653413;24981.446708471987 04:20:29 INFO - PROCESS | 1935 | |2;jquery.html;341.3803945838305;354.2472246476238;360.3765485709722;363.2154546180251;379.0920744816165;12787.168834025782;13703.201585149647;14008.649783755398;16841.98948063117;16848.494545163634;14943.92528037361;15108.093514389117;15406.460773872917;15475.071495710237;15808.52574422769;4871.074495845795;5047.4447810740785;5050.318207042057;5053.393592768854;5064.772085256154;3696.4270538066507;5463.562914966793;5672.262605861232;5750.453706897859;5830.4169583041785;5574.916376254339;5789.9710501447435;5793.275840519935;5797.188393624896;5799.3620701722775;5725.971370143144;5846.795362162304;5849.356570777211;5850.414958504158;5858.853528661796;4928.408590969071;5034.723090230036;5109.846704598868;5124.9743751281;5125.641205115643;19753.11110999994;20040.190354315004;20209.696854547194;20300;20406.69389959152;1872.2043131668986;1892.3660573707825;1918.1080797429122;2090.989545052265;2351.6237402015595;12845.871541284561;12846.35768211595;13134.34328283582;13211.14127581704;13327.200367977855;1688.1390490849756;1721.277063633277;1730.8528775054135;1746.4847869878417;1775.698131317679;66101;66560.66719666419;66585;67343.66328168426;67371.32628673699;8818.779530511789;9033.232175265044;9349.672761453316;9416.576254068583;9448.716538503855;12129.211601246066;12370.319632420304;12389.876101239053;12394.938025309995;12402.06984476168;6474.093626892239;6563.573367731082;6608.636524991076;6765.594064356154;6804.21751498584;6247.219097612799;6328.557001009885;6474.352564743582;6482.416582507502;6496.220453545605;5678.233438485834;5771.163181338767;5787.71061446926;5823.79616713413;5950.702464876739;1575.7400028995087;1615.6041769766248;1643.2933838449342;1657.78448801655;1668.96662066758;806.3549160671439;807.3097501636016;858.2533196119416;869.2698133567707;943.9386439881523;16333.02001879891;16495.752563711554;16948;17346.739798903025;17445.47663570095;883.3728053082383;891.4027601507006;915.7802127489488;919.6137622198693;951.7810903492098;9200.30997675177;9244.491552964651;9528.523573821281;9756.365836220622;10071.194304455626;336907;357032;366609;367760;367828;66350;68541;68893.3110668897;70280.94578581223;70356.296437036;926.9490178040272;944.2493217891792;974.907383798538;977.1352353167498;977.9804403911882;14883.925580372463;14987.209209369237;15321.770173447185;15562.455314063953;15653;35355.29289414249;35558.822205888806;36116.27767444584;36370;37139.257214856094;47486;48135.75932120457;48696;48874.755626223065;49049;7596.202398748052;7640.121386040454;7695.345895598937;7719.845603087794;7921.366290696846;11913.404329783649;11927.343996080299;11976.221545599508;12630.494780209056;12774.680632983878;9724.367916085432;9772.462514561767;10108;10206.642767503101;11307.491162897853;10492.842607360742;10815.783684326112;10917.781644367227;11001.119910406987;11292.887071129184;10039.096481316716;10634.30876992993;10891.237613366988;10933.671989840317;11118.666440006813;7438.925610743823;7536.547807131589;7582.924170758442;7605.961970190114;8001.759947201594;9948.303618746619;10334.379937203792;10364.170866330525;10393.896061039293;11007.119430445386 04:20:29 INFO - PROCESS | 1935 | |3;mootools.html;1271.1864406779657;1363.1071648070515;1394.5816255123464;1471.7424450721128;1716.3906813081355;6586.0779490871255;7324.450666200029;7574.242575742423;7592.316691497771;7807.726729564466;7394.593297368642;7443.739469118582;7449.88825167623;7458.030456040707;7474.140473845509;2189.441692368448;2215.9224427145055;2218.6228341182036;2223.866568005925;2230.208276061999;2146.51703366742;2205.933821985335;2234.575430668176;2238.6865838782583;2243.741969673492;2155.924542641008;2201;2207.4371035386034;2207.922722704706;2228.2312602152233;2177.3903307073965;2181.432827464863;2182.03990244292;2188;2189.1462329691276;2125.8937053147433;2161.8054375106317;2164.729408823897;2166.068590506082;2166.425897137244;7563.205863384375;7719.768406947801;7724.497907635986;7728.613569321511;7734.226577342277;4392.055708022758;4510.188166130095;4516.20966330894;4561.885952851172;4603.28649059393;2895.6235689360246;3037.4228896509594;3055.9694403055905;3073.3699591583672;3093.458644737162;941.9058094190526;949.2690628216304;964.5081008685551;973.3284034016576;978.8238117138912;10327.070563649308;10349.172066234683;10374.84437733427;10382.636607718769;10480.790384192274;4071.918561628751;4123.525794533667;4194.098268872207;4312.159128969821;4338.154059958278;8311.667533298722;8401.579921003926;8424.157584241526;8425.620847061897;8427.536485493234;4518.073794872043;4524.47968483622;4524.728516289033;4525.728456292633;4530.22986092365;3665.065408320861;3733.3653278942643;3997.6002399760373;4152.148809494107;4184.686148538811;3399;3425.9143521411665;3510.5611798525183;3514.683678468899;3519.0674471265133;1049.9317544359592;1052.2055847834988;1066.0565399621237;1069.973250668724;1071.4856868703066;690.6098054599136;787.3858390455454;793.6706266899172;845.5434065604504;851.2508992087047;4991.850244492598;5009.624278179152;5172.586193104617;5173.456787037307;5294.444083371268;403.3264448371227;409.5433591545465;410.0998308712407;415.74223981131286;418.5145231531409;3473.930521389558;3499.2826470573473;3505.772124811879;3534.151803567125;3622.836972336251;9221.81556368869;9630.133288004114;9631.229501639993;9685.757856053655;9707.077827606367;9572.329936904489;9578.56896439662;9602.807943841084;9605.47169905648;9608;6764.661766911635;7008.649567521604;7065.50541462103;7234.963825180946;7254.274572542809;5222.791088356423;5268.209768534751;5301.151815709468;5373.11343628297;5514.310711161105;8796.692115775917;8882.0673829247;8906.109389061043;8913.955430222808;9009.864852027356;7925.128235894163;8436.45163064399;8757.47455152693;8761.342899282707;8933.374663773473;6314.936850631435;6429.196350456193;6522.21733391996;6526.608403495806;6682.83292917681;7369.926300737138;7405.518641288514;7435.7025718970635;7456.217097204715;7562.924370756442;6696.0960270362875;6807.455403567802;6833.086735714008;7001.649917504206;7028.964855175692;6630.635315057718;6690.126764505946;6761.898571521333;6958.965205173941;6971.8605627886145;6108.4807791338235;6166.013437849922;6175.475084617858;6201.534884883562;6212.06818977157;4451;4480.663950203682;4574.565416285448;4605.263157894855;4647.581717645428;4499.550044995474;4501.639868810422;4578.49636539987;4664.533546645308;4697.600703940203 04:20:29 INFO - PROCESS | 1935 | |4;prototype.html;293.4102454067325;304.01044599827566;306.2176139962395;309.4538140182577;312.7513626666798;4785.1289226464105;4788.20994535902;4812.879678008052;4823.565879070883;4840.886596082899;4719.07977944301;4757.191277482828;4758.3814104166495;4767.594754445876;4782.497837727041;3587.6232995535483;3686.6497682720233;3707.7775333479944;3712.8700495482663;3724.430143201196;3631.7457777955424;3713.275911197318;3768.5491085931976;3781.1303400217967;3807.333716599584;3701.7229055975645;3723.2739615774935;3743.5694895087004;3752.1745216052423;3764.228333191703;3613.5302410686704;3729.2355067211292;3750.062484378905;3763.4542991266385;3764.416515440111;3154.952675709866;3214.212517933098;3257.5439438478634;3306.3883181611477;3335.8832440864694;4642.837500687459;4650.721241524717;4653.162430762495;4657.534246575349;4661.417322834645;4272.6154646082;4283.614474697292;4305.590968857953;4327.502337231227;4333.198358303724;4721.669483136182;4938.901221975576;4960.6527543072;4969.254611808222;4974.403071631391;3660.359437098497;3660.670539651444;3670.834812433447;3676.522052133232;3678.6873115784924;5192.532672059533;5214.999999999962;5242.344706911636;5245.632805703603;5256.185291279858;3766.7174961877968;3787.5265591801026;3789.317922773872;3790.223004284115;3793.7344385893275;3591.120175557005;3751.362268414376;3772.0946972726347;3778.471014058034;3782.3759079751535;4246.428821336125;4256.59562341577;4261.893452663646;4264.594863487963;4271.658267338606;4957.256411538298;4957.752112394438;4978.054169707744;4986.227134794112;4990.6257030722845;1241.8696036916174;1277.7955527115616;1279.3922886628964;1303.55027515507;1318.347418028063;837.623069618674;847.8516259654535;863.937357050831;869.5043825019804;875.8248350329959;3921.705872059607;3940.5862384449792;3950.506186726659;3966.5438474575217;3970.3250447424007;4505.301678239878;4511.503734589192;4517.299818528133;4523.592876641118;4547.544326063889;3962.6725047108966;3964.0089977505622;3964.762114273095;3966.504186976628;3986.242613903407;2419.2742177347077;2427.3810178404747;2462.5936720440927;2696.191142657195;2717.4565086982684;4645.744484053342;4737.502562230985;4832.178529649968;4835.323054772335;4863.586595139382;5219.2953951216095;5231.9476805232225;5243.397009343898;5245.763940622604;5280.128778751539;4734.3004809130925;4870.931313272616;4876.341693871282;4879.414470263591;4879.56083952446;5073.644844860824;5102.550775627646;5130.8717282066755;5142.742862856768;5157.613179011664;5025.74871256443;5028.723420211923;5049.368828896388;5058.747062646927;5060.645754797129;4621.6302695784925;4834.178189707828;4834.71879951806;4838.782254798472;4875.390576177978;3575.392183328788;3620.5112309837837;3625.3655610268625;3648.288583726148;3654.3056819204267;5216.295800066942;5240.161574148118;5244.7902083916215;5245.737713114406;5273.947260527499;4916.287138364989;5045.789010637494;5075.137226671549;5076.137056700296;5108.821191258288;4985.227289770091;4986.227134794112;5007.173816320266;5033.577167526118;5038.974805125951;4929.605631549539;4937.629677774109;4938.456769755396;4942.654014218971;4974.5771609413605;4768.523147685204;4989.750512474289;4999.450060493416;5016.792611251038;5063.518965698253;4911.165101932752;4911.287863259879;4927.260910863399;4936.284238785428;4937.5062493751775 04:20:29 INFO - PROCESS | 1935 | |5;yui.html;660.1615947746366;661.1801366305782;686.519436394524;738.7894450081728;744.6351287868943;12824.294663793487;13608.183508989468;13609.251491167981;13622.182669039865;13635.250061246628;13520.391582378792;13690.931545342259;13692.110012849158;13695.246761428118;13709.3830777615;622.3931666625027;737.8634952533798;757.5606148433895;759.741687826139;764.77439155449;761.702935855017;770.9306162445379;771.1594362145261;772.7179579453515;773.4392565390103;810.9959450202741;812.3338862133053;812.358236992775;812.4515951732586;812.6586833529902;642.4282388674084;769.5421224371546;793.5119901260741;804.0994086622961;804.1958041958042;592.3365830270081;595.4968051996057;596.171321862607;596.2010905386802;596.2546816479401;13366.06437549372;13388.79916801239;13436.865631343659;13445.86554134456;13464.057515973886;12214.511419543207;12450.190737602026;12644.55744048963;12744.681382965407;13029.739405211938;11556.245738205067;11607.477663505078;11661;11984.041276697842;12364.319962402064;3999.420084087791;4062.837486500536;4130.752154870687;4145.481814773153;4167.74993500391;64251.678741605996;64288.357116428706;64453.355466445675;65532.34467655358;66055;9850.458224797561;10085.949570252249;10121.392716437038;10310.432926188982;10582.841257381146;18122.09389530518;18247.90876045638;18259;18298.634027319644;18348.44954651336;6439.58142720731;6490.8052758417325;6520.054592084121;6532;6587.308332625101;3738.9065273368383;3756.492873462102;3790.1851102012647;3818.8281527331337;3822.0444888777806;2688.8655567221563;2792.148394739583;2797.118907544117;2799.1602519244148;2801.117647940933;491.43484992259187;493.62484511770595;493.7777999900059;509.33023074656705;512.3928145148011;500.2845930319605;534.9304590403223;537.0279793573644;538.1336048961169;540.5565073551156;7148.320909513588;7436.553806771502;7524.9247507525315;7670.501417407851;7778.727744528915;416.50435981182295;453.7118929479804;500.32706010415933;505.4919805595353;526.6945171800346;3409.006030245805;3506.6142724300307;3520.5423294972;3571.8928432147077;3668.4497325401403;54717.179242311395;54944.45055549473;55432.16851747227;55539.444605554236;77574;81076.59461702654;82570.58714706507;82595;84296.15703842884;85118;43979.560204398185;47134.999999999316;62694.68652656708;71068;71081.28918710747;7929.563873986986;8777.648894044167;8902.910970890209;8906.287497000354;8980.730578082668;35683.643163568035;36620.45069324016;41945.38600753975;43256.567434325254;43713.56286437222;54627.72686136544;57983;59019.40980590139;59222.70388648029;59370.40629593649;10189.490525473548;11476;11559.653210403701;11591.88408115908;11918.616276744859;11460.255083419552;11634.844868735016;11718.121140914294;11734.647960561197;12142.878571214174;11370.31778093317;11567.363794991019;12011.279323240633;12024.218425802295;12435.253884766942;11088.722781930517;11460.828087578526;11462.82805757931;11638.53445862156;11681.299122052704;10784.568617255536;10802.72993175177;11423.086153107564;11444.313341199555;12266.75466490683;9534.570944307661;9659.178969787647;9781.559829807533;9814.509274536387;9997.20022398192;9705.757356066155;9738.269629777653;9764.072413120743;9786.902130978884;9994.400335979864 04:20:29 INFO - PROCESS | 1935 | __end_tp_report 04:20:29 INFO - PROCESS | 1935 | __start_cc_report 04:20:29 INFO - PROCESS | 1935 | _x_x_mozilla_cycle_collect,1134 04:20:29 INFO - PROCESS | 1935 | __end_cc_report 04:20:29 INFO - PROCESS | 1935 | __startTimestamp1471346429517__endTimestamp 04:20:29 INFO - PROCESS | 1935 | 04:20:29 INFO - PROCESS | 1935 | ------- Summary: start ------- 04:20:29 INFO - PROCESS | 1935 | Number of tests: 6 04:20:29 INFO - PROCESS | 1935 | 04:20:29 INFO - PROCESS | 1935 | [#0] dojo.html Cycles:165 Average:4764.66 Median:4865.32 stddev:501.51 (10.3%) stddev-sans-first:474.60 04:20:29 INFO - PROCESS | 1935 | Values: 2642.0 2663.3 2682.2 2701.8 2709.6 4666.3 4731.4 4794.1 4832.8 4847.6 4772.0 4795.0 4801.0 4808.6 4808.7 4386.5 4408.0 4461.0 4482.0 4482.7 4428.6 4462.0 4467.3 4476.1 4483.2 4366.1 4482.1 4528.2 4529.7 4530.8 4317.8 4388.0 4465.2 4497.6 4526.3 4359.6 4408.0 4413.1 4419.4 4484.4 4734.5 4762.3 4821.8 4876.3 4915.3 5174.7 5178.7 5198.3 5218.3 5223.7 5026.5 5055.2 5140.2 5256.8 5258.4 4843.1 4844.7 4878.1 4888.9 4890.0 5334.1 5382.9 5388.0 5388.0 5390.3 4903.9 5023.0 5076.0 5091.3 5152.2 5203.2 5204.8 5226.9 5237.7 5247.9 5132.5 5144.0 5146.3 5149.6 5195.5 4893.7 4956.6 4978.8 5065.4 5101.0 4408.6 4409.6 4437.1 4450.3 4456.0 3811.8 3819.3 3829.3 3833.2 3864.9 4312.2 4363.6 4431.8 4452.6 4485.7 5168.7 5197.6 5198.3 5222.5 5239.5 4441.7 4447.4 4449.2 4449.8 4497.7 4521.3 4575.6 4627.7 4656.6 4694.4 4839.6 4861.7 4863.0 4889.2 4908.1 5159.9 5170.1 5174.2 5176.9 5224.0 4723.4 4801.8 4838.7 4842.0 4898.7 5091.7 5094.3 5111.3 5116.1 5117.8 5159.2 5178.5 5179.4 5180.1 5222.6 4808.8 4966.0 5015.9 5017.9 5037.2 5034.7 5050.0 5050.4 5051.2 5097.0 4941.0 5000.9 5006.5 5009.2 5011.1 4833.8 4873.7 4910.0 4928.7 5033.1 4798.3 4832.3 4840.3 4865.3 4870.8 04:20:29 INFO - PROCESS | 1935 | 04:20:29 INFO - PROCESS | 1935 | [#1] ext.html Cycles:180 Average:46887.54 Median:3653.91 stddev:86131.34 (2357.2%) stddev-sans-first:86302.72 04:20:29 INFO - PROCESS | 1935 | Values: 558.4 570.3 573.1 573.9 574.0 28653.3 38010.6 39267.4 39334.6 42160.8 39215.0 39591.0 39676.0 39686.8 39693.4 7536.3 7669.9 7672.6 7673.0 7676.7 7612.2 7689.9 7692.9 7696.6 7699.8 7706.9 7752.5 7753.6 7754.7 7755.5 7678.5 7717.4 7721.5 7726.8 7731.3 7452.1 7499.9 7501.9 7503.2 7505.5 34949.7 35198.0 35256.3 35261.1 35264.5 3152.8 3196.3 3611.2 3646.0 3662.7 28374.9 28808.3 28831.0 28865.7 28973.6 3439.2 3574.1 3607.2 3661.8 3738.9 237672.0 238593.0 238810.0 238812.0 239155.0 48263.0 48348.8 48617.0 48683.3 48732.3 50130.0 50535.5 50604.7 50898.7 50981.7 12719.6 12932.4 12963.7 12978.5 12979.3 8909.0 9148.5 9151.3 9317.1 9320.0 14502.0 14998.1 15007.0 15464.1 15491.8 4349.3 5416.7 5734.8 5742.6 5788.3 774.3 895.3 937.5 961.5 1002.1 26580.3 26950.0 27012.6 27234.2 27288.2 956.5 964.7 982.0 1015.3 1046.5 14299.7 14518.7 14611.9 14826.2 14842.6 377935.0 378586.0 381985.0 383439.0 383999.0 47680.3 47844.8 47875.0 47918.8 48003.8 1106.5 1113.3 1118.7 1160.0 1162.7 25798.9 26594.3 27052.7 27581.6 27615.3 41720.8 42086.6 42090.8 42099.0 42132.0 341500.0 342736.0 343271.0 343961.0 345601.0 23782.4 35719.5 36025.1 36081.3 36090.8 37860.8 39899.4 40035.2 41052.8 41457.6 34441.7 34612.8 34845.3 34910.7 35115.8 34648.0 34717.3 35188.6 35234.6 35236.0 34012.3 34390.3 34933.8 35045.8 35370.3 29780.4 31935.0 32052.0 32236.8 32468.4 24588.6 24590.9 24794.4 24809.3 24981.4 04:20:29 INFO - PROCESS | 1935 | 04:20:29 INFO - PROCESS | 1935 | [#2] jquery.html Cycles:180 Average:23063.70 Median:48415.88 stddev:59272.30 (122.4%) stddev-sans-first:59414.02 04:20:29 INFO - PROCESS | 1935 | Values: 341.4 354.2 360.4 363.2 379.1 12787.2 13703.2 14008.6 16842.0 16848.5 14943.9 15108.1 15406.5 15475.1 15808.5 4871.1 5047.4 5050.3 5053.4 5064.8 3696.4 5463.6 5672.3 5750.5 5830.4 5574.9 5790.0 5793.3 5797.2 5799.4 5726.0 5846.8 5849.4 5850.4 5858.9 4928.4 5034.7 5109.8 5125.0 5125.6 19753.1 20040.2 20209.7 20300.0 20406.7 1872.2 1892.4 1918.1 2091.0 2351.6 12845.9 12846.4 13134.3 13211.1 13327.2 1688.1 1721.3 1730.9 1746.5 1775.7 66101.0 66560.7 66585.0 67343.7 67371.3 8818.8 9033.2 9349.7 9416.6 9448.7 12129.2 12370.3 12389.9 12394.9 12402.1 6474.1 6563.6 6608.6 6765.6 6804.2 6247.2 6328.6 6474.4 6482.4 6496.2 5678.2 5771.2 5787.7 5823.8 5950.7 1575.7 1615.6 1643.3 1657.8 1669.0 806.4 807.3 858.3 869.3 943.9 16333.0 16495.8 16948.0 17346.7 17445.5 883.4 891.4 915.8 919.6 951.8 9200.3 9244.5 9528.5 9756.4 10071.2 336907.0 357032.0 366609.0 367760.0 367828.0 66350.0 68541.0 68893.3 70280.9 70356.3 926.9 944.2 974.9 977.1 978.0 14883.9 14987.2 15321.8 15562.5 15653.0 35355.3 35558.8 36116.3 36370.0 37139.3 47486.0 48135.8 48696.0 48874.8 49049.0 7596.2 7640.1 7695.3 7719.8 7921.4 11913.4 11927.3 11976.2 12630.5 12774.7 9724.4 9772.5 10108.0 10206.6 11307.5 10492.8 10815.8 10917.8 11001.1 11292.9 10039.1 10634.3 10891.2 10933.7 11118.7 7438.9 7536.5 7582.9 7606.0 8001.8 9948.3 10334.4 10364.2 10393.9 11007.1 04:20:29 INFO - PROCESS | 1935 | 04:20:29 INFO - PROCESS | 1935 | [#3] mootools.html Cycles:180 Average:5034.99 Median:5000.74 stddev:2817.35 (56.3%) stddev-sans-first:2811.05 04:20:29 INFO - PROCESS | 1935 | Values: 1271.2 1363.1 1394.6 1471.7 1716.4 6586.1 7324.5 7574.2 7592.3 7807.7 7394.6 7443.7 7449.9 7458.0 7474.1 2189.4 2215.9 2218.6 2223.9 2230.2 2146.5 2205.9 2234.6 2238.7 2243.7 2155.9 2201.0 2207.4 2207.9 2228.2 2177.4 2181.4 2182.0 2188.0 2189.1 2125.9 2161.8 2164.7 2166.1 2166.4 7563.2 7719.8 7724.5 7728.6 7734.2 4392.1 4510.2 4516.2 4561.9 4603.3 2895.6 3037.4 3056.0 3073.4 3093.5 941.9 949.3 964.5 973.3 978.8 10327.1 10349.2 10374.8 10382.6 10480.8 4071.9 4123.5 4194.1 4312.2 4338.2 8311.7 8401.6 8424.2 8425.6 8427.5 4518.1 4524.5 4524.7 4525.7 4530.2 3665.1 3733.4 3997.6 4152.1 4184.7 3399.0 3425.9 3510.6 3514.7 3519.1 1049.9 1052.2 1066.1 1070.0 1071.5 690.6 787.4 793.7 845.5 851.3 4991.9 5009.6 5172.6 5173.5 5294.4 403.3 409.5 410.1 415.7 418.5 3473.9 3499.3 3505.8 3534.2 3622.8 9221.8 9630.1 9631.2 9685.8 9707.1 9572.3 9578.6 9602.8 9605.5 9608.0 6764.7 7008.6 7065.5 7235.0 7254.3 5222.8 5268.2 5301.2 5373.1 5514.3 8796.7 8882.1 8906.1 8914.0 9009.9 7925.1 8436.5 8757.5 8761.3 8933.4 6314.9 6429.2 6522.2 6526.6 6682.8 7369.9 7405.5 7435.7 7456.2 7562.9 6696.1 6807.5 6833.1 7001.6 7029.0 6630.6 6690.1 6761.9 6959.0 6971.9 6108.5 6166.0 6175.5 6201.5 6212.1 4451.0 4480.7 4574.6 4605.3 4647.6 4499.6 4501.6 4578.5 4664.5 4697.6 04:20:29 INFO - PROCESS | 1935 | 04:20:29 INFO - PROCESS | 1935 | [#4] prototype.html Cycles:180 Average:4118.52 Median:4659.48 stddev:1198.58 (25.7%) stddev-sans-first:1167.05 04:20:29 INFO - PROCESS | 1935 | Values: 293.4 304.0 306.2 309.5 312.8 4785.1 4788.2 4812.9 4823.6 4840.9 4719.1 4757.2 4758.4 4767.6 4782.5 3587.6 3686.6 3707.8 3712.9 3724.4 3631.7 3713.3 3768.5 3781.1 3807.3 3701.7 3723.3 3743.6 3752.2 3764.2 3613.5 3729.2 3750.1 3763.5 3764.4 3155.0 3214.2 3257.5 3306.4 3335.9 4642.8 4650.7 4653.2 4657.5 4661.4 4272.6 4283.6 4305.6 4327.5 4333.2 4721.7 4938.9 4960.7 4969.3 4974.4 3660.4 3660.7 3670.8 3676.5 3678.7 5192.5 5215.0 5242.3 5245.6 5256.2 3766.7 3787.5 3789.3 3790.2 3793.7 3591.1 3751.4 3772.1 3778.5 3782.4 4246.4 4256.6 4261.9 4264.6 4271.7 4957.3 4957.8 4978.1 4986.2 4990.6 1241.9 1277.8 1279.4 1303.6 1318.3 837.6 847.9 863.9 869.5 875.8 3921.7 3940.6 3950.5 3966.5 3970.3 4505.3 4511.5 4517.3 4523.6 4547.5 3962.7 3964.0 3964.8 3966.5 3986.2 2419.3 2427.4 2462.6 2696.2 2717.5 4645.7 4737.5 4832.2 4835.3 4863.6 5219.3 5231.9 5243.4 5245.8 5280.1 4734.3 4870.9 4876.3 4879.4 4879.6 5073.6 5102.6 5130.9 5142.7 5157.6 5025.7 5028.7 5049.4 5058.7 5060.6 4621.6 4834.2 4834.7 4838.8 4875.4 3575.4 3620.5 3625.4 3648.3 3654.3 5216.3 5240.2 5244.8 5245.7 5273.9 4916.3 5045.8 5075.1 5076.1 5108.8 4985.2 4986.2 5007.2 5033.6 5039.0 4929.6 4937.6 4938.5 4942.7 4974.6 4768.5 4989.8 4999.5 5016.8 5063.5 4911.2 4911.3 4927.3 4936.3 4937.5 04:20:29 INFO - PROCESS | 1935 | 04:20:29 INFO - PROCESS | 1935 | [#5] yui.html Cycles:180 Average:16076.14 Median:497.03 stddev:21453.65 (4316.4%) stddev-sans-first:21482.61 04:20:29 INFO - PROCESS | 1935 | Values: 660.2 661.2 686.5 738.8 744.6 12824.3 13608.2 13609.3 13622.2 13635.3 13520.4 13690.9 13692.1 13695.2 13709.4 622.4 737.9 757.6 759.7 764.8 761.7 770.9 771.2 772.7 773.4 811.0 812.3 812.4 812.5 812.7 642.4 769.5 793.5 804.1 804.2 592.3 595.5 596.2 596.2 596.3 13366.1 13388.8 13436.9 13445.9 13464.1 12214.5 12450.2 12644.6 12744.7 13029.7 11556.2 11607.5 11661.0 11984.0 12364.3 3999.4 4062.8 4130.8 4145.5 4167.7 64251.7 64288.4 64453.4 65532.3 66055.0 9850.5 10085.9 10121.4 10310.4 10582.8 18122.1 18247.9 18259.0 18298.6 18348.4 6439.6 6490.8 6520.1 6532.0 6587.3 3738.9 3756.5 3790.2 3818.8 3822.0 2688.9 2792.1 2797.1 2799.2 2801.1 491.4 493.6 493.8 509.3 512.4 500.3 534.9 537.0 538.1 540.6 7148.3 7436.6 7524.9 7670.5 7778.7 416.5 453.7 500.3 505.5 526.7 3409.0 3506.6 3520.5 3571.9 3668.4 54717.2 54944.5 55432.2 55539.4 77574.0 81076.6 82570.6 82595.0 84296.2 85118.0 43979.6 47135.0 62694.7 71068.0 71081.3 7929.6 8777.6 8902.9 8906.3 8980.7 35683.6 36620.5 41945.4 43256.6 43713.6 54627.7 57983.0 59019.4 59222.7 59370.4 10189.5 11476.0 11559.7 11591.9 11918.6 11460.3 11634.8 11718.1 11734.6 12142.9 11370.3 11567.4 12011.3 12024.2 12435.3 11088.7 11460.8 11462.8 11638.5 11681.3 10784.6 10802.7 11423.1 11444.3 12266.8 9534.6 9659.2 9781.6 9814.5 9997.2 9705.8 9738.3 9764.1 9786.9 9994.4 04:20:29 INFO - PROCESS | 1935 | -------- Summary: end -------- 04:20:29 INFO - PROCESS | 1935 | 04:20:30 INFO - TEST-INFO | 1935: exit 0 04:20:30 INFO - TEST-OK | dromaeo_css | took 1121241ms 04:20:30 INFO - TEST-START | kraken 04:20:30 INFO - operating with platform_type : linux_ 04:20:30 INFO - Initialising browser for kraken test... 04:20:30 INFO - TEST-INFO | started process 2240 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAbw3QG/profile http://localhost:57649/getInfo.html) 04:20:34 INFO - PROCESS | 2240 | __metrics Screen width/height:1600/1200 04:20:34 INFO - PROCESS | 2240 | colorDepth:24 04:20:34 INFO - PROCESS | 2240 | Browser inner width/height: 1024/697 04:20:34 INFO - PROCESS | 2240 | __metrics 04:20:35 INFO - PROCESS | 2240 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:20:35 INFO - PROCESS | 2240 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:20:36 INFO - TEST-INFO | 2240: exit 0 04:20:36 INFO - Browser initialized. 04:20:36 INFO - Running cycle 1/1 for kraken test... 04:20:36 INFO - TEST-INFO | started process 2319 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAbw3QG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:20:48 INFO - PROCESS | 2319 | RSS: Main: 156659712 04:20:48 INFO - PROCESS | 2319 | 04:20:51 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:57649/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57649/tests/kraken/driver.html?testName=audio-beat-detection) 04:20:52 INFO - PROCESS | 2319 | RSS: Main: 204873728 04:20:52 INFO - PROCESS | 2319 | 04:20:55 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:57649/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57649/tests/kraken/driver.html?testName=audio-dft) 04:20:55 INFO - PROCESS | 2319 | RSS: Main: 196112384 04:20:55 INFO - PROCESS | 2319 | 04:20:58 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:57649/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57649/tests/kraken/driver.html?testName=audio-fft) 04:20:59 INFO - PROCESS | 2319 | RSS: Main: 276918272 04:20:59 INFO - PROCESS | 2319 | 04:21:01 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:57649/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57649/tests/kraken/driver.html?testName=audio-oscillator) 04:21:02 INFO - PROCESS | 2319 | RSS: Main: 186413056 04:21:02 INFO - PROCESS | 2319 | 04:21:04 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:57649/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57649/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:21:05 INFO - PROCESS | 2319 | RSS: Main: 191344640 04:21:05 INFO - PROCESS | 2319 | 04:21:08 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:57649/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57649/tests/kraken/driver.html?testName=imaging-darkroom) 04:21:09 INFO - PROCESS | 2319 | RSS: Main: 227799040 04:21:09 INFO - PROCESS | 2319 | 04:21:12 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:57649/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57649/tests/kraken/driver.html?testName=imaging-desaturate) 04:21:13 INFO - PROCESS | 2319 | RSS: Main: 227627008 04:21:13 INFO - PROCESS | 2319 | 04:21:16 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:57649/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57649/tests/kraken/driver.html?testName=json-parse-financial) 04:21:16 INFO - PROCESS | 2319 | RSS: Main: 227336192 04:21:16 INFO - PROCESS | 2319 | 04:21:18 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:57649/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57649/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:21:19 INFO - PROCESS | 2319 | RSS: Main: 189919232 04:21:19 INFO - PROCESS | 2319 | 04:21:20 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:57649/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57649/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:21:21 INFO - PROCESS | 2319 | RSS: Main: 197222400 04:21:21 INFO - PROCESS | 2319 | 04:21:23 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:57649/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57649/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:21:24 INFO - PROCESS | 2319 | RSS: Main: 194416640 04:21:24 INFO - PROCESS | 2319 | 04:21:27 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:57649/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57649/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:21:27 INFO - PROCESS | 2319 | RSS: Main: 189485056 04:21:27 INFO - PROCESS | 2319 | 04:21:30 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:57649/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57649/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:21:31 INFO - PROCESS | 2319 | RSS: Main: 193019904 04:21:31 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:57649/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57649/tests/kraken/driver.html?testName=ai-astar) 04:21:33 INFO - PROCESS | 2319 | RSS: Main: 242880512 04:21:33 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | __start_tp_report 04:21:33 INFO - PROCESS | 2319 | _x_x_mozilla_page_load 04:21:33 INFO - PROCESS | 2319 | _x_x_mozilla_page_load_details 04:21:33 INFO - PROCESS | 2319 | |i|pagename|runs| 04:21:33 INFO - PROCESS | 2319 | |0;ai-astar;105;115;115;117;110;116;115;117;116;116 04:21:33 INFO - PROCESS | 2319 | |1;audio-beat-detection;147;122;123;195;124;125;143;122;194;123 04:21:33 INFO - PROCESS | 2319 | |2;audio-dft;161;163;161;160;162;161;161;160;160;161 04:21:33 INFO - PROCESS | 2319 | |3;audio-fft;75;76;76;112;76;76;77;75;106;76 04:21:33 INFO - PROCESS | 2319 | |4;audio-oscillator;87;86;132;86;86;87;123;90;86;123 04:21:33 INFO - PROCESS | 2319 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;116;116 04:21:33 INFO - PROCESS | 2319 | |6;imaging-darkroom;146;146;146;147;146;146;146;148;146;146 04:21:33 INFO - PROCESS | 2319 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;95;95 04:21:33 INFO - PROCESS | 2319 | |8;json-parse-financial;64;63;65;65;65;66;65;64;66;64 04:21:33 INFO - PROCESS | 2319 | |9;json-stringify-tinderbox;65;66;65;65;66;66;66;66;65;66 04:21:33 INFO - PROCESS | 2319 | |10;stanford-crypto-aes;85;86;86;83;88;83;83;85;93;87 04:21:33 INFO - PROCESS | 2319 | |11;stanford-crypto-ccm;133;133;132;134;135;133;131;131;158;130 04:21:33 INFO - PROCESS | 2319 | |12;stanford-crypto-pbkdf2;174;177;171;174;174;171;176;174;172;172 04:21:33 INFO - PROCESS | 2319 | |13;stanford-crypto-sha256-iterative;68;75;70;67;73;70;68;67;73;68 04:21:33 INFO - PROCESS | 2319 | __end_tp_report 04:21:33 INFO - PROCESS | 2319 | __start_cc_report 04:21:33 INFO - PROCESS | 2319 | _x_x_mozilla_cycle_collect,1923 04:21:33 INFO - PROCESS | 2319 | __end_cc_report 04:21:33 INFO - PROCESS | 2319 | __startTimestamp1471346493386__endTimestamp 04:21:33 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | ------- Summary: start ------- 04:21:33 INFO - PROCESS | 2319 | Number of tests: 14 04:21:33 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | [#0] ai-astar Cycles:10 Average:114.20 Median:116.00 stddev:3.79 (3.3%) stddev-sans-first:2.11 04:21:33 INFO - PROCESS | 2319 | Values: 105.0 115.0 115.0 117.0 110.0 116.0 115.0 117.0 116.0 116.0 04:21:33 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | [#1] audio-beat-detection Cycles:10 Average:141.80 Median:134.00 stddev:29.20 (21.8%) stddev-sans-first:30.91 04:21:33 INFO - PROCESS | 2319 | Values: 147.0 122.0 123.0 195.0 124.0 125.0 143.0 122.0 194.0 123.0 04:21:33 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 04:21:33 INFO - PROCESS | 2319 | Values: 161.0 163.0 161.0 160.0 162.0 161.0 161.0 160.0 160.0 161.0 04:21:33 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | [#3] audio-fft Cycles:10 Average:82.50 Median:76.00 stddev:14.05 (18.5%) stddev-sans-first:14.64 04:21:33 INFO - PROCESS | 2319 | Values: 75.0 76.0 76.0 112.0 76.0 76.0 77.0 75.0 106.0 76.0 04:21:33 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | [#4] audio-oscillator Cycles:10 Average:98.60 Median:86.00 stddev:19.10 (22.2%) stddev-sans-first:19.80 04:21:33 INFO - PROCESS | 2319 | Values: 87.0 86.0 132.0 86.0 86.0 87.0 123.0 90.0 86.0 123.0 04:21:33 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:21:33 INFO - PROCESS | 2319 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 04:21:33 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 04:21:33 INFO - PROCESS | 2319 | Values: 146.0 146.0 146.0 147.0 146.0 146.0 146.0 148.0 146.0 146.0 04:21:33 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:21:33 INFO - PROCESS | 2319 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 04:21:33 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:65.00 stddev:0.95 (1.5%) stddev-sans-first:0.97 04:21:33 INFO - PROCESS | 2319 | Values: 64.0 63.0 65.0 65.0 65.0 66.0 65.0 64.0 66.0 64.0 04:21:33 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:66.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 04:21:33 INFO - PROCESS | 2319 | Values: 65.0 66.0 65.0 65.0 66.0 66.0 66.0 66.0 65.0 66.0 04:21:33 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:86.00 stddev:3.03 (3.5%) stddev-sans-first:3.20 04:21:33 INFO - PROCESS | 2319 | Values: 85.0 86.0 86.0 83.0 88.0 83.0 83.0 85.0 93.0 87.0 04:21:33 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | [#11] stanford-crypto-ccm Cycles:10 Average:135.00 Median:133.00 stddev:8.22 (6.2%) stddev-sans-first:8.69 04:21:33 INFO - PROCESS | 2319 | Values: 133.0 133.0 132.0 134.0 135.0 133.0 131.0 131.0 158.0 130.0 04:21:33 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:174.00 stddev:2.01 (1.2%) stddev-sans-first:2.13 04:21:33 INFO - PROCESS | 2319 | Values: 174.0 177.0 171.0 174.0 174.0 171.0 176.0 174.0 172.0 172.0 04:21:33 INFO - PROCESS | 2319 | 04:21:33 INFO - PROCESS | 2319 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:70.00 stddev:2.85 (4.1%) stddev-sans-first:2.93 04:21:33 INFO - PROCESS | 2319 | Values: 68.0 75.0 70.0 67.0 73.0 70.0 68.0 67.0 73.0 68.0 04:21:33 INFO - PROCESS | 2319 | -------- Summary: end -------- 04:21:33 INFO - PROCESS | 2319 | 04:21:34 INFO - TEST-INFO | 2319: exit 0 04:21:34 INFO - TEST-OK | kraken | took 64137ms 04:21:34 INFO - SUITE-END | took 1185s 04:21:34 INFO - Completed test suite (00:19:45) 04:21:34 INFO - javascript benchmark 04:21:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2642.0224516928733, 2663.2542887991344, 2682.1953413975803, 2701.75684188423, 2709.634199383083, 4666.253399456088, 4731.361266229058, 4794.137055330039, 4832.782524786384, 4847.612191024723, 4772.04559088182, 4794.9760251198695, 4801.0, 4808.591269742067, 4808.7114773113635, 4386.517483076859, 4407.9779601101945, 4460.973975985526, 4481.955180448203, 4482.663800214997, 4428.6014258716705, 4462.0, 4467.3299005149165, 4476.082403107355, 4483.193025255453, 4366.148601022786, 4482.125985432842, 4528.230200865861, 4529.660275479352, 4530.841420550281, 4317.784110794448, 4388.0, 4465.1739428205565, 4497.595216430521, 4526.3210518422175, 4359.629431498295, 4407.955920440787, 4413.139437809628, 4419.4033805436175, 4484.439445069366, 4734.526547345273, 4762.261849413347, 4821.783019764084, 4876.317315575822, 4915.262710593403, 5174.715390653512, 5178.663386879878, 5198.324217851655, 5218.269442278084, 5223.71269580173, 5026.472220416877, 5055.216441451544, 5140.177571588528, 5256.789728410898, 5258.3689957205, 4843.104025755247, 4844.709317440965, 4878.121938051185, 4888.902221955541, 4890.0, 5334.14653655421, 5382.946170538245, 5388.0, 5388.030154572136, 5390.326209223847, 4903.8528844134735, 5023.045621331935, 5075.974620126875, 5091.312672789126, 5152.201408781645, 5203.167493201146, 5204.7918083277, 5226.947730522722, 5237.659552129099, 5247.868803279948, 5132.5124113209185, 5144.0, 5146.253793199965, 5149.562287205629, 5195.506426889439, 4893.681910675795, 4956.5786908112495, 4978.751062446863, 5065.39215294167, 5100.979804039207, 4408.603225709701, 4409.647228221798, 4437.09039646878, 4450.265706158448, 4455.977720111378, 3811.8475260989803, 3819.312523745697, 3829.329867273216, 3833.21419109082, 3864.942025869614, 4312.202242585132, 4363.563643635611, 4431.8005689743395, 4452.554744525586, 4485.663575231871, 5168.715720635326, 5197.558207552324, 5198.298229738937, 5222.477752224747, 5239.476052394806, 4441.71128876622, 4447.355133505624, 4449.199144154083, 4449.755263460541, 4497.730136191839, 4521.253993091169, 4575.58819706228, 4627.676062675648, 4656.580907718254, 4694.366260554782, 4839.588634966068, 4861.708297502161, 4863.0, 4889.242167464049, 4908.141075311734, 5159.896802063862, 5170.069387510284, 5174.223866420067, 5176.896462070813, 5224.0, 4723.362346083235, 4801.759912004456, 4838.709677419366, 4841.95158048415, 4898.730569818694, 5091.66904151229, 5094.312267843942, 5111.3355263815465, 5116.130257856099, 5117.820876269313, 5159.2003239497935, 5178.456262092426, 5179.378474583074, 5180.145276029413, 5222.634415590872, 4808.831690890802, 4966.0, 5015.874603134805, 5017.874553136201, 5037.168867136881, 5034.697918124924, 5050.04049230645, 5050.368703912011, 5051.19180931064, 5096.980603879165, 4941.036497882878, 5000.949990500048, 5006.549410553068, 5009.198528235464, 5011.123053465702, 4833.782479788348, 4873.707577545359, 4909.95090049095, 4928.704277743347, 5033.094043072282, 4798.256270278112, 4832.323474713473, 4840.34655321527, 4865.318855360181, 4870.756462176948], "value": 4732.5105325355935, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.4108765252658, 570.2842932120188, 573.1374281706037, 573.8737477754894, 573.9799107031255, 28653.283667908312, 38010.61989380106, 39267.41098883515, 39334.60665393345, 42160.78919605405, 39215.0, 39591.0, 39676.0, 39686.8015659922, 39693.40459893104, 7536.284053014982, 7669.923300767005, 7672.578008209546, 7673.0, 7676.654550545211, 7612.162662107164, 7689.923100768977, 7692.884606730903, 7696.615169241515, 7699.769006929801, 7706.922930770676, 7752.496087754306, 7753.612319384037, 7754.689812407497, 7755.457118001742, 7678.5392876427595, 7717.421193410489, 7721.498102623312, 7726.7681969541, 7731.342835858959, 7452.143003554606, 7499.850002999964, 7501.8874716878745, 7503.174650788409, 7505.512141710771, 34949.65050349515, 35198.0, 35256.29487410237, 35261.11847203815, 35264.471032934525, 3152.7793054486197, 3196.31279274957, 3611.151379425859, 3645.9817700911326, 3662.688671462929, 28374.858125709445, 28808.279793005135, 28831.0, 28865.711342886512, 28973.56539651907, 3439.1917899293644, 3574.070741607175, 3607.1703508193264, 3661.835217415223, 3738.9065273368383, 237672.0, 238593.0, 238810.0, 238812.0, 239154.99999999825, 48263.0, 48348.75825620849, 48617.0, 48683.26975095376, 48732.26901596479, 50130.0, 50535.49464505308, 50604.74697626488, 50898.74550627223, 50981.74509127505, 12719.554815581596, 12932.353382331034, 12963.740725185444, 12978.480860765463, 12979.286139262242, 8909.0, 9148.451092934445, 9151.267898568098, 9317.11487408695, 9319.953400232955, 14502.0, 14998.100113993196, 15006.974058501963, 15464.072155670696, 15491.767623485657, 4349.326889886082, 5416.729163541807, 5734.770609175586, 5742.569307301886, 5788.305403351625, 774.3340726974798, 895.3016647015338, 937.5218638494281, 961.5096300886598, 1002.0981116994617, 26580.348439023095, 26950.0, 27012.594811077848, 27234.1829745104, 27288.181354559394, 956.5026186383042, 964.6671898195111, 981.9509024548744, 1015.2943704125562, 1046.5238316565944, 14299.714005719827, 14518.709625807636, 14611.926940365443, 14826.184559849096, 14842.554723358317, 377935.0, 378586.0, 381985.0, 383439.0, 383999.0, 47680.28479572809, 47844.76077619589, 47875.0, 47918.760406198446, 48003.75998119987, 1106.4633652678444, 1113.259682311254, 1118.7203199200037, 1159.9826002609968, 1162.7093226693328, 25798.87100564485, 26594.33514162084, 27052.72947270502, 27581.586276206275, 27615.30961725973, 41720.79139604283, 42086.57913420827, 42090.7895460533, 42099.0, 42132.0, 341500.0, 342736.0, 343271.0, 343961.0, 345601.0, 23782.405439864142, 35719.46420803638, 36025.09937251485, 36081.27837443289, 36090.8195459021, 37860.810695947446, 39899.40150897797, 40035.1992960145, 41052.79473602613, 41457.58542414537, 34441.65558344385, 34612.82693586516, 34845.303093938484, 34910.65089349074, 35115.82442087875, 34648.0, 34717.305653887284, 35188.64811351854, 35234.64765352416, 35236.0, 34012.31975360528, 34390.31219375548, 34933.8253308742, 35045.824770877, 35370.29259414849, 29780.404391912474, 31935.0, 32052.0, 32236.83881580577, 32468.350632986734, 24588.6311705321, 24590.877045614656, 24794.380140496633, 24809.284247653413, 24981.446708471987], "value": 17016.0675933618, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.3803945838305, 354.2472246476238, 360.3765485709722, 363.2154546180251, 379.0920744816165, 12787.168834025782, 13703.201585149647, 14008.649783755398, 16841.98948063117, 16848.494545163634, 14943.92528037361, 15108.093514389117, 15406.460773872917, 15475.071495710237, 15808.52574422769, 4871.074495845795, 5047.4447810740785, 5050.318207042057, 5053.393592768854, 5064.772085256154, 3696.4270538066507, 5463.562914966793, 5672.262605861232, 5750.453706897859, 5830.4169583041785, 5574.916376254339, 5789.9710501447435, 5793.275840519935, 5797.188393624896, 5799.3620701722775, 5725.971370143144, 5846.795362162304, 5849.356570777211, 5850.414958504158, 5858.853528661796, 4928.408590969071, 5034.723090230036, 5109.846704598868, 5124.9743751281, 5125.641205115643, 19753.11110999994, 20040.190354315004, 20209.696854547194, 20300.0, 20406.69389959152, 1872.2043131668986, 1892.3660573707825, 1918.1080797429122, 2090.989545052265, 2351.6237402015595, 12845.871541284561, 12846.35768211595, 13134.34328283582, 13211.14127581704, 13327.200367977855, 1688.1390490849756, 1721.277063633277, 1730.8528775054135, 1746.4847869878417, 1775.698131317679, 66101.0, 66560.66719666419, 66585.0, 67343.66328168426, 67371.32628673699, 8818.779530511789, 9033.232175265044, 9349.672761453316, 9416.576254068583, 9448.716538503855, 12129.211601246066, 12370.319632420304, 12389.876101239053, 12394.938025309995, 12402.06984476168, 6474.093626892239, 6563.573367731082, 6608.636524991076, 6765.594064356154, 6804.21751498584, 6247.219097612799, 6328.557001009885, 6474.352564743582, 6482.416582507502, 6496.220453545605, 5678.233438485834, 5771.163181338767, 5787.71061446926, 5823.79616713413, 5950.702464876739, 1575.7400028995087, 1615.6041769766248, 1643.2933838449342, 1657.78448801655, 1668.96662066758, 806.3549160671439, 807.3097501636016, 858.2533196119416, 869.2698133567707, 943.9386439881523, 16333.02001879891, 16495.752563711554, 16948.0, 17346.739798903025, 17445.47663570095, 883.3728053082383, 891.4027601507006, 915.7802127489488, 919.6137622198693, 951.7810903492098, 9200.30997675177, 9244.491552964651, 9528.523573821281, 9756.365836220622, 10071.194304455626, 336907.0, 357032.0, 366609.0, 367760.0, 367828.0, 66350.0, 68541.0, 68893.3110668897, 70280.94578581223, 70356.296437036, 926.9490178040272, 944.2493217891792, 974.907383798538, 977.1352353167498, 977.9804403911882, 14883.925580372463, 14987.209209369237, 15321.770173447185, 15562.455314063953, 15653.0, 35355.29289414249, 35558.822205888806, 36116.27767444584, 36370.0, 37139.257214856094, 47486.0, 48135.75932120457, 48696.0, 48874.755626223065, 49049.0, 7596.202398748052, 7640.121386040454, 7695.345895598937, 7719.845603087794, 7921.366290696846, 11913.404329783649, 11927.343996080299, 11976.221545599508, 12630.494780209056, 12774.680632983878, 9724.367916085432, 9772.462514561767, 10108.0, 10206.642767503101, 11307.491162897853, 10492.842607360742, 10815.783684326112, 10917.781644367227, 11001.119910406987, 11292.887071129184, 10039.096481316716, 10634.30876992993, 10891.237613366988, 10933.671989840317, 11118.666440006813, 7438.925610743823, 7536.547807131589, 7582.924170758442, 7605.961970190114, 8001.759947201594, 9948.303618746619, 10334.379937203792, 10364.170866330525, 10393.896061039293, 11007.119430445386], "value": 8189.930048205551, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1271.1864406779657, 1363.1071648070515, 1394.5816255123464, 1471.7424450721128, 1716.3906813081355, 6586.0779490871255, 7324.450666200029, 7574.242575742423, 7592.316691497771, 7807.726729564466, 7394.593297368642, 7443.739469118582, 7449.88825167623, 7458.030456040707, 7474.140473845509, 2189.441692368448, 2215.9224427145055, 2218.6228341182036, 2223.866568005925, 2230.208276061999, 2146.51703366742, 2205.933821985335, 2234.575430668176, 2238.6865838782583, 2243.741969673492, 2155.924542641008, 2201.0, 2207.4371035386034, 2207.922722704706, 2228.2312602152233, 2177.3903307073965, 2181.432827464863, 2182.03990244292, 2188.0, 2189.1462329691276, 2125.8937053147433, 2161.8054375106317, 2164.729408823897, 2166.068590506082, 2166.425897137244, 7563.205863384375, 7719.768406947801, 7724.497907635986, 7728.613569321511, 7734.226577342277, 4392.055708022758, 4510.188166130095, 4516.20966330894, 4561.885952851172, 4603.28649059393, 2895.6235689360246, 3037.4228896509594, 3055.9694403055905, 3073.3699591583672, 3093.458644737162, 941.9058094190526, 949.2690628216304, 964.5081008685551, 973.3284034016576, 978.8238117138912, 10327.070563649308, 10349.172066234683, 10374.84437733427, 10382.636607718769, 10480.790384192274, 4071.918561628751, 4123.525794533667, 4194.098268872207, 4312.159128969821, 4338.154059958278, 8311.667533298722, 8401.579921003926, 8424.157584241526, 8425.620847061897, 8427.536485493234, 4518.073794872043, 4524.47968483622, 4524.728516289033, 4525.728456292633, 4530.22986092365, 3665.065408320861, 3733.3653278942643, 3997.6002399760373, 4152.148809494107, 4184.686148538811, 3399.0, 3425.9143521411665, 3510.5611798525183, 3514.683678468899, 3519.0674471265133, 1049.9317544359592, 1052.2055847834988, 1066.0565399621237, 1069.973250668724, 1071.4856868703066, 690.6098054599136, 787.3858390455454, 793.6706266899172, 845.5434065604504, 851.2508992087047, 4991.850244492598, 5009.624278179152, 5172.586193104617, 5173.456787037307, 5294.444083371268, 403.3264448371227, 409.5433591545465, 410.0998308712407, 415.74223981131286, 418.5145231531409, 3473.930521389558, 3499.2826470573473, 3505.772124811879, 3534.151803567125, 3622.836972336251, 9221.81556368869, 9630.133288004114, 9631.229501639993, 9685.757856053655, 9707.077827606367, 9572.329936904489, 9578.56896439662, 9602.807943841084, 9605.47169905648, 9608.0, 6764.661766911635, 7008.649567521604, 7065.50541462103, 7234.963825180946, 7254.274572542809, 5222.791088356423, 5268.209768534751, 5301.151815709468, 5373.11343628297, 5514.310711161105, 8796.692115775917, 8882.0673829247, 8906.109389061043, 8913.955430222808, 9009.864852027356, 7925.128235894163, 8436.45163064399, 8757.47455152693, 8761.342899282707, 8933.374663773473, 6314.936850631435, 6429.196350456193, 6522.21733391996, 6526.608403495806, 6682.83292917681, 7369.926300737138, 7405.518641288514, 7435.7025718970635, 7456.217097204715, 7562.924370756442, 6696.0960270362875, 6807.455403567802, 6833.086735714008, 7001.649917504206, 7028.964855175692, 6630.635315057718, 6690.126764505946, 6761.898571521333, 6958.965205173941, 6971.8605627886145, 6108.4807791338235, 6166.013437849922, 6175.475084617858, 6201.534884883562, 6212.06818977157, 4451.0, 4480.663950203682, 4574.565416285448, 4605.263157894855, 4647.581717645428, 4499.550044995474, 4501.639868810422, 4578.49636539987, 4664.533546645308, 4697.600703940203], "value": 3994.7204903117986, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.4102454067325, 304.01044599827566, 306.2176139962395, 309.4538140182577, 312.7513626666798, 4785.1289226464105, 4788.20994535902, 4812.879678008052, 4823.565879070883, 4840.886596082899, 4719.07977944301, 4757.191277482828, 4758.3814104166495, 4767.594754445876, 4782.497837727041, 3587.6232995535483, 3686.6497682720233, 3707.7775333479944, 3712.8700495482663, 3724.430143201196, 3631.7457777955424, 3713.275911197318, 3768.5491085931976, 3781.1303400217967, 3807.333716599584, 3701.7229055975645, 3723.2739615774935, 3743.5694895087004, 3752.1745216052423, 3764.228333191703, 3613.5302410686704, 3729.2355067211292, 3750.062484378905, 3763.4542991266385, 3764.416515440111, 3154.952675709866, 3214.212517933098, 3257.5439438478634, 3306.3883181611477, 3335.8832440864694, 4642.837500687459, 4650.721241524717, 4653.162430762495, 4657.534246575349, 4661.417322834645, 4272.6154646082, 4283.614474697292, 4305.590968857953, 4327.502337231227, 4333.198358303724, 4721.669483136182, 4938.901221975576, 4960.6527543072, 4969.254611808222, 4974.403071631391, 3660.359437098497, 3660.670539651444, 3670.834812433447, 3676.522052133232, 3678.6873115784924, 5192.532672059533, 5214.999999999962, 5242.344706911636, 5245.632805703603, 5256.185291279858, 3766.7174961877968, 3787.5265591801026, 3789.317922773872, 3790.223004284115, 3793.7344385893275, 3591.120175557005, 3751.362268414376, 3772.0946972726347, 3778.471014058034, 3782.3759079751535, 4246.428821336125, 4256.59562341577, 4261.893452663646, 4264.594863487963, 4271.658267338606, 4957.256411538298, 4957.752112394438, 4978.054169707744, 4986.227134794112, 4990.6257030722845, 1241.8696036916174, 1277.7955527115616, 1279.3922886628964, 1303.55027515507, 1318.347418028063, 837.623069618674, 847.8516259654535, 863.937357050831, 869.5043825019804, 875.8248350329959, 3921.705872059607, 3940.5862384449792, 3950.506186726659, 3966.5438474575217, 3970.3250447424007, 4505.301678239878, 4511.503734589192, 4517.299818528133, 4523.592876641118, 4547.544326063889, 3962.6725047108966, 3964.0089977505622, 3964.762114273095, 3966.504186976628, 3986.242613903407, 2419.2742177347077, 2427.3810178404747, 2462.5936720440927, 2696.191142657195, 2717.4565086982684, 4645.744484053342, 4737.502562230985, 4832.178529649968, 4835.323054772335, 4863.586595139382, 5219.2953951216095, 5231.9476805232225, 5243.397009343898, 5245.763940622604, 5280.128778751539, 4734.3004809130925, 4870.931313272616, 4876.341693871282, 4879.414470263591, 4879.56083952446, 5073.644844860824, 5102.550775627646, 5130.8717282066755, 5142.742862856768, 5157.613179011664, 5025.74871256443, 5028.723420211923, 5049.368828896388, 5058.747062646927, 5060.645754797129, 4621.6302695784925, 4834.178189707828, 4834.71879951806, 4838.782254798472, 4875.390576177978, 3575.392183328788, 3620.5112309837837, 3625.3655610268625, 3648.288583726148, 3654.3056819204267, 5216.295800066942, 5240.161574148118, 5244.7902083916215, 5245.737713114406, 5273.947260527499, 4916.287138364989, 5045.789010637494, 5075.137226671549, 5076.137056700296, 5108.821191258288, 4985.227289770091, 4986.227134794112, 5007.173816320266, 5033.577167526118, 5038.974805125951, 4929.605631549539, 4937.629677774109, 4938.456769755396, 4942.654014218971, 4974.5771609413605, 4768.523147685204, 4989.750512474289, 4999.450060493416, 5016.792611251038, 5063.518965698253, 4911.165101932752, 4911.287863259879, 4927.260910863399, 4936.284238785428, 4937.5062493751775], "value": 3742.730692027749, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [660.1615947746366, 661.1801366305782, 686.519436394524, 738.7894450081728, 744.6351287868943, 12824.294663793487, 13608.183508989468, 13609.251491167981, 13622.182669039865, 13635.250061246628, 13520.391582378792, 13690.931545342259, 13692.110012849158, 13695.246761428118, 13709.3830777615, 622.3931666625027, 737.8634952533798, 757.5606148433895, 759.741687826139, 764.77439155449, 761.702935855017, 770.9306162445379, 771.1594362145261, 772.7179579453515, 773.4392565390103, 810.9959450202741, 812.3338862133053, 812.358236992775, 812.4515951732586, 812.6586833529902, 642.4282388674084, 769.5421224371546, 793.5119901260741, 804.0994086622961, 804.1958041958042, 592.3365830270081, 595.4968051996057, 596.171321862607, 596.2010905386802, 596.2546816479401, 13366.06437549372, 13388.79916801239, 13436.865631343659, 13445.86554134456, 13464.057515973886, 12214.511419543207, 12450.190737602026, 12644.55744048963, 12744.681382965407, 13029.739405211938, 11556.245738205067, 11607.477663505078, 11661.0, 11984.041276697842, 12364.319962402064, 3999.420084087791, 4062.837486500536, 4130.752154870687, 4145.481814773153, 4167.74993500391, 64251.678741605996, 64288.357116428706, 64453.355466445675, 65532.34467655358, 66055.0, 9850.458224797561, 10085.949570252249, 10121.392716437038, 10310.432926188982, 10582.841257381146, 18122.09389530518, 18247.90876045638, 18259.0, 18298.634027319644, 18348.44954651336, 6439.58142720731, 6490.8052758417325, 6520.054592084121, 6532.0, 6587.308332625101, 3738.9065273368383, 3756.492873462102, 3790.1851102012647, 3818.8281527331337, 3822.0444888777806, 2688.8655567221563, 2792.148394739583, 2797.118907544117, 2799.1602519244148, 2801.117647940933, 491.43484992259187, 493.62484511770595, 493.7777999900059, 509.33023074656705, 512.3928145148011, 500.2845930319605, 534.9304590403223, 537.0279793573644, 538.1336048961169, 540.5565073551156, 7148.320909513588, 7436.553806771502, 7524.9247507525315, 7670.501417407851, 7778.727744528915, 416.50435981182295, 453.7118929479804, 500.32706010415933, 505.4919805595353, 526.6945171800346, 3409.006030245805, 3506.6142724300307, 3520.5423294972, 3571.8928432147077, 3668.4497325401403, 54717.179242311395, 54944.45055549473, 55432.16851747227, 55539.444605554236, 77574.0, 81076.59461702654, 82570.58714706507, 82595.0, 84296.15703842884, 85118.0, 43979.560204398185, 47134.999999999316, 62694.68652656708, 71068.0, 71081.28918710747, 7929.563873986986, 8777.648894044167, 8902.910970890209, 8906.287497000354, 8980.730578082668, 35683.643163568035, 36620.45069324016, 41945.38600753975, 43256.567434325254, 43713.56286437222, 54627.72686136544, 57983.0, 59019.40980590139, 59222.70388648029, 59370.40629593649, 10189.490525473548, 11476.0, 11559.653210403701, 11591.88408115908, 11918.616276744859, 11460.255083419552, 11634.844868735016, 11718.121140914294, 11734.647960561197, 12142.878571214174, 11370.31778093317, 11567.363794991019, 12011.279323240633, 12024.218425802295, 12435.253884766942, 11088.722781930517, 11460.828087578526, 11462.82805757931, 11638.53445862156, 11681.299122052704, 10784.568617255536, 10802.72993175177, 11423.086153107564, 11444.313341199555, 12266.75466490683, 9534.570944307661, 9659.178969787647, 9781.559829807533, 9814.509274536387, 9997.20022398192, 9705.757356066155, 9738.269629777653, 9764.072413120743, 9786.902130978884, 9994.400335979864], "value": 6333.896237775853, "unit": "score"}], "name": "dromaeo_css", "value": 6298.993226560612, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 115.0, 117.0, 110.0, 116.0, 115.0, 117.0, 116.0, 116.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 122.0, 123.0, 195.0, 124.0, 125.0, 143.0, 122.0, 194.0, 123.0], "value": 141.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 161.0, 160.0, 162.0, 161.0, 161.0, 160.0, 160.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 112.0, 76.0, 76.0, 77.0, 75.0, 106.0, 76.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 132.0, 86.0, 86.0, 87.0, 123.0, 90.0, 86.0, 123.0], "value": 98.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 148.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 65.0, 65.0, 65.0, 66.0, 65.0, 64.0, 66.0, 64.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 66.0, 66.0, 66.0, 66.0, 65.0, 66.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 86.0, 83.0, 88.0, 83.0, 83.0, 85.0, 93.0, 87.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 133.0, 132.0, 134.0, 135.0, 133.0, 131.0, 131.0, 158.0, 130.0], "value": 135.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 177.0, 171.0, 174.0, 174.0, 171.0, 176.0, 174.0, 172.0, 172.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 70.0, 67.0, 73.0, 70.0, 68.0, 67.0, 73.0, 68.0], "value": 69.9, "unit": "score"}], "name": "kraken", "value": 1550.9000000000003, "alertThreshold": 2.0}]} 04:21:34 INFO - Return code: 0 04:21:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:21:34 INFO - # TBPL SUCCESS # 04:21:34 INFO - Running post-action listener: _resource_record_post_action 04:21:34 INFO - [mozharness: 2016-08-16 11:21:34.667287Z] Finished run-tests step (success) 04:21:34 INFO - Running post-run listener: _resource_record_post_run 04:21:34 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 407461888; Read time: 12; Write time: 341080 04:21:34 INFO - TinderboxPrint: CPU usage
13.0% 04:21:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 04:21:34 INFO - TinderboxPrint: I/O write bytes / time
407,461,888 / 341,080 04:21:34 INFO - TinderboxPrint: CPU idle
8,346.2 (87.0%) 04:21:34 INFO - TinderboxPrint: CPU user
1,202.0 (12.5%) 04:21:34 INFO - TinderboxPrint: Swap in / out
0 / 0 04:21:34 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 114130944; Read time: 0; Write time: 95968 04:21:34 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 293330944; Read time: 0; Write time: 245112 04:21:35 INFO - Running post-run listener: _upload_blobber_files 04:21:35 INFO - Blob upload gear active. 04:21:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:21:35 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 04:21:35 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:21:35 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:21:35 INFO - (blobuploader) - INFO - Open directory for files ... 04:21:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:21:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:21:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:21:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:21:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:21:37 INFO - (blobuploader) - INFO - Done attempting. 04:21:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:21:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:21:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:21:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:21:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:21:44 INFO - (blobuploader) - INFO - Done attempting. 04:21:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:21:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:21:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:21:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:21:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:21:45 INFO - (blobuploader) - INFO - Done attempting. 04:21:45 INFO - (blobuploader) - INFO - Iteration through files over. 04:21:45 INFO - Return code: 0 04:21:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:21:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:21:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4a53081fc4c6578e539d1d79ee64205b5de9a6c8b8fb95dac3e17406ecc773416b4e0acc1cf0d65febe3934b1d30292f9277b8c33563ae6fc432354d5041c965", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/012002cc53ec8323fb7c755f8ef59dc06d793be52060874c5e561716abb95f39106e495af84e62e31c2e75c0995bbc833730ff45e90ac0e63bc877130746691c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e2ab1155507cc0f1aa534545c7105cb09c9cca400665d14f510e226387c3cc9fa442c112dcb5d9cf0091514692195c8c83f003772cc5f31d224a2c946875c882"} 04:21:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:21:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:21:45 INFO - Contents: 04:21:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4a53081fc4c6578e539d1d79ee64205b5de9a6c8b8fb95dac3e17406ecc773416b4e0acc1cf0d65febe3934b1d30292f9277b8c33563ae6fc432354d5041c965", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/012002cc53ec8323fb7c755f8ef59dc06d793be52060874c5e561716abb95f39106e495af84e62e31c2e75c0995bbc833730ff45e90ac0e63bc877130746691c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e2ab1155507cc0f1aa534545c7105cb09c9cca400665d14f510e226387c3cc9fa442c112dcb5d9cf0091514692195c8c83f003772cc5f31d224a2c946875c882"} 04:21:45 INFO - Running post-run listener: copy_logs_to_upload_dir 04:21:45 INFO - Copying logs to upload dir... 04:21:45 INFO - mkdir: /builds/slave/test/build/upload/logs 04:21:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.800980 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-08-16 04:21:44.855475) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 04:21:44.858523) ========= 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=4300f881736b422476174874000001bc-1471344373.543666-1475995205 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4a53081fc4c6578e539d1d79ee64205b5de9a6c8b8fb95dac3e17406ecc773416b4e0acc1cf0d65febe3934b1d30292f9277b8c33563ae6fc432354d5041c965", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/012002cc53ec8323fb7c755f8ef59dc06d793be52060874c5e561716abb95f39106e495af84e62e31c2e75c0995bbc833730ff45e90ac0e63bc877130746691c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e2ab1155507cc0f1aa534545c7105cb09c9cca400665d14f510e226387c3cc9fa442c112dcb5d9cf0091514692195c8c83f003772cc5f31d224a2c946875c882"} build_url:https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011223 build_url: 'https://queue.taskcluster.net/v1/task/Soi0iJjzTHiQogl-n_bR8g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4a53081fc4c6578e539d1d79ee64205b5de9a6c8b8fb95dac3e17406ecc773416b4e0acc1cf0d65febe3934b1d30292f9277b8c33563ae6fc432354d5041c965", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/012002cc53ec8323fb7c755f8ef59dc06d793be52060874c5e561716abb95f39106e495af84e62e31c2e75c0995bbc833730ff45e90ac0e63bc877130746691c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e2ab1155507cc0f1aa534545c7105cb09c9cca400665d14f510e226387c3cc9fa442c112dcb5d9cf0091514692195c8c83f003772cc5f31d224a2c946875c882"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 04:21:44.885846) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 04:21:44.886160) ========= 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=4300f881736b422476174874000001bc-1471344373.543666-1475995205 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004420 ========= master_lag: 0.10 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 04:21:44.993785) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 04:21:44.995612) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 04:21:44.995946) ========= ========= Total master_lag: 0.19 =========