builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-067 starttime: 1470716896.1 results: success (0) buildid: 20160808173001 builduid: 4d7a8406f94d4fd1b56b1fb59e7f712a revision: 5ff96be046af749644327f75b21ce9437d766886 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 21:28:16.103887) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 21:28:16.104411) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 21:28:16.104726) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 21:28:16.119569) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 21:28:16.119883) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-08 21:28:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.8M=0s 2016-08-08 21:28:16 (37.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.191317 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 21:28:16.325052) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 21:28:16.325428) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020552 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 21:28:16.374931) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 21:28:16.375295) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5ff96be046af749644327f75b21ce9437d766886 --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 5ff96be046af749644327f75b21ce9437d766886 --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=e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-08 21:28:16,679 truncating revision to first 12 chars 2016-08-08 21:28:16,679 Setting DEBUG logging. 2016-08-08 21:28:16,679 attempt 1/10 2016-08-08 21:28:16,679 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5ff96be046af?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-08 21:28:17,355 unpacking tar archive at: fx-team-5ff96be046af/testing/mozharness/ program finished with exit code 0 elapsedTime=1.011973 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 21:28:17.401275) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 21:28:17.401677) ========= script_repo_revision: 5ff96be046af749644327f75b21ce9437d766886 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 21:28:17.402050) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 21:28:17.402362) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 21:28:17.412891) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-08-08 21:28:17.413178) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:28:17 INFO - MultiFileLogger online at 20160808 21:28:17 in /builds/slave/test 21:28:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 21:28:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:28:17 INFO - {'append_to_log': False, 21:28:17 INFO - 'base_work_dir': '/builds/slave/test', 21:28:17 INFO - 'blob_upload_branch': 'Fx-Team', 21:28:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:28:17 INFO - 'branch': 'Fx-Team', 21:28:17 INFO - 'buildbot_json_path': 'buildprops.json', 21:28:17 INFO - 'config_files': ('talos/linux_config.py',), 21:28:17 INFO - 'default_actions': ('clobber', 21:28:17 INFO - 'read-buildbot-config', 21:28:17 INFO - 'download-and-extract', 21:28:17 INFO - 'populate-webroot', 21:28:17 INFO - 'create-virtualenv', 21:28:17 INFO - 'install', 21:28:17 INFO - 'run-tests'), 21:28:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:28:17 INFO - 'download_minidump_stackwalk': True, 21:28:17 INFO - 'download_symbols': 'ondemand', 21:28:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:28:17 INFO - 'tooltool.py': '/tools/tooltool.py', 21:28:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:28:17 INFO - '/tools/misc-python/virtualenv.py')}, 21:28:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:28:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:28:17 INFO - 'installer_path': 'installer.exe', 21:28:17 INFO - 'log_level': 'info', 21:28:17 INFO - 'log_name': 'talos', 21:28:17 INFO - 'log_to_console': True, 21:28:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:28:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:28:17 INFO - 'opt_config_files': (), 21:28:17 INFO - 'pip_index': False, 21:28:17 INFO - 'sps_profile': False, 21:28:17 INFO - 'sps_profile_interval': 0, 21:28:17 INFO - 'suite': 'dromaeojs', 21:28:17 INFO - 'system_bits': '32', 21:28:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:28:17 INFO - 'title': 'talos-linux64-ix-067', 21:28:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:28:17 INFO - 'use_talos_json': True, 21:28:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:28:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:28:17 INFO - 'work_dir': 'build'} 21:28:17 INFO - [mozharness: 2016-08-09 04:28:17.809962Z] Running clobber step. 21:28:17 INFO - Running pre-action listener: _resource_record_pre_action 21:28:17 INFO - Running main action method: clobber 21:28:17 INFO - rmtree: /builds/slave/test/build 21:28:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:28:18 INFO - Running post-action listener: _resource_record_post_action 21:28:18 INFO - [mozharness: 2016-08-09 04:28:18.773630Z] Finished clobber step (success) 21:28:18 INFO - [mozharness: 2016-08-09 04:28:18.773729Z] Running read-buildbot-config step. 21:28:18 INFO - Running pre-action listener: _resource_record_pre_action 21:28:18 INFO - Running main action method: read_buildbot_config 21:28:18 INFO - Using buildbot properties: 21:28:18 INFO - { 21:28:18 INFO - "project": "", 21:28:18 INFO - "product": "firefox", 21:28:18 INFO - "script_repo_revision": "production", 21:28:18 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 21:28:18 INFO - "repository": "", 21:28:18 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 21:28:18 INFO - "buildid": "20160808173001", 21:28:18 INFO - "pgo_build": "True", 21:28:18 INFO - "basedir": "/builds/slave/test", 21:28:18 INFO - "buildnumber": 965, 21:28:18 INFO - "slavename": "talos-linux64-ix-067", 21:28:18 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:28:18 INFO - "platform": "ubuntu64_hw", 21:28:18 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 21:28:18 INFO - "branch": "fx-team", 21:28:18 INFO - "repo_path": "integration/fx-team", 21:28:18 INFO - "revision": "5ff96be046af749644327f75b21ce9437d766886", 21:28:18 INFO - "stage_platform": "linux64-pgo", 21:28:18 INFO - "builduid": "4d7a8406f94d4fd1b56b1fb59e7f712a", 21:28:18 INFO - "slavebuilddir": "test" 21:28:18 INFO - } 21:28:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 21:28:18 INFO - Running post-action listener: _resource_record_post_action 21:28:18 INFO - [mozharness: 2016-08-09 04:28:18.776167Z] Finished read-buildbot-config step (success) 21:28:18 INFO - [mozharness: 2016-08-09 04:28:18.776254Z] Running download-and-extract step. 21:28:18 INFO - Running pre-action listener: _resource_record_pre_action 21:28:18 INFO - Running main action method: download_and_extract 21:28:18 INFO - mkdir: /builds/slave/test/build/tests 21:28:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:28:18 INFO - trying https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 21:28:18 INFO - Downloading https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/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 21:28:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/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 21:28:19 INFO - Downloaded 1361 bytes. 21:28:19 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 21:28:19 INFO - Using the following test package requirements: 21:28:19 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 21:28:19 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:28:19 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:28:19 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:19 INFO - u'jsshell-linux-x86_64.zip'], 21:28:19 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:28:19 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 21:28:19 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:28:19 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:28:19 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:28:19 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:28:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:28:19 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:28:19 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')]} 21:28:19 INFO - trying https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 21:28:19 INFO - Downloading https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/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 21:28:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/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 21:28:20 INFO - Downloaded 21828404 bytes. 21:28:20 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'] 21:28:20 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 21:28:21 INFO - Return code: 0 21:28:21 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 21:28:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:28:21 INFO - trying https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 21:28:21 INFO - Downloading https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/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 21:28:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/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 21:28:22 INFO - Downloaded 21828404 bytes. 21:28:22 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'] 21:28:22 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 21:28:24 INFO - Return code: 0 21:28:24 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')]} 21:28:24 INFO - trying https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 21:28:24 INFO - Downloading https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/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 21:28:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/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 21:28:24 INFO - Downloaded 14413723 bytes. 21:28:24 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'] 21:28:24 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 21:28:25 INFO - Return code: 0 21:28:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:28:25 INFO - trying https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 21:28:25 INFO - Downloading https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/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 21:28:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/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 21:28:26 INFO - Downloaded 59966260 bytes. 21:28:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 21:28:26 INFO - mkdir: /builds/slave/test/properties 21:28:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:28:26 INFO - Writing to file /builds/slave/test/properties/build_url 21:28:26 INFO - Contents: 21:28:26 INFO - build_url:https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 21:28:27 INFO - Running post-action listener: _resource_record_post_action 21:28:27 INFO - Running post-action listener: set_extra_try_arguments 21:28:27 INFO - [mozharness: 2016-08-09 04:28:27.107701Z] Finished download-and-extract step (success) 21:28:27 INFO - [mozharness: 2016-08-09 04:28:27.107933Z] Running populate-webroot step. 21:28:27 INFO - Running pre-action listener: _resource_record_pre_action 21:28:27 INFO - Running main action method: populate_webroot 21:28:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:28:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:28:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:28:27 INFO - '--tppagecycles', 21:28:27 INFO - '7'], 21:28:27 INFO - 'tests': ['tsvgm']}}, 21:28:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:28:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:28:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:28:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:28:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:28:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:28:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:28:27 INFO - 'talos_options': ['--disable-e10s'], 21:28:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:28:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:28:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:28:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:28:27 INFO - 'talos_options': ['--disable-e10s'], 21:28:27 INFO - 'tests': ['damp', 'tps']}, 21:28:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:28:27 INFO - 'tests': ['damp', 'tps']}, 21:28:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:28:27 INFO - 'tests': ['dromaeo_dom']}, 21:28:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:28:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:28:27 INFO - 'tests': ['basic_compositor_video']}, 21:28:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:28:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:28:27 INFO - 'tests': ['a11yr', 21:28:27 INFO - 'ts_paint', 21:28:27 INFO - 'tpaint', 21:28:27 INFO - 'sessionrestore', 21:28:27 INFO - 'sessionrestore_no_auto_restore', 21:28:27 INFO - 'tabpaint']}, 21:28:27 INFO - 'other-e10s': {'tests': ['a11yr', 21:28:27 INFO - 'ts_paint', 21:28:27 INFO - 'tpaint', 21:28:27 INFO - 'sessionrestore', 21:28:27 INFO - 'sessionrestore_no_auto_restore', 21:28:27 INFO - 'tabpaint']}, 21:28:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:28:27 INFO - 'ts_paint', 21:28:27 INFO - 'tpaint', 21:28:27 INFO - 'sessionrestore', 21:28:27 INFO - 'sessionrestore_no_auto_restore', 21:28:27 INFO - 'tabpaint']}, 21:28:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:28:27 INFO - 'ts_paint', 21:28:27 INFO - 'tpaint', 21:28:27 INFO - 'sessionrestore', 21:28:27 INFO - 'sessionrestore_no_auto_restore', 21:28:27 INFO - 'tabpaint']}, 21:28:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:28:27 INFO - 'tests': ['a11yr', 21:28:27 INFO - 'ts_paint', 21:28:27 INFO - 'tpaint', 21:28:27 INFO - 'sessionrestore', 21:28:27 INFO - 'sessionrestore_no_auto_restore', 21:28:27 INFO - 'tabpaint']}, 21:28:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:28:27 INFO - 'tests': ['a11yr', 21:28:27 INFO - 'ts_paint', 21:28:27 INFO - 'tpaint', 21:28:27 INFO - 'sessionrestore', 21:28:27 INFO - 'sessionrestore_no_auto_restore', 21:28:27 INFO - 'tabpaint']}, 21:28:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:28:27 INFO - 'tests': ['tsvgx', 21:28:27 INFO - 'tsvgr_opacity', 21:28:27 INFO - 'tart', 21:28:27 INFO - 'tscrollx', 21:28:27 INFO - 'cart']}, 21:28:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:28:27 INFO - 'tsvgr_opacity', 21:28:27 INFO - 'tart', 21:28:27 INFO - 'tscrollx', 21:28:27 INFO - 'cart']}, 21:28:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:28:27 INFO - 'talos_options': ['--disable-e10s'], 21:28:27 INFO - 'tests': ['tp5o']}, 21:28:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:28:27 INFO - 'tests': ['tp5o']}, 21:28:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:28:27 INFO - 'talos_options': ['--disable-e10s', 21:28:27 INFO - '--xperf_path', 21:28:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:28:27 INFO - 'tests': ['tp5n']}, 21:28:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:28:27 INFO - 'talos_options': ['--xperf_path', 21:28:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:28:27 INFO - 'tests': ['tp5n']}}, 21:28:27 INFO - 'talos.zip': {'path': '', 21:28:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:28:27 INFO - Running post-action listener: _resource_record_post_action 21:28:27 INFO - [mozharness: 2016-08-09 04:28:27.129442Z] Finished populate-webroot step (success) 21:28:27 INFO - [mozharness: 2016-08-09 04:28:27.129530Z] Running create-virtualenv step. 21:28:27 INFO - Running pre-action listener: _resource_record_pre_action 21:28:27 INFO - Running main action method: create_virtualenv 21:28:27 INFO - Creating virtualenv /builds/slave/test/build/venv 21:28:27 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 21:28:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:28:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:28:27 INFO - Using real prefix '/usr' 21:28:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:28:27 INFO - Installing distribute.............................................................................................................................................................................................done. 21:28:29 INFO - Installing pip.................done. 21:28:29 INFO - Return code: 0 21:28:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:28:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:28:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:28:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:29 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 0x1338030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f73b0>, '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 0x15f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15f8740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', '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 21:28:29 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 21:28:29 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 21:28:29 INFO - Using env: {'DISPLAY': ':0', 21:28:29 INFO - 'HOME': '/home/cltbld', 21:28:29 INFO - 'LANG': 'en_US.UTF-8', 21:28:29 INFO - 'LANGUAGE': 'en_US:en', 21:28:29 INFO - 'LOGNAME': 'cltbld', 21:28:29 INFO - 'MAIL': '/var/mail/cltbld', 21:28:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:29 INFO - 'MOZ_NO_REMOTE': '1', 21:28:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:29 INFO - 'NO_EM_RESTART': '1', 21:28:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:29 INFO - 'PWD': '/builds/slave/test', 21:28:29 INFO - 'SHELL': '/bin/bash', 21:28:29 INFO - 'SHLVL': '1', 21:28:29 INFO - 'TERM': 'linux', 21:28:29 INFO - 'TMOUT': '86400', 21:28:29 INFO - 'USER': 'cltbld', 21:28:29 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', 21:28:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:29 INFO - '_': '/tools/buildbot/bin/python'} 21:28:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:29 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 21:28:29 INFO - Cleaning up... 21:28:30 INFO - Return code: 0 21:28:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:28: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')]} 21:28:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28: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')]} 21:28:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:30 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 0x1338030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f73b0>, '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 0x15f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15f8740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', '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 21:28:30 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 21:28:30 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 21:28:30 INFO - Using env: {'DISPLAY': ':0', 21:28:30 INFO - 'HOME': '/home/cltbld', 21:28:30 INFO - 'LANG': 'en_US.UTF-8', 21:28:30 INFO - 'LANGUAGE': 'en_US:en', 21:28:30 INFO - 'LOGNAME': 'cltbld', 21:28:30 INFO - 'MAIL': '/var/mail/cltbld', 21:28:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:30 INFO - 'MOZ_NO_REMOTE': '1', 21:28:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:30 INFO - 'NO_EM_RESTART': '1', 21:28:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:30 INFO - 'PWD': '/builds/slave/test', 21:28:30 INFO - 'SHELL': '/bin/bash', 21:28:30 INFO - 'SHLVL': '1', 21:28:30 INFO - 'TERM': 'linux', 21:28:30 INFO - 'TMOUT': '86400', 21:28:30 INFO - 'USER': 'cltbld', 21:28:30 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', 21:28:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:30 INFO - '_': '/tools/buildbot/bin/python'} 21:28:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:30 INFO - Downloading/unpacking psutil>=3.1.1 21:28:30 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 21:28:30 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 21:28:30 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 21:28:30 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 21:28:30 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 21:28:30 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 21:28:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:28:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:28:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:28:32 INFO - Installing collected packages: psutil 21:28:32 INFO - Running setup.py install for psutil 21:28:32 INFO - building 'psutil._psutil_linux' extension 21:28:32 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 21:28:32 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 21:28:32 INFO - building 'psutil._psutil_posix' extension 21:28:32 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 21:28:32 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 21:28:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:28:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:28:32 INFO - Successfully installed psutil 21:28:32 INFO - Cleaning up... 21:28:32 INFO - Return code: 0 21:28:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:28: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')]} 21:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28: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')]} 21:28:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1338030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f73b0>, '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 0x15f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15f8740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', '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 21:28:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:28:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:28:32 INFO - Using env: {'DISPLAY': ':0', 21:28:32 INFO - 'HOME': '/home/cltbld', 21:28:32 INFO - 'LANG': 'en_US.UTF-8', 21:28:32 INFO - 'LANGUAGE': 'en_US:en', 21:28:32 INFO - 'LOGNAME': 'cltbld', 21:28:32 INFO - 'MAIL': '/var/mail/cltbld', 21:28:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:32 INFO - 'MOZ_NO_REMOTE': '1', 21:28:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:32 INFO - 'NO_EM_RESTART': '1', 21:28:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:32 INFO - 'PWD': '/builds/slave/test', 21:28:32 INFO - 'SHELL': '/bin/bash', 21:28:32 INFO - 'SHLVL': '1', 21:28:32 INFO - 'TERM': 'linux', 21:28:32 INFO - 'TMOUT': '86400', 21:28:32 INFO - 'USER': 'cltbld', 21:28:32 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', 21:28:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:32 INFO - '_': '/tools/buildbot/bin/python'} 21:28:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:28:32 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 21:28:32 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 21:28:32 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 21:28:32 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 21:28:32 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 21:28:32 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 21:28:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:28:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:28:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:28:34 INFO - Installing collected packages: mozsystemmonitor 21:28:34 INFO - Running setup.py install for mozsystemmonitor 21:28:34 INFO - Successfully installed mozsystemmonitor 21:28:34 INFO - Cleaning up... 21:28:35 INFO - Return code: 0 21:28:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1338030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f73b0>, '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 0x15f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15f8740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', '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 21:28:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:28:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:28:35 INFO - Using env: {'DISPLAY': ':0', 21:28:35 INFO - 'HOME': '/home/cltbld', 21:28:35 INFO - 'LANG': 'en_US.UTF-8', 21:28:35 INFO - 'LANGUAGE': 'en_US:en', 21:28:35 INFO - 'LOGNAME': 'cltbld', 21:28:35 INFO - 'MAIL': '/var/mail/cltbld', 21:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:35 INFO - 'MOZ_NO_REMOTE': '1', 21:28:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:35 INFO - 'NO_EM_RESTART': '1', 21:28:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:35 INFO - 'PWD': '/builds/slave/test', 21:28:35 INFO - 'SHELL': '/bin/bash', 21:28:35 INFO - 'SHLVL': '1', 21:28:35 INFO - 'TERM': 'linux', 21:28:35 INFO - 'TMOUT': '86400', 21:28:35 INFO - 'USER': 'cltbld', 21:28:35 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', 21:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:35 INFO - '_': '/tools/buildbot/bin/python'} 21:28:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:35 INFO - Downloading/unpacking jsonschema==2.5.1 21:28:35 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 21:28:35 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 21:28:35 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 21:28:35 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 21:28:35 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 21:28:35 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 21:28:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:28:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:28: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 21:28: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 21:28: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 21:28: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 21:28: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 21:28: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 21:28:44 INFO - Downloading functools32-3.2.3-2.tar.gz 21:28:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:28:44 INFO - warning: no files found matching '*.txt' 21:28:44 INFO - no previously-included directories found matching 'build' 21:28:44 INFO - no previously-included directories found matching 'dist' 21:28:44 INFO - no previously-included directories found matching '.git*' 21:28:44 INFO - Installing collected packages: jsonschema, functools32 21:28:44 INFO - Running setup.py install for functools32 21:28:44 INFO - warning: no files found matching '*.txt' 21:28:44 INFO - no previously-included directories found matching 'build' 21:28:44 INFO - no previously-included directories found matching 'dist' 21:28:44 INFO - no previously-included directories found matching '.git*' 21:28:44 INFO - Successfully installed jsonschema functools32 21:28:44 INFO - Cleaning up... 21:28:44 INFO - Return code: 0 21:28:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:28: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')]} 21:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28: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')]} 21:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28: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', '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 0x1338030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f73b0>, '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 0x15f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15f8740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', '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 21:28: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:28: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 functools32==3.2.3-2 21:28:44 INFO - Using env: {'DISPLAY': ':0', 21:28:44 INFO - 'HOME': '/home/cltbld', 21:28:44 INFO - 'LANG': 'en_US.UTF-8', 21:28:44 INFO - 'LANGUAGE': 'en_US:en', 21:28:44 INFO - 'LOGNAME': 'cltbld', 21:28:44 INFO - 'MAIL': '/var/mail/cltbld', 21:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:44 INFO - 'MOZ_NO_REMOTE': '1', 21:28:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:44 INFO - 'NO_EM_RESTART': '1', 21:28:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:44 INFO - 'PWD': '/builds/slave/test', 21:28:44 INFO - 'SHELL': '/bin/bash', 21:28:44 INFO - 'SHLVL': '1', 21:28:44 INFO - 'TERM': 'linux', 21:28:44 INFO - 'TMOUT': '86400', 21:28:44 INFO - 'USER': 'cltbld', 21:28:44 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', 21:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:44 INFO - '_': '/tools/buildbot/bin/python'} 21:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:28:44 INFO - Cleaning up... 21:28:44 INFO - Return code: 0 21:28:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:28: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')]} 21:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28: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')]} 21:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28: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', '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 0x1338030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f73b0>, '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 0x15f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15f8740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', '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 21:28: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:28: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 blobuploader==1.2.4 21:28:44 INFO - Using env: {'DISPLAY': ':0', 21:28:44 INFO - 'HOME': '/home/cltbld', 21:28:44 INFO - 'LANG': 'en_US.UTF-8', 21:28:44 INFO - 'LANGUAGE': 'en_US:en', 21:28:44 INFO - 'LOGNAME': 'cltbld', 21:28:44 INFO - 'MAIL': '/var/mail/cltbld', 21:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:44 INFO - 'MOZ_NO_REMOTE': '1', 21:28:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:44 INFO - 'NO_EM_RESTART': '1', 21:28:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:44 INFO - 'PWD': '/builds/slave/test', 21:28:44 INFO - 'SHELL': '/bin/bash', 21:28:44 INFO - 'SHLVL': '1', 21:28:44 INFO - 'TERM': 'linux', 21:28:44 INFO - 'TMOUT': '86400', 21:28:44 INFO - 'USER': 'cltbld', 21:28:44 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', 21:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:44 INFO - '_': '/tools/buildbot/bin/python'} 21:28:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:45 INFO - Downloading/unpacking blobuploader==1.2.4 21:28:45 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 21:28:45 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 21:28:45 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 21:28:45 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 21:28:45 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 21:28:45 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 21:28:47 INFO - Downloading blobuploader-1.2.4.tar.gz 21:28:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:28:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:28:48 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 21:28:48 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 21:28:48 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 21:28:48 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 21:28:48 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 21:28:48 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 21:28:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:28:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:28:48 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 21:28:48 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 21:28:48 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 21:28:48 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 21:28:48 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 21:28:48 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 21:28:48 INFO - Downloading docopt-0.6.1.tar.gz 21:28:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:28:48 INFO - Installing collected packages: blobuploader, requests, docopt 21:28:48 INFO - Running setup.py install for blobuploader 21:28:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:28:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:28:48 INFO - Running setup.py install for requests 21:28:49 INFO - Running setup.py install for docopt 21:28:49 INFO - Successfully installed blobuploader requests docopt 21:28:49 INFO - Cleaning up... 21:28:49 INFO - Return code: 0 21:28:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:28:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:28:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:49 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 0x1338030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f73b0>, '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 0x15f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15f8740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', '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 21:28:49 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 21:28:49 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 21:28:49 INFO - Using env: {'DISPLAY': ':0', 21:28:49 INFO - 'HOME': '/home/cltbld', 21:28:49 INFO - 'LANG': 'en_US.UTF-8', 21:28:49 INFO - 'LANGUAGE': 'en_US:en', 21:28:49 INFO - 'LOGNAME': 'cltbld', 21:28:49 INFO - 'MAIL': '/var/mail/cltbld', 21:28:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:49 INFO - 'MOZ_NO_REMOTE': '1', 21:28:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:49 INFO - 'NO_EM_RESTART': '1', 21:28:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:49 INFO - 'PWD': '/builds/slave/test', 21:28:49 INFO - 'SHELL': '/bin/bash', 21:28:49 INFO - 'SHLVL': '1', 21:28:49 INFO - 'TERM': 'linux', 21:28:49 INFO - 'TMOUT': '86400', 21:28:49 INFO - 'USER': 'cltbld', 21:28:49 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', 21:28:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:49 INFO - '_': '/tools/buildbot/bin/python'} 21:28:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:28:49 INFO - Running setup.py (path:/tmp/pip-1ncD44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:28:49 INFO - Running setup.py (path:/tmp/pip-5xJnP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:28:49 INFO - Running setup.py (path:/tmp/pip-xqqYOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:28:49 INFO - Running setup.py (path:/tmp/pip-tJuwcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:28:49 INFO - Running setup.py (path:/tmp/pip-jFlVqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:28:49 INFO - Running setup.py (path:/tmp/pip-8mGxya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:28:50 INFO - Running setup.py (path:/tmp/pip-4khzfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:28:50 INFO - Running setup.py (path:/tmp/pip-rPxQxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:28:50 INFO - Running setup.py (path:/tmp/pip-vv2g10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:28:50 INFO - Running setup.py (path:/tmp/pip-n13g4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:28:50 INFO - Running setup.py (path:/tmp/pip-jTqbwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:28:50 INFO - Running setup.py (path:/tmp/pip-KVjPWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:28:50 INFO - Running setup.py (path:/tmp/pip-EwTTcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:28:50 INFO - Running setup.py (path:/tmp/pip-DAIVEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:28:50 INFO - Running setup.py (path:/tmp/pip-3PydLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:28:50 INFO - Running setup.py (path:/tmp/pip-APABiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:28:50 INFO - Running setup.py (path:/tmp/pip-D28Cqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:28:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:28:50 INFO - Running setup.py install for manifestparser 21:28:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:28:50 INFO - Running setup.py install for mozcrash 21:28:51 INFO - Running setup.py install for mozdebug 21:28:51 INFO - Running setup.py install for mozdevice 21:28:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:28:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:28:51 INFO - Running setup.py install for mozfile 21:28:51 INFO - Running setup.py install for mozhttpd 21:28:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:28:51 INFO - Running setup.py install for mozinfo 21:28:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:28:51 INFO - Running setup.py install for mozInstall 21:28:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:28:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:28:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:28:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:28:51 INFO - Running setup.py install for mozleak 21:28:51 INFO - Running setup.py install for mozlog 21:28:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:28:51 INFO - Running setup.py install for moznetwork 21:28:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:28:51 INFO - Running setup.py install for mozprocess 21:28:52 INFO - Running setup.py install for mozprofile 21:28:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:28:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:28:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:28:52 INFO - Running setup.py install for mozrunner 21:28:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:28:52 INFO - Running setup.py install for mozscreenshot 21:28:52 INFO - Running setup.py install for moztest 21:28:52 INFO - Running setup.py install for mozversion 21:28:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:28:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:28:52 INFO - Cleaning up... 21:28:52 INFO - Return code: 0 21:28:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:52 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 0x1338030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f73b0>, '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 0x15f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15f8740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', '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 21:28:52 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 21:28:52 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 21:28:52 INFO - Using env: {'DISPLAY': ':0', 21:28:52 INFO - 'HOME': '/home/cltbld', 21:28:52 INFO - 'LANG': 'en_US.UTF-8', 21:28:52 INFO - 'LANGUAGE': 'en_US:en', 21:28:52 INFO - 'LOGNAME': 'cltbld', 21:28:52 INFO - 'MAIL': '/var/mail/cltbld', 21:28:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:52 INFO - 'MOZ_NO_REMOTE': '1', 21:28:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:52 INFO - 'NO_EM_RESTART': '1', 21:28:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:52 INFO - 'PWD': '/builds/slave/test', 21:28:52 INFO - 'SHELL': '/bin/bash', 21:28:52 INFO - 'SHLVL': '1', 21:28:52 INFO - 'TERM': 'linux', 21:28:52 INFO - 'TMOUT': '86400', 21:28:52 INFO - 'USER': 'cltbld', 21:28:52 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', 21:28:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:52 INFO - '_': '/tools/buildbot/bin/python'} 21:28:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:28:52 INFO - Running setup.py (path:/tmp/pip-8TWupY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:28:52 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)) 21:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:28:52 INFO - Running setup.py (path:/tmp/pip-JAgTai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:28:52 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)) 21:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:28:52 INFO - Running setup.py (path:/tmp/pip-vkqqdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:28:53 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)) 21:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:28:53 INFO - Running setup.py (path:/tmp/pip-42lSE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:28:53 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)) 21:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:28:53 INFO - Running setup.py (path:/tmp/pip-diQPZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:28:53 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)) 21:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:28:53 INFO - Running setup.py (path:/tmp/pip-cOtJa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:28:53 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)) 21:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:28:53 INFO - Running setup.py (path:/tmp/pip-rkPzuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:28:53 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)) 21:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:28:53 INFO - Running setup.py (path:/tmp/pip-XtLCI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:28:53 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)) 21:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:28:53 INFO - Running setup.py (path:/tmp/pip-dOsXlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:28:53 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)) 21:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:28:53 INFO - Running setup.py (path:/tmp/pip-Hol3vx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:28:53 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)) 21:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:28:53 INFO - Running setup.py (path:/tmp/pip-CxQzPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:28:53 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)) 21:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:28:53 INFO - Running setup.py (path:/tmp/pip-o3iv03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:28:53 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)) 21:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:28:53 INFO - Running setup.py (path:/tmp/pip-abMnZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:28:53 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)) 21:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:28:53 INFO - Running setup.py (path:/tmp/pip-YSstJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:28:53 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)) 21:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:28:53 INFO - Running setup.py (path:/tmp/pip-jdSFQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:28:53 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)) 21:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:28:53 INFO - Running setup.py (path:/tmp/pip-Afv9PP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:28:53 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)) 21:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:28:53 INFO - Running setup.py (path:/tmp/pip-CgBhMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:28:54 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)) 21:28:54 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)) 21:28:54 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)) 21:28:54 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)) 21:28:54 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)) 21:28:54 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)) 21:28:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:28:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:28:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:28:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:28:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:28:55 INFO - Downloading blessings-1.6.tar.gz 21:28:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:28:56 INFO - Installing collected packages: blessings 21:28:56 INFO - Running setup.py install for blessings 21:28:56 INFO - Successfully installed blessings 21:28:56 INFO - Cleaning up... 21:28:56 INFO - Return code: 0 21:28:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:28:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:28:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:28:57 INFO - Reading from file tmpfile_stdout 21:28:57 INFO - Current package versions: 21:28:57 INFO - argparse == 1.2.1 21:28:57 INFO - blessings == 1.6 21:28:57 INFO - blobuploader == 1.2.4 21:28:57 INFO - docopt == 0.6.1 21:28:57 INFO - functools32 == 3.2.3-2 21:28:57 INFO - jsonschema == 2.5.1 21:28:57 INFO - manifestparser == 1.1 21:28:57 INFO - mozInstall == 1.12 21:28:57 INFO - mozcrash == 0.17 21:28:57 INFO - mozdebug == 0.1 21:28:57 INFO - mozdevice == 0.48 21:28:57 INFO - mozfile == 1.2 21:28:57 INFO - mozhttpd == 0.7 21:28:57 INFO - mozinfo == 0.9 21:28:57 INFO - mozleak == 0.1 21:28:57 INFO - mozlog == 3.3 21:28:57 INFO - moznetwork == 0.27 21:28:57 INFO - mozprocess == 0.23 21:28:57 INFO - mozprofile == 0.28 21:28:57 INFO - mozrunner == 6.12 21:28:57 INFO - mozscreenshot == 0.1 21:28:57 INFO - mozsystemmonitor == 0.3 21:28:57 INFO - moztest == 0.7 21:28:57 INFO - mozversion == 1.4 21:28:57 INFO - psutil == 3.1.1 21:28:57 INFO - requests == 1.2.3 21:28:57 INFO - wsgiref == 0.1.2 21:28:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:28: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')]} 21:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28: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')]} 21:28:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28: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 0x1338030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f73b0>, '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 0x15f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15f8740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', '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 21:28: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 21:28: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 21:28:57 INFO - Using env: {'DISPLAY': ':0', 21:28:57 INFO - 'HOME': '/home/cltbld', 21:28:57 INFO - 'LANG': 'en_US.UTF-8', 21:28:57 INFO - 'LANGUAGE': 'en_US:en', 21:28:57 INFO - 'LOGNAME': 'cltbld', 21:28:57 INFO - 'MAIL': '/var/mail/cltbld', 21:28:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:57 INFO - 'MOZ_NO_REMOTE': '1', 21:28:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:57 INFO - 'NO_EM_RESTART': '1', 21:28:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:57 INFO - 'PWD': '/builds/slave/test', 21:28:57 INFO - 'SHELL': '/bin/bash', 21:28:57 INFO - 'SHLVL': '1', 21:28:57 INFO - 'TERM': 'linux', 21:28:57 INFO - 'TMOUT': '86400', 21:28:57 INFO - 'USER': 'cltbld', 21:28:57 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', 21:28:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:57 INFO - '_': '/tools/buildbot/bin/python'} 21:28:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:57 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)) 21:28:57 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)) 21:28:57 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)) 21:28:57 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)) 21:28:57 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)) 21:28:57 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)) 21:28:57 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)) 21:28:57 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)) 21:28:57 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)) 21:28:57 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)) 21:28:57 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)) 21:28:57 INFO - Cleaning up... 21:28:57 INFO - Return code: 0 21:28:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:28: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')]} 21:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:28:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28: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')]} 21:28:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:28:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:28:57 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 0x1338030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x149b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f73b0>, '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 0x15f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15f8740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', '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 21:28:57 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 21:28:57 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 21:28:57 INFO - Using env: {'DISPLAY': ':0', 21:28:57 INFO - 'HOME': '/home/cltbld', 21:28:57 INFO - 'LANG': 'en_US.UTF-8', 21:28:57 INFO - 'LANGUAGE': 'en_US:en', 21:28:57 INFO - 'LOGNAME': 'cltbld', 21:28:57 INFO - 'MAIL': '/var/mail/cltbld', 21:28:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:28:57 INFO - 'MOZ_NO_REMOTE': '1', 21:28:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:28:57 INFO - 'NO_EM_RESTART': '1', 21:28:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:28:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:28:57 INFO - 'PWD': '/builds/slave/test', 21:28:57 INFO - 'SHELL': '/bin/bash', 21:28:57 INFO - 'SHLVL': '1', 21:28:57 INFO - 'TERM': 'linux', 21:28:57 INFO - 'TMOUT': '86400', 21:28:57 INFO - 'USER': 'cltbld', 21:28:57 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', 21:28:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:28:57 INFO - '_': '/tools/buildbot/bin/python'} 21:28:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:28:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:28:57 INFO - Cleaning up... 21:28:57 INFO - Return code: 0 21:28:57 INFO - Running post-action listener: _resource_record_post_action 21:28:57 INFO - Running post-action listener: _start_resource_monitoring 21:28:57 INFO - Starting resource monitoring. 21:28:57 INFO - [mozharness: 2016-08-09 04:28:57.494412Z] Finished create-virtualenv step (success) 21:28:57 INFO - [mozharness: 2016-08-09 04:28:57.494888Z] Running install step. 21:28:57 INFO - Running pre-action listener: _resource_record_pre_action 21:28:57 INFO - Running main action method: install 21:28:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:28:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:28:57 INFO - Reading from file tmpfile_stdout 21:28:57 INFO - Detecting whether we're running mozinstall >=1.0... 21:28:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:28:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:28:57 INFO - Reading from file tmpfile_stdout 21:28:57 INFO - Output received: 21:28:57 INFO - Usage: mozinstall [options] installer 21:28:57 INFO - Options: 21:28:57 INFO - -h, --help show this help message and exit 21:28:57 INFO - -d DEST, --destination=DEST 21:28:57 INFO - Directory to install application into. [default: 21:28:57 INFO - "/builds/slave/test"] 21:28:57 INFO - --app=APP Application being installed. [default: firefox] 21:28:57 INFO - mkdir: /builds/slave/test/build/application 21:28:57 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'] 21:28:57 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 21:29:14 INFO - Reading from file tmpfile_stdout 21:29:14 INFO - Output received: 21:29:14 INFO - /builds/slave/test/build/application/firefox/firefox 21:29:14 INFO - Running post-action listener: _resource_record_post_action 21:29:14 INFO - [mozharness: 2016-08-09 04:29:14.836103Z] Finished install step (success) 21:29:14 INFO - [mozharness: 2016-08-09 04:29:14.836435Z] Running run-tests step. 21:29:14 INFO - Running pre-action listener: _resource_record_pre_action 21:29:14 INFO - Running main action method: run_tests 21:29:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:29:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:29:14 INFO - Python 2.7.3 21:29:14 INFO - Return code: 0 21:29:14 INFO - grabbing minidump binary from tooltool 21:29: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')]} 21:29: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 0x15f73b0>, '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 0x15f82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15f8740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:29: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 21:29: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 21:29: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 21:29:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:29:14 INFO - Return code: 0 21:29:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:29:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:29:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:29:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:29:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:29:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 21:29:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 21:29:14 INFO - Using env: {'DISPLAY': ':0', 21:29:14 INFO - 'HOME': '/home/cltbld', 21:29:14 INFO - 'LANG': 'en_US.UTF-8', 21:29:14 INFO - 'LANGUAGE': 'en_US:en', 21:29:14 INFO - 'LOGNAME': 'cltbld', 21:29:14 INFO - 'MAIL': '/var/mail/cltbld', 21:29:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:29:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:14 INFO - 'MOZ_NO_REMOTE': '1', 21:29:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:29:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:14 INFO - 'NO_EM_RESTART': '1', 21:29:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:14 INFO - 'PWD': '/builds/slave/test', 21:29:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:29:14 INFO - 'SHELL': '/bin/bash', 21:29:14 INFO - 'SHLVL': '1', 21:29:14 INFO - 'TERM': 'linux', 21:29:14 INFO - 'TMOUT': '86400', 21:29:14 INFO - 'USER': 'cltbld', 21:29:14 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452', 21:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:14 INFO - '_': '/tools/buildbot/bin/python'} 21:29:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 21:29:15 INFO - mozversion application_buildid: 20160808173001 21:29:15 INFO - mozversion application_changeset: 5ff96be046af749644327f75b21ce9437d766886 21:29:15 INFO - mozversion application_display_name: Nightly 21:29:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:29:15 INFO - mozversion application_name: Firefox 21:29:15 INFO - mozversion application_remotingname: firefox 21:29:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:29:15 INFO - mozversion application_vendor: Mozilla 21:29:15 INFO - mozversion application_version: 51.0a1 21:29:15 INFO - mozversion platform_buildid: 20160808173001 21:29:15 INFO - mozversion platform_changeset: 5ff96be046af749644327f75b21ce9437d766886 21:29:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:29:15 INFO - mozversion platform_version: 51.0a1 21:29:15 INFO - using testdate: 1470716955 21:29:15 INFO - actual date: 1470716955 21:29:15 INFO - starting webserver on 'localhost:58204' 21:29:15 INFO - SUITE-START | Running 2 tests 21:29:15 INFO - TEST-START | dromaeo_css 21:29:15 INFO - operating with platform_type : linux_ 21:29:15 INFO - Initialising browser for dromaeo_css test... 21:29:15 INFO - TEST-INFO | started process 4636 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdnU9gQ/profile http://localhost:58204/getInfo.html) 21:29:18 INFO - PROCESS | 4636 | __metrics Screen width/height:1600/1200 21:29:18 INFO - PROCESS | 4636 | colorDepth:24 21:29:18 INFO - PROCESS | 4636 | Browser inner width/height: 1024/697 21:29:18 INFO - PROCESS | 4636 | __metrics 21:29:19 INFO - PROCESS | 4636 | 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" 21:29:19 INFO - PROCESS | 4636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:29:20 INFO - TEST-INFO | 4636: exit 0 21:29:20 INFO - Browser initialized. 21:29:20 INFO - Running cycle 1/1 for dromaeo_css test... 21:29:20 INFO - TEST-INFO | started process 4716 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdnU9gQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:29:32 INFO - PROCESS | 4716 | RSS: Main: 156549120 21:29:32 INFO - PROCESS | 4716 | 21:30:23 INFO - PROCESS | 4716 | 1470717023631 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] 21:32:32 INFO - PROCESS | 4716 | Cycle 1(1): loaded http://localhost:58204/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58204/tests/dromaeo/cssquery-ext.html) 21:32:33 INFO - PROCESS | 4716 | RSS: Main: 260501504 21:32:33 INFO - PROCESS | 4716 | 21:35:36 INFO - PROCESS | 4716 | Cycle 1(1): loaded http://localhost:58204/tests/dromaeo/cssquery-ext.html (next: http://localhost:58204/tests/dromaeo/cssquery-jquery.html) 21:35:36 INFO - PROCESS | 4716 | RSS: Main: 246284288 21:35:36 INFO - PROCESS | 4716 | 21:38:38 INFO - PROCESS | 4716 | Cycle 1(1): loaded http://localhost:58204/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58204/tests/dromaeo/cssquery-mootools.html) 21:38:39 INFO - PROCESS | 4716 | RSS: Main: 190799872 21:38:39 INFO - PROCESS | 4716 | 21:41:41 INFO - PROCESS | 4716 | Cycle 1(1): loaded http://localhost:58204/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58204/tests/dromaeo/cssquery-prototype.html) 21:41:41 INFO - PROCESS | 4716 | RSS: Main: 244424704 21:41:41 INFO - PROCESS | 4716 | 21:44:44 INFO - PROCESS | 4716 | Cycle 1(1): loaded http://localhost:58204/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58204/tests/dromaeo/cssquery-yui.html) 21:44:45 INFO - PROCESS | 4716 | RSS: Main: 192065536 21:44:45 INFO - PROCESS | 4716 | 21:47:47 INFO - PROCESS | 4716 | Cycle 1(1): loaded http://localhost:58204/tests/dromaeo/cssquery-yui.html (next: http://localhost:58204/tests/dromaeo/cssquery-dojo.html) 21:47:48 INFO - PROCESS | 4716 | RSS: Main: 283480064 21:47:48 INFO - PROCESS | 4716 | 21:47:48 INFO - PROCESS | 4716 | __start_tp_report 21:47:48 INFO - PROCESS | 4716 | _x_x_mozilla_page_load 21:47:48 INFO - PROCESS | 4716 | _x_x_mozilla_page_load_details 21:47:48 INFO - PROCESS | 4716 | |i|pagename|runs| 21:47:48 INFO - PROCESS | 4716 | |0;dojo.html;2987.3875855449633;2989.850507474626;3016.1705530978984;3024.395120975805;3049.3748781499794;4986.45149033607;5056.772445239963;5105.795768169273;5167.044096842087;5222.268882356469;5139.614528910328;5147.459516750743;5169.250458683498;5177.896442071156;5226.686398816064;4650.651201159918;4712.316714076457;4745.193317136078;4754.049190161964;4887.071456423286;4852.29641701953;4855.878603034935;4866.440359358684;4876.0247950409885;4897.902041959159;4636.744979026153;4672.742999135046;4737.384140061805;4782.593479554242;4788.186008378584;4609.884752881171;4626.976865115653;4638.165130276549;4681.953180468186;4707.976460117712;4607.078584283123;4671.3226582145735;4671.836485723017;4698.600618947359;4734.100520901018;4994.55049045584;5052.545270925598;5125.794968201268;5136.5377116059735;5163.948360516384;5424.810131645413;5458.2904222450825;5467.234587157801;5513.365962914236;5517.668939863581;5509.917351239735;5547.472990065938;5550.639208451478;5551.500364967132;5558.972205138989;4980.551750342486;5100.260462232955;5121.871953201163;5148.9742551287;5174.689518628894;5644.125160600113;5645.5483561314995;5681.971590142106;5690.317161940594;5699.715014249271;5422.267993820863;5432.456754324536;5480.671159730429;5485.424030476823;5490.011797876421;5346.8395948121615;5377.300950876361;5438.238646589481;5516.668999860021;5574.665520068809;5427.185922111715;5436.374816896028;5452.890942181134;5483.8080667176455;5485.533729633026;5417.106177480671;5417.10617748075;5431.022415965164;5445.237666726662;5490.752916118784;4152.6055024772595;4186.1836941796055;4214.599613036757;4235.8729238122905;4237.343211802176;3751.381022131318;3763.7177211708686;3798.582155962842;3801.2207497463505;3811.4092315691114;5103.617228707788;5126.846194614168;5136.717480538531;5182.015417070745;5185.688858668492;5364.544013758796;5364.758585863646;5380.704061276589;5439.075357189287;5479.671219726829;5277.445868183785;5281.894362112736;5296.417394086648;5307.2569840222395;5312.840614781563;4744.1934871072;4760.666753327234;4760.857174284777;4773.11697335994;4806.927896081562;5596.636218645856;5625.09998400254;5753;5761.53907687384;5772.7113644317615;5424.349078110652;5468.48049435303;5480.232767412645;5505.119180931032;5524.806631767869;5664.546836253172;5681.119426488901;5686.09022556389;5694.088945768657;5732.140178973187;5237.738113094406;5304.893902122013;5364.731763411735;5371.5971302153275;5383.192521121863;5420.9186862197885;5508.283923089973;5510.448955104457;5530.944690553043;5541.251930989265;5320.973395133155;5377.27406800077;5378.19327100938;5380.43505431924;5382.838514844561;5271.841844744664;5315.521603055744;5377.865553361197;5414.729263536886;5419.864503387289;5239.397469291081;5291.4443983382735;5306.469353064663;5335.226392173191;5339.519443250126;5387.380451248;5396;5440.29276194108;5446.891062178814;5469.124940009579;5181.611379146504;5270.947290527199;5273.393559740679;5288.153895376721;5300.2049692546425 21:47:48 INFO - PROCESS | 4716 | |1;ext.html;578.7655999320274;592.7865968251429;592.977114477526;594.1147689941985;603.8309273403448;26818.19545413638;42526.14947701044;43974.560254397446;44070.559294407045;48887.266690999575;45238.77380613092;45761;45793.77103114488;45822.54177458224;45915.77042114793;7912.643931023089;8870.82258354831;9009.144131307516;9016.68441604544;9024.232940200058;10317.84523232152;10688.51901664427;10718.946405268001;11105.222634415553;11109.055730262937;10793.352398856054;10794.676159715222;10897.564097436092;10902.454877256145;10904.836427453594;10740.624078157267;10800.78398432027;10811.35131892089;10825.07986821121;10828.296160749525;10504.789904201949;10678.572857085706;10709.892901071045;10716.303440276435;10720.839187412195;42238.577614223774;42349.57650423487;42450.36324455136;42548.78725606383;42570;3400.268942177444;3452.5166476693284;3893.7079719020903;4224.429701990222;4394.868153955386;32779.62676809707;32975.34049319024;33523.664763352295;33550.49674254888;33809.492857607154;4326.545712700184;4340.414044104069;4424.933625995613;4488.775561221959;4557.430321209848;353337;357817;358539.9999999974;358702;360374;59432.70283648554;59698;59748;59758;59851.401485984585;62484.687576562734;62523.374766252666;62531.37468625347;62623;62631.686841566414;16812.41156559539;17144.657106857794;17193.570160745832;17196.398126065527;17196.656066878593;11847.407629618658;12945.158564693265;13105.672358191121;13226.074174807864;13227.867721322857;17270.05014724177;17481.562960925825;17488.825111748974;17499.212535435683;17731.822681773017;6461.709223084972;6626.801195964129;6679.699413526403;6701.932980670131;6808.62552559614;983.2822039910905;1082.929609575375;1104.972375690598;1138.6242539961793;1157.0974639780984;32175;32545;32726.181845454055;32857;32968.70267291033;1070.8714954205388;1091.3942761767137;1127.9774404512027;1131.2590253384046;1146.9139814513778;16560.337586496647;17403;17444.47666570005;17449.825381836632;17540.91229543844;539033;554659;555457;555794;556423;58974;59199.40800591939;59205.70397147987;59221.70389148113;59238.70380648156;1201.08117290273;1283.550757234979;1331;1349.150035477682;1350.352569864783;32150.19624509406;33905.32189356248;33951.49072763861;34295;34349.141271467415;49953.50046499488;50698;50715.74642126766;50745.49254507408;50821.237681435545;411940;412224;412592;413726;413904;28145.59272036349;28541.857290714244;28597.14208573746;30201.244968875955;30408.391832163674;47385.7630711858;47794.28308575444;47973;48118.278225827344;48361.75819120882;40489;40621.79689101536;41494;42558;42597.57402425936;40727.79636101801;41199.58800411958;41550;41981.37027944522;42311;41109;41948.3707744378;41996.580034199265;41997.580024199364;42102.15795684008;34964.12589685278;37028;37142.44286335761;37147.25705485821;37636.435453467675;28112;28144.43711125807;28186.85906570454;28355.716442836132;28407.715922841333 21:47:48 INFO - PROCESS | 4716 | |2;jquery.html;360.23629904602245;372.29821785933507;391.18047690089264;392.75452841973765;408.97341672791265;17322.480325590237;19395.903020484897;19433.31983380582;19557.902210488945;20226.59546809063;19241.134148963294;19713.507162320948;19819.306324278652;20119.899400503014;20190.39428817134;5566.137248726453;5734.225879506265;5778.739956701937;5998.130271110686;5998.2802063752415;3933.449317095595;5900.232969713931;5937.584369094144;5945.702714864261;5946.256717910262;6148.692565371736;6414.037894315843;6438.034294855785;6519.771807986722;6523.575967562117;5823.505002074808;5848.824535263949;5901.144334071536;6488.740450381979;6507.186601674791;5750.856228594277;5752.338481074688;5756.251687280668;5760.423957604248;5761.251037365158;13799.586012419644;13844.515441959584;14122.435102595884;14171.645708857259;14272.714545709028;2363.0902102690416;2402.243293362585;2671.238696971354;2918.6205793246954;2932.5894374787545;9931.851022234672;9963.701088967342;9988.350757200758;10457.529411176516;10487.108595769369;1851.3705340184267;1914.7798003229668;1916.0456334538833;1917.7027560728109;1945.6984167454068;76884;81911.59044204741;83714;85098.14901850965;85510;10852;10966.342019478698;11150.163737719704;11276.323420594626;11286.379249141211;15472;15499.845001550066;15500.147491887828;15506.302216400289;15524.301406436738;8078.798030049295;8194.877076843733;8243.340532757366;8332.291755200877;8358.749237522885;7881.881771773428;7896.249856263646;8179;8221.383396245186;8223.917760822435;4637.188492013884;4705.317728929286;4729.5034021427255;4732.834350797774;4903.632227582945;1867.8225568570963;1922.6635338815931;1939.2921583621876;1962.1758861278297;1984.4046785964154;859.1408591408591;953.2135987810084;1019.4800651667703;1026.9229807764448;1071.1912506057877;19320.516987075436;20220.29228977008;20226.39320820378;20344.25352577383;20556.691649625267;964.8311545479513;973.6640858903667;980.2501086668705;983.8032393521324;1007.7934023525306;10504.474776261157;11310.151738619472;11340.489677964346;11357.318560886373;11372.772544549063;175384;175659;175663;391363;457460;56010.15984760232;56478.43521564732;65127;85719.5714021426;90887.54556227177;945.635930166877;947.4836214263244;974.3179774158118;980.1325826643472;980.5146452506056;17508.912455437643;18298.176582054104;18491;18586.25654973786;18601.534961625526;43016.56983430251;43136.78431607822;43441;43977;44599.77700111479;60648.6967565177;61351;61420;61869.07196392148;62749.372506274354;8265.83468330642;8327.95836020816;8503.532305723245;8600.870986935326;8829.779255518664;12097.637070887888;13307.401166947317;13326.93336533311;13401.329933503092;13559.254241016839;11426.2001659883;11550.325202845372;11803.468843902217;11894.048476121716;11943.522259109539;11121.610743624258;11263.380514071805;11422.82865757031;11626.418679066182;11831.526738930346;11419.257748246337;11577;11584.710382240512;11952.880471195176;12025.037996960396;8082.878756818534;8236.258736713724;8250.587470626564;8356.289715374262;8634.697785577475;10836.566537338418;11245.887541124484;11267.211295209258;11324.716882078013;11668.591599293984 21:47:48 INFO - PROCESS | 4716 | |3;mootools.html;1451.1075688451601;1523.0709267346917;1647.34106357457;1686.1990554486629;1782.6256486137918;8100.230478104578;8636.56817159141;8650.91349086509;8652.8702069469;8662.566871656416;7537.623118844063;8015.118336982927;8319.04331001935;8367.330613550917;8591.140885911405;2294.4837411582384;2341.4146463384154;2344.824138189634;2350.647402889572;2351.8118550516;2252.042881775247;2275.2719129878446;2276.2943487518837;2281.840271181018;2308.7344955330186;2278.829087818412;2284;2290.4617414907484;2324.8256380771427;2325.9185928492507;2224.455008522906;2235.217673814152;2236.519148383097;2245.3488488338444;2248.392933907844;2217.3347995601416;2226.5101677630896;2231.921882734113;2233.0286325448483;2234.921777737771;8364.498130112213;8425.831483370359;8426.283765879907;8432.915670843275;8433.493990360597;5004.974975125101;5130.897382052338;5141.537261646432;5155.355580552394;5230.163173892159;3211.630662473822;3243.675632436761;3403.404404229275;3416.4192087344964;3419.076849250688;1090.1170052257617;1098.4672433869566;1101.6419663609279;1108.0969010256615;1133.2747041893194;11515.36665483398;11634.476448559835;11717.648470545897;11720.472578733978;11725.237859539102;4364.03618371734;4405.757683327448;4578.771061446981;4581.79381927814;4582.679212455096;10046.648367307109;10055.145312648507;10069.647562335284;10072.395656260647;10090.293679442515;5181.5854731621375;5193.1171700810955;5199.506046925536;5201.505856943584;5206;4220.198162349144;4304;4577.244754615451;4649.790759415834;4685.273782563708;3896.2597106549665;3945.11234972129;4057.3102572562143;4061.756294622332;4061.89845253871;1258.3079306381637;1280.5262053040435;1281.7244292476976;1282.6793301674581;1292.6768307923019;973.1193270090579;991.4695637833725;996.2030375699411;1005.3515482513737;1017.1201910347496;5618.943810561842;5659.717014149276;5786.3056433228285;5850.063989761617;5869.8239052828485;422.67031715262146;441.61579425899674;452.7849271595966;462.3711752017254;463.38601353207133;4124.175164967019;4152.6055024772595;4161.188568229166;4163.91672166555;4185.602367775056;10189.796204076025;10620.150387968944;10625.256232063835;10627;10637.095846853104;10498.527566259536;10506.947465262778;10509.159267258601;10511.737206569896;10518.053375196268;7681.0782706074515;8008.399370047387;8026.719064832703;8104;8106.148854370324;5599.468050535193;5750.25246717924;5782.421757824184;5941.257342832146;5972.044472884318;9356.391834530734;9387.859182112137;9817.410955342702;9879.160271377013;9896.703098907044;9440.716778496657;9576.425414475154;9623.663171788954;9655;9932.751681208028;7019.157701075904;7468.477206595486;7623.428242881917;7760;7770.689172433039;8506.78733031679;8626.309895208493;8629.309655227442;8630.870536941826;8632.438891472286;7481.50287290513;7495.063117110361;7610.6194690266375;7697.538147711155;7795.532268063935;7314.4148468123485;7380.18817930038;7446.813829654302;7452.06849143857;7689.231076892266;6620.503462240447;6732.596044237362;6733.6296503692765;6735.090762746966;6738.494612903954;5069.569086627678;5118.104331742004;5231.084560202025;5319.654222475527;5397.136458166831;5192.3769147701005;5354.866128346823;5367.087595108763;5379.381371142213;5430.13117901134 21:47:48 INFO - PROCESS | 4716 | |4;prototype.html;306.6396983544335;322.67732267732265;324.62181558484366;327.1184158692324;329.11796385686364;5100.489951004907;5242.711650859202;5255.448177941318;5274.630775845697;5287.259783630295;5204.947950520494;5225.712585807779;5226.7647955841985;5235.921461178076;5261.736913154346;4139.192857392794;4152.1280531088505;4164.354525048622;4164.541900390955;4164.791760411982;4041.979790101045;4141.606547378009;4174.269502837006;4183.079722461053;4183.602557757008;4095.89760255993;4109.6506796922295;4110.979445102786;4121.9587804121875;4134.37984302354;4084.530279017921;4091.590840915914;4120.19656167049;4137.275976704095;4145.2952997990415;3611.963880361215;3619.438986957026;3619.963800361989;3628.546431696038;3683.907902302437;4845.394325709286;5068.670536415121;5074.822381216677;5109.846704598831;5180.342290467052;4599.264117741145;4623.9768801156115;4634;4636.744979026153;4641.628669706449;5361.865953351158;5394.433584473652;5403.648762830443;5433.483819037185;5447.237386765816;3792.033031576958;3831.272058308912;3855.691544676419;3863.961360386388;3864.439656249828;5388.380336261341;5492.368377636543;5583.776648934037;5605.887882242373;5628.971855140739;3948.447217389568;3954.635650700504;3955.564887862333;3960.8019599019935;3965.1871366369824;3838.9232215355537;3893.7079719021185;3991.3015222335976;4007.73949693269;4016.9756712038243;4557.749323787223;4561.703489273186;4567.885802854888;4579.404677391918;4605.102005109038;5466.289382380265;5472.863178420492;5476.808311709071;5489.807856725075;5494.928488944697;1350.5205651993401;1368.7330970460864;1369.301656155368;1376.2086800089987;1379.0555317002977;867.1718508824058;869.7912500999714;877.9385443018928;880.247388482838;880.9823803523893;4771.498992605796;4775.761211939389;4799.968006878468;4819.879503012453;4832.34763306949;4926.5073492650445;4953.23224900141;4961.355023846877;4961.70229786214;5035.874103147377;4525.732794817456;4727.905441891143;4731.556875153043;4743.837759748883;4746.8101275948575;2620.4366061296914;2621.973780262211;2638.379980704571;2645.9206223813317;2657.4020845310038;5156.9742151289;5182.585393168538;5183.3261675981885;5191.402988656278;5247.711375874287;4852.975735121302;4983.426905905795;5017.648764586444;5076.517730815566;5103.8468845934685;5148.944466384382;5151.5787579837415;5157.149070403417;5173.198154286091;5226.902350506436;4892.315075889307;4957.479464656303;4999.825006124768;5032.547070763649;5078.695278283315;4939.481354457874;5216.608754343363;5308.522232999048;5458.590605704668;5492.80775172867;5744.712764361849;5775.162601422854;5813.906993482427;5864.589478736618;5891.410858914074;4022.9396559052225;4048.6558642514537;4054.79726013704;4059.65492933104;4062.857799976987;5402.621816472966;5516.503514683698;5527.364353099445;5544.362398324244;5553.389127195925;5399.623096110467;5418.891622167614;5440.646357986719;5462.481064298885;5498.257735205696;5220.895582088413;5449.509544141047;5471.50756431923;5480.972595137158;5514.503694667499;5367.382750983531;5390.784368625368;5394.433584473573;5413.458654134555;5421.512063914109;5490.286262785812;5512.503874651301;5552.139418390156;5597;5609.691466969356;5306.602004849729;5312.574994000393;5326.307580014573;5367.651102678314;5489.725513724377 21:47:48 INFO - PROCESS | 4716 | |5;yui.html;736.6132780290347;770.9306162445379;774.0479210570998;841.9663213471464;881.1364862434809;15600.531984040483;16694.499165025038;16695.165241737912;16704.49886503404;16713.24790384434;14406.423743050265;14653.706925861476;14669.413223471049;14767.187804670739;14769.556913292592;667.159379182229;797.5453991225004;815.9428839981218;818.8239528501371;820.8768684697284;816.8162163513192;829.601791140253;829.9253067223949;830.248624994378;830.4643504939311;886.5700135434341;888.995555022224;889.0220757167097;890.1143362354467;890.4078787606234;709.2871663977695;853.8889944307263;876.0494863073573;885.349268287808;885.5173930208052;641.5893827950116;642.6850843086901;643.337362516604;643.3566433566433;643.4434214404553;14851.183184924825;14896.851031489763;14909.627259318495;14971.925140374336;14996.550103496913;14329.35517901697;15035.849641503664;15418.768718469231;15505.922470387688;15508.45720399792;13103.803442948363;13210.801837972438;13284.601461956157;13337.733245335136;14061.437542498288;3818.961810381916;4486.439195100613;4551.499335073172;4599.482170883601;4732.408448943882;69723.30276697219;77748.61125694391;78191.23505976096;79606.60196699038;81748.18251817465;11473.196876218752;11509.999999999833;11611.129165312635;11742.765144697229;11843.170978031456;21132;21133.68299475509;21269.574608507744;21316.680249796267;21378.465538361666;8639.179277968582;8683.2185103341;8713.956430217808;8730.345224108218;8829.911700882909;4945.159322915113;5047.52048555394;5056.140456122468;5075.619328550373;5182;3460.532828068179;3587.587427445825;3587.982060089683;3590.4075827488173;3591.730620203495;608.4250383387745;612.6967151260066;627.9277883043509;629.6442509981937;630.6562923206864;521.4472658981416;667.6294656465708;744.8845428958521;760.6729106484154;774.8798936164903;9765.365251258645;10091.243156763125;10138.949305253427;10177.346937756376;10332.690019299433;671.3454381977533;679.7450955891541;741.9406447484188;753.4236309223448;758.8675820943057;5053.570446512014;5069.239614057921;5106.642535022587;5141.562967147835;5154.53609175176;71703;85649;91761;108110;131550;63063.369366306666;64014.479203644245;71684;103309;106783;55306;56396.154057689164;82932.58533707293;95878;98632.50683746679;9232.861507077534;9403.247740180906;9448.48033358172;9486.098820612031;9536.666216682383;53277;53649.195262070316;54194;54628.63428414321;54813.725931370085;78731;82656.58671706603;83477.49567512985;83519.5824020876;84802.57598711966;12278.263304201777;12580.370981450707;12708.745825083632;12813.23120612729;12955.093143479866;14549.781753273497;14556.781648275073;14807.777883331542;15487.612809679398;15640;13860.237686927316;14011.509597164051;14056.367463463965;14156.646083847987;14199.64500887486;13476.730465390834;13618.795718064039;13869.375878085308;14012.929935350257;14033.1580105194;13384;13547.119437236548;13626.731703416539;13683.726325473235;14397.496087636882;10908.400037997986;11023.393713345522;11090.778184436427;11343.319400835646;11646.94176529112;10370.792584148425;10933.640214684456;10953.137966545866;10970.890291096986;11026.283291586022 21:47:48 INFO - PROCESS | 4716 | __end_tp_report 21:47:48 INFO - PROCESS | 4716 | __start_cc_report 21:47:48 INFO - PROCESS | 4716 | _x_x_mozilla_cycle_collect,955 21:47:48 INFO - PROCESS | 4716 | __end_cc_report 21:47:48 INFO - PROCESS | 4716 | __startTimestamp1470718068231__endTimestamp 21:47:48 INFO - PROCESS | 4716 | 21:47:48 INFO - PROCESS | 4716 | ------- Summary: start ------- 21:47:48 INFO - PROCESS | 4716 | Number of tests: 6 21:47:48 INFO - PROCESS | 4716 | 21:47:48 INFO - PROCESS | 4716 | [#0] dojo.html Cycles:165 Average:5112.31 Median:5304.89 stddev:564.18 (10.6%) stddev-sans-first:540.72 21:47:48 INFO - PROCESS | 4716 | Values: 2987.4 2989.9 3016.2 3024.4 3049.4 4986.5 5056.8 5105.8 5167.0 5222.3 5139.6 5147.5 5169.3 5177.9 5226.7 4650.7 4712.3 4745.2 4754.0 4887.1 4852.3 4855.9 4866.4 4876.0 4897.9 4636.7 4672.7 4737.4 4782.6 4788.2 4609.9 4627.0 4638.2 4682.0 4708.0 4607.1 4671.3 4671.8 4698.6 4734.1 4994.6 5052.5 5125.8 5136.5 5163.9 5424.8 5458.3 5467.2 5513.4 5517.7 5509.9 5547.5 5550.6 5551.5 5559.0 4980.6 5100.3 5121.9 5149.0 5174.7 5644.1 5645.5 5682.0 5690.3 5699.7 5422.3 5432.5 5480.7 5485.4 5490.0 5346.8 5377.3 5438.2 5516.7 5574.7 5427.2 5436.4 5452.9 5483.8 5485.5 5417.1 5417.1 5431.0 5445.2 5490.8 4152.6 4186.2 4214.6 4235.9 4237.3 3751.4 3763.7 3798.6 3801.2 3811.4 5103.6 5126.8 5136.7 5182.0 5185.7 5364.5 5364.8 5380.7 5439.1 5479.7 5277.4 5281.9 5296.4 5307.3 5312.8 4744.2 4760.7 4760.9 4773.1 4806.9 5596.6 5625.1 5753.0 5761.5 5772.7 5424.3 5468.5 5480.2 5505.1 5524.8 5664.5 5681.1 5686.1 5694.1 5732.1 5237.7 5304.9 5364.7 5371.6 5383.2 5420.9 5508.3 5510.4 5530.9 5541.3 5321.0 5377.3 5378.2 5380.4 5382.8 5271.8 5315.5 5377.9 5414.7 5419.9 5239.4 5291.4 5306.5 5335.2 5339.5 5387.4 5396.0 5440.3 5446.9 5469.1 5181.6 5270.9 5273.4 5288.2 5300.2 21:47:48 INFO - PROCESS | 4716 | 21:47:48 INFO - PROCESS | 4716 | [#1] ext.html Cycles:180 Average:60743.92 Median:37391.85 stddev:118813.19 (317.8%) stddev-sans-first:119060.62 21:47:48 INFO - PROCESS | 4716 | Values: 578.8 592.8 593.0 594.1 603.8 26818.2 42526.1 43974.6 44070.6 48887.3 45238.8 45761.0 45793.8 45822.5 45915.8 7912.6 8870.8 9009.1 9016.7 9024.2 10317.8 10688.5 10718.9 11105.2 11109.1 10793.4 10794.7 10897.6 10902.5 10904.8 10740.6 10800.8 10811.4 10825.1 10828.3 10504.8 10678.6 10709.9 10716.3 10720.8 42238.6 42349.6 42450.4 42548.8 42570.0 3400.3 3452.5 3893.7 4224.4 4394.9 32779.6 32975.3 33523.7 33550.5 33809.5 4326.5 4340.4 4424.9 4488.8 4557.4 353337.0 357817.0 358540.0 358702.0 360374.0 59432.7 59698.0 59748.0 59758.0 59851.4 62484.7 62523.4 62531.4 62623.0 62631.7 16812.4 17144.7 17193.6 17196.4 17196.7 11847.4 12945.2 13105.7 13226.1 13227.9 17270.1 17481.6 17488.8 17499.2 17731.8 6461.7 6626.8 6679.7 6701.9 6808.6 983.3 1082.9 1105.0 1138.6 1157.1 32175.0 32545.0 32726.2 32857.0 32968.7 1070.9 1091.4 1128.0 1131.3 1146.9 16560.3 17403.0 17444.5 17449.8 17540.9 539033.0 554659.0 555457.0 555794.0 556423.0 58974.0 59199.4 59205.7 59221.7 59238.7 1201.1 1283.6 1331.0 1349.2 1350.4 32150.2 33905.3 33951.5 34295.0 34349.1 49953.5 50698.0 50715.7 50745.5 50821.2 411940.0 412224.0 412592.0 413726.0 413904.0 28145.6 28541.9 28597.1 30201.2 30408.4 47385.8 47794.3 47973.0 48118.3 48361.8 40489.0 40621.8 41494.0 42558.0 42597.6 40727.8 41199.6 41550.0 41981.4 42311.0 41109.0 41948.4 41996.6 41997.6 42102.2 34964.1 37028.0 37142.4 37147.3 37636.4 28112.0 28144.4 28186.9 28355.7 28407.7 21:47:48 INFO - PROCESS | 4716 | 21:47:48 INFO - PROCESS | 4716 | [#2] jquery.html Cycles:180 Average:22496.30 Median:381.74 stddev:51248.08 (13424.9%) stddev-sans-first:51364.89 21:47:48 INFO - PROCESS | 4716 | Values: 360.2 372.3 391.2 392.8 409.0 17322.5 19395.9 19433.3 19557.9 20226.6 19241.1 19713.5 19819.3 20119.9 20190.4 5566.1 5734.2 5778.7 5998.1 5998.3 3933.4 5900.2 5937.6 5945.7 5946.3 6148.7 6414.0 6438.0 6519.8 6523.6 5823.5 5848.8 5901.1 6488.7 6507.2 5750.9 5752.3 5756.3 5760.4 5761.3 13799.6 13844.5 14122.4 14171.6 14272.7 2363.1 2402.2 2671.2 2918.6 2932.6 9931.9 9963.7 9988.4 10457.5 10487.1 1851.4 1914.8 1916.0 1917.7 1945.7 76884.0 81911.6 83714.0 85098.1 85510.0 10852.0 10966.3 11150.2 11276.3 11286.4 15472.0 15499.8 15500.1 15506.3 15524.3 8078.8 8194.9 8243.3 8332.3 8358.7 7881.9 7896.2 8179.0 8221.4 8223.9 4637.2 4705.3 4729.5 4732.8 4903.6 1867.8 1922.7 1939.3 1962.2 1984.4 859.1 953.2 1019.5 1026.9 1071.2 19320.5 20220.3 20226.4 20344.3 20556.7 964.8 973.7 980.3 983.8 1007.8 10504.5 11310.2 11340.5 11357.3 11372.8 175384.0 175659.0 175663.0 391363.0 457460.0 56010.2 56478.4 65127.0 85719.6 90887.5 945.6 947.5 974.3 980.1 980.5 17508.9 18298.2 18491.0 18586.3 18601.5 43016.6 43136.8 43441.0 43977.0 44599.8 60648.7 61351.0 61420.0 61869.1 62749.4 8265.8 8328.0 8503.5 8600.9 8829.8 12097.6 13307.4 13326.9 13401.3 13559.3 11426.2 11550.3 11803.5 11894.0 11943.5 11121.6 11263.4 11422.8 11626.4 11831.5 11419.3 11577.0 11584.7 11952.9 12025.0 8082.9 8236.3 8250.6 8356.3 8634.7 10836.6 11245.9 11267.2 11324.7 11668.6 21:47:48 INFO - PROCESS | 4716 | 21:47:48 INFO - PROCESS | 4716 | [#3] mootools.html Cycles:180 Average:5636.32 Median:4667.53 stddev:3168.94 (67.9%) stddev-sans-first:3162.22 21:47:48 INFO - PROCESS | 4716 | Values: 1451.1 1523.1 1647.3 1686.2 1782.6 8100.2 8636.6 8650.9 8652.9 8662.6 7537.6 8015.1 8319.0 8367.3 8591.1 2294.5 2341.4 2344.8 2350.6 2351.8 2252.0 2275.3 2276.3 2281.8 2308.7 2278.8 2284.0 2290.5 2324.8 2325.9 2224.5 2235.2 2236.5 2245.3 2248.4 2217.3 2226.5 2231.9 2233.0 2234.9 8364.5 8425.8 8426.3 8432.9 8433.5 5005.0 5130.9 5141.5 5155.4 5230.2 3211.6 3243.7 3403.4 3416.4 3419.1 1090.1 1098.5 1101.6 1108.1 1133.3 11515.4 11634.5 11717.6 11720.5 11725.2 4364.0 4405.8 4578.8 4581.8 4582.7 10046.6 10055.1 10069.6 10072.4 10090.3 5181.6 5193.1 5199.5 5201.5 5206.0 4220.2 4304.0 4577.2 4649.8 4685.3 3896.3 3945.1 4057.3 4061.8 4061.9 1258.3 1280.5 1281.7 1282.7 1292.7 973.1 991.5 996.2 1005.4 1017.1 5618.9 5659.7 5786.3 5850.1 5869.8 422.7 441.6 452.8 462.4 463.4 4124.2 4152.6 4161.2 4163.9 4185.6 10189.8 10620.2 10625.3 10627.0 10637.1 10498.5 10506.9 10509.2 10511.7 10518.1 7681.1 8008.4 8026.7 8104.0 8106.1 5599.5 5750.3 5782.4 5941.3 5972.0 9356.4 9387.9 9817.4 9879.2 9896.7 9440.7 9576.4 9623.7 9655.0 9932.8 7019.2 7468.5 7623.4 7760.0 7770.7 8506.8 8626.3 8629.3 8630.9 8632.4 7481.5 7495.1 7610.6 7697.5 7795.5 7314.4 7380.2 7446.8 7452.1 7689.2 6620.5 6732.6 6733.6 6735.1 6738.5 5069.6 5118.1 5231.1 5319.7 5397.1 5192.4 5354.9 5367.1 5379.4 5430.1 21:47:48 INFO - PROCESS | 4716 | 21:47:48 INFO - PROCESS | 4716 | [#4] prototype.html Cycles:180 Average:4470.33 Median:5008.74 stddev:1297.91 (25.9%) stddev-sans-first:1263.36 21:47:48 INFO - PROCESS | 4716 | Values: 306.6 322.7 324.6 327.1 329.1 5100.5 5242.7 5255.4 5274.6 5287.3 5204.9 5225.7 5226.8 5235.9 5261.7 4139.2 4152.1 4164.4 4164.5 4164.8 4042.0 4141.6 4174.3 4183.1 4183.6 4095.9 4109.7 4111.0 4122.0 4134.4 4084.5 4091.6 4120.2 4137.3 4145.3 3612.0 3619.4 3620.0 3628.5 3683.9 4845.4 5068.7 5074.8 5109.8 5180.3 4599.3 4624.0 4634.0 4636.7 4641.6 5361.9 5394.4 5403.6 5433.5 5447.2 3792.0 3831.3 3855.7 3864.0 3864.4 5388.4 5492.4 5583.8 5605.9 5629.0 3948.4 3954.6 3955.6 3960.8 3965.2 3838.9 3893.7 3991.3 4007.7 4017.0 4557.7 4561.7 4567.9 4579.4 4605.1 5466.3 5472.9 5476.8 5489.8 5494.9 1350.5 1368.7 1369.3 1376.2 1379.1 867.2 869.8 877.9 880.2 881.0 4771.5 4775.8 4800.0 4819.9 4832.3 4926.5 4953.2 4961.4 4961.7 5035.9 4525.7 4727.9 4731.6 4743.8 4746.8 2620.4 2622.0 2638.4 2645.9 2657.4 5157.0 5182.6 5183.3 5191.4 5247.7 4853.0 4983.4 5017.6 5076.5 5103.8 5148.9 5151.6 5157.1 5173.2 5226.9 4892.3 4957.5 4999.8 5032.5 5078.7 4939.5 5216.6 5308.5 5458.6 5492.8 5744.7 5775.2 5813.9 5864.6 5891.4 4022.9 4048.7 4054.8 4059.7 4062.9 5402.6 5516.5 5527.4 5544.4 5553.4 5399.6 5418.9 5440.6 5462.5 5498.3 5220.9 5449.5 5471.5 5481.0 5514.5 5367.4 5390.8 5394.4 5413.5 5421.5 5490.3 5512.5 5552.1 5597.0 5609.7 5306.6 5312.6 5326.3 5367.7 5489.7 21:47:48 INFO - PROCESS | 4716 | 21:47:48 INFO - PROCESS | 4716 | [#5] yui.html Cycles:180 Average:20098.84 Median:5054.86 stddev:27935.85 (552.7%) stddev-sans-first:27976.38 21:47:48 INFO - PROCESS | 4716 | Values: 736.6 770.9 774.0 842.0 881.1 15600.5 16694.5 16695.2 16704.5 16713.2 14406.4 14653.7 14669.4 14767.2 14769.6 667.2 797.5 815.9 818.8 820.9 816.8 829.6 829.9 830.2 830.5 886.6 889.0 889.0 890.1 890.4 709.3 853.9 876.0 885.3 885.5 641.6 642.7 643.3 643.4 643.4 14851.2 14896.9 14909.6 14971.9 14996.6 14329.4 15035.8 15418.8 15505.9 15508.5 13103.8 13210.8 13284.6 13337.7 14061.4 3819.0 4486.4 4551.5 4599.5 4732.4 69723.3 77748.6 78191.2 79606.6 81748.2 11473.2 11510.0 11611.1 11742.8 11843.2 21132.0 21133.7 21269.6 21316.7 21378.5 8639.2 8683.2 8714.0 8730.3 8829.9 4945.2 5047.5 5056.1 5075.6 5182.0 3460.5 3587.6 3588.0 3590.4 3591.7 608.4 612.7 627.9 629.6 630.7 521.4 667.6 744.9 760.7 774.9 9765.4 10091.2 10138.9 10177.3 10332.7 671.3 679.7 741.9 753.4 758.9 5053.6 5069.2 5106.6 5141.6 5154.5 71703.0 85649.0 91761.0 108110.0 131550.0 63063.4 64014.5 71684.0 103309.0 106783.0 55306.0 56396.2 82932.6 95878.0 98632.5 9232.9 9403.2 9448.5 9486.1 9536.7 53277.0 53649.2 54194.0 54628.6 54813.7 78731.0 82656.6 83477.5 83519.6 84802.6 12278.3 12580.4 12708.7 12813.2 12955.1 14549.8 14556.8 14807.8 15487.6 15640.0 13860.2 14011.5 14056.4 14156.6 14199.6 13476.7 13618.8 13869.4 14012.9 14033.2 13384.0 13547.1 13626.7 13683.7 14397.5 10908.4 11023.4 11090.8 11343.3 11646.9 10370.8 10933.6 10953.1 10970.9 11026.3 21:47:48 INFO - PROCESS | 4716 | -------- Summary: end -------- 21:47:48 INFO - PROCESS | 4716 | 21:47:48 INFO - TEST-INFO | 4716: exit 0 21:47:48 INFO - TEST-OK | dromaeo_css | took 1113858ms 21:47:48 INFO - TEST-START | kraken 21:47:48 INFO - operating with platform_type : linux_ 21:47:48 INFO - Initialising browser for kraken test... 21:47:48 INFO - TEST-INFO | started process 4911 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1vsY_r/profile http://localhost:58204/getInfo.html) 21:47:52 INFO - PROCESS | 4911 | __metrics Screen width/height:1600/1200 21:47:52 INFO - PROCESS | 4911 | colorDepth:24 21:47:52 INFO - PROCESS | 4911 | Browser inner width/height: 1024/697 21:47:52 INFO - PROCESS | 4911 | __metrics 21:47:53 INFO - PROCESS | 4911 | 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" 21:47:53 INFO - PROCESS | 4911 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:47:54 INFO - TEST-INFO | 4911: exit 0 21:47:54 INFO - Browser initialized. 21:47:54 INFO - Running cycle 1/1 for kraken test... 21:47:54 INFO - TEST-INFO | started process 4992 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1vsY_r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:48:05 INFO - PROCESS | 4992 | RSS: Main: 156655616 21:48:05 INFO - PROCESS | 4992 | 21:48:08 INFO - PROCESS | 4992 | Cycle 1(1): loaded http://localhost:58204/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58204/tests/kraken/driver.html?testName=audio-beat-detection) 21:48:09 INFO - PROCESS | 4992 | RSS: Main: 212578304 21:48:09 INFO - PROCESS | 4992 | 21:48:11 INFO - PROCESS | 4992 | Cycle 1(1): loaded http://localhost:58204/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58204/tests/kraken/driver.html?testName=audio-dft) 21:48:12 INFO - PROCESS | 4992 | RSS: Main: 192327680 21:48:12 INFO - PROCESS | 4992 | 21:48:15 INFO - PROCESS | 4992 | Cycle 1(1): loaded http://localhost:58204/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58204/tests/kraken/driver.html?testName=audio-fft) 21:48:16 INFO - PROCESS | 4992 | RSS: Main: 269914112 21:48:16 INFO - PROCESS | 4992 | 21:48:18 INFO - PROCESS | 4992 | Cycle 1(1): loaded http://localhost:58204/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58204/tests/kraken/driver.html?testName=audio-oscillator) 21:48:19 INFO - PROCESS | 4992 | RSS: Main: 179625984 21:48:19 INFO - PROCESS | 4992 | 21:48:21 INFO - PROCESS | 4992 | Cycle 1(1): loaded http://localhost:58204/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58204/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:48:21 INFO - PROCESS | 4992 | RSS: Main: 184295424 21:48:21 INFO - PROCESS | 4992 | 21:48:24 INFO - PROCESS | 4992 | Cycle 1(1): loaded http://localhost:58204/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58204/tests/kraken/driver.html?testName=imaging-darkroom) 21:48:25 INFO - PROCESS | 4992 | RSS: Main: 220803072 21:48:25 INFO - PROCESS | 4992 | 21:48:28 INFO - PROCESS | 4992 | Cycle 1(1): loaded http://localhost:58204/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58204/tests/kraken/driver.html?testName=imaging-desaturate) 21:48:29 INFO - PROCESS | 4992 | RSS: Main: 219406336 21:48:29 INFO - PROCESS | 4992 | 21:48:32 INFO - PROCESS | 4992 | Cycle 1(1): loaded http://localhost:58204/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58204/tests/kraken/driver.html?testName=json-parse-financial) 21:48:32 INFO - PROCESS | 4992 | RSS: Main: 220286976 21:48:32 INFO - PROCESS | 4992 | 21:48:34 INFO - PROCESS | 4992 | Cycle 1(1): loaded http://localhost:58204/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58204/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:48:34 INFO - PROCESS | 4992 | RSS: Main: 183963648 21:48:34 INFO - PROCESS | 4992 | 21:48:36 INFO - PROCESS | 4992 | Cycle 1(1): loaded http://localhost:58204/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58204/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:48:37 INFO - PROCESS | 4992 | RSS: Main: 189628416 21:48:37 INFO - PROCESS | 4992 | 21:48:39 INFO - PROCESS | 4992 | Cycle 1(1): loaded http://localhost:58204/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58204/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:48:39 INFO - PROCESS | 4992 | RSS: Main: 194584576 21:48:39 INFO - PROCESS | 4992 | 21:48:42 INFO - PROCESS | 4992 | Cycle 1(1): loaded http://localhost:58204/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58204/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:48:42 INFO - PROCESS | 4992 | RSS: Main: 189558784 21:48:42 INFO - PROCESS | 4992 | 21:48:45 INFO - PROCESS | 4992 | Cycle 1(1): loaded http://localhost:58204/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58204/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:48:46 INFO - PROCESS | 4992 | RSS: Main: 192446464 21:48:46 INFO - PROCESS | 4992 | 21:48:47 INFO - PROCESS | 4992 | Cycle 1(1): loaded http://localhost:58204/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58204/tests/kraken/driver.html?testName=ai-astar) 21:48:48 INFO - PROCESS | 4992 | RSS: Main: 242618368 21:48:48 INFO - PROCESS | 4992 | 21:48:48 INFO - PROCESS | 4992 | __start_tp_report 21:48:48 INFO - PROCESS | 4992 | _x_x_mozilla_page_load 21:48:48 INFO - PROCESS | 4992 | _x_x_mozilla_page_load_details 21:48:48 INFO - PROCESS | 4992 | |i|pagename|runs| 21:48:48 INFO - PROCESS | 4992 | |0;ai-astar;102;110;111;110;110;112;111;110;111;110 21:48:48 INFO - PROCESS | 4992 | |1;audio-beat-detection;140;116;119;180;118;124;145;118;160;118 21:48:48 INFO - PROCESS | 4992 | |2;audio-dft;160;160;159;161;162;161;161;162;161;159 21:48:48 INFO - PROCESS | 4992 | |3;audio-fft;74;73;73;127;75;76;75;76;120;74 21:48:48 INFO - PROCESS | 4992 | |4;audio-oscillator;85;84;121;85;85;85;128;84;85;120 21:48:48 INFO - PROCESS | 4992 | |5;imaging-gaussian-blur;117;117;117;116;117;118;117;116;117;117 21:48:48 INFO - PROCESS | 4992 | |6;imaging-darkroom;146;145;145;145;145;145;145;145;145;146 21:48:48 INFO - PROCESS | 4992 | |7;imaging-desaturate;95;95;95;95;95;95;94;95;95;95 21:48:48 INFO - PROCESS | 4992 | |8;json-parse-financial;51;52;52;51;53;51;51;52;51;52 21:48:48 INFO - PROCESS | 4992 | |9;json-stringify-tinderbox;60;60;61;60;60;67;60;60;60;61 21:48:48 INFO - PROCESS | 4992 | |10;stanford-crypto-aes;77;75;75;75;76;76;76;74;75;79 21:48:48 INFO - PROCESS | 4992 | |11;stanford-crypto-ccm;128;123;121;122;120;122;120;118;138;129 21:48:48 INFO - PROCESS | 4992 | |12;stanford-crypto-pbkdf2;159;163;162;157;155;159;159;160;158;157 21:48:48 INFO - PROCESS | 4992 | |13;stanford-crypto-sha256-iterative;63;62;63;62;63;61;63;61;62;61 21:48:48 INFO - PROCESS | 4992 | __end_tp_report 21:48:48 INFO - PROCESS | 4992 | __start_cc_report 21:48:48 INFO - PROCESS | 4992 | _x_x_mozilla_cycle_collect,1636 21:48:48 INFO - PROCESS | 4992 | __end_cc_report 21:48:48 INFO - PROCESS | 4992 | __startTimestamp1470718128173__endTimestamp 21:48:48 INFO - PROCESS | 4992 | 21:48:48 INFO - PROCESS | 4992 | ------- Summary: start ------- 21:48:48 INFO - PROCESS | 4992 | Number of tests: 14 21:48:48 INFO - PROCESS | 4992 | 21:48:48 INFO - PROCESS | 4992 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.50 stddev:2.79 (2.5%) stddev-sans-first:0.73 21:48:48 INFO - PROCESS | 4992 | Values: 102.0 110.0 111.0 110.0 110.0 112.0 111.0 110.0 111.0 110.0 21:48:48 INFO - PROCESS | 4992 | 21:48:48 INFO - PROCESS | 4992 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:132.00 stddev:22.02 (16.7%) stddev-sans-first:23.25 21:48:48 INFO - PROCESS | 4992 | Values: 140.0 116.0 119.0 180.0 118.0 124.0 145.0 118.0 160.0 118.0 21:48:48 INFO - PROCESS | 4992 | 21:48:48 INFO - PROCESS | 4992 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.07 (0.7%) stddev-sans-first:1.12 21:48:48 INFO - PROCESS | 4992 | Values: 160.0 160.0 159.0 161.0 162.0 161.0 161.0 162.0 161.0 159.0 21:48:48 INFO - PROCESS | 4992 | 21:48:48 INFO - PROCESS | 4992 | [#3] audio-fft Cycles:10 Average:84.30 Median:74.50 stddev:20.75 (27.9%) stddev-sans-first:21.67 21:48:48 INFO - PROCESS | 4992 | Values: 74.0 73.0 73.0 127.0 75.0 76.0 75.0 76.0 120.0 74.0 21:48:48 INFO - PROCESS | 4992 | 21:48:48 INFO - PROCESS | 4992 | [#4] audio-oscillator Cycles:10 Average:96.20 Median:85.00 stddev:18.61 (21.9%) stddev-sans-first:19.29 21:48:48 INFO - PROCESS | 4992 | Values: 85.0 84.0 121.0 85.0 85.0 85.0 128.0 84.0 85.0 120.0 21:48:48 INFO - PROCESS | 4992 | 21:48:48 INFO - PROCESS | 4992 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 21:48:48 INFO - PROCESS | 4992 | Values: 117.0 117.0 117.0 116.0 117.0 118.0 117.0 116.0 117.0 117.0 21:48:48 INFO - PROCESS | 4992 | 21:48:48 INFO - PROCESS | 4992 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 21:48:48 INFO - PROCESS | 4992 | Values: 146.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 146.0 21:48:48 INFO - PROCESS | 4992 | 21:48:48 INFO - PROCESS | 4992 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 21:48:48 INFO - PROCESS | 4992 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 21:48:48 INFO - PROCESS | 4992 | 21:48:48 INFO - PROCESS | 4992 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:52.00 stddev:0.70 (1.3%) stddev-sans-first:0.71 21:48:48 INFO - PROCESS | 4992 | Values: 51.0 52.0 52.0 51.0 53.0 51.0 51.0 52.0 51.0 52.0 21:48:48 INFO - PROCESS | 4992 | 21:48:48 INFO - PROCESS | 4992 | [#9] json-stringify-tinderbox Cycles:10 Average:60.90 Median:60.00 stddev:2.18 (3.6%) stddev-sans-first:2.29 21:48:48 INFO - PROCESS | 4992 | Values: 60.0 60.0 61.0 60.0 60.0 67.0 60.0 60.0 60.0 61.0 21:48:48 INFO - PROCESS | 4992 | 21:48:48 INFO - PROCESS | 4992 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:76.00 stddev:1.40 (1.8%) stddev-sans-first:1.41 21:48:48 INFO - PROCESS | 4992 | Values: 77.0 75.0 75.0 75.0 76.0 76.0 76.0 74.0 75.0 79.0 21:48:48 INFO - PROCESS | 4992 | 21:48:48 INFO - PROCESS | 4992 | [#11] stanford-crypto-ccm Cycles:10 Average:124.10 Median:122.50 stddev:5.99 (4.9%) stddev-sans-first:6.18 21:48:48 INFO - PROCESS | 4992 | Values: 128.0 123.0 121.0 122.0 120.0 122.0 120.0 118.0 138.0 129.0 21:48:48 INFO - PROCESS | 4992 | 21:48:48 INFO - PROCESS | 4992 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.90 Median:159.00 stddev:2.38 (1.5%) stddev-sans-first:2.52 21:48:48 INFO - PROCESS | 4992 | Values: 159.0 163.0 162.0 157.0 155.0 159.0 159.0 160.0 158.0 157.0 21:48:48 INFO - PROCESS | 4992 | 21:48:48 INFO - PROCESS | 4992 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.50 stddev:0.88 (1.4%) stddev-sans-first:0.87 21:48:48 INFO - PROCESS | 4992 | Values: 63.0 62.0 63.0 62.0 63.0 61.0 63.0 61.0 62.0 61.0 21:48:48 INFO - PROCESS | 4992 | -------- Summary: end -------- 21:48:48 INFO - PROCESS | 4992 | 21:48:49 INFO - TEST-INFO | 4992: exit 0 21:48:49 INFO - TEST-OK | kraken | took 60210ms 21:48:49 INFO - SUITE-END | took 1174s 21:48:49 INFO - Completed test suite (00:19:34) 21:48:49 INFO - javascript benchmark 21:48:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2987.3875855449633, 2989.850507474626, 3016.1705530978984, 3024.395120975805, 3049.3748781499794, 4986.45149033607, 5056.772445239963, 5105.795768169273, 5167.044096842087, 5222.268882356469, 5139.614528910328, 5147.459516750743, 5169.250458683498, 5177.896442071156, 5226.686398816064, 4650.651201159918, 4712.316714076457, 4745.193317136078, 4754.049190161964, 4887.071456423286, 4852.29641701953, 4855.878603034935, 4866.440359358684, 4876.0247950409885, 4897.902041959159, 4636.744979026153, 4672.742999135046, 4737.384140061805, 4782.593479554242, 4788.186008378584, 4609.884752881171, 4626.976865115653, 4638.165130276549, 4681.953180468186, 4707.976460117712, 4607.078584283123, 4671.3226582145735, 4671.836485723017, 4698.600618947359, 4734.100520901018, 4994.55049045584, 5052.545270925598, 5125.794968201268, 5136.5377116059735, 5163.948360516384, 5424.810131645413, 5458.2904222450825, 5467.234587157801, 5513.365962914236, 5517.668939863581, 5509.917351239735, 5547.472990065938, 5550.639208451478, 5551.500364967132, 5558.972205138989, 4980.551750342486, 5100.260462232955, 5121.871953201163, 5148.9742551287, 5174.689518628894, 5644.125160600113, 5645.5483561314995, 5681.971590142106, 5690.317161940594, 5699.715014249271, 5422.267993820863, 5432.456754324536, 5480.671159730429, 5485.424030476823, 5490.011797876421, 5346.8395948121615, 5377.300950876361, 5438.238646589481, 5516.668999860021, 5574.665520068809, 5427.185922111715, 5436.374816896028, 5452.890942181134, 5483.8080667176455, 5485.533729633026, 5417.106177480671, 5417.10617748075, 5431.022415965164, 5445.237666726662, 5490.752916118784, 4152.6055024772595, 4186.1836941796055, 4214.599613036757, 4235.8729238122905, 4237.343211802176, 3751.381022131318, 3763.7177211708686, 3798.582155962842, 3801.2207497463505, 3811.4092315691114, 5103.617228707788, 5126.846194614168, 5136.717480538531, 5182.015417070745, 5185.688858668492, 5364.544013758796, 5364.758585863646, 5380.704061276589, 5439.075357189287, 5479.671219726829, 5277.445868183785, 5281.894362112736, 5296.417394086648, 5307.2569840222395, 5312.840614781563, 4744.1934871072, 4760.666753327234, 4760.857174284777, 4773.11697335994, 4806.927896081562, 5596.636218645856, 5625.09998400254, 5753.0, 5761.53907687384, 5772.7113644317615, 5424.349078110652, 5468.48049435303, 5480.232767412645, 5505.119180931032, 5524.806631767869, 5664.546836253172, 5681.119426488901, 5686.09022556389, 5694.088945768657, 5732.140178973187, 5237.738113094406, 5304.893902122013, 5364.731763411735, 5371.5971302153275, 5383.192521121863, 5420.9186862197885, 5508.283923089973, 5510.448955104457, 5530.944690553043, 5541.251930989265, 5320.973395133155, 5377.27406800077, 5378.19327100938, 5380.43505431924, 5382.838514844561, 5271.841844744664, 5315.521603055744, 5377.865553361197, 5414.729263536886, 5419.864503387289, 5239.397469291081, 5291.4443983382735, 5306.469353064663, 5335.226392173191, 5339.519443250126, 5387.380451248, 5396.0, 5440.29276194108, 5446.891062178814, 5469.124940009579, 5181.611379146504, 5270.947290527199, 5273.393559740679, 5288.153895376721, 5300.2049692546425], "value": 5075.271510598613, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.7655999320274, 592.7865968251429, 592.977114477526, 594.1147689941985, 603.8309273403448, 26818.19545413638, 42526.14947701044, 43974.560254397446, 44070.559294407045, 48887.266690999575, 45238.77380613092, 45761.0, 45793.77103114488, 45822.54177458224, 45915.77042114793, 7912.643931023089, 8870.82258354831, 9009.144131307516, 9016.68441604544, 9024.232940200058, 10317.84523232152, 10688.51901664427, 10718.946405268001, 11105.222634415553, 11109.055730262937, 10793.352398856054, 10794.676159715222, 10897.564097436092, 10902.454877256145, 10904.836427453594, 10740.624078157267, 10800.78398432027, 10811.35131892089, 10825.07986821121, 10828.296160749525, 10504.789904201949, 10678.572857085706, 10709.892901071045, 10716.303440276435, 10720.839187412195, 42238.577614223774, 42349.57650423487, 42450.36324455136, 42548.78725606383, 42570.0, 3400.268942177444, 3452.5166476693284, 3893.7079719020903, 4224.429701990222, 4394.868153955386, 32779.62676809707, 32975.34049319024, 33523.664763352295, 33550.49674254888, 33809.492857607154, 4326.545712700184, 4340.414044104069, 4424.933625995613, 4488.775561221959, 4557.430321209848, 353337.0, 357817.0, 358539.9999999974, 358702.0, 360374.0, 59432.70283648554, 59698.0, 59748.0, 59758.0, 59851.401485984585, 62484.687576562734, 62523.374766252666, 62531.37468625347, 62623.0, 62631.686841566414, 16812.41156559539, 17144.657106857794, 17193.570160745832, 17196.398126065527, 17196.656066878593, 11847.407629618658, 12945.158564693265, 13105.672358191121, 13226.074174807864, 13227.867721322857, 17270.05014724177, 17481.562960925825, 17488.825111748974, 17499.212535435683, 17731.822681773017, 6461.709223084972, 6626.801195964129, 6679.699413526403, 6701.932980670131, 6808.62552559614, 983.2822039910905, 1082.929609575375, 1104.972375690598, 1138.6242539961793, 1157.0974639780984, 32175.0, 32545.0, 32726.181845454055, 32857.0, 32968.70267291033, 1070.8714954205388, 1091.3942761767137, 1127.9774404512027, 1131.2590253384046, 1146.9139814513778, 16560.337586496647, 17403.0, 17444.47666570005, 17449.825381836632, 17540.91229543844, 539033.0, 554659.0, 555457.0, 555794.0, 556423.0, 58974.0, 59199.40800591939, 59205.70397147987, 59221.70389148113, 59238.70380648156, 1201.08117290273, 1283.550757234979, 1331.0, 1349.150035477682, 1350.352569864783, 32150.19624509406, 33905.32189356248, 33951.49072763861, 34295.0, 34349.141271467415, 49953.50046499488, 50698.0, 50715.74642126766, 50745.49254507408, 50821.237681435545, 411940.0, 412224.0, 412592.0, 413726.0, 413904.0, 28145.59272036349, 28541.857290714244, 28597.14208573746, 30201.244968875955, 30408.391832163674, 47385.7630711858, 47794.28308575444, 47973.0, 48118.278225827344, 48361.75819120882, 40489.0, 40621.79689101536, 41494.0, 42558.0, 42597.57402425936, 40727.79636101801, 41199.58800411958, 41550.0, 41981.37027944522, 42311.0, 41109.0, 41948.3707744378, 41996.580034199265, 41997.580024199364, 42102.15795684008, 34964.12589685278, 37028.0, 37142.44286335761, 37147.25705485821, 37636.435453467675, 28112.0, 28144.43711125807, 28186.85906570454, 28355.716442836132, 28407.715922841333], "value": 20703.36794820928, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.23629904602245, 372.29821785933507, 391.18047690089264, 392.75452841973765, 408.97341672791265, 17322.480325590237, 19395.903020484897, 19433.31983380582, 19557.902210488945, 20226.59546809063, 19241.134148963294, 19713.507162320948, 19819.306324278652, 20119.899400503014, 20190.39428817134, 5566.137248726453, 5734.225879506265, 5778.739956701937, 5998.130271110686, 5998.2802063752415, 3933.449317095595, 5900.232969713931, 5937.584369094144, 5945.702714864261, 5946.256717910262, 6148.692565371736, 6414.037894315843, 6438.034294855785, 6519.771807986722, 6523.575967562117, 5823.505002074808, 5848.824535263949, 5901.144334071536, 6488.740450381979, 6507.186601674791, 5750.856228594277, 5752.338481074688, 5756.251687280668, 5760.423957604248, 5761.251037365158, 13799.586012419644, 13844.515441959584, 14122.435102595884, 14171.645708857259, 14272.714545709028, 2363.0902102690416, 2402.243293362585, 2671.238696971354, 2918.6205793246954, 2932.5894374787545, 9931.851022234672, 9963.701088967342, 9988.350757200758, 10457.529411176516, 10487.108595769369, 1851.3705340184267, 1914.7798003229668, 1916.0456334538833, 1917.7027560728109, 1945.6984167454068, 76884.0, 81911.59044204741, 83714.0, 85098.14901850965, 85510.0, 10852.0, 10966.342019478698, 11150.163737719704, 11276.323420594626, 11286.379249141211, 15472.0, 15499.845001550066, 15500.147491887828, 15506.302216400289, 15524.301406436738, 8078.798030049295, 8194.877076843733, 8243.340532757366, 8332.291755200877, 8358.749237522885, 7881.881771773428, 7896.249856263646, 8179.0, 8221.383396245186, 8223.917760822435, 4637.188492013884, 4705.317728929286, 4729.5034021427255, 4732.834350797774, 4903.632227582945, 1867.8225568570963, 1922.6635338815931, 1939.2921583621876, 1962.1758861278297, 1984.4046785964154, 859.1408591408591, 953.2135987810084, 1019.4800651667703, 1026.9229807764448, 1071.1912506057877, 19320.516987075436, 20220.29228977008, 20226.39320820378, 20344.25352577383, 20556.691649625267, 964.8311545479513, 973.6640858903667, 980.2501086668705, 983.8032393521324, 1007.7934023525306, 10504.474776261157, 11310.151738619472, 11340.489677964346, 11357.318560886373, 11372.772544549063, 175384.0, 175659.0, 175663.0, 391363.0, 457460.0, 56010.15984760232, 56478.43521564732, 65127.0, 85719.5714021426, 90887.54556227177, 945.635930166877, 947.4836214263244, 974.3179774158118, 980.1325826643472, 980.5146452506056, 17508.912455437643, 18298.176582054104, 18491.0, 18586.25654973786, 18601.534961625526, 43016.56983430251, 43136.78431607822, 43441.0, 43977.0, 44599.77700111479, 60648.6967565177, 61351.0, 61420.0, 61869.07196392148, 62749.372506274354, 8265.83468330642, 8327.95836020816, 8503.532305723245, 8600.870986935326, 8829.779255518664, 12097.637070887888, 13307.401166947317, 13326.93336533311, 13401.329933503092, 13559.254241016839, 11426.2001659883, 11550.325202845372, 11803.468843902217, 11894.048476121716, 11943.522259109539, 11121.610743624258, 11263.380514071805, 11422.82865757031, 11626.418679066182, 11831.526738930346, 11419.257748246337, 11577.0, 11584.710382240512, 11952.880471195176, 12025.037996960396, 8082.878756818534, 8236.258736713724, 8250.587470626564, 8356.289715374262, 8634.697785577475, 10836.566537338418, 11245.887541124484, 11267.211295209258, 11324.716882078013, 11668.591599293984], "value": 8997.857356029699, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1451.1075688451601, 1523.0709267346917, 1647.34106357457, 1686.1990554486629, 1782.6256486137918, 8100.230478104578, 8636.56817159141, 8650.91349086509, 8652.8702069469, 8662.566871656416, 7537.623118844063, 8015.118336982927, 8319.04331001935, 8367.330613550917, 8591.140885911405, 2294.4837411582384, 2341.4146463384154, 2344.824138189634, 2350.647402889572, 2351.8118550516, 2252.042881775247, 2275.2719129878446, 2276.2943487518837, 2281.840271181018, 2308.7344955330186, 2278.829087818412, 2284.0, 2290.4617414907484, 2324.8256380771427, 2325.9185928492507, 2224.455008522906, 2235.217673814152, 2236.519148383097, 2245.3488488338444, 2248.392933907844, 2217.3347995601416, 2226.5101677630896, 2231.921882734113, 2233.0286325448483, 2234.921777737771, 8364.498130112213, 8425.831483370359, 8426.283765879907, 8432.915670843275, 8433.493990360597, 5004.974975125101, 5130.897382052338, 5141.537261646432, 5155.355580552394, 5230.163173892159, 3211.630662473822, 3243.675632436761, 3403.404404229275, 3416.4192087344964, 3419.076849250688, 1090.1170052257617, 1098.4672433869566, 1101.6419663609279, 1108.0969010256615, 1133.2747041893194, 11515.36665483398, 11634.476448559835, 11717.648470545897, 11720.472578733978, 11725.237859539102, 4364.03618371734, 4405.757683327448, 4578.771061446981, 4581.79381927814, 4582.679212455096, 10046.648367307109, 10055.145312648507, 10069.647562335284, 10072.395656260647, 10090.293679442515, 5181.5854731621375, 5193.1171700810955, 5199.506046925536, 5201.505856943584, 5206.0, 4220.198162349144, 4304.0, 4577.244754615451, 4649.790759415834, 4685.273782563708, 3896.2597106549665, 3945.11234972129, 4057.3102572562143, 4061.756294622332, 4061.89845253871, 1258.3079306381637, 1280.5262053040435, 1281.7244292476976, 1282.6793301674581, 1292.6768307923019, 973.1193270090579, 991.4695637833725, 996.2030375699411, 1005.3515482513737, 1017.1201910347496, 5618.943810561842, 5659.717014149276, 5786.3056433228285, 5850.063989761617, 5869.8239052828485, 422.67031715262146, 441.61579425899674, 452.7849271595966, 462.3711752017254, 463.38601353207133, 4124.175164967019, 4152.6055024772595, 4161.188568229166, 4163.91672166555, 4185.602367775056, 10189.796204076025, 10620.150387968944, 10625.256232063835, 10627.0, 10637.095846853104, 10498.527566259536, 10506.947465262778, 10509.159267258601, 10511.737206569896, 10518.053375196268, 7681.0782706074515, 8008.399370047387, 8026.719064832703, 8104.0, 8106.148854370324, 5599.468050535193, 5750.25246717924, 5782.421757824184, 5941.257342832146, 5972.044472884318, 9356.391834530734, 9387.859182112137, 9817.410955342702, 9879.160271377013, 9896.703098907044, 9440.716778496657, 9576.425414475154, 9623.663171788954, 9655.0, 9932.751681208028, 7019.157701075904, 7468.477206595486, 7623.428242881917, 7760.0, 7770.689172433039, 8506.78733031679, 8626.309895208493, 8629.309655227442, 8630.870536941826, 8632.438891472286, 7481.50287290513, 7495.063117110361, 7610.6194690266375, 7697.538147711155, 7795.532268063935, 7314.4148468123485, 7380.18817930038, 7446.813829654302, 7452.06849143857, 7689.231076892266, 6620.503462240447, 6732.596044237362, 6733.6296503692765, 6735.090762746966, 6738.494612903954, 5069.569086627678, 5118.104331742004, 5231.084560202025, 5319.654222475527, 5397.136458166831, 5192.3769147701005, 5354.866128346823, 5367.087595108763, 5379.381371142213, 5430.13117901134], "value": 4464.578039865887, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.6396983544335, 322.67732267732265, 324.62181558484366, 327.1184158692324, 329.11796385686364, 5100.489951004907, 5242.711650859202, 5255.448177941318, 5274.630775845697, 5287.259783630295, 5204.947950520494, 5225.712585807779, 5226.7647955841985, 5235.921461178076, 5261.736913154346, 4139.192857392794, 4152.1280531088505, 4164.354525048622, 4164.541900390955, 4164.791760411982, 4041.979790101045, 4141.606547378009, 4174.269502837006, 4183.079722461053, 4183.602557757008, 4095.89760255993, 4109.6506796922295, 4110.979445102786, 4121.9587804121875, 4134.37984302354, 4084.530279017921, 4091.590840915914, 4120.19656167049, 4137.275976704095, 4145.2952997990415, 3611.963880361215, 3619.438986957026, 3619.963800361989, 3628.546431696038, 3683.907902302437, 4845.394325709286, 5068.670536415121, 5074.822381216677, 5109.846704598831, 5180.342290467052, 4599.264117741145, 4623.9768801156115, 4634.0, 4636.744979026153, 4641.628669706449, 5361.865953351158, 5394.433584473652, 5403.648762830443, 5433.483819037185, 5447.237386765816, 3792.033031576958, 3831.272058308912, 3855.691544676419, 3863.961360386388, 3864.439656249828, 5388.380336261341, 5492.368377636543, 5583.776648934037, 5605.887882242373, 5628.971855140739, 3948.447217389568, 3954.635650700504, 3955.564887862333, 3960.8019599019935, 3965.1871366369824, 3838.9232215355537, 3893.7079719021185, 3991.3015222335976, 4007.73949693269, 4016.9756712038243, 4557.749323787223, 4561.703489273186, 4567.885802854888, 4579.404677391918, 4605.102005109038, 5466.289382380265, 5472.863178420492, 5476.808311709071, 5489.807856725075, 5494.928488944697, 1350.5205651993401, 1368.7330970460864, 1369.301656155368, 1376.2086800089987, 1379.0555317002977, 867.1718508824058, 869.7912500999714, 877.9385443018928, 880.247388482838, 880.9823803523893, 4771.498992605796, 4775.761211939389, 4799.968006878468, 4819.879503012453, 4832.34763306949, 4926.5073492650445, 4953.23224900141, 4961.355023846877, 4961.70229786214, 5035.874103147377, 4525.732794817456, 4727.905441891143, 4731.556875153043, 4743.837759748883, 4746.8101275948575, 2620.4366061296914, 2621.973780262211, 2638.379980704571, 2645.9206223813317, 2657.4020845310038, 5156.9742151289, 5182.585393168538, 5183.3261675981885, 5191.402988656278, 5247.711375874287, 4852.975735121302, 4983.426905905795, 5017.648764586444, 5076.517730815566, 5103.8468845934685, 5148.944466384382, 5151.5787579837415, 5157.149070403417, 5173.198154286091, 5226.902350506436, 4892.315075889307, 4957.479464656303, 4999.825006124768, 5032.547070763649, 5078.695278283315, 4939.481354457874, 5216.608754343363, 5308.522232999048, 5458.590605704668, 5492.80775172867, 5744.712764361849, 5775.162601422854, 5813.906993482427, 5864.589478736618, 5891.410858914074, 4022.9396559052225, 4048.6558642514537, 4054.79726013704, 4059.65492933104, 4062.857799976987, 5402.621816472966, 5516.503514683698, 5527.364353099445, 5544.362398324244, 5553.389127195925, 5399.623096110467, 5418.891622167614, 5440.646357986719, 5462.481064298885, 5498.257735205696, 5220.895582088413, 5449.509544141047, 5471.50756431923, 5480.972595137158, 5514.503694667499, 5367.382750983531, 5390.784368625368, 5394.433584473573, 5413.458654134555, 5421.512063914109, 5490.286262785812, 5512.503874651301, 5552.139418390156, 5597.0, 5609.691466969356, 5306.602004849729, 5312.574994000393, 5326.307580014573, 5367.651102678314, 5489.725513724377], "value": 4053.7929001711905, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.6132780290347, 770.9306162445379, 774.0479210570998, 841.9663213471464, 881.1364862434809, 15600.531984040483, 16694.499165025038, 16695.165241737912, 16704.49886503404, 16713.24790384434, 14406.423743050265, 14653.706925861476, 14669.413223471049, 14767.187804670739, 14769.556913292592, 667.159379182229, 797.5453991225004, 815.9428839981218, 818.8239528501371, 820.8768684697284, 816.8162163513192, 829.601791140253, 829.9253067223949, 830.248624994378, 830.4643504939311, 886.5700135434341, 888.995555022224, 889.0220757167097, 890.1143362354467, 890.4078787606234, 709.2871663977695, 853.8889944307263, 876.0494863073573, 885.349268287808, 885.5173930208052, 641.5893827950116, 642.6850843086901, 643.337362516604, 643.3566433566433, 643.4434214404553, 14851.183184924825, 14896.851031489763, 14909.627259318495, 14971.925140374336, 14996.550103496913, 14329.35517901697, 15035.849641503664, 15418.768718469231, 15505.922470387688, 15508.45720399792, 13103.803442948363, 13210.801837972438, 13284.601461956157, 13337.733245335136, 14061.437542498288, 3818.961810381916, 4486.439195100613, 4551.499335073172, 4599.482170883601, 4732.408448943882, 69723.30276697219, 77748.61125694391, 78191.23505976096, 79606.60196699038, 81748.18251817465, 11473.196876218752, 11509.999999999833, 11611.129165312635, 11742.765144697229, 11843.170978031456, 21132.0, 21133.68299475509, 21269.574608507744, 21316.680249796267, 21378.465538361666, 8639.179277968582, 8683.2185103341, 8713.956430217808, 8730.345224108218, 8829.911700882909, 4945.159322915113, 5047.52048555394, 5056.140456122468, 5075.619328550373, 5182.0, 3460.532828068179, 3587.587427445825, 3587.982060089683, 3590.4075827488173, 3591.730620203495, 608.4250383387745, 612.6967151260066, 627.9277883043509, 629.6442509981937, 630.6562923206864, 521.4472658981416, 667.6294656465708, 744.8845428958521, 760.6729106484154, 774.8798936164903, 9765.365251258645, 10091.243156763125, 10138.949305253427, 10177.346937756376, 10332.690019299433, 671.3454381977533, 679.7450955891541, 741.9406447484188, 753.4236309223448, 758.8675820943057, 5053.570446512014, 5069.239614057921, 5106.642535022587, 5141.562967147835, 5154.53609175176, 71703.0, 85649.0, 91761.0, 108110.0, 131550.0, 63063.369366306666, 64014.479203644245, 71684.0, 103309.0, 106783.0, 55306.0, 56396.154057689164, 82932.58533707293, 95878.0, 98632.50683746679, 9232.861507077534, 9403.247740180906, 9448.48033358172, 9486.098820612031, 9536.666216682383, 53277.0, 53649.195262070316, 54194.0, 54628.63428414321, 54813.725931370085, 78731.0, 82656.58671706603, 83477.49567512985, 83519.5824020876, 84802.57598711966, 12278.263304201777, 12580.370981450707, 12708.745825083632, 12813.23120612729, 12955.093143479866, 14549.781753273497, 14556.781648275073, 14807.777883331542, 15487.612809679398, 15640.0, 13860.237686927316, 14011.509597164051, 14056.367463463965, 14156.646083847987, 14199.64500887486, 13476.730465390834, 13618.795718064039, 13869.375878085308, 14012.929935350257, 14033.1580105194, 13384.0, 13547.119437236548, 13626.731703416539, 13683.726325473235, 14397.496087636882, 10908.400037997986, 11023.393713345522, 11090.778184436427, 11343.319400835646, 11646.94176529112, 10370.792584148425, 10933.640214684456, 10953.137966545866, 10970.890291096986, 11026.283291586022], "value": 7646.943682462752, "unit": "score"}], "name": "dromaeo_css", "value": 7125.301703400917, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 110.0, 111.0, 110.0, 110.0, 112.0, 111.0, 110.0, 111.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 116.0, 119.0, 180.0, 118.0, 124.0, 145.0, 118.0, 160.0, 118.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 159.0, 161.0, 162.0, 161.0, 161.0, 162.0, 161.0, 159.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 127.0, 75.0, 76.0, 75.0, 76.0, 120.0, 74.0], "value": 84.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 121.0, 85.0, 85.0, 85.0, 128.0, 84.0, 85.0, 120.0], "value": 96.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 118.0, 117.0, 116.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 52.0, 51.0, 53.0, 51.0, 51.0, 52.0, 51.0, 52.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 61.0, 60.0, 60.0, 67.0, 60.0, 60.0, 60.0, 61.0], "value": 60.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 75.0, 75.0, 76.0, 76.0, 76.0, 74.0, 75.0, 79.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 123.0, 121.0, 122.0, 120.0, 122.0, 120.0, 118.0, 138.0, 129.0], "value": 124.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 163.0, 162.0, 157.0, 155.0, 159.0, 159.0, 160.0, 158.0, 157.0], "value": 158.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 62.0, 63.0, 61.0, 63.0, 61.0, 62.0, 61.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1475.0, "alertThreshold": 2.0}]} 21:48:49 INFO - Return code: 0 21:48:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:48:49 INFO - # TBPL SUCCESS # 21:48:49 INFO - Running post-action listener: _resource_record_post_action 21:48:49 INFO - [mozharness: 2016-08-09 04:48:49.477342Z] Finished run-tests step (success) 21:48:49 INFO - Running post-run listener: _resource_record_post_run 21:48:49 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 16384; Write bytes: 321724416; Read time: 272; Write time: 231624 21:48:49 INFO - TinderboxPrint: CPU usage
13.1% 21:48:49 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 272 21:48:49 INFO - TinderboxPrint: I/O write bytes / time
321,724,416 / 231,624 21:48:49 INFO - TinderboxPrint: CPU idle
8,264.5 (86.9%) 21:48:49 INFO - TinderboxPrint: CPU user
1,200.8 (12.6%) 21:48:49 INFO - TinderboxPrint: Swap in / out
0 / 0 21:48:49 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 29442048; Read time: 0; Write time: 15192 21:48:49 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 12288; Write bytes: 292282368; Read time: 264; Write time: 216432 21:48:50 INFO - Running post-run listener: _upload_blobber_files 21:48:50 INFO - Blob upload gear active. 21:48:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:48:50 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 21:48:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:48:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:48:50 INFO - (blobuploader) - INFO - Open directory for files ... 21:48:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:48:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:48:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:48:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:48:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:48:54 INFO - (blobuploader) - INFO - Done attempting. 21:48:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:48:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:48:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:48:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:48:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:48:55 INFO - (blobuploader) - INFO - Done attempting. 21:48:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:48:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:48:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:48:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:48:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:48:56 INFO - (blobuploader) - INFO - Done attempting. 21:48:56 INFO - (blobuploader) - INFO - Iteration through files over. 21:48:56 INFO - Return code: 0 21:48:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:48:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:48:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c35d79cff2293bbd4b7b83bc07193a986e425f5fd505bfc4d6b2ca5dc8b7b6f165f4c61e671c6a3a490d6ee53de57c06ede0df1422dbfece4f22d69eaee4994a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d802711175f4d918d57ebd6b7e651ce6a2a91417d6016a6364129cb27e76a66b4563f7dd56daeefb235934c480f83692bcd57a1df7b3b784fc98d5dfb9be39dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b739093abc9b2fb5fac35a4e0ff43d4f8e80d994bf41bf56b50a40b05ab3bf9fc0c94cca73b84eb4c89cbbbee4a01dbb5d87805cc89132605b4d233fdc745311"} 21:48:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:48:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:48:56 INFO - Contents: 21:48:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c35d79cff2293bbd4b7b83bc07193a986e425f5fd505bfc4d6b2ca5dc8b7b6f165f4c61e671c6a3a490d6ee53de57c06ede0df1422dbfece4f22d69eaee4994a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d802711175f4d918d57ebd6b7e651ce6a2a91417d6016a6364129cb27e76a66b4563f7dd56daeefb235934c480f83692bcd57a1df7b3b784fc98d5dfb9be39dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b739093abc9b2fb5fac35a4e0ff43d4f8e80d994bf41bf56b50a40b05ab3bf9fc0c94cca73b84eb4c89cbbbee4a01dbb5d87805cc89132605b4d233fdc745311"} 21:48:56 INFO - Running post-run listener: copy_logs_to_upload_dir 21:48:56 INFO - Copying logs to upload dir... 21:48:56 INFO - mkdir: /builds/slave/test/build/upload/logs 21:48:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.107510 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-08-08 21:48:56.515464) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 21:48:56.518506) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c35d79cff2293bbd4b7b83bc07193a986e425f5fd505bfc4d6b2ca5dc8b7b6f165f4c61e671c6a3a490d6ee53de57c06ede0df1422dbfece4f22d69eaee4994a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d802711175f4d918d57ebd6b7e651ce6a2a91417d6016a6364129cb27e76a66b4563f7dd56daeefb235934c480f83692bcd57a1df7b3b784fc98d5dfb9be39dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b739093abc9b2fb5fac35a4e0ff43d4f8e80d994bf41bf56b50a40b05ab3bf9fc0c94cca73b84eb4c89cbbbee4a01dbb5d87805cc89132605b4d233fdc745311"} build_url:https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011477 build_url: 'https://queue.taskcluster.net/v1/task/COsqWIY4SraQxvpNLgsaRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c35d79cff2293bbd4b7b83bc07193a986e425f5fd505bfc4d6b2ca5dc8b7b6f165f4c61e671c6a3a490d6ee53de57c06ede0df1422dbfece4f22d69eaee4994a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d802711175f4d918d57ebd6b7e651ce6a2a91417d6016a6364129cb27e76a66b4563f7dd56daeefb235934c480f83692bcd57a1df7b3b784fc98d5dfb9be39dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b739093abc9b2fb5fac35a4e0ff43d4f8e80d994bf41bf56b50a40b05ab3bf9fc0c94cca73b84eb4c89cbbbee4a01dbb5d87805cc89132605b4d233fdc745311"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 21:48:56.543617) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 21:48:56.543894) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1470716895.728079-948853452 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004481 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 21:48:56.628755) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 21:48:56.629049) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 21:48:56.629334) ========= ========= Total master_lag: 0.15 =========