builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-004 starttime: 1470872211.04 results: success (0) buildid: 20160810151321 builduid: 31a4f794429742fb820602a891ed21ff revision: 0c4f81bc2b52a7690436569452e6388c54f9ec96 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 16:36:51.036571) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 16:36:51.036942) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 16:36:51.037220) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 16:36:51.057662) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 16:36:51.057916) ========= 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=c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-10 16:36:51-- 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% 66.9M=0s 2016-08-10 16:36:51 (66.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.399059 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 16:36:51.470312) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 16:36:51.470587) ========= 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=c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016942 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 16:36:51.520739) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 16:36:51.521043) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0c4f81bc2b52a7690436569452e6388c54f9ec96 --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 0c4f81bc2b52a7690436569452e6388c54f9ec96 --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=c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-10 16:36:51,979 truncating revision to first 12 chars 2016-08-10 16:36:51,980 Setting DEBUG logging. 2016-08-10 16:36:51,980 attempt 1/10 2016-08-10 16:36:51,980 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0c4f81bc2b52?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-10 16:36:52,320 unpacking tar archive at: fx-team-0c4f81bc2b52/testing/mozharness/ program finished with exit code 0 elapsedTime=0.684398 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 16:36:52.219017) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 16:36:52.219344) ========= script_repo_revision: 0c4f81bc2b52a7690436569452e6388c54f9ec96 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 16:36:52.219754) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 16:36:52.220046) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 16:36:52.229722) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-08-10 16:36:52.230064) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:36:52 INFO - MultiFileLogger online at 20160810 16:36:52 in /builds/slave/test 16:36:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 16:36:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:36:52 INFO - {'append_to_log': False, 16:36:52 INFO - 'base_work_dir': '/builds/slave/test', 16:36:52 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 16:36:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:36:52 INFO - 'branch': 'Fx-Team-Non-PGO', 16:36:52 INFO - 'buildbot_json_path': 'buildprops.json', 16:36:52 INFO - 'config_files': ('talos/linux_config.py',), 16:36:52 INFO - 'default_actions': ('clobber', 16:36:52 INFO - 'read-buildbot-config', 16:36:52 INFO - 'download-and-extract', 16:36:52 INFO - 'populate-webroot', 16:36:52 INFO - 'create-virtualenv', 16:36:52 INFO - 'install', 16:36:52 INFO - 'run-tests'), 16:36:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:36:52 INFO - 'download_minidump_stackwalk': True, 16:36:52 INFO - 'download_symbols': 'ondemand', 16:36:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:36:52 INFO - 'tooltool.py': '/tools/tooltool.py', 16:36:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:36:52 INFO - '/tools/misc-python/virtualenv.py')}, 16:36:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:36:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:36:52 INFO - 'installer_path': 'installer.exe', 16:36:52 INFO - 'log_level': 'info', 16:36:52 INFO - 'log_name': 'talos', 16:36:52 INFO - 'log_to_console': True, 16:36:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:36:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:36:52 INFO - 'opt_config_files': (), 16:36:52 INFO - 'pip_index': False, 16:36:52 INFO - 'sps_profile': False, 16:36:52 INFO - 'sps_profile_interval': 0, 16:36:52 INFO - 'suite': 'dromaeojs', 16:36:52 INFO - 'system_bits': '32', 16:36:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:36:52 INFO - 'title': 'talos-linux64-ix-004', 16:36:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:36:52 INFO - 'use_talos_json': True, 16:36:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:36:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:36:52 INFO - 'work_dir': 'build'} 16:36:52 INFO - [mozharness: 2016-08-10 23:36:52.778189Z] Running clobber step. 16:36:52 INFO - Running pre-action listener: _resource_record_pre_action 16:36:52 INFO - Running main action method: clobber 16:36:52 INFO - rmtree: /builds/slave/test/build 16:36:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:36:53 INFO - Running post-action listener: _resource_record_post_action 16:36:53 INFO - [mozharness: 2016-08-10 23:36:53.238708Z] Finished clobber step (success) 16:36:53 INFO - [mozharness: 2016-08-10 23:36:53.238808Z] Running read-buildbot-config step. 16:36:53 INFO - Running pre-action listener: _resource_record_pre_action 16:36:53 INFO - Running main action method: read_buildbot_config 16:36:53 INFO - Using buildbot properties: 16:36:53 INFO - { 16:36:53 INFO - "project": "", 16:36:53 INFO - "product": "firefox", 16:36:53 INFO - "script_repo_revision": "production", 16:36:53 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 16:36:53 INFO - "repository": "", 16:36:53 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 16:36:53 INFO - "buildid": "20160810151321", 16:36:53 INFO - "pgo_build": "False", 16:36:53 INFO - "basedir": "/builds/slave/test", 16:36:53 INFO - "buildnumber": 1863, 16:36:53 INFO - "slavename": "talos-linux64-ix-004", 16:36:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:36:53 INFO - "platform": "ubuntu64_hw", 16:36:53 INFO - "scheduler": "tests-fx-team-linux64-talos-7-3600", 16:36:53 INFO - "branch": "fx-team", 16:36:53 INFO - "repo_path": "integration/fx-team", 16:36:53 INFO - "revision": "0c4f81bc2b52a7690436569452e6388c54f9ec96", 16:36:53 INFO - "stage_platform": "linux64", 16:36:53 INFO - "builduid": "31a4f794429742fb820602a891ed21ff", 16:36:53 INFO - "slavebuilddir": "test" 16:36:53 INFO - } 16:36:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 16:36:53 INFO - Running post-action listener: _resource_record_post_action 16:36:53 INFO - [mozharness: 2016-08-10 23:36:53.241250Z] Finished read-buildbot-config step (success) 16:36:53 INFO - [mozharness: 2016-08-10 23:36:53.241351Z] Running download-and-extract step. 16:36:53 INFO - Running pre-action listener: _resource_record_pre_action 16:36:53 INFO - Running main action method: download_and_extract 16:36:53 INFO - mkdir: /builds/slave/test/build/tests 16:36:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:53 INFO - trying https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:36:53 INFO - Downloading https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/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:36:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/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:36:53 INFO - Downloaded 1361 bytes. 16:36:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:36:53 INFO - Using the following test package requirements: 16:36:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:36:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:36:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:36:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:53 INFO - u'jsshell-linux-x86_64.zip'], 16:36:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:36:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:36:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:36:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:36:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:36:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:36:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:36:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:53 INFO - trying https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:36:53 INFO - Downloading https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/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:36:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/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:36:57 INFO - Downloaded 20259477 bytes. 16:36:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:36:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:36:58 INFO - Return code: 0 16:36:58 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:36:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:58 INFO - trying https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:36:58 INFO - Downloading https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/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:36:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/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:36:59 INFO - Downloaded 20259477 bytes. 16:36:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:36:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:37:00 INFO - Return code: 0 16:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:00 INFO - trying https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:37:00 INFO - Downloading https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/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:37:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/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:37:02 INFO - Downloaded 14413719 bytes. 16:37:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:37:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:37:02 INFO - Return code: 0 16:37:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:02 INFO - trying https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:37:02 INFO - Downloading https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/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:37:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/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:37:05 INFO - Downloaded 55621711 bytes. 16:37:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:37:05 INFO - mkdir: /builds/slave/test/properties 16:37:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:37:05 INFO - Writing to file /builds/slave/test/properties/build_url 16:37:05 INFO - Contents: 16:37:05 INFO - build_url:https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:37:06 INFO - Running post-action listener: _resource_record_post_action 16:37:06 INFO - Running post-action listener: set_extra_try_arguments 16:37:06 INFO - [mozharness: 2016-08-10 23:37:06.073753Z] Finished download-and-extract step (success) 16:37:06 INFO - [mozharness: 2016-08-10 23:37:06.073986Z] Running populate-webroot step. 16:37:06 INFO - Running pre-action listener: _resource_record_pre_action 16:37:06 INFO - Running main action method: populate_webroot 16:37:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:37:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:37:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:37:06 INFO - '--tppagecycles', 16:37:06 INFO - '7'], 16:37:06 INFO - 'tests': ['tsvgm']}}, 16:37:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:37:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:37:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:37:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:37:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:37:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:37:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:06 INFO - 'talos_options': ['--disable-e10s'], 16:37:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:37:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:37:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:06 INFO - 'talos_options': ['--disable-e10s'], 16:37:06 INFO - 'tests': ['damp', 'tps']}, 16:37:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:06 INFO - 'tests': ['damp', 'tps']}, 16:37:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:37:06 INFO - 'tests': ['dromaeo_dom']}, 16:37:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:37:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:37:06 INFO - 'tests': ['basic_compositor_video']}, 16:37:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:37:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:37:06 INFO - 'tests': ['a11yr', 16:37:06 INFO - 'ts_paint', 16:37:06 INFO - 'tpaint', 16:37:06 INFO - 'sessionrestore', 16:37:06 INFO - 'sessionrestore_no_auto_restore', 16:37:06 INFO - 'tabpaint']}, 16:37:06 INFO - 'other-e10s': {'tests': ['a11yr', 16:37:06 INFO - 'ts_paint', 16:37:06 INFO - 'tpaint', 16:37:06 INFO - 'sessionrestore', 16:37:06 INFO - 'sessionrestore_no_auto_restore', 16:37:06 INFO - 'tabpaint']}, 16:37:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:37:06 INFO - 'ts_paint', 16:37:06 INFO - 'tpaint', 16:37:06 INFO - 'sessionrestore', 16:37:06 INFO - 'sessionrestore_no_auto_restore', 16:37:06 INFO - 'tabpaint']}, 16:37:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:37:06 INFO - 'ts_paint', 16:37:06 INFO - 'tpaint', 16:37:06 INFO - 'sessionrestore', 16:37:06 INFO - 'sessionrestore_no_auto_restore', 16:37:06 INFO - 'tabpaint']}, 16:37:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:37:06 INFO - 'tests': ['a11yr', 16:37:06 INFO - 'ts_paint', 16:37:06 INFO - 'tpaint', 16:37:06 INFO - 'sessionrestore', 16:37:06 INFO - 'sessionrestore_no_auto_restore', 16:37:06 INFO - 'tabpaint']}, 16:37:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:37:06 INFO - 'tests': ['a11yr', 16:37:06 INFO - 'ts_paint', 16:37:06 INFO - 'tpaint', 16:37:06 INFO - 'sessionrestore', 16:37:06 INFO - 'sessionrestore_no_auto_restore', 16:37:06 INFO - 'tabpaint']}, 16:37:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:37:06 INFO - 'tests': ['tsvgx', 16:37:06 INFO - 'tsvgr_opacity', 16:37:06 INFO - 'tart', 16:37:06 INFO - 'tscrollx', 16:37:06 INFO - 'cart']}, 16:37:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:37:06 INFO - 'tsvgr_opacity', 16:37:06 INFO - 'tart', 16:37:06 INFO - 'tscrollx', 16:37:06 INFO - 'cart']}, 16:37:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:06 INFO - 'talos_options': ['--disable-e10s'], 16:37:06 INFO - 'tests': ['tp5o']}, 16:37:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:06 INFO - 'tests': ['tp5o']}, 16:37:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:06 INFO - 'talos_options': ['--disable-e10s', 16:37:06 INFO - '--xperf_path', 16:37:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:37:06 INFO - 'tests': ['tp5n']}, 16:37:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:06 INFO - 'talos_options': ['--xperf_path', 16:37:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:37:06 INFO - 'tests': ['tp5n']}}, 16:37:06 INFO - 'talos.zip': {'path': '', 16:37:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:37:06 INFO - Running post-action listener: _resource_record_post_action 16:37:06 INFO - [mozharness: 2016-08-10 23:37:06.094503Z] Finished populate-webroot step (success) 16:37:06 INFO - [mozharness: 2016-08-10 23:37:06.094592Z] Running create-virtualenv step. 16:37:06 INFO - Running pre-action listener: _resource_record_pre_action 16:37:06 INFO - Running main action method: create_virtualenv 16:37:06 INFO - Creating virtualenv /builds/slave/test/build/venv 16:37:06 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:37:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:37:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:37:06 INFO - Using real prefix '/usr' 16:37:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:37:06 INFO - Installing distribute.............................................................................................................................................................................................done. 16:37:08 INFO - Installing pip.................done. 16:37:08 INFO - Return code: 0 16:37:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:08 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 0xbcf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe90180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe8e3b0>, '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 0xe8f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe8f740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', '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:37:08 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:37:08 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:37:08 INFO - Using env: {'DISPLAY': ':0', 16:37:08 INFO - 'HOME': '/home/cltbld', 16:37:08 INFO - 'LANG': 'en_US.UTF-8', 16:37:08 INFO - 'LANGUAGE': 'en_US:en', 16:37:08 INFO - 'LOGNAME': 'cltbld', 16:37:08 INFO - 'MAIL': '/var/mail/cltbld', 16:37:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:08 INFO - 'MOZ_NO_REMOTE': '1', 16:37:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:08 INFO - 'NO_EM_RESTART': '1', 16:37:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:08 INFO - 'PWD': '/builds/slave/test', 16:37:08 INFO - 'SHELL': '/bin/bash', 16:37:08 INFO - 'SHLVL': '1', 16:37:08 INFO - 'TERM': 'linux', 16:37:08 INFO - 'TMOUT': '86400', 16:37:08 INFO - 'USER': 'cltbld', 16:37:08 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', 16:37:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:08 INFO - '_': '/tools/buildbot/bin/python'} 16:37:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:08 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:37:08 INFO - Cleaning up... 16:37:08 INFO - Return code: 0 16:37:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:08 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 0xbcf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe90180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe8e3b0>, '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 0xe8f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe8f740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', '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:37:08 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:37:08 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:37:08 INFO - Using env: {'DISPLAY': ':0', 16:37:08 INFO - 'HOME': '/home/cltbld', 16:37:08 INFO - 'LANG': 'en_US.UTF-8', 16:37:08 INFO - 'LANGUAGE': 'en_US:en', 16:37:08 INFO - 'LOGNAME': 'cltbld', 16:37:08 INFO - 'MAIL': '/var/mail/cltbld', 16:37:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:08 INFO - 'MOZ_NO_REMOTE': '1', 16:37:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:08 INFO - 'NO_EM_RESTART': '1', 16:37:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:08 INFO - 'PWD': '/builds/slave/test', 16:37:08 INFO - 'SHELL': '/bin/bash', 16:37:08 INFO - 'SHLVL': '1', 16:37:08 INFO - 'TERM': 'linux', 16:37:08 INFO - 'TMOUT': '86400', 16:37:08 INFO - 'USER': 'cltbld', 16:37:08 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', 16:37:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:08 INFO - '_': '/tools/buildbot/bin/python'} 16:37:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:09 INFO - Downloading/unpacking psutil>=3.1.1 16:37:09 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:37:09 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:37:09 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:37:09 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:37:09 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:37:09 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:37:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:37:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:37:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:37:11 INFO - Installing collected packages: psutil 16:37:11 INFO - Running setup.py install for psutil 16:37:11 INFO - building 'psutil._psutil_linux' extension 16:37:11 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:37:11 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:37:11 INFO - building 'psutil._psutil_posix' extension 16:37:11 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:37:11 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:37:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:37:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:37:11 INFO - Successfully installed psutil 16:37:11 INFO - Cleaning up... 16:37:11 INFO - Return code: 0 16:37:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:37:11 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:37:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:11 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:37:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:11 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 0xbcf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe90180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe8e3b0>, '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 0xe8f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe8f740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', '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:37:11 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:37:11 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:37:11 INFO - Using env: {'DISPLAY': ':0', 16:37:11 INFO - 'HOME': '/home/cltbld', 16:37:11 INFO - 'LANG': 'en_US.UTF-8', 16:37:11 INFO - 'LANGUAGE': 'en_US:en', 16:37:11 INFO - 'LOGNAME': 'cltbld', 16:37:11 INFO - 'MAIL': '/var/mail/cltbld', 16:37:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:11 INFO - 'MOZ_NO_REMOTE': '1', 16:37:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:11 INFO - 'NO_EM_RESTART': '1', 16:37:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:11 INFO - 'PWD': '/builds/slave/test', 16:37:11 INFO - 'SHELL': '/bin/bash', 16:37:11 INFO - 'SHLVL': '1', 16:37:11 INFO - 'TERM': 'linux', 16:37:11 INFO - 'TMOUT': '86400', 16:37:11 INFO - 'USER': 'cltbld', 16:37:11 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', 16:37:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:11 INFO - '_': '/tools/buildbot/bin/python'} 16:37:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:37:11 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:37:11 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:37:11 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:37:11 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:37:11 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:37:11 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:37:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:37:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:37:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:37:14 INFO - Installing collected packages: mozsystemmonitor 16:37:14 INFO - Running setup.py install for mozsystemmonitor 16:37:14 INFO - Successfully installed mozsystemmonitor 16:37:14 INFO - Cleaning up... 16:37:14 INFO - Return code: 0 16:37:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:37:14 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:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:14 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:37:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:14 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 0xbcf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe90180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe8e3b0>, '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 0xe8f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe8f740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', '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:37:14 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:37:14 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:37:14 INFO - Using env: {'DISPLAY': ':0', 16:37:14 INFO - 'HOME': '/home/cltbld', 16:37:14 INFO - 'LANG': 'en_US.UTF-8', 16:37:14 INFO - 'LANGUAGE': 'en_US:en', 16:37:14 INFO - 'LOGNAME': 'cltbld', 16:37:14 INFO - 'MAIL': '/var/mail/cltbld', 16:37:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:14 INFO - 'MOZ_NO_REMOTE': '1', 16:37:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:14 INFO - 'NO_EM_RESTART': '1', 16:37:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:14 INFO - 'PWD': '/builds/slave/test', 16:37:14 INFO - 'SHELL': '/bin/bash', 16:37:14 INFO - 'SHLVL': '1', 16:37:14 INFO - 'TERM': 'linux', 16:37:14 INFO - 'TMOUT': '86400', 16:37:14 INFO - 'USER': 'cltbld', 16:37:14 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', 16:37:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:14 INFO - '_': '/tools/buildbot/bin/python'} 16:37:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:15 INFO - Downloading/unpacking jsonschema==2.5.1 16:37:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:37:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:18 INFO - Downloading functools32-3.2.3-2.tar.gz 16:37:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:37:18 INFO - warning: no files found matching '*.txt' 16:37:18 INFO - no previously-included directories found matching 'build' 16:37:18 INFO - no previously-included directories found matching 'dist' 16:37:18 INFO - no previously-included directories found matching '.git*' 16:37:18 INFO - Installing collected packages: jsonschema, functools32 16:37:18 INFO - Running setup.py install for functools32 16:37:18 INFO - warning: no files found matching '*.txt' 16:37:18 INFO - no previously-included directories found matching 'build' 16:37:18 INFO - no previously-included directories found matching 'dist' 16:37:18 INFO - no previously-included directories found matching '.git*' 16:37:18 INFO - Successfully installed jsonschema functools32 16:37:18 INFO - Cleaning up... 16:37:18 INFO - Return code: 0 16:37:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:37:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xbcf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe90180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe8e3b0>, '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 0xe8f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe8f740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', '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:37:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:37:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:37:18 INFO - Using env: {'DISPLAY': ':0', 16:37:18 INFO - 'HOME': '/home/cltbld', 16:37:18 INFO - 'LANG': 'en_US.UTF-8', 16:37:18 INFO - 'LANGUAGE': 'en_US:en', 16:37:18 INFO - 'LOGNAME': 'cltbld', 16:37:18 INFO - 'MAIL': '/var/mail/cltbld', 16:37:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:18 INFO - 'MOZ_NO_REMOTE': '1', 16:37:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:18 INFO - 'NO_EM_RESTART': '1', 16:37:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:18 INFO - 'PWD': '/builds/slave/test', 16:37:18 INFO - 'SHELL': '/bin/bash', 16:37:18 INFO - 'SHLVL': '1', 16:37:18 INFO - 'TERM': 'linux', 16:37:18 INFO - 'TMOUT': '86400', 16:37:18 INFO - 'USER': 'cltbld', 16:37:18 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', 16:37:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:18 INFO - '_': '/tools/buildbot/bin/python'} 16:37:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:37:18 INFO - Cleaning up... 16:37:18 INFO - Return code: 0 16:37:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:37:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xbcf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe90180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe8e3b0>, '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 0xe8f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe8f740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', '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:37:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:37:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:37:18 INFO - Using env: {'DISPLAY': ':0', 16:37:18 INFO - 'HOME': '/home/cltbld', 16:37:18 INFO - 'LANG': 'en_US.UTF-8', 16:37:18 INFO - 'LANGUAGE': 'en_US:en', 16:37:18 INFO - 'LOGNAME': 'cltbld', 16:37:18 INFO - 'MAIL': '/var/mail/cltbld', 16:37:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:18 INFO - 'MOZ_NO_REMOTE': '1', 16:37:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:18 INFO - 'NO_EM_RESTART': '1', 16:37:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:18 INFO - 'PWD': '/builds/slave/test', 16:37:18 INFO - 'SHELL': '/bin/bash', 16:37:18 INFO - 'SHLVL': '1', 16:37:18 INFO - 'TERM': 'linux', 16:37:18 INFO - 'TMOUT': '86400', 16:37:18 INFO - 'USER': 'cltbld', 16:37:18 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', 16:37:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:18 INFO - '_': '/tools/buildbot/bin/python'} 16:37:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:18 INFO - Downloading/unpacking blobuploader==1.2.4 16:37:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:18 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:37:18 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:37:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:23 INFO - Downloading blobuploader-1.2.4.tar.gz 16:37:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:37:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:37:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:37:25 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:37:25 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:37:25 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:37:25 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:37:25 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:37:25 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:37:25 INFO - Downloading docopt-0.6.1.tar.gz 16:37:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:37:25 INFO - Installing collected packages: blobuploader, requests, docopt 16:37:25 INFO - Running setup.py install for blobuploader 16:37:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:37:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:37:25 INFO - Running setup.py install for requests 16:37:26 INFO - Running setup.py install for docopt 16:37:26 INFO - Successfully installed blobuploader requests docopt 16:37:26 INFO - Cleaning up... 16:37:26 INFO - Return code: 0 16:37:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:37:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:26 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 0xbcf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe90180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe8e3b0>, '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 0xe8f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe8f740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', '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:37:26 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:37:26 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:37:26 INFO - Using env: {'DISPLAY': ':0', 16:37:26 INFO - 'HOME': '/home/cltbld', 16:37:26 INFO - 'LANG': 'en_US.UTF-8', 16:37:26 INFO - 'LANGUAGE': 'en_US:en', 16:37:26 INFO - 'LOGNAME': 'cltbld', 16:37:26 INFO - 'MAIL': '/var/mail/cltbld', 16:37:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:26 INFO - 'MOZ_NO_REMOTE': '1', 16:37:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:26 INFO - 'NO_EM_RESTART': '1', 16:37:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:26 INFO - 'PWD': '/builds/slave/test', 16:37:26 INFO - 'SHELL': '/bin/bash', 16:37:26 INFO - 'SHLVL': '1', 16:37:26 INFO - 'TERM': 'linux', 16:37:26 INFO - 'TMOUT': '86400', 16:37:26 INFO - 'USER': 'cltbld', 16:37:26 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', 16:37:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:26 INFO - '_': '/tools/buildbot/bin/python'} 16:37:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:37:26 INFO - Running setup.py (path:/tmp/pip-nkrEla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:37:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:37:26 INFO - Running setup.py (path:/tmp/pip-4U9Otx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:37:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:37:26 INFO - Running setup.py (path:/tmp/pip-YAkweh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:37:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:37:26 INFO - Running setup.py (path:/tmp/pip-pAb3yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:37:27 INFO - Running setup.py (path:/tmp/pip-mENGxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:37:27 INFO - Running setup.py (path:/tmp/pip-otiL27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:37:27 INFO - Running setup.py (path:/tmp/pip-66KmpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:37:27 INFO - Running setup.py (path:/tmp/pip-hao15f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:37:27 INFO - Running setup.py (path:/tmp/pip-FOBGP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:37:27 INFO - Running setup.py (path:/tmp/pip-QSCmsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:37:27 INFO - Running setup.py (path:/tmp/pip-km3aGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:37:27 INFO - Running setup.py (path:/tmp/pip-sqj6Km-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:37:27 INFO - Running setup.py (path:/tmp/pip-5JRdgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:37:27 INFO - Running setup.py (path:/tmp/pip-4rFw5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:37:27 INFO - Running setup.py (path:/tmp/pip-6v7rEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:37:27 INFO - Running setup.py (path:/tmp/pip-1IaYcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:37:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:37:27 INFO - Running setup.py (path:/tmp/pip-JjbsNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:37:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:37:27 INFO - Running setup.py install for manifestparser 16:37:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:37:28 INFO - Running setup.py install for mozcrash 16:37:28 INFO - Running setup.py install for mozdebug 16:37:28 INFO - Running setup.py install for mozdevice 16:37:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:37:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:37:28 INFO - Running setup.py install for mozfile 16:37:28 INFO - Running setup.py install for mozhttpd 16:37:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:37:28 INFO - Running setup.py install for mozinfo 16:37:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:37:28 INFO - Running setup.py install for mozInstall 16:37:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:37:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:37:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:37:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:37:28 INFO - Running setup.py install for mozleak 16:37:28 INFO - Running setup.py install for mozlog 16:37:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:37:28 INFO - Running setup.py install for moznetwork 16:37:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:37:29 INFO - Running setup.py install for mozprocess 16:37:29 INFO - Running setup.py install for mozprofile 16:37:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:37:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:37:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:37:29 INFO - Running setup.py install for mozrunner 16:37:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:37:29 INFO - Running setup.py install for mozscreenshot 16:37:29 INFO - Running setup.py install for moztest 16:37:29 INFO - Running setup.py install for mozversion 16:37:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:37:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:37:29 INFO - Cleaning up... 16:37:29 INFO - Return code: 0 16:37:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:29 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 0xbcf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe90180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe8e3b0>, '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 0xe8f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe8f740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', '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:37:29 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:37:29 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:37:29 INFO - Using env: {'DISPLAY': ':0', 16:37:29 INFO - 'HOME': '/home/cltbld', 16:37:29 INFO - 'LANG': 'en_US.UTF-8', 16:37:29 INFO - 'LANGUAGE': 'en_US:en', 16:37:29 INFO - 'LOGNAME': 'cltbld', 16:37:29 INFO - 'MAIL': '/var/mail/cltbld', 16:37:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:29 INFO - 'MOZ_NO_REMOTE': '1', 16:37:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:29 INFO - 'NO_EM_RESTART': '1', 16:37:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:29 INFO - 'PWD': '/builds/slave/test', 16:37:29 INFO - 'SHELL': '/bin/bash', 16:37:29 INFO - 'SHLVL': '1', 16:37:29 INFO - 'TERM': 'linux', 16:37:29 INFO - 'TMOUT': '86400', 16:37:29 INFO - 'USER': 'cltbld', 16:37:29 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', 16:37:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:29 INFO - '_': '/tools/buildbot/bin/python'} 16:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:37:29 INFO - Running setup.py (path:/tmp/pip-cwdVYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:37:29 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:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:37:29 INFO - Running setup.py (path:/tmp/pip-U6Nduo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:37:30 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:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:37:30 INFO - Running setup.py (path:/tmp/pip-NCLRd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:37:30 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:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:37:30 INFO - Running setup.py (path:/tmp/pip-M3g5i1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:37:30 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:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:37:30 INFO - Running setup.py (path:/tmp/pip-hvb_s6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:37:30 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:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:37:30 INFO - Running setup.py (path:/tmp/pip-ggiHxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:37:30 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:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:37:30 INFO - Running setup.py (path:/tmp/pip-mToFBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:37:30 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:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:37:30 INFO - Running setup.py (path:/tmp/pip-1Q4rj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:37:30 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:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:37:30 INFO - Running setup.py (path:/tmp/pip-XFtmGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:37:30 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:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:37:30 INFO - Running setup.py (path:/tmp/pip-AWTQsa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:37:30 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:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:37:30 INFO - Running setup.py (path:/tmp/pip-nrhdN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:37:30 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:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:37:30 INFO - Running setup.py (path:/tmp/pip-cuMusz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:37:30 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:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:37:30 INFO - Running setup.py (path:/tmp/pip-DVSWHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:37:30 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:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:37:30 INFO - Running setup.py (path:/tmp/pip-N1fAmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:37:30 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:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:37:30 INFO - Running setup.py (path:/tmp/pip-sYfVMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:37:31 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:37:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:37:31 INFO - Running setup.py (path:/tmp/pip-GsYaYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:37:31 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:37:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:37:31 INFO - Running setup.py (path:/tmp/pip-9ty7Mk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:37:31 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:37:31 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:37:31 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:37:31 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:37:31 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:37:31 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:37:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:37:31 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:37:31 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:37:31 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:37:31 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:37:31 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:37:31 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:37:34 INFO - Downloading blessings-1.6.tar.gz 16:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:37:34 INFO - Installing collected packages: blessings 16:37:34 INFO - Running setup.py install for blessings 16:37:34 INFO - Successfully installed blessings 16:37:34 INFO - Cleaning up... 16:37:34 INFO - Return code: 0 16:37:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:37:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:37:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:37:35 INFO - Reading from file tmpfile_stdout 16:37:35 INFO - Current package versions: 16:37:35 INFO - argparse == 1.2.1 16:37:35 INFO - blessings == 1.6 16:37:35 INFO - blobuploader == 1.2.4 16:37:35 INFO - docopt == 0.6.1 16:37:35 INFO - functools32 == 3.2.3-2 16:37:35 INFO - jsonschema == 2.5.1 16:37:35 INFO - manifestparser == 1.1 16:37:35 INFO - mozInstall == 1.12 16:37:35 INFO - mozcrash == 0.17 16:37:35 INFO - mozdebug == 0.1 16:37:35 INFO - mozdevice == 0.48 16:37:35 INFO - mozfile == 1.2 16:37:35 INFO - mozhttpd == 0.7 16:37:35 INFO - mozinfo == 0.9 16:37:35 INFO - mozleak == 0.1 16:37:35 INFO - mozlog == 3.3 16:37:35 INFO - moznetwork == 0.27 16:37:35 INFO - mozprocess == 0.23 16:37:35 INFO - mozprofile == 0.28 16:37:35 INFO - mozrunner == 6.12 16:37:35 INFO - mozscreenshot == 0.1 16:37:35 INFO - mozsystemmonitor == 0.3 16:37:35 INFO - moztest == 0.7 16:37:35 INFO - mozversion == 1.4 16:37:35 INFO - psutil == 3.1.1 16:37:35 INFO - requests == 1.2.3 16:37:35 INFO - wsgiref == 0.1.2 16:37:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:35 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 0xbcf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe90180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe8e3b0>, '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 0xe8f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe8f740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', '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:37:35 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:37:35 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:37:35 INFO - Using env: {'DISPLAY': ':0', 16:37:35 INFO - 'HOME': '/home/cltbld', 16:37:35 INFO - 'LANG': 'en_US.UTF-8', 16:37:35 INFO - 'LANGUAGE': 'en_US:en', 16:37:35 INFO - 'LOGNAME': 'cltbld', 16:37:35 INFO - 'MAIL': '/var/mail/cltbld', 16:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:35 INFO - 'MOZ_NO_REMOTE': '1', 16:37:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:35 INFO - 'NO_EM_RESTART': '1', 16:37:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:35 INFO - 'PWD': '/builds/slave/test', 16:37:35 INFO - 'SHELL': '/bin/bash', 16:37:35 INFO - 'SHLVL': '1', 16:37:35 INFO - 'TERM': 'linux', 16:37:35 INFO - 'TMOUT': '86400', 16:37:35 INFO - 'USER': 'cltbld', 16:37:35 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', 16:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:35 INFO - '_': '/tools/buildbot/bin/python'} 16:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:35 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:37:35 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:37:35 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:37:35 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:37:35 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:37:35 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:37:35 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:37:35 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:37:35 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:37:35 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:37:35 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:37:35 INFO - Cleaning up... 16:37:35 INFO - Return code: 0 16:37:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:35 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 0xbcf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe90180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe8e3b0>, '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 0xe8f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe8f740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', '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:37:35 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:37:35 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:37:35 INFO - Using env: {'DISPLAY': ':0', 16:37:35 INFO - 'HOME': '/home/cltbld', 16:37:35 INFO - 'LANG': 'en_US.UTF-8', 16:37:35 INFO - 'LANGUAGE': 'en_US:en', 16:37:35 INFO - 'LOGNAME': 'cltbld', 16:37:35 INFO - 'MAIL': '/var/mail/cltbld', 16:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:35 INFO - 'MOZ_NO_REMOTE': '1', 16:37:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:35 INFO - 'NO_EM_RESTART': '1', 16:37:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:35 INFO - 'PWD': '/builds/slave/test', 16:37:35 INFO - 'SHELL': '/bin/bash', 16:37:35 INFO - 'SHLVL': '1', 16:37:35 INFO - 'TERM': 'linux', 16:37:35 INFO - 'TMOUT': '86400', 16:37:35 INFO - 'USER': 'cltbld', 16:37:35 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', 16:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:35 INFO - '_': '/tools/buildbot/bin/python'} 16:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:37:35 INFO - Cleaning up... 16:37:35 INFO - Return code: 0 16:37:35 INFO - Running post-action listener: _resource_record_post_action 16:37:35 INFO - Running post-action listener: _start_resource_monitoring 16:37:35 INFO - Starting resource monitoring. 16:37:35 INFO - [mozharness: 2016-08-10 23:37:35.607513Z] Finished create-virtualenv step (success) 16:37:35 INFO - [mozharness: 2016-08-10 23:37:35.607949Z] Running install step. 16:37:35 INFO - Running pre-action listener: _resource_record_pre_action 16:37:35 INFO - Running main action method: install 16:37:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:37:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:37:35 INFO - Reading from file tmpfile_stdout 16:37:35 INFO - Detecting whether we're running mozinstall >=1.0... 16:37:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:37:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:37:35 INFO - Reading from file tmpfile_stdout 16:37:35 INFO - Output received: 16:37:35 INFO - Usage: mozinstall [options] installer 16:37:35 INFO - Options: 16:37:35 INFO - -h, --help show this help message and exit 16:37:35 INFO - -d DEST, --destination=DEST 16:37:35 INFO - Directory to install application into. [default: 16:37:35 INFO - "/builds/slave/test"] 16:37:35 INFO - --app=APP Application being installed. [default: firefox] 16:37:35 INFO - mkdir: /builds/slave/test/build/application 16:37:35 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:37:35 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:37:51 INFO - Reading from file tmpfile_stdout 16:37:51 INFO - Output received: 16:37:51 INFO - /builds/slave/test/build/application/firefox/firefox 16:37:51 INFO - Running post-action listener: _resource_record_post_action 16:37:51 INFO - [mozharness: 2016-08-10 23:37:51.965754Z] Finished install step (success) 16:37:51 INFO - [mozharness: 2016-08-10 23:37:51.966002Z] Running run-tests step. 16:37:51 INFO - Running pre-action listener: _resource_record_pre_action 16:37:51 INFO - Running main action method: run_tests 16:37:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:37:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:37:51 INFO - Python 2.7.3 16:37:51 INFO - Return code: 0 16:37:51 INFO - grabbing minidump binary from tooltool 16:37: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:37:51 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 0xe8e3b0>, '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 0xe8f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe8f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:37:51 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:37:51 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:37:51 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:37:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:37:52 INFO - Return code: 0 16:37:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:37:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:37:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:37:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:37:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:37:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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:37:52 INFO - Using env: {'DISPLAY': ':0', 16:37:52 INFO - 'HOME': '/home/cltbld', 16:37:52 INFO - 'LANG': 'en_US.UTF-8', 16:37:52 INFO - 'LANGUAGE': 'en_US:en', 16:37:52 INFO - 'LOGNAME': 'cltbld', 16:37:52 INFO - 'MAIL': '/var/mail/cltbld', 16:37:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:37:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:52 INFO - 'MOZ_NO_REMOTE': '1', 16:37:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:37:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:52 INFO - 'NO_EM_RESTART': '1', 16:37:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:52 INFO - 'PWD': '/builds/slave/test', 16:37:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:37:52 INFO - 'SHELL': '/bin/bash', 16:37:52 INFO - 'SHLVL': '1', 16:37:52 INFO - 'TERM': 'linux', 16:37:52 INFO - 'TMOUT': '86400', 16:37:52 INFO - 'USER': 'cltbld', 16:37:52 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807', 16:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:52 INFO - '_': '/tools/buildbot/bin/python'} 16:37:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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:37:52 INFO - mozversion application_buildid: 20160810151321 16:37:52 INFO - mozversion application_changeset: 0c4f81bc2b52a7690436569452e6388c54f9ec96 16:37:52 INFO - mozversion application_display_name: Nightly 16:37:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:37:52 INFO - mozversion application_name: Firefox 16:37:52 INFO - mozversion application_remotingname: firefox 16:37:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:37:52 INFO - mozversion application_vendor: Mozilla 16:37:52 INFO - mozversion application_version: 51.0a1 16:37:52 INFO - mozversion platform_buildid: 20160810151321 16:37:52 INFO - mozversion platform_changeset: 0c4f81bc2b52a7690436569452e6388c54f9ec96 16:37:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:37:52 INFO - mozversion platform_version: 51.0a1 16:37:52 INFO - using testdate: 1470872272 16:37:52 INFO - actual date: 1470872272 16:37:52 INFO - starting webserver on 'localhost:47889' 16:37:52 INFO - SUITE-START | Running 2 tests 16:37:52 INFO - TEST-START | dromaeo_css 16:37:52 INFO - operating with platform_type : linux_ 16:37:52 INFO - Initialising browser for dromaeo_css test... 16:37:52 INFO - TEST-INFO | started process 28394 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMyhZOc/profile http://localhost:47889/getInfo.html) 16:37:56 INFO - PROCESS | 28394 | __metrics Screen width/height:1600/1200 16:37:56 INFO - PROCESS | 28394 | colorDepth:24 16:37:56 INFO - PROCESS | 28394 | Browser inner width/height: 1024/697 16:37:56 INFO - PROCESS | 28394 | __metrics 16:37:57 INFO - PROCESS | 28394 | 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:37:57 INFO - PROCESS | 28394 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:37:58 INFO - TEST-INFO | 28394: exit 0 16:37:58 INFO - Browser initialized. 16:37:58 INFO - Running cycle 1/1 for dromaeo_css test... 16:37:58 INFO - TEST-INFO | started process 28476 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMyhZOc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:38:10 INFO - PROCESS | 28476 | RSS: Main: 157392896 16:38:10 INFO - PROCESS | 28476 | 16:39:01 INFO - PROCESS | 28476 | 1470872341227 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:41:14 INFO - PROCESS | 28476 | Cycle 1(1): loaded http://localhost:47889/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47889/tests/dromaeo/cssquery-ext.html) 16:41:15 INFO - PROCESS | 28476 | RSS: Main: 239185920 16:41:15 INFO - PROCESS | 28476 | 16:44:18 INFO - PROCESS | 28476 | Cycle 1(1): loaded http://localhost:47889/tests/dromaeo/cssquery-ext.html (next: http://localhost:47889/tests/dromaeo/cssquery-jquery.html) 16:44:18 INFO - PROCESS | 28476 | RSS: Main: 244473856 16:44:18 INFO - PROCESS | 28476 | 16:47:21 INFO - PROCESS | 28476 | Cycle 1(1): loaded http://localhost:47889/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47889/tests/dromaeo/cssquery-mootools.html) 16:47:21 INFO - PROCESS | 28476 | RSS: Main: 190095360 16:47:21 INFO - PROCESS | 28476 | 16:50:23 INFO - PROCESS | 28476 | Cycle 1(1): loaded http://localhost:47889/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47889/tests/dromaeo/cssquery-prototype.html) 16:50:24 INFO - PROCESS | 28476 | RSS: Main: 186331136 16:50:24 INFO - PROCESS | 28476 | 16:53:27 INFO - PROCESS | 28476 | Cycle 1(1): loaded http://localhost:47889/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47889/tests/dromaeo/cssquery-yui.html) 16:53:28 INFO - PROCESS | 28476 | RSS: Main: 188338176 16:53:28 INFO - PROCESS | 28476 | 16:56:31 INFO - PROCESS | 28476 | Cycle 1(1): loaded http://localhost:47889/tests/dromaeo/cssquery-yui.html (next: http://localhost:47889/tests/dromaeo/cssquery-dojo.html) 16:56:31 INFO - PROCESS | 28476 | RSS: Main: 279818240 16:56:31 INFO - PROCESS | 28476 | 16:56:31 INFO - PROCESS | 28476 | __start_tp_report 16:56:31 INFO - PROCESS | 28476 | _x_x_mozilla_page_load 16:56:31 INFO - PROCESS | 28476 | _x_x_mozilla_page_load_details 16:56:31 INFO - PROCESS | 28476 | |i|pagename|runs| 16:56:31 INFO - PROCESS | 28476 | |0;dojo.html;2642.062067965872;2653.3631928337204;2682.275785537905;2691.3271682079494;2696.3393968477712;4624.982503849148;4700.94228798521;4713.34013238147;4819.95180048199;4836.347093142429;4716.811327546902;4728.3380326754195;4767.427908650958;4768.380110585627;4789.568938795508;4283.464566929134;4301.118270754503;4366.410534577839;4421.646268298545;4463.4420697412825;4397.208502469554;4432.024954510002;4450.755208463534;4473.038296766194;4477;4258.020655249279;4309.741415515079;4340.82636694535;4355.6733245006435;4440.267355886275;4275.850345237902;4276.786160691984;4327.675424343187;4376.6498680105515;4379.715318504319;4261.488621365425;4311.051568654891;4339.110482351142;4346.043870348518;4368.082702632451;4659.860204193879;4728.432588089451;4743.02767932573;4769.546893045155;4793.472718001017;5004;5061.44324124346;5067;5077.339945807022;5087.694738315713;5068.873278168038;5123.769430375642;5152.896942061137;5155.432902380735;5178.974105129488;4626.676132670681;4728.243481043017;4799.11216424959;4801.567858892647;4813.590844778233;5221.451747566451;5291.100512912814;5312.335958005249;5319.760610772524;5322.33470816148;5025.3718285214345;5049.192129259374;5050.873728156752;5095.363079615048;5102.591792656653;4964.553190212898;4987.376577927759;5132;5163.225516172604;5194.35070616173;4964.67729597575;5028.145215313405;5050.621203409759;5050.696958182521;5057.924131138036;4964.975175124174;4968.751562421864;4978.726170060681;4986.252062190701;5009.674371165863;4262.850800221977;4275.679324050709;4275.764832934156;4277.700560960703;4307.095509942885;3656.0859785053735;3665.6151104134215;3689.1883785567134;3724.7206459515646;3742.9893928639117;4299.441072660534;4305.935410968838;4350.695451318441;4394.538573449741;4397.2964325707735;5019.573336266385;5065.265536497187;5066.847994560169;5072.797088116507;5099.490050994945;4237.6821738369745;4261.680373971964;4309.181255561433;4316.589923957219;4340.500842403164;4579.129965306581;4579.793909274091;4580.610648094949;4618.168729628632;4630.097131059479;4807.543283388142;4820.517948205292;4824.758762061953;4828.613710903049;4875.95124048762;4809.495003024632;5030.496950304941;5085.084684756779;5142;5199.974000129975;4789.4971028041555;4813.614910807057;4824.179889418878;4876.585490233228;4881.8535443936735;4987.401511818605;5009.198528235464;5018.146915024382;5027.857723597617;5076.568491678248;4859.392575928009;5015.924761128513;5051.898962020812;5074.543291103819;5141.177411614124;4885;4944.208926571731;4952.8018879245865;4976.776045078052;4998.125328067501;4942.728149951643;4946.703197808143;4952;4955.702657840541;5004.6246531511015;4615.699979501322;4873.805047798191;4879.463259041574;4886.438059623189;4908.5091490852055;4991.850244492671;5052.671576347526;5065.366829146356;5085.821996230115;5097.617678674188;4941.75291235444;4942.258661200849;4945.357103576512;4948.752562371939;5004.199328107486 16:56:31 INFO - PROCESS | 28476 | |1;ext.html;549.939506654268;564.6809552602779;570.9978986878028;575.3354875119239;576.8269519144255;38889.80555097224;40789.18421631573;40815.59184408159;40862.59137408625;45122.774386128025;40580.39129413054;40999.59000409995;41054.794726026324;41093.58906410935;41113.7944310278;7745.612719364051;7812.921870781276;7881.502514522251;7887.132415434297;7888.053433587977;7791.532508049507;7878.566678832662;7881.17247688993;7883.3299169570655;7887.171846956073;7849.332806711436;7891.250331218525;7899.052113746358;7902.644381002869;7907.644156012993;7834.72578459754;7861.764147075597;7870.724524641667;7871.330936870373;7871.881921771207;7798.610069496503;7845.333146682538;7847.6860925562905;7853.803654908673;7858.764237072897;37829.62170378316;38177.80911095454;38226.23547529034;38233.808830955946;38250;2834.2630915962;2958.7189217024347;2997.5203967365337;3130.5617213590117;3174.857131429091;27936.161915142377;28116;28139.85930070357;28621.713782862113;29664.70335296641;3168.3980043791835;3307.4377355849565;3503.5795704515367;3541.7874927504176;3584.8207589620415;273353;273775;275570.62214689;275759;276694;31274.21814454657;33236.66763332384;33239.50140747891;54392.72803636036;54922.450775491736;33291.83354083214;33426.665733342845;33553.328933421195;33711.83144084264;33727.32545349079;12964.351782410842;13089.869101308865;13139.211647301192;13148.40832162555;13168.144070635379;10597.20520960931;10685.305455145392;10700.946495267473;10703.946480267548;10721;7784.649690763929;7840.9215907841335;7878.566678832604;7886.250806173404;7925.960370198112;3054.801437906529;3094.1955091676327;3108.4560201964564;3110.9688903111132;3131.8903838366004;833.9041010283773;923.5013092929877;1020.311479252161;1038.3977293169944;1060.9469526523642;27453.72546274512;27547.173584792487;27868.442631147263;28314.575281370795;28475.71524284772;984.5569493727851;993.7267251505893;1007.2043085962008;1048.2976405808126;1060.1359891688248;14612;15262.08427494354;15281;15472;15545.922270388575;388376;391950;395372;395872;397057;32779.836100819346;32980.67019329776;33042.504362434585;33079.00762977114;33095.338093238;1072.5602502973743;1126.7126882645036;1169.7134202120535;1200.771853347861;1209.3227792436087;26419.471610567296;28733.856330718212;29632.703672962994;30366.696333036387;30369.088927332214;41811.7909410451;42368.57631423646;42399.788001059795;42404;42430;308267;310067;310169;310591;310630;20546.17815287372;21998.560028799653;25178;25273.11544095948;25663.615045773953;42257;42367;42460.78769606132;42944.35583466313;44590;37442.25115497729;37678.811605941795;37994.43008354932;38152.80923595475;39002.41496377614;37124.6287537132;37148.81425592855;37524.437133443564;38246;38295.80852095722;32084.358312833145;32797.18007049843;35811.462828057076;37310.253794924494;37621.68324108643;31464.842675786476;33821.83089084637;34108.488372674925;34332.31335373229;34354.65645343515;26182.4763504725;26222;26273;26359;26478.7244117254 16:56:31 INFO - PROCESS | 28476 | |2;jquery.html;319.70906475107654;352.8641473032882;354.98225088745556;355.5644335688781;356.7146282973622;12826.358682065906;16129.596760081004;16771.664566708674;18226.817731822677;18400.447986560426;16345.673086538261;16483;16546.58633534162;16548.83451165488;16659.75010374842;4789.976050119762;5033.874153146164;5106.5659418949435;5145.81989630363;5148.639595228336;3695.9630403696024;3883.8252278647387;5000.549950504454;5086.821961231358;5096.413912400084;3917.2361389529055;3927.9214415711667;5584.1902924076185;5932.733027013774;5966.46301832835;5831.5334773218465;6045.425684559959;6048.2137322148055;6069.544784141163;6147.44672979434;5423.05096608092;5424.349078110613;5430.647007944471;5432.592555558349;5434.456554344573;19302.806971930382;20767.792322076737;21425.67861482079;21603.67594486084;21790.782092179194;1851.7777866655954;1913.4642300155979;1976.505873531617;1986.8509861760426;2388.2238272561485;9175.58709858058;9382.202512786353;9606.087421694929;9778.315517913748;9829.164521015722;1665.916704164799;1690.475952454743;1744.520256929342;1759.26111033365;1759.9472015839544;65766.67116664434;69660.3033969664;70344;70701.29298706999;71478;10047.748806279902;10095.545700443497;10295.433751143759;10488.475576221159;10696.251262411557;14346.139231646135;14365.712685746226;14366.497172598909;14372.281385930663;14379.85620143806;6905.1713794344;7153.3561979421065;7187.7843664690145;7333.266673332624;7337.963310183415;6658.667066646648;6691.498137639696;6839.794806155824;6847.554908931003;6848.794536163923;4423.800928958204;4477.977610111929;4479.126570318822;4604.953950460453;4613.95386046142;1809.276289484217;1847.159542408201;1851.0559614596652;1861.9534511637046;1914.664933636608;847.3771777743353;848.4145939301862;866.1035827918074;870.5125129927135;894.4454438248328;16781;17434.953902765876;17905.28378864857;17996.460106196835;18047.63904721898;832.076395201326;833.8499070167428;833.9332853371716;838.2581415447252;842.5744998775579;8990.505522196341;9979.501024948724;10041.347312424816;10056.748581285527;10079.748006299902;385953;391587;393615;396235;396956;76484.23515764771;77538;78000.60999694966;78527.21472785313;78882;906.0305473143673;916.6012784438657;938.4228699349851;945.6926498887889;953.3136141977903;15424.922875385551;15886.60283492922;15979.360825566848;16364.67270654604;16567.751483727996;38031.80984095062;38107.42838857364;38394.23211535698;38583.61416385912;39192.41211381774;50683.23975140302;50814;51010.23484647659;51250.23124653059;52982.73508632586;7788.961055194688;7852.842943140991;7895.486793358414;8130.065042519949;8502.362322825937;11501.942490287494;11519.25124866881;11744.355790189209;12527.31099789484;12528;10485.26603137773;10501.684949451528;10813.134949204203;10989.395583242998;11138.164637652371;10390.272680912263;10456.63401780934;10765.084967777826;10949.069329107115;11151;10771.461426928465;10865.456727163768;10985.450727463434;11023.503942322453;11052.226344156154;7780.766577002699;7947.165547617417;7949.165337639464;8031.397645176752;8213.137620549756;8826.338024648048;10073.899261007296;10403.739906502398;10457.477126143815;10515.474226288501 16:56:31 INFO - PROCESS | 28476 | |3;mootools.html;1279.8528169260537;1390.1242217403035;1409.0136904167082;1491.701659668066;1707.3597400974627;6555.573887697302;7554.169041405441;7803.453758236926;7822.843543129141;8031.277185053344;7914.643841027153;7989.280964713174;8005.63974621142;8007.63965621547;8009.519428834259;2173.5544213436287;2206.293985924505;2207.1061220205843;2207.249535158046;2207.7461091974387;2127.7340332458443;2167.8590891592;2204.34971683353;2204.7023651806962;2205.4376134085865;2152.9031193596325;2175.0973346061364;2175.695402643631;2177.031221106608;2185.2133232036454;2102.810747032767;2108.778578249277;2110.419634600482;2112.039022244875;2122.426944724931;2068.75174979002;2118.809307162363;2121.586290673319;2124.4264048706764;2125.7342832145982;7829.608519574055;7900.2889739923685;7968.760937171836;7986.321162701177;8004.319632831216;4406.383106365111;4580.106879158566;4583.610393116589;4589.609883159936;4590.288505281686;2778.8888444462195;2943.323035701779;2981.0460652591178;2992.2968102495897;3004.549317602377;920.1902325953185;924.8705181274627;926.2589928057527;938.3900464697932;942.4769253064546;10829.404382758945;10991.285566438157;11001.449927503672;11008.779824403548;11016.944915275453;3855.325318069327;3882.3594106972596;4042.6967977401814;4043.4137050128156;4072.1448495815616;8527.658893644184;8612.956935215283;8631.266342360845;8684.261837743736;8689;4538.614217791524;4539.273716205457;4540.160070386989;4540.5913467787395;4541.069080838486;3475.4960530722906;4010.3382941814275;4231.555686652857;4234.301340278881;4239.194553034913;3522.2075033117344;3625.8549658013385;3730.68289195416;3739.7195210358786;3775.7356985011334;1133.195431243826;1159.3623507071245;1165.6328256599145;1173.0556901694217;1179.6461061681462;681.417388133149;710.1265443504415;778.2762031310822;801.5671537369872;834.5952213176605;4935.333729946411;5114.514121158484;5119.923201151912;5278.366596008504;5412.539934105566;371.78064941683994;372.25548902195607;372.3892815782119;375.1147292389958;377.6978417266176;3413.2661477782394;3466.9133271667906;3530.3998320285614;3563.6970857476886;3626.709863210937;9593.376430532133;9828.164606008426;9899.901000990041;9906.851397229048;9912.55393507294;9836.11474967242;9876.209903207726;9879.209663226924;9881.901180988241;9895.703128906;7510.4742668013805;7510.924890751132;7514.211007844097;7550.320471157513;7587.848243035219;5023.497650234947;5206.661566998133;5282.577393808563;5367.785288588413;5485.149801780731;8877.378583499092;8933.865992009994;8978.86531702038;9065.77335566616;9069.50117743504;8908.510031948304;9135.634574617206;9180.311476639412;9283.071692830663;9292.767680807763;5685.800996965087;6098.725557349841;6570;6615.5038372123245;6627.867442651023;6817.454603631599;7080.2919708028785;7086.185088714864;7086.610236437045;7087.645617719197;6386.712597933011;6427.132337134427;6437.291897891322;6523.445507131757;6621.867562648817;6183.659898705614;6293.118963345044;6382.5532212744665;6390.360963903572;6432.710528026157;5817.941820581909;5913.940860591339;5926.407359264039;5927.555433342603;5958.880822383615;4465.910681786411;4480.708753930984;4577.519360467236;4585.931211031904;4642.907141857077;4382.956170438382;4400.141972315496;4503.864884053483;4528.818847246073;4589.449266088091 16:56:31 INFO - PROCESS | 28476 | |4;prototype.html;283.39919370933615;287.1342901153024;288.7339018404297;289.6856910252376;290.3698973228095;4780.856574302772;4822.782974766135;4832.130216561018;4833.419989601252;4837.927431088537;4739.0758802033615;4746.928796068053;4755.571998520132;4765.237561990082;4780.4702495201545;3540.371429142591;3650.7261955353324;3659.1218107654236;3733.9626603733886;3787.9431808522895;3753.86861459849;3757.7369584129124;3777.7166712496537;3780.1305699689087;3783.2811765764554;3729.1982223821874;3738.12154143776;3743.7566558173767;3748.1941382602736;3751.118487155516;3553.5558055243096;3579.94630080549;3674.81625918703;3683.5395575553057;3695.9445608315896;3271.1331497153037;3279.655636158193;3285.291648342872;3286.6549012353835;3291.489819078047;4676.088162808253;4676.719396836201;4683.7892294846815;4697;4706.882327941795;4128.784677864334;4141.875743727693;4175.373693945902;4203.600657937491;4219.831206751726;4928.704277743347;4941.234108713155;4956.479569645207;4960.528749768766;4973.925391119136;3535.9116022099397;3540.6990405815277;3546.202104526487;3546.5744110706623;3551.5738111426535;4959.181735013718;5186.636935414523;5190.688558686491;5194.740262986836;5214.97392513035;3628.5101511296166;3658.5061016762397;3658.70730341572;3660.1215708229834;3664.7434679572707;3691.2617476504806;3696.685781708531;3700.538287376471;3708.1100535871196;3731.477593136963;4216.409702641633;4217.768022758716;4228.60456049503;4240.300350442204;4243.405923170759;4876.82931097417;4927.852164435073;4971.279164521125;4988.6507944444265;5025.818932550847;1270.8602053774077;1279.0790630745848;1279.9504406386673;1284.229462322599;1287.5107459165458;819.4223072733708;823.3166471828368;832.8417600655856;833.3916241143999;852.3564708644933;4266.765327906995;4272.273713468718;4287.663223543176;4371.650267978554;4416.84541041062;5089.465606111305;5095.668781529263;5111.207762796773;5111.974440127776;5115.7442127893455;4310.52584215736;4382.145481631052;4388.912221755547;4412.382266482695;4424.535423780521;2700.5814098814717;2704.932376690559;2716.293763621473;2718.510668079765;2720.1975417251956;4854.441739200037;4855.077535268288;4857.125717370836;4864.173090574611;4871.464138944713;4957.578605818546;5210.931758989398;5253.737313134328;5256.737163141827;5272.551833094153;4819.806987820533;4826.903461930741;4846.95153048465;4851.442084160367;4864.27035944597;5092.8472145835685;5108.719020453762;5117.413601783459;5122.667026643257;5124.666896651705;4751.311059896365;4974.228994505857;5022.296878436949;5025.949740502549;5032.119379108714;4889.266610008527;4894.094592500399;4897.338859254098;4897.461279259207;4918.311436398979;3562.447820587813;3576.338377400189;3576.7853928763316;3597.586277578112;3598.244368682606;5004.799808007639;5093.567046800915;5130.12787826063;5148.124818780741;5149.948500514947;4976.253561965589;4995.400551933792;4997.925031124463;5000.774965126505;5015.1474249378425;4984.130950893415;4992.8751781205765;5017.222330538772;5023.874403139951;5038.370203724535;4772.331873537777;4898.363212782316;4913.213885778258;4932.605391568738;4954.653174277766;4812.783424745825;4860.441049279378;4976.701397916137;5009.173486374853;5015;4918.565310171488;4921.729304888266;4928.211486162197;4936.185529387611;4949.7030178189425 16:56:31 INFO - PROCESS | 28476 | |5;yui.html;650.7462089784983;685.9759908403207;704.1936982155436;725.1877896755634;731.5829976913159;12967;14212.218327991955;14216.57350279492;14229.359678814453;14252.501162459315;11993.280403175793;12005.219660722047;12017.639470815868;12046.57836975706;12056.09579281553;617.8671585609093;747.768191860525;748.2255865179527;778.2373274191295;780.7813812132612;783.2285199078902;786.3787607789839;786.5831109511968;786.6735304848481;786.8937693411402;835.9373047021468;836.2014276366086;836.6736972580699;837.0750320895414;837.2213841127749;661.9635920024397;799.0531220503664;821.2321479416705;823.3495538524559;828.0891019878146;601.2904772368603;604.1300527240759;604.7036951893584;604.8034388823606;605.5518916002171;13336.466541338335;13347.799783003262;13365.86634133656;13367.264800435973;13373.264470454122;12251.448684809206;12491.875081249162;12718;12733.172343797623;13217.008724345713;11714.355710435922;11777.823332650018;11900.761984760256;12008;12102.636920892388;4044.391109876932;4133.91732165358;4138.772367519785;4146.834126634961;4262.978685106555;69337;70414.64792676004;70631.64684176547;70779.64610176967;70833.64583177051;10082.74793130163;10458.372497650165;10573.841392379121;10641.201909856793;10787.67636970892;19859.205631774857;19872.50318742014;19880.90059549693;19885.204591816455;19898.70151947722;6905.551139175937;7069.257727938595;7152.642367881585;7167.677454514455;7174.784756457315;3685.1708365617524;3827.655511004023;3872.9612703873163;3896;4008.6392224699916;2749.8350098994124;2868.942621147565;2874.525703258939;2877.755390791764;2880.6543214813937;450.51344547888203;464.185354702495;464.64686838002683;469.8919248572847;475.31554561431426;449.4022949477253;485.8493866901201;535.7883635963773;578.2020811280406;640.9134766806421;8292.668293268202;8777.69278075277;8840.381173317806;8927.196552310323;8980.236679882284;564.7627996241588;579;616.9691515424211;619.5136817598254;636.2301615045847;4042.6765858731233;4125.401816736557;4153.273177193979;4203.6637069034405;4393.121375724868;102869;105653.88692226112;107311;107693.46153269183;111159.44420278008;90469.54765226132;91379.54310228406;91867.5406622976;92083.53958230166;92882;47461.762691186326;64145.679271603345;71515.64242178756;78284;79612.60193698994;7467.215942325977;7633.389328853789;7768.106667733283;7884.605769711607;8852.822943541221;42042;42708.07813648455;43586.346204806316;44276;46076.539234607226;39980.600193997685;52913;64925.67537162284;65377.67311163604;66194.33805661881;11587.304761714324;11707.239029463057;11866.940665296619;11917.702057448632;12276;13199.670008249486;13282.734345313233;13674.589762307147;14219.50231741884;14667.853321467075;12541.811872821732;12628;12739.171953822972;13466.259355735529;13595.592132236048;12461.813072804096;12906.161099528501;13173.077884547989;13201.669958251121;13761.555036721;11355.602553910574;12239.449224784728;12269.975245310086;12376.876231237573;12623.936880315907;10010.699679009642;10044.347117437343;10338.172946164139;10653.254272200871;10717.303375280582;10068.748281293027;10753.89246107529;10763;10828.783424331627;10951.397673128055 16:56:31 INFO - PROCESS | 28476 | __end_tp_report 16:56:31 INFO - PROCESS | 28476 | __start_cc_report 16:56:31 INFO - PROCESS | 28476 | _x_x_mozilla_cycle_collect,1005 16:56:31 INFO - PROCESS | 28476 | __end_cc_report 16:56:31 INFO - PROCESS | 28476 | __startTimestamp1470873391740__endTimestamp 16:56:31 INFO - PROCESS | 28476 | 16:56:31 INFO - PROCESS | 28476 | ------- Summary: start ------- 16:56:31 INFO - PROCESS | 28476 | Number of tests: 6 16:56:31 INFO - PROCESS | 28476 | 16:56:31 INFO - PROCESS | 28476 | [#0] dojo.html Cycles:165 Average:4694.98 Median:4828.61 stddev:502.15 (10.4%) stddev-sans-first:477.17 16:56:31 INFO - PROCESS | 28476 | Values: 2642.1 2653.4 2682.3 2691.3 2696.3 4625.0 4700.9 4713.3 4820.0 4836.3 4716.8 4728.3 4767.4 4768.4 4789.6 4283.5 4301.1 4366.4 4421.6 4463.4 4397.2 4432.0 4450.8 4473.0 4477.0 4258.0 4309.7 4340.8 4355.7 4440.3 4275.9 4276.8 4327.7 4376.6 4379.7 4261.5 4311.1 4339.1 4346.0 4368.1 4659.9 4728.4 4743.0 4769.5 4793.5 5004.0 5061.4 5067.0 5077.3 5087.7 5068.9 5123.8 5152.9 5155.4 5179.0 4626.7 4728.2 4799.1 4801.6 4813.6 5221.5 5291.1 5312.3 5319.8 5322.3 5025.4 5049.2 5050.9 5095.4 5102.6 4964.6 4987.4 5132.0 5163.2 5194.4 4964.7 5028.1 5050.6 5050.7 5057.9 4965.0 4968.8 4978.7 4986.3 5009.7 4262.9 4275.7 4275.8 4277.7 4307.1 3656.1 3665.6 3689.2 3724.7 3743.0 4299.4 4305.9 4350.7 4394.5 4397.3 5019.6 5065.3 5066.8 5072.8 5099.5 4237.7 4261.7 4309.2 4316.6 4340.5 4579.1 4579.8 4580.6 4618.2 4630.1 4807.5 4820.5 4824.8 4828.6 4876.0 4809.5 5030.5 5085.1 5142.0 5200.0 4789.5 4813.6 4824.2 4876.6 4881.9 4987.4 5009.2 5018.1 5027.9 5076.6 4859.4 5015.9 5051.9 5074.5 5141.2 4885.0 4944.2 4952.8 4976.8 4998.1 4942.7 4946.7 4952.0 4955.7 5004.6 4615.7 4873.8 4879.5 4886.4 4908.5 4991.9 5052.7 5065.4 5085.8 5097.6 4941.8 4942.3 4945.4 4948.8 5004.2 16:56:31 INFO - PROCESS | 28476 | 16:56:31 INFO - PROCESS | 28476 | [#1] ext.html Cycles:180 Average:46437.12 Median:34220.40 stddev:87083.62 (254.5%) stddev-sans-first:87259.76 16:56:31 INFO - PROCESS | 28476 | Values: 549.9 564.7 571.0 575.3 576.8 38889.8 40789.2 40815.6 40862.6 45122.8 40580.4 40999.6 41054.8 41093.6 41113.8 7745.6 7812.9 7881.5 7887.1 7888.1 7791.5 7878.6 7881.2 7883.3 7887.2 7849.3 7891.3 7899.1 7902.6 7907.6 7834.7 7861.8 7870.7 7871.3 7871.9 7798.6 7845.3 7847.7 7853.8 7858.8 37829.6 38177.8 38226.2 38233.8 38250.0 2834.3 2958.7 2997.5 3130.6 3174.9 27936.2 28116.0 28139.9 28621.7 29664.7 3168.4 3307.4 3503.6 3541.8 3584.8 273353.0 273775.0 275570.6 275759.0 276694.0 31274.2 33236.7 33239.5 54392.7 54922.5 33291.8 33426.7 33553.3 33711.8 33727.3 12964.4 13089.9 13139.2 13148.4 13168.1 10597.2 10685.3 10700.9 10703.9 10721.0 7784.6 7840.9 7878.6 7886.3 7926.0 3054.8 3094.2 3108.5 3111.0 3131.9 833.9 923.5 1020.3 1038.4 1060.9 27453.7 27547.2 27868.4 28314.6 28475.7 984.6 993.7 1007.2 1048.3 1060.1 14612.0 15262.1 15281.0 15472.0 15545.9 388376.0 391950.0 395372.0 395872.0 397057.0 32779.8 32980.7 33042.5 33079.0 33095.3 1072.6 1126.7 1169.7 1200.8 1209.3 26419.5 28733.9 29632.7 30366.7 30369.1 41811.8 42368.6 42399.8 42404.0 42430.0 308267.0 310067.0 310169.0 310591.0 310630.0 20546.2 21998.6 25178.0 25273.1 25663.6 42257.0 42367.0 42460.8 42944.4 44590.0 37442.3 37678.8 37994.4 38152.8 39002.4 37124.6 37148.8 37524.4 38246.0 38295.8 32084.4 32797.2 35811.5 37310.3 37621.7 31464.8 33821.8 34108.5 34332.3 34354.7 26182.5 26222.0 26273.0 26359.0 26478.7 16:56:31 INFO - PROCESS | 28476 | 16:56:31 INFO - PROCESS | 28476 | [#2] jquery.html Cycles:180 Average:24599.07 Median:21554.82 stddev:64872.47 (301.0%) stddev-sans-first:65028.84 16:56:31 INFO - PROCESS | 28476 | Values: 319.7 352.9 355.0 355.6 356.7 12826.4 16129.6 16771.7 18226.8 18400.4 16345.7 16483.0 16546.6 16548.8 16659.8 4790.0 5033.9 5106.6 5145.8 5148.6 3696.0 3883.8 5000.5 5086.8 5096.4 3917.2 3927.9 5584.2 5932.7 5966.5 5831.5 6045.4 6048.2 6069.5 6147.4 5423.1 5424.3 5430.6 5432.6 5434.5 19302.8 20767.8 21425.7 21603.7 21790.8 1851.8 1913.5 1976.5 1986.9 2388.2 9175.6 9382.2 9606.1 9778.3 9829.2 1665.9 1690.5 1744.5 1759.3 1759.9 65766.7 69660.3 70344.0 70701.3 71478.0 10047.7 10095.5 10295.4 10488.5 10696.3 14346.1 14365.7 14366.5 14372.3 14379.9 6905.2 7153.4 7187.8 7333.3 7338.0 6658.7 6691.5 6839.8 6847.6 6848.8 4423.8 4478.0 4479.1 4605.0 4614.0 1809.3 1847.2 1851.1 1862.0 1914.7 847.4 848.4 866.1 870.5 894.4 16781.0 17435.0 17905.3 17996.5 18047.6 832.1 833.8 833.9 838.3 842.6 8990.5 9979.5 10041.3 10056.7 10079.7 385953.0 391587.0 393615.0 396235.0 396956.0 76484.2 77538.0 78000.6 78527.2 78882.0 906.0 916.6 938.4 945.7 953.3 15424.9 15886.6 15979.4 16364.7 16567.8 38031.8 38107.4 38394.2 38583.6 39192.4 50683.2 50814.0 51010.2 51250.2 52982.7 7789.0 7852.8 7895.5 8130.1 8502.4 11501.9 11519.3 11744.4 12527.3 12528.0 10485.3 10501.7 10813.1 10989.4 11138.2 10390.3 10456.6 10765.1 10949.1 11151.0 10771.5 10865.5 10985.5 11023.5 11052.2 7780.8 7947.2 7949.2 8031.4 8213.1 8826.3 10073.9 10403.7 10457.5 10515.5 16:56:31 INFO - PROCESS | 28476 | 16:56:31 INFO - PROCESS | 28476 | [#3] mootools.html Cycles:180 Average:5075.51 Median:4979.42 stddev:2921.34 (58.7%) stddev-sans-first:2915.61 16:56:31 INFO - PROCESS | 28476 | Values: 1279.9 1390.1 1409.0 1491.7 1707.4 6555.6 7554.2 7803.5 7822.8 8031.3 7914.6 7989.3 8005.6 8007.6 8009.5 2173.6 2206.3 2207.1 2207.2 2207.7 2127.7 2167.9 2204.3 2204.7 2205.4 2152.9 2175.1 2175.7 2177.0 2185.2 2102.8 2108.8 2110.4 2112.0 2122.4 2068.8 2118.8 2121.6 2124.4 2125.7 7829.6 7900.3 7968.8 7986.3 8004.3 4406.4 4580.1 4583.6 4589.6 4590.3 2778.9 2943.3 2981.0 2992.3 3004.5 920.2 924.9 926.3 938.4 942.5 10829.4 10991.3 11001.4 11008.8 11016.9 3855.3 3882.4 4042.7 4043.4 4072.1 8527.7 8613.0 8631.3 8684.3 8689.0 4538.6 4539.3 4540.2 4540.6 4541.1 3475.5 4010.3 4231.6 4234.3 4239.2 3522.2 3625.9 3730.7 3739.7 3775.7 1133.2 1159.4 1165.6 1173.1 1179.6 681.4 710.1 778.3 801.6 834.6 4935.3 5114.5 5119.9 5278.4 5412.5 371.8 372.3 372.4 375.1 377.7 3413.3 3466.9 3530.4 3563.7 3626.7 9593.4 9828.2 9899.9 9906.9 9912.6 9836.1 9876.2 9879.2 9881.9 9895.7 7510.5 7510.9 7514.2 7550.3 7587.8 5023.5 5206.7 5282.6 5367.8 5485.1 8877.4 8933.9 8978.9 9065.8 9069.5 8908.5 9135.6 9180.3 9283.1 9292.8 5685.8 6098.7 6570.0 6615.5 6627.9 6817.5 7080.3 7086.2 7086.6 7087.6 6386.7 6427.1 6437.3 6523.4 6621.9 6183.7 6293.1 6382.6 6390.4 6432.7 5817.9 5913.9 5926.4 5927.6 5958.9 4465.9 4480.7 4577.5 4585.9 4642.9 4383.0 4400.1 4503.9 4528.8 4589.4 16:56:31 INFO - PROCESS | 28476 | 16:56:31 INFO - PROCESS | 28476 | [#4] prototype.html Cycles:180 Average:4138.54 Median:4776.40 stddev:1202.29 (25.2%) stddev-sans-first:1170.32 16:56:31 INFO - PROCESS | 28476 | Values: 283.4 287.1 288.7 289.7 290.4 4780.9 4822.8 4832.1 4833.4 4837.9 4739.1 4746.9 4755.6 4765.2 4780.5 3540.4 3650.7 3659.1 3734.0 3787.9 3753.9 3757.7 3777.7 3780.1 3783.3 3729.2 3738.1 3743.8 3748.2 3751.1 3553.6 3579.9 3674.8 3683.5 3695.9 3271.1 3279.7 3285.3 3286.7 3291.5 4676.1 4676.7 4683.8 4697.0 4706.9 4128.8 4141.9 4175.4 4203.6 4219.8 4928.7 4941.2 4956.5 4960.5 4973.9 3535.9 3540.7 3546.2 3546.6 3551.6 4959.2 5186.6 5190.7 5194.7 5215.0 3628.5 3658.5 3658.7 3660.1 3664.7 3691.3 3696.7 3700.5 3708.1 3731.5 4216.4 4217.8 4228.6 4240.3 4243.4 4876.8 4927.9 4971.3 4988.7 5025.8 1270.9 1279.1 1280.0 1284.2 1287.5 819.4 823.3 832.8 833.4 852.4 4266.8 4272.3 4287.7 4371.7 4416.8 5089.5 5095.7 5111.2 5112.0 5115.7 4310.5 4382.1 4388.9 4412.4 4424.5 2700.6 2704.9 2716.3 2718.5 2720.2 4854.4 4855.1 4857.1 4864.2 4871.5 4957.6 5210.9 5253.7 5256.7 5272.6 4819.8 4826.9 4847.0 4851.4 4864.3 5092.8 5108.7 5117.4 5122.7 5124.7 4751.3 4974.2 5022.3 5025.9 5032.1 4889.3 4894.1 4897.3 4897.5 4918.3 3562.4 3576.3 3576.8 3597.6 3598.2 5004.8 5093.6 5130.1 5148.1 5149.9 4976.3 4995.4 4997.9 5000.8 5015.1 4984.1 4992.9 5017.2 5023.9 5038.4 4772.3 4898.4 4913.2 4932.6 4954.7 4812.8 4860.4 4976.7 5009.2 5015.0 4918.6 4921.7 4928.2 4936.2 4949.7 16:56:31 INFO - PROCESS | 28476 | 16:56:31 INFO - PROCESS | 28476 | [#5] yui.html Cycles:180 Average:18418.49 Median:4142.80 stddev:26627.38 (642.7%) stddev-sans-first:26668.65 16:56:31 INFO - PROCESS | 28476 | Values: 650.7 686.0 704.2 725.2 731.6 12967.0 14212.2 14216.6 14229.4 14252.5 11993.3 12005.2 12017.6 12046.6 12056.1 617.9 747.8 748.2 778.2 780.8 783.2 786.4 786.6 786.7 786.9 835.9 836.2 836.7 837.1 837.2 662.0 799.1 821.2 823.3 828.1 601.3 604.1 604.7 604.8 605.6 13336.5 13347.8 13365.9 13367.3 13373.3 12251.4 12491.9 12718.0 12733.2 13217.0 11714.4 11777.8 11900.8 12008.0 12102.6 4044.4 4133.9 4138.8 4146.8 4263.0 69337.0 70414.6 70631.6 70779.6 70833.6 10082.7 10458.4 10573.8 10641.2 10787.7 19859.2 19872.5 19880.9 19885.2 19898.7 6905.6 7069.3 7152.6 7167.7 7174.8 3685.2 3827.7 3873.0 3896.0 4008.6 2749.8 2868.9 2874.5 2877.8 2880.7 450.5 464.2 464.6 469.9 475.3 449.4 485.8 535.8 578.2 640.9 8292.7 8777.7 8840.4 8927.2 8980.2 564.8 579.0 617.0 619.5 636.2 4042.7 4125.4 4153.3 4203.7 4393.1 102869.0 105653.9 107311.0 107693.5 111159.4 90469.5 91379.5 91867.5 92083.5 92882.0 47461.8 64145.7 71515.6 78284.0 79612.6 7467.2 7633.4 7768.1 7884.6 8852.8 42042.0 42708.1 43586.3 44276.0 46076.5 39980.6 52913.0 64925.7 65377.7 66194.3 11587.3 11707.2 11866.9 11917.7 12276.0 13199.7 13282.7 13674.6 14219.5 14667.9 12541.8 12628.0 12739.2 13466.3 13595.6 12461.8 12906.2 13173.1 13201.7 13761.6 11355.6 12239.4 12270.0 12376.9 12623.9 10010.7 10044.3 10338.2 10653.3 10717.3 10068.7 10753.9 10763.0 10828.8 10951.4 16:56:31 INFO - PROCESS | 28476 | -------- Summary: end -------- 16:56:31 INFO - PROCESS | 28476 | 16:56:32 INFO - TEST-INFO | 28476: exit 0 16:56:32 INFO - TEST-OK | dromaeo_css | took 1120343ms 16:56:32 INFO - TEST-START | kraken 16:56:32 INFO - operating with platform_type : linux_ 16:56:32 INFO - Initialising browser for kraken test... 16:56:32 INFO - TEST-INFO | started process 28663 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgsKkLn/profile http://localhost:47889/getInfo.html) 16:56:36 INFO - PROCESS | 28663 | __metrics Screen width/height:1600/1200 16:56:36 INFO - PROCESS | 28663 | colorDepth:24 16:56:36 INFO - PROCESS | 28663 | Browser inner width/height: 1024/697 16:56:36 INFO - PROCESS | 28663 | __metrics 16:56:37 INFO - PROCESS | 28663 | 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:56:37 INFO - PROCESS | 28663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:56:38 INFO - TEST-INFO | 28663: exit 0 16:56:38 INFO - Browser initialized. 16:56:38 INFO - Running cycle 1/1 for kraken test... 16:56:38 INFO - TEST-INFO | started process 28744 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgsKkLn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:56:50 INFO - PROCESS | 28744 | RSS: Main: 157732864 16:56:50 INFO - PROCESS | 28744 | 16:56:53 INFO - PROCESS | 28744 | Cycle 1(1): loaded http://localhost:47889/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47889/tests/kraken/driver.html?testName=audio-beat-detection) 16:56:54 INFO - PROCESS | 28744 | RSS: Main: 212463616 16:56:54 INFO - PROCESS | 28744 | 16:56:57 INFO - PROCESS | 28744 | Cycle 1(1): loaded http://localhost:47889/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47889/tests/kraken/driver.html?testName=audio-dft) 16:56:57 INFO - PROCESS | 28744 | RSS: Main: 192356352 16:56:57 INFO - PROCESS | 28744 | 16:57:01 INFO - PROCESS | 28744 | Cycle 1(1): loaded http://localhost:47889/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47889/tests/kraken/driver.html?testName=audio-fft) 16:57:01 INFO - PROCESS | 28744 | RSS: Main: 271003648 16:57:01 INFO - PROCESS | 28744 | 16:57:03 INFO - PROCESS | 28744 | Cycle 1(1): loaded http://localhost:47889/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47889/tests/kraken/driver.html?testName=audio-oscillator) 16:57:04 INFO - PROCESS | 28744 | RSS: Main: 179834880 16:57:04 INFO - PROCESS | 28744 | 16:57:06 INFO - PROCESS | 28744 | Cycle 1(1): loaded http://localhost:47889/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47889/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:57:07 INFO - PROCESS | 28744 | RSS: Main: 184397824 16:57:07 INFO - PROCESS | 28744 | 16:57:10 INFO - PROCESS | 28744 | Cycle 1(1): loaded http://localhost:47889/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47889/tests/kraken/driver.html?testName=imaging-darkroom) 16:57:11 INFO - PROCESS | 28744 | RSS: Main: 220971008 16:57:11 INFO - PROCESS | 28744 | 16:57:14 INFO - PROCESS | 28744 | Cycle 1(1): loaded http://localhost:47889/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47889/tests/kraken/driver.html?testName=imaging-desaturate) 16:57:15 INFO - PROCESS | 28744 | RSS: Main: 214437888 16:57:15 INFO - PROCESS | 28744 | 16:57:18 INFO - PROCESS | 28744 | Cycle 1(1): loaded http://localhost:47889/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47889/tests/kraken/driver.html?testName=json-parse-financial) 16:57:18 INFO - PROCESS | 28744 | RSS: Main: 217894912 16:57:18 INFO - PROCESS | 28744 | 16:57:20 INFO - PROCESS | 28744 | Cycle 1(1): loaded http://localhost:47889/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47889/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:57:21 INFO - PROCESS | 28744 | RSS: Main: 183439360 16:57:21 INFO - PROCESS | 28744 | 16:57:23 INFO - PROCESS | 28744 | Cycle 1(1): loaded http://localhost:47889/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47889/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:57:23 INFO - PROCESS | 28744 | RSS: Main: 190074880 16:57:23 INFO - PROCESS | 28744 | 16:57:25 INFO - PROCESS | 28744 | Cycle 1(1): loaded http://localhost:47889/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47889/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:57:26 INFO - PROCESS | 28744 | RSS: Main: 193929216 16:57:26 INFO - PROCESS | 28744 | 16:57:29 INFO - PROCESS | 28744 | Cycle 1(1): loaded http://localhost:47889/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47889/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:57:29 INFO - PROCESS | 28744 | RSS: Main: 187789312 16:57:29 INFO - PROCESS | 28744 | 16:57:32 INFO - PROCESS | 28744 | Cycle 1(1): loaded http://localhost:47889/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47889/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:57:33 INFO - PROCESS | 28744 | RSS: Main: 191750144 16:57:33 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | Cycle 1(1): loaded http://localhost:47889/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47889/tests/kraken/driver.html?testName=ai-astar) 16:57:35 INFO - PROCESS | 28744 | RSS: Main: 242929664 16:57:35 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | __start_tp_report 16:57:35 INFO - PROCESS | 28744 | _x_x_mozilla_page_load 16:57:35 INFO - PROCESS | 28744 | _x_x_mozilla_page_load_details 16:57:35 INFO - PROCESS | 28744 | |i|pagename|runs| 16:57:35 INFO - PROCESS | 28744 | |0;ai-astar;106;116;114;114;114;116;112;112;116;114 16:57:35 INFO - PROCESS | 28744 | |1;audio-beat-detection;151;124;125;185;124;128;145;123;187;128 16:57:35 INFO - PROCESS | 28744 | |2;audio-dft;161;161;163;162;163;162;161;162;163;161 16:57:35 INFO - PROCESS | 28744 | |3;audio-fft;75;76;76;112;76;75;74;74;98;75 16:57:35 INFO - PROCESS | 28744 | |4;audio-oscillator;85;85;130;85;87;86;126;84;85;132 16:57:35 INFO - PROCESS | 28744 | |5;imaging-gaussian-blur;117;117;117;117;117;116;118;117;117;117 16:57:35 INFO - PROCESS | 28744 | |6;imaging-darkroom;146;146;146;146;146;147;147;146;146;147 16:57:35 INFO - PROCESS | 28744 | |7;imaging-desaturate;94;95;95;95;95;94;95;95;94;95 16:57:35 INFO - PROCESS | 28744 | |8;json-parse-financial;64;63;64;63;65;63;62;64;71;64 16:57:35 INFO - PROCESS | 28744 | |9;json-stringify-tinderbox;65;67;65;65;65;66;64;65;66;65 16:57:35 INFO - PROCESS | 28744 | |10;stanford-crypto-aes;81;90;80;81;85;78;80;79;80;79 16:57:35 INFO - PROCESS | 28744 | |11;stanford-crypto-ccm;134;133;131;137;137;138;129;131;157;141 16:57:35 INFO - PROCESS | 28744 | |12;stanford-crypto-pbkdf2;169;171;172;170;178;170;174;174;175;172 16:57:35 INFO - PROCESS | 28744 | |13;stanford-crypto-sha256-iterative;68;70;72;67;68;68;67;68;67;66 16:57:35 INFO - PROCESS | 28744 | __end_tp_report 16:57:35 INFO - PROCESS | 28744 | __start_cc_report 16:57:35 INFO - PROCESS | 28744 | _x_x_mozilla_cycle_collect,1968 16:57:35 INFO - PROCESS | 28744 | __end_cc_report 16:57:35 INFO - PROCESS | 28744 | __startTimestamp1470873455507__endTimestamp 16:57:35 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | ------- Summary: start ------- 16:57:35 INFO - PROCESS | 28744 | Number of tests: 14 16:57:35 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:2.99 (2.6%) stddev-sans-first:1.56 16:57:35 INFO - PROCESS | 28744 | Values: 106.0 116.0 114.0 114.0 114.0 116.0 112.0 112.0 116.0 114.0 16:57:35 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:136.50 stddev:25.06 (18.4%) stddev-sans-first:26.37 16:57:35 INFO - PROCESS | 28744 | Values: 151.0 124.0 125.0 185.0 124.0 128.0 145.0 123.0 187.0 128.0 16:57:35 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:0.88 (0.5%) stddev-sans-first:0.87 16:57:35 INFO - PROCESS | 28744 | Values: 161.0 161.0 163.0 162.0 163.0 162.0 161.0 162.0 163.0 161.0 16:57:35 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | [#3] audio-fft Cycles:10 Average:81.10 Median:75.50 stddev:13.04 (17.3%) stddev-sans-first:13.65 16:57:35 INFO - PROCESS | 28744 | Values: 75.0 76.0 76.0 112.0 76.0 75.0 74.0 74.0 98.0 75.0 16:57:35 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | [#4] audio-oscillator Cycles:10 Average:98.50 Median:85.00 stddev:21.34 (25.1%) stddev-sans-first:22.07 16:57:35 INFO - PROCESS | 28744 | Values: 85.0 85.0 130.0 85.0 87.0 86.0 126.0 84.0 85.0 132.0 16:57:35 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 16:57:35 INFO - PROCESS | 28744 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 118.0 117.0 117.0 117.0 16:57:35 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 16:57:35 INFO - PROCESS | 28744 | Values: 146.0 146.0 146.0 146.0 146.0 147.0 147.0 146.0 146.0 147.0 16:57:35 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 16:57:35 INFO - PROCESS | 28744 | Values: 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 95.0 16:57:35 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.00 stddev:2.50 (3.9%) stddev-sans-first:2.65 16:57:35 INFO - PROCESS | 28744 | Values: 64.0 63.0 64.0 63.0 65.0 63.0 62.0 64.0 71.0 64.0 16:57:35 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | [#9] json-stringify-tinderbox Cycles:10 Average:65.30 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 16:57:35 INFO - PROCESS | 28744 | Values: 65.0 67.0 65.0 65.0 65.0 66.0 64.0 65.0 66.0 65.0 16:57:35 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:80.50 stddev:3.59 (4.5%) stddev-sans-first:3.81 16:57:35 INFO - PROCESS | 28744 | Values: 81.0 90.0 80.0 81.0 85.0 78.0 80.0 79.0 80.0 79.0 16:57:35 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | [#11] stanford-crypto-ccm Cycles:10 Average:136.80 Median:137.00 stddev:8.01 (5.8%) stddev-sans-first:8.43 16:57:35 INFO - PROCESS | 28744 | Values: 134.0 133.0 131.0 137.0 137.0 138.0 129.0 131.0 157.0 141.0 16:57:35 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.50 Median:173.00 stddev:2.76 (1.6%) stddev-sans-first:2.62 16:57:35 INFO - PROCESS | 28744 | Values: 169.0 171.0 172.0 170.0 178.0 170.0 174.0 174.0 175.0 172.0 16:57:35 INFO - PROCESS | 28744 | 16:57:35 INFO - PROCESS | 28744 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:1.73 (2.5%) stddev-sans-first:1.83 16:57:35 INFO - PROCESS | 28744 | Values: 68.0 70.0 72.0 67.0 68.0 68.0 67.0 68.0 67.0 66.0 16:57:35 INFO - PROCESS | 28744 | -------- Summary: end -------- 16:57:35 INFO - PROCESS | 28744 | 16:57:36 INFO - TEST-INFO | 28744: exit 0 16:57:36 INFO - TEST-OK | kraken | took 64097ms 16:57:36 INFO - SUITE-END | took 1184s 16:57:37 INFO - Completed test suite (00:19:44) 16:57:37 INFO - javascript benchmark 16:57:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2642.062067965872, 2653.3631928337204, 2682.275785537905, 2691.3271682079494, 2696.3393968477712, 4624.982503849148, 4700.94228798521, 4713.34013238147, 4819.95180048199, 4836.347093142429, 4716.811327546902, 4728.3380326754195, 4767.427908650958, 4768.380110585627, 4789.568938795508, 4283.464566929134, 4301.118270754503, 4366.410534577839, 4421.646268298545, 4463.4420697412825, 4397.208502469554, 4432.024954510002, 4450.755208463534, 4473.038296766194, 4477.0, 4258.020655249279, 4309.741415515079, 4340.82636694535, 4355.6733245006435, 4440.267355886275, 4275.850345237902, 4276.786160691984, 4327.675424343187, 4376.6498680105515, 4379.715318504319, 4261.488621365425, 4311.051568654891, 4339.110482351142, 4346.043870348518, 4368.082702632451, 4659.860204193879, 4728.432588089451, 4743.02767932573, 4769.546893045155, 4793.472718001017, 5004.0, 5061.44324124346, 5067.0, 5077.339945807022, 5087.694738315713, 5068.873278168038, 5123.769430375642, 5152.896942061137, 5155.432902380735, 5178.974105129488, 4626.676132670681, 4728.243481043017, 4799.11216424959, 4801.567858892647, 4813.590844778233, 5221.451747566451, 5291.100512912814, 5312.335958005249, 5319.760610772524, 5322.33470816148, 5025.3718285214345, 5049.192129259374, 5050.873728156752, 5095.363079615048, 5102.591792656653, 4964.553190212898, 4987.376577927759, 5132.0, 5163.225516172604, 5194.35070616173, 4964.67729597575, 5028.145215313405, 5050.621203409759, 5050.696958182521, 5057.924131138036, 4964.975175124174, 4968.751562421864, 4978.726170060681, 4986.252062190701, 5009.674371165863, 4262.850800221977, 4275.679324050709, 4275.764832934156, 4277.700560960703, 4307.095509942885, 3656.0859785053735, 3665.6151104134215, 3689.1883785567134, 3724.7206459515646, 3742.9893928639117, 4299.441072660534, 4305.935410968838, 4350.695451318441, 4394.538573449741, 4397.2964325707735, 5019.573336266385, 5065.265536497187, 5066.847994560169, 5072.797088116507, 5099.490050994945, 4237.6821738369745, 4261.680373971964, 4309.181255561433, 4316.589923957219, 4340.500842403164, 4579.129965306581, 4579.793909274091, 4580.610648094949, 4618.168729628632, 4630.097131059479, 4807.543283388142, 4820.517948205292, 4824.758762061953, 4828.613710903049, 4875.95124048762, 4809.495003024632, 5030.496950304941, 5085.084684756779, 5142.0, 5199.974000129975, 4789.4971028041555, 4813.614910807057, 4824.179889418878, 4876.585490233228, 4881.8535443936735, 4987.401511818605, 5009.198528235464, 5018.146915024382, 5027.857723597617, 5076.568491678248, 4859.392575928009, 5015.924761128513, 5051.898962020812, 5074.543291103819, 5141.177411614124, 4885.0, 4944.208926571731, 4952.8018879245865, 4976.776045078052, 4998.125328067501, 4942.728149951643, 4946.703197808143, 4952.0, 4955.702657840541, 5004.6246531511015, 4615.699979501322, 4873.805047798191, 4879.463259041574, 4886.438059623189, 4908.5091490852055, 4991.850244492671, 5052.671576347526, 5065.366829146356, 5085.821996230115, 5097.617678674188, 4941.75291235444, 4942.258661200849, 4945.357103576512, 4948.752562371939, 5004.199328107486], "value": 4662.65022593592, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.939506654268, 564.6809552602779, 570.9978986878028, 575.3354875119239, 576.8269519144255, 38889.80555097224, 40789.18421631573, 40815.59184408159, 40862.59137408625, 45122.774386128025, 40580.39129413054, 40999.59000409995, 41054.794726026324, 41093.58906410935, 41113.7944310278, 7745.612719364051, 7812.921870781276, 7881.502514522251, 7887.132415434297, 7888.053433587977, 7791.532508049507, 7878.566678832662, 7881.17247688993, 7883.3299169570655, 7887.171846956073, 7849.332806711436, 7891.250331218525, 7899.052113746358, 7902.644381002869, 7907.644156012993, 7834.72578459754, 7861.764147075597, 7870.724524641667, 7871.330936870373, 7871.881921771207, 7798.610069496503, 7845.333146682538, 7847.6860925562905, 7853.803654908673, 7858.764237072897, 37829.62170378316, 38177.80911095454, 38226.23547529034, 38233.808830955946, 38250.0, 2834.2630915962, 2958.7189217024347, 2997.5203967365337, 3130.5617213590117, 3174.857131429091, 27936.161915142377, 28116.0, 28139.85930070357, 28621.713782862113, 29664.70335296641, 3168.3980043791835, 3307.4377355849565, 3503.5795704515367, 3541.7874927504176, 3584.8207589620415, 273353.0, 273775.0, 275570.62214689, 275759.0, 276694.0, 31274.21814454657, 33236.66763332384, 33239.50140747891, 54392.72803636036, 54922.450775491736, 33291.83354083214, 33426.665733342845, 33553.328933421195, 33711.83144084264, 33727.32545349079, 12964.351782410842, 13089.869101308865, 13139.211647301192, 13148.40832162555, 13168.144070635379, 10597.20520960931, 10685.305455145392, 10700.946495267473, 10703.946480267548, 10721.0, 7784.649690763929, 7840.9215907841335, 7878.566678832604, 7886.250806173404, 7925.960370198112, 3054.801437906529, 3094.1955091676327, 3108.4560201964564, 3110.9688903111132, 3131.8903838366004, 833.9041010283773, 923.5013092929877, 1020.311479252161, 1038.3977293169944, 1060.9469526523642, 27453.72546274512, 27547.173584792487, 27868.442631147263, 28314.575281370795, 28475.71524284772, 984.5569493727851, 993.7267251505893, 1007.2043085962008, 1048.2976405808126, 1060.1359891688248, 14612.0, 15262.08427494354, 15281.0, 15472.0, 15545.922270388575, 388376.0, 391950.0, 395372.0, 395872.0, 397057.0, 32779.836100819346, 32980.67019329776, 33042.504362434585, 33079.00762977114, 33095.338093238, 1072.5602502973743, 1126.7126882645036, 1169.7134202120535, 1200.771853347861, 1209.3227792436087, 26419.471610567296, 28733.856330718212, 29632.703672962994, 30366.696333036387, 30369.088927332214, 41811.7909410451, 42368.57631423646, 42399.788001059795, 42404.0, 42430.0, 308267.0, 310067.0, 310169.0, 310591.0, 310630.0, 20546.17815287372, 21998.560028799653, 25178.0, 25273.11544095948, 25663.615045773953, 42257.0, 42367.0, 42460.78769606132, 42944.35583466313, 44590.0, 37442.25115497729, 37678.811605941795, 37994.43008354932, 38152.80923595475, 39002.41496377614, 37124.6287537132, 37148.81425592855, 37524.437133443564, 38246.0, 38295.80852095722, 32084.358312833145, 32797.18007049843, 35811.462828057076, 37310.253794924494, 37621.68324108643, 31464.842675786476, 33821.83089084637, 34108.488372674925, 34332.31335373229, 34354.65645343515, 26182.4763504725, 26222.0, 26273.0, 26359.0, 26478.7244117254], "value": 16330.168413344854, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.70906475107654, 352.8641473032882, 354.98225088745556, 355.5644335688781, 356.7146282973622, 12826.358682065906, 16129.596760081004, 16771.664566708674, 18226.817731822677, 18400.447986560426, 16345.673086538261, 16483.0, 16546.58633534162, 16548.83451165488, 16659.75010374842, 4789.976050119762, 5033.874153146164, 5106.5659418949435, 5145.81989630363, 5148.639595228336, 3695.9630403696024, 3883.8252278647387, 5000.549950504454, 5086.821961231358, 5096.413912400084, 3917.2361389529055, 3927.9214415711667, 5584.1902924076185, 5932.733027013774, 5966.46301832835, 5831.5334773218465, 6045.425684559959, 6048.2137322148055, 6069.544784141163, 6147.44672979434, 5423.05096608092, 5424.349078110613, 5430.647007944471, 5432.592555558349, 5434.456554344573, 19302.806971930382, 20767.792322076737, 21425.67861482079, 21603.67594486084, 21790.782092179194, 1851.7777866655954, 1913.4642300155979, 1976.505873531617, 1986.8509861760426, 2388.2238272561485, 9175.58709858058, 9382.202512786353, 9606.087421694929, 9778.315517913748, 9829.164521015722, 1665.916704164799, 1690.475952454743, 1744.520256929342, 1759.26111033365, 1759.9472015839544, 65766.67116664434, 69660.3033969664, 70344.0, 70701.29298706999, 71478.0, 10047.748806279902, 10095.545700443497, 10295.433751143759, 10488.475576221159, 10696.251262411557, 14346.139231646135, 14365.712685746226, 14366.497172598909, 14372.281385930663, 14379.85620143806, 6905.1713794344, 7153.3561979421065, 7187.7843664690145, 7333.266673332624, 7337.963310183415, 6658.667066646648, 6691.498137639696, 6839.794806155824, 6847.554908931003, 6848.794536163923, 4423.800928958204, 4477.977610111929, 4479.126570318822, 4604.953950460453, 4613.95386046142, 1809.276289484217, 1847.159542408201, 1851.0559614596652, 1861.9534511637046, 1914.664933636608, 847.3771777743353, 848.4145939301862, 866.1035827918074, 870.5125129927135, 894.4454438248328, 16781.0, 17434.953902765876, 17905.28378864857, 17996.460106196835, 18047.63904721898, 832.076395201326, 833.8499070167428, 833.9332853371716, 838.2581415447252, 842.5744998775579, 8990.505522196341, 9979.501024948724, 10041.347312424816, 10056.748581285527, 10079.748006299902, 385953.0, 391587.0, 393615.0, 396235.0, 396956.0, 76484.23515764771, 77538.0, 78000.60999694966, 78527.21472785313, 78882.0, 906.0305473143673, 916.6012784438657, 938.4228699349851, 945.6926498887889, 953.3136141977903, 15424.922875385551, 15886.60283492922, 15979.360825566848, 16364.67270654604, 16567.751483727996, 38031.80984095062, 38107.42838857364, 38394.23211535698, 38583.61416385912, 39192.41211381774, 50683.23975140302, 50814.0, 51010.23484647659, 51250.23124653059, 52982.73508632586, 7788.961055194688, 7852.842943140991, 7895.486793358414, 8130.065042519949, 8502.362322825937, 11501.942490287494, 11519.25124866881, 11744.355790189209, 12527.31099789484, 12528.0, 10485.26603137773, 10501.684949451528, 10813.134949204203, 10989.395583242998, 11138.164637652371, 10390.272680912263, 10456.63401780934, 10765.084967777826, 10949.069329107115, 11151.0, 10771.461426928465, 10865.456727163768, 10985.450727463434, 11023.503942322453, 11052.226344156154, 7780.766577002699, 7947.165547617417, 7949.165337639464, 8031.397645176752, 8213.137620549756, 8826.338024648048, 10073.899261007296, 10403.739906502398, 10457.477126143815, 10515.474226288501], "value": 8293.248435373447, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1279.8528169260537, 1390.1242217403035, 1409.0136904167082, 1491.701659668066, 1707.3597400974627, 6555.573887697302, 7554.169041405441, 7803.453758236926, 7822.843543129141, 8031.277185053344, 7914.643841027153, 7989.280964713174, 8005.63974621142, 8007.63965621547, 8009.519428834259, 2173.5544213436287, 2206.293985924505, 2207.1061220205843, 2207.249535158046, 2207.7461091974387, 2127.7340332458443, 2167.8590891592, 2204.34971683353, 2204.7023651806962, 2205.4376134085865, 2152.9031193596325, 2175.0973346061364, 2175.695402643631, 2177.031221106608, 2185.2133232036454, 2102.810747032767, 2108.778578249277, 2110.419634600482, 2112.039022244875, 2122.426944724931, 2068.75174979002, 2118.809307162363, 2121.586290673319, 2124.4264048706764, 2125.7342832145982, 7829.608519574055, 7900.2889739923685, 7968.760937171836, 7986.321162701177, 8004.319632831216, 4406.383106365111, 4580.106879158566, 4583.610393116589, 4589.609883159936, 4590.288505281686, 2778.8888444462195, 2943.323035701779, 2981.0460652591178, 2992.2968102495897, 3004.549317602377, 920.1902325953185, 924.8705181274627, 926.2589928057527, 938.3900464697932, 942.4769253064546, 10829.404382758945, 10991.285566438157, 11001.449927503672, 11008.779824403548, 11016.944915275453, 3855.325318069327, 3882.3594106972596, 4042.6967977401814, 4043.4137050128156, 4072.1448495815616, 8527.658893644184, 8612.956935215283, 8631.266342360845, 8684.261837743736, 8689.0, 4538.614217791524, 4539.273716205457, 4540.160070386989, 4540.5913467787395, 4541.069080838486, 3475.4960530722906, 4010.3382941814275, 4231.555686652857, 4234.301340278881, 4239.194553034913, 3522.2075033117344, 3625.8549658013385, 3730.68289195416, 3739.7195210358786, 3775.7356985011334, 1133.195431243826, 1159.3623507071245, 1165.6328256599145, 1173.0556901694217, 1179.6461061681462, 681.417388133149, 710.1265443504415, 778.2762031310822, 801.5671537369872, 834.5952213176605, 4935.333729946411, 5114.514121158484, 5119.923201151912, 5278.366596008504, 5412.539934105566, 371.78064941683994, 372.25548902195607, 372.3892815782119, 375.1147292389958, 377.6978417266176, 3413.2661477782394, 3466.9133271667906, 3530.3998320285614, 3563.6970857476886, 3626.709863210937, 9593.376430532133, 9828.164606008426, 9899.901000990041, 9906.851397229048, 9912.55393507294, 9836.11474967242, 9876.209903207726, 9879.209663226924, 9881.901180988241, 9895.703128906, 7510.4742668013805, 7510.924890751132, 7514.211007844097, 7550.320471157513, 7587.848243035219, 5023.497650234947, 5206.661566998133, 5282.577393808563, 5367.785288588413, 5485.149801780731, 8877.378583499092, 8933.865992009994, 8978.86531702038, 9065.77335566616, 9069.50117743504, 8908.510031948304, 9135.634574617206, 9180.311476639412, 9283.071692830663, 9292.767680807763, 5685.800996965087, 6098.725557349841, 6570.0, 6615.5038372123245, 6627.867442651023, 6817.454603631599, 7080.2919708028785, 7086.185088714864, 7086.610236437045, 7087.645617719197, 6386.712597933011, 6427.132337134427, 6437.291897891322, 6523.445507131757, 6621.867562648817, 6183.659898705614, 6293.118963345044, 6382.5532212744665, 6390.360963903572, 6432.710528026157, 5817.941820581909, 5913.940860591339, 5926.407359264039, 5927.555433342603, 5958.880822383615, 4465.910681786411, 4480.708753930984, 4577.519360467236, 4585.931211031904, 4642.907141857077, 4382.956170438382, 4400.141972315496, 4503.864884053483, 4528.818847246073, 4589.449266088091], "value": 3990.260725687216, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.39919370933615, 287.1342901153024, 288.7339018404297, 289.6856910252376, 290.3698973228095, 4780.856574302772, 4822.782974766135, 4832.130216561018, 4833.419989601252, 4837.927431088537, 4739.0758802033615, 4746.928796068053, 4755.571998520132, 4765.237561990082, 4780.4702495201545, 3540.371429142591, 3650.7261955353324, 3659.1218107654236, 3733.9626603733886, 3787.9431808522895, 3753.86861459849, 3757.7369584129124, 3777.7166712496537, 3780.1305699689087, 3783.2811765764554, 3729.1982223821874, 3738.12154143776, 3743.7566558173767, 3748.1941382602736, 3751.118487155516, 3553.5558055243096, 3579.94630080549, 3674.81625918703, 3683.5395575553057, 3695.9445608315896, 3271.1331497153037, 3279.655636158193, 3285.291648342872, 3286.6549012353835, 3291.489819078047, 4676.088162808253, 4676.719396836201, 4683.7892294846815, 4697.0, 4706.882327941795, 4128.784677864334, 4141.875743727693, 4175.373693945902, 4203.600657937491, 4219.831206751726, 4928.704277743347, 4941.234108713155, 4956.479569645207, 4960.528749768766, 4973.925391119136, 3535.9116022099397, 3540.6990405815277, 3546.202104526487, 3546.5744110706623, 3551.5738111426535, 4959.181735013718, 5186.636935414523, 5190.688558686491, 5194.740262986836, 5214.97392513035, 3628.5101511296166, 3658.5061016762397, 3658.70730341572, 3660.1215708229834, 3664.7434679572707, 3691.2617476504806, 3696.685781708531, 3700.538287376471, 3708.1100535871196, 3731.477593136963, 4216.409702641633, 4217.768022758716, 4228.60456049503, 4240.300350442204, 4243.405923170759, 4876.82931097417, 4927.852164435073, 4971.279164521125, 4988.6507944444265, 5025.818932550847, 1270.8602053774077, 1279.0790630745848, 1279.9504406386673, 1284.229462322599, 1287.5107459165458, 819.4223072733708, 823.3166471828368, 832.8417600655856, 833.3916241143999, 852.3564708644933, 4266.765327906995, 4272.273713468718, 4287.663223543176, 4371.650267978554, 4416.84541041062, 5089.465606111305, 5095.668781529263, 5111.207762796773, 5111.974440127776, 5115.7442127893455, 4310.52584215736, 4382.145481631052, 4388.912221755547, 4412.382266482695, 4424.535423780521, 2700.5814098814717, 2704.932376690559, 2716.293763621473, 2718.510668079765, 2720.1975417251956, 4854.441739200037, 4855.077535268288, 4857.125717370836, 4864.173090574611, 4871.464138944713, 4957.578605818546, 5210.931758989398, 5253.737313134328, 5256.737163141827, 5272.551833094153, 4819.806987820533, 4826.903461930741, 4846.95153048465, 4851.442084160367, 4864.27035944597, 5092.8472145835685, 5108.719020453762, 5117.413601783459, 5122.667026643257, 5124.666896651705, 4751.311059896365, 4974.228994505857, 5022.296878436949, 5025.949740502549, 5032.119379108714, 4889.266610008527, 4894.094592500399, 4897.338859254098, 4897.461279259207, 4918.311436398979, 3562.447820587813, 3576.338377400189, 3576.7853928763316, 3597.586277578112, 3598.244368682606, 5004.799808007639, 5093.567046800915, 5130.12787826063, 5148.124818780741, 5149.948500514947, 4976.253561965589, 4995.400551933792, 4997.925031124463, 5000.774965126505, 5015.1474249378425, 4984.130950893415, 4992.8751781205765, 5017.222330538772, 5023.874403139951, 5038.370203724535, 4772.331873537777, 4898.363212782316, 4913.213885778258, 4932.605391568738, 4954.653174277766, 4812.783424745825, 4860.441049279378, 4976.701397916137, 5009.173486374853, 5015.0, 4918.565310171488, 4921.729304888266, 4928.211486162197, 4936.185529387611, 4949.7030178189425], "value": 3753.177803846586, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.7462089784983, 685.9759908403207, 704.1936982155436, 725.1877896755634, 731.5829976913159, 12967.0, 14212.218327991955, 14216.57350279492, 14229.359678814453, 14252.501162459315, 11993.280403175793, 12005.219660722047, 12017.639470815868, 12046.57836975706, 12056.09579281553, 617.8671585609093, 747.768191860525, 748.2255865179527, 778.2373274191295, 780.7813812132612, 783.2285199078902, 786.3787607789839, 786.5831109511968, 786.6735304848481, 786.8937693411402, 835.9373047021468, 836.2014276366086, 836.6736972580699, 837.0750320895414, 837.2213841127749, 661.9635920024397, 799.0531220503664, 821.2321479416705, 823.3495538524559, 828.0891019878146, 601.2904772368603, 604.1300527240759, 604.7036951893584, 604.8034388823606, 605.5518916002171, 13336.466541338335, 13347.799783003262, 13365.86634133656, 13367.264800435973, 13373.264470454122, 12251.448684809206, 12491.875081249162, 12718.0, 12733.172343797623, 13217.008724345713, 11714.355710435922, 11777.823332650018, 11900.761984760256, 12008.0, 12102.636920892388, 4044.391109876932, 4133.91732165358, 4138.772367519785, 4146.834126634961, 4262.978685106555, 69337.0, 70414.64792676004, 70631.64684176547, 70779.64610176967, 70833.64583177051, 10082.74793130163, 10458.372497650165, 10573.841392379121, 10641.201909856793, 10787.67636970892, 19859.205631774857, 19872.50318742014, 19880.90059549693, 19885.204591816455, 19898.70151947722, 6905.551139175937, 7069.257727938595, 7152.642367881585, 7167.677454514455, 7174.784756457315, 3685.1708365617524, 3827.655511004023, 3872.9612703873163, 3896.0, 4008.6392224699916, 2749.8350098994124, 2868.942621147565, 2874.525703258939, 2877.755390791764, 2880.6543214813937, 450.51344547888203, 464.185354702495, 464.64686838002683, 469.8919248572847, 475.31554561431426, 449.4022949477253, 485.8493866901201, 535.7883635963773, 578.2020811280406, 640.9134766806421, 8292.668293268202, 8777.69278075277, 8840.381173317806, 8927.196552310323, 8980.236679882284, 564.7627996241588, 579.0, 616.9691515424211, 619.5136817598254, 636.2301615045847, 4042.6765858731233, 4125.401816736557, 4153.273177193979, 4203.6637069034405, 4393.121375724868, 102869.0, 105653.88692226112, 107311.0, 107693.46153269183, 111159.44420278008, 90469.54765226132, 91379.54310228406, 91867.5406622976, 92083.53958230166, 92882.0, 47461.762691186326, 64145.679271603345, 71515.64242178756, 78284.0, 79612.60193698994, 7467.215942325977, 7633.389328853789, 7768.106667733283, 7884.605769711607, 8852.822943541221, 42042.0, 42708.07813648455, 43586.346204806316, 44276.0, 46076.539234607226, 39980.600193997685, 52913.0, 64925.67537162284, 65377.67311163604, 66194.33805661881, 11587.304761714324, 11707.239029463057, 11866.940665296619, 11917.702057448632, 12276.0, 13199.670008249486, 13282.734345313233, 13674.589762307147, 14219.50231741884, 14667.853321467075, 12541.811872821732, 12628.0, 12739.171953822972, 13466.259355735529, 13595.592132236048, 12461.813072804096, 12906.161099528501, 13173.077884547989, 13201.669958251121, 13761.555036721, 11355.602553910574, 12239.449224784728, 12269.975245310086, 12376.876231237573, 12623.936880315907, 10010.699679009642, 10044.347117437343, 10338.172946164139, 10653.254272200871, 10717.303375280582, 10068.748281293027, 10753.89246107529, 10763.0, 10828.783424331627, 10951.397673128055], "value": 6753.385824834513, "unit": "score"}], "name": "dromaeo_css", "value": 6322.465859773944, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 114.0, 114.0, 114.0, 116.0, 112.0, 112.0, 116.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 124.0, 125.0, 185.0, 124.0, 128.0, 145.0, 123.0, 187.0, 128.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 163.0, 162.0, 163.0, 162.0, 161.0, 162.0, 163.0, 161.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 112.0, 76.0, 75.0, 74.0, 74.0, 98.0, 75.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 130.0, 85.0, 87.0, 86.0, 126.0, 84.0, 85.0, 132.0], "value": 98.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 118.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 147.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 63.0, 65.0, 63.0, 62.0, 64.0, 71.0, 64.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 65.0, 65.0, 65.0, 66.0, 64.0, 65.0, 66.0, 65.0], "value": 65.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 90.0, 80.0, 81.0, 85.0, 78.0, 80.0, 79.0, 80.0, 79.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 133.0, 131.0, 137.0, 137.0, 138.0, 129.0, 131.0, 157.0, 141.0], "value": 136.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 171.0, 172.0, 170.0, 178.0, 170.0, 174.0, 174.0, 175.0, 172.0], "value": 172.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 72.0, 67.0, 68.0, 68.0, 67.0, 68.0, 67.0, 66.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1543.1999999999998, "alertThreshold": 2.0}]} 16:57:37 INFO - Return code: 0 16:57:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:57:37 INFO - # TBPL SUCCESS # 16:57:37 INFO - Running post-action listener: _resource_record_post_action 16:57:37 INFO - [mozharness: 2016-08-10 23:57:37.286637Z] Finished run-tests step (success) 16:57:37 INFO - Running post-run listener: _resource_record_post_run 16:57:37 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 396468224; Read time: 16; Write time: 397556 16:57:37 INFO - TinderboxPrint: CPU usage
13.0% 16:57:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:57:37 INFO - TinderboxPrint: I/O write bytes / time
396,468,224 / 397,556 16:57:37 INFO - TinderboxPrint: CPU idle
8,341.9 (87.0%) 16:57:37 INFO - TinderboxPrint: CPU user
1,200.5 (12.5%) 16:57:37 INFO - TinderboxPrint: Swap in / out
0 / 0 16:57:37 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99786752; Read time: 0; Write time: 108576 16:57:37 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 296681472; Read time: 0; Write time: 288980 16:57:37 INFO - Running post-run listener: _upload_blobber_files 16:57:37 INFO - Blob upload gear active. 16:57:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:57:37 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:57:37 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:57:37 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:57:38 INFO - (blobuploader) - INFO - Open directory for files ... 16:57:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:57:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:57:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:57:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:57:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:57:38 INFO - (blobuploader) - INFO - Done attempting. 16:57:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:57:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:57:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:57:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:57:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:57:39 INFO - (blobuploader) - INFO - Done attempting. 16:57:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:57:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:57:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:57:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:57:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:57:43 INFO - (blobuploader) - INFO - Done attempting. 16:57:43 INFO - (blobuploader) - INFO - Iteration through files over. 16:57:43 INFO - Return code: 0 16:57:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:57:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:57:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e920f6d0f15e43a8e00371e9432d98a8e2d7c5fd61ed60e8421727c8dce7f8cbfc7dd60aef41f1e5130850dfa4d1ed6357ed84ce7733e57c6c333ab2d1c5b29e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fa16e896c0cfbee2716472b5c52457f0b9c2cd67a370e982237af38f2cacfd5fb78df0037a0794db0517080099ad53e4334108f7ed811273e060d1fe017c250a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1cc2eb5a32882c6876ee37398994f24d54e912d0c7264e0a9095e3d8a5fc75541a690b597f239678e6a434d6b09c6551305c1e9d1d993f8b518934860a46ca22"} 16:57:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:57:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:57:43 INFO - Contents: 16:57:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e920f6d0f15e43a8e00371e9432d98a8e2d7c5fd61ed60e8421727c8dce7f8cbfc7dd60aef41f1e5130850dfa4d1ed6357ed84ce7733e57c6c333ab2d1c5b29e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fa16e896c0cfbee2716472b5c52457f0b9c2cd67a370e982237af38f2cacfd5fb78df0037a0794db0517080099ad53e4334108f7ed811273e060d1fe017c250a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1cc2eb5a32882c6876ee37398994f24d54e912d0c7264e0a9095e3d8a5fc75541a690b597f239678e6a434d6b09c6551305c1e9d1d993f8b518934860a46ca22"} 16:57:43 INFO - Running post-run listener: copy_logs_to_upload_dir 16:57:43 INFO - Copying logs to upload dir... 16:57:43 INFO - mkdir: /builds/slave/test/build/upload/logs 16:57:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.121650 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-08-10 16:57:43.365576) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 16:57:43.369631) ========= 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=c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807 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-Non-PGO/sha512/e920f6d0f15e43a8e00371e9432d98a8e2d7c5fd61ed60e8421727c8dce7f8cbfc7dd60aef41f1e5130850dfa4d1ed6357ed84ce7733e57c6c333ab2d1c5b29e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fa16e896c0cfbee2716472b5c52457f0b9c2cd67a370e982237af38f2cacfd5fb78df0037a0794db0517080099ad53e4334108f7ed811273e060d1fe017c250a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1cc2eb5a32882c6876ee37398994f24d54e912d0c7264e0a9095e3d8a5fc75541a690b597f239678e6a434d6b09c6551305c1e9d1d993f8b518934860a46ca22"} build_url:https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011182 build_url: 'https://queue.taskcluster.net/v1/task/S-p677QYRJGgFYe2PfUxKw/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-Non-PGO/sha512/e920f6d0f15e43a8e00371e9432d98a8e2d7c5fd61ed60e8421727c8dce7f8cbfc7dd60aef41f1e5130850dfa4d1ed6357ed84ce7733e57c6c333ab2d1c5b29e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fa16e896c0cfbee2716472b5c52457f0b9c2cd67a370e982237af38f2cacfd5fb78df0037a0794db0517080099ad53e4334108f7ed811273e060d1fe017c250a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1cc2eb5a32882c6876ee37398994f24d54e912d0c7264e0a9095e3d8a5fc75541a690b597f239678e6a434d6b09c6551305c1e9d1d993f8b518934860a46ca22"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 16:57:43.394464) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 16:57:43.394773) ========= 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=c6927cf182745ad25b1d38b9000001fe-1470871901.16846-653177807 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004383 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 16:57:43.478013) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 16:57:43.478352) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 16:57:43.478645) ========= ========= Total master_lag: 0.17 =========