builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-027 starttime: 1471475534.26 results: success (0) buildid: 20160817143004 builduid: ae97d35ac4d7413faeb3878502af0c3c revision: 876756efc390115f1fde3b23e8369edd95eda403 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 16:12:14.260556) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 16:12:14.261230) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 16:12:14.261546) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 16:12:14.303582) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 16:12:14.307929) ========= 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=1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-17 16:12:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 47.0M=0s 2016-08-17 16:12:14 (47.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122489 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 16:12:14.459517) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 16:12:14.463837) ========= 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=1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015733 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 16:12:14.514505) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-17 16:12:14.518873) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 876756efc390115f1fde3b23e8369edd95eda403 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 876756efc390115f1fde3b23e8369edd95eda403 --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=1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-17 16:12:14,524 truncating revision to first 12 chars 2016-08-17 16:12:14,524 Setting DEBUG logging. 2016-08-17 16:12:14,524 attempt 1/10 2016-08-17 16:12:14,524 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/876756efc390?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-17 16:12:15,277 unpacking tar archive at: fx-team-876756efc390/testing/mozharness/ program finished with exit code 0 elapsedTime=1.146208 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-17 16:12:15.696285) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 16:12:15.696580) ========= script_repo_revision: 876756efc390115f1fde3b23e8369edd95eda403 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 16:12:15.696994) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 16:12:15.697253) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 16:12:15.710784) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-17 16:12:15.711162) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:12:15 INFO - MultiFileLogger online at 20160817 16:12:15 in /builds/slave/test 16:12:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 16:12:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:12:15 INFO - {'append_to_log': False, 16:12:15 INFO - 'base_work_dir': '/builds/slave/test', 16:12:15 INFO - 'blob_upload_branch': 'Fx-Team', 16:12:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:12:15 INFO - 'branch': 'Fx-Team', 16:12:15 INFO - 'buildbot_json_path': 'buildprops.json', 16:12:15 INFO - 'config_files': ('talos/linux_config.py',), 16:12:15 INFO - 'default_actions': ('clobber', 16:12:15 INFO - 'read-buildbot-config', 16:12:15 INFO - 'download-and-extract', 16:12:15 INFO - 'populate-webroot', 16:12:15 INFO - 'create-virtualenv', 16:12:15 INFO - 'install', 16:12:15 INFO - 'run-tests'), 16:12:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:12:15 INFO - 'download_minidump_stackwalk': True, 16:12:15 INFO - 'download_symbols': 'ondemand', 16:12:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:12:15 INFO - 'tooltool.py': '/tools/tooltool.py', 16:12:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:12:15 INFO - '/tools/misc-python/virtualenv.py')}, 16:12:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:12:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:12:15 INFO - 'installer_path': 'installer.exe', 16:12:15 INFO - 'log_level': 'info', 16:12:15 INFO - 'log_name': 'talos', 16:12:15 INFO - 'log_to_console': True, 16:12:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:12:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:12:15 INFO - 'opt_config_files': (), 16:12:15 INFO - 'pip_index': False, 16:12:15 INFO - 'sps_profile': False, 16:12:15 INFO - 'sps_profile_interval': 0, 16:12:15 INFO - 'suite': 'dromaeojs', 16:12:15 INFO - 'system_bits': '32', 16:12:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:12:15 INFO - 'title': 'talos-linux64-ix-027', 16:12:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:12:15 INFO - 'use_talos_json': True, 16:12:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:12:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:12:15 INFO - 'work_dir': 'build'} 16:12:15 INFO - [mozharness: 2016-08-17 23:12:15.813125Z] Running clobber step. 16:12:15 INFO - Running pre-action listener: _resource_record_pre_action 16:12:15 INFO - Running main action method: clobber 16:12:15 INFO - rmtree: /builds/slave/test/build 16:12:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:12:16 INFO - Running post-action listener: _resource_record_post_action 16:12:16 INFO - [mozharness: 2016-08-17 23:12:16.768616Z] Finished clobber step (success) 16:12:16 INFO - [mozharness: 2016-08-17 23:12:16.768715Z] Running read-buildbot-config step. 16:12:16 INFO - Running pre-action listener: _resource_record_pre_action 16:12:16 INFO - Running main action method: read_buildbot_config 16:12:16 INFO - Using buildbot properties: 16:12:16 INFO - { 16:12:16 INFO - "project": "", 16:12:16 INFO - "product": "firefox", 16:12:16 INFO - "script_repo_revision": "production", 16:12:16 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 16:12:16 INFO - "repository": "", 16:12:16 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 16:12:16 INFO - "buildid": "20160817143004", 16:12:16 INFO - "pgo_build": "True", 16:12:16 INFO - "basedir": "/builds/slave/test", 16:12:16 INFO - "buildnumber": 1474, 16:12:16 INFO - "slavename": "talos-linux64-ix-027", 16:12:16 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:12:16 INFO - "platform": "ubuntu64_hw", 16:12:16 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 16:12:16 INFO - "branch": "fx-team", 16:12:16 INFO - "repo_path": "integration/fx-team", 16:12:16 INFO - "revision": "876756efc390115f1fde3b23e8369edd95eda403", 16:12:16 INFO - "stage_platform": "linux64-pgo", 16:12:16 INFO - "builduid": "ae97d35ac4d7413faeb3878502af0c3c", 16:12:16 INFO - "slavebuilddir": "test" 16:12:16 INFO - } 16:12:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 16:12:16 INFO - Running post-action listener: _resource_record_post_action 16:12:16 INFO - [mozharness: 2016-08-17 23:12:16.771123Z] Finished read-buildbot-config step (success) 16:12:16 INFO - [mozharness: 2016-08-17 23:12:16.771211Z] Running download-and-extract step. 16:12:16 INFO - Running pre-action listener: _resource_record_pre_action 16:12:16 INFO - Running main action method: download_and_extract 16:12:16 INFO - mkdir: /builds/slave/test/build/tests 16:12:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:16 INFO - trying https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:12:16 INFO - Downloading https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/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 16:12:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/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 16:12:17 INFO - Downloaded 1361 bytes. 16:12:17 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:12:17 INFO - Using the following test package requirements: 16:12:17 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:12:17 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:12:17 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:12:17 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:17 INFO - u'jsshell-linux-x86_64.zip'], 16:12:17 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:12:17 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:12:17 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:12:17 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:12:17 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:12:17 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:17 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:12:17 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:12:17 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')]} 16:12:17 INFO - trying https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:12:17 INFO - Downloading https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/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 16:12:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/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 16:12:18 INFO - Downloaded 21903402 bytes. 16:12:18 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 16:12:22 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 16:12: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')]} 16:12:22 INFO - trying https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:12:22 INFO - Downloading https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/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 16:12:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/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 16:12:23 INFO - Downloaded 21903402 bytes. 16:12:23 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 16:12:27 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')]} 16:12:27 INFO - trying https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:12:27 INFO - Downloading https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/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 16:12:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/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 16:12:28 INFO - Downloaded 14413981 bytes. 16:12:28 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 16:12:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:28 INFO - trying https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:12:28 INFO - Downloading https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/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 16:12:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/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 16:12:30 INFO - Downloaded 59553996 bytes. 16:12:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:12:30 INFO - mkdir: /builds/slave/test/properties 16:12:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:12:30 INFO - Writing to file /builds/slave/test/properties/build_url 16:12:30 INFO - Contents: 16:12:30 INFO - build_url:https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:12:30 INFO - Running post-action listener: _resource_record_post_action 16:12:30 INFO - Running post-action listener: set_extra_try_arguments 16:12:30 INFO - [mozharness: 2016-08-17 23:12:30.816731Z] Finished download-and-extract step (success) 16:12:30 INFO - [mozharness: 2016-08-17 23:12:30.816953Z] Running populate-webroot step. 16:12:30 INFO - Running pre-action listener: _resource_record_pre_action 16:12:30 INFO - Running main action method: populate_webroot 16:12:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:12:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:12:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:12:30 INFO - '--tppagecycles', 16:12:30 INFO - '7'], 16:12:30 INFO - 'tests': ['tsvgm']}}, 16:12:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:12:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:12:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:12:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:12:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:12:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:12:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:30 INFO - 'talos_options': ['--disable-e10s'], 16:12:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:12:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:12:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:30 INFO - 'talos_options': ['--disable-e10s'], 16:12:30 INFO - 'tests': ['damp', 'tps']}, 16:12:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:30 INFO - 'tests': ['damp', 'tps']}, 16:12:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:12:30 INFO - 'tests': ['dromaeo_dom']}, 16:12:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:12:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:12:30 INFO - 'tests': ['basic_compositor_video']}, 16:12:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:12:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:12:30 INFO - 'tests': ['a11yr', 16:12:30 INFO - 'ts_paint', 16:12:30 INFO - 'tpaint', 16:12:30 INFO - 'sessionrestore', 16:12:30 INFO - 'sessionrestore_no_auto_restore', 16:12:30 INFO - 'tabpaint']}, 16:12:30 INFO - 'other-e10s': {'tests': ['a11yr', 16:12:30 INFO - 'ts_paint', 16:12:30 INFO - 'tpaint', 16:12:30 INFO - 'sessionrestore', 16:12:30 INFO - 'sessionrestore_no_auto_restore', 16:12:30 INFO - 'tabpaint']}, 16:12:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:12:30 INFO - 'ts_paint', 16:12:30 INFO - 'tpaint', 16:12:30 INFO - 'sessionrestore', 16:12:30 INFO - 'sessionrestore_no_auto_restore', 16:12:30 INFO - 'tabpaint']}, 16:12:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:12:30 INFO - 'ts_paint', 16:12:30 INFO - 'tpaint', 16:12:30 INFO - 'sessionrestore', 16:12:30 INFO - 'sessionrestore_no_auto_restore', 16:12:30 INFO - 'tabpaint']}, 16:12:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:12:30 INFO - 'tests': ['a11yr', 16:12:30 INFO - 'ts_paint', 16:12:30 INFO - 'tpaint', 16:12:30 INFO - 'sessionrestore', 16:12:30 INFO - 'sessionrestore_no_auto_restore', 16:12:30 INFO - 'tabpaint']}, 16:12:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:12:30 INFO - 'tests': ['a11yr', 16:12:30 INFO - 'ts_paint', 16:12:30 INFO - 'tpaint', 16:12:30 INFO - 'sessionrestore', 16:12:30 INFO - 'sessionrestore_no_auto_restore', 16:12:30 INFO - 'tabpaint']}, 16:12:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:12:30 INFO - 'tests': ['tsvgx', 16:12:30 INFO - 'tsvgr_opacity', 16:12:30 INFO - 'tart', 16:12:30 INFO - 'tscrollx', 16:12:30 INFO - 'cart']}, 16:12:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:12:30 INFO - 'tsvgr_opacity', 16:12:30 INFO - 'tart', 16:12:30 INFO - 'tscrollx', 16:12:30 INFO - 'cart']}, 16:12:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:30 INFO - 'talos_options': ['--disable-e10s'], 16:12:30 INFO - 'tests': ['tp5o']}, 16:12:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:30 INFO - 'tests': ['tp5o']}, 16:12:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:30 INFO - 'talos_options': ['--disable-e10s', 16:12:30 INFO - '--xperf_path', 16:12:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:12:30 INFO - 'tests': ['tp5n']}, 16:12:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:30 INFO - 'talos_options': ['--xperf_path', 16:12:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:12:30 INFO - 'tests': ['tp5n']}}, 16:12:30 INFO - 'talos.zip': {'path': '', 16:12:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:12:30 INFO - Running post-action listener: _resource_record_post_action 16:12:30 INFO - [mozharness: 2016-08-17 23:12:30.832931Z] Finished populate-webroot step (success) 16:12:30 INFO - [mozharness: 2016-08-17 23:12:30.833018Z] Running create-virtualenv step. 16:12:30 INFO - Running pre-action listener: _resource_record_pre_action 16:12:30 INFO - Running main action method: create_virtualenv 16:12:30 INFO - Creating virtualenv /builds/slave/test/build/venv 16:12:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:12:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:12:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:12:30 INFO - Using real prefix '/usr' 16:12:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:12:31 INFO - Installing distribute.............................................................................................................................................................................................done. 16:12:33 INFO - Installing pip.................done. 16:12:33 INFO - Return code: 0 16:12:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:12:33 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')]} 16:12:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:33 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')]} 16:12:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:33 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 0x7f78ca723f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b46370>, '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 0x1b498f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b49d80>, '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': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:12:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:12:33 INFO - Using env: {'DISPLAY': ':0', 16:12:33 INFO - 'HOME': '/home/cltbld', 16:12:33 INFO - 'LANG': 'en_US.UTF-8', 16:12:33 INFO - 'LANGUAGE': 'en_US:en', 16:12:33 INFO - 'LOGNAME': 'cltbld', 16:12:33 INFO - 'MAIL': '/var/mail/cltbld', 16:12:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:33 INFO - 'MOZ_NO_REMOTE': '1', 16:12:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:33 INFO - 'NO_EM_RESTART': '1', 16:12:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:33 INFO - 'PWD': '/builds/slave/test', 16:12:33 INFO - 'SHELL': '/bin/bash', 16:12:33 INFO - 'SHLVL': '1', 16:12:33 INFO - 'TERM': 'linux', 16:12:33 INFO - 'TMOUT': '86400', 16:12:33 INFO - 'USER': 'cltbld', 16:12:33 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 16:12:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:33 INFO - '_': '/tools/buildbot/bin/python'} 16:12:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:12:33 INFO - Cleaning up... 16:12:33 INFO - Return code: 0 16:12:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:12:33 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')]} 16:12:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:33 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')]} 16:12:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:33 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 0x7f78ca723f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b46370>, '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 0x1b498f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b49d80>, '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': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:12:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:12:33 INFO - Using env: {'DISPLAY': ':0', 16:12:33 INFO - 'HOME': '/home/cltbld', 16:12:33 INFO - 'LANG': 'en_US.UTF-8', 16:12:33 INFO - 'LANGUAGE': 'en_US:en', 16:12:33 INFO - 'LOGNAME': 'cltbld', 16:12:33 INFO - 'MAIL': '/var/mail/cltbld', 16:12:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:33 INFO - 'MOZ_NO_REMOTE': '1', 16:12:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:33 INFO - 'NO_EM_RESTART': '1', 16:12:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:33 INFO - 'PWD': '/builds/slave/test', 16:12:33 INFO - 'SHELL': '/bin/bash', 16:12:33 INFO - 'SHLVL': '1', 16:12:33 INFO - 'TERM': 'linux', 16:12:33 INFO - 'TMOUT': '86400', 16:12:33 INFO - 'USER': 'cltbld', 16:12:33 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 16:12:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:33 INFO - '_': '/tools/buildbot/bin/python'} 16:12:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:33 INFO - Downloading/unpacking psutil>=3.1.1 16:12:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:12:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:12:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:12:35 INFO - Installing collected packages: psutil 16:12:35 INFO - Running setup.py install for psutil 16:12:35 INFO - building 'psutil._psutil_linux' extension 16:12:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:12:36 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:12:36 INFO - building 'psutil._psutil_posix' extension 16:12:36 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:12:36 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:12:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:12:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:12:36 INFO - Successfully installed psutil 16:12:36 INFO - Cleaning up... 16:12:36 INFO - Return code: 0 16:12:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:12: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')]} 16:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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')]} 16:12:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:36 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 0x7f78ca723f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b46370>, '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 0x1b498f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b49d80>, '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': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:12:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:12:36 INFO - Using env: {'DISPLAY': ':0', 16:12:36 INFO - 'HOME': '/home/cltbld', 16:12:36 INFO - 'LANG': 'en_US.UTF-8', 16:12:36 INFO - 'LANGUAGE': 'en_US:en', 16:12:36 INFO - 'LOGNAME': 'cltbld', 16:12:36 INFO - 'MAIL': '/var/mail/cltbld', 16:12:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:36 INFO - 'MOZ_NO_REMOTE': '1', 16:12:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:36 INFO - 'NO_EM_RESTART': '1', 16:12:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:36 INFO - 'PWD': '/builds/slave/test', 16:12:36 INFO - 'SHELL': '/bin/bash', 16:12:36 INFO - 'SHLVL': '1', 16:12:36 INFO - 'TERM': 'linux', 16:12:36 INFO - 'TMOUT': '86400', 16:12:36 INFO - 'USER': 'cltbld', 16:12:36 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 16:12:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:36 INFO - '_': '/tools/buildbot/bin/python'} 16:12:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:12:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:12:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:12:38 INFO - Installing collected packages: mozsystemmonitor 16:12:38 INFO - Running setup.py install for mozsystemmonitor 16:12:38 INFO - Successfully installed mozsystemmonitor 16:12:38 INFO - Cleaning up... 16:12:38 INFO - Return code: 0 16:12:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:12:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:38 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 0x7f78ca723f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b46370>, '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 0x1b498f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b49d80>, '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': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:12:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:12:38 INFO - Using env: {'DISPLAY': ':0', 16:12:38 INFO - 'HOME': '/home/cltbld', 16:12:38 INFO - 'LANG': 'en_US.UTF-8', 16:12:38 INFO - 'LANGUAGE': 'en_US:en', 16:12:38 INFO - 'LOGNAME': 'cltbld', 16:12:38 INFO - 'MAIL': '/var/mail/cltbld', 16:12:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:38 INFO - 'MOZ_NO_REMOTE': '1', 16:12:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:38 INFO - 'NO_EM_RESTART': '1', 16:12:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:38 INFO - 'PWD': '/builds/slave/test', 16:12:38 INFO - 'SHELL': '/bin/bash', 16:12:38 INFO - 'SHLVL': '1', 16:12:38 INFO - 'TERM': 'linux', 16:12:38 INFO - 'TMOUT': '86400', 16:12:38 INFO - 'USER': 'cltbld', 16:12:38 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 16:12:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:38 INFO - '_': '/tools/buildbot/bin/python'} 16:12:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:38 INFO - Downloading/unpacking jsonschema==2.5.1 16:12:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:12:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:12:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:40 INFO - Downloading functools32-3.2.3-2.tar.gz 16:12:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:12:41 INFO - warning: no files found matching '*.txt' 16:12:41 INFO - no previously-included directories found matching 'build' 16:12:41 INFO - no previously-included directories found matching 'dist' 16:12:41 INFO - no previously-included directories found matching '.git*' 16:12:41 INFO - Installing collected packages: jsonschema, functools32 16:12:41 INFO - Running setup.py install for functools32 16:12:41 INFO - warning: no files found matching '*.txt' 16:12:41 INFO - no previously-included directories found matching 'build' 16:12:41 INFO - no previously-included directories found matching 'dist' 16:12:41 INFO - no previously-included directories found matching '.git*' 16:12:41 INFO - Successfully installed jsonschema functools32 16:12:41 INFO - Cleaning up... 16:12:41 INFO - Return code: 0 16:12:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:12: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')]} 16:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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')]} 16:12:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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', '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 0x7f78ca723f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b46370>, '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 0x1b498f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b49d80>, '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': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:12: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 functools32==3.2.3-2 16:12:41 INFO - Using env: {'DISPLAY': ':0', 16:12:41 INFO - 'HOME': '/home/cltbld', 16:12:41 INFO - 'LANG': 'en_US.UTF-8', 16:12:41 INFO - 'LANGUAGE': 'en_US:en', 16:12:41 INFO - 'LOGNAME': 'cltbld', 16:12:41 INFO - 'MAIL': '/var/mail/cltbld', 16:12:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:41 INFO - 'MOZ_NO_REMOTE': '1', 16:12:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:41 INFO - 'NO_EM_RESTART': '1', 16:12:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:41 INFO - 'PWD': '/builds/slave/test', 16:12:41 INFO - 'SHELL': '/bin/bash', 16:12:41 INFO - 'SHLVL': '1', 16:12:41 INFO - 'TERM': 'linux', 16:12:41 INFO - 'TMOUT': '86400', 16:12:41 INFO - 'USER': 'cltbld', 16:12:41 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 16:12:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:41 INFO - '_': '/tools/buildbot/bin/python'} 16:12:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:12:41 INFO - Cleaning up... 16:12:41 INFO - Return code: 0 16:12:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:12: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')]} 16:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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')]} 16:12:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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', '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 0x7f78ca723f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b46370>, '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 0x1b498f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b49d80>, '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': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:12: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 blobuploader==1.2.4 16:12:41 INFO - Using env: {'DISPLAY': ':0', 16:12:41 INFO - 'HOME': '/home/cltbld', 16:12:41 INFO - 'LANG': 'en_US.UTF-8', 16:12:41 INFO - 'LANGUAGE': 'en_US:en', 16:12:41 INFO - 'LOGNAME': 'cltbld', 16:12:41 INFO - 'MAIL': '/var/mail/cltbld', 16:12:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:41 INFO - 'MOZ_NO_REMOTE': '1', 16:12:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:41 INFO - 'NO_EM_RESTART': '1', 16:12:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:41 INFO - 'PWD': '/builds/slave/test', 16:12:41 INFO - 'SHELL': '/bin/bash', 16:12:41 INFO - 'SHLVL': '1', 16:12:41 INFO - 'TERM': 'linux', 16:12:41 INFO - 'TMOUT': '86400', 16:12:41 INFO - 'USER': 'cltbld', 16:12:41 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 16:12:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:41 INFO - '_': '/tools/buildbot/bin/python'} 16:12:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:41 INFO - Downloading/unpacking blobuploader==1.2.4 16:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:43 INFO - Downloading blobuploader-1.2.4.tar.gz 16:12:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:12:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:12:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:44 INFO - Downloading docopt-0.6.1.tar.gz 16:12:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:12:44 INFO - Installing collected packages: blobuploader, requests, docopt 16:12:44 INFO - Running setup.py install for blobuploader 16:12:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:12:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:12:44 INFO - Running setup.py install for requests 16:12:44 INFO - Running setup.py install for docopt 16:12:44 INFO - Successfully installed blobuploader requests docopt 16:12:44 INFO - Cleaning up... 16:12:44 INFO - Return code: 0 16:12:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:44 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 0x7f78ca723f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b46370>, '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 0x1b498f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b49d80>, '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': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:12:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:12:44 INFO - Using env: {'DISPLAY': ':0', 16:12:44 INFO - 'HOME': '/home/cltbld', 16:12:44 INFO - 'LANG': 'en_US.UTF-8', 16:12:44 INFO - 'LANGUAGE': 'en_US:en', 16:12:44 INFO - 'LOGNAME': 'cltbld', 16:12:44 INFO - 'MAIL': '/var/mail/cltbld', 16:12:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:44 INFO - 'MOZ_NO_REMOTE': '1', 16:12:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:44 INFO - 'NO_EM_RESTART': '1', 16:12:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:44 INFO - 'PWD': '/builds/slave/test', 16:12:44 INFO - 'SHELL': '/bin/bash', 16:12:44 INFO - 'SHLVL': '1', 16:12:44 INFO - 'TERM': 'linux', 16:12:44 INFO - 'TMOUT': '86400', 16:12:44 INFO - 'USER': 'cltbld', 16:12:44 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 16:12:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:44 INFO - '_': '/tools/buildbot/bin/python'} 16:12:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:12:45 INFO - Running setup.py (path:/tmp/pip-Jf0bQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:12:45 INFO - Running setup.py (path:/tmp/pip-ZOAnhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:12:45 INFO - Running setup.py (path:/tmp/pip-lwPOhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:12:45 INFO - Running setup.py (path:/tmp/pip-FeAQfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:12:45 INFO - Running setup.py (path:/tmp/pip-OtYtZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:12:45 INFO - Running setup.py (path:/tmp/pip-2U40JZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:12:45 INFO - Running setup.py (path:/tmp/pip-PX4kjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:12:45 INFO - Running setup.py (path:/tmp/pip-iPc6pz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:12:45 INFO - Running setup.py (path:/tmp/pip-jH4G44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:12:45 INFO - Running setup.py (path:/tmp/pip-XhKPQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:12:45 INFO - Running setup.py (path:/tmp/pip-i3Gxz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:12:45 INFO - Running setup.py (path:/tmp/pip-uERlcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:12:45 INFO - Running setup.py (path:/tmp/pip-WHSXlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:12:46 INFO - Running setup.py (path:/tmp/pip-QOZcqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:46 INFO - Running setup.py (path:/tmp/pip-sSp6Gt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:12:46 INFO - Running setup.py (path:/tmp/pip-F5mzh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:12:46 INFO - Running setup.py (path:/tmp/pip-NHle0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:12:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:12:46 INFO - Running setup.py install for manifestparser 16:12:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:12:46 INFO - Running setup.py install for mozcrash 16:12:46 INFO - Running setup.py install for mozdebug 16:12:46 INFO - Running setup.py install for mozdevice 16:12:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:12:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:12:46 INFO - Running setup.py install for mozfile 16:12:46 INFO - Running setup.py install for mozhttpd 16:12:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:12:46 INFO - Running setup.py install for mozinfo 16:12:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:12:47 INFO - Running setup.py install for mozInstall 16:12:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:12:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:12:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:12:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:12:47 INFO - Running setup.py install for mozleak 16:12:47 INFO - Running setup.py install for mozlog 16:12:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:12:47 INFO - Running setup.py install for moznetwork 16:12:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:12:47 INFO - Running setup.py install for mozprocess 16:12:47 INFO - Running setup.py install for mozprofile 16:12:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:12:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:12:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:12:47 INFO - Running setup.py install for mozrunner 16:12:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:12:47 INFO - Running setup.py install for mozscreenshot 16:12:47 INFO - Running setup.py install for moztest 16:12:48 INFO - Running setup.py install for mozversion 16:12:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:12:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:12:48 INFO - Cleaning up... 16:12:48 INFO - Return code: 0 16:12:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:48 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 0x7f78ca723f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b46370>, '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 0x1b498f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b49d80>, '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': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:12:48 INFO - Using env: {'DISPLAY': ':0', 16:12:48 INFO - 'HOME': '/home/cltbld', 16:12:48 INFO - 'LANG': 'en_US.UTF-8', 16:12:48 INFO - 'LANGUAGE': 'en_US:en', 16:12:48 INFO - 'LOGNAME': 'cltbld', 16:12:48 INFO - 'MAIL': '/var/mail/cltbld', 16:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:48 INFO - 'MOZ_NO_REMOTE': '1', 16:12:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:48 INFO - 'NO_EM_RESTART': '1', 16:12:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:48 INFO - 'PWD': '/builds/slave/test', 16:12:48 INFO - 'SHELL': '/bin/bash', 16:12:48 INFO - 'SHLVL': '1', 16:12:48 INFO - 'TERM': 'linux', 16:12:48 INFO - 'TMOUT': '86400', 16:12:48 INFO - 'USER': 'cltbld', 16:12:48 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 16:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:48 INFO - '_': '/tools/buildbot/bin/python'} 16:12:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:12:48 INFO - Running setup.py (path:/tmp/pip-WnM0YD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:12:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:12:48 INFO - Running setup.py (path:/tmp/pip-UXhQj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:12:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:12:48 INFO - Running setup.py (path:/tmp/pip-d70Y5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:12:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:12:48 INFO - Running setup.py (path:/tmp/pip-oWdC4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:12:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:12:48 INFO - Running setup.py (path:/tmp/pip-AS5akz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:12:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:12:48 INFO - Running setup.py (path:/tmp/pip-Fon0Eg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:12:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:12:48 INFO - Running setup.py (path:/tmp/pip-041Q2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:12:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:12:48 INFO - Running setup.py (path:/tmp/pip-me6I8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:12:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:12:48 INFO - Running setup.py (path:/tmp/pip-ih76Yq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:12:49 INFO - Running setup.py (path:/tmp/pip-IfFW6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:12:49 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)) 16:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:12:49 INFO - Running setup.py (path:/tmp/pip-qvCZaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:12:49 INFO - Running setup.py (path:/tmp/pip-FwUy5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:12:49 INFO - Running setup.py (path:/tmp/pip-ooTGoQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:12:49 INFO - Running setup.py (path:/tmp/pip-nSziwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:49 INFO - Running setup.py (path:/tmp/pip-d8Pavi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:12:49 INFO - Running setup.py (path:/tmp/pip-Z_Z3xN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:12:49 INFO - Running setup.py (path:/tmp/pip-MZ7wmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:12:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:51 INFO - Downloading blessings-1.6.tar.gz 16:12:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:12:51 INFO - Installing collected packages: blessings 16:12:51 INFO - Running setup.py install for blessings 16:12:51 INFO - Successfully installed blessings 16:12:51 INFO - Cleaning up... 16:12:51 INFO - Return code: 0 16:12:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:12:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:12:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:12:51 INFO - Reading from file tmpfile_stdout 16:12:51 INFO - Current package versions: 16:12:51 INFO - argparse == 1.2.1 16:12:51 INFO - blessings == 1.6 16:12:51 INFO - blobuploader == 1.2.4 16:12:51 INFO - docopt == 0.6.1 16:12:51 INFO - functools32 == 3.2.3-2 16:12:51 INFO - jsonschema == 2.5.1 16:12:51 INFO - manifestparser == 1.1 16:12:51 INFO - mozInstall == 1.12 16:12:51 INFO - mozcrash == 0.17 16:12:51 INFO - mozdebug == 0.1 16:12:51 INFO - mozdevice == 0.48 16:12:51 INFO - mozfile == 1.2 16:12:51 INFO - mozhttpd == 0.7 16:12:51 INFO - mozinfo == 0.9 16:12:51 INFO - mozleak == 0.1 16:12:51 INFO - mozlog == 3.3 16:12:51 INFO - moznetwork == 0.27 16:12:51 INFO - mozprocess == 0.23 16:12:51 INFO - mozprofile == 0.28 16:12:51 INFO - mozrunner == 6.12 16:12:51 INFO - mozscreenshot == 0.1 16:12:51 INFO - mozsystemmonitor == 0.3 16:12:51 INFO - moztest == 0.7 16:12:51 INFO - mozversion == 1.4 16:12:51 INFO - psutil == 3.1.1 16:12:51 INFO - requests == 1.2.3 16:12:51 INFO - wsgiref == 0.1.2 16:12:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12:51 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')]} 16:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:51 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')]} 16:12:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:51 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 0x7f78ca723f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b46370>, '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 0x1b498f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b49d80>, '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': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:12:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:12:51 INFO - Using env: {'DISPLAY': ':0', 16:12:51 INFO - 'HOME': '/home/cltbld', 16:12:51 INFO - 'LANG': 'en_US.UTF-8', 16:12:51 INFO - 'LANGUAGE': 'en_US:en', 16:12:51 INFO - 'LOGNAME': 'cltbld', 16:12:51 INFO - 'MAIL': '/var/mail/cltbld', 16:12:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:51 INFO - 'MOZ_NO_REMOTE': '1', 16:12:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:51 INFO - 'NO_EM_RESTART': '1', 16:12:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:51 INFO - 'PWD': '/builds/slave/test', 16:12:51 INFO - 'SHELL': '/bin/bash', 16:12:51 INFO - 'SHLVL': '1', 16:12:51 INFO - 'TERM': 'linux', 16:12:51 INFO - 'TMOUT': '86400', 16:12:51 INFO - 'USER': 'cltbld', 16:12:51 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 16:12:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:51 INFO - '_': '/tools/buildbot/bin/python'} 16:12:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:12:52 INFO - Cleaning up... 16:12:52 INFO - Return code: 0 16:12:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:12: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')]} 16:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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')]} 16:12:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f78ca723f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b46370>, '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 0x1b498f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b49d80>, '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': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12: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', 'jsonschema'] in /builds/slave/test/build 16:12: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 jsonschema 16:12:52 INFO - Using env: {'DISPLAY': ':0', 16:12:52 INFO - 'HOME': '/home/cltbld', 16:12:52 INFO - 'LANG': 'en_US.UTF-8', 16:12:52 INFO - 'LANGUAGE': 'en_US:en', 16:12:52 INFO - 'LOGNAME': 'cltbld', 16:12:52 INFO - 'MAIL': '/var/mail/cltbld', 16:12:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:52 INFO - 'MOZ_NO_REMOTE': '1', 16:12:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:52 INFO - 'NO_EM_RESTART': '1', 16:12:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:52 INFO - 'PWD': '/builds/slave/test', 16:12:52 INFO - 'SHELL': '/bin/bash', 16:12:52 INFO - 'SHLVL': '1', 16:12:52 INFO - 'TERM': 'linux', 16:12:52 INFO - 'TMOUT': '86400', 16:12:52 INFO - 'USER': 'cltbld', 16:12:52 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 16:12:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:52 INFO - '_': '/tools/buildbot/bin/python'} 16:12:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:12:52 INFO - Cleaning up... 16:12:52 INFO - Return code: 0 16:12:52 INFO - Running post-action listener: _resource_record_post_action 16:12:52 INFO - Running post-action listener: _start_resource_monitoring 16:12:52 INFO - Starting resource monitoring. 16:12:52 INFO - [mozharness: 2016-08-17 23:12:52.428297Z] Finished create-virtualenv step (success) 16:12:52 INFO - [mozharness: 2016-08-17 23:12:52.428798Z] Running install step. 16:12:52 INFO - Running pre-action listener: _resource_record_pre_action 16:12:52 INFO - Running main action method: install 16:12:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:12:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:12:52 INFO - Reading from file tmpfile_stdout 16:12:52 INFO - Detecting whether we're running mozinstall >=1.0... 16:12:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:12:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:12:52 INFO - Reading from file tmpfile_stdout 16:12:52 INFO - Output received: 16:12:52 INFO - Usage: mozinstall [options] installer 16:12:52 INFO - Options: 16:12:52 INFO - -h, --help show this help message and exit 16:12:52 INFO - -d DEST, --destination=DEST 16:12:52 INFO - Directory to install application into. [default: 16:12:52 INFO - "/builds/slave/test"] 16:12:52 INFO - --app=APP Application being installed. [default: firefox] 16:12:52 INFO - mkdir: /builds/slave/test/build/application 16:12:52 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'] 16:12:52 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 16:13:10 INFO - Reading from file tmpfile_stdout 16:13:10 INFO - Output received: 16:13:10 INFO - /builds/slave/test/build/application/firefox/firefox 16:13:10 INFO - Running post-action listener: _resource_record_post_action 16:13:10 INFO - [mozharness: 2016-08-17 23:13:10.301692Z] Finished install step (success) 16:13:10 INFO - [mozharness: 2016-08-17 23:13:10.301938Z] Running run-tests step. 16:13:10 INFO - Running pre-action listener: _resource_record_pre_action 16:13:10 INFO - Running main action method: run_tests 16:13:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:13:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:13:10 INFO - Python 2.7.3 16:13:10 INFO - Return code: 0 16:13:10 INFO - grabbing minidump binary from tooltool 16:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:10 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 0x1b46370>, '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 0x1b498f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b49d80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:13:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:13:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:13:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:13:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:13:10 INFO - Return code: 0 16:13:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:13:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:13:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:13:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:13:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:13:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:13:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:13:10 INFO - Using env: {'DISPLAY': ':0', 16:13:10 INFO - 'HOME': '/home/cltbld', 16:13:10 INFO - 'LANG': 'en_US.UTF-8', 16:13:10 INFO - 'LANGUAGE': 'en_US:en', 16:13:10 INFO - 'LOGNAME': 'cltbld', 16:13:10 INFO - 'MAIL': '/var/mail/cltbld', 16:13:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:13:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:10 INFO - 'MOZ_NO_REMOTE': '1', 16:13:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:13:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:10 INFO - 'NO_EM_RESTART': '1', 16:13:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:10 INFO - 'PWD': '/builds/slave/test', 16:13:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:13:10 INFO - 'SHELL': '/bin/bash', 16:13:10 INFO - 'SHLVL': '1', 16:13:10 INFO - 'TERM': 'linux', 16:13:10 INFO - 'TMOUT': '86400', 16:13:10 INFO - 'USER': 'cltbld', 16:13:10 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138', 16:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:10 INFO - '_': '/tools/buildbot/bin/python'} 16:13:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:13:10 INFO - mozversion application_buildid: 20160817143004 16:13:10 INFO - mozversion application_changeset: 876756efc390115f1fde3b23e8369edd95eda403 16:13:10 INFO - mozversion application_display_name: Nightly 16:13:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:13:10 INFO - mozversion application_name: Firefox 16:13:10 INFO - mozversion application_remotingname: firefox 16:13:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:13:10 INFO - mozversion application_vendor: Mozilla 16:13:10 INFO - mozversion application_version: 51.0a1 16:13:10 INFO - mozversion platform_buildid: 20160817143004 16:13:10 INFO - mozversion platform_changeset: 876756efc390115f1fde3b23e8369edd95eda403 16:13:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:13:10 INFO - mozversion platform_version: 51.0a1 16:13:10 INFO - using testdate: 1471475590 16:13:10 INFO - actual date: 1471475590 16:13:10 INFO - starting webserver on 'localhost:44255' 16:13:10 INFO - SUITE-START | Running 2 tests 16:13:10 INFO - TEST-START | dromaeo_css 16:13:10 INFO - operating with platform_type : linux_ 16:13:10 INFO - Initialising browser for dromaeo_css test... 16:13:10 INFO - TEST-INFO | started process 12633 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprjPqNC/profile http://localhost:44255/getInfo.html) 16:13:14 INFO - PROCESS | 12633 | __metrics Screen width/height:1600/1200 16:13:14 INFO - PROCESS | 12633 | colorDepth:24 16:13:14 INFO - PROCESS | 12633 | Browser inner width/height: 1024/697 16:13:14 INFO - PROCESS | 12633 | __metrics 16:13:14 INFO - PROCESS | 12633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:13:14 INFO - PROCESS | 12633 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:13:15 INFO - PROCESS | 12633 | console.error: PushService: 16:13:15 INFO - PROCESS | 12633 | stateChangeProcessEnqueue: Error transitioning state 16:13:15 INFO - PROCESS | 12633 | UnknownError 16:13:16 INFO - TEST-INFO | 12633: exit 0 16:13:16 INFO - Browser initialized. 16:13:16 INFO - Running cycle 1/1 for dromaeo_css test... 16:13:16 INFO - TEST-INFO | started process 12712 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprjPqNC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:13:27 INFO - PROCESS | 12712 | RSS: Main: 156188672 16:13:27 INFO - PROCESS | 12712 | 16:14:19 INFO - PROCESS | 12712 | 1471475659359 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:16:28 INFO - PROCESS | 12712 | Cycle 1(1): loaded http://localhost:44255/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44255/tests/dromaeo/cssquery-ext.html) 16:16:29 INFO - PROCESS | 12712 | RSS: Main: 249614336 16:16:29 INFO - PROCESS | 12712 | 16:19:32 INFO - PROCESS | 12712 | Cycle 1(1): loaded http://localhost:44255/tests/dromaeo/cssquery-ext.html (next: http://localhost:44255/tests/dromaeo/cssquery-jquery.html) 16:19:32 INFO - PROCESS | 12712 | RSS: Main: 243380224 16:19:32 INFO - PROCESS | 12712 | 16:22:34 INFO - PROCESS | 12712 | Cycle 1(1): loaded http://localhost:44255/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44255/tests/dromaeo/cssquery-mootools.html) 16:22:35 INFO - PROCESS | 12712 | RSS: Main: 213504000 16:22:35 INFO - PROCESS | 12712 | 16:25:37 INFO - PROCESS | 12712 | Cycle 1(1): loaded http://localhost:44255/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44255/tests/dromaeo/cssquery-prototype.html) 16:25:38 INFO - PROCESS | 12712 | RSS: Main: 184512512 16:25:38 INFO - PROCESS | 12712 | 16:28:40 INFO - PROCESS | 12712 | Cycle 1(1): loaded http://localhost:44255/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44255/tests/dromaeo/cssquery-yui.html) 16:28:41 INFO - PROCESS | 12712 | RSS: Main: 189239296 16:28:41 INFO - PROCESS | 12712 | 16:31:44 INFO - PROCESS | 12712 | Cycle 1(1): loaded http://localhost:44255/tests/dromaeo/cssquery-yui.html (next: http://localhost:44255/tests/dromaeo/cssquery-dojo.html) 16:31:44 INFO - PROCESS | 12712 | RSS: Main: 275963904 16:31:44 INFO - PROCESS | 12712 | 16:31:44 INFO - PROCESS | 12712 | __start_tp_report 16:31:44 INFO - PROCESS | 12712 | _x_x_mozilla_page_load 16:31:44 INFO - PROCESS | 12712 | _x_x_mozilla_page_load_details 16:31:44 INFO - PROCESS | 12712 | |i|pagename|runs| 16:31:44 INFO - PROCESS | 12712 | |0;dojo.html;2976.7320941115295;3004.924876878079;3020.969790302097;3030.333326668132;3032.590600268963;5060.392752869651;5100.362454693163;5199.194124910636;5323.148296272592;5382.38102618199;5165.509276618715;5218.39988401334;5276.050310944029;5278.47215278472;5349.625526213166;4636.837710680127;4663.207254766681;4676.7427791471455;4712.340272361872;4891.48639392864;4786.946871688223;4826.227803551433;4831.516848315176;4835.709857408549;4877.244027175776;4597.954020459786;4652.395188625457;4671.556202160823;4675.485696573374;4715.905681886377;4644.396228490275;4670.252759558454;4672.95327046732;4673.182193116191;4682.110399024208;4651.720896746172;4707.8587642370785;4732.692374995649;4743.620510359163;4765.21373973294;5076.466970968032;5160.870978225536;5230.476952304777;5240.423553409122;5293.841184764463;5496.065668836308;5563.248961390201;5607.663540187602;5614.410486898899;5646.774129034834;5588.413216612278;5609.635373700738;5627.662340259598;5631.718414079321;5647.37878833328;5034.773435195389;5196.558292545101;5245.082110630625;5248.343957005374;5250.422453530108;5748.798792042342;5777.884442311214;5799.507041901401;5802.738876750556;5822.155787410802;5523.337199536081;5555.416681248492;5577.749001294951;5598.412166722517;5603.3836278009385;5435.1303791393975;5516.834494965157;5549.306336707911;5624.887502249932;5675.091985282335;5601;5601.131824567198;5601.719914004283;5636.126400407861;5644.379118296984;5556.222128901957;5600.411956744565;5609.1305847593685;5611.466910643482;5611.859703507445;4162.812673429642;4210.852620158341;4247.638950689165;4346.130773845181;4383.802728877145;3807.238552289498;3842.2507611016144;3863.4977452931503;3883.922321553553;3899.7660140391667;5202.2196670499725;5206.271122042917;5207.635465517377;5211.452797456288;5211.765470553834;5387.730613469389;5496.642718223303;5589.077802162597;5605.523530499871;5607.8317650469735;5146.022255771391;5208.453112423141;5223.608229382811;5248.029114613809;5251.212318152231;4799.9760001199775;4803.18345881201;4804.807807687654;4809.975950120227;4835.323054772335;5590.692511911884;5721.1990321354215;5744.913826292609;5744.942550574524;5753.827385178452;5512.2558454608115;5547.972260138674;5575.330960284629;5670.489655930986;5674.602777805514;5816.069428891357;5827.096799996008;5829.125631155361;5832.416758324133;5832.912506312324;5513.476219759116;5517.779288828402;5524.696141712084;5525.336959564717;5532.806351777668;5352.866178345416;5493.862653433536;5515.972420137874;5547.916781248366;5650.434956504317;5422.891542169055;5459.426760190282;5472.50747432733;5472.699001554953;5473.616846820843;5399.352077750695;5435.2934118564335;5455.18172274162;5455.536279416294;5509.090999984957;5302.734863256745;5324.6006549508165;5329.333833270841;5342.038433081927;5411.2965314508865;5447.4824891635235;5460.918086228789;5468.671879687232;5476.644018138808;5494.945050549603;5185.688858668492;5323.494268044529;5323.733813309396;5325.387580428301;5328.49379308965 16:31:44 INFO - PROCESS | 12712 | |1;ext.html;566.0330352026708;590.9320428150763;608.0271565495207;611.162707091285;629.279475001124;32258.193545161383;43645.12709745803;44361;44425.999999999956;44490.11019779603;46321.536784632146;46852.06295874089;46960.765196174056;46992.76503617486;47006;8179.468334558264;9006.324525660586;9145.72562823113;9167.312451566126;10664.466776661136;10924.126069914388;11075.944620276927;11076.833847492253;11082.778344433107;11084.279521831055;11174.776504469906;11232.775344493146;11243.100551955864;11244.775104497905;11245.943770281137;8127.918720812775;10743.731406714816;10985.61550345734;11127.888721112766;11165.38590377529;10923.890761092367;10942.726431839188;10988.780224395547;10997;10997.670069897917;43961.12077758427;44296;44323.556764432586;44347;44362;3620.5112309838096;3722.40441529354;3756.530433695788;3878.7090968177217;4396.296592545209;32707.18232044194;33407.83296083504;33668.6633133668;33676;33702.83148584266;4425.291953287491;4467.888302792424;4549.931751023737;4564.726116433025;4648.883777905546;255353;256039;258317.9999999981;258603;258967;60121.398786012454;60143.39856601466;60381.39618603846;60437;60490.69754651198;62711;62744.686276568325;62852;62970.68514657489;63019.6849015752;16966.321347146222;17213.65572688564;17262.395816146374;17263.56841078983;17278.395256166226;11870.287782733063;12900.483980640669;13027.413766380536;13157.67105822343;13321.600351989457;16858.831411685973;16941.66116677684;16999.32002719902;17006.659866802594;17072.658546828992;5823.79616713413;5892.234009578728;5909.202257695156;6012.158297838306;6037.63774173551;981.7153025622632;1025.6666583360438;1085.1210519479248;1098.9670309890716;1185.466540056978;33554.8322258392;34121.65878341234;34162.48756268608;34208.48687269693;34511.30977380438;1082.5345101606226;1128.5598616539555;1141.092831199182;1193.5046955513537;1201.3092471828734;17428.215730292166;18014.2794288227;18053.638927221382;18078.45764627063;18129.18418671163;554234;557290;559443;560621;560648;59862.70068649716;59962.70018649879;59964;59985;60016.399836001954;1229.0720506018079;1339.4240476595157;1383.9031267811358;1395.6022533578084;1400.712853864935;33361.499577506846;34913.12717182091;34944.82527587346;35513.64486355103;35616.82191589026;51519.74240128775;51973.48026519838;52000;52044;52064.479355205964;312508;313118;313162;313585.8641413648;314369;28290.717092828807;29384.412311753218;29403.29547272426;30403.54394684037;30429.847850760605;49053.2642010363;50146.247806282205;50773.23840142327;51344.48655513397;55144.44855551393;40739.592604074765;42530.57469425266;46803.29795053145;47153.764231178626;47334.76332618315;43886.8168481782;46379;46534;47328.763356183;47385.52614473949;44817.77591112023;45765.54234457746;46179.53820461752;46944.427780111495;47278;38316.2336753269;41015.1796964065;41129.38305925473;41252.793736031126;41772.58227417809;30480.84759576188;30549.389012220076;30836.383272334875;31171.688283117786;31303.530447042856 16:31:44 INFO - PROCESS | 12712 | |2;jquery.html;375.06234413965086;386.7022392757577;395.685430083084;401.4339780908918;420.5605142625956;14118.223497707635;14970.251487425627;19829.603407931834;19831.90084049578;19871;18034.5491362716;18407;18636.254549818026;18680.813191868077;18708.345207917693;5531.0873705838485;5753.827385178452;5762.164486149506;5770.076787713967;5772.307323121231;3996.3233824881036;6271.404216599439;6524.608523488582;6531.804045878631;6574.408303252706;6247.625142491443;6333.366663333676;6496.837579060538;6509.99095140254;6513.57661751987;6401.711922963478;6418.42234198922;6434.356564343575;6448.548601597867;6456.838579035515;5148.176291793333;5395.81114660985;5428.945710542884;5494.642848214893;5702.030654788655;13835.930820345935;13851.930740346335;13949;14034.859651403458;14114.858851411456;2220.922267720638;2277.1233075265977;2286.2912497125944;2331.1141766128762;2523;9733.075357840993;10031.69904902854;10420;12712.618621441372;13721.45114195431;1942.524081600003;1958.4222654317011;1976.8517361197823;1982.7918068602733;1986.145957238387;57737.851777217125;63634;69734;76860.23139768642;84747;10816.78366432667;11065.50205240766;11083.72290692723;11164.888351116548;11204.887951120547;15580.532584022498;15612.687746245012;15618;15722.371105155895;15749.291281892343;7857.428514297163;8055.516668999879;8073.596320183968;8281.917180828235;8293.917060829435;7515.924840751632;7554.169041405441;7631.122420921554;7754.651040703182;7793.298603125746;4638.489766125724;4705.60002399793;4810.59109975649;4899.2896030075435;4913.213885778258;1958.8628795984143;2001.5196352875496;2014.4661664658877;2047.4471892589215;2061;859.5702148925537;903.290916630442;906.6826610686207;974.4835237324229;1023.0996722883815;18843.811561884206;19101.90449047746;19685;19862.60274794525;20211.59576808485;937.2033771294482;957.2963871554402;980.6420656460483;1006.3659894266565;1013.2805707947292;10408.427536485415;10889;10998.505067271992;10999.945000274947;11098.500567474483;492980;496533;503031;516236;516476;93525.53237233771;94459.52770236105;95169.0483095174;95556.52221738847;104483.47758261312;996.1283876607968;1005.9849102263472;1047.4867315015588;1056.6248981611418;1067.380919066955;17717.822821771617;18007.369742058963;18782.624347513247;18930.905345473184;18991.33530326432;31986;43585.910352241444;44721.552784471736;45535.54464455313;46441.53558464372;63227.6838615804;63614;64320.356796433305;65812.67093664501;67053.32946670472;8252.504849709037;8337.791555211168;8397.328213742763;8649.394542381973;8705.216530512283;11978.820317695066;12528.185667931555;13723.519676811264;13979.720405592034;14030.368633411725;11791.587294444653;11923.463444144862;11952.940235298767;12126.818097728717;12587.433565489391;11176.050035747052;11294.491747871201;11562.248453850472;11677.06583473337;11754.647360579196;11629.76740465203;12044.397780110785;12301.44643491063;12365.258084514959;12378.542007840944;8713.91286087131;8726.694565690424;8756.211940925346;8886.155815197546;9036.774080648036;8962.955185224033;10923.737969698162;11623.709407264885;11717.824232636347;11784.70538236551 16:31:44 INFO - PROCESS | 12712 | |3;mootools.html;1327.5685402244274;1477.386884442956;1510.1543135843924;1597.3610555777686;1737.8001529824073;7010.228874823772;8004.119546849849;8290.585470726455;8313.251807337347;8493.745187644365;8236.629351679174;8277.420580559363;8282.792930176733;8302.70940517082;8304.833903321929;2263.2305016294417;2286.531261091472;2290.061074959267;2292.56441276157;2293.7820907013806;2253.2902135827244;2295.426143464134;2305.4351683837485;2307.8615283082986;2308.1575225042907;2148.4414052346347;2157.8489505734606;2168.143583284602;2188.627933251351;2192.112194561197;2160.621891169039;2182.127149140341;2196.7473740519886;2204.7464541577683;2207.668849672546;2185.9234926777485;2190.5618876224817;2190.726159230096;2192.0135938827434;2196.5936301784063;8009.479383840032;8349.499030058156;8403.705870294572;8435.240828325419;8437.493750374997;4892.486288939681;5068.695878247317;5075.771590278447;5092.083424983502;5095.5159259870625;3196.7122958933573;3414.96585034149;3429.9314013719586;3479.2519608284088;3481.9477707834403;1103.5806393570392;1144.776768530137;1147.8335641332044;1163.9941800290944;1172.8944395004492;11498.540058397653;11560.132990025782;11620.593279235187;11652.184347095792;11673.708157296136;3913.6869050475893;4020.2964481216254;4169.187008533307;4187.560306167869;4309.892252693645;9139.588718507683;9142.81714365709;9145.954270228607;9147.862782058275;9154.35919485643;4965.751712414365;4974.751262436864;4978.303037574742;4979.253112033224;4983.25251212321;4014.4580481634607;4445.866624001285;4583.954160458419;4604.424446944132;4697.765111744399;3432.502287168347;3489.5289135966827;3582.5521809773777;3621.022323972513;3628.9818550907075;1034.1209971524295;1049.0977759127145;1056.3397876327294;1057.4871187474068;1065.0255016659823;920.4293338130268;952.6903756279237;967.8306296398102;974.1086905481405;1020.8264595018865;5705.115707065412;5791.8552036199435;5887.587868849139;5933.554983376178;6020.127081573235;440.1658856466977;448.1865414273084;458.431544884341;467.66561908235775;480.43788767142524;3928.901777455529;4068.5931406859077;4073.124278280184;4100.7334523255895;4125.484314460692;10114.190864730952;10457.320274182153;10484.580616775396;10487;10496.580136794442;10272.845907311395;10374.221933354878;10413.114885234689;10424.166066714644;10427.217958653131;7654.502457340255;7705.4220933430215;7706.306432420997;7710.151883292833;7728.111267204231;5596.132599447093;5650.915236271541;5740.799072032626;5798.043322851682;5867.970660146672;9601.663941762004;9614.423134611945;9661.420314781135;9684.757881052748;9697.369670971364;9556.665516706882;9699.369540979813;9761.170300524534;9866.753331166778;9882.901170988485;6300.936990630035;6878.415334696607;6912.930870691229;7020.7893763187185;7047.15434147885;8230.588470576327;8293.377996650395;8302.584870756558;8309.418340716333;8311.916880831115;7370.189279179179;7417.703291868481;7493.775186744407;7530.736424225126;7646;7230.204677485367;7231.602261875437;7289.708411663474;7294.817629559091;7381.224971377928;6917.861642767016;6931.4108300795;6962.756303529352;6967;6969.825754356181;4988.4263309719845;5001.949980500149;5087.440381557952;5101.54086132235;5128.538431541179;4974.5771609413605;5089.134847075932;5141.25451809493;5175.378954525481;5187.948120518747 16:31:44 INFO - PROCESS | 12712 | |4;prototype.html;316.51257064121256;326.3570765591786;326.40594118703956;327.7836627825635;337.99493007604883;5244.239585260135;5287.576993840493;5294.311739473867;5297.8940421191555;5351.652142610727;5262.2632831403635;5275.393329767068;5281.762320695569;5285.2600635911;5288.524032837044;3615;3923.666488348487;4035.556088830226;4046.676265898736;4070.470838790953;4095;4119.979400102995;4128.112455821998;4134.193832202722;4142.64787493062;4075.572064933184;4102.405151253067;4105;4108.465899433085;4126.112885729567;3925.234579257046;3969.3649016157565;4105.548389677148;4112.424260603518;4115.855945041924;3599.70410652165;3605;3611.898370996871;3618.981905090484;3626.818659067036;5085.74571271435;5097.623641616779;5102.443754654829;5117;5119.923201151985;4472.195004899117;4678.719276843401;4686.27362758773;4687.88280292992;4708.693934894221;5437.537809286216;5460.133429949268;5469.808556700496;5473.890522189574;5478.945210547883;3952.565217826037;3953.3476976298875;3970.1265721541213;3974.205158968189;3977;5513.255710479074;5621.662700237958;5680.727708916425;5688.203651488753;5725.398833122504;4037.313656678372;4046.8785936421955;4060.7360521566593;4061.6953728470485;4067.8576249831112;4049.595040495986;4059.5534491205945;4081.7346872453195;4089.2026054919006;4092.7953602319767;4476.955230447719;4647.488776234612;4648.884267775709;4655.231886738718;4684.578387945101;5477.014137455217;5514.779408823602;5526.281583394213;5534.529564987021;5536.7785288588;1394.3655636685285;1407.774756039029;1411.7670584353673;1412.8233970753656;1417.454280102168;841.4488510025943;891.1756625121736;901.3915606965272;915.555955361649;915.688665853613;4633.189191891405;4652.557407361162;4658.277966915133;4670.229412147026;4692.272697731857;4946.901061978812;4984.675996060317;4995.9500404996215;4998.525140111684;5002.74986250686;4712.646551508582;4718.693284936468;4740.952590474051;4754.21555443348;4919.581835544019;2946.2781618503177;2991.551267309921;2991.7157870002316;3003.6395632524236;3004.7896647234484;5869.911951320733;5877.147813567009;5878.617889837147;5881.441263080001;5883.294004719461;5512.3385193777;5696.202531645573;5732.455416735404;5740.253767010262;5743;5608.327000759853;5741.598088133874;5825.359210486843;5871.85498827733;5882.411758824083;5505.229267902417;5637.46444087811;5666.716664166857;5682.374938756816;5687.947729670024;5473.890522189614;5593.664380137205;5624.775008999758;5633.605717570098;5641.37944826077;5860.033094539353;5896.321922978912;5896.587238893237;5897.64614123154;5899.758091925041;4020;4044.8179831908224;4045.5145382553105;4059.9797001014804;4066.6543343814924;5464.23500709893;5584.692841893735;5622.662640241599;5629.943700563106;5631.831045068655;5449.3188351456065;5491.258680078298;5492.297387809792;5493.368262649847;5501.339839219319;5336.332958380202;5449.209864569536;5451.672899626035;5475.534579560782;5499.945000549943;5440.401555828935;5468.863278417913;5470.425605311544;5485.451454854483;5492.258545096361;5463.590230732631;5491.505764481216;5517.310336207974;5520.779168833202;5544.889102218014;5254.3974087902025;5389.541888939327;5396.325459317585;5400.891982160256;5405.8107966221 16:31:44 INFO - PROCESS | 12712 | |5;yui.html;724.1563578431128;740.344794856552;756.4818099601773;756.9545827250365;765.2462324610261;15524.767128493068;16179.19104044799;16465.753013704805;16620;16655.833441665578;15666.530004099895;15684.372625095011;15753.60615984601;15775.921120394381;15788.210589470538;804.4007214625098;818.4598165210962;819.3445243804962;819.5082950229844;820.2576668115365;657.3262406033806;781.4061313401827;811.5009269299368;813.3858936502961;813.975580732576;866.6360128745911;868.9608967596473;869.1916517638593;869.3870821071162;869.6825658634616;856.8543347630917;859.6174702257498;860.6772460327377;860.8923884514436;861.5347712235385;502.69586899925383;603.7072020070262;626.6960524145786;628.3716283716284;630.2562975688732;14973.101613903093;14991.550253492413;15011;15020.549383518512;15029.924850375679;13892.652683682887;14436.855631443656;14618.195999220039;14668.413263469449;15002.849971500364;13779.586612401645;13878.791818122736;14128.788068178985;14190.645233869132;14730.116193028345;3796.2976849282973;3933.6459718625174;3946.388309811955;3993.2412841560013;4153.68847336451;73975.63012184904;74329.62835185844;75404;75694;76677.23322766757;11363.261388009752;11525.538978440936;11575.363355015555;11689.064874809996;11702.414879256174;20671.689924651142;20674.17303307881;20696.379108626767;20700;20754.273600423912;8223.465474744122;8346.958265208636;8818;8826.86759698605;8841.425307355;4773.188557945157;4918.680285781485;5032;5186.351706036746;5232.3459567554055;3657.579378371468;3732.4401339798706;3784.6972242220554;3786.091338078896;3798.24035192957;608.7520582805231;621.5307442880686;624.447364082781;631.0156156395946;632.6457183976987;730.1129128109372;733.8495608400265;749.4042236422058;756.8070142113726;770.5261264322402;9893.208543316518;10857.294275872044;10879.401632910107;10894.891051089387;11070.667879963614;579.6493121661418;654.1234745441132;734.9343451994629;788.1960400391683;794.7933537280348;4903.4606193318705;5024.874378140576;5162.8967420652125;5202.2196670499725;5235.136202526541;68613;69665.65167174234;94884;119161;119338.40330798464;61116.38883611107;68798.65600672064;89330;90027;99762;53786.462135378926;54744.72627636916;55709.721451392485;82992;83059;9132.634694612407;9563.282753793354;9607.16547724141;9627.711168664951;9684.370515916444;53093.73453132709;54157.72921135369;54701.179482307;54974.45025549693;55612;42297.577024230595;50586.49413505818;66508.66745666241;76516.70449886512;77130.22869771456;12046.939765301116;13269.867301326864;13300.135491193041;13537.187768733907;13713.17720936747;15330.616734581725;15431.382744690087;15477.767833482281;16398.67202655964;16422.589435263737;13839.169649821042;14136.363863625957;15001.699966000837;15081.170535620207;15440.536783896501;14830.62923426923;15439.922800386375;16253.918730406273;16256.756148657543;16262.837371626132;14508.709825803637;15283.46507872219;15720.292586833848;15734.606634834221;15750.212489375257;11329.943350283196;11444.313341199555;11610.94194529022;11728.530858765555;11785.587504437304;11971.281723096643;12009.63971080869;12164.330961797186;12581.748365032465;12984.545540906023 16:31:44 INFO - PROCESS | 12712 | __end_tp_report 16:31:44 INFO - PROCESS | 12712 | __start_cc_report 16:31:44 INFO - PROCESS | 12712 | _x_x_mozilla_cycle_collect,932 16:31:44 INFO - PROCESS | 12712 | __end_cc_report 16:31:44 INFO - PROCESS | 12712 | __startTimestamp1471476704957__endTimestamp 16:31:44 INFO - PROCESS | 12712 | 16:31:44 INFO - PROCESS | 12712 | ------- Summary: start ------- 16:31:44 INFO - PROCESS | 12712 | Number of tests: 6 16:31:44 INFO - PROCESS | 12712 | 16:31:44 INFO - PROCESS | 12712 | [#0] dojo.html Cycles:165 Average:5179.17 Median:5349.63 stddev:591.83 (11.1%) stddev-sans-first:567.87 16:31:44 INFO - PROCESS | 12712 | Values: 2976.7 3004.9 3021.0 3030.3 3032.6 5060.4 5100.4 5199.2 5323.1 5382.4 5165.5 5218.4 5276.1 5278.5 5349.6 4636.8 4663.2 4676.7 4712.3 4891.5 4786.9 4826.2 4831.5 4835.7 4877.2 4598.0 4652.4 4671.6 4675.5 4715.9 4644.4 4670.3 4673.0 4673.2 4682.1 4651.7 4707.9 4732.7 4743.6 4765.2 5076.5 5160.9 5230.5 5240.4 5293.8 5496.1 5563.2 5607.7 5614.4 5646.8 5588.4 5609.6 5627.7 5631.7 5647.4 5034.8 5196.6 5245.1 5248.3 5250.4 5748.8 5777.9 5799.5 5802.7 5822.2 5523.3 5555.4 5577.7 5598.4 5603.4 5435.1 5516.8 5549.3 5624.9 5675.1 5601.0 5601.1 5601.7 5636.1 5644.4 5556.2 5600.4 5609.1 5611.5 5611.9 4162.8 4210.9 4247.6 4346.1 4383.8 3807.2 3842.3 3863.5 3883.9 3899.8 5202.2 5206.3 5207.6 5211.5 5211.8 5387.7 5496.6 5589.1 5605.5 5607.8 5146.0 5208.5 5223.6 5248.0 5251.2 4800.0 4803.2 4804.8 4810.0 4835.3 5590.7 5721.2 5744.9 5744.9 5753.8 5512.3 5548.0 5575.3 5670.5 5674.6 5816.1 5827.1 5829.1 5832.4 5832.9 5513.5 5517.8 5524.7 5525.3 5532.8 5352.9 5493.9 5516.0 5547.9 5650.4 5422.9 5459.4 5472.5 5472.7 5473.6 5399.4 5435.3 5455.2 5455.5 5509.1 5302.7 5324.6 5329.3 5342.0 5411.3 5447.5 5460.9 5468.7 5476.6 5494.9 5185.7 5323.5 5323.7 5325.4 5328.5 16:31:44 INFO - PROCESS | 12712 | 16:31:44 INFO - PROCESS | 12712 | [#1] ext.html Cycles:180 Average:56274.66 Median:3671.46 stddev:105673.91 (2878.3%) stddev-sans-first:105887.58 16:31:44 INFO - PROCESS | 12712 | Values: 566.0 590.9 608.0 611.2 629.3 32258.2 43645.1 44361.0 44426.0 44490.1 46321.5 46852.1 46960.8 46992.8 47006.0 8179.5 9006.3 9145.7 9167.3 10664.5 10924.1 11075.9 11076.8 11082.8 11084.3 11174.8 11232.8 11243.1 11244.8 11245.9 8127.9 10743.7 10985.6 11127.9 11165.4 10923.9 10942.7 10988.8 10997.0 10997.7 43961.1 44296.0 44323.6 44347.0 44362.0 3620.5 3722.4 3756.5 3878.7 4396.3 32707.2 33407.8 33668.7 33676.0 33702.8 4425.3 4467.9 4549.9 4564.7 4648.9 255353.0 256039.0 258318.0 258603.0 258967.0 60121.4 60143.4 60381.4 60437.0 60490.7 62711.0 62744.7 62852.0 62970.7 63019.7 16966.3 17213.7 17262.4 17263.6 17278.4 11870.3 12900.5 13027.4 13157.7 13321.6 16858.8 16941.7 16999.3 17006.7 17072.7 5823.8 5892.2 5909.2 6012.2 6037.6 981.7 1025.7 1085.1 1099.0 1185.5 33554.8 34121.7 34162.5 34208.5 34511.3 1082.5 1128.6 1141.1 1193.5 1201.3 17428.2 18014.3 18053.6 18078.5 18129.2 554234.0 557290.0 559443.0 560621.0 560648.0 59862.7 59962.7 59964.0 59985.0 60016.4 1229.1 1339.4 1383.9 1395.6 1400.7 33361.5 34913.1 34944.8 35513.6 35616.8 51519.7 51973.5 52000.0 52044.0 52064.5 312508.0 313118.0 313162.0 313585.9 314369.0 28290.7 29384.4 29403.3 30403.5 30429.8 49053.3 50146.2 50773.2 51344.5 55144.4 40739.6 42530.6 46803.3 47153.8 47334.8 43886.8 46379.0 46534.0 47328.8 47385.5 44817.8 45765.5 46179.5 46944.4 47278.0 38316.2 41015.2 41129.4 41252.8 41772.6 30480.8 30549.4 30836.4 31171.7 31303.5 16:31:44 INFO - PROCESS | 12712 | 16:31:44 INFO - PROCESS | 12712 | [#2] jquery.html Cycles:180 Average:29279.81 Median:17254.50 stddev:83261.30 (482.5%) stddev-sans-first:83466.58 16:31:44 INFO - PROCESS | 12712 | Values: 375.1 386.7 395.7 401.4 420.6 14118.2 14970.3 19829.6 19831.9 19871.0 18034.5 18407.0 18636.3 18680.8 18708.3 5531.1 5753.8 5762.2 5770.1 5772.3 3996.3 6271.4 6524.6 6531.8 6574.4 6247.6 6333.4 6496.8 6510.0 6513.6 6401.7 6418.4 6434.4 6448.5 6456.8 5148.2 5395.8 5428.9 5494.6 5702.0 13835.9 13851.9 13949.0 14034.9 14114.9 2220.9 2277.1 2286.3 2331.1 2523.0 9733.1 10031.7 10420.0 12712.6 13721.5 1942.5 1958.4 1976.9 1982.8 1986.1 57737.9 63634.0 69734.0 76860.2 84747.0 10816.8 11065.5 11083.7 11164.9 11204.9 15580.5 15612.7 15618.0 15722.4 15749.3 7857.4 8055.5 8073.6 8281.9 8293.9 7515.9 7554.2 7631.1 7754.7 7793.3 4638.5 4705.6 4810.6 4899.3 4913.2 1958.9 2001.5 2014.5 2047.4 2061.0 859.6 903.3 906.7 974.5 1023.1 18843.8 19101.9 19685.0 19862.6 20211.6 937.2 957.3 980.6 1006.4 1013.3 10408.4 10889.0 10998.5 10999.9 11098.5 492980.0 496533.0 503031.0 516236.0 516476.0 93525.5 94459.5 95169.0 95556.5 104483.5 996.1 1006.0 1047.5 1056.6 1067.4 17717.8 18007.4 18782.6 18930.9 18991.3 31986.0 43585.9 44721.6 45535.5 46441.5 63227.7 63614.0 64320.4 65812.7 67053.3 8252.5 8337.8 8397.3 8649.4 8705.2 11978.8 12528.2 13723.5 13979.7 14030.4 11791.6 11923.5 11952.9 12126.8 12587.4 11176.1 11294.5 11562.2 11677.1 11754.6 11629.8 12044.4 12301.4 12365.3 12378.5 8713.9 8726.7 8756.2 8886.2 9036.8 8963.0 10923.7 11623.7 11717.8 11784.7 16:31:44 INFO - PROCESS | 12712 | 16:31:44 INFO - PROCESS | 12712 | [#3] mootools.html Cycles:180 Average:5494.58 Median:4978.78 stddev:3119.69 (62.7%) stddev-sans-first:3112.72 16:31:44 INFO - PROCESS | 12712 | Values: 1327.6 1477.4 1510.2 1597.4 1737.8 7010.2 8004.1 8290.6 8313.3 8493.7 8236.6 8277.4 8282.8 8302.7 8304.8 2263.2 2286.5 2290.1 2292.6 2293.8 2253.3 2295.4 2305.4 2307.9 2308.2 2148.4 2157.8 2168.1 2188.6 2192.1 2160.6 2182.1 2196.7 2204.7 2207.7 2185.9 2190.6 2190.7 2192.0 2196.6 8009.5 8349.5 8403.7 8435.2 8437.5 4892.5 5068.7 5075.8 5092.1 5095.5 3196.7 3415.0 3429.9 3479.3 3481.9 1103.6 1144.8 1147.8 1164.0 1172.9 11498.5 11560.1 11620.6 11652.2 11673.7 3913.7 4020.3 4169.2 4187.6 4309.9 9139.6 9142.8 9146.0 9147.9 9154.4 4965.8 4974.8 4978.3 4979.3 4983.3 4014.5 4445.9 4584.0 4604.4 4697.8 3432.5 3489.5 3582.6 3621.0 3629.0 1034.1 1049.1 1056.3 1057.5 1065.0 920.4 952.7 967.8 974.1 1020.8 5705.1 5791.9 5887.6 5933.6 6020.1 440.2 448.2 458.4 467.7 480.4 3928.9 4068.6 4073.1 4100.7 4125.5 10114.2 10457.3 10484.6 10487.0 10496.6 10272.8 10374.2 10413.1 10424.2 10427.2 7654.5 7705.4 7706.3 7710.2 7728.1 5596.1 5650.9 5740.8 5798.0 5868.0 9601.7 9614.4 9661.4 9684.8 9697.4 9556.7 9699.4 9761.2 9866.8 9882.9 6300.9 6878.4 6912.9 7020.8 7047.2 8230.6 8293.4 8302.6 8309.4 8311.9 7370.2 7417.7 7493.8 7530.7 7646.0 7230.2 7231.6 7289.7 7294.8 7381.2 6917.9 6931.4 6962.8 6967.0 6969.8 4988.4 5001.9 5087.4 5101.5 5128.5 4974.6 5089.1 5141.3 5175.4 5187.9 16:31:44 INFO - PROCESS | 12712 | 16:31:44 INFO - PROCESS | 12712 | [#4] prototype.html Cycles:180 Average:4580.72 Median:5249.32 stddev:1347.44 (25.7%) stddev-sans-first:1312.65 16:31:44 INFO - PROCESS | 12712 | Values: 316.5 326.4 326.4 327.8 338.0 5244.2 5287.6 5294.3 5297.9 5351.7 5262.3 5275.4 5281.8 5285.3 5288.5 3615.0 3923.7 4035.6 4046.7 4070.5 4095.0 4120.0 4128.1 4134.2 4142.6 4075.6 4102.4 4105.0 4108.5 4126.1 3925.2 3969.4 4105.5 4112.4 4115.9 3599.7 3605.0 3611.9 3619.0 3626.8 5085.7 5097.6 5102.4 5117.0 5119.9 4472.2 4678.7 4686.3 4687.9 4708.7 5437.5 5460.1 5469.8 5473.9 5478.9 3952.6 3953.3 3970.1 3974.2 3977.0 5513.3 5621.7 5680.7 5688.2 5725.4 4037.3 4046.9 4060.7 4061.7 4067.9 4049.6 4059.6 4081.7 4089.2 4092.8 4477.0 4647.5 4648.9 4655.2 4684.6 5477.0 5514.8 5526.3 5534.5 5536.8 1394.4 1407.8 1411.8 1412.8 1417.5 841.4 891.2 901.4 915.6 915.7 4633.2 4652.6 4658.3 4670.2 4692.3 4946.9 4984.7 4996.0 4998.5 5002.7 4712.6 4718.7 4741.0 4754.2 4919.6 2946.3 2991.6 2991.7 3003.6 3004.8 5869.9 5877.1 5878.6 5881.4 5883.3 5512.3 5696.2 5732.5 5740.3 5743.0 5608.3 5741.6 5825.4 5871.9 5882.4 5505.2 5637.5 5666.7 5682.4 5687.9 5473.9 5593.7 5624.8 5633.6 5641.4 5860.0 5896.3 5896.6 5897.6 5899.8 4020.0 4044.8 4045.5 4060.0 4066.7 5464.2 5584.7 5622.7 5629.9 5631.8 5449.3 5491.3 5492.3 5493.4 5501.3 5336.3 5449.2 5451.7 5475.5 5499.9 5440.4 5468.9 5470.4 5485.5 5492.3 5463.6 5491.5 5517.3 5520.8 5544.9 5254.4 5389.5 5396.3 5400.9 5405.8 16:31:44 INFO - PROCESS | 12712 | 16:31:44 INFO - PROCESS | 12712 | [#5] yui.html Cycles:180 Average:19298.98 Median:2763.79 stddev:25723.17 (930.7%) stddev-sans-first:25757.52 16:31:44 INFO - PROCESS | 12712 | Values: 724.2 740.3 756.5 757.0 765.2 15524.8 16179.2 16465.8 16620.0 16655.8 15666.5 15684.4 15753.6 15775.9 15788.2 804.4 818.5 819.3 819.5 820.3 657.3 781.4 811.5 813.4 814.0 866.6 869.0 869.2 869.4 869.7 856.9 859.6 860.7 860.9 861.5 502.7 603.7 626.7 628.4 630.3 14973.1 14991.6 15011.0 15020.5 15029.9 13892.7 14436.9 14618.2 14668.4 15002.8 13779.6 13878.8 14128.8 14190.6 14730.1 3796.3 3933.6 3946.4 3993.2 4153.7 73975.6 74329.6 75404.0 75694.0 76677.2 11363.3 11525.5 11575.4 11689.1 11702.4 20671.7 20674.2 20696.4 20700.0 20754.3 8223.5 8347.0 8818.0 8826.9 8841.4 4773.2 4918.7 5032.0 5186.4 5232.3 3657.6 3732.4 3784.7 3786.1 3798.2 608.8 621.5 624.4 631.0 632.6 730.1 733.8 749.4 756.8 770.5 9893.2 10857.3 10879.4 10894.9 11070.7 579.6 654.1 734.9 788.2 794.8 4903.5 5024.9 5162.9 5202.2 5235.1 68613.0 69665.7 94884.0 119161.0 119338.4 61116.4 68798.7 89330.0 90027.0 99762.0 53786.5 54744.7 55709.7 82992.0 83059.0 9132.6 9563.3 9607.2 9627.7 9684.4 53093.7 54157.7 54701.2 54974.5 55612.0 42297.6 50586.5 66508.7 76516.7 77130.2 12046.9 13269.9 13300.1 13537.2 13713.2 15330.6 15431.4 15477.8 16398.7 16422.6 13839.2 14136.4 15001.7 15081.2 15440.5 14830.6 15439.9 16253.9 16256.8 16262.8 14508.7 15283.5 15720.3 15734.6 15750.2 11329.9 11444.3 11610.9 11728.5 11785.6 11971.3 12009.6 12164.3 12581.7 12984.5 16:31:44 INFO - PROCESS | 12712 | -------- Summary: end -------- 16:31:44 INFO - PROCESS | 12712 | 16:31:45 INFO - TEST-INFO | 12712: exit 0 16:31:45 INFO - TEST-OK | dromaeo_css | took 1115175ms 16:31:45 INFO - TEST-START | kraken 16:31:45 INFO - operating with platform_type : linux_ 16:31:45 INFO - Initialising browser for kraken test... 16:31:45 INFO - TEST-INFO | started process 13024 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphjBgdW/profile http://localhost:44255/getInfo.html) 16:31:49 INFO - PROCESS | 13024 | __metrics Screen width/height:1600/1200 16:31:49 INFO - PROCESS | 13024 | colorDepth:24 16:31:49 INFO - PROCESS | 13024 | Browser inner width/height: 1024/697 16:31:49 INFO - PROCESS | 13024 | __metrics 16:31:50 INFO - PROCESS | 13024 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:31:50 INFO - PROCESS | 13024 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:31:50 INFO - PROCESS | 13024 | console.error: PushService: 16:31:50 INFO - PROCESS | 13024 | stateChangeProcessEnqueue: Error transitioning state 16:31:50 INFO - PROCESS | 13024 | UnknownError 16:31:51 INFO - TEST-INFO | 13024: exit 0 16:31:51 INFO - Browser initialized. 16:31:51 INFO - Running cycle 1/1 for kraken test... 16:31:51 INFO - TEST-INFO | started process 13106 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphjBgdW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:32:02 INFO - PROCESS | 13106 | RSS: Main: 155856896 16:32:02 INFO - PROCESS | 13106 | 16:32:05 INFO - PROCESS | 13106 | Cycle 1(1): loaded http://localhost:44255/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44255/tests/kraken/driver.html?testName=audio-beat-detection) 16:32:06 INFO - PROCESS | 13106 | RSS: Main: 203972608 16:32:06 INFO - PROCESS | 13106 | 16:32:08 INFO - PROCESS | 13106 | Cycle 1(1): loaded http://localhost:44255/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44255/tests/kraken/driver.html?testName=audio-dft) 16:32:09 INFO - PROCESS | 13106 | RSS: Main: 195293184 16:32:09 INFO - PROCESS | 13106 | 16:32:12 INFO - PROCESS | 13106 | Cycle 1(1): loaded http://localhost:44255/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44255/tests/kraken/driver.html?testName=audio-fft) 16:32:13 INFO - PROCESS | 13106 | RSS: Main: 275505152 16:32:13 INFO - PROCESS | 13106 | 16:32:15 INFO - PROCESS | 13106 | Cycle 1(1): loaded http://localhost:44255/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44255/tests/kraken/driver.html?testName=audio-oscillator) 16:32:15 INFO - PROCESS | 13106 | RSS: Main: 186056704 16:32:15 INFO - PROCESS | 13106 | 16:32:18 INFO - PROCESS | 13106 | Cycle 1(1): loaded http://localhost:44255/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44255/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:32:18 INFO - PROCESS | 13106 | RSS: Main: 191205376 16:32:18 INFO - PROCESS | 13106 | 16:32:21 INFO - PROCESS | 13106 | Cycle 1(1): loaded http://localhost:44255/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44255/tests/kraken/driver.html?testName=imaging-darkroom) 16:32:22 INFO - PROCESS | 13106 | RSS: Main: 225820672 16:32:22 INFO - PROCESS | 13106 | 16:32:25 INFO - PROCESS | 13106 | Cycle 1(1): loaded http://localhost:44255/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44255/tests/kraken/driver.html?testName=imaging-desaturate) 16:32:26 INFO - PROCESS | 13106 | RSS: Main: 225693696 16:32:26 INFO - PROCESS | 13106 | 16:32:29 INFO - PROCESS | 13106 | Cycle 1(1): loaded http://localhost:44255/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44255/tests/kraken/driver.html?testName=json-parse-financial) 16:32:29 INFO - PROCESS | 13106 | RSS: Main: 226611200 16:32:29 INFO - PROCESS | 13106 | 16:32:31 INFO - PROCESS | 13106 | Cycle 1(1): loaded http://localhost:44255/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44255/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:32:31 INFO - PROCESS | 13106 | RSS: Main: 188727296 16:32:31 INFO - PROCESS | 13106 | 16:32:33 INFO - PROCESS | 13106 | Cycle 1(1): loaded http://localhost:44255/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44255/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:32:34 INFO - PROCESS | 13106 | RSS: Main: 196222976 16:32:34 INFO - PROCESS | 13106 | 16:32:36 INFO - PROCESS | 13106 | Cycle 1(1): loaded http://localhost:44255/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44255/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:32:36 INFO - PROCESS | 13106 | RSS: Main: 193990656 16:32:36 INFO - PROCESS | 13106 | 16:32:39 INFO - PROCESS | 13106 | Cycle 1(1): loaded http://localhost:44255/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44255/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:32:40 INFO - PROCESS | 13106 | RSS: Main: 187506688 16:32:40 INFO - PROCESS | 13106 | 16:32:42 INFO - PROCESS | 13106 | Cycle 1(1): loaded http://localhost:44255/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44255/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:32:43 INFO - PROCESS | 13106 | RSS: Main: 192098304 16:32:43 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | Cycle 1(1): loaded http://localhost:44255/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44255/tests/kraken/driver.html?testName=ai-astar) 16:32:45 INFO - PROCESS | 13106 | RSS: Main: 242438144 16:32:45 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | __start_tp_report 16:32:45 INFO - PROCESS | 13106 | _x_x_mozilla_page_load 16:32:45 INFO - PROCESS | 13106 | _x_x_mozilla_page_load_details 16:32:45 INFO - PROCESS | 13106 | |i|pagename|runs| 16:32:45 INFO - PROCESS | 13106 | |0;ai-astar;102;111;111;110;107;110;111;110;110;111 16:32:45 INFO - PROCESS | 13106 | |1;audio-beat-detection;151;123;119;172;118;121;146;117;152;116 16:32:45 INFO - PROCESS | 13106 | |2;audio-dft;161;162;159;159;162;160;160;159;160;162 16:32:45 INFO - PROCESS | 13106 | |3;audio-fft;75;75;75;105;74;76;75;75;102;76 16:32:45 INFO - PROCESS | 13106 | |4;audio-oscillator;85;86;129;85;84;85;129;84;85;144 16:32:45 INFO - PROCESS | 13106 | |5;imaging-gaussian-blur;118;118;117;118;119;118;118;118;118;118 16:32:45 INFO - PROCESS | 13106 | |6;imaging-darkroom;145;146;145;146;146;147;146;147;146;146 16:32:45 INFO - PROCESS | 13106 | |7;imaging-desaturate;95;95;96;96;95;95;96;96;95;95 16:32:45 INFO - PROCESS | 13106 | |8;json-parse-financial;51;50;53;51;50;53;51;50;53;52 16:32:45 INFO - PROCESS | 13106 | |9;json-stringify-tinderbox;62;62;62;61;62;62;61;62;61;62 16:32:45 INFO - PROCESS | 13106 | |10;stanford-crypto-aes;79;78;78;79;78;78;80;78;78;78 16:32:45 INFO - PROCESS | 13106 | |11;stanford-crypto-ccm;130;121;120;122;124;129;123;128;138;126 16:32:45 INFO - PROCESS | 13106 | |12;stanford-crypto-pbkdf2;168;164;163;167;164;166;167;161;165;163 16:32:45 INFO - PROCESS | 13106 | |13;stanford-crypto-sha256-iterative;67;66;64;62;64;63;65;64;63;63 16:32:45 INFO - PROCESS | 13106 | __end_tp_report 16:32:45 INFO - PROCESS | 13106 | __start_cc_report 16:32:45 INFO - PROCESS | 13106 | _x_x_mozilla_cycle_collect,1675 16:32:45 INFO - PROCESS | 13106 | __end_cc_report 16:32:45 INFO - PROCESS | 13106 | __startTimestamp1471476765314__endTimestamp 16:32:45 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | ------- Summary: start ------- 16:32:45 INFO - PROCESS | 13106 | Number of tests: 14 16:32:45 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | [#0] ai-astar Cycles:10 Average:109.30 Median:110.50 stddev:2.83 (2.6%) stddev-sans-first:1.27 16:32:45 INFO - PROCESS | 13106 | Values: 102.0 111.0 111.0 110.0 107.0 110.0 111.0 110.0 110.0 111.0 16:32:45 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | [#1] audio-beat-detection Cycles:10 Average:133.50 Median:134.50 stddev:19.95 (14.8%) stddev-sans-first:20.13 16:32:45 INFO - PROCESS | 13106 | Values: 151.0 123.0 119.0 172.0 118.0 121.0 146.0 117.0 152.0 116.0 16:32:45 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:1.26 (0.8%) stddev-sans-first:1.32 16:32:45 INFO - PROCESS | 13106 | Values: 161.0 162.0 159.0 159.0 162.0 160.0 160.0 159.0 160.0 162.0 16:32:45 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | [#3] audio-fft Cycles:10 Average:80.80 Median:75.00 stddev:12.00 (16.0%) stddev-sans-first:12.54 16:32:45 INFO - PROCESS | 13106 | Values: 75.0 75.0 75.0 105.0 74.0 76.0 75.0 75.0 102.0 76.0 16:32:45 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | [#4] audio-oscillator Cycles:10 Average:99.60 Median:85.00 stddev:24.09 (28.3%) stddev-sans-first:24.97 16:32:45 INFO - PROCESS | 13106 | Values: 85.0 86.0 129.0 85.0 84.0 85.0 129.0 84.0 85.0 144.0 16:32:45 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:118.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 16:32:45 INFO - PROCESS | 13106 | Values: 118.0 118.0 117.0 118.0 119.0 118.0 118.0 118.0 118.0 118.0 16:32:45 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.60 16:32:45 INFO - PROCESS | 13106 | Values: 145.0 146.0 145.0 146.0 146.0 147.0 146.0 147.0 146.0 146.0 16:32:45 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 16:32:45 INFO - PROCESS | 13106 | Values: 95.0 95.0 96.0 96.0 95.0 95.0 96.0 96.0 95.0 95.0 16:32:45 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | [#8] json-parse-financial Cycles:10 Average:51.40 Median:51.50 stddev:1.26 (2.5%) stddev-sans-first:1.33 16:32:45 INFO - PROCESS | 13106 | Values: 51.0 50.0 53.0 51.0 50.0 53.0 51.0 50.0 53.0 52.0 16:32:45 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | [#9] json-stringify-tinderbox Cycles:10 Average:61.70 Median:62.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 16:32:45 INFO - PROCESS | 13106 | Values: 62.0 62.0 62.0 61.0 62.0 62.0 61.0 62.0 61.0 62.0 16:32:45 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:78.00 stddev:0.70 (0.9%) stddev-sans-first:0.71 16:32:45 INFO - PROCESS | 13106 | Values: 79.0 78.0 78.0 79.0 78.0 78.0 80.0 78.0 78.0 78.0 16:32:45 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | [#11] stanford-crypto-ccm Cycles:10 Average:126.10 Median:127.00 stddev:5.40 (4.3%) stddev-sans-first:5.55 16:32:45 INFO - PROCESS | 13106 | Values: 130.0 121.0 120.0 122.0 124.0 129.0 123.0 128.0 138.0 126.0 16:32:45 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.80 Median:165.50 stddev:2.20 (1.3%) stddev-sans-first:2.01 16:32:45 INFO - PROCESS | 13106 | Values: 168.0 164.0 163.0 167.0 164.0 166.0 167.0 161.0 165.0 163.0 16:32:45 INFO - PROCESS | 13106 | 16:32:45 INFO - PROCESS | 13106 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.00 stddev:1.52 (2.4%) stddev-sans-first:1.20 16:32:45 INFO - PROCESS | 13106 | Values: 67.0 66.0 64.0 62.0 64.0 63.0 65.0 64.0 63.0 63.0 16:32:45 INFO - PROCESS | 13106 | -------- Summary: end -------- 16:32:45 INFO - PROCESS | 13106 | 16:32:46 INFO - TEST-INFO | 13106: exit 0 16:32:46 INFO - TEST-OK | kraken | took 60647ms 16:32:46 INFO - SUITE-END | took 1175s 16:32:46 INFO - Completed test suite (00:19:35) 16:32:46 INFO - javascript benchmark 16:32:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2976.7320941115295, 3004.924876878079, 3020.969790302097, 3030.333326668132, 3032.590600268963, 5060.392752869651, 5100.362454693163, 5199.194124910636, 5323.148296272592, 5382.38102618199, 5165.509276618715, 5218.39988401334, 5276.050310944029, 5278.47215278472, 5349.625526213166, 4636.837710680127, 4663.207254766681, 4676.7427791471455, 4712.340272361872, 4891.48639392864, 4786.946871688223, 4826.227803551433, 4831.516848315176, 4835.709857408549, 4877.244027175776, 4597.954020459786, 4652.395188625457, 4671.556202160823, 4675.485696573374, 4715.905681886377, 4644.396228490275, 4670.252759558454, 4672.95327046732, 4673.182193116191, 4682.110399024208, 4651.720896746172, 4707.8587642370785, 4732.692374995649, 4743.620510359163, 4765.21373973294, 5076.466970968032, 5160.870978225536, 5230.476952304777, 5240.423553409122, 5293.841184764463, 5496.065668836308, 5563.248961390201, 5607.663540187602, 5614.410486898899, 5646.774129034834, 5588.413216612278, 5609.635373700738, 5627.662340259598, 5631.718414079321, 5647.37878833328, 5034.773435195389, 5196.558292545101, 5245.082110630625, 5248.343957005374, 5250.422453530108, 5748.798792042342, 5777.884442311214, 5799.507041901401, 5802.738876750556, 5822.155787410802, 5523.337199536081, 5555.416681248492, 5577.749001294951, 5598.412166722517, 5603.3836278009385, 5435.1303791393975, 5516.834494965157, 5549.306336707911, 5624.887502249932, 5675.091985282335, 5601.0, 5601.131824567198, 5601.719914004283, 5636.126400407861, 5644.379118296984, 5556.222128901957, 5600.411956744565, 5609.1305847593685, 5611.466910643482, 5611.859703507445, 4162.812673429642, 4210.852620158341, 4247.638950689165, 4346.130773845181, 4383.802728877145, 3807.238552289498, 3842.2507611016144, 3863.4977452931503, 3883.922321553553, 3899.7660140391667, 5202.2196670499725, 5206.271122042917, 5207.635465517377, 5211.452797456288, 5211.765470553834, 5387.730613469389, 5496.642718223303, 5589.077802162597, 5605.523530499871, 5607.8317650469735, 5146.022255771391, 5208.453112423141, 5223.608229382811, 5248.029114613809, 5251.212318152231, 4799.9760001199775, 4803.18345881201, 4804.807807687654, 4809.975950120227, 4835.323054772335, 5590.692511911884, 5721.1990321354215, 5744.913826292609, 5744.942550574524, 5753.827385178452, 5512.2558454608115, 5547.972260138674, 5575.330960284629, 5670.489655930986, 5674.602777805514, 5816.069428891357, 5827.096799996008, 5829.125631155361, 5832.416758324133, 5832.912506312324, 5513.476219759116, 5517.779288828402, 5524.696141712084, 5525.336959564717, 5532.806351777668, 5352.866178345416, 5493.862653433536, 5515.972420137874, 5547.916781248366, 5650.434956504317, 5422.891542169055, 5459.426760190282, 5472.50747432733, 5472.699001554953, 5473.616846820843, 5399.352077750695, 5435.2934118564335, 5455.18172274162, 5455.536279416294, 5509.090999984957, 5302.734863256745, 5324.6006549508165, 5329.333833270841, 5342.038433081927, 5411.2965314508865, 5447.4824891635235, 5460.918086228789, 5468.671879687232, 5476.644018138808, 5494.945050549603, 5185.688858668492, 5323.494268044529, 5323.733813309396, 5325.387580428301, 5328.49379308965], "value": 5139.212044662236, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.0330352026708, 590.9320428150763, 608.0271565495207, 611.162707091285, 629.279475001124, 32258.193545161383, 43645.12709745803, 44361.0, 44425.999999999956, 44490.11019779603, 46321.536784632146, 46852.06295874089, 46960.765196174056, 46992.76503617486, 47006.0, 8179.468334558264, 9006.324525660586, 9145.72562823113, 9167.312451566126, 10664.466776661136, 10924.126069914388, 11075.944620276927, 11076.833847492253, 11082.778344433107, 11084.279521831055, 11174.776504469906, 11232.775344493146, 11243.100551955864, 11244.775104497905, 11245.943770281137, 8127.918720812775, 10743.731406714816, 10985.61550345734, 11127.888721112766, 11165.38590377529, 10923.890761092367, 10942.726431839188, 10988.780224395547, 10997.0, 10997.670069897917, 43961.12077758427, 44296.0, 44323.556764432586, 44347.0, 44362.0, 3620.5112309838096, 3722.40441529354, 3756.530433695788, 3878.7090968177217, 4396.296592545209, 32707.18232044194, 33407.83296083504, 33668.6633133668, 33676.0, 33702.83148584266, 4425.291953287491, 4467.888302792424, 4549.931751023737, 4564.726116433025, 4648.883777905546, 255353.0, 256039.0, 258317.9999999981, 258603.0, 258967.0, 60121.398786012454, 60143.39856601466, 60381.39618603846, 60437.0, 60490.69754651198, 62711.0, 62744.686276568325, 62852.0, 62970.68514657489, 63019.6849015752, 16966.321347146222, 17213.65572688564, 17262.395816146374, 17263.56841078983, 17278.395256166226, 11870.287782733063, 12900.483980640669, 13027.413766380536, 13157.67105822343, 13321.600351989457, 16858.831411685973, 16941.66116677684, 16999.32002719902, 17006.659866802594, 17072.658546828992, 5823.79616713413, 5892.234009578728, 5909.202257695156, 6012.158297838306, 6037.63774173551, 981.7153025622632, 1025.6666583360438, 1085.1210519479248, 1098.9670309890716, 1185.466540056978, 33554.8322258392, 34121.65878341234, 34162.48756268608, 34208.48687269693, 34511.30977380438, 1082.5345101606226, 1128.5598616539555, 1141.092831199182, 1193.5046955513537, 1201.3092471828734, 17428.215730292166, 18014.2794288227, 18053.638927221382, 18078.45764627063, 18129.18418671163, 554234.0, 557290.0, 559443.0, 560621.0, 560648.0, 59862.70068649716, 59962.70018649879, 59964.0, 59985.0, 60016.399836001954, 1229.0720506018079, 1339.4240476595157, 1383.9031267811358, 1395.6022533578084, 1400.712853864935, 33361.499577506846, 34913.12717182091, 34944.82527587346, 35513.64486355103, 35616.82191589026, 51519.74240128775, 51973.48026519838, 52000.0, 52044.0, 52064.479355205964, 312508.0, 313118.0, 313162.0, 313585.8641413648, 314369.0, 28290.717092828807, 29384.412311753218, 29403.29547272426, 30403.54394684037, 30429.847850760605, 49053.2642010363, 50146.247806282205, 50773.23840142327, 51344.48655513397, 55144.44855551393, 40739.592604074765, 42530.57469425266, 46803.29795053145, 47153.764231178626, 47334.76332618315, 43886.8168481782, 46379.0, 46534.0, 47328.763356183, 47385.52614473949, 44817.77591112023, 45765.54234457746, 46179.53820461752, 46944.427780111495, 47278.0, 38316.2336753269, 41015.1796964065, 41129.38305925473, 41252.793736031126, 41772.58227417809, 30480.84759576188, 30549.389012220076, 30836.383272334875, 31171.688283117786, 31303.530447042856], "value": 20856.44088190194, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.06234413965086, 386.7022392757577, 395.685430083084, 401.4339780908918, 420.5605142625956, 14118.223497707635, 14970.251487425627, 19829.603407931834, 19831.90084049578, 19871.0, 18034.5491362716, 18407.0, 18636.254549818026, 18680.813191868077, 18708.345207917693, 5531.0873705838485, 5753.827385178452, 5762.164486149506, 5770.076787713967, 5772.307323121231, 3996.3233824881036, 6271.404216599439, 6524.608523488582, 6531.804045878631, 6574.408303252706, 6247.625142491443, 6333.366663333676, 6496.837579060538, 6509.99095140254, 6513.57661751987, 6401.711922963478, 6418.42234198922, 6434.356564343575, 6448.548601597867, 6456.838579035515, 5148.176291793333, 5395.81114660985, 5428.945710542884, 5494.642848214893, 5702.030654788655, 13835.930820345935, 13851.930740346335, 13949.0, 14034.859651403458, 14114.858851411456, 2220.922267720638, 2277.1233075265977, 2286.2912497125944, 2331.1141766128762, 2523.0, 9733.075357840993, 10031.69904902854, 10420.0, 12712.618621441372, 13721.45114195431, 1942.524081600003, 1958.4222654317011, 1976.8517361197823, 1982.7918068602733, 1986.145957238387, 57737.851777217125, 63634.0, 69734.0, 76860.23139768642, 84747.0, 10816.78366432667, 11065.50205240766, 11083.72290692723, 11164.888351116548, 11204.887951120547, 15580.532584022498, 15612.687746245012, 15618.0, 15722.371105155895, 15749.291281892343, 7857.428514297163, 8055.516668999879, 8073.596320183968, 8281.917180828235, 8293.917060829435, 7515.924840751632, 7554.169041405441, 7631.122420921554, 7754.651040703182, 7793.298603125746, 4638.489766125724, 4705.60002399793, 4810.59109975649, 4899.2896030075435, 4913.213885778258, 1958.8628795984143, 2001.5196352875496, 2014.4661664658877, 2047.4471892589215, 2061.0, 859.5702148925537, 903.290916630442, 906.6826610686207, 974.4835237324229, 1023.0996722883815, 18843.811561884206, 19101.90449047746, 19685.0, 19862.60274794525, 20211.59576808485, 937.2033771294482, 957.2963871554402, 980.6420656460483, 1006.3659894266565, 1013.2805707947292, 10408.427536485415, 10889.0, 10998.505067271992, 10999.945000274947, 11098.500567474483, 492980.0, 496533.0, 503031.0, 516236.0, 516476.0, 93525.53237233771, 94459.52770236105, 95169.0483095174, 95556.52221738847, 104483.47758261312, 996.1283876607968, 1005.9849102263472, 1047.4867315015588, 1056.6248981611418, 1067.380919066955, 17717.822821771617, 18007.369742058963, 18782.624347513247, 18930.905345473184, 18991.33530326432, 31986.0, 43585.910352241444, 44721.552784471736, 45535.54464455313, 46441.53558464372, 63227.6838615804, 63614.0, 64320.356796433305, 65812.67093664501, 67053.32946670472, 8252.504849709037, 8337.791555211168, 8397.328213742763, 8649.394542381973, 8705.216530512283, 11978.820317695066, 12528.185667931555, 13723.519676811264, 13979.720405592034, 14030.368633411725, 11791.587294444653, 11923.463444144862, 11952.940235298767, 12126.818097728717, 12587.433565489391, 11176.050035747052, 11294.491747871201, 11562.248453850472, 11677.06583473337, 11754.647360579196, 11629.76740465203, 12044.397780110785, 12301.44643491063, 12365.258084514959, 12378.542007840944, 8713.91286087131, 8726.694565690424, 8756.211940925346, 8886.155815197546, 9036.774080648036, 8962.955185224033, 10923.737969698162, 11623.709407264885, 11717.824232636347, 11784.70538236551], "value": 9194.95567723406, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.5685402244274, 1477.386884442956, 1510.1543135843924, 1597.3610555777686, 1737.8001529824073, 7010.228874823772, 8004.119546849849, 8290.585470726455, 8313.251807337347, 8493.745187644365, 8236.629351679174, 8277.420580559363, 8282.792930176733, 8302.70940517082, 8304.833903321929, 2263.2305016294417, 2286.531261091472, 2290.061074959267, 2292.56441276157, 2293.7820907013806, 2253.2902135827244, 2295.426143464134, 2305.4351683837485, 2307.8615283082986, 2308.1575225042907, 2148.4414052346347, 2157.8489505734606, 2168.143583284602, 2188.627933251351, 2192.112194561197, 2160.621891169039, 2182.127149140341, 2196.7473740519886, 2204.7464541577683, 2207.668849672546, 2185.9234926777485, 2190.5618876224817, 2190.726159230096, 2192.0135938827434, 2196.5936301784063, 8009.479383840032, 8349.499030058156, 8403.705870294572, 8435.240828325419, 8437.493750374997, 4892.486288939681, 5068.695878247317, 5075.771590278447, 5092.083424983502, 5095.5159259870625, 3196.7122958933573, 3414.96585034149, 3429.9314013719586, 3479.2519608284088, 3481.9477707834403, 1103.5806393570392, 1144.776768530137, 1147.8335641332044, 1163.9941800290944, 1172.8944395004492, 11498.540058397653, 11560.132990025782, 11620.593279235187, 11652.184347095792, 11673.708157296136, 3913.6869050475893, 4020.2964481216254, 4169.187008533307, 4187.560306167869, 4309.892252693645, 9139.588718507683, 9142.81714365709, 9145.954270228607, 9147.862782058275, 9154.35919485643, 4965.751712414365, 4974.751262436864, 4978.303037574742, 4979.253112033224, 4983.25251212321, 4014.4580481634607, 4445.866624001285, 4583.954160458419, 4604.424446944132, 4697.765111744399, 3432.502287168347, 3489.5289135966827, 3582.5521809773777, 3621.022323972513, 3628.9818550907075, 1034.1209971524295, 1049.0977759127145, 1056.3397876327294, 1057.4871187474068, 1065.0255016659823, 920.4293338130268, 952.6903756279237, 967.8306296398102, 974.1086905481405, 1020.8264595018865, 5705.115707065412, 5791.8552036199435, 5887.587868849139, 5933.554983376178, 6020.127081573235, 440.1658856466977, 448.1865414273084, 458.431544884341, 467.66561908235775, 480.43788767142524, 3928.901777455529, 4068.5931406859077, 4073.124278280184, 4100.7334523255895, 4125.484314460692, 10114.190864730952, 10457.320274182153, 10484.580616775396, 10487.0, 10496.580136794442, 10272.845907311395, 10374.221933354878, 10413.114885234689, 10424.166066714644, 10427.217958653131, 7654.502457340255, 7705.4220933430215, 7706.306432420997, 7710.151883292833, 7728.111267204231, 5596.132599447093, 5650.915236271541, 5740.799072032626, 5798.043322851682, 5867.970660146672, 9601.663941762004, 9614.423134611945, 9661.420314781135, 9684.757881052748, 9697.369670971364, 9556.665516706882, 9699.369540979813, 9761.170300524534, 9866.753331166778, 9882.901170988485, 6300.936990630035, 6878.415334696607, 6912.930870691229, 7020.7893763187185, 7047.15434147885, 8230.588470576327, 8293.377996650395, 8302.584870756558, 8309.418340716333, 8311.916880831115, 7370.189279179179, 7417.703291868481, 7493.775186744407, 7530.736424225126, 7646.0, 7230.204677485367, 7231.602261875437, 7289.708411663474, 7294.817629559091, 7381.224971377928, 6917.861642767016, 6931.4108300795, 6962.756303529352, 6967.0, 6969.825754356181, 4988.4263309719845, 5001.949980500149, 5087.440381557952, 5101.54086132235, 5128.538431541179, 4974.5771609413605, 5089.134847075932, 5141.25451809493, 5175.378954525481, 5187.948120518747], "value": 4337.960901836315, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.51257064121256, 326.3570765591786, 326.40594118703956, 327.7836627825635, 337.99493007604883, 5244.239585260135, 5287.576993840493, 5294.311739473867, 5297.8940421191555, 5351.652142610727, 5262.2632831403635, 5275.393329767068, 5281.762320695569, 5285.2600635911, 5288.524032837044, 3615.0, 3923.666488348487, 4035.556088830226, 4046.676265898736, 4070.470838790953, 4095.0, 4119.979400102995, 4128.112455821998, 4134.193832202722, 4142.64787493062, 4075.572064933184, 4102.405151253067, 4105.0, 4108.465899433085, 4126.112885729567, 3925.234579257046, 3969.3649016157565, 4105.548389677148, 4112.424260603518, 4115.855945041924, 3599.70410652165, 3605.0, 3611.898370996871, 3618.981905090484, 3626.818659067036, 5085.74571271435, 5097.623641616779, 5102.443754654829, 5117.0, 5119.923201151985, 4472.195004899117, 4678.719276843401, 4686.27362758773, 4687.88280292992, 4708.693934894221, 5437.537809286216, 5460.133429949268, 5469.808556700496, 5473.890522189574, 5478.945210547883, 3952.565217826037, 3953.3476976298875, 3970.1265721541213, 3974.205158968189, 3977.0, 5513.255710479074, 5621.662700237958, 5680.727708916425, 5688.203651488753, 5725.398833122504, 4037.313656678372, 4046.8785936421955, 4060.7360521566593, 4061.6953728470485, 4067.8576249831112, 4049.595040495986, 4059.5534491205945, 4081.7346872453195, 4089.2026054919006, 4092.7953602319767, 4476.955230447719, 4647.488776234612, 4648.884267775709, 4655.231886738718, 4684.578387945101, 5477.014137455217, 5514.779408823602, 5526.281583394213, 5534.529564987021, 5536.7785288588, 1394.3655636685285, 1407.774756039029, 1411.7670584353673, 1412.8233970753656, 1417.454280102168, 841.4488510025943, 891.1756625121736, 901.3915606965272, 915.555955361649, 915.688665853613, 4633.189191891405, 4652.557407361162, 4658.277966915133, 4670.229412147026, 4692.272697731857, 4946.901061978812, 4984.675996060317, 4995.9500404996215, 4998.525140111684, 5002.74986250686, 4712.646551508582, 4718.693284936468, 4740.952590474051, 4754.21555443348, 4919.581835544019, 2946.2781618503177, 2991.551267309921, 2991.7157870002316, 3003.6395632524236, 3004.7896647234484, 5869.911951320733, 5877.147813567009, 5878.617889837147, 5881.441263080001, 5883.294004719461, 5512.3385193777, 5696.202531645573, 5732.455416735404, 5740.253767010262, 5743.0, 5608.327000759853, 5741.598088133874, 5825.359210486843, 5871.85498827733, 5882.411758824083, 5505.229267902417, 5637.46444087811, 5666.716664166857, 5682.374938756816, 5687.947729670024, 5473.890522189614, 5593.664380137205, 5624.775008999758, 5633.605717570098, 5641.37944826077, 5860.033094539353, 5896.321922978912, 5896.587238893237, 5897.64614123154, 5899.758091925041, 4020.0, 4044.8179831908224, 4045.5145382553105, 4059.9797001014804, 4066.6543343814924, 5464.23500709893, 5584.692841893735, 5622.662640241599, 5629.943700563106, 5631.831045068655, 5449.3188351456065, 5491.258680078298, 5492.297387809792, 5493.368262649847, 5501.339839219319, 5336.332958380202, 5449.209864569536, 5451.672899626035, 5475.534579560782, 5499.945000549943, 5440.401555828935, 5468.863278417913, 5470.425605311544, 5485.451454854483, 5492.258545096361, 5463.590230732631, 5491.505764481216, 5517.310336207974, 5520.779168833202, 5544.889102218014, 5254.3974087902025, 5389.541888939327, 5396.325459317585, 5400.891982160256, 5405.8107966221], "value": 4150.452817532175, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.1563578431128, 740.344794856552, 756.4818099601773, 756.9545827250365, 765.2462324610261, 15524.767128493068, 16179.19104044799, 16465.753013704805, 16620.0, 16655.833441665578, 15666.530004099895, 15684.372625095011, 15753.60615984601, 15775.921120394381, 15788.210589470538, 804.4007214625098, 818.4598165210962, 819.3445243804962, 819.5082950229844, 820.2576668115365, 657.3262406033806, 781.4061313401827, 811.5009269299368, 813.3858936502961, 813.975580732576, 866.6360128745911, 868.9608967596473, 869.1916517638593, 869.3870821071162, 869.6825658634616, 856.8543347630917, 859.6174702257498, 860.6772460327377, 860.8923884514436, 861.5347712235385, 502.69586899925383, 603.7072020070262, 626.6960524145786, 628.3716283716284, 630.2562975688732, 14973.101613903093, 14991.550253492413, 15011.0, 15020.549383518512, 15029.924850375679, 13892.652683682887, 14436.855631443656, 14618.195999220039, 14668.413263469449, 15002.849971500364, 13779.586612401645, 13878.791818122736, 14128.788068178985, 14190.645233869132, 14730.116193028345, 3796.2976849282973, 3933.6459718625174, 3946.388309811955, 3993.2412841560013, 4153.68847336451, 73975.63012184904, 74329.62835185844, 75404.0, 75694.0, 76677.23322766757, 11363.261388009752, 11525.538978440936, 11575.363355015555, 11689.064874809996, 11702.414879256174, 20671.689924651142, 20674.17303307881, 20696.379108626767, 20700.0, 20754.273600423912, 8223.465474744122, 8346.958265208636, 8818.0, 8826.86759698605, 8841.425307355, 4773.188557945157, 4918.680285781485, 5032.0, 5186.351706036746, 5232.3459567554055, 3657.579378371468, 3732.4401339798706, 3784.6972242220554, 3786.091338078896, 3798.24035192957, 608.7520582805231, 621.5307442880686, 624.447364082781, 631.0156156395946, 632.6457183976987, 730.1129128109372, 733.8495608400265, 749.4042236422058, 756.8070142113726, 770.5261264322402, 9893.208543316518, 10857.294275872044, 10879.401632910107, 10894.891051089387, 11070.667879963614, 579.6493121661418, 654.1234745441132, 734.9343451994629, 788.1960400391683, 794.7933537280348, 4903.4606193318705, 5024.874378140576, 5162.8967420652125, 5202.2196670499725, 5235.136202526541, 68613.0, 69665.65167174234, 94884.0, 119161.0, 119338.40330798464, 61116.38883611107, 68798.65600672064, 89330.0, 90027.0, 99762.0, 53786.462135378926, 54744.72627636916, 55709.721451392485, 82992.0, 83059.0, 9132.634694612407, 9563.282753793354, 9607.16547724141, 9627.711168664951, 9684.370515916444, 53093.73453132709, 54157.72921135369, 54701.179482307, 54974.45025549693, 55612.0, 42297.577024230595, 50586.49413505818, 66508.66745666241, 76516.70449886512, 77130.22869771456, 12046.939765301116, 13269.867301326864, 13300.135491193041, 13537.187768733907, 13713.17720936747, 15330.616734581725, 15431.382744690087, 15477.767833482281, 16398.67202655964, 16422.589435263737, 13839.169649821042, 14136.363863625957, 15001.699966000837, 15081.170535620207, 15440.536783896501, 14830.62923426923, 15439.922800386375, 16253.918730406273, 16256.756148657543, 16262.837371626132, 14508.709825803637, 15283.46507872219, 15720.292586833848, 15734.606634834221, 15750.212489375257, 11329.943350283196, 11444.313341199555, 11610.94194529022, 11728.530858765555, 11785.587504437304, 11971.281723096643, 12009.63971080869, 12164.330961797186, 12581.748365032465, 12984.545540906023], "value": 7643.162825385921, "unit": "score"}], "name": "dromaeo_css", "value": 7168.008846969655, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 111.0, 110.0, 107.0, 110.0, 111.0, 110.0, 110.0, 111.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 123.0, 119.0, 172.0, 118.0, 121.0, 146.0, 117.0, 152.0, 116.0], "value": 133.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 159.0, 159.0, 162.0, 160.0, 160.0, 159.0, 160.0, 162.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 105.0, 74.0, 76.0, 75.0, 75.0, 102.0, 76.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 129.0, 85.0, 84.0, 85.0, 129.0, 84.0, 85.0, 144.0], "value": 99.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 118.0, 119.0, 118.0, 118.0, 118.0, 118.0, 118.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 53.0, 51.0, 50.0, 53.0, 51.0, 50.0, 53.0, 52.0], "value": 51.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 61.0, 62.0, 62.0, 61.0, 62.0, 61.0, 62.0], "value": 61.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 79.0, 78.0, 78.0, 80.0, 78.0, 78.0, 78.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 121.0, 120.0, 122.0, 124.0, 129.0, 123.0, 128.0, 138.0, 126.0], "value": 126.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 164.0, 163.0, 167.0, 164.0, 166.0, 167.0, 161.0, 165.0, 163.0], "value": 164.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 64.0, 62.0, 64.0, 63.0, 65.0, 64.0, 63.0, 63.0], "value": 64.1, "unit": "score"}], "name": "kraken", "value": 1489.4999999999998, "alertThreshold": 2.0}]} 16:32:46 INFO - Return code: 0 16:32:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:32:46 INFO - # TBPL SUCCESS # 16:32:46 INFO - Running post-action listener: _resource_record_post_action 16:32:46 INFO - [mozharness: 2016-08-17 23:32:46.593663Z] Finished run-tests step (success) 16:32:46 INFO - Running post-run listener: _resource_record_post_run 16:32:46 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 49152; Write bytes: 426545152; Read time: 68; Write time: 324768 16:32:46 INFO - TinderboxPrint: CPU usage
13.0% 16:32:46 INFO - TinderboxPrint: I/O read bytes / time
49,152 / 68 16:32:46 INFO - TinderboxPrint: I/O write bytes / time
426,545,152 / 324,768 16:32:46 INFO - TinderboxPrint: CPU idle
8,286.9 (86.9%) 16:32:46 INFO - TinderboxPrint: CPU user
1,197.2 (12.6%) 16:32:46 INFO - TinderboxPrint: Swap in / out
0 / 0 16:32:46 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 134397952; Read time: 0; Write time: 108240 16:32:46 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 49152; Write bytes: 291532800; Read time: 68; Write time: 216528 16:32:47 INFO - Running post-run listener: _upload_blobber_files 16:32:47 INFO - Blob upload gear active. 16:32:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:32:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:32:47 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:32:47 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:32:47 INFO - (blobuploader) - INFO - Open directory for files ... 16:32:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:32:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:32:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:51 INFO - (blobuploader) - INFO - Done attempting. 16:32:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:32:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:32:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:51 INFO - (blobuploader) - INFO - Done attempting. 16:32:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:32:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:32:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:52 INFO - (blobuploader) - INFO - Done attempting. 16:32:52 INFO - (blobuploader) - INFO - Iteration through files over. 16:32:52 INFO - Return code: 0 16:32:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:32:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:32:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/64525067bcb39bb0ce60d58ed36d8c6d5026b9bf2c51f7792561e29df346ab81260295e02ba64e7c18be0a7d85d664496da045ca961ac0e84409a75d87f54974", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/73bcec0ef05a6491b246f3e02a62a2f4c5d4f17e75cd77850349617d640b9b00ba38272343691f1f568ac898d0cb701adb62dc01b1781781bfeee82fb96d49ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3dec3238fa66b7b521eb94b15a4fb2cdb58228cf7a53e90427be99446824a25be3383b44993d9f58c551d9ca777fb91b22db56a5ad284bbd6670f9cf70deebe6"} 16:32:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:32:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:32:52 INFO - Contents: 16:32:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/64525067bcb39bb0ce60d58ed36d8c6d5026b9bf2c51f7792561e29df346ab81260295e02ba64e7c18be0a7d85d664496da045ca961ac0e84409a75d87f54974", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/73bcec0ef05a6491b246f3e02a62a2f4c5d4f17e75cd77850349617d640b9b00ba38272343691f1f568ac898d0cb701adb62dc01b1781781bfeee82fb96d49ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3dec3238fa66b7b521eb94b15a4fb2cdb58228cf7a53e90427be99446824a25be3383b44993d9f58c551d9ca777fb91b22db56a5ad284bbd6670f9cf70deebe6"} 16:32:52 INFO - Running post-run listener: copy_logs_to_upload_dir 16:32:52 INFO - Copying logs to upload dir... 16:32:52 INFO - mkdir: /builds/slave/test/build/upload/logs 16:32:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.202627 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-17 16:32:52.928543) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 16:32:52.932055) ========= 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=1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/64525067bcb39bb0ce60d58ed36d8c6d5026b9bf2c51f7792561e29df346ab81260295e02ba64e7c18be0a7d85d664496da045ca961ac0e84409a75d87f54974", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/73bcec0ef05a6491b246f3e02a62a2f4c5d4f17e75cd77850349617d640b9b00ba38272343691f1f568ac898d0cb701adb62dc01b1781781bfeee82fb96d49ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3dec3238fa66b7b521eb94b15a4fb2cdb58228cf7a53e90427be99446824a25be3383b44993d9f58c551d9ca777fb91b22db56a5ad284bbd6670f9cf70deebe6"} build_url:https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011446 build_url: 'https://queue.taskcluster.net/v1/task/dnfTDhoMT4qKaSW0l98Q7A/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/Fx-Team/sha512/64525067bcb39bb0ce60d58ed36d8c6d5026b9bf2c51f7792561e29df346ab81260295e02ba64e7c18be0a7d85d664496da045ca961ac0e84409a75d87f54974", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/73bcec0ef05a6491b246f3e02a62a2f4c5d4f17e75cd77850349617d640b9b00ba38272343691f1f568ac898d0cb701adb62dc01b1781781bfeee82fb96d49ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3dec3238fa66b7b521eb94b15a4fb2cdb58228cf7a53e90427be99446824a25be3383b44993d9f58c551d9ca777fb91b22db56a5ad284bbd6670f9cf70deebe6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 16:32:52.957343) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 16:32:52.957671) ========= 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=1f5564757af27664ca02d71f000001b0-1471475465.632618-1021586138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004764 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 16:32:53.043570) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 16:32:53.043887) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 16:32:53.044213) ========= ========= Total master_lag: 0.20 =========