builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-046 starttime: 1470452696.76 results: success (0) buildid: 20160805173004 builduid: d396da246bc94f43beafd790c741d100 revision: 1646172ac6e709f5abdecbd8edbb53a54392428a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-05 20:04:56.755389) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-05 20:04:56.755822) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-05 20:04:56.756108) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-05 20:04:56.801280) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 20:04:56.801553) ========= 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=652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-05 20:04:56-- 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% 222M=0s 2016-08-05 20:04:56 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119162 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 20:04:56.934396) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 20:04:56.934692) ========= 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=652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014293 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 20:04:56.983901) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 20:04:56.984222) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1646172ac6e709f5abdecbd8edbb53a54392428a --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 1646172ac6e709f5abdecbd8edbb53a54392428a --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=652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-05 20:04:57,099 truncating revision to first 12 chars 2016-08-05 20:04:57,099 Setting DEBUG logging. 2016-08-05 20:04:57,099 attempt 1/10 2016-08-05 20:04:57,099 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1646172ac6e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-05 20:04:57,416 unpacking tar archive at: fx-team-1646172ac6e7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.645879 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 20:04:57.643621) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-05 20:04:57.643906) ========= script_repo_revision: 1646172ac6e709f5abdecbd8edbb53a54392428a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-05 20:04:57.644287) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-05 20:04:57.644568) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-05 20:04:57.656519) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-08-05 20:04:57.656800) ========= /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=652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:04:57 INFO - MultiFileLogger online at 20160805 20:04:57 in /builds/slave/test 20:04:57 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 20:04:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:04:57 INFO - {'append_to_log': False, 20:04:57 INFO - 'base_work_dir': '/builds/slave/test', 20:04:57 INFO - 'blob_upload_branch': 'Fx-Team', 20:04:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:04:57 INFO - 'branch': 'Fx-Team', 20:04:57 INFO - 'buildbot_json_path': 'buildprops.json', 20:04:57 INFO - 'config_files': ('talos/linux_config.py',), 20:04:57 INFO - 'default_actions': ('clobber', 20:04:57 INFO - 'read-buildbot-config', 20:04:57 INFO - 'download-and-extract', 20:04:57 INFO - 'populate-webroot', 20:04:57 INFO - 'create-virtualenv', 20:04:57 INFO - 'install', 20:04:57 INFO - 'run-tests'), 20:04:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:04:57 INFO - 'download_minidump_stackwalk': True, 20:04:57 INFO - 'download_symbols': 'ondemand', 20:04:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:04:57 INFO - 'tooltool.py': '/tools/tooltool.py', 20:04:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:04:57 INFO - '/tools/misc-python/virtualenv.py')}, 20:04:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:04:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:04:57 INFO - 'installer_path': 'installer.exe', 20:04:57 INFO - 'log_level': 'info', 20:04:57 INFO - 'log_name': 'talos', 20:04:57 INFO - 'log_to_console': True, 20:04:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:04:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:04:57 INFO - 'opt_config_files': (), 20:04:57 INFO - 'pip_index': False, 20:04:57 INFO - 'sps_profile': False, 20:04:57 INFO - 'sps_profile_interval': 0, 20:04:57 INFO - 'suite': 'dromaeojs', 20:04:57 INFO - 'system_bits': '32', 20:04:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:04:57 INFO - 'title': 'talos-linux64-ix-046', 20:04:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:04:57 INFO - 'use_talos_json': True, 20:04:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:04:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:04:57 INFO - 'work_dir': 'build'} 20:04:57 INFO - [mozharness: 2016-08-06 03:04:57.861481Z] Running clobber step. 20:04:57 INFO - Running pre-action listener: _resource_record_pre_action 20:04:57 INFO - Running main action method: clobber 20:04:57 INFO - rmtree: /builds/slave/test/build 20:04:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:04:58 INFO - Running post-action listener: _resource_record_post_action 20:04:58 INFO - [mozharness: 2016-08-06 03:04:58.323513Z] Finished clobber step (success) 20:04:58 INFO - [mozharness: 2016-08-06 03:04:58.323614Z] Running read-buildbot-config step. 20:04:58 INFO - Running pre-action listener: _resource_record_pre_action 20:04:58 INFO - Running main action method: read_buildbot_config 20:04:58 INFO - Using buildbot properties: 20:04:58 INFO - { 20:04:58 INFO - "project": "", 20:04:58 INFO - "product": "firefox", 20:04:58 INFO - "script_repo_revision": "production", 20:04:58 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 20:04:58 INFO - "repository": "", 20:04:58 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 20:04:58 INFO - "buildid": "20160805173004", 20:04:58 INFO - "pgo_build": "True", 20:04:58 INFO - "basedir": "/builds/slave/test", 20:04:58 INFO - "buildnumber": 1431, 20:04:58 INFO - "slavename": "talos-linux64-ix-046", 20:04:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 20:04:58 INFO - "platform": "ubuntu64_hw", 20:04:58 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 20:04:58 INFO - "branch": "fx-team", 20:04:58 INFO - "repo_path": "integration/fx-team", 20:04:58 INFO - "revision": "1646172ac6e709f5abdecbd8edbb53a54392428a", 20:04:58 INFO - "stage_platform": "linux64-pgo", 20:04:58 INFO - "builduid": "d396da246bc94f43beafd790c741d100", 20:04:58 INFO - "slavebuilddir": "test" 20:04:58 INFO - } 20:04:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 20:04:58 INFO - Running post-action listener: _resource_record_post_action 20:04:58 INFO - [mozharness: 2016-08-06 03:04:58.326058Z] Finished read-buildbot-config step (success) 20:04:58 INFO - [mozharness: 2016-08-06 03:04:58.326148Z] Running download-and-extract step. 20:04:58 INFO - Running pre-action listener: _resource_record_pre_action 20:04:58 INFO - Running main action method: download_and_extract 20:04:58 INFO - mkdir: /builds/slave/test/build/tests 20:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:58 INFO - trying https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:04:58 INFO - Downloading https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/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 20:04:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/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 20:04:58 INFO - Downloaded 1361 bytes. 20:04:58 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:04:58 INFO - Using the following test package requirements: 20:04:58 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 20:04:58 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:04:58 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:04:58 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:04:58 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:04:58 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:04:58 INFO - u'jsshell-linux-x86_64.zip'], 20:04:58 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:04:58 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:04:58 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 20:04:58 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:04:58 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:04:58 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:04:58 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:04:58 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:04:58 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:04:58 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:04:58 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:04:58 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:04:58 INFO - trying https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:04:58 INFO - Downloading https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/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 20:04:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/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 20:04:59 INFO - Downloaded 21825863 bytes. 20:04:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:04:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 20:05:01 INFO - Return code: 0 20:05:01 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 20:05:01 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')]} 20:05:01 INFO - trying https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:05:01 INFO - Downloading https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/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 20:05:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/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 20:05:02 INFO - Downloaded 21825863 bytes. 20:05:02 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'] 20:05:02 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 20:05:03 INFO - Return code: 0 20:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:03 INFO - trying https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 20:05:03 INFO - Downloading https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/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 20:05:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/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 20:05:04 INFO - Downloaded 14413723 bytes. 20:05:04 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'] 20:05:04 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 20:05:05 INFO - Return code: 0 20:05:05 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')]} 20:05:05 INFO - trying https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:05:05 INFO - Downloading https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/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 20:05:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/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 20:05:07 INFO - Downloaded 59968636 bytes. 20:05:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:05:07 INFO - mkdir: /builds/slave/test/properties 20:05:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:05:07 INFO - Writing to file /builds/slave/test/properties/build_url 20:05:07 INFO - Contents: 20:05:07 INFO - build_url:https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:05:07 INFO - Running post-action listener: _resource_record_post_action 20:05:07 INFO - Running post-action listener: set_extra_try_arguments 20:05:07 INFO - [mozharness: 2016-08-06 03:05:07.758099Z] Finished download-and-extract step (success) 20:05:07 INFO - [mozharness: 2016-08-06 03:05:07.758331Z] Running populate-webroot step. 20:05:07 INFO - Running pre-action listener: _resource_record_pre_action 20:05:07 INFO - Running main action method: populate_webroot 20:05:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:05:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:05:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:05:07 INFO - '--tppagecycles', 20:05:07 INFO - '7'], 20:05:07 INFO - 'tests': ['tsvgm']}}, 20:05:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:05:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:05:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:05:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:05:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:05:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:05:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:05:07 INFO - 'talos_options': ['--disable-e10s'], 20:05:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:05:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:05:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:05:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:05:07 INFO - 'talos_options': ['--disable-e10s'], 20:05:07 INFO - 'tests': ['damp', 'tps']}, 20:05:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:05:07 INFO - 'tests': ['damp', 'tps']}, 20:05:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:05:07 INFO - 'tests': ['dromaeo_dom']}, 20:05:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:05:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:05:07 INFO - 'tests': ['basic_compositor_video']}, 20:05:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:05:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:05:07 INFO - 'tests': ['a11yr', 20:05:07 INFO - 'ts_paint', 20:05:07 INFO - 'tpaint', 20:05:07 INFO - 'sessionrestore', 20:05:07 INFO - 'sessionrestore_no_auto_restore', 20:05:07 INFO - 'tabpaint']}, 20:05:07 INFO - 'other-e10s': {'tests': ['a11yr', 20:05:07 INFO - 'ts_paint', 20:05:07 INFO - 'tpaint', 20:05:07 INFO - 'sessionrestore', 20:05:07 INFO - 'sessionrestore_no_auto_restore', 20:05:07 INFO - 'tabpaint']}, 20:05:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:05:07 INFO - 'ts_paint', 20:05:07 INFO - 'tpaint', 20:05:07 INFO - 'sessionrestore', 20:05:07 INFO - 'sessionrestore_no_auto_restore', 20:05:07 INFO - 'tabpaint']}, 20:05:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:05:07 INFO - 'ts_paint', 20:05:07 INFO - 'tpaint', 20:05:07 INFO - 'sessionrestore', 20:05:07 INFO - 'sessionrestore_no_auto_restore', 20:05:07 INFO - 'tabpaint']}, 20:05:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:05:07 INFO - 'tests': ['a11yr', 20:05:07 INFO - 'ts_paint', 20:05:07 INFO - 'tpaint', 20:05:07 INFO - 'sessionrestore', 20:05:07 INFO - 'sessionrestore_no_auto_restore', 20:05:07 INFO - 'tabpaint']}, 20:05:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:05:07 INFO - 'tests': ['a11yr', 20:05:07 INFO - 'ts_paint', 20:05:07 INFO - 'tpaint', 20:05:07 INFO - 'sessionrestore', 20:05:07 INFO - 'sessionrestore_no_auto_restore', 20:05:07 INFO - 'tabpaint']}, 20:05:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:05:07 INFO - 'tests': ['tsvgx', 20:05:07 INFO - 'tsvgr_opacity', 20:05:07 INFO - 'tart', 20:05:07 INFO - 'tscrollx', 20:05:07 INFO - 'cart']}, 20:05:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:05:07 INFO - 'tsvgr_opacity', 20:05:07 INFO - 'tart', 20:05:07 INFO - 'tscrollx', 20:05:07 INFO - 'cart']}, 20:05:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:05:07 INFO - 'talos_options': ['--disable-e10s'], 20:05:07 INFO - 'tests': ['tp5o']}, 20:05:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:05:07 INFO - 'tests': ['tp5o']}, 20:05:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:05:07 INFO - 'talos_options': ['--disable-e10s', 20:05:07 INFO - '--xperf_path', 20:05:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:05:07 INFO - 'tests': ['tp5n']}, 20:05:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:05:07 INFO - 'talos_options': ['--xperf_path', 20:05:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:05:07 INFO - 'tests': ['tp5n']}}, 20:05:07 INFO - 'talos.zip': {'path': '', 20:05:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:05:07 INFO - Running post-action listener: _resource_record_post_action 20:05:07 INFO - [mozharness: 2016-08-06 03:05:07.778448Z] Finished populate-webroot step (success) 20:05:07 INFO - [mozharness: 2016-08-06 03:05:07.778537Z] Running create-virtualenv step. 20:05:07 INFO - Running pre-action listener: _resource_record_pre_action 20:05:07 INFO - Running main action method: create_virtualenv 20:05:07 INFO - Creating virtualenv /builds/slave/test/build/venv 20:05:07 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 20:05:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:05:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:05:07 INFO - Using real prefix '/usr' 20:05:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:05:08 INFO - Installing distribute.............................................................................................................................................................................................done. 20:05:10 INFO - Installing pip.................done. 20:05:10 INFO - Return code: 0 20:05:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:05:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:10 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 0x23ce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268d3b0>, '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 0x268e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x268e740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', '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 20:05:10 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 20:05:10 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 20:05:10 INFO - Using env: {'DISPLAY': ':0', 20:05:10 INFO - 'HOME': '/home/cltbld', 20:05:10 INFO - 'LANG': 'en_US.UTF-8', 20:05:10 INFO - 'LANGUAGE': 'en_US:en', 20:05:10 INFO - 'LOGNAME': 'cltbld', 20:05:10 INFO - 'MAIL': '/var/mail/cltbld', 20:05:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:10 INFO - 'MOZ_NO_REMOTE': '1', 20:05:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:05:10 INFO - 'NO_EM_RESTART': '1', 20:05:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:05:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:10 INFO - 'PWD': '/builds/slave/test', 20:05:10 INFO - 'SHELL': '/bin/bash', 20:05:10 INFO - 'SHLVL': '1', 20:05:10 INFO - 'TERM': 'linux', 20:05:10 INFO - 'TMOUT': '86400', 20:05:10 INFO - 'USER': 'cltbld', 20:05:10 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', 20:05:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:10 INFO - '_': '/tools/buildbot/bin/python'} 20:05:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:10 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 20:05:10 INFO - Cleaning up... 20:05:10 INFO - Return code: 0 20:05:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:05:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:10 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 0x23ce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268d3b0>, '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 0x268e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x268e740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', '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 20:05:10 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 20:05:10 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 20:05:10 INFO - Using env: {'DISPLAY': ':0', 20:05:10 INFO - 'HOME': '/home/cltbld', 20:05:10 INFO - 'LANG': 'en_US.UTF-8', 20:05:10 INFO - 'LANGUAGE': 'en_US:en', 20:05:10 INFO - 'LOGNAME': 'cltbld', 20:05:10 INFO - 'MAIL': '/var/mail/cltbld', 20:05:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:10 INFO - 'MOZ_NO_REMOTE': '1', 20:05:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:05:10 INFO - 'NO_EM_RESTART': '1', 20:05:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:05:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:10 INFO - 'PWD': '/builds/slave/test', 20:05:10 INFO - 'SHELL': '/bin/bash', 20:05:10 INFO - 'SHLVL': '1', 20:05:10 INFO - 'TERM': 'linux', 20:05:10 INFO - 'TMOUT': '86400', 20:05:10 INFO - 'USER': 'cltbld', 20:05:10 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', 20:05:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:10 INFO - '_': '/tools/buildbot/bin/python'} 20:05:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:10 INFO - Downloading/unpacking psutil>=3.1.1 20:05:10 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 20:05:10 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 20:05:10 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 20:05:10 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 20:05:10 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 20:05:10 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 20:05:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:05:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:05:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:05:12 INFO - Installing collected packages: psutil 20:05:12 INFO - Running setup.py install for psutil 20:05:12 INFO - building 'psutil._psutil_linux' extension 20:05:12 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 20:05:12 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 20:05:13 INFO - building 'psutil._psutil_posix' extension 20:05:13 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 20:05:13 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 20:05:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:05:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:05:13 INFO - Successfully installed psutil 20:05:13 INFO - Cleaning up... 20:05:13 INFO - Return code: 0 20:05:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:05:13 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')]} 20:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:13 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')]} 20:05:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:13 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 0x23ce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268d3b0>, '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 0x268e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x268e740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', '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 20:05:13 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 20:05:13 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 20:05:13 INFO - Using env: {'DISPLAY': ':0', 20:05:13 INFO - 'HOME': '/home/cltbld', 20:05:13 INFO - 'LANG': 'en_US.UTF-8', 20:05:13 INFO - 'LANGUAGE': 'en_US:en', 20:05:13 INFO - 'LOGNAME': 'cltbld', 20:05:13 INFO - 'MAIL': '/var/mail/cltbld', 20:05:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:13 INFO - 'MOZ_NO_REMOTE': '1', 20:05:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:05:13 INFO - 'NO_EM_RESTART': '1', 20:05:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:05:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:13 INFO - 'PWD': '/builds/slave/test', 20:05:13 INFO - 'SHELL': '/bin/bash', 20:05:13 INFO - 'SHLVL': '1', 20:05:13 INFO - 'TERM': 'linux', 20:05:13 INFO - 'TMOUT': '86400', 20:05:13 INFO - 'USER': 'cltbld', 20:05:13 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', 20:05:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:13 INFO - '_': '/tools/buildbot/bin/python'} 20:05:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:05:13 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 20:05:13 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 20:05:13 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 20:05:13 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 20:05:13 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 20:05:13 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 20:05:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:05:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:05:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:05:15 INFO - Installing collected packages: mozsystemmonitor 20:05:15 INFO - Running setup.py install for mozsystemmonitor 20:05:15 INFO - Successfully installed mozsystemmonitor 20:05:15 INFO - Cleaning up... 20:05:15 INFO - Return code: 0 20:05:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:05:15 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')]} 20:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:15 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')]} 20:05:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:15 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 0x23ce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268d3b0>, '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 0x268e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x268e740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', '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 20:05:15 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 20:05:15 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 20:05:15 INFO - Using env: {'DISPLAY': ':0', 20:05:15 INFO - 'HOME': '/home/cltbld', 20:05:15 INFO - 'LANG': 'en_US.UTF-8', 20:05:15 INFO - 'LANGUAGE': 'en_US:en', 20:05:15 INFO - 'LOGNAME': 'cltbld', 20:05:15 INFO - 'MAIL': '/var/mail/cltbld', 20:05:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:15 INFO - 'MOZ_NO_REMOTE': '1', 20:05:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:05:15 INFO - 'NO_EM_RESTART': '1', 20:05:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:05:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:15 INFO - 'PWD': '/builds/slave/test', 20:05:15 INFO - 'SHELL': '/bin/bash', 20:05:15 INFO - 'SHLVL': '1', 20:05:15 INFO - 'TERM': 'linux', 20:05:15 INFO - 'TMOUT': '86400', 20:05:15 INFO - 'USER': 'cltbld', 20:05:15 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', 20:05:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:15 INFO - '_': '/tools/buildbot/bin/python'} 20:05:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:15 INFO - Downloading/unpacking jsonschema==2.5.1 20:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:05:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:05:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:05:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:05:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:05:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:05:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:05:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:05:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:05:17 INFO - Downloading functools32-3.2.3-2.tar.gz 20:05:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:05:17 INFO - warning: no files found matching '*.txt' 20:05:17 INFO - no previously-included directories found matching 'build' 20:05:17 INFO - no previously-included directories found matching 'dist' 20:05:17 INFO - no previously-included directories found matching '.git*' 20:05:17 INFO - Installing collected packages: jsonschema, functools32 20:05:17 INFO - Running setup.py install for functools32 20:05:18 INFO - warning: no files found matching '*.txt' 20:05:18 INFO - no previously-included directories found matching 'build' 20:05:18 INFO - no previously-included directories found matching 'dist' 20:05:18 INFO - no previously-included directories found matching '.git*' 20:05:18 INFO - Successfully installed jsonschema functools32 20:05:18 INFO - Cleaning up... 20:05:18 INFO - Return code: 0 20:05:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:05: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')]} 20:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05: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')]} 20:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268d3b0>, '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 0x268e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x268e740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', '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 20:05:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:05:18 INFO - Using env: {'DISPLAY': ':0', 20:05:18 INFO - 'HOME': '/home/cltbld', 20:05:18 INFO - 'LANG': 'en_US.UTF-8', 20:05:18 INFO - 'LANGUAGE': 'en_US:en', 20:05:18 INFO - 'LOGNAME': 'cltbld', 20:05:18 INFO - 'MAIL': '/var/mail/cltbld', 20:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:18 INFO - 'MOZ_NO_REMOTE': '1', 20:05:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:05:18 INFO - 'NO_EM_RESTART': '1', 20:05:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:18 INFO - 'PWD': '/builds/slave/test', 20:05:18 INFO - 'SHELL': '/bin/bash', 20:05:18 INFO - 'SHLVL': '1', 20:05:18 INFO - 'TERM': 'linux', 20:05:18 INFO - 'TMOUT': '86400', 20:05:18 INFO - 'USER': 'cltbld', 20:05:18 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', 20:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:18 INFO - '_': '/tools/buildbot/bin/python'} 20:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:05:18 INFO - Cleaning up... 20:05:18 INFO - Return code: 0 20:05:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:05: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')]} 20:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05: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')]} 20:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268d3b0>, '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 0x268e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x268e740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', '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 20:05:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:05:18 INFO - Using env: {'DISPLAY': ':0', 20:05:18 INFO - 'HOME': '/home/cltbld', 20:05:18 INFO - 'LANG': 'en_US.UTF-8', 20:05:18 INFO - 'LANGUAGE': 'en_US:en', 20:05:18 INFO - 'LOGNAME': 'cltbld', 20:05:18 INFO - 'MAIL': '/var/mail/cltbld', 20:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:18 INFO - 'MOZ_NO_REMOTE': '1', 20:05:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:05:18 INFO - 'NO_EM_RESTART': '1', 20:05:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:18 INFO - 'PWD': '/builds/slave/test', 20:05:18 INFO - 'SHELL': '/bin/bash', 20:05:18 INFO - 'SHLVL': '1', 20:05:18 INFO - 'TERM': 'linux', 20:05:18 INFO - 'TMOUT': '86400', 20:05:18 INFO - 'USER': 'cltbld', 20:05:18 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', 20:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:18 INFO - '_': '/tools/buildbot/bin/python'} 20:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:18 INFO - Downloading/unpacking blobuploader==1.2.4 20:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:05:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:05:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:05:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:05:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:05:20 INFO - Downloading blobuploader-1.2.4.tar.gz 20:05:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:05:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:05:20 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 20:05:20 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 20:05:20 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 20:05:20 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 20:05:20 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 20:05:20 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 20:05:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:05:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:05:20 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 20:05:20 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 20:05:20 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 20:05:20 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 20:05:20 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 20:05:20 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 20:05:21 INFO - Downloading docopt-0.6.1.tar.gz 20:05:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:05:21 INFO - Installing collected packages: blobuploader, requests, docopt 20:05:21 INFO - Running setup.py install for blobuploader 20:05:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:05:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:05:21 INFO - Running setup.py install for requests 20:05:21 INFO - Running setup.py install for docopt 20:05:21 INFO - Successfully installed blobuploader requests docopt 20:05:21 INFO - Cleaning up... 20:05:21 INFO - Return code: 0 20:05:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:05: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')]} 20:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05: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')]} 20:05:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:21 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 0x23ce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268d3b0>, '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 0x268e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x268e740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', '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 20:05:21 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 20:05:21 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 20:05:21 INFO - Using env: {'DISPLAY': ':0', 20:05:21 INFO - 'HOME': '/home/cltbld', 20:05:21 INFO - 'LANG': 'en_US.UTF-8', 20:05:21 INFO - 'LANGUAGE': 'en_US:en', 20:05:21 INFO - 'LOGNAME': 'cltbld', 20:05:21 INFO - 'MAIL': '/var/mail/cltbld', 20:05:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:21 INFO - 'MOZ_NO_REMOTE': '1', 20:05:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:05:21 INFO - 'NO_EM_RESTART': '1', 20:05:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:05:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:21 INFO - 'PWD': '/builds/slave/test', 20:05:21 INFO - 'SHELL': '/bin/bash', 20:05:21 INFO - 'SHLVL': '1', 20:05:21 INFO - 'TERM': 'linux', 20:05:21 INFO - 'TMOUT': '86400', 20:05:21 INFO - 'USER': 'cltbld', 20:05:21 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', 20:05:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:21 INFO - '_': '/tools/buildbot/bin/python'} 20:05:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:05:22 INFO - Running setup.py (path:/tmp/pip-K9bKvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:05:22 INFO - Running setup.py (path:/tmp/pip-k0TK9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:05:22 INFO - Running setup.py (path:/tmp/pip-wf02OS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:05:22 INFO - Running setup.py (path:/tmp/pip-VcBpQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:05:22 INFO - Running setup.py (path:/tmp/pip-dkcXFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:05:22 INFO - Running setup.py (path:/tmp/pip-FJ8fic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:05:22 INFO - Running setup.py (path:/tmp/pip-JjNLdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:05:22 INFO - Running setup.py (path:/tmp/pip-hfYkp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:05:22 INFO - Running setup.py (path:/tmp/pip-1WiEqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:05:22 INFO - Running setup.py (path:/tmp/pip-1K0Poz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:05:22 INFO - Running setup.py (path:/tmp/pip-DoVfBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:05:22 INFO - Running setup.py (path:/tmp/pip-SQPf7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:05:22 INFO - Running setup.py (path:/tmp/pip-GmuDeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:05:22 INFO - Running setup.py (path:/tmp/pip-Q58f3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:05:23 INFO - Running setup.py (path:/tmp/pip-VVHw7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:05:23 INFO - Running setup.py (path:/tmp/pip-XL6aA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:05:23 INFO - Running setup.py (path:/tmp/pip-9wyQKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:05:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:05:23 INFO - Running setup.py install for manifestparser 20:05:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:05:23 INFO - Running setup.py install for mozcrash 20:05:23 INFO - Running setup.py install for mozdebug 20:05:23 INFO - Running setup.py install for mozdevice 20:05:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:05:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:05:23 INFO - Running setup.py install for mozfile 20:05:23 INFO - Running setup.py install for mozhttpd 20:05:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:05:23 INFO - Running setup.py install for mozinfo 20:05:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:05:23 INFO - Running setup.py install for mozInstall 20:05:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:05:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:05:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:05:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:05:24 INFO - Running setup.py install for mozleak 20:05:24 INFO - Running setup.py install for mozlog 20:05:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:05:24 INFO - Running setup.py install for moznetwork 20:05:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:05:24 INFO - Running setup.py install for mozprocess 20:05:24 INFO - Running setup.py install for mozprofile 20:05:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:05:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:05:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:05:24 INFO - Running setup.py install for mozrunner 20:05:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:05:24 INFO - Running setup.py install for mozscreenshot 20:05:24 INFO - Running setup.py install for moztest 20:05:24 INFO - Running setup.py install for mozversion 20:05:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:05:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:05:25 INFO - Cleaning up... 20:05:25 INFO - Return code: 0 20:05:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:05: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')]} 20:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05: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')]} 20:05:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:25 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 0x23ce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268d3b0>, '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 0x268e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x268e740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', '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 20:05:25 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 20:05:25 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 20:05:25 INFO - Using env: {'DISPLAY': ':0', 20:05:25 INFO - 'HOME': '/home/cltbld', 20:05:25 INFO - 'LANG': 'en_US.UTF-8', 20:05:25 INFO - 'LANGUAGE': 'en_US:en', 20:05:25 INFO - 'LOGNAME': 'cltbld', 20:05:25 INFO - 'MAIL': '/var/mail/cltbld', 20:05:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:25 INFO - 'MOZ_NO_REMOTE': '1', 20:05:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:05:25 INFO - 'NO_EM_RESTART': '1', 20:05:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:05:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:25 INFO - 'PWD': '/builds/slave/test', 20:05:25 INFO - 'SHELL': '/bin/bash', 20:05:25 INFO - 'SHLVL': '1', 20:05:25 INFO - 'TERM': 'linux', 20:05:25 INFO - 'TMOUT': '86400', 20:05:25 INFO - 'USER': 'cltbld', 20:05:25 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', 20:05:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:25 INFO - '_': '/tools/buildbot/bin/python'} 20:05:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:05:25 INFO - Running setup.py (path:/tmp/pip-X0lRBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:05:25 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)) 20:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:05:25 INFO - Running setup.py (path:/tmp/pip-uipYzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:05:25 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)) 20:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:05:25 INFO - Running setup.py (path:/tmp/pip-lB4g5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:05:25 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)) 20:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:05:25 INFO - Running setup.py (path:/tmp/pip-m8ZSbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:05:25 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)) 20:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:05:25 INFO - Running setup.py (path:/tmp/pip-qwlg9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:05:25 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)) 20:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:05:25 INFO - Running setup.py (path:/tmp/pip-xGMV26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:05:25 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)) 20:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:05:25 INFO - Running setup.py (path:/tmp/pip-awO0A1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:05:25 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)) 20:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:05:25 INFO - Running setup.py (path:/tmp/pip-0w47MV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:05:25 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)) 20:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:05:25 INFO - Running setup.py (path:/tmp/pip-Wu64Zb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:05:25 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)) 20:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:05:25 INFO - Running setup.py (path:/tmp/pip-YimJUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:05:26 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)) 20:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:05:26 INFO - Running setup.py (path:/tmp/pip-OF6SL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:05:26 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)) 20:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:05:26 INFO - Running setup.py (path:/tmp/pip-WwzlUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:05:26 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)) 20:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:05:26 INFO - Running setup.py (path:/tmp/pip-hZqDEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:05:26 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)) 20:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:05:26 INFO - Running setup.py (path:/tmp/pip-3I69CF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:05:26 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)) 20:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:05:26 INFO - Running setup.py (path:/tmp/pip-MZsMMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:05:26 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)) 20:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:05:26 INFO - Running setup.py (path:/tmp/pip-4tXuhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:05:26 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)) 20:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:05:26 INFO - Running setup.py (path:/tmp/pip-pHxn69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:05:26 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)) 20:05:26 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)) 20:05:26 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)) 20:05:26 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)) 20:05:26 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)) 20:05:26 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)) 20:05:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:05:26 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 20:05:26 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 20:05:26 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 20:05:26 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 20:05:26 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 20:05:26 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 20:05:28 INFO - Downloading blessings-1.6.tar.gz 20:05:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:05:28 INFO - Installing collected packages: blessings 20:05:28 INFO - Running setup.py install for blessings 20:05:28 INFO - Successfully installed blessings 20:05:28 INFO - Cleaning up... 20:05:28 INFO - Return code: 0 20:05:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:05:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:05:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:05:28 INFO - Reading from file tmpfile_stdout 20:05:28 INFO - Current package versions: 20:05:28 INFO - argparse == 1.2.1 20:05:28 INFO - blessings == 1.6 20:05:28 INFO - blobuploader == 1.2.4 20:05:28 INFO - docopt == 0.6.1 20:05:28 INFO - functools32 == 3.2.3-2 20:05:28 INFO - jsonschema == 2.5.1 20:05:28 INFO - manifestparser == 1.1 20:05:28 INFO - mozInstall == 1.12 20:05:28 INFO - mozcrash == 0.17 20:05:28 INFO - mozdebug == 0.1 20:05:28 INFO - mozdevice == 0.48 20:05:28 INFO - mozfile == 1.2 20:05:28 INFO - mozhttpd == 0.7 20:05:28 INFO - mozinfo == 0.9 20:05:28 INFO - mozleak == 0.1 20:05:28 INFO - mozlog == 3.3 20:05:28 INFO - moznetwork == 0.27 20:05:28 INFO - mozprocess == 0.23 20:05:28 INFO - mozprofile == 0.28 20:05:28 INFO - mozrunner == 6.12 20:05:28 INFO - mozscreenshot == 0.1 20:05:28 INFO - mozsystemmonitor == 0.3 20:05:28 INFO - moztest == 0.7 20:05:28 INFO - mozversion == 1.4 20:05:28 INFO - psutil == 3.1.1 20:05:28 INFO - requests == 1.2.3 20:05:28 INFO - wsgiref == 0.1.2 20:05:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:05:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05:28 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 0x23ce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268d3b0>, '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 0x268e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x268e740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', '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 20:05:28 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 20:05:28 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 20:05:28 INFO - Using env: {'DISPLAY': ':0', 20:05:28 INFO - 'HOME': '/home/cltbld', 20:05:28 INFO - 'LANG': 'en_US.UTF-8', 20:05:28 INFO - 'LANGUAGE': 'en_US:en', 20:05:28 INFO - 'LOGNAME': 'cltbld', 20:05:28 INFO - 'MAIL': '/var/mail/cltbld', 20:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:28 INFO - 'MOZ_NO_REMOTE': '1', 20:05:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:05:28 INFO - 'NO_EM_RESTART': '1', 20:05:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:28 INFO - 'PWD': '/builds/slave/test', 20:05:28 INFO - 'SHELL': '/bin/bash', 20:05:28 INFO - 'SHLVL': '1', 20:05:28 INFO - 'TERM': 'linux', 20:05:28 INFO - 'TMOUT': '86400', 20:05:28 INFO - 'USER': 'cltbld', 20:05:28 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', 20:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:28 INFO - '_': '/tools/buildbot/bin/python'} 20:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:29 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)) 20:05:29 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)) 20:05:29 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)) 20:05:29 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)) 20:05:29 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)) 20:05:29 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)) 20:05:29 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)) 20:05:29 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)) 20:05:29 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)) 20:05:29 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)) 20:05:29 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)) 20:05:29 INFO - Cleaning up... 20:05:29 INFO - Return code: 0 20:05:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:05: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')]} 20:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:05:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05: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')]} 20:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:05:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:05: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23ce030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x268f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x268d3b0>, '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 0x268e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x268e740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', '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 20:05: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', 'jsonschema'] in /builds/slave/test/build 20:05: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 jsonschema 20:05:29 INFO - Using env: {'DISPLAY': ':0', 20:05:29 INFO - 'HOME': '/home/cltbld', 20:05:29 INFO - 'LANG': 'en_US.UTF-8', 20:05:29 INFO - 'LANGUAGE': 'en_US:en', 20:05:29 INFO - 'LOGNAME': 'cltbld', 20:05:29 INFO - 'MAIL': '/var/mail/cltbld', 20:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:29 INFO - 'MOZ_NO_REMOTE': '1', 20:05:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:05:29 INFO - 'NO_EM_RESTART': '1', 20:05:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:29 INFO - 'PWD': '/builds/slave/test', 20:05:29 INFO - 'SHELL': '/bin/bash', 20:05:29 INFO - 'SHLVL': '1', 20:05:29 INFO - 'TERM': 'linux', 20:05:29 INFO - 'TMOUT': '86400', 20:05:29 INFO - 'USER': 'cltbld', 20:05:29 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', 20:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:29 INFO - '_': '/tools/buildbot/bin/python'} 20:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:05:29 INFO - Cleaning up... 20:05:29 INFO - Return code: 0 20:05:29 INFO - Running post-action listener: _resource_record_post_action 20:05:29 INFO - Running post-action listener: _start_resource_monitoring 20:05:29 INFO - Starting resource monitoring. 20:05:29 INFO - [mozharness: 2016-08-06 03:05:29.323757Z] Finished create-virtualenv step (success) 20:05:29 INFO - [mozharness: 2016-08-06 03:05:29.324190Z] Running install step. 20:05:29 INFO - Running pre-action listener: _resource_record_pre_action 20:05:29 INFO - Running main action method: install 20:05:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:05:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:05:29 INFO - Reading from file tmpfile_stdout 20:05:29 INFO - Detecting whether we're running mozinstall >=1.0... 20:05:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:05:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:05:29 INFO - Reading from file tmpfile_stdout 20:05:29 INFO - Output received: 20:05:29 INFO - Usage: mozinstall [options] installer 20:05:29 INFO - Options: 20:05:29 INFO - -h, --help show this help message and exit 20:05:29 INFO - -d DEST, --destination=DEST 20:05:29 INFO - Directory to install application into. [default: 20:05:29 INFO - "/builds/slave/test"] 20:05:29 INFO - --app=APP Application being installed. [default: firefox] 20:05:29 INFO - mkdir: /builds/slave/test/build/application 20:05:29 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'] 20:05:29 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 20:05:46 INFO - Reading from file tmpfile_stdout 20:05:46 INFO - Output received: 20:05:46 INFO - /builds/slave/test/build/application/firefox/firefox 20:05:46 INFO - Running post-action listener: _resource_record_post_action 20:05:46 INFO - [mozharness: 2016-08-06 03:05:46.733540Z] Finished install step (success) 20:05:46 INFO - [mozharness: 2016-08-06 03:05:46.733787Z] Running run-tests step. 20:05:46 INFO - Running pre-action listener: _resource_record_pre_action 20:05:46 INFO - Running main action method: run_tests 20:05:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:05:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:05:46 INFO - Python 2.7.3 20:05:46 INFO - Return code: 0 20:05:46 INFO - grabbing minidump binary from tooltool 20:05:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:46 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 0x268d3b0>, '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 0x268e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x268e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:05:46 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 20:05:46 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 20:05:46 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 20:05:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:05:46 INFO - Return code: 0 20:05:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:05:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:05:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:05:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:05:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:05:46 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/TNMfJLCISr6tlwBUJNFOwA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--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 20:05:46 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/TNMfJLCISr6tlwBUJNFOwA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --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 20:05:46 INFO - Using env: {'DISPLAY': ':0', 20:05:46 INFO - 'HOME': '/home/cltbld', 20:05:46 INFO - 'LANG': 'en_US.UTF-8', 20:05:46 INFO - 'LANGUAGE': 'en_US:en', 20:05:46 INFO - 'LOGNAME': 'cltbld', 20:05:46 INFO - 'MAIL': '/var/mail/cltbld', 20:05:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:05:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:05:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:05:46 INFO - 'MOZ_NO_REMOTE': '1', 20:05:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:05:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:05:46 INFO - 'NO_EM_RESTART': '1', 20:05:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:05:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:05:46 INFO - 'PWD': '/builds/slave/test', 20:05:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:05:46 INFO - 'SHELL': '/bin/bash', 20:05:46 INFO - 'SHLVL': '1', 20:05:46 INFO - 'TERM': 'linux', 20:05:46 INFO - 'TMOUT': '86400', 20:05:46 INFO - 'USER': 'cltbld', 20:05:46 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485', 20:05:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:05:46 INFO - '_': '/tools/buildbot/bin/python'} 20:05:46 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/TNMfJLCISr6tlwBUJNFOwA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--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 20:05:47 INFO - mozversion application_buildid: 20160805173004 20:05:47 INFO - mozversion application_changeset: 1646172ac6e709f5abdecbd8edbb53a54392428a 20:05:47 INFO - mozversion application_display_name: Nightly 20:05:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:05:47 INFO - mozversion application_name: Firefox 20:05:47 INFO - mozversion application_remotingname: firefox 20:05:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:05:47 INFO - mozversion application_vendor: Mozilla 20:05:47 INFO - mozversion application_version: 51.0a1 20:05:47 INFO - mozversion platform_buildid: 20160805173004 20:05:47 INFO - mozversion platform_changeset: 1646172ac6e709f5abdecbd8edbb53a54392428a 20:05:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:05:47 INFO - mozversion platform_version: 51.0a1 20:05:47 INFO - using testdate: 1470452747 20:05:47 INFO - actual date: 1470452747 20:05:47 INFO - starting webserver on 'localhost:34420' 20:05:47 INFO - SUITE-START | Running 2 tests 20:05:47 INFO - TEST-START | dromaeo_css 20:05:47 INFO - operating with platform_type : linux_ 20:05:47 INFO - Initialising browser for dromaeo_css test... 20:05:47 INFO - TEST-INFO | started process 11297 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRI_UsY/profile http://localhost:34420/getInfo.html) 20:05:51 INFO - PROCESS | 11297 | __metrics Screen width/height:1600/1200 20:05:51 INFO - PROCESS | 11297 | colorDepth:24 20:05:51 INFO - PROCESS | 11297 | Browser inner width/height: 1024/697 20:05:51 INFO - PROCESS | 11297 | __metrics 20:05:51 INFO - PROCESS | 11297 | 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" 20:05:51 INFO - PROCESS | 11297 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:05:52 INFO - TEST-INFO | 11297: exit 0 20:05:52 INFO - Browser initialized. 20:05:52 INFO - Running cycle 1/1 for dromaeo_css test... 20:05:52 INFO - TEST-INFO | started process 11378 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRI_UsY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:06:04 INFO - PROCESS | 11378 | RSS: Main: 156655616 20:06:04 INFO - PROCESS | 11378 | 20:06:56 INFO - PROCESS | 11378 | 1470452816033 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] 20:09:05 INFO - PROCESS | 11378 | Cycle 1(1): loaded http://localhost:34420/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34420/tests/dromaeo/cssquery-ext.html) 20:09:06 INFO - PROCESS | 11378 | RSS: Main: 252248064 20:09:06 INFO - PROCESS | 11378 | 20:12:09 INFO - PROCESS | 11378 | Cycle 1(1): loaded http://localhost:34420/tests/dromaeo/cssquery-ext.html (next: http://localhost:34420/tests/dromaeo/cssquery-jquery.html) 20:12:09 INFO - PROCESS | 11378 | RSS: Main: 240451584 20:12:09 INFO - PROCESS | 11378 | 20:15:11 INFO - PROCESS | 11378 | Cycle 1(1): loaded http://localhost:34420/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34420/tests/dromaeo/cssquery-mootools.html) 20:15:12 INFO - PROCESS | 11378 | RSS: Main: 237989888 20:15:12 INFO - PROCESS | 11378 | 20:18:14 INFO - PROCESS | 11378 | Cycle 1(1): loaded http://localhost:34420/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34420/tests/dromaeo/cssquery-prototype.html) 20:18:14 INFO - PROCESS | 11378 | RSS: Main: 218931200 20:18:14 INFO - PROCESS | 11378 | 20:21:17 INFO - PROCESS | 11378 | Cycle 1(1): loaded http://localhost:34420/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34420/tests/dromaeo/cssquery-yui.html) 20:21:18 INFO - PROCESS | 11378 | RSS: Main: 186556416 20:21:18 INFO - PROCESS | 11378 | 20:24:20 INFO - PROCESS | 11378 | Cycle 1(1): loaded http://localhost:34420/tests/dromaeo/cssquery-yui.html (next: http://localhost:34420/tests/dromaeo/cssquery-dojo.html) 20:24:21 INFO - PROCESS | 11378 | RSS: Main: 277434368 20:24:21 INFO - PROCESS | 11378 | 20:24:21 INFO - PROCESS | 11378 | __start_tp_report 20:24:21 INFO - PROCESS | 11378 | _x_x_mozilla_page_load 20:24:21 INFO - PROCESS | 11378 | _x_x_mozilla_page_load_details 20:24:21 INFO - PROCESS | 11378 | |i|pagename|runs| 20:24:21 INFO - PROCESS | 11378 | |0;dojo.html;2953.5274356103023;3086.1204556701346;3108.331708682633;3108.751299896008;3114.7041031102053;5132.050570644434;5192.299039629658;5275.419703832576;5352.03663340599;5382.354117505895;5318.734063296839;5320.414754377025;5353.0899747042995;5392.137258038705;5405.783768649249;4744.478107408191;4786.641001924852;4839;4840.733759643218;4987.650864439491;4856.174450343432;4875.4393244776775;4890.77991490382;4896.338994235768;4908.435529914052;4691.999999999983;4746.121967436035;4773.618110551148;4777.307290442866;4777.4744778074555;4740.597049250783;4741.762911854394;4757.310190022462;4803.495632958524;4847.951520484785;4796.232602783538;4813.807447702087;4816.084943860656;4842.080004799077;4869.82955596556;5181.637285390024;5304.761285742151;5366.892662146735;5385.3268341457315;5390.757415916254;5791.652500849962;5847.532197424196;5850;5859.501942334907;5880.029795083806;5827.155062515953;5832.854178645525;5839.970800146015;5848.88302233953;5877.617954832922;5069.569086627641;5274.55166310864;5353.678779273256;5364.731763411853;5463.562914966793;5911.261092363455;5983.760649573968;5985.790497332572;5988.700564971734;5990.071538911476;5740.368559458456;5742.770289188469;5748.45389687979;5753.971230143906;5759.769609215668;5502.064649009678;5652.773889044474;5697.487226149667;5701;5791;5691.743871525791;5696.3164420269;5702.2301989231755;5704.771809127671;5706;5664.943350566524;5667.914981275284;5719.456651618089;5730.770769169187;5733.283339582552;4275.508316543637;4308.956910430856;4324.308110702273;4361.847335343247;4366.541513141138;3846.1538461538416;3910.7262491625315;3913.9021524462114;3929.5284565851707;3930.253251882133;5450.5639548836;5451.182322651554;5452.754626041838;5496.6701997880255;5496.972515137399;5743.368229494752;5747.770089196385;5749.913751293734;5754.1944127822135;5755.280589926259;5193.558547523503;5331.173668081454;5391.218273350342;5439.374471935699;5443.047466693313;4903.092927808367;4919.827806026772;4921.753912304371;4928.48250933654;4938.08645400602;5942.227510423617;5948.434898684618;5951;5957.314908785545;5957.3744756799915;5541.307336582927;5651.8304450866535;5711.086226203954;5756.30924289088;5796.101604251347;5941.168236446987;5941.673207973603;5942.10868369748;5975.342712301556;5989.790357337472;5614.747336369955;5665.716714164358;5667.829965101054;5672.177534257427;5674.914876276776;5547;5637.238972738628;5682.318121825408;5700.942990570207;5776.653400795965;5589.748461319332;5589.80435684749;5592.3848376677715;5626.521745651502;5640.0693885508435;5574.163875418558;5581.832545023656;5583.357913839798;5583.944160558342;5585.273914391103;5346.385165705838;5478.233047373451;5508.063629182969;5516.393196748274;5529.502344788988;5685.51673107774;5719.48524632785;5721.713914304351;5733.8566535836935;5738.368779434176;5493.587980901529;5538.086215774352;5541.889162216814;5554.91667624994;5556.055470570093 20:24:21 INFO - PROCESS | 11378 | |1;ext.html;599.7511032921335;610.7770663707748;612.1857928954491;631.5137344244931;642.2325321241117;32186;35286.29427411454;35298.29403411933;42499.15001699964;43713.12573748523;44654.553454465444;45047.32429013559;45054.324185137164;45098.32352514715;45100.54899451004;8803.339749518796;8950.239229665487;8950.86573701395;8954.910450895473;8957.283417326598;8792.07683193262;8805;8873.378863479558;8889.4666320021;8894.421862578944;8917.152870477326;8958.059403762609;8970.058143894896;8971.192592666659;8973.95513022434;8895.599698013573;8904.287656987426;8908.643654253821;8915.643374265022;8939.73180804577;8874.733757987271;8929.642814287421;8936.776580585472;8937.64249430022;8949.776255593597;42876.78561607203;43166.35250471246;43205;43256.13487730228;43350.78324608388;3505.8772942946875;3549.201429678302;3625.3111908737255;3687.557493100818;4018.859339923118;33315.059208165614;33653.6634633653;33977;34607.4808877867;35228.823855880815;4253.829846806124;4304.827806887721;4305.63402110821;4438.533953934855;4485.932711009337;370593;372799;372959;376351;376405.1179744111;61705.691471542355;62021.68989155025;62099;62119.68940155361;62236.68881655563;38582.22835543274;39598.802005990365;39621;39648.60351396507;65423.67288163624;16359.182040897907;16441.26014329358;16497.505074847773;16525.421610243582;16528.75206871898;12361.814572781415;12564.560240391726;12565.246085234916;12573.685657858627;12574.81137782934;19425.0644703438;19484.20649639293;19526.97517267496;19563.339707328832;19644;7048.929510704827;7247.528910620898;7327.377172940253;7375.299346562068;7412.221716719775;1015.8780946286345;1017.165923942375;1135.1940122512942;1138.481990694232;1148.7989601819647;33276.83361583225;33489.83255083709;33582.160445989044;33860.49209261863;34144.4634991426;1122.938238396896;1132.3036332655358;1146.1976616368574;1154.3766366162179;1182.5388098641536;16867.83132168687;17149.399771008204;17378.565535861453;17413.3034678614;17434.825651743573;580896;909010;921037;922791;923309;60662.090068649006;61061;61300;61386.940501841964;61564.38435615587;1256.9560065397668;1375.9862401376058;1377.4903285784324;1390;1414.9717005660034;29554.113376598736;33705.49441758425;33796.32407351888;33974.49038264379;34408;50096;50715.23927141022;50718.492815071375;50762;50783.238251427;433390;434699;436687;440135;441509;29128.854355728086;29788.553171702875;29814.85092574523;30057.54913676253;31361.529577055906;49300.50699493103;49418.25872611986;49762.25356619581;50051.749741251064;54128.458715412344;42617.78691106525;42998.570014299454;43143.56856431396;43780;45447.77276113598;42530.362044568734;42798.572014280704;43452.78273608612;43486;44752.55247447484;36017.279654406244;44317.55682443134;45236;45612.31581526213;45867.77066114781;41764.3735343964;44965.77517112524;45280.54719452763;46414.535854642374;46726.766366167954;34556.30887382188;35942.281154376244;35993.28013439769;35995.28009439849;36080.63919360773 20:24:21 INFO - PROCESS | 11378 | |2;jquery.html;370.9610490898455;371.1945079178185;372.225770397573;386.3798603241797;389.6473691309366;18588.62822743544;20044.398668039947;20639.17443302266;20968.475788105305;21246.15015399384;19165.1375688094;19416.4175074748;19815.5046123847;20096.49758756032;20154;5297.708626025567;5478.150886612581;5586.385497595261;5719.742611582468;5762.3373312069225;5862.912056319158;5892.852678683046;5903.616264942786;5904.498117660004;5909.290885093795;5724.055530837407;5729.05470597353;5748.338941021773;5762.250907382035;5848.385919478457;5490.725463726818;5618.971905140448;5620.735334549735;5626.943730562703;5753.165790960308;4935.827246046407;4953.380827396575;4985.077760614298;4985.202367621199;4993.900121997576;23440.17959371414;24158.758412415827;24267.27198184057;24499.755002449925;24528.509429811482;2157.5037741319584;2278.054607337953;2358.870262135582;2419.9637005444756;3136.6079240094987;9878.160356369717;10141.340812847215;10170.898291017069;10180.541875615616;10342;1896.895670738109;1986.6225417170692;1995.2318357432348;2011.8289945354663;2029.4926268432891;82196.58901705513;84781;85127.5743621278;86481.70277445843;86834;11133.387663678413;11271.267367621078;11314.547418103348;11430.028447581883;11509.25189862673;16025.118618476095;16066.75899861503;16084.115373654562;16091.356345746273;16104.919475402783;7970;8085.636146373427;8086.272235498834;8286.70996515131;8348.791280217922;7655;7805.453618246782;7921.089074756477;7954;8123.390745694097;4759.214729569651;4883.902321953612;4901.43633482147;4938.4320803107385;5073.670211436245;1932.845372370207;1990.8307793837687;2001.559656875485;2060.423081537172;2060.6702927531824;914.5153068873508;1019.8164330420448;1072.3083611070972;1098.1873413673825;1106.4523061084653;19803.900980495004;20456.69314960277;20615.38153855387;20659.97560829325;20903;1023.2019025160387;1044.2637940252105;1057.4289883462998;1072.84443755655;1080.0981180714034;10938.234323597271;11507.07943364529;11529.308241505536;11547.133964952662;11564.826527601925;501740;502804;506097;526295;528423;74541.62729186319;90478.54760726287;90830.54584727166;91235.54382228047;91881;951.4434056077134;989.0900371658092;1012.2964539644876;1015.9136473399844;1018.7809620931536;17104.144792760064;17878.19548120364;18005.189766460804;18170.72743908816;18322;32780;42173.789131054145;44101;44134.55865441304;44437.555624444634;60707;61075.389246108745;61307.693461532406;62747.6862615684;63304.366956329846;8540.188682075193;8568.657253709782;8673.956630216808;8714.825703486022;8802.515861627417;12372.81440778407;12470.251784892935;12937.223766574036;13081.345932703518;13145.934270328587;10999.065079468335;11325.546978120783;11416.31502109876;11475.254108482923;11822.349770762346;11101.396417776288;11235.943820280847;11276.887231127585;11701;11760.58837940668;11542;11702.473388697359;11761.235519691192;12045.156839021185;12447.813282800584;8358.28954538871;8522.190391912758;8560.272376848037;8583.65665373378;8755.168259015638;5513.558915286848;10681.305715128492;10803.45982700846;10961.616343427942;11494.655160345203 20:24:21 INFO - PROCESS | 11378 | |3;mootools.html;1361.63916562111;1453.6002599285198;1523.794287771151;1650.1254335202339;1774.095211442165;8299.58502074897;8638.395312328132;8752.21230089291;8927.642894284238;8935.374523783337;8669.739907802761;8790.604422800974;8814.33892458065;8818.77953051174;8825.735227943173;2330.9650355244726;2354.540864531417;2361.51588924271;2363.1847012780563;2363.326451961191;2292.3352227853984;2354.3407845803204;2356.22244659262;2362.7164740231196;2365.089440565386;2265.1166045242367;2285.245868863271;2287.645414960684;2293.54129174165;2294.804941579968;2159.730033745782;2172.837037222199;2175.3147758456116;2180.9563808723897;2184.967225491611;2224;2227.944301392462;2235.407616981501;2235.61994460942;2237.284069097889;8711.69509067186;8805.383623146383;8810.38327317088;8810.86783698245;8923.955380223122;5063;5240.528352448298;5249.396319423277;5253.7635806388425;5266.4733526647415;3219.114743445548;3239.1902024493875;3252.203210213489;3252.609686837595;3281.343731253759;1060.3352736204163;1064.1912146768434;1076.1390887290136;1080.8000519903842;1095.8520599719016;12023.27860328383;12120.81818772719;12140.45367958444;12178.878211217952;12198.878011219775;4228.1966426378885;4408.4930233023615;4434.645228381722;4455.2648812946145;4508.211063063951;9593;9607.231421486404;9611.471369074769;9618.759531011781;9619.615215391446;5160.612954028463;5163.044836705221;5163.922541161885;5171.043356978971;5176.404713457925;4268.445102136702;4312.827486900552;4456.866294011184;4846;4847.95152048475;3759.9436008459893;3786.3941769316775;3866.4200369944733;3888.669463095612;3905.4922860028014;1081.5017310013893;1084.9349039057524;1095.9452027398759;1101.6805126513382;1108.3128460354633;935.9204467620192;976.9023097690316;979.2264111351952;979.7550612346913;987.017917172418;5911.674857882857;5947.405259474018;5999.880002400015;6051.909221361683;6098.634081955097;450.05937590436145;451.69510580358127;455.84045584045316;458.07697489558564;462.7570525474086;4172.7913604320265;4190.832366705297;4198.286291330481;4206.0956894267265;4229.407882896397;10784.622538211124;11124.61063862761;11130.109591232844;11154.330740155616;11165.218434709655;10957.671269861918;11056.668299951014;11071.612493562687;11093.445327733582;11103.38931358783;7837.09873364559;8198.508089514648;8200.302974247255;8288.668453261922;8324.417290789708;5881.38245484218;5909.054551271776;6008.579399441997;6050.878982420415;6158.107074474266;10051.798964020825;10110.140638045554;10310.175185985254;10313.896861031293;10316;9794.363366381162;9900.108990190918;9986.700398988041;10025.899741002497;10294.433806140734;6930.930690693231;7598.468107232441;7708.075030996316;7710.575918324546;7943.44395892282;8585.227329540372;8917.197452229331;8934.687285944961;8949.32880033987;8956.865647015169;7732.381409487115;7765.381696240127;7873.645685944031;7981.800454988672;8011;7311.4516411268305;7650.081990161217;7722.459427839997;7722.575258360844;8010.599470026359;7043.2604576518725;7167.784966451015;7179.353858152791;7203.603801790951;7204.675789589376;5361.678299302054;5403.270558474555;5424.484673955968;5460.918086228789;5636.239107720406;5036.974815125901;5146.511081447256;5188.247704082963;5200.947990520046;5327.627066105335 20:24:21 INFO - PROCESS | 11378 | |4;prototype.html;299.6194832562646;310.5419506228312;321.7088534875937;323.17912502244303;329.7774002548279;5130.589552835773;5159.5295340827915;5171.870703232421;5173.017126745915;5225.686458812474;4882.780274887629;4998.600391890267;5031.597472202224;5056.570191533715;5185.429602743695;3886.455896174538;3889.483101590382;3967.504061992251;4005.4792876926103;4051.966748479134;4018.3570628699413;4027.2549578327958;4028.8388464461386;4038.172174704179;4043.7666511713915;3808.9622052202076;3980.9004774880714;4002.599740026003;4003;4016;3985.9202815943518;3986.880393588197;3999.360102383619;4001.8799436016966;4005.359142537209;3527.871081254;3539.592946811124;3543.6179889842983;3557.964420355789;3558.484019817112;4880.755962201875;5012.122878496249;5013.498650134994;5013.949860501385;5014.147594908874;4540.841070562547;4579.290210017452;4579.725216486988;4587.977060114678;4590.724556526619;5285.841424757264;5298.125809241471;5299.49654782795;5322.441143679896;5336.626436149432;3888.319544079775;3889.9027524311837;3899.7075219358376;3901.6878649707955;3903;5353.304070470853;5455.890882182334;5493.835184944418;5532.419095994943;5543.477717638465;3969.4244334571326;3972.523297204354;3983.940240896389;3984.960150398459;3994.360902255625;4010.859619913289;4018.8392464301687;4021.5777343378522;4025.255327764373;4028.4762980812307;4440.245158323093;4509.323601459741;4527.841525546656;4564.7032942858605;4578.2903649934315;5364.946350536445;5367.409584945653;5367.892642147135;5370.597205209547;5370.758315875795;1364.1405914273944;1371.533678549298;1374.113696665645;1376.3462355381114;1377.6900197455693;878.0429332027992;945.9574319155655;947.15229869268;963.7927845513249;965.3001573858896;5132.332796736475;5135.460776618401;5141.331626888481;5141.640085194075;5154.484551544815;5400;5407.729613519308;5415.783368665209;5420;5429.945700543023;4982.750862456863;5145.562627176732;5215.269862219292;5240.554552863049;5263.473652634706;2900.5359142537513;2929.750971167432;2933.8386388748822;2934.0611004478787;2935.9448386129793;5412.619781955655;5653;5675.716214189274;5695.259616249861;5711.885762284731;5624.690642014729;5639.351474580394;5656.9817432861655;5657.321121465368;5673.602847800615;5666.773329066791;5670.213882626959;5701.11632696941;5703.885922281531;5715.228444160151;5250.18622113573;5539.504333829763;5546;5560.527355174693;5560.527355174855;5481.890362192814;5513.558915286848;5524.508382736558;5526.143447765603;5535.640183388067;5578.442155784389;5702.515286200553;5712.314522257356;5714.457126572969;5714.514266287412;3933.645971862546;3953.565107838193;3958.366661334173;3981.9339108886575;3984.621460961204;5538.889222215614;5538.944610553843;5539.473749993744;5547.750351234285;5568.972155139199;5224.242484839601;5391.59563032782;5404.891902161856;5410.918836217381;5424.511793938565;5346.8395948121615;5401.810936617357;5411.134218525174;5412.269343638558;5473.233747275305;5080.847574572768;5230.843074707765;5332.760025798926;5355.892882142258;5367.114426119647;5416.864578385572;5439.156930675752;5459.454054594509;5464.781408743605;5467.39858615544;5312.184542192644;5320.57435405159;5326.067938110893;5332.440093790251;5342.278792363137 20:24:21 INFO - PROCESS | 11378 | |5;yui.html;753.8115471132218;766.5132640773107;818.3371469110019;839.38305345571;850.8808766772656;17701.911490442544;18314.08429578519;18343.724844127333;18364.90817545914;18372.265109395626;16643.66712665749;16702.164891755423;16717.91641041796;16718.41485548006;16750.66498670026;680.170192365316;818.1246066708624;841.6969890839293;844.6114787197865;845.6659619450344;807.3904202327235;830.5265998380926;831.3224721851672;844.3414136973171;844.9366297527679;878.3719640457072;879.2043200903192;879.2087121590556;879.4371602174581;879.520661239626;696.571608460793;869.447900583128;876.5617191404298;877.4472082587961;877.7191298784392;622.3527531367417;636.3604577399684;637.2512298049772;642.0914406115342;642.489221069251;14394.208318542476;14431.061980971203;14511.854881451156;14538.927305363512;14546;14048.789268160986;14077.366518506691;14257.643558911006;14839.851601483955;14939;13746;13783.241921694304;13875.098118622258;14180.787288190686;14650.63373415673;4370;4384.9561504384865;4433.13553856998;4565.20108980927;4713.764311784432;90726;91115;92360;93904.53047734854;93964;11556.469566432701;11644.3595602241;11741.354225517507;11771.941140294244;11789.528418863149;22138.446538836335;22276.108955641917;22355.32934011982;22397.552048959256;22521.774782252294;8910.688125915562;8947;8989.955050224708;9212.401193922507;9357;4930.556249937523;4964.826231081895;5028.17035189197;5102.285680004802;5107.591392688576;3376.662333766653;3475.600305964796;3478.7390945679176;3481.112316359312;3485.9477107843404;588.3939542271468;588.808637192914;591.3436085944599;591.6538824787461;592.0674936974283;681.3731367142142;682.2358957967109;686.615495322621;687.965601719912;713.2974020589714;8879.733607991771;9103.544822758835;9613.90386096144;9681.467519286365;9690;562.5612022622361;572.373251289837;585.6632436349031;615.8897567285281;698.1691786773723;4117;4248.299030659907;4531.614812740888;4639.164950308909;4706.764661766898;76934.23065769383;77268.22731772723;128550.42899141964;133580;135701;117259.41370293093;119092;119183;121988.39005805092;123101;56223.437765622635;57288.713556431954;81618.59190704009;82650.58674706708;94615;9215.677451289173;9449.669261575811;9603.183729383081;9994.950025249827;10421.166306695597;50584.49415505945;53284;54296.18555721588;55017;55564;68172.659136704;69400;82674.58662706648;82744.58627706823;84940.15059849323;12770.16993895431;13062.54281100195;13092;13315.866841331463;13399;14212.573622791333;14218.928905355408;14241.686457850787;14941.103533788008;15097.698046039237;13931.72136557243;13998.720025599634;14412.279386030867;14420.711585768435;14516.20160891119;13213.144341461835;14006.159630422208;14197.716045679235;14233.146011239358;15229.923850380677;12791.744165116459;12974.675633109247;13164.275964822027;13325.666858328232;13780.310984450938;11187.272827266203;11208.775824483628;11226.55093796239;11667.941660291644;11842.230255033395;10809.837852432378;10820.513076911715;11064.944675276573;11249.38128402913;11573.768524629291 20:24:21 INFO - PROCESS | 11378 | __end_tp_report 20:24:21 INFO - PROCESS | 11378 | __start_cc_report 20:24:21 INFO - PROCESS | 11378 | _x_x_mozilla_cycle_collect,915 20:24:21 INFO - PROCESS | 11378 | __end_cc_report 20:24:21 INFO - PROCESS | 11378 | __startTimestamp1470453861138__endTimestamp 20:24:21 INFO - PROCESS | 11378 | 20:24:21 INFO - PROCESS | 11378 | ------- Summary: start ------- 20:24:21 INFO - PROCESS | 11378 | Number of tests: 6 20:24:21 INFO - PROCESS | 11378 | 20:24:21 INFO - PROCESS | 11378 | [#0] dojo.html Cycles:165 Average:5317.71 Median:5538.09 stddev:627.24 (11.3%) stddev-sans-first:601.12 20:24:21 INFO - PROCESS | 11378 | Values: 2953.5 3086.1 3108.3 3108.8 3114.7 5132.1 5192.3 5275.4 5352.0 5382.4 5318.7 5320.4 5353.1 5392.1 5405.8 4744.5 4786.6 4839.0 4840.7 4987.7 4856.2 4875.4 4890.8 4896.3 4908.4 4692.0 4746.1 4773.6 4777.3 4777.5 4740.6 4741.8 4757.3 4803.5 4848.0 4796.2 4813.8 4816.1 4842.1 4869.8 5181.6 5304.8 5366.9 5385.3 5390.8 5791.7 5847.5 5850.0 5859.5 5880.0 5827.2 5832.9 5840.0 5848.9 5877.6 5069.6 5274.6 5353.7 5364.7 5463.6 5911.3 5983.8 5985.8 5988.7 5990.1 5740.4 5742.8 5748.5 5754.0 5759.8 5502.1 5652.8 5697.5 5701.0 5791.0 5691.7 5696.3 5702.2 5704.8 5706.0 5664.9 5667.9 5719.5 5730.8 5733.3 4275.5 4309.0 4324.3 4361.8 4366.5 3846.2 3910.7 3913.9 3929.5 3930.3 5450.6 5451.2 5452.8 5496.7 5497.0 5743.4 5747.8 5749.9 5754.2 5755.3 5193.6 5331.2 5391.2 5439.4 5443.0 4903.1 4919.8 4921.8 4928.5 4938.1 5942.2 5948.4 5951.0 5957.3 5957.4 5541.3 5651.8 5711.1 5756.3 5796.1 5941.2 5941.7 5942.1 5975.3 5989.8 5614.7 5665.7 5667.8 5672.2 5674.9 5547.0 5637.2 5682.3 5700.9 5776.7 5589.7 5589.8 5592.4 5626.5 5640.1 5574.2 5581.8 5583.4 5583.9 5585.3 5346.4 5478.2 5508.1 5516.4 5529.5 5685.5 5719.5 5721.7 5733.9 5738.4 5493.6 5538.1 5541.9 5554.9 5556.1 20:24:21 INFO - PROCESS | 11378 | 20:24:21 INFO - PROCESS | 11378 | [#1] ext.html Cycles:180 Average:70261.45 Median:43230.57 stddev:161306.18 (373.1%) stddev-sans-first:161673.89 20:24:21 INFO - PROCESS | 11378 | Values: 599.8 610.8 612.2 631.5 642.2 32186.0 35286.3 35298.3 42499.2 43713.1 44654.6 45047.3 45054.3 45098.3 45100.5 8803.3 8950.2 8950.9 8954.9 8957.3 8792.1 8805.0 8873.4 8889.5 8894.4 8917.2 8958.1 8970.1 8971.2 8974.0 8895.6 8904.3 8908.6 8915.6 8939.7 8874.7 8929.6 8936.8 8937.6 8949.8 42876.8 43166.4 43205.0 43256.1 43350.8 3505.9 3549.2 3625.3 3687.6 4018.9 33315.1 33653.7 33977.0 34607.5 35228.8 4253.8 4304.8 4305.6 4438.5 4485.9 370593.0 372799.0 372959.0 376351.0 376405.1 61705.7 62021.7 62099.0 62119.7 62236.7 38582.2 39598.8 39621.0 39648.6 65423.7 16359.2 16441.3 16497.5 16525.4 16528.8 12361.8 12564.6 12565.2 12573.7 12574.8 19425.1 19484.2 19527.0 19563.3 19644.0 7048.9 7247.5 7327.4 7375.3 7412.2 1015.9 1017.2 1135.2 1138.5 1148.8 33276.8 33489.8 33582.2 33860.5 34144.5 1122.9 1132.3 1146.2 1154.4 1182.5 16867.8 17149.4 17378.6 17413.3 17434.8 580896.0 909010.0 921037.0 922791.0 923309.0 60662.1 61061.0 61300.0 61386.9 61564.4 1257.0 1376.0 1377.5 1390.0 1415.0 29554.1 33705.5 33796.3 33974.5 34408.0 50096.0 50715.2 50718.5 50762.0 50783.2 433390.0 434699.0 436687.0 440135.0 441509.0 29128.9 29788.6 29814.9 30057.5 31361.5 49300.5 49418.3 49762.3 50051.7 54128.5 42617.8 42998.6 43143.6 43780.0 45447.8 42530.4 42798.6 43452.8 43486.0 44752.6 36017.3 44317.6 45236.0 45612.3 45867.8 41764.4 44965.8 45280.5 46414.5 46726.8 34556.3 35942.3 35993.3 35995.3 36080.6 20:24:21 INFO - PROCESS | 11378 | 20:24:21 INFO - PROCESS | 11378 | [#2] jquery.html Cycles:180 Average:29839.00 Median:13343.62 stddev:84526.34 (633.5%) stddev-sans-first:84734.50 20:24:21 INFO - PROCESS | 11378 | Values: 371.0 371.2 372.2 386.4 389.6 18588.6 20044.4 20639.2 20968.5 21246.2 19165.1 19416.4 19815.5 20096.5 20154.0 5297.7 5478.2 5586.4 5719.7 5762.3 5862.9 5892.9 5903.6 5904.5 5909.3 5724.1 5729.1 5748.3 5762.3 5848.4 5490.7 5619.0 5620.7 5626.9 5753.2 4935.8 4953.4 4985.1 4985.2 4993.9 23440.2 24158.8 24267.3 24499.8 24528.5 2157.5 2278.1 2358.9 2420.0 3136.6 9878.2 10141.3 10170.9 10180.5 10342.0 1896.9 1986.6 1995.2 2011.8 2029.5 82196.6 84781.0 85127.6 86481.7 86834.0 11133.4 11271.3 11314.5 11430.0 11509.3 16025.1 16066.8 16084.1 16091.4 16104.9 7970.0 8085.6 8086.3 8286.7 8348.8 7655.0 7805.5 7921.1 7954.0 8123.4 4759.2 4883.9 4901.4 4938.4 5073.7 1932.8 1990.8 2001.6 2060.4 2060.7 914.5 1019.8 1072.3 1098.2 1106.5 19803.9 20456.7 20615.4 20660.0 20903.0 1023.2 1044.3 1057.4 1072.8 1080.1 10938.2 11507.1 11529.3 11547.1 11564.8 501740.0 502804.0 506097.0 526295.0 528423.0 74541.6 90478.5 90830.5 91235.5 91881.0 951.4 989.1 1012.3 1015.9 1018.8 17104.1 17878.2 18005.2 18170.7 18322.0 32780.0 42173.8 44101.0 44134.6 44437.6 60707.0 61075.4 61307.7 62747.7 63304.4 8540.2 8568.7 8674.0 8714.8 8802.5 12372.8 12470.3 12937.2 13081.3 13145.9 10999.1 11325.5 11416.3 11475.3 11822.3 11101.4 11235.9 11276.9 11701.0 11760.6 11542.0 11702.5 11761.2 12045.2 12447.8 8358.3 8522.2 8560.3 8583.7 8755.2 5513.6 10681.3 10803.5 10961.6 11494.7 20:24:21 INFO - PROCESS | 11378 | 20:24:21 INFO - PROCESS | 11378 | [#3] mootools.html Cycles:180 Average:5765.00 Median:4846.98 stddev:3326.57 (68.6%) stddev-sans-first:3319.44 20:24:21 INFO - PROCESS | 11378 | Values: 1361.6 1453.6 1523.8 1650.1 1774.1 8299.6 8638.4 8752.2 8927.6 8935.4 8669.7 8790.6 8814.3 8818.8 8825.7 2331.0 2354.5 2361.5 2363.2 2363.3 2292.3 2354.3 2356.2 2362.7 2365.1 2265.1 2285.2 2287.6 2293.5 2294.8 2159.7 2172.8 2175.3 2181.0 2185.0 2224.0 2227.9 2235.4 2235.6 2237.3 8711.7 8805.4 8810.4 8810.9 8924.0 5063.0 5240.5 5249.4 5253.8 5266.5 3219.1 3239.2 3252.2 3252.6 3281.3 1060.3 1064.2 1076.1 1080.8 1095.9 12023.3 12120.8 12140.5 12178.9 12198.9 4228.2 4408.5 4434.6 4455.3 4508.2 9593.0 9607.2 9611.5 9618.8 9619.6 5160.6 5163.0 5163.9 5171.0 5176.4 4268.4 4312.8 4456.9 4846.0 4848.0 3759.9 3786.4 3866.4 3888.7 3905.5 1081.5 1084.9 1095.9 1101.7 1108.3 935.9 976.9 979.2 979.8 987.0 5911.7 5947.4 5999.9 6051.9 6098.6 450.1 451.7 455.8 458.1 462.8 4172.8 4190.8 4198.3 4206.1 4229.4 10784.6 11124.6 11130.1 11154.3 11165.2 10957.7 11056.7 11071.6 11093.4 11103.4 7837.1 8198.5 8200.3 8288.7 8324.4 5881.4 5909.1 6008.6 6050.9 6158.1 10051.8 10110.1 10310.2 10313.9 10316.0 9794.4 9900.1 9986.7 10025.9 10294.4 6930.9 7598.5 7708.1 7710.6 7943.4 8585.2 8917.2 8934.7 8949.3 8956.9 7732.4 7765.4 7873.6 7981.8 8011.0 7311.5 7650.1 7722.5 7722.6 8010.6 7043.3 7167.8 7179.4 7203.6 7204.7 5361.7 5403.3 5424.5 5460.9 5636.2 5037.0 5146.5 5188.2 5200.9 5327.6 20:24:21 INFO - PROCESS | 11378 | 20:24:21 INFO - PROCESS | 11378 | [#4] prototype.html Cycles:180 Average:4531.14 Median:5179.22 stddev:1325.90 (25.6%) stddev-sans-first:1291.02 20:24:21 INFO - PROCESS | 11378 | Values: 299.6 310.5 321.7 323.2 329.8 5130.6 5159.5 5171.9 5173.0 5225.7 4882.8 4998.6 5031.6 5056.6 5185.4 3886.5 3889.5 3967.5 4005.5 4052.0 4018.4 4027.3 4028.8 4038.2 4043.8 3809.0 3980.9 4002.6 4003.0 4016.0 3985.9 3986.9 3999.4 4001.9 4005.4 3527.9 3539.6 3543.6 3558.0 3558.5 4880.8 5012.1 5013.5 5013.9 5014.1 4540.8 4579.3 4579.7 4588.0 4590.7 5285.8 5298.1 5299.5 5322.4 5336.6 3888.3 3889.9 3899.7 3901.7 3903.0 5353.3 5455.9 5493.8 5532.4 5543.5 3969.4 3972.5 3983.9 3985.0 3994.4 4010.9 4018.8 4021.6 4025.3 4028.5 4440.2 4509.3 4527.8 4564.7 4578.3 5364.9 5367.4 5367.9 5370.6 5370.8 1364.1 1371.5 1374.1 1376.3 1377.7 878.0 946.0 947.2 963.8 965.3 5132.3 5135.5 5141.3 5141.6 5154.5 5400.0 5407.7 5415.8 5420.0 5429.9 4982.8 5145.6 5215.3 5240.6 5263.5 2900.5 2929.8 2933.8 2934.1 2935.9 5412.6 5653.0 5675.7 5695.3 5711.9 5624.7 5639.4 5657.0 5657.3 5673.6 5666.8 5670.2 5701.1 5703.9 5715.2 5250.2 5539.5 5546.0 5560.5 5560.5 5481.9 5513.6 5524.5 5526.1 5535.6 5578.4 5702.5 5712.3 5714.5 5714.5 3933.6 3953.6 3958.4 3981.9 3984.6 5538.9 5538.9 5539.5 5547.8 5569.0 5224.2 5391.6 5404.9 5410.9 5424.5 5346.8 5401.8 5411.1 5412.3 5473.2 5080.8 5230.8 5332.8 5355.9 5367.1 5416.9 5439.2 5459.5 5464.8 5467.4 5312.2 5320.6 5326.1 5332.4 5342.3 20:24:21 INFO - PROCESS | 11378 | 20:24:21 INFO - PROCESS | 11378 | [#5] yui.html Cycles:180 Average:21801.59 Median:4602.18 stddev:32135.36 (698.3%) stddev-sans-first:32186.64 20:24:21 INFO - PROCESS | 11378 | Values: 753.8 766.5 818.3 839.4 850.9 17701.9 18314.1 18343.7 18364.9 18372.3 16643.7 16702.2 16717.9 16718.4 16750.7 680.2 818.1 841.7 844.6 845.7 807.4 830.5 831.3 844.3 844.9 878.4 879.2 879.2 879.4 879.5 696.6 869.4 876.6 877.4 877.7 622.4 636.4 637.3 642.1 642.5 14394.2 14431.1 14511.9 14538.9 14546.0 14048.8 14077.4 14257.6 14839.9 14939.0 13746.0 13783.2 13875.1 14180.8 14650.6 4370.0 4385.0 4433.1 4565.2 4713.8 90726.0 91115.0 92360.0 93904.5 93964.0 11556.5 11644.4 11741.4 11771.9 11789.5 22138.4 22276.1 22355.3 22397.6 22521.8 8910.7 8947.0 8990.0 9212.4 9357.0 4930.6 4964.8 5028.2 5102.3 5107.6 3376.7 3475.6 3478.7 3481.1 3485.9 588.4 588.8 591.3 591.7 592.1 681.4 682.2 686.6 688.0 713.3 8879.7 9103.5 9613.9 9681.5 9690.0 562.6 572.4 585.7 615.9 698.2 4117.0 4248.3 4531.6 4639.2 4706.8 76934.2 77268.2 128550.4 133580.0 135701.0 117259.4 119092.0 119183.0 121988.4 123101.0 56223.4 57288.7 81618.6 82650.6 94615.0 9215.7 9449.7 9603.2 9995.0 10421.2 50584.5 53284.0 54296.2 55017.0 55564.0 68172.7 69400.0 82674.6 82744.6 84940.2 12770.2 13062.5 13092.0 13315.9 13399.0 14212.6 14218.9 14241.7 14941.1 15097.7 13931.7 13998.7 14412.3 14420.7 14516.2 13213.1 14006.2 14197.7 14233.1 15229.9 12791.7 12974.7 13164.3 13325.7 13780.3 11187.3 11208.8 11226.6 11667.9 11842.2 10809.8 10820.5 11064.9 11249.4 11573.8 20:24:21 INFO - PROCESS | 11378 | -------- Summary: end -------- 20:24:21 INFO - PROCESS | 11378 | 20:24:21 INFO - TEST-INFO | 11378: exit 0 20:24:21 INFO - TEST-OK | dromaeo_css | took 1114890ms 20:24:21 INFO - TEST-START | kraken 20:24:21 INFO - operating with platform_type : linux_ 20:24:21 INFO - Initialising browser for kraken test... 20:24:21 INFO - TEST-INFO | started process 11576 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPUFrEk/profile http://localhost:34420/getInfo.html) 20:24:25 INFO - PROCESS | 11576 | __metrics Screen width/height:1600/1200 20:24:25 INFO - PROCESS | 11576 | colorDepth:24 20:24:25 INFO - PROCESS | 11576 | Browser inner width/height: 1024/697 20:24:25 INFO - PROCESS | 11576 | __metrics 20:24:26 INFO - PROCESS | 11576 | 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" 20:24:26 INFO - PROCESS | 11576 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:24:27 INFO - TEST-INFO | 11576: exit 0 20:24:27 INFO - Browser initialized. 20:24:27 INFO - Running cycle 1/1 for kraken test... 20:24:27 INFO - TEST-INFO | started process 11658 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPUFrEk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:24:39 INFO - PROCESS | 11658 | RSS: Main: 157368320 20:24:39 INFO - PROCESS | 11658 | 20:24:41 INFO - PROCESS | 11658 | Cycle 1(1): loaded http://localhost:34420/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34420/tests/kraken/driver.html?testName=audio-beat-detection) 20:24:42 INFO - PROCESS | 11658 | RSS: Main: 213475328 20:24:42 INFO - PROCESS | 11658 | 20:24:45 INFO - PROCESS | 11658 | Cycle 1(1): loaded http://localhost:34420/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34420/tests/kraken/driver.html?testName=audio-dft) 20:24:45 INFO - PROCESS | 11658 | RSS: Main: 193384448 20:24:45 INFO - PROCESS | 11658 | 20:24:49 INFO - PROCESS | 11658 | Cycle 1(1): loaded http://localhost:34420/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34420/tests/kraken/driver.html?testName=audio-fft) 20:24:49 INFO - PROCESS | 11658 | RSS: Main: 269221888 20:24:49 INFO - PROCESS | 11658 | 20:24:51 INFO - PROCESS | 11658 | Cycle 1(1): loaded http://localhost:34420/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34420/tests/kraken/driver.html?testName=audio-oscillator) 20:24:52 INFO - PROCESS | 11658 | RSS: Main: 179298304 20:24:52 INFO - PROCESS | 11658 | 20:24:54 INFO - PROCESS | 11658 | Cycle 1(1): loaded http://localhost:34420/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34420/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:24:55 INFO - PROCESS | 11658 | RSS: Main: 184934400 20:24:55 INFO - PROCESS | 11658 | 20:24:58 INFO - PROCESS | 11658 | Cycle 1(1): loaded http://localhost:34420/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34420/tests/kraken/driver.html?testName=imaging-darkroom) 20:24:58 INFO - PROCESS | 11658 | RSS: Main: 220123136 20:24:58 INFO - PROCESS | 11658 | 20:25:02 INFO - PROCESS | 11658 | Cycle 1(1): loaded http://localhost:34420/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34420/tests/kraken/driver.html?testName=imaging-desaturate) 20:25:02 INFO - PROCESS | 11658 | RSS: Main: 219910144 20:25:02 INFO - PROCESS | 11658 | 20:25:05 INFO - PROCESS | 11658 | Cycle 1(1): loaded http://localhost:34420/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34420/tests/kraken/driver.html?testName=json-parse-financial) 20:25:06 INFO - PROCESS | 11658 | RSS: Main: 219684864 20:25:06 INFO - PROCESS | 11658 | 20:25:07 INFO - PROCESS | 11658 | Cycle 1(1): loaded http://localhost:34420/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34420/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:25:08 INFO - PROCESS | 11658 | RSS: Main: 184217600 20:25:08 INFO - PROCESS | 11658 | 20:25:09 INFO - PROCESS | 11658 | Cycle 1(1): loaded http://localhost:34420/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34420/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:25:10 INFO - PROCESS | 11658 | RSS: Main: 189755392 20:25:10 INFO - PROCESS | 11658 | 20:25:12 INFO - PROCESS | 11658 | Cycle 1(1): loaded http://localhost:34420/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34420/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:25:13 INFO - PROCESS | 11658 | RSS: Main: 193720320 20:25:13 INFO - PROCESS | 11658 | 20:25:15 INFO - PROCESS | 11658 | Cycle 1(1): loaded http://localhost:34420/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34420/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:25:16 INFO - PROCESS | 11658 | RSS: Main: 189288448 20:25:16 INFO - PROCESS | 11658 | 20:25:19 INFO - PROCESS | 11658 | Cycle 1(1): loaded http://localhost:34420/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34420/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:25:19 INFO - PROCESS | 11658 | RSS: Main: 193040384 20:25:19 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | Cycle 1(1): loaded http://localhost:34420/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34420/tests/kraken/driver.html?testName=ai-astar) 20:25:21 INFO - PROCESS | 11658 | RSS: Main: 243240960 20:25:21 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | __start_tp_report 20:25:21 INFO - PROCESS | 11658 | _x_x_mozilla_page_load 20:25:21 INFO - PROCESS | 11658 | _x_x_mozilla_page_load_details 20:25:21 INFO - PROCESS | 11658 | |i|pagename|runs| 20:25:21 INFO - PROCESS | 11658 | |0;ai-astar;103;111;113;111;112;113;112;112;112;114 20:25:21 INFO - PROCESS | 11658 | |1;audio-beat-detection;151;116;124;171;118;120;143;117;153;117 20:25:21 INFO - PROCESS | 11658 | |2;audio-dft;162;161;160;160;160;162;161;161;160;160 20:25:21 INFO - PROCESS | 11658 | |3;audio-fft;74;76;75;115;75;77;74;75;102;76 20:25:21 INFO - PROCESS | 11658 | |4;audio-oscillator;84;84;138;85;85;85;120;85;85;127 20:25:21 INFO - PROCESS | 11658 | |5;imaging-gaussian-blur;117;118;117;117;117;117;118;118;117;117 20:25:21 INFO - PROCESS | 11658 | |6;imaging-darkroom;146;146;146;145;145;146;146;146;147;148 20:25:21 INFO - PROCESS | 11658 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 20:25:21 INFO - PROCESS | 11658 | |8;json-parse-financial;58;49;49;50;51;51;51;50;50;49 20:25:21 INFO - PROCESS | 11658 | |9;json-stringify-tinderbox;60;61;60;61;61;60;60;60;60;62 20:25:21 INFO - PROCESS | 11658 | |10;stanford-crypto-aes;78;77;85;79;76;75;75;75;85;77 20:25:21 INFO - PROCESS | 11658 | |11;stanford-crypto-ccm;123;120;135;121;119;120;128;127;139;125 20:25:21 INFO - PROCESS | 11658 | |12;stanford-crypto-pbkdf2;161;160;163;165;161;167;161;163;159;163 20:25:21 INFO - PROCESS | 11658 | |13;stanford-crypto-sha256-iterative;63;62;63;64;62;63;62;63;61;64 20:25:21 INFO - PROCESS | 11658 | __end_tp_report 20:25:21 INFO - PROCESS | 11658 | __start_cc_report 20:25:21 INFO - PROCESS | 11658 | _x_x_mozilla_cycle_collect,1692 20:25:21 INFO - PROCESS | 11658 | __end_cc_report 20:25:21 INFO - PROCESS | 11658 | __startTimestamp1470453921576__endTimestamp 20:25:21 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | ------- Summary: start ------- 20:25:21 INFO - PROCESS | 11658 | Number of tests: 14 20:25:21 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:3.06 (2.7%) stddev-sans-first:0.97 20:25:21 INFO - PROCESS | 11658 | Values: 103.0 111.0 113.0 111.0 112.0 113.0 112.0 112.0 112.0 114.0 20:25:21 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:133.50 stddev:19.84 (14.9%) stddev-sans-first:19.95 20:25:21 INFO - PROCESS | 11658 | Values: 151.0 116.0 124.0 171.0 118.0 120.0 143.0 117.0 153.0 117.0 20:25:21 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.73 20:25:21 INFO - PROCESS | 11658 | Values: 162.0 161.0 160.0 160.0 160.0 162.0 161.0 161.0 160.0 160.0 20:25:21 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | [#3] audio-fft Cycles:10 Average:81.90 Median:75.00 stddev:14.38 (19.2%) stddev-sans-first:14.96 20:25:21 INFO - PROCESS | 11658 | Values: 74.0 76.0 75.0 115.0 75.0 77.0 74.0 75.0 102.0 76.0 20:25:21 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:85.00 stddev:21.50 (25.3%) stddev-sans-first:22.22 20:25:21 INFO - PROCESS | 11658 | Values: 84.0 84.0 138.0 85.0 85.0 85.0 120.0 85.0 85.0 127.0 20:25:21 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 20:25:21 INFO - PROCESS | 11658 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 118.0 118.0 117.0 117.0 20:25:21 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 20:25:21 INFO - PROCESS | 11658 | Values: 146.0 146.0 146.0 145.0 145.0 146.0 146.0 146.0 147.0 148.0 20:25:21 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 20:25:21 INFO - PROCESS | 11658 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 20:25:21 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:50.50 stddev:2.66 (5.3%) stddev-sans-first:0.87 20:25:21 INFO - PROCESS | 11658 | Values: 58.0 49.0 49.0 50.0 51.0 51.0 51.0 50.0 50.0 49.0 20:25:21 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:60.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 20:25:21 INFO - PROCESS | 11658 | Values: 60.0 61.0 60.0 61.0 61.0 60.0 60.0 60.0 60.0 62.0 20:25:21 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | [#10] stanford-crypto-aes Cycles:10 Average:78.20 Median:77.50 stddev:3.82 (4.9%) stddev-sans-first:4.06 20:25:21 INFO - PROCESS | 11658 | Values: 78.0 77.0 85.0 79.0 76.0 75.0 75.0 75.0 85.0 77.0 20:25:21 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | [#11] stanford-crypto-ccm Cycles:10 Average:125.70 Median:126.00 stddev:6.75 (5.4%) stddev-sans-first:7.09 20:25:21 INFO - PROCESS | 11658 | Values: 123.0 120.0 135.0 121.0 119.0 120.0 128.0 127.0 139.0 125.0 20:25:21 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.30 Median:163.00 stddev:2.41 (1.5%) stddev-sans-first:2.51 20:25:21 INFO - PROCESS | 11658 | Values: 161.0 160.0 163.0 165.0 161.0 167.0 161.0 163.0 159.0 163.0 20:25:21 INFO - PROCESS | 11658 | 20:25:21 INFO - PROCESS | 11658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:0.95 (1.5%) stddev-sans-first:1.00 20:25:21 INFO - PROCESS | 11658 | Values: 63.0 62.0 63.0 64.0 62.0 63.0 62.0 63.0 61.0 64.0 20:25:21 INFO - PROCESS | 11658 | -------- Summary: end -------- 20:25:21 INFO - PROCESS | 11658 | 20:25:22 INFO - TEST-INFO | 11658: exit 0 20:25:22 INFO - TEST-OK | kraken | took 60679ms 20:25:22 INFO - SUITE-END | took 1175s 20:25:22 INFO - Completed test suite (00:19:35) 20:25:22 INFO - javascript benchmark 20:25:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2953.5274356103023, 3086.1204556701346, 3108.331708682633, 3108.751299896008, 3114.7041031102053, 5132.050570644434, 5192.299039629658, 5275.419703832576, 5352.03663340599, 5382.354117505895, 5318.734063296839, 5320.414754377025, 5353.0899747042995, 5392.137258038705, 5405.783768649249, 4744.478107408191, 4786.641001924852, 4839.0, 4840.733759643218, 4987.650864439491, 4856.174450343432, 4875.4393244776775, 4890.77991490382, 4896.338994235768, 4908.435529914052, 4691.999999999983, 4746.121967436035, 4773.618110551148, 4777.307290442866, 4777.4744778074555, 4740.597049250783, 4741.762911854394, 4757.310190022462, 4803.495632958524, 4847.951520484785, 4796.232602783538, 4813.807447702087, 4816.084943860656, 4842.080004799077, 4869.82955596556, 5181.637285390024, 5304.761285742151, 5366.892662146735, 5385.3268341457315, 5390.757415916254, 5791.652500849962, 5847.532197424196, 5850.0, 5859.501942334907, 5880.029795083806, 5827.155062515953, 5832.854178645525, 5839.970800146015, 5848.88302233953, 5877.617954832922, 5069.569086627641, 5274.55166310864, 5353.678779273256, 5364.731763411853, 5463.562914966793, 5911.261092363455, 5983.760649573968, 5985.790497332572, 5988.700564971734, 5990.071538911476, 5740.368559458456, 5742.770289188469, 5748.45389687979, 5753.971230143906, 5759.769609215668, 5502.064649009678, 5652.773889044474, 5697.487226149667, 5701.0, 5791.0, 5691.743871525791, 5696.3164420269, 5702.2301989231755, 5704.771809127671, 5706.0, 5664.943350566524, 5667.914981275284, 5719.456651618089, 5730.770769169187, 5733.283339582552, 4275.508316543637, 4308.956910430856, 4324.308110702273, 4361.847335343247, 4366.541513141138, 3846.1538461538416, 3910.7262491625315, 3913.9021524462114, 3929.5284565851707, 3930.253251882133, 5450.5639548836, 5451.182322651554, 5452.754626041838, 5496.6701997880255, 5496.972515137399, 5743.368229494752, 5747.770089196385, 5749.913751293734, 5754.1944127822135, 5755.280589926259, 5193.558547523503, 5331.173668081454, 5391.218273350342, 5439.374471935699, 5443.047466693313, 4903.092927808367, 4919.827806026772, 4921.753912304371, 4928.48250933654, 4938.08645400602, 5942.227510423617, 5948.434898684618, 5951.0, 5957.314908785545, 5957.3744756799915, 5541.307336582927, 5651.8304450866535, 5711.086226203954, 5756.30924289088, 5796.101604251347, 5941.168236446987, 5941.673207973603, 5942.10868369748, 5975.342712301556, 5989.790357337472, 5614.747336369955, 5665.716714164358, 5667.829965101054, 5672.177534257427, 5674.914876276776, 5547.0, 5637.238972738628, 5682.318121825408, 5700.942990570207, 5776.653400795965, 5589.748461319332, 5589.80435684749, 5592.3848376677715, 5626.521745651502, 5640.0693885508435, 5574.163875418558, 5581.832545023656, 5583.357913839798, 5583.944160558342, 5585.273914391103, 5346.385165705838, 5478.233047373451, 5508.063629182969, 5516.393196748274, 5529.502344788988, 5685.51673107774, 5719.48524632785, 5721.713914304351, 5733.8566535836935, 5738.368779434176, 5493.587980901529, 5538.086215774352, 5541.889162216814, 5554.91667624994, 5556.055470570093], "value": 5274.035669146335, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.7511032921335, 610.7770663707748, 612.1857928954491, 631.5137344244931, 642.2325321241117, 32186.0, 35286.29427411454, 35298.29403411933, 42499.15001699964, 43713.12573748523, 44654.553454465444, 45047.32429013559, 45054.324185137164, 45098.32352514715, 45100.54899451004, 8803.339749518796, 8950.239229665487, 8950.86573701395, 8954.910450895473, 8957.283417326598, 8792.07683193262, 8805.0, 8873.378863479558, 8889.4666320021, 8894.421862578944, 8917.152870477326, 8958.059403762609, 8970.058143894896, 8971.192592666659, 8973.95513022434, 8895.599698013573, 8904.287656987426, 8908.643654253821, 8915.643374265022, 8939.73180804577, 8874.733757987271, 8929.642814287421, 8936.776580585472, 8937.64249430022, 8949.776255593597, 42876.78561607203, 43166.35250471246, 43205.0, 43256.13487730228, 43350.78324608388, 3505.8772942946875, 3549.201429678302, 3625.3111908737255, 3687.557493100818, 4018.859339923118, 33315.059208165614, 33653.6634633653, 33977.0, 34607.4808877867, 35228.823855880815, 4253.829846806124, 4304.827806887721, 4305.63402110821, 4438.533953934855, 4485.932711009337, 370593.0, 372799.0, 372959.0, 376351.0, 376405.1179744111, 61705.691471542355, 62021.68989155025, 62099.0, 62119.68940155361, 62236.68881655563, 38582.22835543274, 39598.802005990365, 39621.0, 39648.60351396507, 65423.67288163624, 16359.182040897907, 16441.26014329358, 16497.505074847773, 16525.421610243582, 16528.75206871898, 12361.814572781415, 12564.560240391726, 12565.246085234916, 12573.685657858627, 12574.81137782934, 19425.0644703438, 19484.20649639293, 19526.97517267496, 19563.339707328832, 19644.0, 7048.929510704827, 7247.528910620898, 7327.377172940253, 7375.299346562068, 7412.221716719775, 1015.8780946286345, 1017.165923942375, 1135.1940122512942, 1138.481990694232, 1148.7989601819647, 33276.83361583225, 33489.83255083709, 33582.160445989044, 33860.49209261863, 34144.4634991426, 1122.938238396896, 1132.3036332655358, 1146.1976616368574, 1154.3766366162179, 1182.5388098641536, 16867.83132168687, 17149.399771008204, 17378.565535861453, 17413.3034678614, 17434.825651743573, 580896.0, 909010.0, 921037.0, 922791.0, 923309.0, 60662.090068649006, 61061.0, 61300.0, 61386.940501841964, 61564.38435615587, 1256.9560065397668, 1375.9862401376058, 1377.4903285784324, 1390.0, 1414.9717005660034, 29554.113376598736, 33705.49441758425, 33796.32407351888, 33974.49038264379, 34408.0, 50096.0, 50715.23927141022, 50718.492815071375, 50762.0, 50783.238251427, 433390.0, 434699.0, 436687.0, 440135.0, 441509.0, 29128.854355728086, 29788.553171702875, 29814.85092574523, 30057.54913676253, 31361.529577055906, 49300.50699493103, 49418.25872611986, 49762.25356619581, 50051.749741251064, 54128.458715412344, 42617.78691106525, 42998.570014299454, 43143.56856431396, 43780.0, 45447.77276113598, 42530.362044568734, 42798.572014280704, 43452.78273608612, 43486.0, 44752.55247447484, 36017.279654406244, 44317.55682443134, 45236.0, 45612.31581526213, 45867.77066114781, 41764.3735343964, 44965.77517112524, 45280.54719452763, 46414.535854642374, 46726.766366167954, 34556.30887382188, 35942.281154376244, 35993.28013439769, 35995.28009439849, 36080.63919360773], "value": 20879.516706056867, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.9610490898455, 371.1945079178185, 372.225770397573, 386.3798603241797, 389.6473691309366, 18588.62822743544, 20044.398668039947, 20639.17443302266, 20968.475788105305, 21246.15015399384, 19165.1375688094, 19416.4175074748, 19815.5046123847, 20096.49758756032, 20154.0, 5297.708626025567, 5478.150886612581, 5586.385497595261, 5719.742611582468, 5762.3373312069225, 5862.912056319158, 5892.852678683046, 5903.616264942786, 5904.498117660004, 5909.290885093795, 5724.055530837407, 5729.05470597353, 5748.338941021773, 5762.250907382035, 5848.385919478457, 5490.725463726818, 5618.971905140448, 5620.735334549735, 5626.943730562703, 5753.165790960308, 4935.827246046407, 4953.380827396575, 4985.077760614298, 4985.202367621199, 4993.900121997576, 23440.17959371414, 24158.758412415827, 24267.27198184057, 24499.755002449925, 24528.509429811482, 2157.5037741319584, 2278.054607337953, 2358.870262135582, 2419.9637005444756, 3136.6079240094987, 9878.160356369717, 10141.340812847215, 10170.898291017069, 10180.541875615616, 10342.0, 1896.895670738109, 1986.6225417170692, 1995.2318357432348, 2011.8289945354663, 2029.4926268432891, 82196.58901705513, 84781.0, 85127.5743621278, 86481.70277445843, 86834.0, 11133.387663678413, 11271.267367621078, 11314.547418103348, 11430.028447581883, 11509.25189862673, 16025.118618476095, 16066.75899861503, 16084.115373654562, 16091.356345746273, 16104.919475402783, 7970.0, 8085.636146373427, 8086.272235498834, 8286.70996515131, 8348.791280217922, 7655.0, 7805.453618246782, 7921.089074756477, 7954.0, 8123.390745694097, 4759.214729569651, 4883.902321953612, 4901.43633482147, 4938.4320803107385, 5073.670211436245, 1932.845372370207, 1990.8307793837687, 2001.559656875485, 2060.423081537172, 2060.6702927531824, 914.5153068873508, 1019.8164330420448, 1072.3083611070972, 1098.1873413673825, 1106.4523061084653, 19803.900980495004, 20456.69314960277, 20615.38153855387, 20659.97560829325, 20903.0, 1023.2019025160387, 1044.2637940252105, 1057.4289883462998, 1072.84443755655, 1080.0981180714034, 10938.234323597271, 11507.07943364529, 11529.308241505536, 11547.133964952662, 11564.826527601925, 501740.0, 502804.0, 506097.0, 526295.0, 528423.0, 74541.62729186319, 90478.54760726287, 90830.54584727166, 91235.54382228047, 91881.0, 951.4434056077134, 989.0900371658092, 1012.2964539644876, 1015.9136473399844, 1018.7809620931536, 17104.144792760064, 17878.19548120364, 18005.189766460804, 18170.72743908816, 18322.0, 32780.0, 42173.789131054145, 44101.0, 44134.55865441304, 44437.555624444634, 60707.0, 61075.389246108745, 61307.693461532406, 62747.6862615684, 63304.366956329846, 8540.188682075193, 8568.657253709782, 8673.956630216808, 8714.825703486022, 8802.515861627417, 12372.81440778407, 12470.251784892935, 12937.223766574036, 13081.345932703518, 13145.934270328587, 10999.065079468335, 11325.546978120783, 11416.31502109876, 11475.254108482923, 11822.349770762346, 11101.396417776288, 11235.943820280847, 11276.887231127585, 11701.0, 11760.58837940668, 11542.0, 11702.473388697359, 11761.235519691192, 12045.156839021185, 12447.813282800584, 8358.28954538871, 8522.190391912758, 8560.272376848037, 8583.65665373378, 8755.168259015638, 5513.558915286848, 10681.305715128492, 10803.45982700846, 10961.616343427942, 11494.655160345203], "value": 9300.73682007084, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.63916562111, 1453.6002599285198, 1523.794287771151, 1650.1254335202339, 1774.095211442165, 8299.58502074897, 8638.395312328132, 8752.21230089291, 8927.642894284238, 8935.374523783337, 8669.739907802761, 8790.604422800974, 8814.33892458065, 8818.77953051174, 8825.735227943173, 2330.9650355244726, 2354.540864531417, 2361.51588924271, 2363.1847012780563, 2363.326451961191, 2292.3352227853984, 2354.3407845803204, 2356.22244659262, 2362.7164740231196, 2365.089440565386, 2265.1166045242367, 2285.245868863271, 2287.645414960684, 2293.54129174165, 2294.804941579968, 2159.730033745782, 2172.837037222199, 2175.3147758456116, 2180.9563808723897, 2184.967225491611, 2224.0, 2227.944301392462, 2235.407616981501, 2235.61994460942, 2237.284069097889, 8711.69509067186, 8805.383623146383, 8810.38327317088, 8810.86783698245, 8923.955380223122, 5063.0, 5240.528352448298, 5249.396319423277, 5253.7635806388425, 5266.4733526647415, 3219.114743445548, 3239.1902024493875, 3252.203210213489, 3252.609686837595, 3281.343731253759, 1060.3352736204163, 1064.1912146768434, 1076.1390887290136, 1080.8000519903842, 1095.8520599719016, 12023.27860328383, 12120.81818772719, 12140.45367958444, 12178.878211217952, 12198.878011219775, 4228.1966426378885, 4408.4930233023615, 4434.645228381722, 4455.2648812946145, 4508.211063063951, 9593.0, 9607.231421486404, 9611.471369074769, 9618.759531011781, 9619.615215391446, 5160.612954028463, 5163.044836705221, 5163.922541161885, 5171.043356978971, 5176.404713457925, 4268.445102136702, 4312.827486900552, 4456.866294011184, 4846.0, 4847.95152048475, 3759.9436008459893, 3786.3941769316775, 3866.4200369944733, 3888.669463095612, 3905.4922860028014, 1081.5017310013893, 1084.9349039057524, 1095.9452027398759, 1101.6805126513382, 1108.3128460354633, 935.9204467620192, 976.9023097690316, 979.2264111351952, 979.7550612346913, 987.017917172418, 5911.674857882857, 5947.405259474018, 5999.880002400015, 6051.909221361683, 6098.634081955097, 450.05937590436145, 451.69510580358127, 455.84045584045316, 458.07697489558564, 462.7570525474086, 4172.7913604320265, 4190.832366705297, 4198.286291330481, 4206.0956894267265, 4229.407882896397, 10784.622538211124, 11124.61063862761, 11130.109591232844, 11154.330740155616, 11165.218434709655, 10957.671269861918, 11056.668299951014, 11071.612493562687, 11093.445327733582, 11103.38931358783, 7837.09873364559, 8198.508089514648, 8200.302974247255, 8288.668453261922, 8324.417290789708, 5881.38245484218, 5909.054551271776, 6008.579399441997, 6050.878982420415, 6158.107074474266, 10051.798964020825, 10110.140638045554, 10310.175185985254, 10313.896861031293, 10316.0, 9794.363366381162, 9900.108990190918, 9986.700398988041, 10025.899741002497, 10294.433806140734, 6930.930690693231, 7598.468107232441, 7708.075030996316, 7710.575918324546, 7943.44395892282, 8585.227329540372, 8917.197452229331, 8934.687285944961, 8949.32880033987, 8956.865647015169, 7732.381409487115, 7765.381696240127, 7873.645685944031, 7981.800454988672, 8011.0, 7311.4516411268305, 7650.081990161217, 7722.459427839997, 7722.575258360844, 8010.599470026359, 7043.2604576518725, 7167.784966451015, 7179.353858152791, 7203.603801790951, 7204.675789589376, 5361.678299302054, 5403.270558474555, 5424.484673955968, 5460.918086228789, 5636.239107720406, 5036.974815125901, 5146.511081447256, 5188.247704082963, 5200.947990520046, 5327.627066105335], "value": 4502.9107400654375, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.6194832562646, 310.5419506228312, 321.7088534875937, 323.17912502244303, 329.7774002548279, 5130.589552835773, 5159.5295340827915, 5171.870703232421, 5173.017126745915, 5225.686458812474, 4882.780274887629, 4998.600391890267, 5031.597472202224, 5056.570191533715, 5185.429602743695, 3886.455896174538, 3889.483101590382, 3967.504061992251, 4005.4792876926103, 4051.966748479134, 4018.3570628699413, 4027.2549578327958, 4028.8388464461386, 4038.172174704179, 4043.7666511713915, 3808.9622052202076, 3980.9004774880714, 4002.599740026003, 4003.0, 4016.0, 3985.9202815943518, 3986.880393588197, 3999.360102383619, 4001.8799436016966, 4005.359142537209, 3527.871081254, 3539.592946811124, 3543.6179889842983, 3557.964420355789, 3558.484019817112, 4880.755962201875, 5012.122878496249, 5013.498650134994, 5013.949860501385, 5014.147594908874, 4540.841070562547, 4579.290210017452, 4579.725216486988, 4587.977060114678, 4590.724556526619, 5285.841424757264, 5298.125809241471, 5299.49654782795, 5322.441143679896, 5336.626436149432, 3888.319544079775, 3889.9027524311837, 3899.7075219358376, 3901.6878649707955, 3903.0, 5353.304070470853, 5455.890882182334, 5493.835184944418, 5532.419095994943, 5543.477717638465, 3969.4244334571326, 3972.523297204354, 3983.940240896389, 3984.960150398459, 3994.360902255625, 4010.859619913289, 4018.8392464301687, 4021.5777343378522, 4025.255327764373, 4028.4762980812307, 4440.245158323093, 4509.323601459741, 4527.841525546656, 4564.7032942858605, 4578.2903649934315, 5364.946350536445, 5367.409584945653, 5367.892642147135, 5370.597205209547, 5370.758315875795, 1364.1405914273944, 1371.533678549298, 1374.113696665645, 1376.3462355381114, 1377.6900197455693, 878.0429332027992, 945.9574319155655, 947.15229869268, 963.7927845513249, 965.3001573858896, 5132.332796736475, 5135.460776618401, 5141.331626888481, 5141.640085194075, 5154.484551544815, 5400.0, 5407.729613519308, 5415.783368665209, 5420.0, 5429.945700543023, 4982.750862456863, 5145.562627176732, 5215.269862219292, 5240.554552863049, 5263.473652634706, 2900.5359142537513, 2929.750971167432, 2933.8386388748822, 2934.0611004478787, 2935.9448386129793, 5412.619781955655, 5653.0, 5675.716214189274, 5695.259616249861, 5711.885762284731, 5624.690642014729, 5639.351474580394, 5656.9817432861655, 5657.321121465368, 5673.602847800615, 5666.773329066791, 5670.213882626959, 5701.11632696941, 5703.885922281531, 5715.228444160151, 5250.18622113573, 5539.504333829763, 5546.0, 5560.527355174693, 5560.527355174855, 5481.890362192814, 5513.558915286848, 5524.508382736558, 5526.143447765603, 5535.640183388067, 5578.442155784389, 5702.515286200553, 5712.314522257356, 5714.457126572969, 5714.514266287412, 3933.645971862546, 3953.565107838193, 3958.366661334173, 3981.9339108886575, 3984.621460961204, 5538.889222215614, 5538.944610553843, 5539.473749993744, 5547.750351234285, 5568.972155139199, 5224.242484839601, 5391.59563032782, 5404.891902161856, 5410.918836217381, 5424.511793938565, 5346.8395948121615, 5401.810936617357, 5411.134218525174, 5412.269343638558, 5473.233747275305, 5080.847574572768, 5230.843074707765, 5332.760025798926, 5355.892882142258, 5367.114426119647, 5416.864578385572, 5439.156930675752, 5459.454054594509, 5464.781408743605, 5467.39858615544, 5312.184542192644, 5320.57435405159, 5326.067938110893, 5332.440093790251, 5342.278792363137], "value": 4108.16602956696, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.8115471132218, 766.5132640773107, 818.3371469110019, 839.38305345571, 850.8808766772656, 17701.911490442544, 18314.08429578519, 18343.724844127333, 18364.90817545914, 18372.265109395626, 16643.66712665749, 16702.164891755423, 16717.91641041796, 16718.41485548006, 16750.66498670026, 680.170192365316, 818.1246066708624, 841.6969890839293, 844.6114787197865, 845.6659619450344, 807.3904202327235, 830.5265998380926, 831.3224721851672, 844.3414136973171, 844.9366297527679, 878.3719640457072, 879.2043200903192, 879.2087121590556, 879.4371602174581, 879.520661239626, 696.571608460793, 869.447900583128, 876.5617191404298, 877.4472082587961, 877.7191298784392, 622.3527531367417, 636.3604577399684, 637.2512298049772, 642.0914406115342, 642.489221069251, 14394.208318542476, 14431.061980971203, 14511.854881451156, 14538.927305363512, 14546.0, 14048.789268160986, 14077.366518506691, 14257.643558911006, 14839.851601483955, 14939.0, 13746.0, 13783.241921694304, 13875.098118622258, 14180.787288190686, 14650.63373415673, 4370.0, 4384.9561504384865, 4433.13553856998, 4565.20108980927, 4713.764311784432, 90726.0, 91115.0, 92360.0, 93904.53047734854, 93964.0, 11556.469566432701, 11644.3595602241, 11741.354225517507, 11771.941140294244, 11789.528418863149, 22138.446538836335, 22276.108955641917, 22355.32934011982, 22397.552048959256, 22521.774782252294, 8910.688125915562, 8947.0, 8989.955050224708, 9212.401193922507, 9357.0, 4930.556249937523, 4964.826231081895, 5028.17035189197, 5102.285680004802, 5107.591392688576, 3376.662333766653, 3475.600305964796, 3478.7390945679176, 3481.112316359312, 3485.9477107843404, 588.3939542271468, 588.808637192914, 591.3436085944599, 591.6538824787461, 592.0674936974283, 681.3731367142142, 682.2358957967109, 686.615495322621, 687.965601719912, 713.2974020589714, 8879.733607991771, 9103.544822758835, 9613.90386096144, 9681.467519286365, 9690.0, 562.5612022622361, 572.373251289837, 585.6632436349031, 615.8897567285281, 698.1691786773723, 4117.0, 4248.299030659907, 4531.614812740888, 4639.164950308909, 4706.764661766898, 76934.23065769383, 77268.22731772723, 128550.42899141964, 133580.0, 135701.0, 117259.41370293093, 119092.0, 119183.0, 121988.39005805092, 123101.0, 56223.437765622635, 57288.713556431954, 81618.59190704009, 82650.58674706708, 94615.0, 9215.677451289173, 9449.669261575811, 9603.183729383081, 9994.950025249827, 10421.166306695597, 50584.49415505945, 53284.0, 54296.18555721588, 55017.0, 55564.0, 68172.659136704, 69400.0, 82674.58662706648, 82744.58627706823, 84940.15059849323, 12770.16993895431, 13062.54281100195, 13092.0, 13315.866841331463, 13399.0, 14212.573622791333, 14218.928905355408, 14241.686457850787, 14941.103533788008, 15097.698046039237, 13931.72136557243, 13998.720025599634, 14412.279386030867, 14420.711585768435, 14516.20160891119, 13213.144341461835, 14006.159630422208, 14197.716045679235, 14233.146011239358, 15229.923850380677, 12791.744165116459, 12974.675633109247, 13164.275964822027, 13325.666858328232, 13780.310984450938, 11187.272827266203, 11208.775824483628, 11226.55093796239, 11667.941660291644, 11842.230255033395, 10809.837852432378, 10820.513076911715, 11064.944675276573, 11249.38128402913, 11573.768524629291], "value": 7753.625813074007, "unit": "score"}], "name": "dromaeo_css", "value": 7264.059353349921, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 113.0, 111.0, 112.0, 113.0, 112.0, 112.0, 112.0, 114.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 116.0, 124.0, 171.0, 118.0, 120.0, 143.0, 117.0, 153.0, 117.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 160.0, 160.0, 162.0, 161.0, 161.0, 160.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 115.0, 75.0, 77.0, 74.0, 75.0, 102.0, 76.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 138.0, 85.0, 85.0, 85.0, 120.0, 85.0, 85.0, 127.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 147.0, 148.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 49.0, 49.0, 50.0, 51.0, 51.0, 51.0, 50.0, 50.0, 49.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 61.0, 61.0, 60.0, 60.0, 60.0, 60.0, 62.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 85.0, 79.0, 76.0, 75.0, 75.0, 75.0, 85.0, 77.0], "value": 78.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 120.0, 135.0, 121.0, 119.0, 120.0, 128.0, 127.0, 139.0, 125.0], "value": 125.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 163.0, 165.0, 161.0, 167.0, 161.0, 163.0, 159.0, 163.0], "value": 162.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 64.0, 62.0, 63.0, 62.0, 63.0, 61.0, 64.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1483.3, "alertThreshold": 2.0}]} 20:25:22 INFO - Return code: 0 20:25:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:25:22 INFO - # TBPL SUCCESS # 20:25:22 INFO - Running post-action listener: _resource_record_post_action 20:25:22 INFO - [mozharness: 2016-08-06 03:25:22.880574Z] Finished run-tests step (success) 20:25:22 INFO - Running post-run listener: _resource_record_post_run 20:25:22 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 555769856; Read time: 8; Write time: 569940 20:25:22 INFO - TinderboxPrint: CPU usage
13.0% 20:25:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 20:25:22 INFO - TinderboxPrint: I/O write bytes / time
555,769,856 / 569,940 20:25:22 INFO - TinderboxPrint: CPU idle
8,284.9 (87.0%) 20:25:22 INFO - TinderboxPrint: CPU user
1,191.8 (12.5%) 20:25:22 INFO - TinderboxPrint: Swap in / out
0 / 0 20:25:22 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 260751360; Read time: 0; Write time: 307960 20:25:22 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 294780928; Read time: 0; Write time: 261944 20:25:23 INFO - Running post-run listener: _upload_blobber_files 20:25:23 INFO - Blob upload gear active. 20:25:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:25:23 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 20:25:23 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'] 20:25:23 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 20:25:23 INFO - (blobuploader) - INFO - Open directory for files ... 20:25:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:25:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:25:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:25:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:25:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:25:24 INFO - (blobuploader) - INFO - Done attempting. 20:25:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:25:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:25:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:25:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:25:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:25:25 INFO - (blobuploader) - INFO - Done attempting. 20:25:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:25:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:25:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:25:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:25:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:25:28 INFO - (blobuploader) - INFO - Done attempting. 20:25:28 INFO - (blobuploader) - INFO - Iteration through files over. 20:25:28 INFO - Return code: 0 20:25:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:25:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:25:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/846684479c5e8a4670590515ef4f476589990059bc1e86fd89efb80109badf7451ed368354c46fc603680bc1358cfa4a83daf1d199e892a57977267e7fb11d2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/07d4fe0e98ec9d96a19eeffb8f882349f76a2a0f5fc1d46cd75888841bf6385e31c5c7dbc4bdababf4793b0dc92d5f03a29f30441f763506f02bec530702aa0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/39f3b8c5de98b7c59e6fae0714bad321f7fdf8fefd92c8d34f8ccca943dead8129abbc3e761565679b45e3ca7b6592b45c6f6fc8064a1e0d3db18ee951f0afc3"} 20:25:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:25:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:25:28 INFO - Contents: 20:25:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/846684479c5e8a4670590515ef4f476589990059bc1e86fd89efb80109badf7451ed368354c46fc603680bc1358cfa4a83daf1d199e892a57977267e7fb11d2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/07d4fe0e98ec9d96a19eeffb8f882349f76a2a0f5fc1d46cd75888841bf6385e31c5c7dbc4bdababf4793b0dc92d5f03a29f30441f763506f02bec530702aa0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/39f3b8c5de98b7c59e6fae0714bad321f7fdf8fefd92c8d34f8ccca943dead8129abbc3e761565679b45e3ca7b6592b45c6f6fc8064a1e0d3db18ee951f0afc3"} 20:25:28 INFO - Running post-run listener: copy_logs_to_upload_dir 20:25:28 INFO - Copying logs to upload dir... 20:25:28 INFO - mkdir: /builds/slave/test/build/upload/logs 20:25:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.836614 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-08-05 20:25:28.515242) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-05 20:25:28.518834) ========= 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=652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485 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/846684479c5e8a4670590515ef4f476589990059bc1e86fd89efb80109badf7451ed368354c46fc603680bc1358cfa4a83daf1d199e892a57977267e7fb11d2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/07d4fe0e98ec9d96a19eeffb8f882349f76a2a0f5fc1d46cd75888841bf6385e31c5c7dbc4bdababf4793b0dc92d5f03a29f30441f763506f02bec530702aa0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/39f3b8c5de98b7c59e6fae0714bad321f7fdf8fefd92c8d34f8ccca943dead8129abbc3e761565679b45e3ca7b6592b45c6f6fc8064a1e0d3db18ee951f0afc3"} build_url:https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011144 build_url: 'https://queue.taskcluster.net/v1/task/TNMfJLCISr6tlwBUJNFOwA/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/846684479c5e8a4670590515ef4f476589990059bc1e86fd89efb80109badf7451ed368354c46fc603680bc1358cfa4a83daf1d199e892a57977267e7fb11d2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/07d4fe0e98ec9d96a19eeffb8f882349f76a2a0f5fc1d46cd75888841bf6385e31c5c7dbc4bdababf4793b0dc92d5f03a29f30441f763506f02bec530702aa0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/39f3b8c5de98b7c59e6fae0714bad321f7fdf8fefd92c8d34f8ccca943dead8129abbc3e761565679b45e3ca7b6592b45c6f6fc8064a1e0d3db18ee951f0afc3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-05 20:25:28.545027) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 20:25:28.545483) ========= 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=652eb03db935cfc1c15ebda0000001ad-1470450489.73375-1228503485 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004457 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-05 20:25:28.630639) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-05 20:25:28.631135) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-05 20:25:28.631567) ========= ========= Total master_lag: 0.18 =========