builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-030 starttime: 1471018465.74 results: success (0) buildid: 20160812071715 builduid: 839fb31bf62e489a96d7176f79fea145 revision: 124669ed2f09f68f7f8dd38f4fee5f3084da0aff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 09:14:25.737216) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 09:14:25.738007) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 09:14:25.738307) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 09:14:25.775802) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 09:14:25.776064) ========= 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=4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-12 09:14:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 175K=0.07s 2016-08-12 09:14:26 (175 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.294593 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 09:14:26.086174) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 09:14:26.086516) ========= 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=4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017894 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 09:14:26.136515) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 09:14:26.136866) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 124669ed2f09f68f7f8dd38f4fee5f3084da0aff --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 124669ed2f09f68f7f8dd38f4fee5f3084da0aff --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=4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-12 09:14:26,298 truncating revision to first 12 chars 2016-08-12 09:14:26,298 Setting DEBUG logging. 2016-08-12 09:14:26,298 attempt 1/10 2016-08-12 09:14:26,298 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/124669ed2f09?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-12 09:14:26,613 unpacking tar archive at: fx-team-124669ed2f09/testing/mozharness/ program finished with exit code 0 elapsedTime=0.624520 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 09:14:26.776380) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 09:14:26.776686) ========= script_repo_revision: 124669ed2f09f68f7f8dd38f4fee5f3084da0aff ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 09:14:26.777407) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 09:14:26.777685) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 09:14:26.786908) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-12 09:14:26.787317) ========= /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=4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:14:27 INFO - MultiFileLogger online at 20160812 09:14:27 in /builds/slave/test 09:14:27 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 09:14:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:14:27 INFO - {'append_to_log': False, 09:14:27 INFO - 'base_work_dir': '/builds/slave/test', 09:14:27 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 09:14:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:14:27 INFO - 'branch': 'Fx-Team-Non-PGO', 09:14:27 INFO - 'buildbot_json_path': 'buildprops.json', 09:14:27 INFO - 'config_files': ('talos/linux_config.py',), 09:14:27 INFO - 'default_actions': ('clobber', 09:14:27 INFO - 'read-buildbot-config', 09:14:27 INFO - 'download-and-extract', 09:14:27 INFO - 'populate-webroot', 09:14:27 INFO - 'create-virtualenv', 09:14:27 INFO - 'install', 09:14:27 INFO - 'run-tests'), 09:14:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:14:27 INFO - 'download_minidump_stackwalk': True, 09:14:27 INFO - 'download_symbols': 'ondemand', 09:14:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:14:27 INFO - 'tooltool.py': '/tools/tooltool.py', 09:14:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:14:27 INFO - '/tools/misc-python/virtualenv.py')}, 09:14:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:14:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:14:27 INFO - 'installer_path': 'installer.exe', 09:14:27 INFO - 'log_level': 'info', 09:14:27 INFO - 'log_name': 'talos', 09:14:27 INFO - 'log_to_console': True, 09:14:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:14:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:14:27 INFO - 'opt_config_files': (), 09:14:27 INFO - 'pip_index': False, 09:14:27 INFO - 'sps_profile': False, 09:14:27 INFO - 'sps_profile_interval': 0, 09:14:27 INFO - 'suite': 'dromaeojs', 09:14:27 INFO - 'system_bits': '32', 09:14:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:14:27 INFO - 'title': 'talos-linux64-ix-030', 09:14:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:14:27 INFO - 'use_talos_json': True, 09:14:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:14:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:14:27 INFO - 'work_dir': 'build'} 09:14:27 INFO - [mozharness: 2016-08-12 16:14:27.042812Z] Running clobber step. 09:14:27 INFO - Running pre-action listener: _resource_record_pre_action 09:14:27 INFO - Running main action method: clobber 09:14:27 INFO - rmtree: /builds/slave/test/build 09:14:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:14:27 INFO - Running post-action listener: _resource_record_post_action 09:14:27 INFO - [mozharness: 2016-08-12 16:14:27.496939Z] Finished clobber step (success) 09:14:27 INFO - [mozharness: 2016-08-12 16:14:27.497041Z] Running read-buildbot-config step. 09:14:27 INFO - Running pre-action listener: _resource_record_pre_action 09:14:27 INFO - Running main action method: read_buildbot_config 09:14:27 INFO - Using buildbot properties: 09:14:27 INFO - { 09:14:27 INFO - "project": "", 09:14:27 INFO - "product": "firefox", 09:14:27 INFO - "script_repo_revision": "production", 09:14:27 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 09:14:27 INFO - "repository": "", 09:14:27 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 09:14:27 INFO - "buildid": "20160812071715", 09:14:27 INFO - "pgo_build": "False", 09:14:27 INFO - "basedir": "/builds/slave/test", 09:14:27 INFO - "buildnumber": 2092, 09:14:27 INFO - "slavename": "talos-linux64-ix-030", 09:14:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:14:27 INFO - "platform": "ubuntu64_hw", 09:14:27 INFO - "scheduler": "tests-fx-team-linux64-talos-7-3600", 09:14:27 INFO - "branch": "fx-team", 09:14:27 INFO - "repo_path": "integration/fx-team", 09:14:27 INFO - "revision": "124669ed2f09f68f7f8dd38f4fee5f3084da0aff", 09:14:27 INFO - "stage_platform": "linux64", 09:14:27 INFO - "builduid": "839fb31bf62e489a96d7176f79fea145", 09:14:27 INFO - "slavebuilddir": "test" 09:14:27 INFO - } 09:14:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 09:14:27 INFO - Running post-action listener: _resource_record_post_action 09:14:27 INFO - [mozharness: 2016-08-12 16:14:27.499500Z] Finished read-buildbot-config step (success) 09:14:27 INFO - [mozharness: 2016-08-12 16:14:27.499589Z] Running download-and-extract step. 09:14:27 INFO - Running pre-action listener: _resource_record_pre_action 09:14:27 INFO - Running main action method: download_and_extract 09:14:27 INFO - mkdir: /builds/slave/test/build/tests 09:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:27 INFO - trying https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:14:27 INFO - Downloading https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/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 09:14:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/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 09:14:28 INFO - Downloaded 1361 bytes. 09:14:28 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:14:28 INFO - Using the following test package requirements: 09:14:28 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:14:28 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:14:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:14:28 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:14:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:14:28 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:14:28 INFO - u'jsshell-linux-x86_64.zip'], 09:14:28 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:14:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:14:28 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:14:28 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:14:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:14:28 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:14:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:14:28 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:14:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:14:28 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:14:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:14:28 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:28 INFO - trying https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:14:28 INFO - Downloading https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/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 09:14:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/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 09:14:29 INFO - Downloaded 20262322 bytes. 09:14:29 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'] 09:14:29 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 09:14:30 INFO - Return code: 0 09:14:30 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 09:14:30 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')]} 09:14:30 INFO - trying https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:14:30 INFO - Downloading https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/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 09:14:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/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 09:14:31 INFO - Downloaded 20262322 bytes. 09:14:31 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'] 09:14:31 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 09:14:32 INFO - Return code: 0 09:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:32 INFO - trying https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:14:32 INFO - Downloading https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/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 09:14:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/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 09:14:33 INFO - Downloaded 14413719 bytes. 09:14:33 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'] 09:14:33 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 09:14:34 INFO - Return code: 0 09:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:34 INFO - trying https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:14:34 INFO - Downloading https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/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 09:14:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/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 09:14:35 INFO - Downloaded 55619069 bytes. 09:14:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:14:35 INFO - mkdir: /builds/slave/test/properties 09:14:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:14:35 INFO - Writing to file /builds/slave/test/properties/build_url 09:14:35 INFO - Contents: 09:14:35 INFO - build_url:https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:14:36 INFO - Running post-action listener: _resource_record_post_action 09:14:36 INFO - Running post-action listener: set_extra_try_arguments 09:14:36 INFO - [mozharness: 2016-08-12 16:14:36.542620Z] Finished download-and-extract step (success) 09:14:36 INFO - [mozharness: 2016-08-12 16:14:36.542853Z] Running populate-webroot step. 09:14:36 INFO - Running pre-action listener: _resource_record_pre_action 09:14:36 INFO - Running main action method: populate_webroot 09:14:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:14:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:14:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:14:36 INFO - '--tppagecycles', 09:14:36 INFO - '7'], 09:14:36 INFO - 'tests': ['tsvgm']}}, 09:14:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:14:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:14:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:14:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:14:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:14:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:14:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:14:36 INFO - 'talos_options': ['--disable-e10s'], 09:14:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:14:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:14:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:14:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:14:36 INFO - 'talos_options': ['--disable-e10s'], 09:14:36 INFO - 'tests': ['damp', 'tps']}, 09:14:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:14:36 INFO - 'tests': ['damp', 'tps']}, 09:14:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:14:36 INFO - 'tests': ['dromaeo_dom']}, 09:14:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:14:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:14:36 INFO - 'tests': ['basic_compositor_video']}, 09:14:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:14:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:14:36 INFO - 'tests': ['a11yr', 09:14:36 INFO - 'ts_paint', 09:14:36 INFO - 'tpaint', 09:14:36 INFO - 'sessionrestore', 09:14:36 INFO - 'sessionrestore_no_auto_restore', 09:14:36 INFO - 'tabpaint']}, 09:14:36 INFO - 'other-e10s': {'tests': ['a11yr', 09:14:36 INFO - 'ts_paint', 09:14:36 INFO - 'tpaint', 09:14:36 INFO - 'sessionrestore', 09:14:36 INFO - 'sessionrestore_no_auto_restore', 09:14:36 INFO - 'tabpaint']}, 09:14:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:14:36 INFO - 'ts_paint', 09:14:36 INFO - 'tpaint', 09:14:36 INFO - 'sessionrestore', 09:14:36 INFO - 'sessionrestore_no_auto_restore', 09:14:36 INFO - 'tabpaint']}, 09:14:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:14:36 INFO - 'ts_paint', 09:14:36 INFO - 'tpaint', 09:14:36 INFO - 'sessionrestore', 09:14:36 INFO - 'sessionrestore_no_auto_restore', 09:14:36 INFO - 'tabpaint']}, 09:14:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:14:36 INFO - 'tests': ['a11yr', 09:14:36 INFO - 'ts_paint', 09:14:36 INFO - 'tpaint', 09:14:36 INFO - 'sessionrestore', 09:14:36 INFO - 'sessionrestore_no_auto_restore', 09:14:36 INFO - 'tabpaint']}, 09:14:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:14:36 INFO - 'tests': ['a11yr', 09:14:36 INFO - 'ts_paint', 09:14:36 INFO - 'tpaint', 09:14:36 INFO - 'sessionrestore', 09:14:36 INFO - 'sessionrestore_no_auto_restore', 09:14:36 INFO - 'tabpaint']}, 09:14:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:14:36 INFO - 'tests': ['tsvgx', 09:14:36 INFO - 'tsvgr_opacity', 09:14:36 INFO - 'tart', 09:14:36 INFO - 'tscrollx', 09:14:36 INFO - 'cart']}, 09:14:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:14:36 INFO - 'tsvgr_opacity', 09:14:36 INFO - 'tart', 09:14:36 INFO - 'tscrollx', 09:14:36 INFO - 'cart']}, 09:14:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:14:36 INFO - 'talos_options': ['--disable-e10s'], 09:14:36 INFO - 'tests': ['tp5o']}, 09:14:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:14:36 INFO - 'tests': ['tp5o']}, 09:14:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:14:36 INFO - 'talos_options': ['--disable-e10s', 09:14:36 INFO - '--xperf_path', 09:14:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:14:36 INFO - 'tests': ['tp5n']}, 09:14:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:14:36 INFO - 'talos_options': ['--xperf_path', 09:14:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:14:36 INFO - 'tests': ['tp5n']}}, 09:14:36 INFO - 'talos.zip': {'path': '', 09:14:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:14:36 INFO - Running post-action listener: _resource_record_post_action 09:14:36 INFO - [mozharness: 2016-08-12 16:14:36.565377Z] Finished populate-webroot step (success) 09:14:36 INFO - [mozharness: 2016-08-12 16:14:36.565468Z] Running create-virtualenv step. 09:14:36 INFO - Running pre-action listener: _resource_record_pre_action 09:14:36 INFO - Running main action method: create_virtualenv 09:14:36 INFO - Creating virtualenv /builds/slave/test/build/venv 09:14:36 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 09:14:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:14:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:14:36 INFO - Using real prefix '/usr' 09:14:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:14:37 INFO - Installing distribute.............................................................................................................................................................................................done. 09:14:39 INFO - Installing pip.................done. 09:14:39 INFO - Return code: 0 09:14:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:14:39 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')]} 09:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:39 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')]} 09:14:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:39 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 0x1715030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d43b0>, '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 0x19d52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d5740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', '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 09:14:39 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 09:14:39 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 09:14:39 INFO - Using env: {'DISPLAY': ':0', 09:14:39 INFO - 'HOME': '/home/cltbld', 09:14:39 INFO - 'LANG': 'en_US.UTF-8', 09:14:39 INFO - 'LANGUAGE': 'en_US:en', 09:14:39 INFO - 'LOGNAME': 'cltbld', 09:14:39 INFO - 'MAIL': '/var/mail/cltbld', 09:14:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:39 INFO - 'MOZ_NO_REMOTE': '1', 09:14:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:39 INFO - 'NO_EM_RESTART': '1', 09:14:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:39 INFO - 'PWD': '/builds/slave/test', 09:14:39 INFO - 'SHELL': '/bin/bash', 09:14:39 INFO - 'SHLVL': '1', 09:14:39 INFO - 'TERM': 'linux', 09:14:39 INFO - 'TMOUT': '86400', 09:14:39 INFO - 'USER': 'cltbld', 09:14:39 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', 09:14:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:39 INFO - '_': '/tools/buildbot/bin/python'} 09:14:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:39 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 09:14:39 INFO - Cleaning up... 09:14:39 INFO - Return code: 0 09:14:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:14:39 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')]} 09:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:39 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')]} 09:14:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:39 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 0x1715030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d43b0>, '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 0x19d52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d5740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', '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 09:14:39 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 09:14:39 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 09:14:39 INFO - Using env: {'DISPLAY': ':0', 09:14:39 INFO - 'HOME': '/home/cltbld', 09:14:39 INFO - 'LANG': 'en_US.UTF-8', 09:14:39 INFO - 'LANGUAGE': 'en_US:en', 09:14:39 INFO - 'LOGNAME': 'cltbld', 09:14:39 INFO - 'MAIL': '/var/mail/cltbld', 09:14:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:39 INFO - 'MOZ_NO_REMOTE': '1', 09:14:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:39 INFO - 'NO_EM_RESTART': '1', 09:14:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:39 INFO - 'PWD': '/builds/slave/test', 09:14:39 INFO - 'SHELL': '/bin/bash', 09:14:39 INFO - 'SHLVL': '1', 09:14:39 INFO - 'TERM': 'linux', 09:14:39 INFO - 'TMOUT': '86400', 09:14:39 INFO - 'USER': 'cltbld', 09:14:39 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', 09:14:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:39 INFO - '_': '/tools/buildbot/bin/python'} 09:14:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:39 INFO - Downloading/unpacking psutil>=3.1.1 09:14:39 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 09:14:39 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 09:14:39 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 09:14:39 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 09:14:39 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 09:14:39 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 09:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:14:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:14:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:14:41 INFO - Installing collected packages: psutil 09:14:41 INFO - Running setup.py install for psutil 09:14:41 INFO - building 'psutil._psutil_linux' extension 09:14:41 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 09:14:41 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 09:14:41 INFO - building 'psutil._psutil_posix' extension 09:14:41 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 09:14:41 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 09:14:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:14:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:14:42 INFO - Successfully installed psutil 09:14:42 INFO - Cleaning up... 09:14:42 INFO - Return code: 0 09:14:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:14:42 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')]} 09:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:42 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')]} 09:14:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:42 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 0x1715030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d43b0>, '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 0x19d52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d5740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', '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 09:14:42 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 09:14:42 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 09:14:42 INFO - Using env: {'DISPLAY': ':0', 09:14:42 INFO - 'HOME': '/home/cltbld', 09:14:42 INFO - 'LANG': 'en_US.UTF-8', 09:14:42 INFO - 'LANGUAGE': 'en_US:en', 09:14:42 INFO - 'LOGNAME': 'cltbld', 09:14:42 INFO - 'MAIL': '/var/mail/cltbld', 09:14:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:42 INFO - 'MOZ_NO_REMOTE': '1', 09:14:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:42 INFO - 'NO_EM_RESTART': '1', 09:14:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:42 INFO - 'PWD': '/builds/slave/test', 09:14:42 INFO - 'SHELL': '/bin/bash', 09:14:42 INFO - 'SHLVL': '1', 09:14:42 INFO - 'TERM': 'linux', 09:14:42 INFO - 'TMOUT': '86400', 09:14:42 INFO - 'USER': 'cltbld', 09:14:42 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', 09:14:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:42 INFO - '_': '/tools/buildbot/bin/python'} 09:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:14:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:14:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:14:44 INFO - Installing collected packages: mozsystemmonitor 09:14:44 INFO - Running setup.py install for mozsystemmonitor 09:14:44 INFO - Successfully installed mozsystemmonitor 09:14:44 INFO - Cleaning up... 09:14:44 INFO - Return code: 0 09:14:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:14:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:44 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 0x1715030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d43b0>, '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 0x19d52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d5740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', '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 09:14:44 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 09:14:44 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 09:14:44 INFO - Using env: {'DISPLAY': ':0', 09:14:44 INFO - 'HOME': '/home/cltbld', 09:14:44 INFO - 'LANG': 'en_US.UTF-8', 09:14:44 INFO - 'LANGUAGE': 'en_US:en', 09:14:44 INFO - 'LOGNAME': 'cltbld', 09:14:44 INFO - 'MAIL': '/var/mail/cltbld', 09:14:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:44 INFO - 'MOZ_NO_REMOTE': '1', 09:14:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:44 INFO - 'NO_EM_RESTART': '1', 09:14:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:44 INFO - 'PWD': '/builds/slave/test', 09:14:44 INFO - 'SHELL': '/bin/bash', 09:14:44 INFO - 'SHLVL': '1', 09:14:44 INFO - 'TERM': 'linux', 09:14:44 INFO - 'TMOUT': '86400', 09:14:44 INFO - 'USER': 'cltbld', 09:14:44 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', 09:14:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:44 INFO - '_': '/tools/buildbot/bin/python'} 09:14:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:44 INFO - Downloading/unpacking jsonschema==2.5.1 09:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:14:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:14:46 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 09:14:46 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 09:14:46 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 09:14:46 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 09:14:46 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 09:14:46 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 09:14:46 INFO - Downloading functools32-3.2.3-2.tar.gz 09:14:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:14:46 INFO - warning: no files found matching '*.txt' 09:14:46 INFO - no previously-included directories found matching 'build' 09:14:46 INFO - no previously-included directories found matching 'dist' 09:14:46 INFO - no previously-included directories found matching '.git*' 09:14:46 INFO - Installing collected packages: jsonschema, functools32 09:14:46 INFO - Running setup.py install for functools32 09:14:46 INFO - warning: no files found matching '*.txt' 09:14:46 INFO - no previously-included directories found matching 'build' 09:14:46 INFO - no previously-included directories found matching 'dist' 09:14:46 INFO - no previously-included directories found matching '.git*' 09:14:46 INFO - Successfully installed jsonschema functools32 09:14:46 INFO - Cleaning up... 09:14:46 INFO - Return code: 0 09:14:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:14:46 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')]} 09:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:46 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')]} 09:14:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:46 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 0x1715030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d43b0>, '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 0x19d52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d5740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', '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 09:14:46 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 09:14:46 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 09:14:46 INFO - Using env: {'DISPLAY': ':0', 09:14:46 INFO - 'HOME': '/home/cltbld', 09:14:46 INFO - 'LANG': 'en_US.UTF-8', 09:14:46 INFO - 'LANGUAGE': 'en_US:en', 09:14:46 INFO - 'LOGNAME': 'cltbld', 09:14:46 INFO - 'MAIL': '/var/mail/cltbld', 09:14:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:46 INFO - 'MOZ_NO_REMOTE': '1', 09:14:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:46 INFO - 'NO_EM_RESTART': '1', 09:14:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:46 INFO - 'PWD': '/builds/slave/test', 09:14:46 INFO - 'SHELL': '/bin/bash', 09:14:46 INFO - 'SHLVL': '1', 09:14:46 INFO - 'TERM': 'linux', 09:14:46 INFO - 'TMOUT': '86400', 09:14:46 INFO - 'USER': 'cltbld', 09:14:46 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', 09:14:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:46 INFO - '_': '/tools/buildbot/bin/python'} 09:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:14:47 INFO - Cleaning up... 09:14:47 INFO - Return code: 0 09:14:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1715030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d43b0>, '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 0x19d52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d5740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', '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 09:14:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:14:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:14:47 INFO - Using env: {'DISPLAY': ':0', 09:14:47 INFO - 'HOME': '/home/cltbld', 09:14:47 INFO - 'LANG': 'en_US.UTF-8', 09:14:47 INFO - 'LANGUAGE': 'en_US:en', 09:14:47 INFO - 'LOGNAME': 'cltbld', 09:14:47 INFO - 'MAIL': '/var/mail/cltbld', 09:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:47 INFO - 'MOZ_NO_REMOTE': '1', 09:14:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:47 INFO - 'NO_EM_RESTART': '1', 09:14:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:47 INFO - 'PWD': '/builds/slave/test', 09:14:47 INFO - 'SHELL': '/bin/bash', 09:14:47 INFO - 'SHLVL': '1', 09:14:47 INFO - 'TERM': 'linux', 09:14:47 INFO - 'TMOUT': '86400', 09:14:47 INFO - 'USER': 'cltbld', 09:14:47 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', 09:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:47 INFO - '_': '/tools/buildbot/bin/python'} 09:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:47 INFO - Downloading/unpacking blobuploader==1.2.4 09:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:49 INFO - Downloading blobuploader-1.2.4.tar.gz 09:14:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:14:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:14:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:50 INFO - Downloading docopt-0.6.1.tar.gz 09:14:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:14:50 INFO - Installing collected packages: blobuploader, requests, docopt 09:14:50 INFO - Running setup.py install for blobuploader 09:14:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:14:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:14:50 INFO - Running setup.py install for requests 09:14:50 INFO - Running setup.py install for docopt 09:14:50 INFO - Successfully installed blobuploader requests docopt 09:14:50 INFO - Cleaning up... 09:14:50 INFO - Return code: 0 09:14:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:14:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:50 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 0x1715030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d43b0>, '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 0x19d52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d5740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', '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 09:14:50 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 09:14:50 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 09:14:50 INFO - Using env: {'DISPLAY': ':0', 09:14:50 INFO - 'HOME': '/home/cltbld', 09:14:50 INFO - 'LANG': 'en_US.UTF-8', 09:14:50 INFO - 'LANGUAGE': 'en_US:en', 09:14:50 INFO - 'LOGNAME': 'cltbld', 09:14:50 INFO - 'MAIL': '/var/mail/cltbld', 09:14:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:50 INFO - 'MOZ_NO_REMOTE': '1', 09:14:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:50 INFO - 'NO_EM_RESTART': '1', 09:14:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:50 INFO - 'PWD': '/builds/slave/test', 09:14:50 INFO - 'SHELL': '/bin/bash', 09:14:50 INFO - 'SHLVL': '1', 09:14:50 INFO - 'TERM': 'linux', 09:14:50 INFO - 'TMOUT': '86400', 09:14:50 INFO - 'USER': 'cltbld', 09:14:50 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', 09:14:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:50 INFO - '_': '/tools/buildbot/bin/python'} 09:14:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:14:51 INFO - Running setup.py (path:/tmp/pip-LZqYdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:14:51 INFO - Running setup.py (path:/tmp/pip-wkXnGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:14:51 INFO - Running setup.py (path:/tmp/pip-MYdusa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:14:51 INFO - Running setup.py (path:/tmp/pip-g5YErh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:14:51 INFO - Running setup.py (path:/tmp/pip-vSCaT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:14:51 INFO - Running setup.py (path:/tmp/pip-XoNqAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:14:51 INFO - Running setup.py (path:/tmp/pip-qMfhRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:14:51 INFO - Running setup.py (path:/tmp/pip-TAA6wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:14:51 INFO - Running setup.py (path:/tmp/pip-AIVgVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:14:51 INFO - Running setup.py (path:/tmp/pip-viLriE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:14:51 INFO - Running setup.py (path:/tmp/pip-Zn6fu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:14:51 INFO - Running setup.py (path:/tmp/pip-g5CPug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:14:51 INFO - Running setup.py (path:/tmp/pip-LHXuHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:14:52 INFO - Running setup.py (path:/tmp/pip-h0s8pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:52 INFO - Running setup.py (path:/tmp/pip-d3m26A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:14:52 INFO - Running setup.py (path:/tmp/pip-maG_cF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:14:52 INFO - Running setup.py (path:/tmp/pip-PddzaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:14:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:14:52 INFO - Running setup.py install for manifestparser 09:14:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:14:52 INFO - Running setup.py install for mozcrash 09:14:52 INFO - Running setup.py install for mozdebug 09:14:52 INFO - Running setup.py install for mozdevice 09:14:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:14:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:14:52 INFO - Running setup.py install for mozfile 09:14:52 INFO - Running setup.py install for mozhttpd 09:14:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:14:52 INFO - Running setup.py install for mozinfo 09:14:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:14:52 INFO - Running setup.py install for mozInstall 09:14:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:14:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:14:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:14:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:14:53 INFO - Running setup.py install for mozleak 09:14:53 INFO - Running setup.py install for mozlog 09:14:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:14:53 INFO - Running setup.py install for moznetwork 09:14:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:14:53 INFO - Running setup.py install for mozprocess 09:14:53 INFO - Running setup.py install for mozprofile 09:14:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:14:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:14:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:14:53 INFO - Running setup.py install for mozrunner 09:14:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:14:53 INFO - Running setup.py install for mozscreenshot 09:14:53 INFO - Running setup.py install for moztest 09:14:53 INFO - Running setup.py install for mozversion 09:14:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:14:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:14:54 INFO - Cleaning up... 09:14:54 INFO - Return code: 0 09:14:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:54 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 0x1715030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d43b0>, '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 0x19d52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d5740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', '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 09:14:54 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 09:14:54 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 09:14:54 INFO - Using env: {'DISPLAY': ':0', 09:14:54 INFO - 'HOME': '/home/cltbld', 09:14:54 INFO - 'LANG': 'en_US.UTF-8', 09:14:54 INFO - 'LANGUAGE': 'en_US:en', 09:14:54 INFO - 'LOGNAME': 'cltbld', 09:14:54 INFO - 'MAIL': '/var/mail/cltbld', 09:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:54 INFO - 'MOZ_NO_REMOTE': '1', 09:14:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:54 INFO - 'NO_EM_RESTART': '1', 09:14:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:54 INFO - 'PWD': '/builds/slave/test', 09:14:54 INFO - 'SHELL': '/bin/bash', 09:14:54 INFO - 'SHLVL': '1', 09:14:54 INFO - 'TERM': 'linux', 09:14:54 INFO - 'TMOUT': '86400', 09:14:54 INFO - 'USER': 'cltbld', 09:14:54 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', 09:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:54 INFO - '_': '/tools/buildbot/bin/python'} 09:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:14:54 INFO - Running setup.py (path:/tmp/pip-Gp9rVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:14:54 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)) 09:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:14:54 INFO - Running setup.py (path:/tmp/pip-WIBUVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:14:54 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)) 09:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:14:54 INFO - Running setup.py (path:/tmp/pip-Z4ajni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:14:54 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)) 09:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:14:54 INFO - Running setup.py (path:/tmp/pip-EkVySr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:14:54 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)) 09:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:14:54 INFO - Running setup.py (path:/tmp/pip-_zlb3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:14:54 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)) 09:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:14:54 INFO - Running setup.py (path:/tmp/pip-a7sYTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:14:54 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)) 09:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:14:54 INFO - Running setup.py (path:/tmp/pip-yL2C_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:14:54 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)) 09:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:14:54 INFO - Running setup.py (path:/tmp/pip-D9wACD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:14:54 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)) 09:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:14:54 INFO - Running setup.py (path:/tmp/pip-UWPHOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:14:54 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)) 09:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:14:54 INFO - Running setup.py (path:/tmp/pip-WJ5k0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:14:55 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)) 09:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:14:55 INFO - Running setup.py (path:/tmp/pip-bjF5BD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:14:55 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)) 09:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:14:55 INFO - Running setup.py (path:/tmp/pip-JlH_KE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:14:55 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)) 09:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:14:55 INFO - Running setup.py (path:/tmp/pip-RITNXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:14:55 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)) 09:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:14:55 INFO - Running setup.py (path:/tmp/pip-IP83Op-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:14:55 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)) 09:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:55 INFO - Running setup.py (path:/tmp/pip-jd5aOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:14:55 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)) 09:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:14:55 INFO - Running setup.py (path:/tmp/pip-T9dg2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:14:55 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)) 09:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:14:55 INFO - Running setup.py (path:/tmp/pip-mDpqat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:14:55 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)) 09:14:55 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)) 09:14:55 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)) 09:14:55 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)) 09:14:55 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)) 09:14:55 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)) 09:14:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:14:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:14:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:14:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:57 INFO - Downloading blessings-1.6.tar.gz 09:14:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:14:57 INFO - Installing collected packages: blessings 09:14:57 INFO - Running setup.py install for blessings 09:14:57 INFO - Successfully installed blessings 09:14:57 INFO - Cleaning up... 09:14:57 INFO - Return code: 0 09:14:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:14:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:14:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:14:57 INFO - Reading from file tmpfile_stdout 09:14:57 INFO - Current package versions: 09:14:57 INFO - argparse == 1.2.1 09:14:57 INFO - blessings == 1.6 09:14:57 INFO - blobuploader == 1.2.4 09:14:57 INFO - docopt == 0.6.1 09:14:57 INFO - functools32 == 3.2.3-2 09:14:57 INFO - jsonschema == 2.5.1 09:14:57 INFO - manifestparser == 1.1 09:14:57 INFO - mozInstall == 1.12 09:14:57 INFO - mozcrash == 0.17 09:14:57 INFO - mozdebug == 0.1 09:14:57 INFO - mozdevice == 0.48 09:14:57 INFO - mozfile == 1.2 09:14:57 INFO - mozhttpd == 0.7 09:14:57 INFO - mozinfo == 0.9 09:14:57 INFO - mozleak == 0.1 09:14:57 INFO - mozlog == 3.3 09:14:57 INFO - moznetwork == 0.27 09:14:57 INFO - mozprocess == 0.23 09:14:57 INFO - mozprofile == 0.28 09:14:57 INFO - mozrunner == 6.12 09:14:57 INFO - mozscreenshot == 0.1 09:14:57 INFO - mozsystemmonitor == 0.3 09:14:57 INFO - moztest == 0.7 09:14:57 INFO - mozversion == 1.4 09:14:57 INFO - psutil == 3.1.1 09:14:57 INFO - requests == 1.2.3 09:14:57 INFO - wsgiref == 0.1.2 09:14:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:14:57 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')]} 09:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:57 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')]} 09:14:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:57 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 0x1715030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d43b0>, '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 0x19d52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d5740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', '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 09:14:57 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 09:14:57 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 09:14:57 INFO - Using env: {'DISPLAY': ':0', 09:14:57 INFO - 'HOME': '/home/cltbld', 09:14:57 INFO - 'LANG': 'en_US.UTF-8', 09:14:57 INFO - 'LANGUAGE': 'en_US:en', 09:14:57 INFO - 'LOGNAME': 'cltbld', 09:14:57 INFO - 'MAIL': '/var/mail/cltbld', 09:14:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:57 INFO - 'MOZ_NO_REMOTE': '1', 09:14:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:57 INFO - 'NO_EM_RESTART': '1', 09:14:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:57 INFO - 'PWD': '/builds/slave/test', 09:14:57 INFO - 'SHELL': '/bin/bash', 09:14:57 INFO - 'SHLVL': '1', 09:14:57 INFO - 'TERM': 'linux', 09:14:57 INFO - 'TMOUT': '86400', 09:14:57 INFO - 'USER': 'cltbld', 09:14:57 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', 09:14:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:57 INFO - '_': '/tools/buildbot/bin/python'} 09:14:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:58 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)) 09:14:58 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)) 09:14:58 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)) 09:14:58 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)) 09:14:58 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)) 09:14:58 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)) 09:14:58 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)) 09:14:58 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)) 09:14:58 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)) 09:14:58 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)) 09:14:58 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)) 09:14:58 INFO - Cleaning up... 09:14:58 INFO - Return code: 0 09:14:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:14: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')]} 09:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14: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')]} 09:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:58 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 0x1715030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d43b0>, '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 0x19d52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d5740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', '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 09:14:58 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 09:14:58 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 09:14:58 INFO - Using env: {'DISPLAY': ':0', 09:14:58 INFO - 'HOME': '/home/cltbld', 09:14:58 INFO - 'LANG': 'en_US.UTF-8', 09:14:58 INFO - 'LANGUAGE': 'en_US:en', 09:14:58 INFO - 'LOGNAME': 'cltbld', 09:14:58 INFO - 'MAIL': '/var/mail/cltbld', 09:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:58 INFO - 'MOZ_NO_REMOTE': '1', 09:14:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:58 INFO - 'NO_EM_RESTART': '1', 09:14:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:58 INFO - 'PWD': '/builds/slave/test', 09:14:58 INFO - 'SHELL': '/bin/bash', 09:14:58 INFO - 'SHLVL': '1', 09:14:58 INFO - 'TERM': 'linux', 09:14:58 INFO - 'TMOUT': '86400', 09:14:58 INFO - 'USER': 'cltbld', 09:14:58 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', 09:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:58 INFO - '_': '/tools/buildbot/bin/python'} 09:14:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:14:58 INFO - Cleaning up... 09:14:58 INFO - Return code: 0 09:14:58 INFO - Running post-action listener: _resource_record_post_action 09:14:58 INFO - Running post-action listener: _start_resource_monitoring 09:14:58 INFO - Starting resource monitoring. 09:14:58 INFO - [mozharness: 2016-08-12 16:14:58.402133Z] Finished create-virtualenv step (success) 09:14:58 INFO - [mozharness: 2016-08-12 16:14:58.402576Z] Running install step. 09:14:58 INFO - Running pre-action listener: _resource_record_pre_action 09:14:58 INFO - Running main action method: install 09:14:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:14:58 INFO - Reading from file tmpfile_stdout 09:14:58 INFO - Detecting whether we're running mozinstall >=1.0... 09:14:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:14:58 INFO - Reading from file tmpfile_stdout 09:14:58 INFO - Output received: 09:14:58 INFO - Usage: mozinstall [options] installer 09:14:58 INFO - Options: 09:14:58 INFO - -h, --help show this help message and exit 09:14:58 INFO - -d DEST, --destination=DEST 09:14:58 INFO - Directory to install application into. [default: 09:14:58 INFO - "/builds/slave/test"] 09:14:58 INFO - --app=APP Application being installed. [default: firefox] 09:14:58 INFO - mkdir: /builds/slave/test/build/application 09:14:58 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'] 09:14:58 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 09:15:14 INFO - Reading from file tmpfile_stdout 09:15:14 INFO - Output received: 09:15:14 INFO - /builds/slave/test/build/application/firefox/firefox 09:15:14 INFO - Running post-action listener: _resource_record_post_action 09:15:14 INFO - [mozharness: 2016-08-12 16:15:14.742801Z] Finished install step (success) 09:15:14 INFO - [mozharness: 2016-08-12 16:15:14.743051Z] Running run-tests step. 09:15:14 INFO - Running pre-action listener: _resource_record_pre_action 09:15:14 INFO - Running main action method: run_tests 09:15:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:15:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:15:14 INFO - Python 2.7.3 09:15:14 INFO - Return code: 0 09:15:14 INFO - grabbing minidump binary from tooltool 09:15: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')]} 09:15:14 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 0x19d43b0>, '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 0x19d52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19d5740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:15:14 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 09:15:14 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 09:15:14 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 09:15:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:15:14 INFO - Return code: 0 09:15:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:15:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:15:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:15:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:15:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:15:14 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/EvIc5pFeRTW8YoNIuW0RVw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 09:15:14 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/EvIc5pFeRTW8YoNIuW0RVw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --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 09:15:14 INFO - Using env: {'DISPLAY': ':0', 09:15:14 INFO - 'HOME': '/home/cltbld', 09:15:14 INFO - 'LANG': 'en_US.UTF-8', 09:15:14 INFO - 'LANGUAGE': 'en_US:en', 09:15:14 INFO - 'LOGNAME': 'cltbld', 09:15:14 INFO - 'MAIL': '/var/mail/cltbld', 09:15:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:15:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:15:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:14 INFO - 'MOZ_NO_REMOTE': '1', 09:15:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:15:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:14 INFO - 'NO_EM_RESTART': '1', 09:15:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:14 INFO - 'PWD': '/builds/slave/test', 09:15:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:15:14 INFO - 'SHELL': '/bin/bash', 09:15:14 INFO - 'SHLVL': '1', 09:15:14 INFO - 'TERM': 'linux', 09:15:14 INFO - 'TMOUT': '86400', 09:15:14 INFO - 'USER': 'cltbld', 09:15:14 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767', 09:15:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:14 INFO - '_': '/tools/buildbot/bin/python'} 09:15:14 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/EvIc5pFeRTW8YoNIuW0RVw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 09:15:15 INFO - mozversion application_buildid: 20160812071715 09:15:15 INFO - mozversion application_changeset: 124669ed2f09f68f7f8dd38f4fee5f3084da0aff 09:15:15 INFO - mozversion application_display_name: Nightly 09:15:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:15:15 INFO - mozversion application_name: Firefox 09:15:15 INFO - mozversion application_remotingname: firefox 09:15:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:15:15 INFO - mozversion application_vendor: Mozilla 09:15:15 INFO - mozversion application_version: 51.0a1 09:15:15 INFO - mozversion platform_buildid: 20160812071715 09:15:15 INFO - mozversion platform_changeset: 124669ed2f09f68f7f8dd38f4fee5f3084da0aff 09:15:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:15:15 INFO - mozversion platform_version: 51.0a1 09:15:15 INFO - using testdate: 1471018515 09:15:15 INFO - actual date: 1471018515 09:15:15 INFO - starting webserver on 'localhost:43215' 09:15:15 INFO - SUITE-START | Running 2 tests 09:15:15 INFO - TEST-START | dromaeo_css 09:15:15 INFO - operating with platform_type : linux_ 09:15:15 INFO - Initialising browser for dromaeo_css test... 09:15:15 INFO - TEST-INFO | started process 5980 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcix119/profile http://localhost:43215/getInfo.html) 09:15:19 INFO - PROCESS | 5980 | __metrics Screen width/height:1600/1200 09:15:19 INFO - PROCESS | 5980 | colorDepth:24 09:15:19 INFO - PROCESS | 5980 | Browser inner width/height: 1024/697 09:15:19 INFO - PROCESS | 5980 | __metrics 09:15:20 INFO - PROCESS | 5980 | 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" 09:15:20 INFO - PROCESS | 5980 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:15:21 INFO - TEST-INFO | 5980: exit 0 09:15:21 INFO - Browser initialized. 09:15:21 INFO - Running cycle 1/1 for dromaeo_css test... 09:15:21 INFO - TEST-INFO | started process 6060 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcix119/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:15:33 INFO - PROCESS | 6060 | RSS: Main: 157302784 09:15:33 INFO - PROCESS | 6060 | 09:16:24 INFO - PROCESS | 6060 | 1471018584208 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] 09:18:37 INFO - PROCESS | 6060 | Cycle 1(1): loaded http://localhost:43215/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43215/tests/dromaeo/cssquery-ext.html) 09:18:38 INFO - PROCESS | 6060 | RSS: Main: 279642112 09:18:38 INFO - PROCESS | 6060 | 09:21:41 INFO - PROCESS | 6060 | Cycle 1(1): loaded http://localhost:43215/tests/dromaeo/cssquery-ext.html (next: http://localhost:43215/tests/dromaeo/cssquery-jquery.html) 09:21:42 INFO - PROCESS | 6060 | RSS: Main: 246145024 09:21:42 INFO - PROCESS | 6060 | 09:24:44 INFO - PROCESS | 6060 | Cycle 1(1): loaded http://localhost:43215/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43215/tests/dromaeo/cssquery-mootools.html) 09:24:45 INFO - PROCESS | 6060 | RSS: Main: 226660352 09:24:45 INFO - PROCESS | 6060 | 09:27:47 INFO - PROCESS | 6060 | Cycle 1(1): loaded http://localhost:43215/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43215/tests/dromaeo/cssquery-prototype.html) 09:27:47 INFO - PROCESS | 6060 | RSS: Main: 187768832 09:27:47 INFO - PROCESS | 6060 | 09:30:51 INFO - PROCESS | 6060 | Cycle 1(1): loaded http://localhost:43215/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43215/tests/dromaeo/cssquery-yui.html) 09:30:51 INFO - PROCESS | 6060 | RSS: Main: 188358656 09:30:51 INFO - PROCESS | 6060 | 09:33:54 INFO - PROCESS | 6060 | Cycle 1(1): loaded http://localhost:43215/tests/dromaeo/cssquery-yui.html (next: http://localhost:43215/tests/dromaeo/cssquery-dojo.html) 09:33:54 INFO - PROCESS | 6060 | RSS: Main: 277938176 09:33:54 INFO - PROCESS | 6060 | 09:33:54 INFO - PROCESS | 6060 | __start_tp_report 09:33:54 INFO - PROCESS | 6060 | _x_x_mozilla_page_load 09:33:54 INFO - PROCESS | 6060 | _x_x_mozilla_page_load_details 09:33:54 INFO - PROCESS | 6060 | |i|pagename|runs| 09:33:54 INFO - PROCESS | 6060 | |0;dojo.html;2646.841189528627;2669.7063323034467;2689.1125928443616;2696.635954146191;2709.6341993830833;4605.470370907338;4679.649026323023;4835.371401717776;4987.576056035241;5003.3245511855885;4844.297576851363;4847.224444088946;4895.437024742156;4895.534924182206;4901.3873265841685;4411.757353345564;4413.051193993291;4488.573585509372;4550.772461376918;4584.954150458503;4502.09958008398;4529.388532548097;4539;4558.544145585448;4570.7257564546235;4352.499462561815;4397.494288156839;4401.141777353418;4442.400275962736;4526.094781043771;4437.9112417751785;4461.888452788674;4484.349769283468;4555.954440455586;4572.519885412017;4476.865694029184;4496.168208881368;4501.054778496519;4537.069900670354;4547.931781023287;4613.354130421743;4883.340748998875;4887.926681099786;4935.876603084916;4937.35814344133;5209.765560549784;5260.894782104337;5267.868303292448;5348.1442969124755;5348.411674715778;5311.282976798121;5314.3091398117995;5360.785568577252;5371.435999220064;5379.892402151974;4590.67865249436;5001.849944501707;5012.398512178489;5016.648834581544;5020.297158397827;5040.49595040493;5313.787448502017;5355.919661205085;5384.111621582473;5446.455354464521;5175.508326708957;5216.869578260498;5229.267902493578;5235.1623740201385;5241.0566098101945;5246.711430871339;5263.815766448232;5264.92102618461;5316.362036555638;5321.813736519203;4915.655904086751;4959.528844759742;5013.1227035268685;5051.116054690415;5055;5077.949220507821;5092.134337162691;5094.898102038012;5097.158968770185;5129.9743501283;4303.440552728188;4304.096139810675;4311.547287534827;4312.029793296483;4350.760708161081;3687.631236876291;3690.225052738901;3760.6427389397963;3782.508273924372;3809.238152369537;4515;4518.751468669255;4541.931871021938;4553.772311384418;4557.407537020166;5213.765380557884;5240.34495688039;5246.134387826042;5249.2126181073145;5251.10731175701;4311.827526898952;4341.891452713644;4344.630706389992;4510.66170037242;4542.636589072867;4672.135654903854;4716.434027916672;4722.740249286254;4731.550502702238;4739.881502962385;4929.383827021622;4939.876503087452;4942.308076869242;4954.355933728592;4991.850244492671;5219.556337711337;5223.712695801767;5232.843014709565;5250.448702886142;5251.448597897319;4719.10337035962;4757.619390448686;4839.975800120977;4876.95123048765;4886.951130488792;5074.746262686777;5106;5113.8721531962;5114.232865070269;5165.457626949116;5191.792328306826;5194.7922083116255;5203.92194117081;5210.921836172384;5252.501012403815;4789.521047895182;5037.92443113361;5047.1419858623385;5070.670406423571;5096.261042148793;5089.211172268304;5110.259012443101;5115.079285728604;5123.257127716534;5163.612729045261;5029.0947629427055;5041.697498150123;5085.949140508696;5089.847304580869;5091.490850914879;4914.36113305268;5013.749312534432;5058.089543882136;5058.140116180185;5071.949280507295;4966.776495057658;4967.3542439482635;4971.353724015855;4977.601791856715;5017.648764586444 09:33:54 INFO - PROCESS | 6060 | |1;ext.html;565.8557067947672;572.1903506538249;572.3303734630482;578.661483032426;580.9535237181026;28921.27696807581;40563.18873622526;41868.23185913275;41874.79062604683;44915.77542112293;41032;41261;41325.380119298236;41416.585834141646;41432.79283603586;7456.888146677804;7634.312911837934;7640.694372225104;7642.465027448081;7643;7385.630718464082;9789.461579613118;10038.397696138218;10058.396496210251;10371.948140259288;10168.64409745659;10236.283460157792;10252.538635761372;10254.282200245985;10262.589496420134;9840.458774767385;9875.506224688737;9885.25860560461;9903.306768526207;9906.306558540906;9971.650992215238;10055.69832905014;10061.14480269178;10070.446125463097;10071.194304455626;38702.225955481015;38813.223735525135;38815.22369552621;38882.80558597189;38909.41635875464;3087.3516561522106;3201.055688571877;3229.273413481971;3372.898813035613;3450;29619.555706664418;29954.40091198164;30196.396072078656;30501.38997220043;30714.53928191079;3284.917877053069;3342.2479942013097;3350.748693847947;3385.509101180315;3398.6091599465885;251217;251706;252001.7399913007;252305;252549;32262.677373226437;54447.45552544424;54677.45322546724;54785.452145478834;54806;32596.348073038407;34226.31547369039;34277;34291.14272143217;34310.65689343074;12217;12273.815892761615;12290;12295.75408491843;12309.876901231051;9462.763430914281;10418.895811041944;10640.46797660114;10779.137668986463;10779.137668986463;7742.574158421228;7905.407094467938;8004.879926801103;8017.358611311081;8019.117897031322;3084.0901933929545;3093.5359696045325;3112.8015713950294;3117.080461263887;3136.1532386255585;839.3033781960958;896.8254361494927;914.7484441778431;973.2384334546925;983.7983213441228;25093.372665683502;25312.441154025182;25347.87326063358;25647.358816029748;25897.352566185997;963.9614415423305;982.3710140892582;1048.0619845238468;1066.6053560182627;1094.2887123369715;13461.730765384833;13919;14134.222617756122;14447.85552144486;14485.130892146504;393265;396543;396763;397611;398337;32991;33102.66897331044;33132;33132.3373532528;33206.83396583002;1052.2108418685987;1077.579743899864;1127.3292391027326;1156.9016633586239;1209.909256805743;27145.45709085847;28760.424791504473;29707;30022.549661754656;30051.849740751157;42703;43203.567964319955;43238.351424729284;43272.56727432685;43313.350299744896;236415;237766;237892;238705;238774;25519;25891.611625826004;26088.478230434906;26319.86840065864;27240.863795680896;42550.5744942559;42618.57381426146;42671.35992960046;42744.786276069666;42856.5714342865;36083.63916360908;36122.819385902905;36232.63767362293;36235.63764362428;36648.81675591605;36139.994422532676;36449.817750911076;36799;37504;37575;35742.64257357393;36233;36257.81871090628;36336.45495317625;37186.25627487489;31370.372592547566;33550.32899342048;33944.830275848464;33995.4900676495;34192.82903585466;25502.617460738475;25668.614970775827;25790.226293211235;25982.74017259803;26411 09:33:54 INFO - PROCESS | 6060 | |2;jquery.html;333.96493368196354;358.76859425670426;366.6791557387287;366.79459502678503;372.81715555423;12664.936675316621;13593.863336016126;13796.310184490772;17136.82863171368;17168.82831171688;15205.695886082272;15983.92008039961;16004.279807408666;16465.506034818944;16741.330346786144;4474.776261186944;4606.654500912445;4615.8153673853;4617.676762626617;4619.284010978304;3452.430348992413;4947.431045429767;5126.41046279677;5160.741962901859;5215.269862219292;5229.424763276036;5230.895382092356;5241.42344342122;5245.868853278679;5251.55361794248;5056.544910958013;5249.553787928012;5262.131748261532;5276.841694749163;5279.340082489689;5042.243663450475;5082.517160869711;5084.313617661605;5088.7964481420695;5091.032703786269;11701;11894.762104757943;11965.10261730365;12018.098642601839;12018.819717704242;1829.268292682924;1847.6674198644373;1852.6016906365196;1872.307246318857;1937.3703546347494;8690.782730431727;9143.222826059793;9242.260619150518;11474.221495668186;13836.169829810144;1705.6588682263473;1737.4787563730831;1746.3451205797826;1754.175537497374;1819.4541637508826;60415;72051;72566;72775.63612181906;73759.26240737578;9701.369410988264;9955.352902061342;10023.348482348623;10129.544170512345;10159.187265018782;13363.198208107544;13452.730945381038;13458;13461.25963072021;13461.25963072021;6668.799936001928;6810.9318906810295;6883.105196324445;6968.721251149897;6979.720811167598;6440.967795160994;6452.580582262138;6622.437092847065;6689.933100668931;6702.530822842452;4167.437395951569;4188.162367526507;4197.580241975778;4223.472065991751;4228.61942425177;1593.5139782366257;1698.3800912666788;1705.914704264782;1734.4276388791668;1774.2370780564481;702.4696354252507;808.1675873849944;851.153102662842;869.5912920927154;916.9724908252763;16990.150492475328;17078.146092695562;17379.91310043467;17602.383916563114;17965.820341796414;858.7466697324304;864.9308055355557;880.7713240030122;885.7790401916151;935.6163972771267;9361.531923403803;9495.287853411022;9796.951015244878;10028.047335503115;10226.28416010872;408647;411877;422385;423155;424510;78305.605732477;78796;80551;80949;81425.59287203527;934.425328423015;935.0042205051734;945.3477100800426;964.8552717092492;977.2524019125375;15943.920280398524;16005.439809606607;16500;16595.66808663844;16669.416570419977;38720.80639596784;39960.2007959845;40450.39324410195;41851.372229415974;42180.78909605432;51507;53230.46769532255;55618;56232.156517653086;56404.153937691786;10811.729706757395;11037.944810275898;11488.54045838157;11731.120165987755;11963.983061439874;12714.300713460849;12895.742085158432;12975.545855894998;13018.218906865619;13151;11110.277831940897;11326;11349.773004540028;11388.316700997966;11550.727526410272;11065.668029959113;11214.66356009321;11349.773004539698;11632.360220187633;11761.823572646246;10764.677059688222;10784.787668263283;11279.605213817476;11298.09615230796;11412.372319522507;11119.888801112209;11152.330860148417;11238.494267758135;11584.304941703525;11618.186726929034;10934.398608076632;10976.725581860517;11169.38568378747;11400.372979486208;11677.941610291895 09:33:54 INFO - PROCESS | 6060 | |3;mootools.html;1368.5894231730479;1397.6226418866906;1414.8443671196173;1604.8796340274482;1635.8325219084709;7322.633868306583;7915.050193976724;7917.168697286788;7919.722809701661;7927.524348539092;6504.414602685757;7198.892016619755;7451.068616422947;7452.701891924316;7772.455928085036;2123.097683484517;2151.4943988162768;2152.1929276521305;2152.9354119376444;2156.996996396675;2086.62440760663;2139.828813694901;2167.2414654870827;2171.869687818728;2173.7717539658265;2127.2129312154525;2134.3810295014428;2143.132031527226;2144.6997420361163;2166.068590506082;2108.2199586153147;2109.282843833104;2113.429374069008;2115.270231770037;2116.047778499666;2075.1284460526617;2107.1887323380456;2109.7362829646295;2110.5989861926187;2110.6517424624917;7584.634765742164;7678;7776.611169441505;7789.221077892222;7795.025621797276;4343.630791382736;4499.055198408339;4508.661850361236;4514.706544074657;4518.954810451919;2771.9445611087867;2825.3360460291815;2942.337973955864;2947.4252520758246;2947.439986402577;884.7788052986754;912.3431129586753;922.0318665401302;922.1424075609681;955.2214944820018;10523.052925236689;10616.203784716177;10656.20078494116;10663.306885052447;10729.248952573324;3769.7361184717356;3900.9609903900596;4021.1153546219784;4060.4924384451942;4061.918761624751;8923.776905577413;8937.46375217489;8942.329325300629;8971;8985.865212021825;4466.6203372713035;4483.663725220622;4483.798229079699;4485.618722408632;4486.753228572395;3446.431338829065;3994.2211268802885;4090.8363665453644;4097.733647312915;4124.608162224584;3576.5529308836394;3593.5508061492314;3721.4417837324077;3724.9068773280883;3726.9440958385644;1161.5760247509768;1184.1888306509895;1189.250772013626;1190.5535424215918;1194.062660811259;769.5998080997849;833.3000279765026;839.4711331860889;850.5959669157224;856.5931182688173;4931.704097754075;4955.925661115014;5052.267421223902;5125.743712814419;5180.067587834151;357.1553276501056;363.1103795700544;372.24620144207654;372.7632953074817;373.9719521035933;3355.1444381682513;3427.8286085695618;3428.211511352353;3444.741644376682;3484.094135524731;8712.477251364939;9093.81812363749;9101.135392137736;9250.953745231365;9313.208377288007;9197;9230.123138301984;9232;9232.215261702697;9233.630654773733;7029.261927497641;7197.604131772803;7203.459740519482;7208.135023797073;7213.278672132849;5340.412554619066;5398;5473.972630136983;5604.495595396434;5761.2222349983895;8510.659573616986;8684.261837743863;8758.518281494578;8800.515971621366;8804.427712198687;8395.874061889199;8556.486610803371;8596.355273354398;8610.741677749678;8750.956245218733;5746.741396637078;6513.478921686348;6673.299303573055;6673.933260667525;6721.126253587003;6915.446764258948;7021.297870212938;7164.534305270141;7188.784336469915;7195.532290401107;6335.606226268826;6611.206655201407;6671.132752742113;6671.199456065303;6820.658967051727;6152.630842149485;6159.414855588712;6177.320494745484;6230.252369715663;6250.49996000328;5984.1921340618455;6099.420555047263;6111.327753946974;6111.96944015277;6118.388161183846;4384.649228061811;4520.254158063883;4540.704854184468;4553.54464553542;4719.528047195253;4489.34904438848;4635.119327327797;4674.76626168697;4798.832040878552;4872.537108974641 09:33:54 INFO - PROCESS | 6060 | |4;prototype.html;284.2495811058805;287.1357214783502;290.6091307191827;291.3226747811338;293.2156481412222;4758.143534163849;4827.8310259140935;4850.490698476658;4855.247436647316;4860.586850117736;4748.240281554952;4780;4782.337221209319;4784.282357646355;4790.544898234671;3603.5315408996858;3623.3332666973224;3652.1234903623204;3664.4320130379833;3697.297513472445;3682.3187710273687;3690.1697118148472;3691.593924668284;3732.3095227382887;3737.5141231639986;3641.3263546243898;3644.744867859251;3655.232401195752;3671.7980511072014;3684.6683798458134;3545.450455179451;3551.68034876861;3643.417053271477;3671.6328367163337;3695.981520092382;3199.984000080008;3206.71139597435;3210.4060748761553;3210.9839450802597;3219.4687876500348;4623.9075218495445;4663.27719203524;4665.253559430509;4675.532446755331;4700.31845382421;3959.821808018646;4175.269327867642;4178.8328466861285;4184.079502509443;4213.431186789805;4922.80308787648;4939.481354457802;4951.92572111415;4953.678010929278;4973.975130124362;3463.8614455421753;3480.3039392121677;3484.9477257841154;3485.128717820545;3501.422265326218;5179.663321884065;5217.6608520446425;5229.581633469314;5239.869003274911;5240.34495688039;3467.757256991986;3552.2540266544315;3556.5376501054698;3557.679808817219;3586.794121615188;3577.58857731359;3590.281943611288;3594.946075808865;3598.442241452579;3617.4754660574595;4183.728057676241;4189.895252618709;4192.916141677149;4195.098053918422;4202.663786897041;4831.541003604652;4955.281484184773;5001.274815151783;5022.874428139326;5053.620978426633;1228.5270170984056;1246.5013994402311;1247.65689435406;1247.8627350991383;1257.974840503203;768.8885111658778;774.3108633316241;789.2265579731895;804.1676864445271;807.885118536416;4385.693001489865;4392.231359512137;4452.220861349316;4473.686841921034;4481.708688935209;5110.182370820651;5120.718360490208;5140.3831540215415;5152.845414637567;5171.560417364566;4435.246008178618;4446.243329135892;4452.931296488884;4461.977690111528;4473.373727678128;2590.663213782234;2732.4261904999776;2737.7536021757737;2738.342797728531;2744.5608702607487;4882.194437917775;4899.951000490021;4901.558859702573;4902.935665209244;4906.675197696673;5009.499050095034;5268.604854635918;5287.206918962186;5294.364676238799;5295;4902.240152776254;4903.289023091632;4908.190148625579;4917.286993385867;4946.406431228275;5158.200478925773;5185.899710617206;5219.164933610604;5242.213667949838;5254.579633629377;4912.189488734319;5080.415752188545;5101.489851014869;5128.333316668809;5142.922856157235;4852.2479015752615;4887.36464259644;4946.332245147003;4948.802047918043;4957.429895561912;3626.9274614507035;3643.961470980758;3674.7978861161823;3683.3001729671278;3684.3368193725387;4908.9754551227015;5251.081060814418;5261.842144735664;5263.526282634582;5270.762815673381;5012.598992080552;5029.220470826881;5037.496250374933;5040.840606660416;5066.36670416198;5015.774290157025;5017.949820501748;5043.117454445384;5062.797488100582;5067.746612669278;4776.737279449663;4959.553640172402;4968.3044373788425;4989.775460104232;4996.325496057985;4778.426588809365;4855.8057677692495;5002.449730529566;5038.193888977746;5039.244113383022;4923.556879880828;4941.110600092018;4943.505649435027;4944.3572335596145;4957.281194226889 09:33:54 INFO - PROCESS | 6060 | |5;yui.html;613.834264748518;628.8616504369037;643.6845945486713;717.8205448637841;745.9701611935525;13249.205047697145;14548.41806327747;14601.342939567718;14627.70744585109;14636.926815365909;13482.12366196199;13590;13616.591502254923;13635;13666.863331366683;608.3916083916084;747.484235877243;757.7347928225118;774.9496282741632;777.7394572818113;744.2222877093424;754.5887491317245;755.0109356742657;755.6750597243183;756.6027835386438;804.2721337189853;806.5523634382915;809.2918696140877;810.440795850864;811.0875265326507;621.5214285000569;776.5069181070061;800.591698233899;801.374927556504;801.4790386248926;596.4095545410056;596.5257620191958;596.5615272774508;596.6062398816804;596.9791057312973;12745.872541274653;12797.744045119138;12823.358832058453;12828.743425131539;12832.230066196058;12095.758084838342;12285.93857030718;12454.25274483534;12474.750504989941;13054.412551435209;11206.383648899307;11219.270747401393;11367.602133925355;11388.658340249805;11556.711082222928;4037.37420699792;4114.732542384735;4125.1749650069805;4202.579742025773;4248.830046798124;65047.67476162636;66256;66915.66542167257;67100.3289967099;67274.66362668204;9634.566444510014;10032.297739158188;10108.848367274497;10269.537870795832;10302.948485257526;17065.573360666083;17210;17232.138393080295;17303.04833234184;17318.220680069426;6582.736690532325;6684.63234522106;6703.296153903867;6915.308469153045;6951.304869513008;3389.796612203276;3404.608470025979;3417.4532074867902;3495.580530336325;3504.0188747150837;2597.8701064946677;2711.9050833220745;2712.5253080710795;2718.8640567971524;2726.836389816617;471.79948521878066;474.02824210368607;475.731211865295;483.51165323024;493.55086870947264;426.6330955378372;501.603733050893;521.4290352064559;530.7956436771793;556.1018954388633;7934;7966.561839098905;7982.920170798334;8020.518768873886;8025.31784798287;550.0703810560141;566.9977469300067;569.4504802865255;573.730346737033;576.4926864359421;3574.6425357464045;3626.401643728808;3633.091727068233;3642.307961487309;3760.135168911111;105103.47448262863;105718.47140764247;106694.46652766687;108753;109196;89750.55124724466;90218;92075.07924920665;93678;93905;43418.78290608527;49718.50281497211;76840;78956.6052169747;86078;6970.7211711533;7072.3634872861685;7138.39323657495;7208.747693830691;7581;49039;49496.75251623719;49587.25619115788;49801;51311;38758.22483550369;54675.72662136823;67338;67600.63397781055;69538.65230674017;8583.527905964982;8917.375783695079;8985.411802270852;9150.084991500797;9196.218321442751;9829.262805289774;9849.261305402268;10416.427096509462;10466.476676166314;10915.56337746513;9145.496997664926;9275.350725449423;9309.20871725911;9319.813603727753;9742.31803773729;9580;9613.567389467351;9650.420974741539;11370.54517819278;12563.37183140821;11656.30062196271;12434.129610927148;12910.741785164057;12967.416466258852;13453.327333633475;10034.598616055568;10325.793484130425;10353.223508236762;10458.738531536772;11152.386618736047;9703.514824258618;9969.40183588987;10051.145652619316;10234.283600147919;10287.125594324567 09:33:54 INFO - PROCESS | 6060 | __end_tp_report 09:33:54 INFO - PROCESS | 6060 | __start_cc_report 09:33:54 INFO - PROCESS | 6060 | _x_x_mozilla_cycle_collect,1181 09:33:54 INFO - PROCESS | 6060 | __end_cc_report 09:33:54 INFO - PROCESS | 6060 | __startTimestamp1471019634762__endTimestamp 09:33:54 INFO - PROCESS | 6060 | 09:33:54 INFO - PROCESS | 6060 | ------- Summary: start ------- 09:33:54 INFO - PROCESS | 6060 | Number of tests: 6 09:33:54 INFO - PROCESS | 6060 | 09:33:54 INFO - PROCESS | 6060 | [#0] dojo.html Cycles:165 Average:4808.09 Median:4966.78 stddev:524.16 (10.6%) stddev-sans-first:497.59 09:33:54 INFO - PROCESS | 6060 | Values: 2646.8 2669.7 2689.1 2696.6 2709.6 4605.5 4679.6 4835.4 4987.6 5003.3 4844.3 4847.2 4895.4 4895.5 4901.4 4411.8 4413.1 4488.6 4550.8 4585.0 4502.1 4529.4 4539.0 4558.5 4570.7 4352.5 4397.5 4401.1 4442.4 4526.1 4437.9 4461.9 4484.3 4556.0 4572.5 4476.9 4496.2 4501.1 4537.1 4547.9 4613.4 4883.3 4887.9 4935.9 4937.4 5209.8 5260.9 5267.9 5348.1 5348.4 5311.3 5314.3 5360.8 5371.4 5379.9 4590.7 5001.8 5012.4 5016.6 5020.3 5040.5 5313.8 5355.9 5384.1 5446.5 5175.5 5216.9 5229.3 5235.2 5241.1 5246.7 5263.8 5264.9 5316.4 5321.8 4915.7 4959.5 5013.1 5051.1 5055.0 5077.9 5092.1 5094.9 5097.2 5130.0 4303.4 4304.1 4311.5 4312.0 4350.8 3687.6 3690.2 3760.6 3782.5 3809.2 4515.0 4518.8 4541.9 4553.8 4557.4 5213.8 5240.3 5246.1 5249.2 5251.1 4311.8 4341.9 4344.6 4510.7 4542.6 4672.1 4716.4 4722.7 4731.6 4739.9 4929.4 4939.9 4942.3 4954.4 4991.9 5219.6 5223.7 5232.8 5250.4 5251.4 4719.1 4757.6 4840.0 4877.0 4887.0 5074.7 5106.0 5113.9 5114.2 5165.5 5191.8 5194.8 5203.9 5210.9 5252.5 4789.5 5037.9 5047.1 5070.7 5096.3 5089.2 5110.3 5115.1 5123.3 5163.6 5029.1 5041.7 5085.9 5089.8 5091.5 4914.4 5013.7 5058.1 5058.1 5071.9 4966.8 4967.4 4971.4 4977.6 5017.6 09:33:54 INFO - PROCESS | 6060 | 09:33:54 INFO - PROCESS | 6060 | [#1] ext.html Cycles:180 Average:44288.75 Median:3379.20 stddev:80140.83 (2371.6%) stddev-sans-first:80298.41 09:33:54 INFO - PROCESS | 6060 | Values: 565.9 572.2 572.3 578.7 581.0 28921.3 40563.2 41868.2 41874.8 44915.8 41032.0 41261.0 41325.4 41416.6 41432.8 7456.9 7634.3 7640.7 7642.5 7643.0 7385.6 9789.5 10038.4 10058.4 10371.9 10168.6 10236.3 10252.5 10254.3 10262.6 9840.5 9875.5 9885.3 9903.3 9906.3 9971.7 10055.7 10061.1 10070.4 10071.2 38702.2 38813.2 38815.2 38882.8 38909.4 3087.4 3201.1 3229.3 3372.9 3450.0 29619.6 29954.4 30196.4 30501.4 30714.5 3284.9 3342.2 3350.7 3385.5 3398.6 251217.0 251706.0 252001.7 252305.0 252549.0 32262.7 54447.5 54677.5 54785.5 54806.0 32596.3 34226.3 34277.0 34291.1 34310.7 12217.0 12273.8 12290.0 12295.8 12309.9 9462.8 10418.9 10640.5 10779.1 10779.1 7742.6 7905.4 8004.9 8017.4 8019.1 3084.1 3093.5 3112.8 3117.1 3136.2 839.3 896.8 914.7 973.2 983.8 25093.4 25312.4 25347.9 25647.4 25897.4 964.0 982.4 1048.1 1066.6 1094.3 13461.7 13919.0 14134.2 14447.9 14485.1 393265.0 396543.0 396763.0 397611.0 398337.0 32991.0 33102.7 33132.0 33132.3 33206.8 1052.2 1077.6 1127.3 1156.9 1209.9 27145.5 28760.4 29707.0 30022.5 30051.8 42703.0 43203.6 43238.4 43272.6 43313.4 236415.0 237766.0 237892.0 238705.0 238774.0 25519.0 25891.6 26088.5 26319.9 27240.9 42550.6 42618.6 42671.4 42744.8 42856.6 36083.6 36122.8 36232.6 36235.6 36648.8 36140.0 36449.8 36799.0 37504.0 37575.0 35742.6 36233.0 36257.8 36336.5 37186.3 31370.4 33550.3 33944.8 33995.5 34192.8 25502.6 25668.6 25790.2 25982.7 26411.0 09:33:54 INFO - PROCESS | 6060 | 09:33:54 INFO - PROCESS | 6060 | [#2] jquery.html Cycles:180 Average:25435.20 Median:366.74 stddev:69000.62 (18814.7%) stddev-sans-first:69168.44 09:33:54 INFO - PROCESS | 6060 | Values: 334.0 358.8 366.7 366.8 372.8 12664.9 13593.9 13796.3 17136.8 17168.8 15205.7 15983.9 16004.3 16465.5 16741.3 4474.8 4606.7 4615.8 4617.7 4619.3 3452.4 4947.4 5126.4 5160.7 5215.3 5229.4 5230.9 5241.4 5245.9 5251.6 5056.5 5249.6 5262.1 5276.8 5279.3 5042.2 5082.5 5084.3 5088.8 5091.0 11701.0 11894.8 11965.1 12018.1 12018.8 1829.3 1847.7 1852.6 1872.3 1937.4 8690.8 9143.2 9242.3 11474.2 13836.2 1705.7 1737.5 1746.3 1754.2 1819.5 60415.0 72051.0 72566.0 72775.6 73759.3 9701.4 9955.4 10023.3 10129.5 10159.2 13363.2 13452.7 13458.0 13461.3 13461.3 6668.8 6810.9 6883.1 6968.7 6979.7 6441.0 6452.6 6622.4 6689.9 6702.5 4167.4 4188.2 4197.6 4223.5 4228.6 1593.5 1698.4 1705.9 1734.4 1774.2 702.5 808.2 851.2 869.6 917.0 16990.2 17078.1 17379.9 17602.4 17965.8 858.7 864.9 880.8 885.8 935.6 9361.5 9495.3 9797.0 10028.0 10226.3 408647.0 411877.0 422385.0 423155.0 424510.0 78305.6 78796.0 80551.0 80949.0 81425.6 934.4 935.0 945.3 964.9 977.3 15943.9 16005.4 16500.0 16595.7 16669.4 38720.8 39960.2 40450.4 41851.4 42180.8 51507.0 53230.5 55618.0 56232.2 56404.2 10811.7 11037.9 11488.5 11731.1 11964.0 12714.3 12895.7 12975.5 13018.2 13151.0 11110.3 11326.0 11349.8 11388.3 11550.7 11065.7 11214.7 11349.8 11632.4 11761.8 10764.7 10784.8 11279.6 11298.1 11412.4 11119.9 11152.3 11238.5 11584.3 11618.2 10934.4 10976.7 11169.4 11400.4 11677.9 09:33:54 INFO - PROCESS | 6060 | 09:33:54 INFO - PROCESS | 6060 | [#3] mootools.html Cycles:180 Average:4992.64 Median:4943.81 stddev:2805.70 (56.8%) stddev-sans-first:2800.35 09:33:54 INFO - PROCESS | 6060 | Values: 1368.6 1397.6 1414.8 1604.9 1635.8 7322.6 7915.1 7917.2 7919.7 7927.5 6504.4 7198.9 7451.1 7452.7 7772.5 2123.1 2151.5 2152.2 2152.9 2157.0 2086.6 2139.8 2167.2 2171.9 2173.8 2127.2 2134.4 2143.1 2144.7 2166.1 2108.2 2109.3 2113.4 2115.3 2116.0 2075.1 2107.2 2109.7 2110.6 2110.7 7584.6 7678.0 7776.6 7789.2 7795.0 4343.6 4499.1 4508.7 4514.7 4519.0 2771.9 2825.3 2942.3 2947.4 2947.4 884.8 912.3 922.0 922.1 955.2 10523.1 10616.2 10656.2 10663.3 10729.2 3769.7 3901.0 4021.1 4060.5 4061.9 8923.8 8937.5 8942.3 8971.0 8985.9 4466.6 4483.7 4483.8 4485.6 4486.8 3446.4 3994.2 4090.8 4097.7 4124.6 3576.6 3593.6 3721.4 3724.9 3726.9 1161.6 1184.2 1189.3 1190.6 1194.1 769.6 833.3 839.5 850.6 856.6 4931.7 4955.9 5052.3 5125.7 5180.1 357.2 363.1 372.2 372.8 374.0 3355.1 3427.8 3428.2 3444.7 3484.1 8712.5 9093.8 9101.1 9251.0 9313.2 9197.0 9230.1 9232.0 9232.2 9233.6 7029.3 7197.6 7203.5 7208.1 7213.3 5340.4 5398.0 5474.0 5604.5 5761.2 8510.7 8684.3 8758.5 8800.5 8804.4 8395.9 8556.5 8596.4 8610.7 8751.0 5746.7 6513.5 6673.3 6673.9 6721.1 6915.4 7021.3 7164.5 7188.8 7195.5 6335.6 6611.2 6671.1 6671.2 6820.7 6152.6 6159.4 6177.3 6230.3 6250.5 5984.2 6099.4 6111.3 6112.0 6118.4 4384.6 4520.3 4540.7 4553.5 4719.5 4489.3 4635.1 4674.8 4798.8 4872.5 09:33:54 INFO - PROCESS | 6060 | 09:33:54 INFO - PROCESS | 6060 | [#4] prototype.html Cycles:180 Average:4146.86 Median:4783.31 stddev:1230.26 (25.7%) stddev-sans-first:1199.06 09:33:54 INFO - PROCESS | 6060 | Values: 284.2 287.1 290.6 291.3 293.2 4758.1 4827.8 4850.5 4855.2 4860.6 4748.2 4780.0 4782.3 4784.3 4790.5 3603.5 3623.3 3652.1 3664.4 3697.3 3682.3 3690.2 3691.6 3732.3 3737.5 3641.3 3644.7 3655.2 3671.8 3684.7 3545.5 3551.7 3643.4 3671.6 3696.0 3200.0 3206.7 3210.4 3211.0 3219.5 4623.9 4663.3 4665.3 4675.5 4700.3 3959.8 4175.3 4178.8 4184.1 4213.4 4922.8 4939.5 4951.9 4953.7 4974.0 3463.9 3480.3 3484.9 3485.1 3501.4 5179.7 5217.7 5229.6 5239.9 5240.3 3467.8 3552.3 3556.5 3557.7 3586.8 3577.6 3590.3 3594.9 3598.4 3617.5 4183.7 4189.9 4192.9 4195.1 4202.7 4831.5 4955.3 5001.3 5022.9 5053.6 1228.5 1246.5 1247.7 1247.9 1258.0 768.9 774.3 789.2 804.2 807.9 4385.7 4392.2 4452.2 4473.7 4481.7 5110.2 5120.7 5140.4 5152.8 5171.6 4435.2 4446.2 4452.9 4462.0 4473.4 2590.7 2732.4 2737.8 2738.3 2744.6 4882.2 4900.0 4901.6 4902.9 4906.7 5009.5 5268.6 5287.2 5294.4 5295.0 4902.2 4903.3 4908.2 4917.3 4946.4 5158.2 5185.9 5219.2 5242.2 5254.6 4912.2 5080.4 5101.5 5128.3 5142.9 4852.2 4887.4 4946.3 4948.8 4957.4 3626.9 3644.0 3674.8 3683.3 3684.3 4909.0 5251.1 5261.8 5263.5 5270.8 5012.6 5029.2 5037.5 5040.8 5066.4 5015.8 5017.9 5043.1 5062.8 5067.7 4776.7 4959.6 4968.3 4989.8 4996.3 4778.4 4855.8 5002.4 5038.2 5039.2 4923.6 4941.1 4943.5 4944.4 4957.3 09:33:54 INFO - PROCESS | 6060 | 09:33:54 INFO - PROCESS | 6060 | [#5] yui.html Cycles:180 Average:17998.57 Median:25906.30 stddev:26872.34 (103.7%) stddev-sans-first:26916.01 09:33:54 INFO - PROCESS | 6060 | Values: 613.8 628.9 643.7 717.8 746.0 13249.2 14548.4 14601.3 14627.7 14636.9 13482.1 13590.0 13616.6 13635.0 13666.9 608.4 747.5 757.7 774.9 777.7 744.2 754.6 755.0 755.7 756.6 804.3 806.6 809.3 810.4 811.1 621.5 776.5 800.6 801.4 801.5 596.4 596.5 596.6 596.6 597.0 12745.9 12797.7 12823.4 12828.7 12832.2 12095.8 12285.9 12454.3 12474.8 13054.4 11206.4 11219.3 11367.6 11388.7 11556.7 4037.4 4114.7 4125.2 4202.6 4248.8 65047.7 66256.0 66915.7 67100.3 67274.7 9634.6 10032.3 10108.8 10269.5 10302.9 17065.6 17210.0 17232.1 17303.0 17318.2 6582.7 6684.6 6703.3 6915.3 6951.3 3389.8 3404.6 3417.5 3495.6 3504.0 2597.9 2711.9 2712.5 2718.9 2726.8 471.8 474.0 475.7 483.5 493.6 426.6 501.6 521.4 530.8 556.1 7934.0 7966.6 7982.9 8020.5 8025.3 550.1 567.0 569.5 573.7 576.5 3574.6 3626.4 3633.1 3642.3 3760.1 105103.5 105718.5 106694.5 108753.0 109196.0 89750.6 90218.0 92075.1 93678.0 93905.0 43418.8 49718.5 76840.0 78956.6 86078.0 6970.7 7072.4 7138.4 7208.7 7581.0 49039.0 49496.8 49587.3 49801.0 51311.0 38758.2 54675.7 67338.0 67600.6 69538.7 8583.5 8917.4 8985.4 9150.1 9196.2 9829.3 9849.3 10416.4 10466.5 10915.6 9145.5 9275.4 9309.2 9319.8 9742.3 9580.0 9613.6 9650.4 11370.5 12563.4 11656.3 12434.1 12910.7 12967.4 13453.3 10034.6 10325.8 10353.2 10458.7 11152.4 9703.5 9969.4 10051.1 10234.3 10287.1 09:33:54 INFO - PROCESS | 6060 | -------- Summary: end -------- 09:33:54 INFO - PROCESS | 6060 | 09:33:55 INFO - TEST-INFO | 6060: exit 0 09:33:55 INFO - TEST-OK | dromaeo_css | took 1120666ms 09:33:55 INFO - TEST-START | kraken 09:33:55 INFO - operating with platform_type : linux_ 09:33:55 INFO - Initialising browser for kraken test... 09:33:55 INFO - TEST-INFO | started process 6253 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLETOqA/profile http://localhost:43215/getInfo.html) 09:33:59 INFO - PROCESS | 6253 | __metrics Screen width/height:1600/1200 09:33:59 INFO - PROCESS | 6253 | colorDepth:24 09:33:59 INFO - PROCESS | 6253 | Browser inner width/height: 1024/697 09:33:59 INFO - PROCESS | 6253 | __metrics 09:34:00 INFO - PROCESS | 6253 | 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" 09:34:00 INFO - PROCESS | 6253 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:34:01 INFO - PROCESS | 6253 | console.error: PushService: 09:34:01 INFO - PROCESS | 6253 | stateChangeProcessEnqueue: Error transitioning state 09:34:01 INFO - PROCESS | 6253 | UnknownError 09:34:01 INFO - TEST-INFO | 6253: exit 0 09:34:01 INFO - Browser initialized. 09:34:01 INFO - Running cycle 1/1 for kraken test... 09:34:01 INFO - TEST-INFO | started process 6335 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLETOqA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:34:13 INFO - PROCESS | 6335 | RSS: Main: 157335552 09:34:13 INFO - PROCESS | 6335 | 09:34:16 INFO - PROCESS | 6335 | Cycle 1(1): loaded http://localhost:43215/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43215/tests/kraken/driver.html?testName=audio-beat-detection) 09:34:17 INFO - PROCESS | 6335 | RSS: Main: 213028864 09:34:17 INFO - PROCESS | 6335 | 09:34:19 INFO - PROCESS | 6335 | Cycle 1(1): loaded http://localhost:43215/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43215/tests/kraken/driver.html?testName=audio-dft) 09:34:20 INFO - PROCESS | 6335 | RSS: Main: 188350464 09:34:20 INFO - PROCESS | 6335 | 09:34:23 INFO - PROCESS | 6335 | Cycle 1(1): loaded http://localhost:43215/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43215/tests/kraken/driver.html?testName=audio-fft) 09:34:24 INFO - PROCESS | 6335 | RSS: Main: 269701120 09:34:24 INFO - PROCESS | 6335 | 09:34:26 INFO - PROCESS | 6335 | Cycle 1(1): loaded http://localhost:43215/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43215/tests/kraken/driver.html?testName=audio-oscillator) 09:34:27 INFO - PROCESS | 6335 | RSS: Main: 180375552 09:34:27 INFO - PROCESS | 6335 | 09:34:29 INFO - PROCESS | 6335 | Cycle 1(1): loaded http://localhost:43215/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43215/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:34:30 INFO - PROCESS | 6335 | RSS: Main: 184406016 09:34:30 INFO - PROCESS | 6335 | 09:34:33 INFO - PROCESS | 6335 | Cycle 1(1): loaded http://localhost:43215/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43215/tests/kraken/driver.html?testName=imaging-darkroom) 09:34:33 INFO - PROCESS | 6335 | RSS: Main: 221298688 09:34:33 INFO - PROCESS | 6335 | 09:34:37 INFO - PROCESS | 6335 | Cycle 1(1): loaded http://localhost:43215/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43215/tests/kraken/driver.html?testName=imaging-desaturate) 09:34:37 INFO - PROCESS | 6335 | RSS: Main: 221065216 09:34:37 INFO - PROCESS | 6335 | 09:34:40 INFO - PROCESS | 6335 | Cycle 1(1): loaded http://localhost:43215/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43215/tests/kraken/driver.html?testName=json-parse-financial) 09:34:41 INFO - PROCESS | 6335 | RSS: Main: 220041216 09:34:41 INFO - PROCESS | 6335 | 09:34:43 INFO - PROCESS | 6335 | Cycle 1(1): loaded http://localhost:43215/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43215/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:34:43 INFO - PROCESS | 6335 | RSS: Main: 184721408 09:34:43 INFO - PROCESS | 6335 | 09:34:45 INFO - PROCESS | 6335 | Cycle 1(1): loaded http://localhost:43215/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43215/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:34:46 INFO - PROCESS | 6335 | RSS: Main: 189845504 09:34:46 INFO - PROCESS | 6335 | 09:34:48 INFO - PROCESS | 6335 | Cycle 1(1): loaded http://localhost:43215/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43215/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:34:49 INFO - PROCESS | 6335 | RSS: Main: 193474560 09:34:49 INFO - PROCESS | 6335 | 09:34:51 INFO - PROCESS | 6335 | Cycle 1(1): loaded http://localhost:43215/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43215/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:34:52 INFO - PROCESS | 6335 | RSS: Main: 189181952 09:34:52 INFO - PROCESS | 6335 | 09:34:55 INFO - PROCESS | 6335 | Cycle 1(1): loaded http://localhost:43215/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43215/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:34:55 INFO - PROCESS | 6335 | RSS: Main: 192724992 09:34:55 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | Cycle 1(1): loaded http://localhost:43215/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43215/tests/kraken/driver.html?testName=ai-astar) 09:34:57 INFO - PROCESS | 6335 | RSS: Main: 243748864 09:34:57 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | __start_tp_report 09:34:57 INFO - PROCESS | 6335 | _x_x_mozilla_page_load 09:34:57 INFO - PROCESS | 6335 | _x_x_mozilla_page_load_details 09:34:57 INFO - PROCESS | 6335 | |i|pagename|runs| 09:34:57 INFO - PROCESS | 6335 | |0;ai-astar;104;116;113;113;113;115;115;114;114;114 09:34:57 INFO - PROCESS | 6335 | |1;audio-beat-detection;149;124;125;180;123;127;155;131;185;122 09:34:57 INFO - PROCESS | 6335 | |2;audio-dft;160;160;160;161;161;162;161;161;160;163 09:34:57 INFO - PROCESS | 6335 | |3;audio-fft;76;76;75;110;87;77;76;75;101;76 09:34:57 INFO - PROCESS | 6335 | |4;audio-oscillator;85;86;122;86;84;85;127;84;84;132 09:34:57 INFO - PROCESS | 6335 | |5;imaging-gaussian-blur;117;117;117;117;119;117;117;118;118;117 09:34:57 INFO - PROCESS | 6335 | |6;imaging-darkroom;146;147;146;146;146;146;146;146;146;146 09:34:57 INFO - PROCESS | 6335 | |7;imaging-desaturate;95;95;94;96;95;95;95;95;94;95 09:34:57 INFO - PROCESS | 6335 | |8;json-parse-financial;63;62;63;61;63;62;62;62;63;63 09:34:57 INFO - PROCESS | 6335 | |9;json-stringify-tinderbox;67;67;67;65;66;66;66;66;67;65 09:34:57 INFO - PROCESS | 6335 | |10;stanford-crypto-aes;89;80;89;85;80;80;79;79;79;87 09:34:57 INFO - PROCESS | 6335 | |11;stanford-crypto-ccm;134;135;132;128;128;130;129;129;165;140 09:34:57 INFO - PROCESS | 6335 | |12;stanford-crypto-pbkdf2;172;167;168;166;167;165;166;170;165;164 09:34:57 INFO - PROCESS | 6335 | |13;stanford-crypto-sha256-iterative;66;67;69;68;67;71;67;66;71;66 09:34:57 INFO - PROCESS | 6335 | __end_tp_report 09:34:57 INFO - PROCESS | 6335 | __start_cc_report 09:34:57 INFO - PROCESS | 6335 | _x_x_mozilla_cycle_collect,1929 09:34:57 INFO - PROCESS | 6335 | __end_cc_report 09:34:57 INFO - PROCESS | 6335 | __startTimestamp1471019697953__endTimestamp 09:34:57 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | ------- Summary: start ------- 09:34:57 INFO - PROCESS | 6335 | Number of tests: 14 09:34:57 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:3.35 (2.9%) stddev-sans-first:1.05 09:34:57 INFO - PROCESS | 6335 | Values: 104.0 116.0 113.0 113.0 113.0 115.0 115.0 114.0 114.0 114.0 09:34:57 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | [#1] audio-beat-detection Cycles:10 Average:142.10 Median:140.00 stddev:24.11 (17.2%) stddev-sans-first:25.44 09:34:57 INFO - PROCESS | 6335 | Values: 149.0 124.0 125.0 180.0 123.0 127.0 155.0 131.0 185.0 122.0 09:34:57 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.00 09:34:57 INFO - PROCESS | 6335 | Values: 160.0 160.0 160.0 161.0 161.0 162.0 161.0 161.0 160.0 163.0 09:34:57 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | [#3] audio-fft Cycles:10 Average:82.90 Median:76.00 stddev:12.60 (16.6%) stddev-sans-first:13.11 09:34:57 INFO - PROCESS | 6335 | Values: 76.0 76.0 75.0 110.0 87.0 77.0 76.0 75.0 101.0 76.0 09:34:57 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:84.50 stddev:20.51 (24.3%) stddev-sans-first:21.25 09:34:57 INFO - PROCESS | 6335 | Values: 85.0 86.0 122.0 86.0 84.0 85.0 127.0 84.0 84.0 132.0 09:34:57 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 09:34:57 INFO - PROCESS | 6335 | Values: 117.0 117.0 117.0 117.0 119.0 117.0 117.0 118.0 118.0 117.0 09:34:57 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 09:34:57 INFO - PROCESS | 6335 | Values: 146.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 09:34:57 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 09:34:57 INFO - PROCESS | 6335 | Values: 95.0 95.0 94.0 96.0 95.0 95.0 95.0 95.0 94.0 95.0 09:34:57 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:63.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 09:34:57 INFO - PROCESS | 6335 | Values: 63.0 62.0 63.0 61.0 63.0 62.0 62.0 62.0 63.0 63.0 09:34:57 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:66.50 stddev:0.79 (1.2%) stddev-sans-first:0.78 09:34:57 INFO - PROCESS | 6335 | Values: 67.0 67.0 67.0 65.0 66.0 66.0 66.0 66.0 67.0 65.0 09:34:57 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:82.50 stddev:4.30 (5.2%) stddev-sans-first:3.91 09:34:57 INFO - PROCESS | 6335 | Values: 89.0 80.0 89.0 85.0 80.0 80.0 79.0 79.0 79.0 87.0 09:34:57 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | [#11] stanford-crypto-ccm Cycles:10 Average:135.00 Median:133.00 stddev:11.21 (8.4%) stddev-sans-first:11.88 09:34:57 INFO - PROCESS | 6335 | Values: 134.0 135.0 132.0 128.0 128.0 130.0 129.0 129.0 165.0 140.0 09:34:57 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:167.00 stddev:2.45 (1.5%) stddev-sans-first:1.81 09:34:57 INFO - PROCESS | 6335 | Values: 172.0 167.0 168.0 166.0 167.0 165.0 166.0 170.0 165.0 164.0 09:34:57 INFO - PROCESS | 6335 | 09:34:57 INFO - PROCESS | 6335 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:67.50 stddev:1.93 (2.9%) stddev-sans-first:1.94 09:34:57 INFO - PROCESS | 6335 | Values: 66.0 67.0 69.0 68.0 67.0 71.0 67.0 66.0 71.0 66.0 09:34:57 INFO - PROCESS | 6335 | -------- Summary: end -------- 09:34:57 INFO - PROCESS | 6335 | 09:34:59 INFO - TEST-INFO | 6335: exit 0 09:34:59 INFO - TEST-OK | kraken | took 63407ms 09:34:59 INFO - SUITE-END | took 1184s 09:34:59 INFO - Completed test suite (00:19:44) 09:34:59 INFO - javascript benchmark 09:34:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2646.841189528627, 2669.7063323034467, 2689.1125928443616, 2696.635954146191, 2709.6341993830833, 4605.470370907338, 4679.649026323023, 4835.371401717776, 4987.576056035241, 5003.3245511855885, 4844.297576851363, 4847.224444088946, 4895.437024742156, 4895.534924182206, 4901.3873265841685, 4411.757353345564, 4413.051193993291, 4488.573585509372, 4550.772461376918, 4584.954150458503, 4502.09958008398, 4529.388532548097, 4539.0, 4558.544145585448, 4570.7257564546235, 4352.499462561815, 4397.494288156839, 4401.141777353418, 4442.400275962736, 4526.094781043771, 4437.9112417751785, 4461.888452788674, 4484.349769283468, 4555.954440455586, 4572.519885412017, 4476.865694029184, 4496.168208881368, 4501.054778496519, 4537.069900670354, 4547.931781023287, 4613.354130421743, 4883.340748998875, 4887.926681099786, 4935.876603084916, 4937.35814344133, 5209.765560549784, 5260.894782104337, 5267.868303292448, 5348.1442969124755, 5348.411674715778, 5311.282976798121, 5314.3091398117995, 5360.785568577252, 5371.435999220064, 5379.892402151974, 4590.67865249436, 5001.849944501707, 5012.398512178489, 5016.648834581544, 5020.297158397827, 5040.49595040493, 5313.787448502017, 5355.919661205085, 5384.111621582473, 5446.455354464521, 5175.508326708957, 5216.869578260498, 5229.267902493578, 5235.1623740201385, 5241.0566098101945, 5246.711430871339, 5263.815766448232, 5264.92102618461, 5316.362036555638, 5321.813736519203, 4915.655904086751, 4959.528844759742, 5013.1227035268685, 5051.116054690415, 5055.0, 5077.949220507821, 5092.134337162691, 5094.898102038012, 5097.158968770185, 5129.9743501283, 4303.440552728188, 4304.096139810675, 4311.547287534827, 4312.029793296483, 4350.760708161081, 3687.631236876291, 3690.225052738901, 3760.6427389397963, 3782.508273924372, 3809.238152369537, 4515.0, 4518.751468669255, 4541.931871021938, 4553.772311384418, 4557.407537020166, 5213.765380557884, 5240.34495688039, 5246.134387826042, 5249.2126181073145, 5251.10731175701, 4311.827526898952, 4341.891452713644, 4344.630706389992, 4510.66170037242, 4542.636589072867, 4672.135654903854, 4716.434027916672, 4722.740249286254, 4731.550502702238, 4739.881502962385, 4929.383827021622, 4939.876503087452, 4942.308076869242, 4954.355933728592, 4991.850244492671, 5219.556337711337, 5223.712695801767, 5232.843014709565, 5250.448702886142, 5251.448597897319, 4719.10337035962, 4757.619390448686, 4839.975800120977, 4876.95123048765, 4886.951130488792, 5074.746262686777, 5106.0, 5113.8721531962, 5114.232865070269, 5165.457626949116, 5191.792328306826, 5194.7922083116255, 5203.92194117081, 5210.921836172384, 5252.501012403815, 4789.521047895182, 5037.92443113361, 5047.1419858623385, 5070.670406423571, 5096.261042148793, 5089.211172268304, 5110.259012443101, 5115.079285728604, 5123.257127716534, 5163.612729045261, 5029.0947629427055, 5041.697498150123, 5085.949140508696, 5089.847304580869, 5091.490850914879, 4914.36113305268, 5013.749312534432, 5058.089543882136, 5058.140116180185, 5071.949280507295, 4966.776495057658, 4967.3542439482635, 4971.353724015855, 4977.601791856715, 5017.648764586444], "value": 4773.4999122723675, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.8557067947672, 572.1903506538249, 572.3303734630482, 578.661483032426, 580.9535237181026, 28921.27696807581, 40563.18873622526, 41868.23185913275, 41874.79062604683, 44915.77542112293, 41032.0, 41261.0, 41325.380119298236, 41416.585834141646, 41432.79283603586, 7456.888146677804, 7634.312911837934, 7640.694372225104, 7642.465027448081, 7643.0, 7385.630718464082, 9789.461579613118, 10038.397696138218, 10058.396496210251, 10371.948140259288, 10168.64409745659, 10236.283460157792, 10252.538635761372, 10254.282200245985, 10262.589496420134, 9840.458774767385, 9875.506224688737, 9885.25860560461, 9903.306768526207, 9906.306558540906, 9971.650992215238, 10055.69832905014, 10061.14480269178, 10070.446125463097, 10071.194304455626, 38702.225955481015, 38813.223735525135, 38815.22369552621, 38882.80558597189, 38909.41635875464, 3087.3516561522106, 3201.055688571877, 3229.273413481971, 3372.898813035613, 3450.0, 29619.555706664418, 29954.40091198164, 30196.396072078656, 30501.38997220043, 30714.53928191079, 3284.917877053069, 3342.2479942013097, 3350.748693847947, 3385.509101180315, 3398.6091599465885, 251217.0, 251706.0, 252001.7399913007, 252305.0, 252549.0, 32262.677373226437, 54447.45552544424, 54677.45322546724, 54785.452145478834, 54806.0, 32596.348073038407, 34226.31547369039, 34277.0, 34291.14272143217, 34310.65689343074, 12217.0, 12273.815892761615, 12290.0, 12295.75408491843, 12309.876901231051, 9462.763430914281, 10418.895811041944, 10640.46797660114, 10779.137668986463, 10779.137668986463, 7742.574158421228, 7905.407094467938, 8004.879926801103, 8017.358611311081, 8019.117897031322, 3084.0901933929545, 3093.5359696045325, 3112.8015713950294, 3117.080461263887, 3136.1532386255585, 839.3033781960958, 896.8254361494927, 914.7484441778431, 973.2384334546925, 983.7983213441228, 25093.372665683502, 25312.441154025182, 25347.87326063358, 25647.358816029748, 25897.352566185997, 963.9614415423305, 982.3710140892582, 1048.0619845238468, 1066.6053560182627, 1094.2887123369715, 13461.730765384833, 13919.0, 14134.222617756122, 14447.85552144486, 14485.130892146504, 393265.0, 396543.0, 396763.0, 397611.0, 398337.0, 32991.0, 33102.66897331044, 33132.0, 33132.3373532528, 33206.83396583002, 1052.2108418685987, 1077.579743899864, 1127.3292391027326, 1156.9016633586239, 1209.909256805743, 27145.45709085847, 28760.424791504473, 29707.0, 30022.549661754656, 30051.849740751157, 42703.0, 43203.567964319955, 43238.351424729284, 43272.56727432685, 43313.350299744896, 236415.0, 237766.0, 237892.0, 238705.0, 238774.0, 25519.0, 25891.611625826004, 26088.478230434906, 26319.86840065864, 27240.863795680896, 42550.5744942559, 42618.57381426146, 42671.35992960046, 42744.786276069666, 42856.5714342865, 36083.63916360908, 36122.819385902905, 36232.63767362293, 36235.63764362428, 36648.81675591605, 36139.994422532676, 36449.817750911076, 36799.0, 37504.0, 37575.0, 35742.64257357393, 36233.0, 36257.81871090628, 36336.45495317625, 37186.25627487489, 31370.372592547566, 33550.32899342048, 33944.830275848464, 33995.4900676495, 34192.82903585466, 25502.617460738475, 25668.614970775827, 25790.226293211235, 25982.74017259803, 26411.0], "value": 16609.221634464317, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.96493368196354, 358.76859425670426, 366.6791557387287, 366.79459502678503, 372.81715555423, 12664.936675316621, 13593.863336016126, 13796.310184490772, 17136.82863171368, 17168.82831171688, 15205.695886082272, 15983.92008039961, 16004.279807408666, 16465.506034818944, 16741.330346786144, 4474.776261186944, 4606.654500912445, 4615.8153673853, 4617.676762626617, 4619.284010978304, 3452.430348992413, 4947.431045429767, 5126.41046279677, 5160.741962901859, 5215.269862219292, 5229.424763276036, 5230.895382092356, 5241.42344342122, 5245.868853278679, 5251.55361794248, 5056.544910958013, 5249.553787928012, 5262.131748261532, 5276.841694749163, 5279.340082489689, 5042.243663450475, 5082.517160869711, 5084.313617661605, 5088.7964481420695, 5091.032703786269, 11701.0, 11894.762104757943, 11965.10261730365, 12018.098642601839, 12018.819717704242, 1829.268292682924, 1847.6674198644373, 1852.6016906365196, 1872.307246318857, 1937.3703546347494, 8690.782730431727, 9143.222826059793, 9242.260619150518, 11474.221495668186, 13836.169829810144, 1705.6588682263473, 1737.4787563730831, 1746.3451205797826, 1754.175537497374, 1819.4541637508826, 60415.0, 72051.0, 72566.0, 72775.63612181906, 73759.26240737578, 9701.369410988264, 9955.352902061342, 10023.348482348623, 10129.544170512345, 10159.187265018782, 13363.198208107544, 13452.730945381038, 13458.0, 13461.25963072021, 13461.25963072021, 6668.799936001928, 6810.9318906810295, 6883.105196324445, 6968.721251149897, 6979.720811167598, 6440.967795160994, 6452.580582262138, 6622.437092847065, 6689.933100668931, 6702.530822842452, 4167.437395951569, 4188.162367526507, 4197.580241975778, 4223.472065991751, 4228.61942425177, 1593.5139782366257, 1698.3800912666788, 1705.914704264782, 1734.4276388791668, 1774.2370780564481, 702.4696354252507, 808.1675873849944, 851.153102662842, 869.5912920927154, 916.9724908252763, 16990.150492475328, 17078.146092695562, 17379.91310043467, 17602.383916563114, 17965.820341796414, 858.7466697324304, 864.9308055355557, 880.7713240030122, 885.7790401916151, 935.6163972771267, 9361.531923403803, 9495.287853411022, 9796.951015244878, 10028.047335503115, 10226.28416010872, 408647.0, 411877.0, 422385.0, 423155.0, 424510.0, 78305.605732477, 78796.0, 80551.0, 80949.0, 81425.59287203527, 934.425328423015, 935.0042205051734, 945.3477100800426, 964.8552717092492, 977.2524019125375, 15943.920280398524, 16005.439809606607, 16500.0, 16595.66808663844, 16669.416570419977, 38720.80639596784, 39960.2007959845, 40450.39324410195, 41851.372229415974, 42180.78909605432, 51507.0, 53230.46769532255, 55618.0, 56232.156517653086, 56404.153937691786, 10811.729706757395, 11037.944810275898, 11488.54045838157, 11731.120165987755, 11963.983061439874, 12714.300713460849, 12895.742085158432, 12975.545855894998, 13018.218906865619, 13151.0, 11110.277831940897, 11326.0, 11349.773004540028, 11388.316700997966, 11550.727526410272, 11065.668029959113, 11214.66356009321, 11349.773004539698, 11632.360220187633, 11761.823572646246, 10764.677059688222, 10784.787668263283, 11279.605213817476, 11298.09615230796, 11412.372319522507, 11119.888801112209, 11152.330860148417, 11238.494267758135, 11584.304941703525, 11618.186726929034, 10934.398608076632, 10976.725581860517, 11169.38568378747, 11400.372979486208, 11677.941610291895], "value": 8311.512978055176, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.5894231730479, 1397.6226418866906, 1414.8443671196173, 1604.8796340274482, 1635.8325219084709, 7322.633868306583, 7915.050193976724, 7917.168697286788, 7919.722809701661, 7927.524348539092, 6504.414602685757, 7198.892016619755, 7451.068616422947, 7452.701891924316, 7772.455928085036, 2123.097683484517, 2151.4943988162768, 2152.1929276521305, 2152.9354119376444, 2156.996996396675, 2086.62440760663, 2139.828813694901, 2167.2414654870827, 2171.869687818728, 2173.7717539658265, 2127.2129312154525, 2134.3810295014428, 2143.132031527226, 2144.6997420361163, 2166.068590506082, 2108.2199586153147, 2109.282843833104, 2113.429374069008, 2115.270231770037, 2116.047778499666, 2075.1284460526617, 2107.1887323380456, 2109.7362829646295, 2110.5989861926187, 2110.6517424624917, 7584.634765742164, 7678.0, 7776.611169441505, 7789.221077892222, 7795.025621797276, 4343.630791382736, 4499.055198408339, 4508.661850361236, 4514.706544074657, 4518.954810451919, 2771.9445611087867, 2825.3360460291815, 2942.337973955864, 2947.4252520758246, 2947.439986402577, 884.7788052986754, 912.3431129586753, 922.0318665401302, 922.1424075609681, 955.2214944820018, 10523.052925236689, 10616.203784716177, 10656.20078494116, 10663.306885052447, 10729.248952573324, 3769.7361184717356, 3900.9609903900596, 4021.1153546219784, 4060.4924384451942, 4061.918761624751, 8923.776905577413, 8937.46375217489, 8942.329325300629, 8971.0, 8985.865212021825, 4466.6203372713035, 4483.663725220622, 4483.798229079699, 4485.618722408632, 4486.753228572395, 3446.431338829065, 3994.2211268802885, 4090.8363665453644, 4097.733647312915, 4124.608162224584, 3576.5529308836394, 3593.5508061492314, 3721.4417837324077, 3724.9068773280883, 3726.9440958385644, 1161.5760247509768, 1184.1888306509895, 1189.250772013626, 1190.5535424215918, 1194.062660811259, 769.5998080997849, 833.3000279765026, 839.4711331860889, 850.5959669157224, 856.5931182688173, 4931.704097754075, 4955.925661115014, 5052.267421223902, 5125.743712814419, 5180.067587834151, 357.1553276501056, 363.1103795700544, 372.24620144207654, 372.7632953074817, 373.9719521035933, 3355.1444381682513, 3427.8286085695618, 3428.211511352353, 3444.741644376682, 3484.094135524731, 8712.477251364939, 9093.81812363749, 9101.135392137736, 9250.953745231365, 9313.208377288007, 9197.0, 9230.123138301984, 9232.0, 9232.215261702697, 9233.630654773733, 7029.261927497641, 7197.604131772803, 7203.459740519482, 7208.135023797073, 7213.278672132849, 5340.412554619066, 5398.0, 5473.972630136983, 5604.495595396434, 5761.2222349983895, 8510.659573616986, 8684.261837743863, 8758.518281494578, 8800.515971621366, 8804.427712198687, 8395.874061889199, 8556.486610803371, 8596.355273354398, 8610.741677749678, 8750.956245218733, 5746.741396637078, 6513.478921686348, 6673.299303573055, 6673.933260667525, 6721.126253587003, 6915.446764258948, 7021.297870212938, 7164.534305270141, 7188.784336469915, 7195.532290401107, 6335.606226268826, 6611.206655201407, 6671.132752742113, 6671.199456065303, 6820.658967051727, 6152.630842149485, 6159.414855588712, 6177.320494745484, 6230.252369715663, 6250.49996000328, 5984.1921340618455, 6099.420555047263, 6111.327753946974, 6111.96944015277, 6118.388161183846, 4384.649228061811, 4520.254158063883, 4540.704854184468, 4553.54464553542, 4719.528047195253, 4489.34904438848, 4635.119327327797, 4674.76626168697, 4798.832040878552, 4872.537108974641], "value": 3955.900629941514, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.2495811058805, 287.1357214783502, 290.6091307191827, 291.3226747811338, 293.2156481412222, 4758.143534163849, 4827.8310259140935, 4850.490698476658, 4855.247436647316, 4860.586850117736, 4748.240281554952, 4780.0, 4782.337221209319, 4784.282357646355, 4790.544898234671, 3603.5315408996858, 3623.3332666973224, 3652.1234903623204, 3664.4320130379833, 3697.297513472445, 3682.3187710273687, 3690.1697118148472, 3691.593924668284, 3732.3095227382887, 3737.5141231639986, 3641.3263546243898, 3644.744867859251, 3655.232401195752, 3671.7980511072014, 3684.6683798458134, 3545.450455179451, 3551.68034876861, 3643.417053271477, 3671.6328367163337, 3695.981520092382, 3199.984000080008, 3206.71139597435, 3210.4060748761553, 3210.9839450802597, 3219.4687876500348, 4623.9075218495445, 4663.27719203524, 4665.253559430509, 4675.532446755331, 4700.31845382421, 3959.821808018646, 4175.269327867642, 4178.8328466861285, 4184.079502509443, 4213.431186789805, 4922.80308787648, 4939.481354457802, 4951.92572111415, 4953.678010929278, 4973.975130124362, 3463.8614455421753, 3480.3039392121677, 3484.9477257841154, 3485.128717820545, 3501.422265326218, 5179.663321884065, 5217.6608520446425, 5229.581633469314, 5239.869003274911, 5240.34495688039, 3467.757256991986, 3552.2540266544315, 3556.5376501054698, 3557.679808817219, 3586.794121615188, 3577.58857731359, 3590.281943611288, 3594.946075808865, 3598.442241452579, 3617.4754660574595, 4183.728057676241, 4189.895252618709, 4192.916141677149, 4195.098053918422, 4202.663786897041, 4831.541003604652, 4955.281484184773, 5001.274815151783, 5022.874428139326, 5053.620978426633, 1228.5270170984056, 1246.5013994402311, 1247.65689435406, 1247.8627350991383, 1257.974840503203, 768.8885111658778, 774.3108633316241, 789.2265579731895, 804.1676864445271, 807.885118536416, 4385.693001489865, 4392.231359512137, 4452.220861349316, 4473.686841921034, 4481.708688935209, 5110.182370820651, 5120.718360490208, 5140.3831540215415, 5152.845414637567, 5171.560417364566, 4435.246008178618, 4446.243329135892, 4452.931296488884, 4461.977690111528, 4473.373727678128, 2590.663213782234, 2732.4261904999776, 2737.7536021757737, 2738.342797728531, 2744.5608702607487, 4882.194437917775, 4899.951000490021, 4901.558859702573, 4902.935665209244, 4906.675197696673, 5009.499050095034, 5268.604854635918, 5287.206918962186, 5294.364676238799, 5295.0, 4902.240152776254, 4903.289023091632, 4908.190148625579, 4917.286993385867, 4946.406431228275, 5158.200478925773, 5185.899710617206, 5219.164933610604, 5242.213667949838, 5254.579633629377, 4912.189488734319, 5080.415752188545, 5101.489851014869, 5128.333316668809, 5142.922856157235, 4852.2479015752615, 4887.36464259644, 4946.332245147003, 4948.802047918043, 4957.429895561912, 3626.9274614507035, 3643.961470980758, 3674.7978861161823, 3683.3001729671278, 3684.3368193725387, 4908.9754551227015, 5251.081060814418, 5261.842144735664, 5263.526282634582, 5270.762815673381, 5012.598992080552, 5029.220470826881, 5037.496250374933, 5040.840606660416, 5066.36670416198, 5015.774290157025, 5017.949820501748, 5043.117454445384, 5062.797488100582, 5067.746612669278, 4776.737279449663, 4959.553640172402, 4968.3044373788425, 4989.775460104232, 4996.325496057985, 4778.426588809365, 4855.8057677692495, 5002.449730529566, 5038.193888977746, 5039.244113383022, 4923.556879880828, 4941.110600092018, 4943.505649435027, 4944.3572335596145, 4957.281194226889], "value": 3749.3039506239816, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.834264748518, 628.8616504369037, 643.6845945486713, 717.8205448637841, 745.9701611935525, 13249.205047697145, 14548.41806327747, 14601.342939567718, 14627.70744585109, 14636.926815365909, 13482.12366196199, 13590.0, 13616.591502254923, 13635.0, 13666.863331366683, 608.3916083916084, 747.484235877243, 757.7347928225118, 774.9496282741632, 777.7394572818113, 744.2222877093424, 754.5887491317245, 755.0109356742657, 755.6750597243183, 756.6027835386438, 804.2721337189853, 806.5523634382915, 809.2918696140877, 810.440795850864, 811.0875265326507, 621.5214285000569, 776.5069181070061, 800.591698233899, 801.374927556504, 801.4790386248926, 596.4095545410056, 596.5257620191958, 596.5615272774508, 596.6062398816804, 596.9791057312973, 12745.872541274653, 12797.744045119138, 12823.358832058453, 12828.743425131539, 12832.230066196058, 12095.758084838342, 12285.93857030718, 12454.25274483534, 12474.750504989941, 13054.412551435209, 11206.383648899307, 11219.270747401393, 11367.602133925355, 11388.658340249805, 11556.711082222928, 4037.37420699792, 4114.732542384735, 4125.1749650069805, 4202.579742025773, 4248.830046798124, 65047.67476162636, 66256.0, 66915.66542167257, 67100.3289967099, 67274.66362668204, 9634.566444510014, 10032.297739158188, 10108.848367274497, 10269.537870795832, 10302.948485257526, 17065.573360666083, 17210.0, 17232.138393080295, 17303.04833234184, 17318.220680069426, 6582.736690532325, 6684.63234522106, 6703.296153903867, 6915.308469153045, 6951.304869513008, 3389.796612203276, 3404.608470025979, 3417.4532074867902, 3495.580530336325, 3504.0188747150837, 2597.8701064946677, 2711.9050833220745, 2712.5253080710795, 2718.8640567971524, 2726.836389816617, 471.79948521878066, 474.02824210368607, 475.731211865295, 483.51165323024, 493.55086870947264, 426.6330955378372, 501.603733050893, 521.4290352064559, 530.7956436771793, 556.1018954388633, 7934.0, 7966.561839098905, 7982.920170798334, 8020.518768873886, 8025.31784798287, 550.0703810560141, 566.9977469300067, 569.4504802865255, 573.730346737033, 576.4926864359421, 3574.6425357464045, 3626.401643728808, 3633.091727068233, 3642.307961487309, 3760.135168911111, 105103.47448262863, 105718.47140764247, 106694.46652766687, 108753.0, 109196.0, 89750.55124724466, 90218.0, 92075.07924920665, 93678.0, 93905.0, 43418.78290608527, 49718.50281497211, 76840.0, 78956.6052169747, 86078.0, 6970.7211711533, 7072.3634872861685, 7138.39323657495, 7208.747693830691, 7581.0, 49039.0, 49496.75251623719, 49587.25619115788, 49801.0, 51311.0, 38758.22483550369, 54675.72662136823, 67338.0, 67600.63397781055, 69538.65230674017, 8583.527905964982, 8917.375783695079, 8985.411802270852, 9150.084991500797, 9196.218321442751, 9829.262805289774, 9849.261305402268, 10416.427096509462, 10466.476676166314, 10915.56337746513, 9145.496997664926, 9275.350725449423, 9309.20871725911, 9319.813603727753, 9742.31803773729, 9580.0, 9613.567389467351, 9650.420974741539, 11370.54517819278, 12563.37183140821, 11656.30062196271, 12434.129610927148, 12910.741785164057, 12967.416466258852, 13453.327333633475, 10034.598616055568, 10325.793484130425, 10353.223508236762, 10458.738531536772, 11152.386618736047, 9703.514824258618, 9969.40183588987, 10051.145652619316, 10234.283600147919, 10287.125594324567], "value": 6391.415603498492, "unit": "score"}], "name": "dromaeo_css", "value": 6299.190932114904, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 113.0, 113.0, 113.0, 115.0, 115.0, 114.0, 114.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 124.0, 125.0, 180.0, 123.0, 127.0, 155.0, 131.0, 185.0, 122.0], "value": 142.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 161.0, 161.0, 162.0, 161.0, 161.0, 160.0, 163.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 110.0, 87.0, 77.0, 76.0, 75.0, 101.0, 76.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 122.0, 86.0, 84.0, 85.0, 127.0, 84.0, 84.0, 132.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 119.0, 117.0, 117.0, 118.0, 118.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 96.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 61.0, 63.0, 62.0, 62.0, 62.0, 63.0, 63.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 65.0, 66.0, 66.0, 66.0, 66.0, 67.0, 65.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 80.0, 89.0, 85.0, 80.0, 80.0, 79.0, 79.0, 79.0, 87.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 135.0, 132.0, 128.0, 128.0, 130.0, 129.0, 129.0, 165.0, 140.0], "value": 135.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 167.0, 168.0, 166.0, 167.0, 165.0, 166.0, 170.0, 165.0, 164.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 69.0, 68.0, 67.0, 71.0, 67.0, 66.0, 71.0, 66.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1536.0, "alertThreshold": 2.0}]} 09:34:59 INFO - Return code: 0 09:34:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:34:59 INFO - # TBPL SUCCESS # 09:34:59 INFO - Running post-action listener: _resource_record_post_action 09:34:59 INFO - [mozharness: 2016-08-12 16:34:59.279851Z] Finished run-tests step (success) 09:34:59 INFO - Running post-run listener: _resource_record_post_run 09:34:59 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 536420352; Read time: 32; Write time: 461164 09:34:59 INFO - TinderboxPrint: CPU usage
13.0% 09:34:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 32 09:34:59 INFO - TinderboxPrint: I/O write bytes / time
536,420,352 / 461,164 09:34:59 INFO - TinderboxPrint: CPU idle
8,334.7 (87.0%) 09:34:59 INFO - TinderboxPrint: CPU user
1,198.7 (12.5%) 09:34:59 INFO - TinderboxPrint: Swap in / out
0 / 0 09:34:59 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 250638336; Read time: 0; Write time: 239980 09:34:59 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 285782016; Read time: 0; Write time: 221184 09:34:59 INFO - Running post-run listener: _upload_blobber_files 09:34:59 INFO - Blob upload gear active. 09:34:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:34:59 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 09:34:59 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'] 09:34:59 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 09:35:00 INFO - (blobuploader) - INFO - Open directory for files ... 09:35:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:35:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:35:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:01 INFO - (blobuploader) - INFO - Done attempting. 09:35:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:35:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:35:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:01 INFO - (blobuploader) - INFO - Done attempting. 09:35:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:35:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:35:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:05 INFO - (blobuploader) - INFO - Done attempting. 09:35:05 INFO - (blobuploader) - INFO - Iteration through files over. 09:35:05 INFO - Return code: 0 09:35:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:35:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:35:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/46cacf1494daa5f380ba8a683383ad9b949e70f0b3d8784fdbe0ce565a64774b46c61bac7afff0bd6b676a0877a8c9a1b9a016a9750a564ae936746e47c39b46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9628f1af735425ff11619c6beff341467d1b536339389963328d0f1a81de0f1434f0d531936f4579cfbb04d0e55d824700264f12791b156041a00a95e6659302", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f816bca5c0d413d0780e1bbacae478d7976e1dae0452d8c384923216082684d45490144b2b7f64f73192ad30d18228a11bcaeae279024ba9c8ead93aa0963d50"} 09:35:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:35:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:35:05 INFO - Contents: 09:35:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/46cacf1494daa5f380ba8a683383ad9b949e70f0b3d8784fdbe0ce565a64774b46c61bac7afff0bd6b676a0877a8c9a1b9a016a9750a564ae936746e47c39b46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9628f1af735425ff11619c6beff341467d1b536339389963328d0f1a81de0f1434f0d531936f4579cfbb04d0e55d824700264f12791b156041a00a95e6659302", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f816bca5c0d413d0780e1bbacae478d7976e1dae0452d8c384923216082684d45490144b2b7f64f73192ad30d18228a11bcaeae279024ba9c8ead93aa0963d50"} 09:35:05 INFO - Running post-run listener: copy_logs_to_upload_dir 09:35:05 INFO - Copying logs to upload dir... 09:35:05 INFO - mkdir: /builds/slave/test/build/upload/logs 09:35:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.531393 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-12 09:35:05.350365) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 09:35:05.353698) ========= 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=4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767 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/46cacf1494daa5f380ba8a683383ad9b949e70f0b3d8784fdbe0ce565a64774b46c61bac7afff0bd6b676a0877a8c9a1b9a016a9750a564ae936746e47c39b46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9628f1af735425ff11619c6beff341467d1b536339389963328d0f1a81de0f1434f0d531936f4579cfbb04d0e55d824700264f12791b156041a00a95e6659302", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f816bca5c0d413d0780e1bbacae478d7976e1dae0452d8c384923216082684d45490144b2b7f64f73192ad30d18228a11bcaeae279024ba9c8ead93aa0963d50"} build_url:https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011385 build_url: 'https://queue.taskcluster.net/v1/task/EvIc5pFeRTW8YoNIuW0RVw/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/46cacf1494daa5f380ba8a683383ad9b949e70f0b3d8784fdbe0ce565a64774b46c61bac7afff0bd6b676a0877a8c9a1b9a016a9750a564ae936746e47c39b46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9628f1af735425ff11619c6beff341467d1b536339389963328d0f1a81de0f1434f0d531936f4579cfbb04d0e55d824700264f12791b156041a00a95e6659302", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f816bca5c0d413d0780e1bbacae478d7976e1dae0452d8c384923216082684d45490144b2b7f64f73192ad30d18228a11bcaeae279024ba9c8ead93aa0963d50"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 09:35:05.379502) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 09:35:05.379892) ========= 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=4ac8d263c7293b36a1be1aed000001be-1471009117.290155-1026116767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004450 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 09:35:05.465310) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 09:35:05.465659) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 09:35:05.465961) ========= ========= Total master_lag: 0.19 =========