builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-023 starttime: 1471292240.11 results: success (0) buildid: 20160815114117 builduid: 5105cb1d79e64ea8a526cbf5af5d5eb4 revision: fccbaff0ea6f339ebaddc5d74dd574ca84f41d1e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 13:17:20.111059) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 13:17:20.111476) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 13:17:20.111747) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 13:17:20.127720) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 13:17:20.127982) ========= 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=6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-15 13:17:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 23.0M=0.001s 2016-08-15 13:17:20 (23.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.141052 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 13:17:20.300206) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 13:17:20.300509) ========= 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=6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.066031 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 13:17:20.378171) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 13:17:20.378485) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev fccbaff0ea6f339ebaddc5d74dd574ca84f41d1e --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 fccbaff0ea6f339ebaddc5d74dd574ca84f41d1e --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=6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-15 13:17:20,628 truncating revision to first 12 chars 2016-08-15 13:17:20,628 Setting DEBUG logging. 2016-08-15 13:17:20,628 attempt 1/10 2016-08-15 13:17:20,628 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/fccbaff0ea6f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-15 13:17:20,930 unpacking tar archive at: autoland-fccbaff0ea6f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.705442 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 13:17:21.098399) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 13:17:21.098737) ========= script_repo_revision: fccbaff0ea6f339ebaddc5d74dd574ca84f41d1e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 13:17:21.099178) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 13:17:21.099446) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 13:17:21.115338) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-08-15 13:17:21.115800) ========= /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=6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:17:21 INFO - MultiFileLogger online at 20160815 13:17:21 in /builds/slave/test 13:17:21 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 13:17:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:17:21 INFO - {'append_to_log': False, 13:17:21 INFO - 'base_work_dir': '/builds/slave/test', 13:17:21 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 13:17:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:17:21 INFO - 'branch': 'Autoland-Non-PGO', 13:17:21 INFO - 'buildbot_json_path': 'buildprops.json', 13:17:21 INFO - 'config_files': ('talos/linux_config.py',), 13:17:21 INFO - 'default_actions': ('clobber', 13:17:21 INFO - 'read-buildbot-config', 13:17:21 INFO - 'download-and-extract', 13:17:21 INFO - 'populate-webroot', 13:17:21 INFO - 'create-virtualenv', 13:17:21 INFO - 'install', 13:17:21 INFO - 'run-tests'), 13:17:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:17:21 INFO - 'download_minidump_stackwalk': True, 13:17:21 INFO - 'download_symbols': 'ondemand', 13:17:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:17:21 INFO - 'tooltool.py': '/tools/tooltool.py', 13:17:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:17:21 INFO - '/tools/misc-python/virtualenv.py')}, 13:17:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:17:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:17:21 INFO - 'installer_path': 'installer.exe', 13:17:21 INFO - 'log_level': 'info', 13:17:21 INFO - 'log_name': 'talos', 13:17:21 INFO - 'log_to_console': True, 13:17:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:17:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:17:21 INFO - 'opt_config_files': (), 13:17:21 INFO - 'pip_index': False, 13:17:21 INFO - 'sps_profile': False, 13:17:21 INFO - 'sps_profile_interval': 0, 13:17:21 INFO - 'suite': 'dromaeojs', 13:17:21 INFO - 'system_bits': '32', 13:17:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:17:21 INFO - 'title': 'talos-linux64-ix-023', 13:17:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:17:21 INFO - 'use_talos_json': True, 13:17:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:17:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:17:21 INFO - 'work_dir': 'build'} 13:17:21 INFO - [mozharness: 2016-08-15 20:17:21.548112Z] Running clobber step. 13:17:21 INFO - Running pre-action listener: _resource_record_pre_action 13:17:21 INFO - Running main action method: clobber 13:17:21 INFO - rmtree: /builds/slave/test/build 13:17:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:17:22 INFO - Running post-action listener: _resource_record_post_action 13:17:22 INFO - [mozharness: 2016-08-15 20:17:22.633639Z] Finished clobber step (success) 13:17:22 INFO - [mozharness: 2016-08-15 20:17:22.633751Z] Running read-buildbot-config step. 13:17:22 INFO - Running pre-action listener: _resource_record_pre_action 13:17:22 INFO - Running main action method: read_buildbot_config 13:17:22 INFO - Using buildbot properties: 13:17:22 INFO - { 13:17:22 INFO - "project": "", 13:17:22 INFO - "product": "firefox", 13:17:22 INFO - "script_repo_revision": "production", 13:17:22 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 13:17:22 INFO - "repository": "", 13:17:22 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 13:17:22 INFO - "buildid": "20160815114117", 13:17:22 INFO - "pgo_build": "False", 13:17:22 INFO - "basedir": "/builds/slave/test", 13:17:22 INFO - "buildnumber": 394, 13:17:22 INFO - "slavename": "talos-linux64-ix-023", 13:17:22 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:17:22 INFO - "platform": "ubuntu64_hw", 13:17:22 INFO - "scheduler": "tests-autoland-linux64-talos", 13:17:22 INFO - "branch": "autoland", 13:17:22 INFO - "repo_path": "integration/autoland", 13:17:22 INFO - "revision": "fccbaff0ea6f339ebaddc5d74dd574ca84f41d1e", 13:17:22 INFO - "stage_platform": "linux64", 13:17:22 INFO - "builduid": "5105cb1d79e64ea8a526cbf5af5d5eb4", 13:17:22 INFO - "slavebuilddir": "test" 13:17:22 INFO - } 13:17:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 13:17:22 INFO - Running post-action listener: _resource_record_post_action 13:17:22 INFO - [mozharness: 2016-08-15 20:17:22.636150Z] Finished read-buildbot-config step (success) 13:17:22 INFO - [mozharness: 2016-08-15 20:17:22.636237Z] Running download-and-extract step. 13:17:22 INFO - Running pre-action listener: _resource_record_pre_action 13:17:22 INFO - Running main action method: download_and_extract 13:17:22 INFO - mkdir: /builds/slave/test/build/tests 13:17:22 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')]} 13:17:22 INFO - trying https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 13:17:22 INFO - Downloading https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/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 13:17:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/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 13:17:23 INFO - Downloaded 1361 bytes. 13:17:23 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 13:17:23 INFO - Using the following test package requirements: 13:17:23 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 13:17:23 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:17:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 13:17:23 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:17:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 13:17:23 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:17:23 INFO - u'jsshell-linux-x86_64.zip'], 13:17:23 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:17:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 13:17:23 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 13:17:23 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:17:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 13:17:23 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:17:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 13:17:23 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:17:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 13:17:23 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:17:23 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 13:17:23 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:17:23 INFO - trying https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 13:17:23 INFO - Downloading https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/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 13:17:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/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 13:17:24 INFO - Downloaded 20265677 bytes. 13:17:24 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 13:17:29 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 13:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:17:29 INFO - trying https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 13:17:29 INFO - Downloading https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/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 13:17:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/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 13:17:30 INFO - Downloaded 20265677 bytes. 13:17:30 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 13:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:17:34 INFO - trying https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 13:17:34 INFO - Downloading https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/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 13:17:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/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 13:17:36 INFO - Downloaded 14413719 bytes. 13:17:36 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 13:17:36 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')]} 13:17:36 INFO - trying https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 13:17:36 INFO - Downloading https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/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 13:17:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/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 13:17:38 INFO - Downloaded 55618047 bytes. 13:17:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 13:17:38 INFO - mkdir: /builds/slave/test/properties 13:17:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:17:38 INFO - Writing to file /builds/slave/test/properties/build_url 13:17:38 INFO - Contents: 13:17:38 INFO - build_url:https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 13:17:38 INFO - Running post-action listener: _resource_record_post_action 13:17:38 INFO - Running post-action listener: set_extra_try_arguments 13:17:38 INFO - [mozharness: 2016-08-15 20:17:38.785969Z] Finished download-and-extract step (success) 13:17:38 INFO - [mozharness: 2016-08-15 20:17:38.786269Z] Running populate-webroot step. 13:17:38 INFO - Running pre-action listener: _resource_record_pre_action 13:17:38 INFO - Running main action method: populate_webroot 13:17:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:17:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:17:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:17:38 INFO - '--tppagecycles', 13:17:38 INFO - '7'], 13:17:38 INFO - 'tests': ['tsvgm']}}, 13:17:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:17:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:17:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:17:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:17:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:17:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:17:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:38 INFO - 'talos_options': ['--disable-e10s'], 13:17:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:17:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:17:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:38 INFO - 'talos_options': ['--disable-e10s'], 13:17:38 INFO - 'tests': ['damp', 'tps']}, 13:17:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:38 INFO - 'tests': ['damp', 'tps']}, 13:17:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:17:38 INFO - 'tests': ['dromaeo_dom']}, 13:17:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:17:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:17:38 INFO - 'tests': ['basic_compositor_video']}, 13:17:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:17:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:17:38 INFO - 'tests': ['a11yr', 13:17:38 INFO - 'ts_paint', 13:17:38 INFO - 'tpaint', 13:17:38 INFO - 'sessionrestore', 13:17:38 INFO - 'sessionrestore_no_auto_restore', 13:17:38 INFO - 'tabpaint']}, 13:17:38 INFO - 'other-e10s': {'tests': ['a11yr', 13:17:38 INFO - 'ts_paint', 13:17:38 INFO - 'tpaint', 13:17:38 INFO - 'sessionrestore', 13:17:38 INFO - 'sessionrestore_no_auto_restore', 13:17:38 INFO - 'tabpaint']}, 13:17:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:17:38 INFO - 'ts_paint', 13:17:38 INFO - 'tpaint', 13:17:38 INFO - 'sessionrestore', 13:17:38 INFO - 'sessionrestore_no_auto_restore', 13:17:38 INFO - 'tabpaint']}, 13:17:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:17:38 INFO - 'ts_paint', 13:17:38 INFO - 'tpaint', 13:17:38 INFO - 'sessionrestore', 13:17:38 INFO - 'sessionrestore_no_auto_restore', 13:17:38 INFO - 'tabpaint']}, 13:17:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:17:38 INFO - 'tests': ['a11yr', 13:17:38 INFO - 'ts_paint', 13:17:38 INFO - 'tpaint', 13:17:38 INFO - 'sessionrestore', 13:17:38 INFO - 'sessionrestore_no_auto_restore', 13:17:38 INFO - 'tabpaint']}, 13:17:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:17:38 INFO - 'tests': ['a11yr', 13:17:38 INFO - 'ts_paint', 13:17:38 INFO - 'tpaint', 13:17:38 INFO - 'sessionrestore', 13:17:38 INFO - 'sessionrestore_no_auto_restore', 13:17:38 INFO - 'tabpaint']}, 13:17:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:17:38 INFO - 'tests': ['tsvgx', 13:17:38 INFO - 'tsvgr_opacity', 13:17:38 INFO - 'tart', 13:17:38 INFO - 'tscrollx', 13:17:38 INFO - 'cart']}, 13:17:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:17:38 INFO - 'tsvgr_opacity', 13:17:38 INFO - 'tart', 13:17:38 INFO - 'tscrollx', 13:17:38 INFO - 'cart']}, 13:17:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:38 INFO - 'talos_options': ['--disable-e10s'], 13:17:38 INFO - 'tests': ['tp5o']}, 13:17:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:38 INFO - 'tests': ['tp5o']}, 13:17:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:38 INFO - 'talos_options': ['--disable-e10s', 13:17:38 INFO - '--xperf_path', 13:17:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:17:38 INFO - 'tests': ['tp5n']}, 13:17:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:17:38 INFO - 'talos_options': ['--xperf_path', 13:17:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:17:38 INFO - 'tests': ['tp5n']}}, 13:17:38 INFO - 'talos.zip': {'path': '', 13:17:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:17:38 INFO - Running post-action listener: _resource_record_post_action 13:17:38 INFO - [mozharness: 2016-08-15 20:17:38.807843Z] Finished populate-webroot step (success) 13:17:38 INFO - [mozharness: 2016-08-15 20:17:38.807934Z] Running create-virtualenv step. 13:17:38 INFO - Running pre-action listener: _resource_record_pre_action 13:17:38 INFO - Running main action method: create_virtualenv 13:17:38 INFO - Creating virtualenv /builds/slave/test/build/venv 13:17:38 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 13:17:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:17:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:17:38 INFO - Using real prefix '/usr' 13:17:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:17:39 INFO - Installing distribute.............................................................................................................................................................................................done. 13:17:41 INFO - Installing pip.................done. 13:17:41 INFO - Return code: 0 13:17:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:17:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:41 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 0x7fc9211b9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2102230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fc090>, '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 0x20ff9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ffe40>, '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': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', '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 13:17:41 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 13:17:41 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 13:17:41 INFO - Using env: {'DISPLAY': ':0', 13:17:41 INFO - 'HOME': '/home/cltbld', 13:17:41 INFO - 'LANG': 'en_US.UTF-8', 13:17:41 INFO - 'LANGUAGE': 'en_US:en', 13:17:41 INFO - 'LOGNAME': 'cltbld', 13:17:41 INFO - 'MAIL': '/var/mail/cltbld', 13:17:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:41 INFO - 'MOZ_NO_REMOTE': '1', 13:17:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:17:41 INFO - 'NO_EM_RESTART': '1', 13:17:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:17:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:41 INFO - 'PWD': '/builds/slave/test', 13:17:41 INFO - 'SHELL': '/bin/bash', 13:17:41 INFO - 'SHLVL': '1', 13:17:41 INFO - 'TERM': 'linux', 13:17:41 INFO - 'TMOUT': '86400', 13:17:41 INFO - 'USER': 'cltbld', 13:17:41 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', 13:17:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:41 INFO - '_': '/tools/buildbot/bin/python'} 13:17:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:41 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 13:17:41 INFO - Cleaning up... 13:17:41 INFO - Return code: 0 13:17:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:17:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:41 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 0x7fc9211b9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2102230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fc090>, '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 0x20ff9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ffe40>, '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': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', '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 13:17:41 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 13:17:41 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 13:17:41 INFO - Using env: {'DISPLAY': ':0', 13:17:41 INFO - 'HOME': '/home/cltbld', 13:17:41 INFO - 'LANG': 'en_US.UTF-8', 13:17:41 INFO - 'LANGUAGE': 'en_US:en', 13:17:41 INFO - 'LOGNAME': 'cltbld', 13:17:41 INFO - 'MAIL': '/var/mail/cltbld', 13:17:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:41 INFO - 'MOZ_NO_REMOTE': '1', 13:17:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:17:41 INFO - 'NO_EM_RESTART': '1', 13:17:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:17:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:41 INFO - 'PWD': '/builds/slave/test', 13:17:41 INFO - 'SHELL': '/bin/bash', 13:17:41 INFO - 'SHLVL': '1', 13:17:41 INFO - 'TERM': 'linux', 13:17:41 INFO - 'TMOUT': '86400', 13:17:41 INFO - 'USER': 'cltbld', 13:17:41 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', 13:17:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:41 INFO - '_': '/tools/buildbot/bin/python'} 13:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:42 INFO - Downloading/unpacking psutil>=3.1.1 13:17:42 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 13:17:42 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 13:17:42 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 13:17:42 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 13:17:42 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 13:17:42 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 13:17:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:17:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:17:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:17:45 INFO - Installing collected packages: psutil 13:17:45 INFO - Running setup.py install for psutil 13:17:45 INFO - building 'psutil._psutil_linux' extension 13:17:45 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 13:17:46 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 13:17:47 INFO - building 'psutil._psutil_posix' extension 13:17:47 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 13:17:47 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 13:17:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:17:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:17:47 INFO - Successfully installed psutil 13:17:47 INFO - Cleaning up... 13:17:47 INFO - Return code: 0 13:17:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:17:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:47 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 0x7fc9211b9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2102230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fc090>, '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 0x20ff9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ffe40>, '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': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', '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 13:17:47 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 13:17:47 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 13:17:47 INFO - Using env: {'DISPLAY': ':0', 13:17:47 INFO - 'HOME': '/home/cltbld', 13:17:47 INFO - 'LANG': 'en_US.UTF-8', 13:17:47 INFO - 'LANGUAGE': 'en_US:en', 13:17:47 INFO - 'LOGNAME': 'cltbld', 13:17:47 INFO - 'MAIL': '/var/mail/cltbld', 13:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:47 INFO - 'MOZ_NO_REMOTE': '1', 13:17:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:17:47 INFO - 'NO_EM_RESTART': '1', 13:17:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:47 INFO - 'PWD': '/builds/slave/test', 13:17:47 INFO - 'SHELL': '/bin/bash', 13:17:47 INFO - 'SHLVL': '1', 13:17:47 INFO - 'TERM': 'linux', 13:17:47 INFO - 'TMOUT': '86400', 13:17:47 INFO - 'USER': 'cltbld', 13:17:47 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', 13:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:47 INFO - '_': '/tools/buildbot/bin/python'} 13:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:17:47 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 13:17:47 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 13:17:47 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 13:17:47 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 13:17:47 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 13:17:47 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 13:17:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:17:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:17:49 INFO - Installing collected packages: mozsystemmonitor 13:17:49 INFO - Running setup.py install for mozsystemmonitor 13:17:49 INFO - Successfully installed mozsystemmonitor 13:17:49 INFO - Cleaning up... 13:17:49 INFO - Return code: 0 13:17:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc9211b9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2102230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fc090>, '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 0x20ff9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ffe40>, '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': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', '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 13:17:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:17:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:17:49 INFO - Using env: {'DISPLAY': ':0', 13:17:49 INFO - 'HOME': '/home/cltbld', 13:17:49 INFO - 'LANG': 'en_US.UTF-8', 13:17:49 INFO - 'LANGUAGE': 'en_US:en', 13:17:49 INFO - 'LOGNAME': 'cltbld', 13:17:49 INFO - 'MAIL': '/var/mail/cltbld', 13:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:49 INFO - 'MOZ_NO_REMOTE': '1', 13:17:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:17:49 INFO - 'NO_EM_RESTART': '1', 13:17:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:49 INFO - 'PWD': '/builds/slave/test', 13:17:49 INFO - 'SHELL': '/bin/bash', 13:17:49 INFO - 'SHLVL': '1', 13:17:49 INFO - 'TERM': 'linux', 13:17:49 INFO - 'TMOUT': '86400', 13:17:49 INFO - 'USER': 'cltbld', 13:17:49 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', 13:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:49 INFO - '_': '/tools/buildbot/bin/python'} 13:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:50 INFO - Downloading/unpacking jsonschema==2.5.1 13:17:50 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 13:17:50 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 13:17:50 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 13:17:50 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 13:17:50 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 13:17:50 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 13:17:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:17:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:17:51 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 13:17:51 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 13:17:51 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 13:17:51 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 13:17:51 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 13:17:51 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 13:17:52 INFO - Downloading functools32-3.2.3-2.tar.gz 13:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:17:52 INFO - warning: no files found matching '*.txt' 13:17:52 INFO - no previously-included directories found matching 'build' 13:17:52 INFO - no previously-included directories found matching 'dist' 13:17:52 INFO - no previously-included directories found matching '.git*' 13:17:52 INFO - Installing collected packages: jsonschema, functools32 13:17:52 INFO - Running setup.py install for functools32 13:17:52 INFO - warning: no files found matching '*.txt' 13:17:52 INFO - no previously-included directories found matching 'build' 13:17:52 INFO - no previously-included directories found matching 'dist' 13:17:52 INFO - no previously-included directories found matching '.git*' 13:17:52 INFO - Successfully installed jsonschema functools32 13:17:52 INFO - Cleaning up... 13:17:52 INFO - Return code: 0 13:17:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:17:52 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')]} 13:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:52 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')]} 13:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:52 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 0x7fc9211b9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2102230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fc090>, '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 0x20ff9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ffe40>, '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': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', '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 13:17:52 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 13:17:52 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 13:17:52 INFO - Using env: {'DISPLAY': ':0', 13:17:52 INFO - 'HOME': '/home/cltbld', 13:17:52 INFO - 'LANG': 'en_US.UTF-8', 13:17:52 INFO - 'LANGUAGE': 'en_US:en', 13:17:52 INFO - 'LOGNAME': 'cltbld', 13:17:52 INFO - 'MAIL': '/var/mail/cltbld', 13:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:52 INFO - 'MOZ_NO_REMOTE': '1', 13:17:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:17:52 INFO - 'NO_EM_RESTART': '1', 13:17:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:52 INFO - 'PWD': '/builds/slave/test', 13:17:52 INFO - 'SHELL': '/bin/bash', 13:17:52 INFO - 'SHLVL': '1', 13:17:52 INFO - 'TERM': 'linux', 13:17:52 INFO - 'TMOUT': '86400', 13:17:52 INFO - 'USER': 'cltbld', 13:17:52 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', 13:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:52 INFO - '_': '/tools/buildbot/bin/python'} 13:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:17:52 INFO - Cleaning up... 13:17:52 INFO - Return code: 0 13:17:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:17:52 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')]} 13:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:52 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')]} 13:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:52 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 0x7fc9211b9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2102230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fc090>, '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 0x20ff9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ffe40>, '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': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', '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 13:17:52 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 13:17:52 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 13:17:52 INFO - Using env: {'DISPLAY': ':0', 13:17:52 INFO - 'HOME': '/home/cltbld', 13:17:52 INFO - 'LANG': 'en_US.UTF-8', 13:17:52 INFO - 'LANGUAGE': 'en_US:en', 13:17:52 INFO - 'LOGNAME': 'cltbld', 13:17:52 INFO - 'MAIL': '/var/mail/cltbld', 13:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:52 INFO - 'MOZ_NO_REMOTE': '1', 13:17:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:17:52 INFO - 'NO_EM_RESTART': '1', 13:17:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:52 INFO - 'PWD': '/builds/slave/test', 13:17:52 INFO - 'SHELL': '/bin/bash', 13:17:52 INFO - 'SHLVL': '1', 13:17:52 INFO - 'TERM': 'linux', 13:17:52 INFO - 'TMOUT': '86400', 13:17:52 INFO - 'USER': 'cltbld', 13:17:52 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', 13:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:52 INFO - '_': '/tools/buildbot/bin/python'} 13:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:52 INFO - Downloading/unpacking blobuploader==1.2.4 13:17:52 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 13:17:52 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 13:17:52 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 13:17:52 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 13:17:52 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 13:17:52 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 13:17:54 INFO - Downloading blobuploader-1.2.4.tar.gz 13:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:17:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:17:54 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 13:17:54 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 13:17:54 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 13:17:54 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 13:17:54 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 13:17:54 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 13:17:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:17:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:55 INFO - Downloading docopt-0.6.1.tar.gz 13:17:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:17:55 INFO - Installing collected packages: blobuploader, requests, docopt 13:17:55 INFO - Running setup.py install for blobuploader 13:17:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:17:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:17:55 INFO - Running setup.py install for requests 13:17:55 INFO - Running setup.py install for docopt 13:17:56 INFO - Successfully installed blobuploader requests docopt 13:17:56 INFO - Cleaning up... 13:17:56 INFO - Return code: 0 13:17:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:17:56 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')]} 13:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:56 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')]} 13:17:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:56 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 0x7fc9211b9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2102230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fc090>, '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 0x20ff9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ffe40>, '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': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', '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 13:17:56 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 13:17:56 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 13:17:56 INFO - Using env: {'DISPLAY': ':0', 13:17:56 INFO - 'HOME': '/home/cltbld', 13:17:56 INFO - 'LANG': 'en_US.UTF-8', 13:17:56 INFO - 'LANGUAGE': 'en_US:en', 13:17:56 INFO - 'LOGNAME': 'cltbld', 13:17:56 INFO - 'MAIL': '/var/mail/cltbld', 13:17:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:56 INFO - 'MOZ_NO_REMOTE': '1', 13:17:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:17:56 INFO - 'NO_EM_RESTART': '1', 13:17:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:17:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:56 INFO - 'PWD': '/builds/slave/test', 13:17:56 INFO - 'SHELL': '/bin/bash', 13:17:56 INFO - 'SHLVL': '1', 13:17:56 INFO - 'TERM': 'linux', 13:17:56 INFO - 'TMOUT': '86400', 13:17:56 INFO - 'USER': 'cltbld', 13:17:56 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', 13:17:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:56 INFO - '_': '/tools/buildbot/bin/python'} 13:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:17:56 INFO - Running setup.py (path:/tmp/pip-ie4pAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:17:56 INFO - Running setup.py (path:/tmp/pip-B_jyNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:17:56 INFO - Running setup.py (path:/tmp/pip-NXzK5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:17:56 INFO - Running setup.py (path:/tmp/pip-a4hPvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:17:56 INFO - Running setup.py (path:/tmp/pip-V74eBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:17:56 INFO - Running setup.py (path:/tmp/pip-9oPrFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:17:56 INFO - Running setup.py (path:/tmp/pip-fGmJ8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:17:56 INFO - Running setup.py (path:/tmp/pip-vcg6vi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:17:56 INFO - Running setup.py (path:/tmp/pip-8_AmHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:17:56 INFO - Running setup.py (path:/tmp/pip-LwSzRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:17:57 INFO - Running setup.py (path:/tmp/pip-p3Appj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:17:57 INFO - Running setup.py (path:/tmp/pip-WPatVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:17:57 INFO - Running setup.py (path:/tmp/pip-b_Ex7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:17:57 INFO - Running setup.py (path:/tmp/pip-lAgI6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:17:57 INFO - Running setup.py (path:/tmp/pip-7Wi2ir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:17:57 INFO - Running setup.py (path:/tmp/pip-vgeWNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:17:57 INFO - Running setup.py (path:/tmp/pip-TKihUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:17:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:17:57 INFO - Running setup.py install for manifestparser 13:17:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:17:57 INFO - Running setup.py install for mozcrash 13:17:57 INFO - Running setup.py install for mozdebug 13:17:57 INFO - Running setup.py install for mozdevice 13:17:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:17:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:17:57 INFO - Running setup.py install for mozfile 13:17:58 INFO - Running setup.py install for mozhttpd 13:17:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:17:58 INFO - Running setup.py install for mozinfo 13:17:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:17:58 INFO - Running setup.py install for mozInstall 13:17:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:17:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:17:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:17:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:17:58 INFO - Running setup.py install for mozleak 13:17:58 INFO - Running setup.py install for mozlog 13:17:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:17:58 INFO - Running setup.py install for moznetwork 13:17:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:17:58 INFO - Running setup.py install for mozprocess 13:17:58 INFO - Running setup.py install for mozprofile 13:17:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:17:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:17:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:17:58 INFO - Running setup.py install for mozrunner 13:17:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:17:59 INFO - Running setup.py install for mozscreenshot 13:17:59 INFO - Running setup.py install for moztest 13:17:59 INFO - Running setup.py install for mozversion 13:17:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:17:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:17:59 INFO - Cleaning up... 13:17:59 INFO - Return code: 0 13:17:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:17: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')]} 13:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17: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')]} 13:17:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:59 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 0x7fc9211b9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2102230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fc090>, '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 0x20ff9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ffe40>, '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': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', '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 13:17:59 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 13:17:59 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 13:17:59 INFO - Using env: {'DISPLAY': ':0', 13:17:59 INFO - 'HOME': '/home/cltbld', 13:17:59 INFO - 'LANG': 'en_US.UTF-8', 13:17:59 INFO - 'LANGUAGE': 'en_US:en', 13:17:59 INFO - 'LOGNAME': 'cltbld', 13:17:59 INFO - 'MAIL': '/var/mail/cltbld', 13:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:59 INFO - 'MOZ_NO_REMOTE': '1', 13:17:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:17:59 INFO - 'NO_EM_RESTART': '1', 13:17:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:17:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:59 INFO - 'PWD': '/builds/slave/test', 13:17:59 INFO - 'SHELL': '/bin/bash', 13:17:59 INFO - 'SHLVL': '1', 13:17:59 INFO - 'TERM': 'linux', 13:17:59 INFO - 'TMOUT': '86400', 13:17:59 INFO - 'USER': 'cltbld', 13:17:59 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', 13:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:59 INFO - '_': '/tools/buildbot/bin/python'} 13:17:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:17:59 INFO - Running setup.py (path:/tmp/pip-zIgKkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:17:59 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)) 13:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:17:59 INFO - Running setup.py (path:/tmp/pip-23780h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:17:59 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)) 13:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:17:59 INFO - Running setup.py (path:/tmp/pip-S74gxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:17:59 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)) 13:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:17:59 INFO - Running setup.py (path:/tmp/pip-kQwwM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:17:59 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)) 13:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:17:59 INFO - Running setup.py (path:/tmp/pip-Dt4MSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:17:59 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)) 13:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:17:59 INFO - Running setup.py (path:/tmp/pip-G0Qycb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:17:59 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)) 13:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:17:59 INFO - Running setup.py (path:/tmp/pip-5MskGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:18:00 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)) 13:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:18:00 INFO - Running setup.py (path:/tmp/pip-Yqc37q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:18:00 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)) 13:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:18:00 INFO - Running setup.py (path:/tmp/pip-DL_1gJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:18:00 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)) 13:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:18:00 INFO - Running setup.py (path:/tmp/pip-RIP7e1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:18:00 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)) 13:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:18:00 INFO - Running setup.py (path:/tmp/pip-pjYJvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:18:00 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)) 13:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:18:00 INFO - Running setup.py (path:/tmp/pip-tJy2ZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:18:00 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)) 13:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:18:00 INFO - Running setup.py (path:/tmp/pip-1eq8jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:18:00 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)) 13:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:18:00 INFO - Running setup.py (path:/tmp/pip-XyemWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:18:00 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)) 13:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:18:00 INFO - Running setup.py (path:/tmp/pip-qFm0uS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:18:00 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)) 13:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:18:00 INFO - Running setup.py (path:/tmp/pip-eZIfji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:18:00 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)) 13:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:18:00 INFO - Running setup.py (path:/tmp/pip-w66wpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:18:00 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)) 13:18:00 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)) 13:18:00 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)) 13:18:00 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)) 13:18:00 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)) 13:18:00 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)) 13:18:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:18:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:18:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:18:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:18:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:18:02 INFO - Downloading blessings-1.6.tar.gz 13:18:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:18:02 INFO - Installing collected packages: blessings 13:18:02 INFO - Running setup.py install for blessings 13:18:02 INFO - Successfully installed blessings 13:18:02 INFO - Cleaning up... 13:18:02 INFO - Return code: 0 13:18:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:18:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:18:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:18:03 INFO - Reading from file tmpfile_stdout 13:18:03 INFO - Current package versions: 13:18:03 INFO - argparse == 1.2.1 13:18:03 INFO - blessings == 1.6 13:18:03 INFO - blobuploader == 1.2.4 13:18:03 INFO - docopt == 0.6.1 13:18:03 INFO - functools32 == 3.2.3-2 13:18:03 INFO - jsonschema == 2.5.1 13:18:03 INFO - manifestparser == 1.1 13:18:03 INFO - mozInstall == 1.12 13:18:03 INFO - mozcrash == 0.17 13:18:03 INFO - mozdebug == 0.1 13:18:03 INFO - mozdevice == 0.48 13:18:03 INFO - mozfile == 1.2 13:18:03 INFO - mozhttpd == 0.7 13:18:03 INFO - mozinfo == 0.9 13:18:03 INFO - mozleak == 0.1 13:18:03 INFO - mozlog == 3.3 13:18:03 INFO - moznetwork == 0.27 13:18:03 INFO - mozprocess == 0.23 13:18:03 INFO - mozprofile == 0.28 13:18:03 INFO - mozrunner == 6.12 13:18:03 INFO - mozscreenshot == 0.1 13:18:03 INFO - mozsystemmonitor == 0.3 13:18:03 INFO - moztest == 0.7 13:18:03 INFO - mozversion == 1.4 13:18:03 INFO - psutil == 3.1.1 13:18:03 INFO - requests == 1.2.3 13:18:03 INFO - wsgiref == 0.1.2 13:18:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:18:03 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')]} 13:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:18:03 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')]} 13:18:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:18:03 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 0x7fc9211b9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2102230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fc090>, '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 0x20ff9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ffe40>, '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': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', '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 13:18:03 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 13:18:03 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 13:18:03 INFO - Using env: {'DISPLAY': ':0', 13:18:03 INFO - 'HOME': '/home/cltbld', 13:18:03 INFO - 'LANG': 'en_US.UTF-8', 13:18:03 INFO - 'LANGUAGE': 'en_US:en', 13:18:03 INFO - 'LOGNAME': 'cltbld', 13:18:03 INFO - 'MAIL': '/var/mail/cltbld', 13:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:03 INFO - 'MOZ_NO_REMOTE': '1', 13:18:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:18:03 INFO - 'NO_EM_RESTART': '1', 13:18:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:18:03 INFO - 'PWD': '/builds/slave/test', 13:18:03 INFO - 'SHELL': '/bin/bash', 13:18:03 INFO - 'SHLVL': '1', 13:18:03 INFO - 'TERM': 'linux', 13:18:03 INFO - 'TMOUT': '86400', 13:18:03 INFO - 'USER': 'cltbld', 13:18:03 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', 13:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:18:03 INFO - '_': '/tools/buildbot/bin/python'} 13:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:03 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)) 13:18:03 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)) 13:18:03 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)) 13:18:03 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)) 13:18:03 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)) 13:18:03 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)) 13:18:03 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)) 13:18:03 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)) 13:18:03 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)) 13:18:03 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)) 13:18:03 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)) 13:18:03 INFO - Cleaning up... 13:18:03 INFO - Return code: 0 13:18:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:18:03 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')]} 13:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:18:03 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')]} 13:18:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:18:03 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 0x7fc9211b9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2102230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fc090>, '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 0x20ff9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ffe40>, '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': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', '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 13:18:03 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 13:18:03 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 13:18:03 INFO - Using env: {'DISPLAY': ':0', 13:18:03 INFO - 'HOME': '/home/cltbld', 13:18:03 INFO - 'LANG': 'en_US.UTF-8', 13:18:03 INFO - 'LANGUAGE': 'en_US:en', 13:18:03 INFO - 'LOGNAME': 'cltbld', 13:18:03 INFO - 'MAIL': '/var/mail/cltbld', 13:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:03 INFO - 'MOZ_NO_REMOTE': '1', 13:18:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:18:03 INFO - 'NO_EM_RESTART': '1', 13:18:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:18:03 INFO - 'PWD': '/builds/slave/test', 13:18:03 INFO - 'SHELL': '/bin/bash', 13:18:03 INFO - 'SHLVL': '1', 13:18:03 INFO - 'TERM': 'linux', 13:18:03 INFO - 'TMOUT': '86400', 13:18:03 INFO - 'USER': 'cltbld', 13:18:03 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', 13:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:18:03 INFO - '_': '/tools/buildbot/bin/python'} 13:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:18:03 INFO - Cleaning up... 13:18:03 INFO - Return code: 0 13:18:03 INFO - Running post-action listener: _resource_record_post_action 13:18:03 INFO - Running post-action listener: _start_resource_monitoring 13:18:03 INFO - Starting resource monitoring. 13:18:03 INFO - [mozharness: 2016-08-15 20:18:03.670120Z] Finished create-virtualenv step (success) 13:18:03 INFO - [mozharness: 2016-08-15 20:18:03.671117Z] Running install step. 13:18:03 INFO - Running pre-action listener: _resource_record_pre_action 13:18:03 INFO - Running main action method: install 13:18:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:18:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:18:03 INFO - Reading from file tmpfile_stdout 13:18:03 INFO - Detecting whether we're running mozinstall >=1.0... 13:18:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:18:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:18:03 INFO - Reading from file tmpfile_stdout 13:18:03 INFO - Output received: 13:18:03 INFO - Usage: mozinstall [options] installer 13:18:03 INFO - Options: 13:18:03 INFO - -h, --help show this help message and exit 13:18:03 INFO - -d DEST, --destination=DEST 13:18:03 INFO - Directory to install application into. [default: 13:18:03 INFO - "/builds/slave/test"] 13:18:03 INFO - --app=APP Application being installed. [default: firefox] 13:18:03 INFO - mkdir: /builds/slave/test/build/application 13:18:03 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'] 13:18:03 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 13:18:19 INFO - Reading from file tmpfile_stdout 13:18:19 INFO - Output received: 13:18:19 INFO - /builds/slave/test/build/application/firefox/firefox 13:18:20 INFO - Running post-action listener: _resource_record_post_action 13:18:20 INFO - [mozharness: 2016-08-15 20:18:20.000847Z] Finished install step (success) 13:18:20 INFO - [mozharness: 2016-08-15 20:18:20.001097Z] Running run-tests step. 13:18:20 INFO - Running pre-action listener: _resource_record_pre_action 13:18:20 INFO - Running main action method: run_tests 13:18:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:18:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:18:20 INFO - Python 2.7.3 13:18:20 INFO - Return code: 0 13:18:20 INFO - grabbing minidump binary from tooltool 13:18:20 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')]} 13:18:20 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 0x20fc090>, '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 0x20ff9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20ffe40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:18:20 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 13:18:20 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 13:18:20 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 13:18:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:18:20 INFO - Return code: 0 13:18:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:18:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:18:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:18:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:18:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:18:20 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/LXZQQ2MlS0W5LbvI_KqJoQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 13:18:20 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/LXZQQ2MlS0W5LbvI_KqJoQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --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 13:18:20 INFO - Using env: {'DISPLAY': ':0', 13:18:20 INFO - 'HOME': '/home/cltbld', 13:18:20 INFO - 'LANG': 'en_US.UTF-8', 13:18:20 INFO - 'LANGUAGE': 'en_US:en', 13:18:20 INFO - 'LOGNAME': 'cltbld', 13:18:20 INFO - 'MAIL': '/var/mail/cltbld', 13:18:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:18:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:20 INFO - 'MOZ_NO_REMOTE': '1', 13:18:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:18:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:18:20 INFO - 'NO_EM_RESTART': '1', 13:18:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:18:20 INFO - 'PWD': '/builds/slave/test', 13:18:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:18:20 INFO - 'SHELL': '/bin/bash', 13:18:20 INFO - 'SHLVL': '1', 13:18:20 INFO - 'TERM': 'linux', 13:18:20 INFO - 'TMOUT': '86400', 13:18:20 INFO - 'USER': 'cltbld', 13:18:20 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070', 13:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:18:20 INFO - '_': '/tools/buildbot/bin/python'} 13:18:20 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/LXZQQ2MlS0W5LbvI_KqJoQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 13:18:20 INFO - mozversion application_buildid: 20160815114117 13:18:20 INFO - mozversion application_changeset: fccbaff0ea6f339ebaddc5d74dd574ca84f41d1e 13:18:20 INFO - mozversion application_display_name: Nightly 13:18:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:18:20 INFO - mozversion application_name: Firefox 13:18:20 INFO - mozversion application_remotingname: firefox 13:18:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 13:18:20 INFO - mozversion application_vendor: Mozilla 13:18:20 INFO - mozversion application_version: 51.0a1 13:18:20 INFO - mozversion platform_buildid: 20160815114117 13:18:20 INFO - mozversion platform_changeset: fccbaff0ea6f339ebaddc5d74dd574ca84f41d1e 13:18:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 13:18:20 INFO - mozversion platform_version: 51.0a1 13:18:20 INFO - using testdate: 1471292300 13:18:20 INFO - actual date: 1471292300 13:18:20 INFO - starting webserver on 'localhost:54207' 13:18:20 INFO - SUITE-START | Running 2 tests 13:18:20 INFO - TEST-START | dromaeo_css 13:18:20 INFO - operating with platform_type : linux_ 13:18:20 INFO - Initialising browser for dromaeo_css test... 13:18:20 INFO - TEST-INFO | started process 3315 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7YuC7H/profile http://localhost:54207/getInfo.html) 13:18:26 INFO - PROCESS | 3315 | __metrics Screen width/height:1600/1200 13:18:26 INFO - PROCESS | 3315 | colorDepth:24 13:18:26 INFO - PROCESS | 3315 | Browser inner width/height: 1024/697 13:18:26 INFO - PROCESS | 3315 | __metrics 13:18:29 INFO - PROCESS | 3315 | 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" 13:18:29 INFO - PROCESS | 3315 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:18:30 INFO - TEST-INFO | 3315: exit 0 13:18:30 INFO - Browser initialized. 13:18:30 INFO - Running cycle 1/1 for dromaeo_css test... 13:18:30 INFO - TEST-INFO | started process 3395 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7YuC7H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:18:42 INFO - PROCESS | 3395 | RSS: Main: 156794880 13:18:42 INFO - PROCESS | 3395 | 13:19:33 INFO - PROCESS | 3395 | 1471292373700 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] 13:21:47 INFO - PROCESS | 3395 | Cycle 1(1): loaded http://localhost:54207/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54207/tests/dromaeo/cssquery-ext.html) 13:21:48 INFO - PROCESS | 3395 | RSS: Main: 239861760 13:21:48 INFO - PROCESS | 3395 | 13:24:50 INFO - PROCESS | 3395 | Cycle 1(1): loaded http://localhost:54207/tests/dromaeo/cssquery-ext.html (next: http://localhost:54207/tests/dromaeo/cssquery-jquery.html) 13:24:51 INFO - PROCESS | 3395 | RSS: Main: 244629504 13:24:51 INFO - PROCESS | 3395 | 13:27:53 INFO - PROCESS | 3395 | Cycle 1(1): loaded http://localhost:54207/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54207/tests/dromaeo/cssquery-mootools.html) 13:27:54 INFO - PROCESS | 3395 | RSS: Main: 225247232 13:27:54 INFO - PROCESS | 3395 | 13:30:56 INFO - PROCESS | 3395 | Cycle 1(1): loaded http://localhost:54207/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54207/tests/dromaeo/cssquery-prototype.html) 13:30:56 INFO - PROCESS | 3395 | RSS: Main: 189472768 13:30:56 INFO - PROCESS | 3395 | 13:33:59 INFO - PROCESS | 3395 | Cycle 1(1): loaded http://localhost:54207/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54207/tests/dromaeo/cssquery-yui.html) 13:34:00 INFO - PROCESS | 3395 | RSS: Main: 189239296 13:34:00 INFO - PROCESS | 3395 | 13:37:03 INFO - PROCESS | 3395 | Cycle 1(1): loaded http://localhost:54207/tests/dromaeo/cssquery-yui.html (next: http://localhost:54207/tests/dromaeo/cssquery-dojo.html) 13:37:03 INFO - PROCESS | 3395 | RSS: Main: 276422656 13:37:03 INFO - PROCESS | 3395 | 13:37:03 INFO - PROCESS | 3395 | __start_tp_report 13:37:03 INFO - PROCESS | 3395 | _x_x_mozilla_page_load 13:37:03 INFO - PROCESS | 3395 | _x_x_mozilla_page_load_details 13:37:03 INFO - PROCESS | 3395 | |i|pagename|runs| 13:37:03 INFO - PROCESS | 3395 | |0;dojo.html;2597.1299614629097;2603.479304139172;2631.9342016449586;2641.3000554852965;2646.5368560501906;4559.589636932684;4628.814847406108;4633.513481084488;4654.301854721789;4664.416947881515;4639.1881420751315;4657.37125488059;4691.5073917238715;4703.529647035295;4704.694194877339;4247.915041699164;4284.207421626993;4300.6344460720875;4350.651947844165;4489.25927222008;4413.757243351614;4445.066536027438;4457.888552786174;4459.041306119184;4474.485434175062;4206.978965105185;4271.850485233002;4326.069894972596;4326.329418940069;4358.193734159159;4270.530241673413;4291.9785401072795;4293.29160688486;4309.288967320388;4340.674449416307;4315.352697095429;4324.048709283952;4326.5673432656795;4345.9348109778375;4361.890952726176;4608.446986361624;4686.133065382915;4700.059988002413;4703.106409782131;4746.454157771831;5073.365829271341;5098.821541246076;5142.51146141116;5147.639665223436;5149.2791009258735;5089.770960306795;5109.489051094898;5135.152699804527;5153.66501177426;5175.456577059392;4577.56513131252;4693.296005599119;4751.40607424072;4803.351547541072;4818.325434439182;5214.530692237717;5223.268742376071;5248.790048398021;5253.921191182136;5259.421463638997;5037.546620804072;5042.949570504321;5044;5044.974775126101;5045.747712614428;4912.968276661941;4948.950510494849;4983.202687569971;5131.307273518102;5132.79468821243;4880.95119048805;4887.315775791392;4888.95111048885;4898.877528061756;4919.065377578248;4900.534449227318;4901.730404827768;4902.681325713888;4903.239997800347;4920.6555541111775;4093.4269202311702;4131.648809851136;4138.441310423054;4141.378793181047;4170.374443833449;3646.033801042726;3657.5245218122004;3693.445983102556;3725.515682961198;3745.2883952049024;4395.230834603932;4403.581659742319;4408.228560001987;4450.35469856869;4450.3991961085485;4799.016201678647;4838.830640927551;5038.672486288379;5042.218456139304;5045.41977672565;4323.351497275371;4330.566943305707;4341.804618792162;4359.934600980924;4395.428594282723;4462.174497717932;4466.6873318867365;4467.597916187558;4468.687191896601;4510.909781804346;4694.6244300456565;4716.64625153108;4727.290906364073;4727.3854398927915;4759.50025247351;4899.902001959869;4942.061008408391;4952.801887924443;4994.750262486934;5001.074801161797;4690.226112691368;4694.906101878011;4696.671233013656;4707.976460117678;4713.269443236304;4894.021195760791;4895.706257624412;4897.2654101885;4906.55840974314;4937.9259311109645;4887.340209071872;4955.108080545537;5067.5185857343495;5107.87230319245;5121.206213036985;4938.333325001079;4939.357883475125;4940.6788558743565;4941.851744447672;4978.975105124596;4931.112399768076;4939.6542242042715;4939.777710003131;4940.654154209315;4975.4278258000795;4688.976555117202;4737.052589482187;4793.736344501086;4799.616030717604;4891.33966914462;4865.343178670834;4871.926921096116;4872;4887.535684110004;4907.3620429343955;4674.883127921829;4751.61987041029;4760.571548560646;4762.07139607787;4767 13:37:03 INFO - PROCESS | 3395 | |1;ext.html;547.3623228938286;567.2342337843909;574.7643466178868;575.1889835332181;575.8502789274787;28139.437211255787;39298.80350598247;40544.39183412244;40567.99999999996;44154;40585.391219131736;41120.79439602805;41208.793956030175;41269;41292;7455.589942553157;7629.313361797424;7767.223277672216;7772.028496437945;7774.689012439496;7489.6629651665535;7556.848863022737;7559.924400756005;9820.165285950667;10040.447775372351;9896.802063958716;9952.452615106165;9953.452560109192;9958.502074896227;9960.800783984316;10086.747831304203;10138.188944884465;10149.289549731522;10153.695389138338;10156.441395723232;9838.557264923096;9932.205423566169;9937.205023598168;9941.90058099417;9942.254330925138;37851.810740946115;38132.61867381319;38199.23601527954;38205.23589528194;38216.23567528662;3027.6782158502765;3138.3880143372053;3496.947545786815;3507.824608769551;3508.333416650828;28529.714702852914;29094.27264318388;29111;29123.126306210637;29253.414931701245;3396.7112795412418;3411.5564976553137;3462.2036931505645;3578.5526809148855;3617.385044542434;249136;249225;250456;250570;250632;31222.21944451407;51391;51798.48201517937;51897.740511297205;52016.21975670368;31612.89032425816;33568.49647255293;33608.663913361044;33645.663543364746;33687.15782105467;12953.676158095934;12971.5459958901;13082.215067096005;13205.801912971314;13213.207207567577;10523.894761052445;10709.518071686793;10730;10732.946335268274;10741.624043158452;7176.7488137914925;7569.5079819811535;7741.729039483704;7805.102413222438;7938.722144724907;2776.555751079858;2788.4980703473166;2814.5637426198973;2825.138332808472;2864.069177517315;811.3063330852154;861.4099341950783;879.445949052093;889.3818795936891;914.7667344827026;27452.862735686194;28064.85967570149;28390.716092838808;29059.418811623647;29072.563911541343;918.4351623751345;925.421611492817;944.5796620503947;974.6247694637616;1039.18424037132;14987.475438359823;15156.469523566622;15200.011999220236;15451.84548154504;15518.43076092505;384260;449120;458996;459712;684226;49468;50120.49879501231;50198.498015020115;50202.49797502051;50260.497395026316;1106.319613437746;1114.676743744305;1125.555405614794;1157.774234024374;1183.4378670131618;24817.766401023993;29474.263143421587;29603.25991850135;29860.701392985793;29876.253093672833;36107.27785444329;46168.30747538857;47683.52316476929;47700.761496193685;52324.738376307876;277456.6127169351;280498;280511;280560;281429;24430.26709198727;25033.74966250314;36453;36490;37000.62999369972;35475;35633.46549801703;42361.57638423699;46121.53878461172;47430.76284618555;34606.7887623932;36369.454458183674;37290.44064339092;37490;37901.24197516089;36122;36419.63580364268;36676.26647466982;36721.63278367182;36791;35446.46830297496;35872.4619130708;36229.637703622626;36230;36648.63351366452;31277;33715.76258392206;33754.66245337515;33878.3224335507;34032;24956;25770;25792.355191119623;25855;25948.481030379666 13:37:03 INFO - PROCESS | 3395 | |2;jquery.html;360.71863946122016;367.06947887106185;368.8838016024953;379.15448549734094;379.2339474261993;15765.369385224592;17362.652746945052;17888.373906913246;17932.28270869167;17974.101294935233;14906;14952.62618434537;15175.468858589951;15265.313060912258;15302.311395987179;4897.583705385046;5128.769205385766;5134.897302053957;5138.126518491847;5145.202493613496;3730.7575007624555;3821.48409964654;5023.045621331954;5732.713364331767;6033.396660333975;6021.006533921897;6128.172696685935;6132.938670613281;6135.693215339237;6135.846603834895;5844.707764611752;5943.64338139713;5959.463648271633;5969.403059694039;5969.552283578749;5091.745412729349;5209.843704688866;5217.791288348461;5235.633505654606;5261.842144735664;21456;22022.669659955114;22110;22296;23137;2057.8765274083603;2138.7326584176976;2248.9437764055865;2546.096135871776;2546.5543529882198;14168.291585420688;14290.92854535731;14463.421463141462;14864.256787160706;15277.770833437507;1765.470358892327;1824.963500729978;1867.3464287499403;1912.3880358285355;1914.8755330903446;76938;77822.61088694577;78001;79013.60493197554;81808.18191818065;16149.757753633705;16504.83495165033;16989.830101699074;17047.14764261782;17157.485275441755;28423.28941776472;28447;28570.857145714555;28631.57052644212;28694.426111477653;8588.312934965188;8822.691205807894;8832.55837208137;9118.908810911938;9188.40275382098;7220.602866842269;7266;7286.70853165878;7473.290037446434;7490.887636685454;8334.874976875351;8383.365243777527;8437.198466145828;8579.142085791496;8590.14098590136;2719.8440662718267;3050.2679356954613;3125.48429509133;3154.6529881712995;3397.6432474590306;333.79138038725796;343.5139277921763;381.54596030723906;384.6884023940617;738.5531753289183;10151.390916545031;10577.259591828652;10663.146948244123;11114.944425277821;11170.720731981603;395.82188015392614;413.50173041484936;415.34997728554714;421.56999860142486;428.346771173956;5652.63257888236;5656.547476201977;5819.6508209507565;5965.940340596625;5971.19388882504;381234;385017;385405;388621.0809973048;388803;74616;75037;75333.62333188299;76912;79188.60405697934;437.37673814813763;443.9089986552749;447.2262985035906;874.4578361415963;923.1184219070772;14965.176915269765;15618.765718514003;15725.685486289982;16028;16847.663046739242;34612;38723.70560248969;38860.6113938857;38944.2211155781;40369;48653.5134648649;49098.509014909396;50345.244821328444;50725.74637126791;51377.229341560655;7295.927040729524;7658.540487570764;7891.763247102596;7942.523448593103;7952.125266220827;5528.723563821713;5738.282714660668;11888.524459021888;11898.643040708792;12280.692982675504;10311.432871191857;10340.586376544854;10399.32404393712;10466.476676166008;11020.614278500214;10478.476076196008;10508.842367364343;10573.89426105729;10780.568777248822;10873.510692018719;9645.890722566995;10205.540750666387;10341.793164136825;10543;10769.9461502692;7699.422543309162;7750.457467977188;7823.100343460347;7868.72459463916;7939.245771651684;9432.669856555262;9725.805483890425;9756.07317304855;9833.14927618066;10292.588296468057 13:37:03 INFO - PROCESS | 3395 | |3;mootools.html;1258.0187453786048;1298.9415476303566;1346.5960211936417;1503.6691927775885;1514.1520748380901;7490.9625451872735;8159.469634473762;8198.877016844752;8201;8202.507849529033;6969.965150174255;7168.641567921596;7169.247229040954;7365.484416090862;8032.036155661329;2042.7855075217112;2121.6499277619173;2143.65701487762;2149.3014770199748;2149.570085982802;2137.711408959794;2154.8383871209644;2180.6292930201903;2186.967195492069;2228.320362289501;2181.4001149683804;2185.9344219673435;2191.112599397247;2199.670049492573;2202.3723238876923;2069.8758074515504;2139.304725964068;2149.387424583986;2150.8924553772326;2151.6449785785326;2085.3222702621556;2103.2428325802853;2112.2290364017176;2152.89235538224;2153.0849389009763;7897.881531777028;7996.720114795954;8007.199280071946;8026;8104.716334928308;4460.264056430685;4607.9308810367875;4613.469451013143;4614.792334344963;4617.676762626617;2740.657417822772;2859.5567687008547;2866.971330286691;2877.9712202877913;2914.12576227131;897.210454799772;927.967521136757;955.2405837359316;968.9031096890325;977.7751117243002;11105.777884442266;11149.108071354352;11153.609623663133;11155.832662510069;11215.102791776639;3971.960280397217;4114.526829414595;4180.3102488089735;4200.243956087934;4243.427137336482;9449.527523623792;9469.384490008128;9469.763255918519;9469.905300946903;9474.857877131848;4428.269335559661;4429.2027435062;4430.867073987785;4432.955670443319;4433.135538570013;3553.07620018797;4021.7184797063924;4023.5775243599587;4023.7384570002855;4028.697847661437;3567.714582833367;3697.556293244828;3710.07248187953;3732.738708290394;3775.811209439517;1172.0330727150135;1176.5117476247433;1186.454231053725;1192.070185255502;1192.2667559450874;762.4777027736019;768.3584207187062;771.5756334016269;775.3913178155121;783;4918.5573298403315;4922.556969872729;4954.5540901319055;4957.876053098701;5191.740412979336;825.9834803303901;875.5315905990265;921.5162039929091;929.3030227329502;932.9067093290616;3220.1949512621845;3244.4808830586994;3276.4266253406036;3310.519974603669;3413.709834664032;9580.473073980858;9735.318527702993;9899.60401583943;9912.35569687968;9922.900770992197;9819.116279534876;9827.508624568743;9830;9835.655752048788;9837.852432213522;7226.060612120391;7334;7349.779506614703;7363.226861179499;7367.079115110611;4804.975975120102;4830.4444988826735;4895.681780684244;4935.827246046371;5138.332016837937;8817.38278320537;8867.645294188418;8871.645134194818;8924.598393072198;9054.54727263621;9059.320550958573;9188.632454701738;9240.445573265626;9338.486383248986;9361.906380936103;6507.73969041252;6545.770898018546;6560.179977502812;6618.867622647616;6624.867502650017;7495.475316727995;7538.246175382418;7562.130355009024;7562.281583249583;7589.544627322378;6640.767573134917;6714.194296684429;6758.222804377363;6793.218779840382;6996.965015174892;6745.12313399255;6753.3246675332075;6777.728890844311;6797.93202067973;6865.210500792273;6102.877942441215;6158.476529495043;6188.102725104744;6216.191895053614;6231.750729970931;4305.095929854766;4352.15133049065;4378.540253273488;4386.254336762821;4437.29003359461;4321.978390108029;4396.846110386121;4454.732716037048;4474.597286244254;4521.434820647419 13:37:03 INFO - PROCESS | 3395 | |4;prototype.html;284.2518511156388;288.9624348834651;291.45061558961356;294.81132075471703;295.4917541828055;4777.8088876444945;4826.276058591213;4832.758362081895;4856.2472816713425;4857.902841943168;4710.670253082277;4726.881827954303;4778.546038126381;4782.402199725035;4790.257510085934;3552.1119720070046;3688.686461650763;3689.5019172411785;3692.8707495237672;3698.8163787587982;3622.7320437846806;3688.686461650763;3756.4740936268945;3774.4527043578664;3774.962250377502;3701.666849983501;3709.536307961505;3715.5541335039698;3723;3723.497327860745;3577.4276115821604;3688.852445902174;3695.1870588470492;3722.1947355532543;3732.5520937487677;3272.885449009273;3288;3288.8848890288964;3299.5215693724517;3313.2379552702773;4571.291449825282;4580.290055041474;4585.335126406686;4588.4035075440315;4625.514320996281;4238.321868501056;4248.681348898814;4252.468441444819;4256.553061928484;4262.190183865056;4694;4893.730844803535;4894.461609222983;4938.925916111225;4985.052839960396;3575.4994300797907;3576.445650924111;3582.946255806165;3584.731145164097;3587.192881601645;5155.845324640267;5157.9684063187515;5169.353830771153;5180.222966555009;5203.297554830087;3398.1674489749676;3585.731070169748;3691.1141326082084;3694.2057457646733;3698.815059247027;3614.548181477315;3618.565772107364;3622.9637703623152;3633.818309084535;3635.2547727715755;4140.855070072594;4153.979230103891;4166.58334166587;4176.436181115571;4184.790760462026;4948.134076536592;4960.156773348539;4961.759560109973;4965.702057876539;4975.726335051607;1196.4804698033622;1237.9786675992343;1239.8388209532702;1245.5080243304026;1247.3513772838073;783.4868161354322;786.3866184376195;796.2873228460495;796.8565658181583;808.2559709785246;4006.5392479864604;4198.85304014358;4348.195583817004;4444.1333939881415;4452.777361131931;5071.3153724247495;5072.010957863181;5078.289039534468;5079.212722028092;5088.745562721849;4432.955670443254;4440.734390698635;4450.376947227391;4457.398251236106;4467.709598876063;2561.295643697998;2660.6408134901926;2674.906378276751;2675.5986602009853;2679.102500662261;4812.759362031885;4819.807207711652;4828.444728856227;4832.710037397767;4834.468208497063;5176.663516871391;5204.245384419238;5210.192420174879;5212.687238765686;5230.63385563007;4605.631549476034;4838.612910967114;4848.5636292733825;4849.248366503127;4867.634927380532;5094.84715458522;5105;5114.207297868837;5115.667481613683;5126.897462050663;5028.270900719448;5034;5038.84883453497;5042.268871013755;5047.167217409086;4664.390785179108;4808.763924774857;4821.5178482152905;4822.614190864652;4839.540243676845;3567.7324200684534;3589.10272431892;3605.351036813399;3610.9097272568397;3619.8190090495896;5154.14956532168;5157.7679004445645;5162.148245539444;5166.173412254172;5172.456892026434;4786.042791441656;4937.580305674058;4962.486441635336;4966.063235338293;4984.8753781155765;4954.727489987941;4964;4970.478099799469;4973.776180071833;4974.801007959641;4888.853334399973;4909.435414927186;4910.238912968495;4918.409790825124;4924.556789888928;4761.9047619048115;4782.52174782519;4963.950360496348;5000.499950004971;5021.72380519075;4747.335373047845;4838.395200599925;4859.080663138111;4870.951290487192;4883.584895283941 13:37:03 INFO - PROCESS | 3395 | |5;yui.html;623.875224955009;631.1826185090307;683.6308393467525;699.7515881861935;750.1148644599373;11901.52393904244;12838.165519241244;12859.292738899358;12871.09902306839;12896.613101606956;13351.198928064323;13589.11670741401;13592.184468931846;13594.184348939047;13603.319834008309;611.2786911444493;738.4609235258265;746.3432179681886;758.472861361354;770.6416516319911;750.2272659161073;750.6959681329043;751.0461713623695;751.0536723728087;751.2074761127009;807.1807115777491;807.5840941914919;807.6446363600027;807.9313258373045;808.4987307869129;649.4187702006725;667.1760375935716;667.6161207305819;670.0217682184017;805.1264378149715;565.124057710547;579.9768009279666;580.1558732044865;582.4263100845664;582.5543459253632;12386.876131238752;12473.376331183495;12495.375231238402;12501.124921255516;12517.687057823536;11235.03386907296;11312.552536421137;11849.119101101382;11978.161528692995;12423.254604723745;9930.2055835533;10133.594656213743;11005.944970275177;11006.614768483145;11140.777184456347;3966.7223294369405;3989.4813674222164;4033.6773058155572;4297.226499230137;4302.9991054159655;50689.98620027616;57561.42438575644;58178.41821581772;58450.70774646142;58798;10439.582416703246;10455.790884182426;10462.26764126519;10484.475776211159;10497;18091.819081809015;18101.818981810015;18102.90948545275;18105.366312179012;18116.818831811775;6912.792616221421;6967.198772141269;6973.79078627642;7016;7240.565566066053;3641.289948460025;3692.538432695913;3699.445083237482;3759.49246851677;3797.1076796952693;2792.4415116976684;2936.295289130636;2941.838198899038;2944.2933695912825;2954.704529547071;496.9403671559436;502.8642266588001;506.1900958466498;508.22241969786285;510.2270060857803;531.9760610772524;582.7465052702088;601.7352364959492;615.3907631444837;649.2663290481726;8192.30365418946;8200.712975045844;8269.338452923752;8363.28912042471;8609.182127697859;532.6378062917177;539.7166487593965;553.5682167908957;555.9888802223933;563.1580786723874;3915.334393153171;3949.289127956938;4018.3168861293652;4041.211963667057;4141.875743727693;100350.4982475083;100769;103695;108640;108674.45662771635;88644.55677721699;91866.08133918709;93635.53182234045;93795;94250.5287473572;78326;80530.5973470129;80591.19408805837;80738;81307;7549.2073332299315;7590.544567325978;8044;8964.282857371525;9028;41083.7945810281;41960.79019604882;42566.14867702567;42983.78508107439;43883.78058109689;37300.62699372972;37632;38974.22051558896;39321.60678393294;40157.79921100376;10100.292979491365;10463.476826158509;10730.141588673043;10786.724949964564;10952;12664.493420263087;12754.48982040708;12941.352932353157;13185.736285274432;14185.787213192018;11932.76134477323;12148.878511215129;12665.050121241127;12945.158564693265;13258.851719741293;11295.096392288762;11602.593909213474;11811.114166437723;11870.465829037541;12044.19450350744;11990.160688751703;12281;12544.059195560187;12613.36933153357;12993.630573248332;10244.795104097728;10418.739531511774;10623.415712135908;10652.254342195973;10801.405922674328;9552.37909535878;10315.587376504856;10526.263161578616;10526.421046842497;10930.726731831768 13:37:03 INFO - PROCESS | 3395 | __end_tp_report 13:37:03 INFO - PROCESS | 3395 | __start_cc_report 13:37:03 INFO - PROCESS | 3395 | _x_x_mozilla_cycle_collect,923 13:37:03 INFO - PROCESS | 3395 | __end_cc_report 13:37:03 INFO - PROCESS | 3395 | __startTimestamp1471293423694__endTimestamp 13:37:03 INFO - PROCESS | 3395 | 13:37:03 INFO - PROCESS | 3395 | ------- Summary: start ------- 13:37:03 INFO - PROCESS | 3395 | Number of tests: 6 13:37:03 INFO - PROCESS | 3395 | 13:37:03 INFO - PROCESS | 3395 | [#0] dojo.html Cycles:165 Average:4638.60 Median:4737.05 stddev:490.59 (10.4%) stddev-sans-first:465.22 13:37:03 INFO - PROCESS | 3395 | Values: 2597.1 2603.5 2631.9 2641.3 2646.5 4559.6 4628.8 4633.5 4654.3 4664.4 4639.2 4657.4 4691.5 4703.5 4704.7 4247.9 4284.2 4300.6 4350.7 4489.3 4413.8 4445.1 4457.9 4459.0 4474.5 4207.0 4271.9 4326.1 4326.3 4358.2 4270.5 4292.0 4293.3 4309.3 4340.7 4315.4 4324.0 4326.6 4345.9 4361.9 4608.4 4686.1 4700.1 4703.1 4746.5 5073.4 5098.8 5142.5 5147.6 5149.3 5089.8 5109.5 5135.2 5153.7 5175.5 4577.6 4693.3 4751.4 4803.4 4818.3 5214.5 5223.3 5248.8 5253.9 5259.4 5037.5 5042.9 5044.0 5045.0 5045.7 4913.0 4949.0 4983.2 5131.3 5132.8 4881.0 4887.3 4889.0 4898.9 4919.1 4900.5 4901.7 4902.7 4903.2 4920.7 4093.4 4131.6 4138.4 4141.4 4170.4 3646.0 3657.5 3693.4 3725.5 3745.3 4395.2 4403.6 4408.2 4450.4 4450.4 4799.0 4838.8 5038.7 5042.2 5045.4 4323.4 4330.6 4341.8 4359.9 4395.4 4462.2 4466.7 4467.6 4468.7 4510.9 4694.6 4716.6 4727.3 4727.4 4759.5 4899.9 4942.1 4952.8 4994.8 5001.1 4690.2 4694.9 4696.7 4708.0 4713.3 4894.0 4895.7 4897.3 4906.6 4937.9 4887.3 4955.1 5067.5 5107.9 5121.2 4938.3 4939.4 4940.7 4941.9 4979.0 4931.1 4939.7 4939.8 4940.7 4975.4 4689.0 4737.1 4793.7 4799.6 4891.3 4865.3 4871.9 4872.0 4887.5 4907.4 4674.9 4751.6 4760.6 4762.1 4767.0 13:37:03 INFO - PROCESS | 3395 | 13:37:03 INFO - PROCESS | 3395 | [#1] ext.html Cycles:180 Average:48496.90 Median:36114.64 stddev:95866.69 (265.5%) stddev-sans-first:96068.03 13:37:03 INFO - PROCESS | 3395 | Values: 547.4 567.2 574.8 575.2 575.9 28139.4 39298.8 40544.4 40568.0 44154.0 40585.4 41120.8 41208.8 41269.0 41292.0 7455.6 7629.3 7767.2 7772.0 7774.7 7489.7 7556.8 7559.9 9820.2 10040.4 9896.8 9952.5 9953.5 9958.5 9960.8 10086.7 10138.2 10149.3 10153.7 10156.4 9838.6 9932.2 9937.2 9941.9 9942.3 37851.8 38132.6 38199.2 38205.2 38216.2 3027.7 3138.4 3496.9 3507.8 3508.3 28529.7 29094.3 29111.0 29123.1 29253.4 3396.7 3411.6 3462.2 3578.6 3617.4 249136.0 249225.0 250456.0 250570.0 250632.0 31222.2 51391.0 51798.5 51897.7 52016.2 31612.9 33568.5 33608.7 33645.7 33687.2 12953.7 12971.5 13082.2 13205.8 13213.2 10523.9 10709.5 10730.0 10732.9 10741.6 7176.7 7569.5 7741.7 7805.1 7938.7 2776.6 2788.5 2814.6 2825.1 2864.1 811.3 861.4 879.4 889.4 914.8 27452.9 28064.9 28390.7 29059.4 29072.6 918.4 925.4 944.6 974.6 1039.2 14987.5 15156.5 15200.0 15451.8 15518.4 384260.0 449120.0 458996.0 459712.0 684226.0 49468.0 50120.5 50198.5 50202.5 50260.5 1106.3 1114.7 1125.6 1157.8 1183.4 24817.8 29474.3 29603.3 29860.7 29876.3 36107.3 46168.3 47683.5 47700.8 52324.7 277456.6 280498.0 280511.0 280560.0 281429.0 24430.3 25033.7 36453.0 36490.0 37000.6 35475.0 35633.5 42361.6 46121.5 47430.8 34606.8 36369.5 37290.4 37490.0 37901.2 36122.0 36419.6 36676.3 36721.6 36791.0 35446.5 35872.5 36229.6 36230.0 36648.6 31277.0 33715.8 33754.7 33878.3 34032.0 24956.0 25770.0 25792.4 25855.0 25948.5 13:37:03 INFO - PROCESS | 3395 | 13:37:03 INFO - PROCESS | 3395 | [#2] jquery.html Cycles:180 Average:25095.77 Median:388712.04 stddev:63846.27 (16.4%) stddev-sans-first:63998.36 13:37:03 INFO - PROCESS | 3395 | Values: 360.7 367.1 368.9 379.2 379.2 15765.4 17362.7 17888.4 17932.3 17974.1 14906.0 14952.6 15175.5 15265.3 15302.3 4897.6 5128.8 5134.9 5138.1 5145.2 3730.8 3821.5 5023.0 5732.7 6033.4 6021.0 6128.2 6132.9 6135.7 6135.8 5844.7 5943.6 5959.5 5969.4 5969.6 5091.7 5209.8 5217.8 5235.6 5261.8 21456.0 22022.7 22110.0 22296.0 23137.0 2057.9 2138.7 2248.9 2546.1 2546.6 14168.3 14290.9 14463.4 14864.3 15277.8 1765.5 1825.0 1867.3 1912.4 1914.9 76938.0 77822.6 78001.0 79013.6 81808.2 16149.8 16504.8 16989.8 17047.1 17157.5 28423.3 28447.0 28570.9 28631.6 28694.4 8588.3 8822.7 8832.6 9118.9 9188.4 7220.6 7266.0 7286.7 7473.3 7490.9 8334.9 8383.4 8437.2 8579.1 8590.1 2719.8 3050.3 3125.5 3154.7 3397.6 333.8 343.5 381.5 384.7 738.6 10151.4 10577.3 10663.1 11114.9 11170.7 395.8 413.5 415.3 421.6 428.3 5652.6 5656.5 5819.7 5965.9 5971.2 381234.0 385017.0 385405.0 388621.1 388803.0 74616.0 75037.0 75333.6 76912.0 79188.6 437.4 443.9 447.2 874.5 923.1 14965.2 15618.8 15725.7 16028.0 16847.7 34612.0 38723.7 38860.6 38944.2 40369.0 48653.5 49098.5 50345.2 50725.7 51377.2 7295.9 7658.5 7891.8 7942.5 7952.1 5528.7 5738.3 11888.5 11898.6 12280.7 10311.4 10340.6 10399.3 10466.5 11020.6 10478.5 10508.8 10573.9 10780.6 10873.5 9645.9 10205.5 10341.8 10543.0 10769.9 7699.4 7750.5 7823.1 7868.7 7939.2 9432.7 9725.8 9756.1 9833.1 10292.6 13:37:03 INFO - PROCESS | 3395 | 13:37:03 INFO - PROCESS | 3395 | [#3] mootools.html Cycles:180 Average:5127.05 Median:4945.19 stddev:2966.66 (60.0%) stddev-sans-first:2960.73 13:37:03 INFO - PROCESS | 3395 | Values: 1258.0 1298.9 1346.6 1503.7 1514.2 7491.0 8159.5 8198.9 8201.0 8202.5 6970.0 7168.6 7169.2 7365.5 8032.0 2042.8 2121.6 2143.7 2149.3 2149.6 2137.7 2154.8 2180.6 2187.0 2228.3 2181.4 2185.9 2191.1 2199.7 2202.4 2069.9 2139.3 2149.4 2150.9 2151.6 2085.3 2103.2 2112.2 2152.9 2153.1 7897.9 7996.7 8007.2 8026.0 8104.7 4460.3 4607.9 4613.5 4614.8 4617.7 2740.7 2859.6 2867.0 2878.0 2914.1 897.2 928.0 955.2 968.9 977.8 11105.8 11149.1 11153.6 11155.8 11215.1 3972.0 4114.5 4180.3 4200.2 4243.4 9449.5 9469.4 9469.8 9469.9 9474.9 4428.3 4429.2 4430.9 4433.0 4433.1 3553.1 4021.7 4023.6 4023.7 4028.7 3567.7 3697.6 3710.1 3732.7 3775.8 1172.0 1176.5 1186.5 1192.1 1192.3 762.5 768.4 771.6 775.4 783.0 4918.6 4922.6 4954.6 4957.9 5191.7 826.0 875.5 921.5 929.3 932.9 3220.2 3244.5 3276.4 3310.5 3413.7 9580.5 9735.3 9899.6 9912.4 9922.9 9819.1 9827.5 9830.0 9835.7 9837.9 7226.1 7334.0 7349.8 7363.2 7367.1 4805.0 4830.4 4895.7 4935.8 5138.3 8817.4 8867.6 8871.6 8924.6 9054.5 9059.3 9188.6 9240.4 9338.5 9361.9 6507.7 6545.8 6560.2 6618.9 6624.9 7495.5 7538.2 7562.1 7562.3 7589.5 6640.8 6714.2 6758.2 6793.2 6997.0 6745.1 6753.3 6777.7 6797.9 6865.2 6102.9 6158.5 6188.1 6216.2 6231.8 4305.1 4352.2 4378.5 4386.3 4437.3 4322.0 4396.8 4454.7 4474.6 4521.4 13:37:03 INFO - PROCESS | 3395 | 13:37:03 INFO - PROCESS | 3395 | [#4] prototype.html Cycles:180 Average:4121.77 Median:4754.62 stddev:1205.53 (25.4%) stddev-sans-first:1174.00 13:37:03 INFO - PROCESS | 3395 | Values: 284.3 289.0 291.5 294.8 295.5 4777.8 4826.3 4832.8 4856.2 4857.9 4710.7 4726.9 4778.5 4782.4 4790.3 3552.1 3688.7 3689.5 3692.9 3698.8 3622.7 3688.7 3756.5 3774.5 3775.0 3701.7 3709.5 3715.6 3723.0 3723.5 3577.4 3688.9 3695.2 3722.2 3732.6 3272.9 3288.0 3288.9 3299.5 3313.2 4571.3 4580.3 4585.3 4588.4 4625.5 4238.3 4248.7 4252.5 4256.6 4262.2 4694.0 4893.7 4894.5 4938.9 4985.1 3575.5 3576.4 3582.9 3584.7 3587.2 5155.8 5158.0 5169.4 5180.2 5203.3 3398.2 3585.7 3691.1 3694.2 3698.8 3614.5 3618.6 3623.0 3633.8 3635.3 4140.9 4154.0 4166.6 4176.4 4184.8 4948.1 4960.2 4961.8 4965.7 4975.7 1196.5 1238.0 1239.8 1245.5 1247.4 783.5 786.4 796.3 796.9 808.3 4006.5 4198.9 4348.2 4444.1 4452.8 5071.3 5072.0 5078.3 5079.2 5088.7 4433.0 4440.7 4450.4 4457.4 4467.7 2561.3 2660.6 2674.9 2675.6 2679.1 4812.8 4819.8 4828.4 4832.7 4834.5 5176.7 5204.2 5210.2 5212.7 5230.6 4605.6 4838.6 4848.6 4849.2 4867.6 5094.8 5105.0 5114.2 5115.7 5126.9 5028.3 5034.0 5038.8 5042.3 5047.2 4664.4 4808.8 4821.5 4822.6 4839.5 3567.7 3589.1 3605.4 3610.9 3619.8 5154.1 5157.8 5162.1 5166.2 5172.5 4786.0 4937.6 4962.5 4966.1 4984.9 4954.7 4964.0 4970.5 4973.8 4974.8 4888.9 4909.4 4910.2 4918.4 4924.6 4761.9 4782.5 4964.0 5000.5 5021.7 4747.3 4838.4 4859.1 4871.0 4883.6 13:37:03 INFO - PROCESS | 3395 | 13:37:03 INFO - PROCESS | 3395 | [#5] yui.html Cycles:180 Average:17512.21 Median:22073.64 stddev:25783.93 (116.8%) stddev-sans-first:25825.08 13:37:03 INFO - PROCESS | 3395 | Values: 623.9 631.2 683.6 699.8 750.1 11901.5 12838.2 12859.3 12871.1 12896.6 13351.2 13589.1 13592.2 13594.2 13603.3 611.3 738.5 746.3 758.5 770.6 750.2 750.7 751.0 751.1 751.2 807.2 807.6 807.6 807.9 808.5 649.4 667.2 667.6 670.0 805.1 565.1 580.0 580.2 582.4 582.6 12386.9 12473.4 12495.4 12501.1 12517.7 11235.0 11312.6 11849.1 11978.2 12423.3 9930.2 10133.6 11005.9 11006.6 11140.8 3966.7 3989.5 4033.7 4297.2 4303.0 50690.0 57561.4 58178.4 58450.7 58798.0 10439.6 10455.8 10462.3 10484.5 10497.0 18091.8 18101.8 18102.9 18105.4 18116.8 6912.8 6967.2 6973.8 7016.0 7240.6 3641.3 3692.5 3699.4 3759.5 3797.1 2792.4 2936.3 2941.8 2944.3 2954.7 496.9 502.9 506.2 508.2 510.2 532.0 582.7 601.7 615.4 649.3 8192.3 8200.7 8269.3 8363.3 8609.2 532.6 539.7 553.6 556.0 563.2 3915.3 3949.3 4018.3 4041.2 4141.9 100350.5 100769.0 103695.0 108640.0 108674.5 88644.6 91866.1 93635.5 93795.0 94250.5 78326.0 80530.6 80591.2 80738.0 81307.0 7549.2 7590.5 8044.0 8964.3 9028.0 41083.8 41960.8 42566.1 42983.8 43883.8 37300.6 37632.0 38974.2 39321.6 40157.8 10100.3 10463.5 10730.1 10786.7 10952.0 12664.5 12754.5 12941.4 13185.7 14185.8 11932.8 12148.9 12665.1 12945.2 13258.9 11295.1 11602.6 11811.1 11870.5 12044.2 11990.2 12281.0 12544.1 12613.4 12993.6 10244.8 10418.7 10623.4 10652.3 10801.4 9552.4 10315.6 10526.3 10526.4 10930.7 13:37:03 INFO - PROCESS | 3395 | -------- Summary: end -------- 13:37:03 INFO - PROCESS | 3395 | 13:37:04 INFO - TEST-INFO | 3395: exit 0 13:37:04 INFO - TEST-OK | dromaeo_css | took 1124188ms 13:37:04 INFO - TEST-START | kraken 13:37:04 INFO - operating with platform_type : linux_ 13:37:04 INFO - Initialising browser for kraken test... 13:37:04 INFO - TEST-INFO | started process 3696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvNgXQm/profile http://localhost:54207/getInfo.html) 13:37:08 INFO - PROCESS | 3696 | __metrics Screen width/height:1600/1200 13:37:08 INFO - PROCESS | 3696 | colorDepth:24 13:37:08 INFO - PROCESS | 3696 | Browser inner width/height: 1024/697 13:37:08 INFO - PROCESS | 3696 | __metrics 13:37:09 INFO - PROCESS | 3696 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:37:09 INFO - PROCESS | 3696 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:37:10 INFO - TEST-INFO | 3696: exit 0 13:37:10 INFO - Browser initialized. 13:37:10 INFO - Running cycle 1/1 for kraken test... 13:37:10 INFO - TEST-INFO | started process 3777 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvNgXQm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:37:22 INFO - PROCESS | 3777 | RSS: Main: 157286400 13:37:22 INFO - PROCESS | 3777 | 13:37:25 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54207/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54207/tests/kraken/driver.html?testName=audio-beat-detection) 13:37:26 INFO - PROCESS | 3777 | RSS: Main: 205012992 13:37:26 INFO - PROCESS | 3777 | 13:37:29 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54207/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54207/tests/kraken/driver.html?testName=audio-dft) 13:37:29 INFO - PROCESS | 3777 | RSS: Main: 195596288 13:37:29 INFO - PROCESS | 3777 | 13:37:33 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54207/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54207/tests/kraken/driver.html?testName=audio-fft) 13:37:33 INFO - PROCESS | 3777 | RSS: Main: 278061056 13:37:33 INFO - PROCESS | 3777 | 13:37:35 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54207/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54207/tests/kraken/driver.html?testName=audio-oscillator) 13:37:36 INFO - PROCESS | 3777 | RSS: Main: 187555840 13:37:36 INFO - PROCESS | 3777 | 13:37:38 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54207/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54207/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:37:39 INFO - PROCESS | 3777 | RSS: Main: 192225280 13:37:39 INFO - PROCESS | 3777 | 13:37:42 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54207/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54207/tests/kraken/driver.html?testName=imaging-darkroom) 13:37:42 INFO - PROCESS | 3777 | RSS: Main: 228556800 13:37:42 INFO - PROCESS | 3777 | 13:37:46 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54207/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54207/tests/kraken/driver.html?testName=imaging-desaturate) 13:37:47 INFO - PROCESS | 3777 | RSS: Main: 222429184 13:37:47 INFO - PROCESS | 3777 | 13:37:49 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54207/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54207/tests/kraken/driver.html?testName=json-parse-financial) 13:37:50 INFO - PROCESS | 3777 | RSS: Main: 225521664 13:37:50 INFO - PROCESS | 3777 | 13:37:52 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54207/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54207/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:37:52 INFO - PROCESS | 3777 | RSS: Main: 189046784 13:37:52 INFO - PROCESS | 3777 | 13:37:54 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54207/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54207/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:37:55 INFO - PROCESS | 3777 | RSS: Main: 196149248 13:37:55 INFO - PROCESS | 3777 | 13:37:57 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54207/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54207/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:37:58 INFO - PROCESS | 3777 | RSS: Main: 193716224 13:37:58 INFO - PROCESS | 3777 | 13:38:01 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54207/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54207/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:38:01 INFO - PROCESS | 3777 | RSS: Main: 188817408 13:38:01 INFO - PROCESS | 3777 | 13:38:04 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54207/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54207/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:38:05 INFO - PROCESS | 3777 | RSS: Main: 192688128 13:38:05 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | Cycle 1(1): loaded http://localhost:54207/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54207/tests/kraken/driver.html?testName=ai-astar) 13:38:07 INFO - PROCESS | 3777 | RSS: Main: 242982912 13:38:07 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | __start_tp_report 13:38:07 INFO - PROCESS | 3777 | _x_x_mozilla_page_load 13:38:07 INFO - PROCESS | 3777 | _x_x_mozilla_page_load_details 13:38:07 INFO - PROCESS | 3777 | |i|pagename|runs| 13:38:07 INFO - PROCESS | 3777 | |0;ai-astar;105;116;115;116;112;115;114;117;114;115 13:38:07 INFO - PROCESS | 3777 | |1;audio-beat-detection;147;122;130;189;122;150;149;124;179;125 13:38:07 INFO - PROCESS | 3777 | |2;audio-dft;163;160;161;162;162;168;162;161;162;161 13:38:07 INFO - PROCESS | 3777 | |3;audio-fft;74;75;75;105;76;77;76;75;101;76 13:38:07 INFO - PROCESS | 3777 | |4;audio-oscillator;88;87;137;87;88;87;126;87;87;125 13:38:07 INFO - PROCESS | 3777 | |5;imaging-gaussian-blur;117;116;116;117;117;116;117;117;117;117 13:38:07 INFO - PROCESS | 3777 | |6;imaging-darkroom;146;147;147;147;146;148;146;147;147;147 13:38:07 INFO - PROCESS | 3777 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;95;95 13:38:07 INFO - PROCESS | 3777 | |8;json-parse-financial;63;61;62;62;63;72;62;62;65;63 13:38:07 INFO - PROCESS | 3777 | |9;json-stringify-tinderbox;65;65;66;66;65;64;65;67;65;66 13:38:07 INFO - PROCESS | 3777 | |10;stanford-crypto-aes;84;83;83;83;87;84;84;83;82;88 13:38:07 INFO - PROCESS | 3777 | |11;stanford-crypto-ccm;132;142;145;135;132;132;133;140;148;156 13:38:07 INFO - PROCESS | 3777 | |12;stanford-crypto-pbkdf2;173;183;176;181;174;171;178;173;173;170 13:38:07 INFO - PROCESS | 3777 | |13;stanford-crypto-sha256-iterative;68;69;68;67;68;73;71;68;67;66 13:38:07 INFO - PROCESS | 3777 | __end_tp_report 13:38:07 INFO - PROCESS | 3777 | __start_cc_report 13:38:07 INFO - PROCESS | 3777 | _x_x_mozilla_cycle_collect,1904 13:38:07 INFO - PROCESS | 3777 | __end_cc_report 13:38:07 INFO - PROCESS | 3777 | __startTimestamp1471293487306__endTimestamp 13:38:07 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | ------- Summary: start ------- 13:38:07 INFO - PROCESS | 3777 | Number of tests: 14 13:38:07 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | [#0] ai-astar Cycles:10 Average:113.90 Median:115.00 stddev:3.41 (3.0%) stddev-sans-first:1.45 13:38:07 INFO - PROCESS | 3777 | Values: 105.0 116.0 115.0 116.0 112.0 115.0 114.0 117.0 114.0 115.0 13:38:07 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | [#1] audio-beat-detection Cycles:10 Average:143.70 Median:148.00 stddev:24.14 (16.3%) stddev-sans-first:25.57 13:38:07 INFO - PROCESS | 3777 | Values: 147.0 122.0 130.0 189.0 122.0 150.0 149.0 124.0 179.0 125.0 13:38:07 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | [#2] audio-dft Cycles:10 Average:162.20 Median:162.00 stddev:2.20 (1.4%) stddev-sans-first:2.32 13:38:07 INFO - PROCESS | 3777 | Values: 163.0 160.0 161.0 162.0 162.0 168.0 162.0 161.0 162.0 161.0 13:38:07 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.50 stddev:11.66 (15.4%) stddev-sans-first:12.09 13:38:07 INFO - PROCESS | 3777 | Values: 74.0 75.0 75.0 105.0 76.0 77.0 76.0 75.0 101.0 76.0 13:38:07 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | [#4] audio-oscillator Cycles:10 Average:99.90 Median:87.00 stddev:20.56 (23.6%) stddev-sans-first:21.35 13:38:07 INFO - PROCESS | 3777 | Values: 88.0 87.0 137.0 87.0 88.0 87.0 126.0 87.0 87.0 125.0 13:38:07 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 13:38:07 INFO - PROCESS | 3777 | Values: 117.0 116.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 13:38:07 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 13:38:07 INFO - PROCESS | 3777 | Values: 146.0 147.0 147.0 147.0 146.0 148.0 146.0 147.0 147.0 147.0 13:38:07 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 13:38:07 INFO - PROCESS | 3777 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 13:38:07 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:63.00 stddev:3.17 (5.0%) stddev-sans-first:3.36 13:38:07 INFO - PROCESS | 3777 | Values: 63.0 61.0 62.0 62.0 63.0 72.0 62.0 62.0 65.0 63.0 13:38:07 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.50 stddev:0.84 (1.3%) stddev-sans-first:0.88 13:38:07 INFO - PROCESS | 3777 | Values: 65.0 65.0 66.0 66.0 65.0 64.0 65.0 67.0 65.0 66.0 13:38:07 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:84.00 stddev:1.91 (2.3%) stddev-sans-first:2.03 13:38:07 INFO - PROCESS | 3777 | Values: 84.0 83.0 83.0 83.0 87.0 84.0 84.0 83.0 82.0 88.0 13:38:07 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | [#11] stanford-crypto-ccm Cycles:10 Average:139.50 Median:141.00 stddev:8.25 (5.9%) stddev-sans-first:8.29 13:38:07 INFO - PROCESS | 3777 | Values: 132.0 142.0 145.0 135.0 132.0 132.0 133.0 140.0 148.0 156.0 13:38:07 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:175.00 stddev:4.26 (2.4%) stddev-sans-first:4.45 13:38:07 INFO - PROCESS | 3777 | Values: 173.0 183.0 176.0 181.0 174.0 171.0 178.0 173.0 173.0 170.0 13:38:07 INFO - PROCESS | 3777 | 13:38:07 INFO - PROCESS | 3777 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.00 stddev:2.07 (3.0%) stddev-sans-first:2.19 13:38:07 INFO - PROCESS | 3777 | Values: 68.0 69.0 68.0 67.0 68.0 73.0 71.0 68.0 67.0 66.0 13:38:07 INFO - PROCESS | 3777 | -------- Summary: end -------- 13:38:07 INFO - PROCESS | 3777 | 13:38:08 INFO - TEST-INFO | 3777: exit 0 13:38:08 INFO - TEST-OK | kraken | took 63809ms 13:38:08 INFO - SUITE-END | took 1187s 13:38:08 INFO - Completed test suite (00:19:48) 13:38:08 INFO - javascript benchmark 13:38:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2597.1299614629097, 2603.479304139172, 2631.9342016449586, 2641.3000554852965, 2646.5368560501906, 4559.589636932684, 4628.814847406108, 4633.513481084488, 4654.301854721789, 4664.416947881515, 4639.1881420751315, 4657.37125488059, 4691.5073917238715, 4703.529647035295, 4704.694194877339, 4247.915041699164, 4284.207421626993, 4300.6344460720875, 4350.651947844165, 4489.25927222008, 4413.757243351614, 4445.066536027438, 4457.888552786174, 4459.041306119184, 4474.485434175062, 4206.978965105185, 4271.850485233002, 4326.069894972596, 4326.329418940069, 4358.193734159159, 4270.530241673413, 4291.9785401072795, 4293.29160688486, 4309.288967320388, 4340.674449416307, 4315.352697095429, 4324.048709283952, 4326.5673432656795, 4345.9348109778375, 4361.890952726176, 4608.446986361624, 4686.133065382915, 4700.059988002413, 4703.106409782131, 4746.454157771831, 5073.365829271341, 5098.821541246076, 5142.51146141116, 5147.639665223436, 5149.2791009258735, 5089.770960306795, 5109.489051094898, 5135.152699804527, 5153.66501177426, 5175.456577059392, 4577.56513131252, 4693.296005599119, 4751.40607424072, 4803.351547541072, 4818.325434439182, 5214.530692237717, 5223.268742376071, 5248.790048398021, 5253.921191182136, 5259.421463638997, 5037.546620804072, 5042.949570504321, 5044.0, 5044.974775126101, 5045.747712614428, 4912.968276661941, 4948.950510494849, 4983.202687569971, 5131.307273518102, 5132.79468821243, 4880.95119048805, 4887.315775791392, 4888.95111048885, 4898.877528061756, 4919.065377578248, 4900.534449227318, 4901.730404827768, 4902.681325713888, 4903.239997800347, 4920.6555541111775, 4093.4269202311702, 4131.648809851136, 4138.441310423054, 4141.378793181047, 4170.374443833449, 3646.033801042726, 3657.5245218122004, 3693.445983102556, 3725.515682961198, 3745.2883952049024, 4395.230834603932, 4403.581659742319, 4408.228560001987, 4450.35469856869, 4450.3991961085485, 4799.016201678647, 4838.830640927551, 5038.672486288379, 5042.218456139304, 5045.41977672565, 4323.351497275371, 4330.566943305707, 4341.804618792162, 4359.934600980924, 4395.428594282723, 4462.174497717932, 4466.6873318867365, 4467.597916187558, 4468.687191896601, 4510.909781804346, 4694.6244300456565, 4716.64625153108, 4727.290906364073, 4727.3854398927915, 4759.50025247351, 4899.902001959869, 4942.061008408391, 4952.801887924443, 4994.750262486934, 5001.074801161797, 4690.226112691368, 4694.906101878011, 4696.671233013656, 4707.976460117678, 4713.269443236304, 4894.021195760791, 4895.706257624412, 4897.2654101885, 4906.55840974314, 4937.9259311109645, 4887.340209071872, 4955.108080545537, 5067.5185857343495, 5107.87230319245, 5121.206213036985, 4938.333325001079, 4939.357883475125, 4940.6788558743565, 4941.851744447672, 4978.975105124596, 4931.112399768076, 4939.6542242042715, 4939.777710003131, 4940.654154209315, 4975.4278258000795, 4688.976555117202, 4737.052589482187, 4793.736344501086, 4799.616030717604, 4891.33966914462, 4865.343178670834, 4871.926921096116, 4872.0, 4887.535684110004, 4907.3620429343955, 4674.883127921829, 4751.61987041029, 4760.571548560646, 4762.07139607787, 4767.0], "value": 4607.11856093281, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [547.3623228938286, 567.2342337843909, 574.7643466178868, 575.1889835332181, 575.8502789274787, 28139.437211255787, 39298.80350598247, 40544.39183412244, 40567.99999999996, 44154.0, 40585.391219131736, 41120.79439602805, 41208.793956030175, 41269.0, 41292.0, 7455.589942553157, 7629.313361797424, 7767.223277672216, 7772.028496437945, 7774.689012439496, 7489.6629651665535, 7556.848863022737, 7559.924400756005, 9820.165285950667, 10040.447775372351, 9896.802063958716, 9952.452615106165, 9953.452560109192, 9958.502074896227, 9960.800783984316, 10086.747831304203, 10138.188944884465, 10149.289549731522, 10153.695389138338, 10156.441395723232, 9838.557264923096, 9932.205423566169, 9937.205023598168, 9941.90058099417, 9942.254330925138, 37851.810740946115, 38132.61867381319, 38199.23601527954, 38205.23589528194, 38216.23567528662, 3027.6782158502765, 3138.3880143372053, 3496.947545786815, 3507.824608769551, 3508.333416650828, 28529.714702852914, 29094.27264318388, 29111.0, 29123.126306210637, 29253.414931701245, 3396.7112795412418, 3411.5564976553137, 3462.2036931505645, 3578.5526809148855, 3617.385044542434, 249136.0, 249225.0, 250456.0, 250570.0, 250632.0, 31222.21944451407, 51391.0, 51798.48201517937, 51897.740511297205, 52016.21975670368, 31612.89032425816, 33568.49647255293, 33608.663913361044, 33645.663543364746, 33687.15782105467, 12953.676158095934, 12971.5459958901, 13082.215067096005, 13205.801912971314, 13213.207207567577, 10523.894761052445, 10709.518071686793, 10730.0, 10732.946335268274, 10741.624043158452, 7176.7488137914925, 7569.5079819811535, 7741.729039483704, 7805.102413222438, 7938.722144724907, 2776.555751079858, 2788.4980703473166, 2814.5637426198973, 2825.138332808472, 2864.069177517315, 811.3063330852154, 861.4099341950783, 879.445949052093, 889.3818795936891, 914.7667344827026, 27452.862735686194, 28064.85967570149, 28390.716092838808, 29059.418811623647, 29072.563911541343, 918.4351623751345, 925.421611492817, 944.5796620503947, 974.6247694637616, 1039.18424037132, 14987.475438359823, 15156.469523566622, 15200.011999220236, 15451.84548154504, 15518.43076092505, 384260.0, 449120.0, 458996.0, 459712.0, 684226.0, 49468.0, 50120.49879501231, 50198.498015020115, 50202.49797502051, 50260.497395026316, 1106.319613437746, 1114.676743744305, 1125.555405614794, 1157.774234024374, 1183.4378670131618, 24817.766401023993, 29474.263143421587, 29603.25991850135, 29860.701392985793, 29876.253093672833, 36107.27785444329, 46168.30747538857, 47683.52316476929, 47700.761496193685, 52324.738376307876, 277456.6127169351, 280498.0, 280511.0, 280560.0, 281429.0, 24430.26709198727, 25033.74966250314, 36453.0, 36490.0, 37000.62999369972, 35475.0, 35633.46549801703, 42361.57638423699, 46121.53878461172, 47430.76284618555, 34606.7887623932, 36369.454458183674, 37290.44064339092, 37490.0, 37901.24197516089, 36122.0, 36419.63580364268, 36676.26647466982, 36721.63278367182, 36791.0, 35446.46830297496, 35872.4619130708, 36229.637703622626, 36230.0, 36648.63351366452, 31277.0, 33715.76258392206, 33754.66245337515, 33878.3224335507, 34032.0, 24956.0, 25770.0, 25792.355191119623, 25855.0, 25948.481030379666], "value": 16967.40270501378, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.71863946122016, 367.06947887106185, 368.8838016024953, 379.15448549734094, 379.2339474261993, 15765.369385224592, 17362.652746945052, 17888.373906913246, 17932.28270869167, 17974.101294935233, 14906.0, 14952.62618434537, 15175.468858589951, 15265.313060912258, 15302.311395987179, 4897.583705385046, 5128.769205385766, 5134.897302053957, 5138.126518491847, 5145.202493613496, 3730.7575007624555, 3821.48409964654, 5023.045621331954, 5732.713364331767, 6033.396660333975, 6021.006533921897, 6128.172696685935, 6132.938670613281, 6135.693215339237, 6135.846603834895, 5844.707764611752, 5943.64338139713, 5959.463648271633, 5969.403059694039, 5969.552283578749, 5091.745412729349, 5209.843704688866, 5217.791288348461, 5235.633505654606, 5261.842144735664, 21456.0, 22022.669659955114, 22110.0, 22296.0, 23137.0, 2057.8765274083603, 2138.7326584176976, 2248.9437764055865, 2546.096135871776, 2546.5543529882198, 14168.291585420688, 14290.92854535731, 14463.421463141462, 14864.256787160706, 15277.770833437507, 1765.470358892327, 1824.963500729978, 1867.3464287499403, 1912.3880358285355, 1914.8755330903446, 76938.0, 77822.61088694577, 78001.0, 79013.60493197554, 81808.18191818065, 16149.757753633705, 16504.83495165033, 16989.830101699074, 17047.14764261782, 17157.485275441755, 28423.28941776472, 28447.0, 28570.857145714555, 28631.57052644212, 28694.426111477653, 8588.312934965188, 8822.691205807894, 8832.55837208137, 9118.908810911938, 9188.40275382098, 7220.602866842269, 7266.0, 7286.70853165878, 7473.290037446434, 7490.887636685454, 8334.874976875351, 8383.365243777527, 8437.198466145828, 8579.142085791496, 8590.14098590136, 2719.8440662718267, 3050.2679356954613, 3125.48429509133, 3154.6529881712995, 3397.6432474590306, 333.79138038725796, 343.5139277921763, 381.54596030723906, 384.6884023940617, 738.5531753289183, 10151.390916545031, 10577.259591828652, 10663.146948244123, 11114.944425277821, 11170.720731981603, 395.82188015392614, 413.50173041484936, 415.34997728554714, 421.56999860142486, 428.346771173956, 5652.63257888236, 5656.547476201977, 5819.6508209507565, 5965.940340596625, 5971.19388882504, 381234.0, 385017.0, 385405.0, 388621.0809973048, 388803.0, 74616.0, 75037.0, 75333.62333188299, 76912.0, 79188.60405697934, 437.37673814813763, 443.9089986552749, 447.2262985035906, 874.4578361415963, 923.1184219070772, 14965.176915269765, 15618.765718514003, 15725.685486289982, 16028.0, 16847.663046739242, 34612.0, 38723.70560248969, 38860.6113938857, 38944.2211155781, 40369.0, 48653.5134648649, 49098.509014909396, 50345.244821328444, 50725.74637126791, 51377.229341560655, 7295.927040729524, 7658.540487570764, 7891.763247102596, 7942.523448593103, 7952.125266220827, 5528.723563821713, 5738.282714660668, 11888.524459021888, 11898.643040708792, 12280.692982675504, 10311.432871191857, 10340.586376544854, 10399.32404393712, 10466.476676166008, 11020.614278500214, 10478.476076196008, 10508.842367364343, 10573.89426105729, 10780.568777248822, 10873.510692018719, 9645.890722566995, 10205.540750666387, 10341.793164136825, 10543.0, 10769.9461502692, 7699.422543309162, 7750.457467977188, 7823.100343460347, 7868.72459463916, 7939.245771651684, 9432.669856555262, 9725.805483890425, 9756.07317304855, 9833.14927618066, 10292.588296468057], "value": 8374.800868108148, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1258.0187453786048, 1298.9415476303566, 1346.5960211936417, 1503.6691927775885, 1514.1520748380901, 7490.9625451872735, 8159.469634473762, 8198.877016844752, 8201.0, 8202.507849529033, 6969.965150174255, 7168.641567921596, 7169.247229040954, 7365.484416090862, 8032.036155661329, 2042.7855075217112, 2121.6499277619173, 2143.65701487762, 2149.3014770199748, 2149.570085982802, 2137.711408959794, 2154.8383871209644, 2180.6292930201903, 2186.967195492069, 2228.320362289501, 2181.4001149683804, 2185.9344219673435, 2191.112599397247, 2199.670049492573, 2202.3723238876923, 2069.8758074515504, 2139.304725964068, 2149.387424583986, 2150.8924553772326, 2151.6449785785326, 2085.3222702621556, 2103.2428325802853, 2112.2290364017176, 2152.89235538224, 2153.0849389009763, 7897.881531777028, 7996.720114795954, 8007.199280071946, 8026.0, 8104.716334928308, 4460.264056430685, 4607.9308810367875, 4613.469451013143, 4614.792334344963, 4617.676762626617, 2740.657417822772, 2859.5567687008547, 2866.971330286691, 2877.9712202877913, 2914.12576227131, 897.210454799772, 927.967521136757, 955.2405837359316, 968.9031096890325, 977.7751117243002, 11105.777884442266, 11149.108071354352, 11153.609623663133, 11155.832662510069, 11215.102791776639, 3971.960280397217, 4114.526829414595, 4180.3102488089735, 4200.243956087934, 4243.427137336482, 9449.527523623792, 9469.384490008128, 9469.763255918519, 9469.905300946903, 9474.857877131848, 4428.269335559661, 4429.2027435062, 4430.867073987785, 4432.955670443319, 4433.135538570013, 3553.07620018797, 4021.7184797063924, 4023.5775243599587, 4023.7384570002855, 4028.697847661437, 3567.714582833367, 3697.556293244828, 3710.07248187953, 3732.738708290394, 3775.811209439517, 1172.0330727150135, 1176.5117476247433, 1186.454231053725, 1192.070185255502, 1192.2667559450874, 762.4777027736019, 768.3584207187062, 771.5756334016269, 775.3913178155121, 783.0, 4918.5573298403315, 4922.556969872729, 4954.5540901319055, 4957.876053098701, 5191.740412979336, 825.9834803303901, 875.5315905990265, 921.5162039929091, 929.3030227329502, 932.9067093290616, 3220.1949512621845, 3244.4808830586994, 3276.4266253406036, 3310.519974603669, 3413.709834664032, 9580.473073980858, 9735.318527702993, 9899.60401583943, 9912.35569687968, 9922.900770992197, 9819.116279534876, 9827.508624568743, 9830.0, 9835.655752048788, 9837.852432213522, 7226.060612120391, 7334.0, 7349.779506614703, 7363.226861179499, 7367.079115110611, 4804.975975120102, 4830.4444988826735, 4895.681780684244, 4935.827246046371, 5138.332016837937, 8817.38278320537, 8867.645294188418, 8871.645134194818, 8924.598393072198, 9054.54727263621, 9059.320550958573, 9188.632454701738, 9240.445573265626, 9338.486383248986, 9361.906380936103, 6507.73969041252, 6545.770898018546, 6560.179977502812, 6618.867622647616, 6624.867502650017, 7495.475316727995, 7538.246175382418, 7562.130355009024, 7562.281583249583, 7589.544627322378, 6640.767573134917, 6714.194296684429, 6758.222804377363, 6793.218779840382, 6996.965015174892, 6745.12313399255, 6753.3246675332075, 6777.728890844311, 6797.93202067973, 6865.210500792273, 6102.877942441215, 6158.476529495043, 6188.102725104744, 6216.191895053614, 6231.750729970931, 4305.095929854766, 4352.15133049065, 4378.540253273488, 4386.254336762821, 4437.29003359461, 4321.978390108029, 4396.846110386121, 4454.732716037048, 4474.597286244254, 4521.434820647419], "value": 4095.515170662293, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.2518511156388, 288.9624348834651, 291.45061558961356, 294.81132075471703, 295.4917541828055, 4777.8088876444945, 4826.276058591213, 4832.758362081895, 4856.2472816713425, 4857.902841943168, 4710.670253082277, 4726.881827954303, 4778.546038126381, 4782.402199725035, 4790.257510085934, 3552.1119720070046, 3688.686461650763, 3689.5019172411785, 3692.8707495237672, 3698.8163787587982, 3622.7320437846806, 3688.686461650763, 3756.4740936268945, 3774.4527043578664, 3774.962250377502, 3701.666849983501, 3709.536307961505, 3715.5541335039698, 3723.0, 3723.497327860745, 3577.4276115821604, 3688.852445902174, 3695.1870588470492, 3722.1947355532543, 3732.5520937487677, 3272.885449009273, 3288.0, 3288.8848890288964, 3299.5215693724517, 3313.2379552702773, 4571.291449825282, 4580.290055041474, 4585.335126406686, 4588.4035075440315, 4625.514320996281, 4238.321868501056, 4248.681348898814, 4252.468441444819, 4256.553061928484, 4262.190183865056, 4694.0, 4893.730844803535, 4894.461609222983, 4938.925916111225, 4985.052839960396, 3575.4994300797907, 3576.445650924111, 3582.946255806165, 3584.731145164097, 3587.192881601645, 5155.845324640267, 5157.9684063187515, 5169.353830771153, 5180.222966555009, 5203.297554830087, 3398.1674489749676, 3585.731070169748, 3691.1141326082084, 3694.2057457646733, 3698.815059247027, 3614.548181477315, 3618.565772107364, 3622.9637703623152, 3633.818309084535, 3635.2547727715755, 4140.855070072594, 4153.979230103891, 4166.58334166587, 4176.436181115571, 4184.790760462026, 4948.134076536592, 4960.156773348539, 4961.759560109973, 4965.702057876539, 4975.726335051607, 1196.4804698033622, 1237.9786675992343, 1239.8388209532702, 1245.5080243304026, 1247.3513772838073, 783.4868161354322, 786.3866184376195, 796.2873228460495, 796.8565658181583, 808.2559709785246, 4006.5392479864604, 4198.85304014358, 4348.195583817004, 4444.1333939881415, 4452.777361131931, 5071.3153724247495, 5072.010957863181, 5078.289039534468, 5079.212722028092, 5088.745562721849, 4432.955670443254, 4440.734390698635, 4450.376947227391, 4457.398251236106, 4467.709598876063, 2561.295643697998, 2660.6408134901926, 2674.906378276751, 2675.5986602009853, 2679.102500662261, 4812.759362031885, 4819.807207711652, 4828.444728856227, 4832.710037397767, 4834.468208497063, 5176.663516871391, 5204.245384419238, 5210.192420174879, 5212.687238765686, 5230.63385563007, 4605.631549476034, 4838.612910967114, 4848.5636292733825, 4849.248366503127, 4867.634927380532, 5094.84715458522, 5105.0, 5114.207297868837, 5115.667481613683, 5126.897462050663, 5028.270900719448, 5034.0, 5038.84883453497, 5042.268871013755, 5047.167217409086, 4664.390785179108, 4808.763924774857, 4821.5178482152905, 4822.614190864652, 4839.540243676845, 3567.7324200684534, 3589.10272431892, 3605.351036813399, 3610.9097272568397, 3619.8190090495896, 5154.14956532168, 5157.7679004445645, 5162.148245539444, 5166.173412254172, 5172.456892026434, 4786.042791441656, 4937.580305674058, 4962.486441635336, 4966.063235338293, 4984.8753781155765, 4954.727489987941, 4964.0, 4970.478099799469, 4973.776180071833, 4974.801007959641, 4888.853334399973, 4909.435414927186, 4910.238912968495, 4918.409790825124, 4924.556789888928, 4761.9047619048115, 4782.52174782519, 4963.950360496348, 5000.499950004971, 5021.72380519075, 4747.335373047845, 4838.395200599925, 4859.080663138111, 4870.951290487192, 4883.584895283941], "value": 3732.6806627747533, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [623.875224955009, 631.1826185090307, 683.6308393467525, 699.7515881861935, 750.1148644599373, 11901.52393904244, 12838.165519241244, 12859.292738899358, 12871.09902306839, 12896.613101606956, 13351.198928064323, 13589.11670741401, 13592.184468931846, 13594.184348939047, 13603.319834008309, 611.2786911444493, 738.4609235258265, 746.3432179681886, 758.472861361354, 770.6416516319911, 750.2272659161073, 750.6959681329043, 751.0461713623695, 751.0536723728087, 751.2074761127009, 807.1807115777491, 807.5840941914919, 807.6446363600027, 807.9313258373045, 808.4987307869129, 649.4187702006725, 667.1760375935716, 667.6161207305819, 670.0217682184017, 805.1264378149715, 565.124057710547, 579.9768009279666, 580.1558732044865, 582.4263100845664, 582.5543459253632, 12386.876131238752, 12473.376331183495, 12495.375231238402, 12501.124921255516, 12517.687057823536, 11235.03386907296, 11312.552536421137, 11849.119101101382, 11978.161528692995, 12423.254604723745, 9930.2055835533, 10133.594656213743, 11005.944970275177, 11006.614768483145, 11140.777184456347, 3966.7223294369405, 3989.4813674222164, 4033.6773058155572, 4297.226499230137, 4302.9991054159655, 50689.98620027616, 57561.42438575644, 58178.41821581772, 58450.70774646142, 58798.0, 10439.582416703246, 10455.790884182426, 10462.26764126519, 10484.475776211159, 10497.0, 18091.819081809015, 18101.818981810015, 18102.90948545275, 18105.366312179012, 18116.818831811775, 6912.792616221421, 6967.198772141269, 6973.79078627642, 7016.0, 7240.565566066053, 3641.289948460025, 3692.538432695913, 3699.445083237482, 3759.49246851677, 3797.1076796952693, 2792.4415116976684, 2936.295289130636, 2941.838198899038, 2944.2933695912825, 2954.704529547071, 496.9403671559436, 502.8642266588001, 506.1900958466498, 508.22241969786285, 510.2270060857803, 531.9760610772524, 582.7465052702088, 601.7352364959492, 615.3907631444837, 649.2663290481726, 8192.30365418946, 8200.712975045844, 8269.338452923752, 8363.28912042471, 8609.182127697859, 532.6378062917177, 539.7166487593965, 553.5682167908957, 555.9888802223933, 563.1580786723874, 3915.334393153171, 3949.289127956938, 4018.3168861293652, 4041.211963667057, 4141.875743727693, 100350.4982475083, 100769.0, 103695.0, 108640.0, 108674.45662771635, 88644.55677721699, 91866.08133918709, 93635.53182234045, 93795.0, 94250.5287473572, 78326.0, 80530.5973470129, 80591.19408805837, 80738.0, 81307.0, 7549.2073332299315, 7590.544567325978, 8044.0, 8964.282857371525, 9028.0, 41083.7945810281, 41960.79019604882, 42566.14867702567, 42983.78508107439, 43883.78058109689, 37300.62699372972, 37632.0, 38974.22051558896, 39321.60678393294, 40157.79921100376, 10100.292979491365, 10463.476826158509, 10730.141588673043, 10786.724949964564, 10952.0, 12664.493420263087, 12754.48982040708, 12941.352932353157, 13185.736285274432, 14185.787213192018, 11932.76134477323, 12148.878511215129, 12665.050121241127, 12945.158564693265, 13258.851719741293, 11295.096392288762, 11602.593909213474, 11811.114166437723, 11870.465829037541, 12044.19450350744, 11990.160688751703, 12281.0, 12544.059195560187, 12613.36933153357, 12993.630573248332, 10244.795104097728, 10418.739531511774, 10623.415712135908, 10652.254342195973, 10801.405922674328, 9552.37909535878, 10315.587376504856, 10526.263161578616, 10526.421046842497, 10930.726731831768], "value": 6493.242463732451, "unit": "score"}], "name": "dromaeo_css", "value": 6340.790113456812, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 115.0, 116.0, 112.0, 115.0, 114.0, 117.0, 114.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 122.0, 130.0, 189.0, 122.0, 150.0, 149.0, 124.0, 179.0, 125.0], "value": 143.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 161.0, 162.0, 162.0, 168.0, 162.0, 161.0, 162.0, 161.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 105.0, 76.0, 77.0, 76.0, 75.0, 101.0, 76.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 137.0, 87.0, 88.0, 87.0, 126.0, 87.0, 87.0, 125.0], "value": 99.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 147.0, 146.0, 148.0, 146.0, 147.0, 147.0, 147.0], "value": 146.8, "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": [63.0, 61.0, 62.0, 62.0, 63.0, 72.0, 62.0, 62.0, 65.0, 63.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 66.0, 65.0, 64.0, 65.0, 67.0, 65.0, 66.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 83.0, 87.0, 84.0, 84.0, 83.0, 82.0, 88.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 142.0, 145.0, 135.0, 132.0, 132.0, 133.0, 140.0, 148.0, 156.0], "value": 139.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 183.0, 176.0, 181.0, 174.0, 171.0, 178.0, 173.0, 173.0, 170.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 67.0, 68.0, 73.0, 71.0, 68.0, 67.0, 66.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1555.5, "alertThreshold": 2.0}]} 13:38:08 INFO - Return code: 0 13:38:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:38:08 INFO - # TBPL SUCCESS # 13:38:08 INFO - Running post-action listener: _resource_record_post_action 13:38:08 INFO - [mozharness: 2016-08-15 20:38:08.595533Z] Finished run-tests step (success) 13:38:08 INFO - Running post-run listener: _resource_record_post_run 13:38:08 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 7770112; Write bytes: 419815424; Read time: 1456; Write time: 429880 13:38:08 INFO - TinderboxPrint: CPU usage
13.0% 13:38:08 INFO - TinderboxPrint: I/O read bytes / time
7,770,112 / 1,456 13:38:08 INFO - TinderboxPrint: I/O write bytes / time
419,815,424 / 429,880 13:38:08 INFO - TinderboxPrint: CPU idle
8,361.9 (87.0%) 13:38:08 INFO - TinderboxPrint: CPU user
1,197.4 (12.5%) 13:38:08 INFO - TinderboxPrint: Swap in / out
0 / 0 13:38:08 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 4677632; Read time: 0; Write time: 352 13:38:08 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 5529600; Write bytes: 415137792; Read time: 1296; Write time: 429528 13:38:09 INFO - Running post-run listener: _upload_blobber_files 13:38:09 INFO - Blob upload gear active. 13:38:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:38:09 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 13:38:09 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'] 13:38:09 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 13:38:09 INFO - (blobuploader) - INFO - Open directory for files ... 13:38:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:38:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:38:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:38:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:38:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:38:10 INFO - (blobuploader) - INFO - Done attempting. 13:38:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:38:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:38:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:38:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:38:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:38:11 INFO - (blobuploader) - INFO - Done attempting. 13:38:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:38:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:38:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:38:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:38:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:38:15 INFO - (blobuploader) - INFO - Done attempting. 13:38:15 INFO - (blobuploader) - INFO - Iteration through files over. 13:38:15 INFO - Return code: 0 13:38:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:38:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:38:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/433d43eea4131a63196cd4069e21991c9ca007d46a7fa0e347e2f7239d200f1c454b0edf5909e1fb849a235eced1363f9154602914c5c5f39c9f2fe42609aab6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/97153e5900af6395f719d4aa0b1816708830c074556e417e0537a5f32e42e0faf6351d5679f1728f047be2e32433b2fe95f4bbffb4d29a93827a7f61d9270ad4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ee67e8b287a1982bf9636d679e29ae3087c618e81398d7487872fb3e524eee439a7947e91a66af622a0a1c7be488798228bab64aaf238ce0096e2704753272f6"} 13:38:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:38:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:38:15 INFO - Contents: 13:38:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/433d43eea4131a63196cd4069e21991c9ca007d46a7fa0e347e2f7239d200f1c454b0edf5909e1fb849a235eced1363f9154602914c5c5f39c9f2fe42609aab6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/97153e5900af6395f719d4aa0b1816708830c074556e417e0537a5f32e42e0faf6351d5679f1728f047be2e32433b2fe95f4bbffb4d29a93827a7f61d9270ad4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ee67e8b287a1982bf9636d679e29ae3087c618e81398d7487872fb3e524eee439a7947e91a66af622a0a1c7be488798228bab64aaf238ce0096e2704753272f6"} 13:38:15 INFO - Running post-run listener: copy_logs_to_upload_dir 13:38:15 INFO - Copying logs to upload dir... 13:38:15 INFO - mkdir: /builds/slave/test/build/upload/logs 13:38:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.433664 ========= master_lag: 0.26 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-08-15 13:38:15.808587) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 13:38:15.812520) ========= 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=6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070 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/433d43eea4131a63196cd4069e21991c9ca007d46a7fa0e347e2f7239d200f1c454b0edf5909e1fb849a235eced1363f9154602914c5c5f39c9f2fe42609aab6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/97153e5900af6395f719d4aa0b1816708830c074556e417e0537a5f32e42e0faf6351d5679f1728f047be2e32433b2fe95f4bbffb4d29a93827a7f61d9270ad4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ee67e8b287a1982bf9636d679e29ae3087c618e81398d7487872fb3e524eee439a7947e91a66af622a0a1c7be488798228bab64aaf238ce0096e2704753272f6"} build_url:https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011088 build_url: 'https://queue.taskcluster.net/v1/task/LXZQQ2MlS0W5LbvI_KqJoQ/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/433d43eea4131a63196cd4069e21991c9ca007d46a7fa0e347e2f7239d200f1c454b0edf5909e1fb849a235eced1363f9154602914c5c5f39c9f2fe42609aab6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/97153e5900af6395f719d4aa0b1816708830c074556e417e0537a5f32e42e0faf6351d5679f1728f047be2e32433b2fe95f4bbffb4d29a93827a7f61d9270ad4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ee67e8b287a1982bf9636d679e29ae3087c618e81398d7487872fb3e524eee439a7947e91a66af622a0a1c7be488798228bab64aaf238ce0096e2704753272f6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 13:38:15.841369) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 13:38:15.842072) ========= 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=6d15e502d8e29d5d2676d664000001c3-1471292239.165270-499218070 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004342 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 13:38:15.921638) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 13:38:15.921923) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 13:38:15.922195) ========= ========= Total master_lag: 0.41 =========