builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-012 starttime: 1468884596.71 results: success (0) buildid: 20160718155219 builduid: 4067c172f185448bb79ac3227fd8d75d revision: 62bb8900cfef139ac0ea2f9bc4db742166d6f9a5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 16:29:56.713152) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 16:29:56.713767) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 16:29:56.714206) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 16:29:56.729919) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 16:29:56.730330) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-18 16:29: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-07-18 16:29:56 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087478 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 16:29:56.833745) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 16:29:56.834218) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014837 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 16:29:56.886182) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 16:29:56.886764) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 62bb8900cfef139ac0ea2f9bc4db742166d6f9a5 --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 62bb8900cfef139ac0ea2f9bc4db742166d6f9a5 --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=2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-18 16:29:57,052 truncating revision to first 12 chars 2016-07-18 16:29:57,052 Setting DEBUG logging. 2016-07-18 16:29:57,052 attempt 1/10 2016-07-18 16:29:57,052 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/62bb8900cfef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 16:29:57,996 unpacking tar archive at: fx-team-62bb8900cfef/testing/mozharness/ program finished with exit code 0 elapsedTime=1.308573 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 16:29:58.211426) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 16:29:58.211873) ========= script_repo_revision: 62bb8900cfef139ac0ea2f9bc4db742166d6f9a5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 16:29:58.212388) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 16:29:58.212716) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 16:29:58.224139) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-07-18 16:29:58.224535) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:29:58 INFO - MultiFileLogger online at 20160718 16:29:58 in /builds/slave/test 16:29:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 16:29:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:29:58 INFO - {'append_to_log': False, 16:29:58 INFO - 'base_work_dir': '/builds/slave/test', 16:29:58 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 16:29:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:29:58 INFO - 'branch': 'Fx-Team-Non-PGO', 16:29:58 INFO - 'buildbot_json_path': 'buildprops.json', 16:29:58 INFO - 'config_files': ('talos/linux_config.py',), 16:29:58 INFO - 'default_actions': ('clobber', 16:29:58 INFO - 'read-buildbot-config', 16:29:58 INFO - 'download-and-extract', 16:29:58 INFO - 'populate-webroot', 16:29:58 INFO - 'create-virtualenv', 16:29:58 INFO - 'install', 16:29:58 INFO - 'run-tests'), 16:29:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:29:58 INFO - 'download_minidump_stackwalk': True, 16:29:58 INFO - 'download_symbols': 'ondemand', 16:29:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:29:58 INFO - 'tooltool.py': '/tools/tooltool.py', 16:29:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:29:58 INFO - '/tools/misc-python/virtualenv.py')}, 16:29:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:29:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:29:58 INFO - 'installer_path': 'installer.exe', 16:29:58 INFO - 'log_level': 'info', 16:29:58 INFO - 'log_name': 'talos', 16:29:58 INFO - 'log_to_console': True, 16:29:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:29:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:29:58 INFO - 'opt_config_files': (), 16:29:58 INFO - 'pip_index': False, 16:29:58 INFO - 'sps_profile': False, 16:29:58 INFO - 'sps_profile_interval': 0, 16:29:58 INFO - 'suite': 'dromaeojs', 16:29:58 INFO - 'system_bits': '32', 16:29:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:29:58 INFO - 'title': 'talos-linux64-ix-012', 16:29:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:29:58 INFO - 'use_talos_json': True, 16:29:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:29:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:29:58 INFO - 'work_dir': 'build'} 16:29:58 INFO - ##### 16:29:58 INFO - ##### Running clobber step. 16:29:58 INFO - ##### 16:29:58 INFO - Running pre-action listener: _resource_record_pre_action 16:29:58 INFO - Running main action method: clobber 16:29:58 INFO - rmtree: /builds/slave/test/build 16:29:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:29:58 INFO - Running post-action listener: _resource_record_post_action 16:29:58 INFO - ##### 16:29:58 INFO - ##### Finished clobber step (success) 16:29:58 INFO - ##### 16:29:58 INFO - ##### 16:29:58 INFO - ##### Running read-buildbot-config step. 16:29:58 INFO - ##### 16:29:58 INFO - Running pre-action listener: _resource_record_pre_action 16:29:58 INFO - Running main action method: read_buildbot_config 16:29:58 INFO - Using buildbot properties: 16:29:58 INFO - { 16:29:58 INFO - "project": "", 16:29:58 INFO - "product": "firefox", 16:29:58 INFO - "script_repo_revision": "production", 16:29:58 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 16:29:58 INFO - "repository": "", 16:29:58 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 16:29:58 INFO - "buildid": "20160718155219", 16:29:58 INFO - "pgo_build": "False", 16:29:58 INFO - "basedir": "/builds/slave/test", 16:29:58 INFO - "buildnumber": 3174, 16:29:58 INFO - "slavename": "talos-linux64-ix-012", 16:29:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:29:58 INFO - "platform": "ubuntu64_hw", 16:29:58 INFO - "scheduler": "tests-fx-team-linux64-talos", 16:29:58 INFO - "branch": "fx-team", 16:29:58 INFO - "repo_path": "integration/fx-team", 16:29:58 INFO - "revision": "62bb8900cfef139ac0ea2f9bc4db742166d6f9a5", 16:29:58 INFO - "stage_platform": "linux64", 16:29:58 INFO - "builduid": "4067c172f185448bb79ac3227fd8d75d", 16:29:58 INFO - "slavebuilddir": "test" 16:29:58 INFO - } 16:29:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:29:58 INFO - Running post-action listener: _resource_record_post_action 16:29:58 INFO - ##### 16:29:58 INFO - ##### Finished read-buildbot-config step (success) 16:29:58 INFO - ##### 16:29:58 INFO - ##### 16:29:58 INFO - ##### Running download-and-extract step. 16:29:58 INFO - ##### 16:29:58 INFO - Running pre-action listener: _resource_record_pre_action 16:29:58 INFO - Running main action method: download_and_extract 16:29:58 INFO - mkdir: /builds/slave/test/build/tests 16:29: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:58 INFO - https://queue.taskcluster.net/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:29:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:29:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:29:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:29:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:29:59 INFO - Downloaded 1361 bytes. 16:29:59 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:29:59 INFO - Using the following test package requirements: 16:29:59 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:29:59 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:29:59 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:29:59 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:29:59 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:29:59 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:29:59 INFO - u'jsshell-linux-x86_64.zip'], 16:29:59 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:29:59 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:29:59 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:29:59 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:29:59 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:29:59 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:29:59 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:29:59 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:29:59 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:29:59 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:29:59 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:29:59 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:29:59 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:59 INFO - https://queue.taskcluster.net/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:29:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:29:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:29:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:29:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:30:00 INFO - Downloaded 22763363 bytes. 16:30:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:30:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:30:01 INFO - Return code: 0 16:30:01 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:01 INFO - https://queue.taskcluster.net/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:30:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:30:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:30:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:30:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:30:02 INFO - Downloaded 22763363 bytes. 16:30:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:30:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:30:04 INFO - Return code: 0 16:30:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:04 INFO - https://queue.taskcluster.net/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:30:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:30:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:30:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:30:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:30:05 INFO - Downloaded 14403317 bytes. 16:30:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:30:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:30:05 INFO - Return code: 0 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:05 INFO - https://queue.taskcluster.net/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:30:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:30:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:30:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:30:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:30:06 INFO - Downloaded 57125602 bytes. 16:30:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:30:06 INFO - mkdir: /builds/slave/test/properties 16:30:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:30:06 INFO - Writing to file /builds/slave/test/properties/build_url 16:30:06 INFO - Contents: 16:30:06 INFO - build_url:https://queue.taskcluster.net/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:30:07 INFO - Running post-action listener: _resource_record_post_action 16:30:07 INFO - Running post-action listener: set_extra_try_arguments 16:30:07 INFO - ##### 16:30:07 INFO - ##### Finished download-and-extract step (success) 16:30:07 INFO - ##### 16:30:07 INFO - ##### 16:30:07 INFO - ##### Running populate-webroot step. 16:30:07 INFO - ##### 16:30:07 INFO - Running pre-action listener: _resource_record_pre_action 16:30:07 INFO - Running main action method: populate_webroot 16:30:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:30:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:30:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:30:07 INFO - '--tppagecycles', 16:30:07 INFO - '7'], 16:30:07 INFO - 'tests': ['tsvgm']}}, 16:30:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:30:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:30:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:30:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:30:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:30:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:30:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:07 INFO - 'talos_options': ['--disable-e10s'], 16:30:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:30:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:30:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:07 INFO - 'talos_options': ['--disable-e10s'], 16:30:07 INFO - 'tests': ['damp', 'tps']}, 16:30:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:07 INFO - 'tests': ['damp', 'tps']}, 16:30:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:30:07 INFO - 'tests': ['dromaeo_dom']}, 16:30:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:30:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:30:07 INFO - 'tests': ['basic_compositor_video']}, 16:30:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:30:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:30:07 INFO - 'tests': ['a11yr', 16:30:07 INFO - 'ts_paint', 16:30:07 INFO - 'tpaint', 16:30:07 INFO - 'sessionrestore', 16:30:07 INFO - 'sessionrestore_no_auto_restore', 16:30:07 INFO - 'tabpaint']}, 16:30:07 INFO - 'other-e10s': {'tests': ['a11yr', 16:30:07 INFO - 'ts_paint', 16:30:07 INFO - 'tpaint', 16:30:07 INFO - 'sessionrestore', 16:30:07 INFO - 'sessionrestore_no_auto_restore', 16:30:07 INFO - 'tabpaint']}, 16:30:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:30:07 INFO - 'ts_paint', 16:30:07 INFO - 'tpaint', 16:30:07 INFO - 'sessionrestore', 16:30:07 INFO - 'sessionrestore_no_auto_restore', 16:30:07 INFO - 'tabpaint']}, 16:30:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:30:07 INFO - 'ts_paint', 16:30:07 INFO - 'tpaint', 16:30:07 INFO - 'sessionrestore', 16:30:07 INFO - 'sessionrestore_no_auto_restore', 16:30:07 INFO - 'tabpaint']}, 16:30:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:30:07 INFO - 'tests': ['a11yr', 16:30:07 INFO - 'ts_paint', 16:30:07 INFO - 'tpaint', 16:30:07 INFO - 'sessionrestore', 16:30:07 INFO - 'sessionrestore_no_auto_restore', 16:30:07 INFO - 'tabpaint']}, 16:30:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:30:07 INFO - 'tests': ['a11yr', 16:30:07 INFO - 'ts_paint', 16:30:07 INFO - 'tpaint', 16:30:07 INFO - 'sessionrestore', 16:30:07 INFO - 'sessionrestore_no_auto_restore', 16:30:07 INFO - 'tabpaint']}, 16:30:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:30:07 INFO - 'tests': ['tsvgx', 16:30:07 INFO - 'tsvgr_opacity', 16:30:07 INFO - 'tart', 16:30:07 INFO - 'tscrollx', 16:30:07 INFO - 'cart']}, 16:30:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:30:07 INFO - 'tsvgr_opacity', 16:30:07 INFO - 'tart', 16:30:07 INFO - 'tscrollx', 16:30:07 INFO - 'cart']}, 16:30:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:07 INFO - 'talos_options': ['--disable-e10s'], 16:30:07 INFO - 'tests': ['tp5o']}, 16:30:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:07 INFO - 'tests': ['tp5o']}, 16:30:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:07 INFO - 'talos_options': ['--disable-e10s', 16:30:07 INFO - '--xperf_path', 16:30:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:30:07 INFO - 'tests': ['tp5n']}, 16:30:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:07 INFO - 'talos_options': ['--xperf_path', 16:30:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:30:07 INFO - 'tests': ['tp5n']}}, 16:30:07 INFO - 'talos.zip': {'path': '', 16:30:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:30:07 INFO - Running post-action listener: _resource_record_post_action 16:30:07 INFO - ##### 16:30:07 INFO - ##### Finished populate-webroot step (success) 16:30:07 INFO - ##### 16:30:07 INFO - ##### 16:30:07 INFO - ##### Running create-virtualenv step. 16:30:07 INFO - ##### 16:30:07 INFO - Running pre-action listener: _resource_record_pre_action 16:30:07 INFO - Running main action method: create_virtualenv 16:30:07 INFO - Creating virtualenv /builds/slave/test/build/venv 16:30: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 16:30:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:30:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:30:07 INFO - Using real prefix '/usr' 16:30:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:30:08 INFO - Installing distribute.............................................................................................................................................................................................done. 16:30:10 INFO - Installing pip.................done. 16:30:10 INFO - Return code: 0 16:30:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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 0x2099030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23583b0>, '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 0x23592b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2359740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30: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 16:30: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 16:30:10 INFO - Using env: {'DISPLAY': ':0', 16:30:10 INFO - 'HOME': '/home/cltbld', 16:30:10 INFO - 'LANG': 'en_US.UTF-8', 16:30:10 INFO - 'LANGUAGE': 'en_US:en', 16:30:10 INFO - 'LOGNAME': 'cltbld', 16:30:10 INFO - 'MAIL': '/var/mail/cltbld', 16:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:10 INFO - 'MOZ_NO_REMOTE': '1', 16:30:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:10 INFO - 'NO_EM_RESTART': '1', 16:30:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:10 INFO - 'PWD': '/builds/slave/test', 16:30:10 INFO - 'SHELL': '/bin/bash', 16:30:10 INFO - 'SHLVL': '1', 16:30:10 INFO - 'TERM': 'linux', 16:30:10 INFO - 'TMOUT': '86400', 16:30:10 INFO - 'USER': 'cltbld', 16:30:10 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 16:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:10 INFO - '_': '/tools/buildbot/bin/python'} 16:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30: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 16:30:10 INFO - Cleaning up... 16:30:10 INFO - Return code: 0 16:30:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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 0x2099030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23583b0>, '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 0x23592b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2359740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30: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 16:30: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 16:30:10 INFO - Using env: {'DISPLAY': ':0', 16:30:10 INFO - 'HOME': '/home/cltbld', 16:30:10 INFO - 'LANG': 'en_US.UTF-8', 16:30:10 INFO - 'LANGUAGE': 'en_US:en', 16:30:10 INFO - 'LOGNAME': 'cltbld', 16:30:10 INFO - 'MAIL': '/var/mail/cltbld', 16:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:10 INFO - 'MOZ_NO_REMOTE': '1', 16:30:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:10 INFO - 'NO_EM_RESTART': '1', 16:30:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:10 INFO - 'PWD': '/builds/slave/test', 16:30:10 INFO - 'SHELL': '/bin/bash', 16:30:10 INFO - 'SHLVL': '1', 16:30:10 INFO - 'TERM': 'linux', 16:30:10 INFO - 'TMOUT': '86400', 16:30:10 INFO - 'USER': 'cltbld', 16:30:10 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 16:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:10 INFO - '_': '/tools/buildbot/bin/python'} 16:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:10 INFO - Downloading/unpacking psutil>=3.1.1 16:30: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 16:30: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 16:30: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 16:30: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 16:30: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 16:30: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 16:30:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:30:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:30:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:30:12 INFO - Installing collected packages: psutil 16:30:12 INFO - Running setup.py install for psutil 16:30:12 INFO - building 'psutil._psutil_linux' extension 16:30: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 16:30: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_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:30:13 INFO - building 'psutil._psutil_posix' extension 16:30: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 16:30: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 16:30:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:30:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:30:13 INFO - Successfully installed psutil 16:30:13 INFO - Cleaning up... 16:30:13 INFO - Return code: 0 16:30:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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 0x2099030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23583b0>, '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 0x23592b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2359740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30: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 16:30: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 16:30:13 INFO - Using env: {'DISPLAY': ':0', 16:30:13 INFO - 'HOME': '/home/cltbld', 16:30:13 INFO - 'LANG': 'en_US.UTF-8', 16:30:13 INFO - 'LANGUAGE': 'en_US:en', 16:30:13 INFO - 'LOGNAME': 'cltbld', 16:30:13 INFO - 'MAIL': '/var/mail/cltbld', 16:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:13 INFO - 'MOZ_NO_REMOTE': '1', 16:30:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:13 INFO - 'NO_EM_RESTART': '1', 16:30:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:13 INFO - 'PWD': '/builds/slave/test', 16:30:13 INFO - 'SHELL': '/bin/bash', 16:30:13 INFO - 'SHLVL': '1', 16:30:13 INFO - 'TERM': 'linux', 16:30:13 INFO - 'TMOUT': '86400', 16:30:13 INFO - 'USER': 'cltbld', 16:30:13 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 16:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:13 INFO - '_': '/tools/buildbot/bin/python'} 16:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:30: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 16:30: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 16:30: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 16:30: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 16:30: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 16:30: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 16:30:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:30:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:30:15 INFO - Installing collected packages: mozsystemmonitor 16:30:15 INFO - Running setup.py install for mozsystemmonitor 16:30:15 INFO - Successfully installed mozsystemmonitor 16:30:15 INFO - Cleaning up... 16:30:15 INFO - Return code: 0 16:30:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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 0x2099030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23583b0>, '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 0x23592b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2359740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30: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 16:30: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 16:30:15 INFO - Using env: {'DISPLAY': ':0', 16:30:15 INFO - 'HOME': '/home/cltbld', 16:30:15 INFO - 'LANG': 'en_US.UTF-8', 16:30:15 INFO - 'LANGUAGE': 'en_US:en', 16:30:15 INFO - 'LOGNAME': 'cltbld', 16:30:15 INFO - 'MAIL': '/var/mail/cltbld', 16:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:15 INFO - 'MOZ_NO_REMOTE': '1', 16:30:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:15 INFO - 'NO_EM_RESTART': '1', 16:30:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:15 INFO - 'PWD': '/builds/slave/test', 16:30:15 INFO - 'SHELL': '/bin/bash', 16:30:15 INFO - 'SHLVL': '1', 16:30:15 INFO - 'TERM': 'linux', 16:30:15 INFO - 'TMOUT': '86400', 16:30:15 INFO - 'USER': 'cltbld', 16:30:15 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 16:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:15 INFO - '_': '/tools/buildbot/bin/python'} 16:30:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:15 INFO - Downloading/unpacking jsonschema==2.5.1 16:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:30:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:17 INFO - Downloading functools32-3.2.3-2.tar.gz 16:30:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:30:18 INFO - warning: no files found matching '*.txt' 16:30:18 INFO - no previously-included directories found matching 'build' 16:30:18 INFO - no previously-included directories found matching 'dist' 16:30:18 INFO - no previously-included directories found matching '.git*' 16:30:18 INFO - Installing collected packages: jsonschema, functools32 16:30:18 INFO - Running setup.py install for functools32 16:30:18 INFO - warning: no files found matching '*.txt' 16:30:18 INFO - no previously-included directories found matching 'build' 16:30:18 INFO - no previously-included directories found matching 'dist' 16:30:18 INFO - no previously-included directories found matching '.git*' 16:30:18 INFO - Successfully installed jsonschema functools32 16:30:18 INFO - Cleaning up... 16:30:18 INFO - Return code: 0 16:30:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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 0x2099030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23583b0>, '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 0x23592b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2359740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:30:18 INFO - Using env: {'DISPLAY': ':0', 16:30:18 INFO - 'HOME': '/home/cltbld', 16:30:18 INFO - 'LANG': 'en_US.UTF-8', 16:30:18 INFO - 'LANGUAGE': 'en_US:en', 16:30:18 INFO - 'LOGNAME': 'cltbld', 16:30:18 INFO - 'MAIL': '/var/mail/cltbld', 16:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:18 INFO - 'MOZ_NO_REMOTE': '1', 16:30:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:18 INFO - 'NO_EM_RESTART': '1', 16:30:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:18 INFO - 'PWD': '/builds/slave/test', 16:30:18 INFO - 'SHELL': '/bin/bash', 16:30:18 INFO - 'SHLVL': '1', 16:30:18 INFO - 'TERM': 'linux', 16:30:18 INFO - 'TMOUT': '86400', 16:30:18 INFO - 'USER': 'cltbld', 16:30:18 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 16:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:18 INFO - '_': '/tools/buildbot/bin/python'} 16:30:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:18 INFO - Downloading/unpacking blobuploader==1.2.4 16:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:20 INFO - Downloading blobuploader-1.2.4.tar.gz 16:30:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:30:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:30: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 16:30: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 16:30: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 16:30: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 16:30: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 16:30: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 16:30:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:30:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:30: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 16:30: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 16:30: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 16:30: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 16:30: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 16:30: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 16:30:21 INFO - Downloading docopt-0.6.1.tar.gz 16:30:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:30:21 INFO - Installing collected packages: blobuploader, requests, docopt 16:30:21 INFO - Running setup.py install for blobuploader 16:30:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:30:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:30:21 INFO - Running setup.py install for requests 16:30:21 INFO - Running setup.py install for docopt 16:30:21 INFO - Successfully installed blobuploader requests docopt 16:30:21 INFO - Cleaning up... 16:30:21 INFO - Return code: 0 16:30:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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 0x2099030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23583b0>, '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 0x23592b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2359740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30: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 16:30: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 16:30:21 INFO - Using env: {'DISPLAY': ':0', 16:30:21 INFO - 'HOME': '/home/cltbld', 16:30:21 INFO - 'LANG': 'en_US.UTF-8', 16:30:21 INFO - 'LANGUAGE': 'en_US:en', 16:30:21 INFO - 'LOGNAME': 'cltbld', 16:30:21 INFO - 'MAIL': '/var/mail/cltbld', 16:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:21 INFO - 'MOZ_NO_REMOTE': '1', 16:30:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:21 INFO - 'NO_EM_RESTART': '1', 16:30:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:21 INFO - 'PWD': '/builds/slave/test', 16:30:21 INFO - 'SHELL': '/bin/bash', 16:30:21 INFO - 'SHLVL': '1', 16:30:21 INFO - 'TERM': 'linux', 16:30:21 INFO - 'TMOUT': '86400', 16:30:21 INFO - 'USER': 'cltbld', 16:30:21 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 16:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:21 INFO - '_': '/tools/buildbot/bin/python'} 16:30:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:30:22 INFO - Running setup.py (path:/tmp/pip-GFNmRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:30:22 INFO - Running setup.py (path:/tmp/pip-S5OvVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:30:22 INFO - Running setup.py (path:/tmp/pip-krnlOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:30:22 INFO - Running setup.py (path:/tmp/pip-XqEa7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:30:22 INFO - Running setup.py (path:/tmp/pip-qCyg3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:30:22 INFO - Running setup.py (path:/tmp/pip-Jia54Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:30:22 INFO - Running setup.py (path:/tmp/pip-zyatmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:30:22 INFO - Running setup.py (path:/tmp/pip-fnC0l7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:30:22 INFO - Running setup.py (path:/tmp/pip-UX77BN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:30:22 INFO - Running setup.py (path:/tmp/pip-ATNiEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:30:22 INFO - Running setup.py (path:/tmp/pip-WAiSC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:30:22 INFO - Running setup.py (path:/tmp/pip-NskJDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:30:22 INFO - Running setup.py (path:/tmp/pip-YIaKpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:30:22 INFO - Running setup.py (path:/tmp/pip-kv9aZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:30:23 INFO - Running setup.py (path:/tmp/pip-203nwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:30:23 INFO - Running setup.py (path:/tmp/pip-W7vLDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:30:23 INFO - Running setup.py (path:/tmp/pip-ozjVzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:30:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:30:23 INFO - Running setup.py install for manifestparser 16:30:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:30:23 INFO - Running setup.py install for mozcrash 16:30:23 INFO - Running setup.py install for mozdebug 16:30:23 INFO - Running setup.py install for mozdevice 16:30:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:30:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:30:23 INFO - Running setup.py install for mozfile 16:30:23 INFO - Running setup.py install for mozhttpd 16:30:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:30:23 INFO - Running setup.py install for mozinfo 16:30:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:30:23 INFO - Running setup.py install for mozInstall 16:30:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:30:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:30:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:30:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:30:24 INFO - Running setup.py install for mozleak 16:30:24 INFO - Running setup.py install for mozlog 16:30:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:30:24 INFO - Running setup.py install for moznetwork 16:30:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:30:24 INFO - Running setup.py install for mozprocess 16:30:24 INFO - Running setup.py install for mozprofile 16:30:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:30:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:30:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:30:24 INFO - Running setup.py install for mozrunner 16:30:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:30:24 INFO - Running setup.py install for mozscreenshot 16:30:24 INFO - Running setup.py install for moztest 16:30:24 INFO - Running setup.py install for mozversion 16:30:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:30:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:30:25 INFO - Cleaning up... 16:30:25 INFO - Return code: 0 16:30:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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 0x2099030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23583b0>, '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 0x23592b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2359740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30: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 16:30: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 16:30:25 INFO - Using env: {'DISPLAY': ':0', 16:30:25 INFO - 'HOME': '/home/cltbld', 16:30:25 INFO - 'LANG': 'en_US.UTF-8', 16:30:25 INFO - 'LANGUAGE': 'en_US:en', 16:30:25 INFO - 'LOGNAME': 'cltbld', 16:30:25 INFO - 'MAIL': '/var/mail/cltbld', 16:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:25 INFO - 'MOZ_NO_REMOTE': '1', 16:30:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:25 INFO - 'NO_EM_RESTART': '1', 16:30:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:25 INFO - 'PWD': '/builds/slave/test', 16:30:25 INFO - 'SHELL': '/bin/bash', 16:30:25 INFO - 'SHLVL': '1', 16:30:25 INFO - 'TERM': 'linux', 16:30:25 INFO - 'TMOUT': '86400', 16:30:25 INFO - 'USER': 'cltbld', 16:30:25 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 16:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:25 INFO - '_': '/tools/buildbot/bin/python'} 16:30:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:30:25 INFO - Running setup.py (path:/tmp/pip-PViB0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:30: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)) 16:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:30:25 INFO - Running setup.py (path:/tmp/pip-KQQh2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:30: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)) 16:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:30:25 INFO - Running setup.py (path:/tmp/pip-6tpsw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:30: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)) 16:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:30:25 INFO - Running setup.py (path:/tmp/pip-L38zz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:30: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)) 16:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:30:25 INFO - Running setup.py (path:/tmp/pip-qSKquq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:30: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)) 16:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:30:25 INFO - Running setup.py (path:/tmp/pip-e_33C8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:30: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)) 16:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:30:25 INFO - Running setup.py (path:/tmp/pip-x1zSJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:30: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)) 16:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:30:25 INFO - Running setup.py (path:/tmp/pip-A2xbFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:30: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)) 16:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:30:25 INFO - Running setup.py (path:/tmp/pip-eOTX6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:30: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)) 16:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:30:25 INFO - Running setup.py (path:/tmp/pip-JC3Cp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:30:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:30:25 INFO - Running setup.py (path:/tmp/pip-_FNaV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:30: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)) 16:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:30:26 INFO - Running setup.py (path:/tmp/pip-4QlcdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:30: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)) 16:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:30:26 INFO - Running setup.py (path:/tmp/pip-A3iGBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:30: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)) 16:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:30:26 INFO - Running setup.py (path:/tmp/pip-I_WeWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:30: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)) 16:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:30:26 INFO - Running setup.py (path:/tmp/pip-8RJhCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:30: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)) 16:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:30:26 INFO - Running setup.py (path:/tmp/pip-zBVb2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:30: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)) 16:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:30:26 INFO - Running setup.py (path:/tmp/pip-HB2NJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:30: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)) 16:30: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)) 16:30: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)) 16:30: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)) 16:30: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)) 16:30: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)) 16:30:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:30: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 16:30: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 16:30: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 16:30: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 16:30: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 16:30: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 16:30:28 INFO - Downloading blessings-1.6.tar.gz 16:30:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:30:28 INFO - Installing collected packages: blessings 16:30:28 INFO - Running setup.py install for blessings 16:30:28 INFO - Successfully installed blessings 16:30:28 INFO - Cleaning up... 16:30:28 INFO - Return code: 0 16:30:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:30:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:30:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:30:28 INFO - Reading from file tmpfile_stdout 16:30:28 INFO - Current package versions: 16:30:28 INFO - argparse == 1.2.1 16:30:28 INFO - blessings == 1.6 16:30:28 INFO - blobuploader == 1.2.4 16:30:28 INFO - docopt == 0.6.1 16:30:28 INFO - functools32 == 3.2.3-2 16:30:28 INFO - jsonschema == 2.5.1 16:30:28 INFO - manifestparser == 1.1 16:30:28 INFO - mozInstall == 1.12 16:30:28 INFO - mozcrash == 0.17 16:30:28 INFO - mozdebug == 0.1 16:30:28 INFO - mozdevice == 0.48 16:30:28 INFO - mozfile == 1.2 16:30:28 INFO - mozhttpd == 0.7 16:30:28 INFO - mozinfo == 0.9 16:30:28 INFO - mozleak == 0.1 16:30:28 INFO - mozlog == 3.2 16:30:28 INFO - moznetwork == 0.27 16:30:28 INFO - mozprocess == 0.23 16:30:28 INFO - mozprofile == 0.28 16:30:28 INFO - mozrunner == 6.12 16:30:28 INFO - mozscreenshot == 0.1 16:30:28 INFO - mozsystemmonitor == 0.3 16:30:28 INFO - moztest == 0.7 16:30:28 INFO - mozversion == 1.4 16:30:28 INFO - psutil == 3.1.1 16:30:28 INFO - requests == 1.2.3 16:30:28 INFO - wsgiref == 0.1.2 16:30:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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 0x2099030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23583b0>, '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 0x23592b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2359740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30: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 16:30: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 16:30:28 INFO - Using env: {'DISPLAY': ':0', 16:30:28 INFO - 'HOME': '/home/cltbld', 16:30:28 INFO - 'LANG': 'en_US.UTF-8', 16:30:28 INFO - 'LANGUAGE': 'en_US:en', 16:30:28 INFO - 'LOGNAME': 'cltbld', 16:30:28 INFO - 'MAIL': '/var/mail/cltbld', 16:30:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:28 INFO - 'MOZ_NO_REMOTE': '1', 16:30:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:28 INFO - 'NO_EM_RESTART': '1', 16:30:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:28 INFO - 'PWD': '/builds/slave/test', 16:30:28 INFO - 'SHELL': '/bin/bash', 16:30:28 INFO - 'SHLVL': '1', 16:30:28 INFO - 'TERM': 'linux', 16:30:28 INFO - 'TMOUT': '86400', 16:30:28 INFO - 'USER': 'cltbld', 16:30:28 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 16:30:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:28 INFO - '_': '/tools/buildbot/bin/python'} 16:30:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30: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)) 16:30: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)) 16:30: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)) 16:30: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)) 16:30: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)) 16:30: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)) 16:30: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)) 16:30: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)) 16:30: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)) 16:30: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)) 16:30: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)) 16:30:29 INFO - Cleaning up... 16:30:29 INFO - Return code: 0 16:30:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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 0x2099030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23583b0>, '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 0x23592b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2359740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30: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 16:30: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 16:30:29 INFO - Using env: {'DISPLAY': ':0', 16:30:29 INFO - 'HOME': '/home/cltbld', 16:30:29 INFO - 'LANG': 'en_US.UTF-8', 16:30:29 INFO - 'LANGUAGE': 'en_US:en', 16:30:29 INFO - 'LOGNAME': 'cltbld', 16:30:29 INFO - 'MAIL': '/var/mail/cltbld', 16:30:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:29 INFO - 'MOZ_NO_REMOTE': '1', 16:30:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:29 INFO - 'NO_EM_RESTART': '1', 16:30:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:29 INFO - 'PWD': '/builds/slave/test', 16:30:29 INFO - 'SHELL': '/bin/bash', 16:30:29 INFO - 'SHLVL': '1', 16:30:29 INFO - 'TERM': 'linux', 16:30:29 INFO - 'TMOUT': '86400', 16:30:29 INFO - 'USER': 'cltbld', 16:30:29 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 16:30:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:29 INFO - '_': '/tools/buildbot/bin/python'} 16:30:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:30:29 INFO - Cleaning up... 16:30:29 INFO - Return code: 0 16:30:29 INFO - Running post-action listener: _resource_record_post_action 16:30:29 INFO - Running post-action listener: _start_resource_monitoring 16:30:29 INFO - Starting resource monitoring. 16:30:29 INFO - ##### 16:30:29 INFO - ##### Finished create-virtualenv step (success) 16:30:29 INFO - ##### 16:30:29 INFO - ##### 16:30:29 INFO - ##### Running install step. 16:30:29 INFO - ##### 16:30:29 INFO - Running pre-action listener: _resource_record_pre_action 16:30:29 INFO - Running main action method: install 16:30:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:30:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:30:29 INFO - Reading from file tmpfile_stdout 16:30:29 INFO - Detecting whether we're running mozinstall >=1.0... 16:30:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:30:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:30:29 INFO - Reading from file tmpfile_stdout 16:30:29 INFO - Output received: 16:30:29 INFO - Usage: mozinstall [options] installer 16:30:29 INFO - Options: 16:30:29 INFO - -h, --help show this help message and exit 16:30:29 INFO - -d DEST, --destination=DEST 16:30:29 INFO - Directory to install application into. [default: 16:30:29 INFO - "/builds/slave/test"] 16:30:29 INFO - --app=APP Application being installed. [default: firefox] 16:30:29 INFO - mkdir: /builds/slave/test/build/application 16:30:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:30:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:30:45 INFO - Reading from file tmpfile_stdout 16:30:45 INFO - Output received: 16:30:45 INFO - /builds/slave/test/build/application/firefox/firefox 16:30:45 INFO - Running post-action listener: _resource_record_post_action 16:30:45 INFO - ##### 16:30:45 INFO - ##### Finished install step (success) 16:30:45 INFO - ##### 16:30:45 INFO - ##### 16:30:45 INFO - ##### Running run-tests step. 16:30:45 INFO - ##### 16:30:45 INFO - Running pre-action listener: _resource_record_pre_action 16:30:45 INFO - Running main action method: run_tests 16:30:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:30:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:30:46 INFO - Python 2.7.3 16:30:46 INFO - Return code: 0 16:30:46 INFO - grabbing minidump binary from tooltool 16:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30: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 0x23583b0>, '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 0x23592b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2359740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:30: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 16:30: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 16:30: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 16:30:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:30:46 INFO - Return code: 0 16:30:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:30:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:30:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:30:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:30:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:30:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:30:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:30:46 INFO - Using env: {'DISPLAY': ':0', 16:30:46 INFO - 'HOME': '/home/cltbld', 16:30:46 INFO - 'LANG': 'en_US.UTF-8', 16:30:46 INFO - 'LANGUAGE': 'en_US:en', 16:30:46 INFO - 'LOGNAME': 'cltbld', 16:30:46 INFO - 'MAIL': '/var/mail/cltbld', 16:30:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:30:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:46 INFO - 'MOZ_NO_REMOTE': '1', 16:30:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:30:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:46 INFO - 'NO_EM_RESTART': '1', 16:30:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:46 INFO - 'PWD': '/builds/slave/test', 16:30:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:30:46 INFO - 'SHELL': '/bin/bash', 16:30:46 INFO - 'SHLVL': '1', 16:30:46 INFO - 'TERM': 'linux', 16:30:46 INFO - 'TMOUT': '86400', 16:30:46 INFO - 'USER': 'cltbld', 16:30:46 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940', 16:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:46 INFO - '_': '/tools/buildbot/bin/python'} 16:30:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:30:46 INFO - mozversion application_buildid: 20160718155219 16:30:46 INFO - mozversion application_changeset: 62bb8900cfef139ac0ea2f9bc4db742166d6f9a5 16:30:46 INFO - mozversion application_display_name: Nightly 16:30:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:30:46 INFO - mozversion application_name: Firefox 16:30:46 INFO - mozversion application_remotingname: firefox 16:30:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:30:46 INFO - mozversion application_vendor: Mozilla 16:30:46 INFO - mozversion application_version: 50.0a1 16:30:46 INFO - mozversion platform_buildid: 20160718155219 16:30:46 INFO - mozversion platform_changeset: 62bb8900cfef139ac0ea2f9bc4db742166d6f9a5 16:30:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:30:46 INFO - mozversion platform_version: 50.0a1 16:30:46 INFO - using testdate: 1468884646 16:30:46 INFO - actual date: 1468884646 16:30:46 INFO - starting webserver on 'localhost:47097' 16:30:46 INFO - SUITE-START | Running 2 tests 16:30:46 INFO - TEST-START | dromaeo_css 16:30:46 INFO - operating with platform_type : linux_ 16:30:46 INFO - Initialising browser for dromaeo_css test... 16:30:46 INFO - TEST-INFO | started process 16222 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMjnTo5/profile http://localhost:47097/getInfo.html) 16:30:50 INFO - PROCESS | 16222 | __metrics Screen width/height:1600/1200 16:30:50 INFO - PROCESS | 16222 | colorDepth:24 16:30:50 INFO - PROCESS | 16222 | Browser inner width/height: 1024/697 16:30:50 INFO - PROCESS | 16222 | __metrics 16:30:50 INFO - PROCESS | 16222 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:30:50 INFO - PROCESS | 16222 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:30:51 INFO - TEST-INFO | 16222: exit 0 16:30:51 INFO - Browser initialized. 16:30:51 INFO - Running cycle 1/1 for dromaeo_css test... 16:30:51 INFO - TEST-INFO | started process 16305 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMjnTo5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:31:03 INFO - PROCESS | 16305 | RSS: Main: 158793728 16:31:03 INFO - PROCESS | 16305 | 16:31:55 INFO - PROCESS | 16305 | 1468884715817 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:34:07 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:47097/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47097/tests/dromaeo/cssquery-ext.html) 16:34:08 INFO - PROCESS | 16305 | RSS: Main: 262389760 16:34:08 INFO - PROCESS | 16305 | 16:37:11 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:47097/tests/dromaeo/cssquery-ext.html (next: http://localhost:47097/tests/dromaeo/cssquery-jquery.html) 16:37:11 INFO - PROCESS | 16305 | RSS: Main: 243023872 16:37:11 INFO - PROCESS | 16305 | 16:40:13 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:47097/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47097/tests/dromaeo/cssquery-mootools.html) 16:40:14 INFO - PROCESS | 16305 | RSS: Main: 231632896 16:40:14 INFO - PROCESS | 16305 | 16:43:16 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:47097/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47097/tests/dromaeo/cssquery-prototype.html) 16:43:17 INFO - PROCESS | 16305 | RSS: Main: 235487232 16:43:17 INFO - PROCESS | 16305 | 16:46:20 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:47097/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47097/tests/dromaeo/cssquery-yui.html) 16:46:21 INFO - PROCESS | 16305 | RSS: Main: 217341952 16:46:21 INFO - PROCESS | 16305 | 16:49:23 INFO - PROCESS | 16305 | Cycle 1(1): loaded http://localhost:47097/tests/dromaeo/cssquery-yui.html (next: http://localhost:47097/tests/dromaeo/cssquery-dojo.html) 16:49:24 INFO - PROCESS | 16305 | RSS: Main: 331345920 16:49:24 INFO - PROCESS | 16305 | 16:49:24 INFO - PROCESS | 16305 | __start_tp_report 16:49:24 INFO - PROCESS | 16305 | _x_x_mozilla_page_load 16:49:24 INFO - PROCESS | 16305 | _x_x_mozilla_page_load_details 16:49:24 INFO - PROCESS | 16305 | |i|pagename|runs| 16:49:24 INFO - PROCESS | 16305 | |0;dojo.html;2588.844669319841;2593.105378644367;2635.156749840051;2638.6833579970407;2642.8017898657595;4493.977530112353;4597.954020459794;4625.5836974672275;4657.231556793129;4659.044895796362;4624.05206932578;4624.514425985273;4647.07058588282;4653.115907977482;4687.179743544874;4234.618884300412;4242.363645453192;4280.250956082688;4320.675949303799;4337.154254920292;4346.6522678185665;4380.364847097185;4413.6910416270875;4426.490953540352;4437.5784300491405;4243.554426785174;4284.892877678052;4289.571042895685;4303.117860838552;4354.608085272341;4263.339182426729;4268.850590229327;4287.957120428787;4301.698881078326;4329.393884856091;4288.24955632763;4335.804888780012;4351.042770590465;4359.367891655692;4392.121575684876;4568.634509239286;4720.693154944917;4729.196036673739;4758.690685105492;4759.357486739281;4883.780229889663;4923.18767403378;4923.606111511071;4958.677685950402;4982.227754698028;4970.403551573798;5010.023045506127;5026.29631851541;5028.044671512401;5044.621653375974;4568.794404251817;4667.183242932473;4669.719816810934;4715.41057367829;4725.976370118127;5127.256547800548;5133.25567792668;5154.690718556899;5200.63595548308;5225.712585807817;4939.259111133287;4953.752312384367;5000.774965126505;5000.849974500771;5004.199328107558;4918.3360246367;4948.777305021283;4992.525710057539;5034.622403319766;5088.72012039334;4949;4956.578690811321;4966.404031516241;4974;5004.84985450437;4953.083679519301;4956.256561515802;4956.479569645207;4964.528369804862;4993.251012348177;4250.936235956463;4299.032717638569;4303.63419109373;4349.325854492558;4351.238533256667;3691.483192353073;3717.126475278307;3734.066483379155;3744.8689295874515;3798.753081049723;4288.592583704543;4291.270484017725;4293.484781826201;4330.307150855848;4344.978275108604;4984.40187177541;4984.925226121611;4986.152354099811;4990.850274491771;5023.723695196726;4187.853425130106;4336.631386332134;4378.737275763465;4389;4423.889402764892;4608.654350923694;4611.492735799059;4613.331066995334;4616.053708989649;4659.254519276899;4684.695494792828;4784.641151913551;4827.758612069382;4834.20235661112;4861.124997500484;5088.236764485357;5150.97424512875;5157.355330583677;5179.249008893765;5180.9740951295;4826.686265392738;4857.101436234308;4859.878503037311;4859.975700121477;4900.068986892479;4829.9275510868065;4937.802487900443;5069.898602027865;5073.974630126826;5125.974370128126;5043.319151914445;5052.040112378636;5074.517920797666;5074.720890351066;5124.5131712487255;4938;4947.455779864284;4951.9752401237765;4952.628552858622;4994.900101998012;4752.097101550694;4856.781444835062;4902.1911384621135;4920.5571498565305;4982.626303027215;4876.731779752147;4883.755812209446;4910.558049775537;4916.237983112624;4927.433345165352;4935.309056732132;4957.702537847741;4960.751962401793;4963.677360971526;5007.349044624176;4617.58441740245;4724.71651700899;4811.1820990431015;4833.830815921426;4866.513348665105 16:49:24 INFO - PROCESS | 16305 | |1;ext.html;547.0254904527687;584.6813486649776;588.7792077970761;592.718458732102;597.5607928172792;39254.607453925455;40980.180396392054;41124;41169.58830411695;45213.09573808522;41477.37783933243;41837;41861.581384186145;41987.79006104973;42010.159796804044;7847.058352997634;7982.28159465648;7993.320567751748;7993.44045916786;7994;7849.332806711408;7897.644605992716;7911.169327220645;7935.444518883681;7940.602969851484;7959.204079592052;7991.440599158062;7991.880121798177;7994;7996.0404751429905;7914;7938.563379014152;7956.562389068599;7956.801079972989;7958.880616790695;7823.921760782433;7866;7868.724594639218;7878.7787892876695;7878.960605196995;39013.02467438337;39181.41227881584;39249.21501569981;39254.803725981474;39263.41104883429;3218.8873389431255;3229.6931791480006;3364.327134573095;3515.5078289039557;3837.9808100959594;30037.699623003708;30235.697643023508;30257.394852103054;30972.535411968838;31045.22386940322;3252.414565378231;3270.3622793555264;3289.276359200972;3341.281624450755;3352.614449338333;283522;286644;288562;406640;407331;30798.230044249074;56667;56926.715366422904;57064.71467642635;57303.42696573064;32575.185620359207;34425.65574344224;34606.30787384238;34634.307313853584;34647;14144.434222631186;14373.568792936228;14382.496612618508;14385.424583016771;14407.63980900465;11692.29846209213;11879.643610691694;11880.524779008916;11886.28682279066;11892.227005244631;8014.358851291882;8020.11778704342;8024.518528888286;8116.066652335057;8117.837643247102;3064.1420402287395;3065.371598822236;3095.7987730797427;3104.9223769405494;3109.595752552154;880.190224993008;923.4367036107968;948.6347756113946;978.2076518020426;978.8629591857145;27075.323116922234;27596.448071038467;28517.42965140727;28751.712482875322;28864.71135288662;926.8331700293877;947.9478628675352;996.7358649957768;1026.5329275179774;1062.3519653011656;14688.41246350134;14692.26538673062;15088.622784430258;15135.848641513665;15250.313735881911;399641;403213;403215;403382;403488;49446.752766236656;50057;50112.498875011515;50221.475637679825;50242;1043.8851726310252;1109.6227282723912;1138.1350173867754;1155.930644161353;1190.9761804763857;26779.33051673724;28897.711022890344;29481.262968425104;29948.70051299459;30009.549856752605;40187;40329.79835100806;41017.79491102525;41054.38418423786;41069;251152;251415;251926;252178;252859;22372.888135559217;23955.760442395353;24936.750632494168;25959.610605841306;25998.87000564985;41645.58354416538;41689;42037.36943945904;42600.36099458449;42738.78630606827;35827.462588061724;35929.46105808362;36356.454653179695;36629.63370366369;36636.45045324269;35818;36413.635863642085;36630.45054324134;37041.25917481689;37142.628573714996;34485.48271775875;34957.65042349544;35181.648183517835;35570;35871;31588.684113158575;32522.51216231711;32585.837070815443;32718.672813272515;32888.67111328952;24478.63282050735;25019.499610007333;25228.369290767143;25414.745852541237;25578.23265302044 16:49:24 INFO - PROCESS | 16305 | |2;jquery.html;346.88726163996705;367.2947939955287;374.11708368250925;377.7469095705878;380.1086452269427;13008.219506829597;16007.19964001801;16811.411600593965;17524.474265772016;18585.163667634955;16064.518064458056;16371.099589522571;16939.237734301954;17386.304547818072;17696.38062667807;4407.625351845081;5065.113605119099;5255.106631872572;5268.394134674523;5268.683878967274;5221.660592061504;5270.39390470097;5277.947220527803;5284.577233821305;5287.735613219343;5026.472220416858;5229.241759944805;5229.947700523003;5237.554807841338;5241.606879484054;5195.922061169085;5212.5308722214995;5213.165893457029;5223.451537588575;5223.921641175367;4548.636109111263;4611.469680986663;4672.742999135046;4678.719276843401;4679.906401871977;21094.156233750786;21482.462938426506;21697.34907952764;22087.116515339367;22146.114155433763;1839.5217243516647;1862.9254829806791;1894.2423030787656;1944.4555524453176;1985.3349128042123;13521.932390337985;13544.525941591997;13600.31998400086;13840.861591384159;13930.651733706638;1721.0276193950378;1746.8515176210037;1800.297883825309;1859.665260253154;1998.580298137393;67166.66416667934;68784.6560767193;69508;70370;71046;8410.747677569683;8886.422382545114;9010.27917766577;9586.424814511152;9784;12475.189112707645;13350.799738003938;13355.866441335462;13363.799543006862;13934.790978135336;7251.818704532323;7333.2766799802;7408.296211859864;7612.657430415645;7742.380609551223;7306.707731690779;7391.778246652609;7479.70081196746;7496.325330720261;7517.699292028367;4296.3555466680245;4373.890652733707;4391.714538554984;4479.328100784843;4483.058557702853;1809.9819001810076;1842.6591080650123;1849.713294439364;1856.925722971066;1862.0059971361354;829.7344849648055;856.4604299291531;864.5504337744463;914.6707185413246;922.423485321674;17135.400260990806;17323.48029559115;17705;17801.910990445223;17927.641447170983;810.785141937387;869.3045563549135;874.4578361415963;906.7461110888962;910.7540963939832;9620.663276785279;9928.50357482123;9999.550020249106;10032.949835250776;10164.949175254076;377483;395213;395415;395560;396011;76659.61670191614;78678;80308;80630.59684701657;80748.59625701833;836.5900708652716;878.2885862451435;888.2538667519315;899.3344924755634;900.090908182727;15069.773953390928;15107.244637768288;15342.309596068428;15603.609909752347;15696;35992.64007359998;36654;37977.4303385455;38077.809610951765;38667.613323866404;50426.74786626043;50787.746061269456;51500.742496287276;51959.74020129875;52943.20585191302;7477.744807121575;7595.3164215220895;7650.273224043707;7733.729319473791;7948.920510794818;11147.163962702993;11981.940090299493;12064.155509114278;12113.515459381879;12262.4481898313;10125.088742013255;10283.84574231402;10423.114035307084;10457.895421045996;10656.946715266375;10389;10479.842802357818;10535.894641053492;10552.841707374242;10582.153427725609;10484.947575262075;10488.580456781949;10554.2084343673;10685.465726713788;10713.839292410465;7558.33716582351;7628.23717628255;7644.732434364771;7761;7947.801304967422;7779.988601481998;9128.954355228181;10408.427536485566;10488.790224195322;10736.141108711128 16:49:24 INFO - PROCESS | 16305 | |3;mootools.html;1258.3708145927037;1360.9523666671669;1402.2918426194774;1459.7299499592575;1677.2955358749332;6631.933680663198;7600.695972161114;7870.370370370357;7873.212678732124;8100.149484304152;7854.293113619773;8005.279524842763;8010.038795344567;8013.158618345077;8020.919790802091;2173.217641649005;2208.8012078912893;2209.3482422685265;2210.6462965925457;2214.9114035438565;2140.1867290429614;2172.8587641803233;2186.1911092895652;2189.2994241842616;2191.2549733090746;2132.6907598398225;2146.2273581510644;2156.277646988261;2158.0936006877073;2161.3948094533557;2111.5460176062143;2114.608797372491;2117.14255726431;2117.3859580721573;2118.2374345235703;2039.2454791727157;2087.039961617658;2103.4741314671332;2106.24175296893;2109.261758384553;7610.467267291292;7894.368450524002;7927.5243485391065;7979.680812767482;7995.840083198362;4602.079584083163;4700.177468942921;4708.223143181371;4708.434987801452;4711.670183087185;2767.640206773127;2868.584055311989;2897.826130432181;2907.2731817045737;2908.8691008904652;942.2509105261333;949.586929685589;951.743029382063;954.6420092465326;960.9231261499064;10634.308769930007;10744.14046876248;10744.516496757664;10760.784784304271;10764.031237188612;3903.4730311407598;4072.6131017553284;4126.257273690764;4133.937990930079;4217.072244106292;8416.579171041545;8462.11147829469;8485.95757021211;8488.74533763988;8490.702825401082;4697.084068606658;4698.906021879543;4699.365585645894;4699.882502937454;4700.153972284953;3727.552693676776;3769.208466222124;3940.566537680853;4336.067745434747;4365.978170109129;3330.0675810772536;3368.6294507604143;3393.8133402662597;3421.1447138215444;3444.603870554868;1000.2845120618175;1031.9638812641522;1045.3780000899453;1051.1853313681804;1068.8022715797601;849.4011721736161;849.9150084991452;870.6386849457405;878.9208971192623;890.2432932007871;5438.782448702008;5517.724113794295;5546.168074788814;5677.460641239076;5700.657960522382;887.8046829697456;901.2114399900088;902.9992829414264;906.1527471813876;913.7669894177076;3651.8721844735305;3675.933979146071;3724.7578907370935;3740;3845.115623406632;9598.712038638852;9694.660686875926;9823.607055717692;9900.504974751379;9907.851382229272;9733.2213422926;9813.01869813013;9841.753956151011;9847.261455390875;9860.852087218698;7178.066851309296;7243.963780181065;7247.855042899218;7302.525335853259;7427.071616047994;5270.552003079782;5330.813421530228;5332.70670113132;5368.2484452177505;5547.1679248113105;8814.294856411343;8972.506512141634;9045.954770226106;9046.411983221069;9059.501727405055;7849.568273744999;8538.231559159705;8924.776880578038;8950.552472376225;9010.549472526216;6371.74513019474;6522.412982831568;6544.28012918569;6561.80314590563;6667.66661666905;7377.77866664001;7425.8143546411775;7432.293932076444;7435.776926692208;7494.662740176596;6784.93215067843;6836.453083753388;6869.416099631388;6872.656367181521;6921;6596.109525214035;6791.524593278423;6841.521093523405;6845.247022827584;6911.274316196727;6424.839379015375;6481.027845823164;6500.837479063061;6506.837329066811;6518.934810652023;4602.838900638461;4829.05833362505;4833.709977401367;4862.4651288357545;4872;4682.157211701926;4699.788509517011;4707.788149533348;4729.196036673705;4792.041591681747 16:49:24 INFO - PROCESS | 16305 | |4;prototype.html;284.5447284345047;297.55366949575637;298.0804218984433;299.7781641585226;304.9618797650293;4690.90618187636;4713.717176969384;4737.715737055779;4742.739149346785;4757.42910850698;4402.273624851896;4558.247889098295;4560.9087818243615;4579.519150489201;4679.532046795319;3606.296772129436;3640.7451478396456;3719.3119272934596;3742.345089609321;3759.793211373373;3715.386961151407;3729.291434627412;3735.2716220337047;3747.8126093695205;3748.5314335708035;3614.3313487004857;3695.5380577427823;3704.9259014819822;3713.6100709425527;3722.7207959403017;3693.169036966688;3704.240485769265;3706.406245314514;3709.295233905563;3709.7217708671956;3284.3759685659647;3287.3589650018257;3288.3752087103376;3290.7038366546885;3300.092474569489;4494.213512635276;4554.908901821978;4558.179527685015;4576.542345765397;4579.67942244043;4022.9195416091807;4224.155168966219;4230.873073807791;4232.978835105805;4268;4909.3126962225315;4914.754262286907;4918.483559226265;4925.827596034122;4931.5944955687455;3619.8416506717863;3624.1483251435893;3624.3476174288617;3627.4740162676526;3630.8729194478065;4886.511348865156;5103.413107492612;5144.56271216947;5154.175331946871;5162.406323272834;3551.1654761131113;3573.3210689968823;3577.266660334625;3578.6063533011347;3579.5167652367118;3629.491871138043;3630.6913912317223;3631.94552081719;3635.1457407509215;3638.4178531434845;4157.708960372805;4157.91684166321;4159.91680166395;4160.958390416057;4163.7293575917465;4694.95305046952;4925.374626373288;4930.063287975273;4951.480094590088;4964.379452568429;1242.8260043593905;1244.8506179258547;1246.9151837901084;1250.6935800728697;1258.3205069262497;786.6853258696567;838.6393850644281;853.6457370191425;858.1461445861407;862.3230763850347;4293.97853010733;4300.354946757949;4309.633681137076;4320.65434765218;4321.222180007629;4981.580249628839;4988.478514053176;4988.6757360771435;4989.126902791997;5033.899322013613;4159.1265834175165;4332.523422423531;4333.826646934087;4337.37108119321;4394.846525202692;2704.3104008478103;2714.3078514978556;2717.1169369954846;2718.7824974002033;2735.480258750831;4834.830780922651;4836.903261934812;4844.515548445198;4853.136649376885;4863.440704319049;4968.552830245295;5190.844274671766;5194.870128246824;5211.374635043819;5226.372835259793;4786.497417771084;4838.153323168361;4839.467658557556;4849.75751212445;4856.562909338177;5111.284420181252;5111.437741848468;5116.2372318947355;5118.38579370478;5123.923141152811;4708.529147085264;5019.941823852203;5020.698758074527;5044.293798868235;5052.69683818972;4838.637102217418;4850;4865.756712164307;4867.610591152628;4873.439554451142;3612.422012477991;3616.65641764032;3617.113807117273;3617.3669607818;3636.3818150914935;5060.952719656959;5073.416557095834;5104.979004199101;5116.641835071509;5125.2568377585785;4976.552110310089;4977.850664479926;4996.675216110941;5005.399352077774;5018.946021335414;4959.504049595012;4998.875028124326;5002.399712034579;5013.824516142064;5026.346574945233;4703.858884233478;4767.499412561631;4921.753912304299;4938.456769755252;4942.975285123552;4876.439539347375;4966.950330496648;4988.650794444354;5000.649954503296;5011.398632164163;4837.298591704253;4871.34236878017;4893.681910675795;4899.877503062452;4907.877303067452 16:49:24 INFO - PROCESS | 16305 | |5;yui.html;585.1515302810924;650.9316521765214;659.3921042277789;670.9060731497591;720.8233982674245;11574.189806713533;12578.496860125595;12916.354182290872;12927.224366538003;14273.2863356832;14311.069780464288;14344.497942572012;14357.78463323051;14390;14437.061590996604;725.3725527418771;746.8767653337193;747.4730315127849;752.4582300743456;753.137657382297;606.6329870428393;742.7251916790794;760.8782594784835;760.9847803043936;765.418282105601;806.379088102161;807.5840941914919;809.550699361854;810.8905297784812;811.3995643224031;797.0515087046397;798.0941631248517;800.9639566219535;802.4583406200791;802.6428239433454;588.5968111843358;588.7703795519751;589.4989259129749;589.9646121057517;590.3152343281772;12288.385580720927;12303.384830758516;12312.630621081382;12324.260544367276;12327.753444931051;13183.340832958409;13561.525346612822;13645.385957631832;13766.31168441574;13806.17162970215;10655.04009078284;10690;10732.356058636507;10871.891281087166;11237.83143252852;3110.09538950715;3127.9061628151194;3161.2413020875056;3168.588083549124;3229.418704633165;58490.257244454275;58973.41026589722;59718.40281597216;60521.39478605201;61099.083513747326;9676.70969870905;9733.318667693335;9775.462349570842;9816.165625921734;10145.543450544596;17176.39882604103;18249.726254106197;18268.908655456904;18271;18705.438836834917;7275.0542429484885;7295.197528271886;7387.298206670358;7424.591647459441;7455.5148648178565;3194.377096466213;3692.298463291966;3771.792551409644;3779.60314167014;3822.5604055533413;2348.166400927646;3040.4071206115036;3202;3226;3237.7733558650666;485.3375142929875;491.42994126812727;501.55361728062053;501.74411050364574;503.26523276016695;577.4600748300353;583.1049339264209;588.8439563515672;616.2635650397822;621.7948077134535;8556.358273129541;8556.401051926305;8753.781155471164;8801;8864.20222180007;596.6181643748005;598.0461164442706;607.341034977046;611.626907586372;612.9693515324216;4123.2165888481095;4315.654747620246;4336.3278691802825;4387.210302145644;4482.529334419904;93899.53050234706;103777.48111259396;106446;107330;107891.46054269835;51746.48253517492;52689;71476.64261678659;89489;90342.54828725815;79515.60242198867;80515;80562.59718701369;80928;81137.99999999882;7823.374130069695;8267.255946964802;8303.709370172015;8322.20939010793;8371.790705232173;45432.31851522164;46662.76668616635;47495.33766318162;47901.28148077851;48440.5155948436;47297.527024729316;54435.45564544462;56725;63617.04574431295;63872.36127638664;11040.723981900517;11643;11677.240979336317;11834.585789497327;11837.289762614271;8504.840515661055;13385.063045586716;13581.388837502534;13868.306584670525;14080.507182248983;12163.695907602381;12228.144029918176;12329.69175770577;12487.06347023995;12938.805917911428;11764.176507644725;11884.881151188378;11884.940575297069;11890.702732431759;12475.563355282522;11459.828102578302;11705;11823.940880295544;12068.275903445821;12141.514339426323;9700.514974251118;9959.900400996186;10106.747331316776;10253.33353332031;10325.690229293134;9672.613095476103;9830.557624906753;10169.745756356151;10328.483575821028;10747.838782418114 16:49:24 INFO - PROCESS | 16305 | __end_tp_report 16:49:24 INFO - PROCESS | 16305 | __start_cc_report 16:49:24 INFO - PROCESS | 16305 | _x_x_mozilla_cycle_collect,1011 16:49:24 INFO - PROCESS | 16305 | __end_cc_report 16:49:24 INFO - PROCESS | 16305 | __startTimestamp1468885764047__endTimestamp 16:49:24 INFO - PROCESS | 16305 | 16:49:24 INFO - PROCESS | 16305 | ------- Summary: start ------- 16:49:24 INFO - PROCESS | 16305 | Number of tests: 6 16:49:24 INFO - PROCESS | 16305 | 16:49:24 INFO - PROCESS | 16305 | [#0] dojo.html Cycles:165 Average:4654.00 Median:4827.76 stddev:490.31 (10.2%) stddev-sans-first:464.28 16:49:24 INFO - PROCESS | 16305 | Values: 2588.8 2593.1 2635.2 2638.7 2642.8 4494.0 4598.0 4625.6 4657.2 4659.0 4624.1 4624.5 4647.1 4653.1 4687.2 4234.6 4242.4 4280.3 4320.7 4337.2 4346.7 4380.4 4413.7 4426.5 4437.6 4243.6 4284.9 4289.6 4303.1 4354.6 4263.3 4268.9 4288.0 4301.7 4329.4 4288.2 4335.8 4351.0 4359.4 4392.1 4568.6 4720.7 4729.2 4758.7 4759.4 4883.8 4923.2 4923.6 4958.7 4982.2 4970.4 5010.0 5026.3 5028.0 5044.6 4568.8 4667.2 4669.7 4715.4 4726.0 5127.3 5133.3 5154.7 5200.6 5225.7 4939.3 4953.8 5000.8 5000.8 5004.2 4918.3 4948.8 4992.5 5034.6 5088.7 4949.0 4956.6 4966.4 4974.0 5004.8 4953.1 4956.3 4956.5 4964.5 4993.3 4250.9 4299.0 4303.6 4349.3 4351.2 3691.5 3717.1 3734.1 3744.9 3798.8 4288.6 4291.3 4293.5 4330.3 4345.0 4984.4 4984.9 4986.2 4990.9 5023.7 4187.9 4336.6 4378.7 4389.0 4423.9 4608.7 4611.5 4613.3 4616.1 4659.3 4684.7 4784.6 4827.8 4834.2 4861.1 5088.2 5151.0 5157.4 5179.2 5181.0 4826.7 4857.1 4859.9 4860.0 4900.1 4829.9 4937.8 5069.9 5074.0 5126.0 5043.3 5052.0 5074.5 5074.7 5124.5 4938.0 4947.5 4952.0 4952.6 4994.9 4752.1 4856.8 4902.2 4920.6 4982.6 4876.7 4883.8 4910.6 4916.2 4927.4 4935.3 4957.7 4960.8 4963.7 5007.3 4617.6 4724.7 4811.2 4833.8 4866.5 16:49:24 INFO - PROCESS | 16305 | 16:49:24 INFO - PROCESS | 16305 | [#1] ext.html Cycles:180 Average:47522.64 Median:36630.04 stddev:89115.52 (243.3%) stddev-sans-first:89295.72 16:49:24 INFO - PROCESS | 16305 | Values: 547.0 584.7 588.8 592.7 597.6 39254.6 40980.2 41124.0 41169.6 45213.1 41477.4 41837.0 41861.6 41987.8 42010.2 7847.1 7982.3 7993.3 7993.4 7994.0 7849.3 7897.6 7911.2 7935.4 7940.6 7959.2 7991.4 7991.9 7994.0 7996.0 7914.0 7938.6 7956.6 7956.8 7958.9 7823.9 7866.0 7868.7 7878.8 7879.0 39013.0 39181.4 39249.2 39254.8 39263.4 3218.9 3229.7 3364.3 3515.5 3838.0 30037.7 30235.7 30257.4 30972.5 31045.2 3252.4 3270.4 3289.3 3341.3 3352.6 283522.0 286644.0 288562.0 406640.0 407331.0 30798.2 56667.0 56926.7 57064.7 57303.4 32575.2 34425.7 34606.3 34634.3 34647.0 14144.4 14373.6 14382.5 14385.4 14407.6 11692.3 11879.6 11880.5 11886.3 11892.2 8014.4 8020.1 8024.5 8116.1 8117.8 3064.1 3065.4 3095.8 3104.9 3109.6 880.2 923.4 948.6 978.2 978.9 27075.3 27596.4 28517.4 28751.7 28864.7 926.8 947.9 996.7 1026.5 1062.4 14688.4 14692.3 15088.6 15135.8 15250.3 399641.0 403213.0 403215.0 403382.0 403488.0 49446.8 50057.0 50112.5 50221.5 50242.0 1043.9 1109.6 1138.1 1155.9 1191.0 26779.3 28897.7 29481.3 29948.7 30009.5 40187.0 40329.8 41017.8 41054.4 41069.0 251152.0 251415.0 251926.0 252178.0 252859.0 22372.9 23955.8 24936.8 25959.6 25998.9 41645.6 41689.0 42037.4 42600.4 42738.8 35827.5 35929.5 36356.5 36629.6 36636.5 35818.0 36413.6 36630.5 37041.3 37142.6 34485.5 34957.7 35181.6 35570.0 35871.0 31588.7 32522.5 32585.8 32718.7 32888.7 24478.6 25019.5 25228.4 25414.7 25578.2 16:49:24 INFO - PROCESS | 16305 | 16:49:24 INFO - PROCESS | 16305 | [#2] jquery.html Cycles:180 Average:24595.99 Median:4335.12 stddev:64762.36 (1493.9%) stddev-sans-first:64918.44 16:49:24 INFO - PROCESS | 16305 | Values: 346.9 367.3 374.1 377.7 380.1 13008.2 16007.2 16811.4 17524.5 18585.2 16064.5 16371.1 16939.2 17386.3 17696.4 4407.6 5065.1 5255.1 5268.4 5268.7 5221.7 5270.4 5277.9 5284.6 5287.7 5026.5 5229.2 5229.9 5237.6 5241.6 5195.9 5212.5 5213.2 5223.5 5223.9 4548.6 4611.5 4672.7 4678.7 4679.9 21094.2 21482.5 21697.3 22087.1 22146.1 1839.5 1862.9 1894.2 1944.5 1985.3 13521.9 13544.5 13600.3 13840.9 13930.7 1721.0 1746.9 1800.3 1859.7 1998.6 67166.7 68784.7 69508.0 70370.0 71046.0 8410.7 8886.4 9010.3 9586.4 9784.0 12475.2 13350.8 13355.9 13363.8 13934.8 7251.8 7333.3 7408.3 7612.7 7742.4 7306.7 7391.8 7479.7 7496.3 7517.7 4296.4 4373.9 4391.7 4479.3 4483.1 1810.0 1842.7 1849.7 1856.9 1862.0 829.7 856.5 864.6 914.7 922.4 17135.4 17323.5 17705.0 17801.9 17927.6 810.8 869.3 874.5 906.7 910.8 9620.7 9928.5 9999.6 10032.9 10164.9 377483.0 395213.0 395415.0 395560.0 396011.0 76659.6 78678.0 80308.0 80630.6 80748.6 836.6 878.3 888.3 899.3 900.1 15069.8 15107.2 15342.3 15603.6 15696.0 35992.6 36654.0 37977.4 38077.8 38667.6 50426.7 50787.7 51500.7 51959.7 52943.2 7477.7 7595.3 7650.3 7733.7 7948.9 11147.2 11981.9 12064.2 12113.5 12262.4 10125.1 10283.8 10423.1 10457.9 10656.9 10389.0 10479.8 10535.9 10552.8 10582.2 10484.9 10488.6 10554.2 10685.5 10713.8 7558.3 7628.2 7644.7 7761.0 7947.8 7780.0 9129.0 10408.4 10488.8 10736.1 16:49:24 INFO - PROCESS | 16305 | 16:49:24 INFO - PROCESS | 16305 | [#3] mootools.html Cycles:180 Average:5144.43 Median:5531.95 stddev:2881.41 (52.1%) stddev-sans-first:2874.70 16:49:24 INFO - PROCESS | 16305 | Values: 1258.4 1361.0 1402.3 1459.7 1677.3 6631.9 7600.7 7870.4 7873.2 8100.1 7854.3 8005.3 8010.0 8013.2 8020.9 2173.2 2208.8 2209.3 2210.6 2214.9 2140.2 2172.9 2186.2 2189.3 2191.3 2132.7 2146.2 2156.3 2158.1 2161.4 2111.5 2114.6 2117.1 2117.4 2118.2 2039.2 2087.0 2103.5 2106.2 2109.3 7610.5 7894.4 7927.5 7979.7 7995.8 4602.1 4700.2 4708.2 4708.4 4711.7 2767.6 2868.6 2897.8 2907.3 2908.9 942.3 949.6 951.7 954.6 960.9 10634.3 10744.1 10744.5 10760.8 10764.0 3903.5 4072.6 4126.3 4133.9 4217.1 8416.6 8462.1 8486.0 8488.7 8490.7 4697.1 4698.9 4699.4 4699.9 4700.2 3727.6 3769.2 3940.6 4336.1 4366.0 3330.1 3368.6 3393.8 3421.1 3444.6 1000.3 1032.0 1045.4 1051.2 1068.8 849.4 849.9 870.6 878.9 890.2 5438.8 5517.7 5546.2 5677.5 5700.7 887.8 901.2 903.0 906.2 913.8 3651.9 3675.9 3724.8 3740.0 3845.1 9598.7 9694.7 9823.6 9900.5 9907.9 9733.2 9813.0 9841.8 9847.3 9860.9 7178.1 7244.0 7247.9 7302.5 7427.1 5270.6 5330.8 5332.7 5368.2 5547.2 8814.3 8972.5 9046.0 9046.4 9059.5 7849.6 8538.2 8924.8 8950.6 9010.5 6371.7 6522.4 6544.3 6561.8 6667.7 7377.8 7425.8 7432.3 7435.8 7494.7 6784.9 6836.5 6869.4 6872.7 6921.0 6596.1 6791.5 6841.5 6845.2 6911.3 6424.8 6481.0 6500.8 6506.8 6518.9 4602.8 4829.1 4833.7 4862.5 4872.0 4682.2 4699.8 4707.8 4729.2 4792.0 16:49:24 INFO - PROCESS | 16305 | 16:49:24 INFO - PROCESS | 16305 | [#4] prototype.html Cycles:180 Average:4110.07 Median:4692.93 stddev:1188.48 (25.3%) stddev-sans-first:1156.61 16:49:24 INFO - PROCESS | 16305 | Values: 284.5 297.6 298.1 299.8 305.0 4690.9 4713.7 4737.7 4742.7 4757.4 4402.3 4558.2 4560.9 4579.5 4679.5 3606.3 3640.7 3719.3 3742.3 3759.8 3715.4 3729.3 3735.3 3747.8 3748.5 3614.3 3695.5 3704.9 3713.6 3722.7 3693.2 3704.2 3706.4 3709.3 3709.7 3284.4 3287.4 3288.4 3290.7 3300.1 4494.2 4554.9 4558.2 4576.5 4579.7 4022.9 4224.2 4230.9 4233.0 4268.0 4909.3 4914.8 4918.5 4925.8 4931.6 3619.8 3624.1 3624.3 3627.5 3630.9 4886.5 5103.4 5144.6 5154.2 5162.4 3551.2 3573.3 3577.3 3578.6 3579.5 3629.5 3630.7 3631.9 3635.1 3638.4 4157.7 4157.9 4159.9 4161.0 4163.7 4695.0 4925.4 4930.1 4951.5 4964.4 1242.8 1244.9 1246.9 1250.7 1258.3 786.7 838.6 853.6 858.1 862.3 4294.0 4300.4 4309.6 4320.7 4321.2 4981.6 4988.5 4988.7 4989.1 5033.9 4159.1 4332.5 4333.8 4337.4 4394.8 2704.3 2714.3 2717.1 2718.8 2735.5 4834.8 4836.9 4844.5 4853.1 4863.4 4968.6 5190.8 5194.9 5211.4 5226.4 4786.5 4838.2 4839.5 4849.8 4856.6 5111.3 5111.4 5116.2 5118.4 5123.9 4708.5 5019.9 5020.7 5044.3 5052.7 4838.6 4850.0 4865.8 4867.6 4873.4 3612.4 3616.7 3617.1 3617.4 3636.4 5061.0 5073.4 5105.0 5116.6 5125.3 4976.6 4977.9 4996.7 5005.4 5018.9 4959.5 4998.9 5002.4 5013.8 5026.3 4703.9 4767.5 4921.8 4938.5 4943.0 4876.4 4967.0 4988.7 5000.6 5011.4 4837.3 4871.3 4893.7 4899.9 4907.9 16:49:24 INFO - PROCESS | 16305 | 16:49:24 INFO - PROCESS | 16305 | [#5] yui.html Cycles:180 Average:17690.08 Median:24462.93 stddev:25313.21 (103.5%) stddev-sans-first:25351.64 16:49:24 INFO - PROCESS | 16305 | Values: 585.2 650.9 659.4 670.9 720.8 11574.2 12578.5 12916.4 12927.2 14273.3 14311.1 14344.5 14357.8 14390.0 14437.1 725.4 746.9 747.5 752.5 753.1 606.6 742.7 760.9 761.0 765.4 806.4 807.6 809.6 810.9 811.4 797.1 798.1 801.0 802.5 802.6 588.6 588.8 589.5 590.0 590.3 12288.4 12303.4 12312.6 12324.3 12327.8 13183.3 13561.5 13645.4 13766.3 13806.2 10655.0 10690.0 10732.4 10871.9 11237.8 3110.1 3127.9 3161.2 3168.6 3229.4 58490.3 58973.4 59718.4 60521.4 61099.1 9676.7 9733.3 9775.5 9816.2 10145.5 17176.4 18249.7 18268.9 18271.0 18705.4 7275.1 7295.2 7387.3 7424.6 7455.5 3194.4 3692.3 3771.8 3779.6 3822.6 2348.2 3040.4 3202.0 3226.0 3237.8 485.3 491.4 501.6 501.7 503.3 577.5 583.1 588.8 616.3 621.8 8556.4 8556.4 8753.8 8801.0 8864.2 596.6 598.0 607.3 611.6 613.0 4123.2 4315.7 4336.3 4387.2 4482.5 93899.5 103777.5 106446.0 107330.0 107891.5 51746.5 52689.0 71476.6 89489.0 90342.5 79515.6 80515.0 80562.6 80928.0 81138.0 7823.4 8267.3 8303.7 8322.2 8371.8 45432.3 46662.8 47495.3 47901.3 48440.5 47297.5 54435.5 56725.0 63617.0 63872.4 11040.7 11643.0 11677.2 11834.6 11837.3 8504.8 13385.1 13581.4 13868.3 14080.5 12163.7 12228.1 12329.7 12487.1 12938.8 11764.2 11884.9 11884.9 11890.7 12475.6 11459.8 11705.0 11823.9 12068.3 12141.5 9700.5 9959.9 10106.7 10253.3 10325.7 9672.6 9830.6 10169.7 10328.5 10747.8 16:49:24 INFO - PROCESS | 16305 | -------- Summary: end -------- 16:49:24 INFO - PROCESS | 16305 | 16:49:24 INFO - TEST-INFO | 16305: exit 0 16:49:24 INFO - TEST-OK | dromaeo_css | took 1118593ms 16:49:24 INFO - TEST-START | kraken 16:49:24 INFO - operating with platform_type : linux_ 16:49:24 INFO - Initialising browser for kraken test... 16:49:24 INFO - TEST-INFO | started process 16494 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprSd4fb/profile http://localhost:47097/getInfo.html) 16:49:28 INFO - PROCESS | 16494 | __metrics Screen width/height:1600/1200 16:49:28 INFO - PROCESS | 16494 | colorDepth:24 16:49:28 INFO - PROCESS | 16494 | Browser inner width/height: 1024/697 16:49:28 INFO - PROCESS | 16494 | __metrics 16:49:29 INFO - PROCESS | 16494 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:49:29 INFO - PROCESS | 16494 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:49:30 INFO - TEST-INFO | 16494: exit 0 16:49:30 INFO - Browser initialized. 16:49:30 INFO - Running cycle 1/1 for kraken test... 16:49:30 INFO - TEST-INFO | started process 16574 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprSd4fb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:49:42 INFO - PROCESS | 16574 | RSS: Main: 159145984 16:49:42 INFO - PROCESS | 16574 | 16:49:44 INFO - PROCESS | 16574 | Cycle 1(1): loaded http://localhost:47097/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47097/tests/kraken/driver.html?testName=audio-beat-detection) 16:49:45 INFO - PROCESS | 16574 | RSS: Main: 218185728 16:49:45 INFO - PROCESS | 16574 | 16:49:48 INFO - PROCESS | 16574 | Cycle 1(1): loaded http://localhost:47097/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47097/tests/kraken/driver.html?testName=audio-dft) 16:49:48 INFO - PROCESS | 16574 | RSS: Main: 206643200 16:49:48 INFO - PROCESS | 16574 | 16:49:52 INFO - PROCESS | 16574 | Cycle 1(1): loaded http://localhost:47097/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47097/tests/kraken/driver.html?testName=audio-fft) 16:49:52 INFO - PROCESS | 16574 | RSS: Main: 271630336 16:49:52 INFO - PROCESS | 16574 | 16:49:55 INFO - PROCESS | 16574 | Cycle 1(1): loaded http://localhost:47097/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47097/tests/kraken/driver.html?testName=audio-oscillator) 16:49:55 INFO - PROCESS | 16574 | RSS: Main: 181616640 16:49:55 INFO - PROCESS | 16574 | 16:49:57 INFO - PROCESS | 16574 | Cycle 1(1): loaded http://localhost:47097/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47097/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:49:58 INFO - PROCESS | 16574 | RSS: Main: 186437632 16:49:58 INFO - PROCESS | 16574 | 16:50:01 INFO - PROCESS | 16574 | Cycle 1(1): loaded http://localhost:47097/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47097/tests/kraken/driver.html?testName=imaging-darkroom) 16:50:02 INFO - PROCESS | 16574 | RSS: Main: 222154752 16:50:02 INFO - PROCESS | 16574 | 16:50:05 INFO - PROCESS | 16574 | Cycle 1(1): loaded http://localhost:47097/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47097/tests/kraken/driver.html?testName=imaging-desaturate) 16:50:06 INFO - PROCESS | 16574 | RSS: Main: 222748672 16:50:06 INFO - PROCESS | 16574 | 16:50:09 INFO - PROCESS | 16574 | Cycle 1(1): loaded http://localhost:47097/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47097/tests/kraken/driver.html?testName=json-parse-financial) 16:50:09 INFO - PROCESS | 16574 | RSS: Main: 223178752 16:50:09 INFO - PROCESS | 16574 | 16:50:11 INFO - PROCESS | 16574 | Cycle 1(1): loaded http://localhost:47097/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47097/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:50:12 INFO - PROCESS | 16574 | RSS: Main: 211824640 16:50:12 INFO - PROCESS | 16574 | 16:50:14 INFO - PROCESS | 16574 | Cycle 1(1): loaded http://localhost:47097/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47097/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:50:14 INFO - PROCESS | 16574 | RSS: Main: 214814720 16:50:14 INFO - PROCESS | 16574 | 16:50:16 INFO - PROCESS | 16574 | Cycle 1(1): loaded http://localhost:47097/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47097/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:50:17 INFO - PROCESS | 16574 | RSS: Main: 210460672 16:50:17 INFO - PROCESS | 16574 | 16:50:20 INFO - PROCESS | 16574 | Cycle 1(1): loaded http://localhost:47097/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47097/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:50:20 INFO - PROCESS | 16574 | RSS: Main: 226369536 16:50:20 INFO - PROCESS | 16574 | 16:50:23 INFO - PROCESS | 16574 | Cycle 1(1): loaded http://localhost:47097/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47097/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:50:24 INFO - PROCESS | 16574 | RSS: Main: 227463168 16:50:24 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | Cycle 1(1): loaded http://localhost:47097/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47097/tests/kraken/driver.html?testName=ai-astar) 16:50:26 INFO - PROCESS | 16574 | RSS: Main: 269967360 16:50:26 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | __start_tp_report 16:50:26 INFO - PROCESS | 16574 | _x_x_mozilla_page_load 16:50:26 INFO - PROCESS | 16574 | _x_x_mozilla_page_load_details 16:50:26 INFO - PROCESS | 16574 | |i|pagename|runs| 16:50:26 INFO - PROCESS | 16574 | |0;ai-astar;104;114;114;113;114;114;112;112;113;112 16:50:26 INFO - PROCESS | 16574 | |1;audio-beat-detection;154;122;126;180;123;123;148;122;187;130 16:50:26 INFO - PROCESS | 16574 | |2;audio-dft;161;164;163;161;162;162;162;165;163;161 16:50:26 INFO - PROCESS | 16574 | |3;audio-fft;75;76;76;108;77;78;77;75;104;76 16:50:26 INFO - PROCESS | 16574 | |4;audio-oscillator;84;85;121;86;85;85;124;84;85;127 16:50:26 INFO - PROCESS | 16574 | |5;imaging-gaussian-blur;117;116;117;117;118;117;117;117;118;117 16:50:26 INFO - PROCESS | 16574 | |6;imaging-darkroom;146;147;146;148;147;146;146;146;147;146 16:50:26 INFO - PROCESS | 16574 | |7;imaging-desaturate;95;95;95;95;95;96;95;95;96;94 16:50:26 INFO - PROCESS | 16574 | |8;json-parse-financial;63;63;64;62;62;63;64;63;64;62 16:50:26 INFO - PROCESS | 16574 | |9;json-stringify-tinderbox;65;66;65;66;66;65;65;66;67;67 16:50:26 INFO - PROCESS | 16574 | |10;stanford-crypto-aes;78;77;77;77;78;78;78;78;78;80 16:50:26 INFO - PROCESS | 16574 | |11;stanford-crypto-ccm;127;131;129;139;134;132;137;129;224;148 16:50:26 INFO - PROCESS | 16574 | |12;stanford-crypto-pbkdf2;166;164;162;163;164;166;170;163;168;165 16:50:26 INFO - PROCESS | 16574 | |13;stanford-crypto-sha256-iterative;66;66;67;68;67;66;66;66;66;65 16:50:26 INFO - PROCESS | 16574 | __end_tp_report 16:50:26 INFO - PROCESS | 16574 | __start_cc_report 16:50:26 INFO - PROCESS | 16574 | _x_x_mozilla_cycle_collect,1915 16:50:26 INFO - PROCESS | 16574 | __end_cc_report 16:50:26 INFO - PROCESS | 16574 | __startTimestamp1468885826374__endTimestamp 16:50:26 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | ------- Summary: start ------- 16:50:26 INFO - PROCESS | 16574 | Number of tests: 14 16:50:26 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.50 stddev:3.01 (2.7%) stddev-sans-first:0.93 16:50:26 INFO - PROCESS | 16574 | Values: 104.0 114.0 114.0 113.0 114.0 114.0 112.0 112.0 113.0 112.0 16:50:26 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:139.00 stddev:24.87 (17.9%) stddev-sans-first:25.97 16:50:26 INFO - PROCESS | 16574 | Values: 154.0 122.0 126.0 180.0 123.0 123.0 148.0 122.0 187.0 130.0 16:50:26 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | [#2] audio-dft Cycles:10 Average:162.40 Median:162.50 stddev:1.35 (0.8%) stddev-sans-first:1.33 16:50:26 INFO - PROCESS | 16574 | Values: 161.0 164.0 163.0 161.0 162.0 162.0 162.0 165.0 163.0 161.0 16:50:26 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | [#3] audio-fft Cycles:10 Average:82.20 Median:76.00 stddev:12.61 (16.6%) stddev-sans-first:13.11 16:50:26 INFO - PROCESS | 16574 | Values: 75.0 76.0 76.0 108.0 77.0 78.0 77.0 75.0 104.0 76.0 16:50:26 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:85.00 stddev:18.97 (22.3%) stddev-sans-first:19.56 16:50:26 INFO - PROCESS | 16574 | Values: 84.0 85.0 121.0 86.0 85.0 85.0 124.0 84.0 85.0 127.0 16:50:26 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 16:50:26 INFO - PROCESS | 16574 | Values: 117.0 116.0 117.0 117.0 118.0 117.0 117.0 117.0 118.0 117.0 16:50:26 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:146.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 16:50:26 INFO - PROCESS | 16574 | Values: 146.0 147.0 146.0 148.0 147.0 146.0 146.0 146.0 147.0 146.0 16:50:26 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:50:26 INFO - PROCESS | 16574 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 94.0 16:50:26 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | [#8] json-parse-financial Cycles:10 Average:63.00 Median:63.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 16:50:26 INFO - PROCESS | 16574 | Values: 63.0 63.0 64.0 62.0 62.0 63.0 64.0 63.0 64.0 62.0 16:50:26 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.79 (1.2%) stddev-sans-first:0.78 16:50:26 INFO - PROCESS | 16574 | Values: 65.0 66.0 65.0 66.0 66.0 65.0 65.0 66.0 67.0 67.0 16:50:26 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | [#10] stanford-crypto-aes Cycles:10 Average:77.90 Median:78.00 stddev:0.88 (1.1%) stddev-sans-first:0.93 16:50:26 INFO - PROCESS | 16574 | Values: 78.0 77.0 77.0 77.0 78.0 78.0 78.0 78.0 78.0 80.0 16:50:26 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | [#11] stanford-crypto-ccm Cycles:10 Average:143.00 Median:135.50 stddev:29.12 (21.5%) stddev-sans-first:30.31 16:50:26 INFO - PROCESS | 16574 | Values: 127.0 131.0 129.0 139.0 134.0 132.0 137.0 129.0 224.0 148.0 16:50:26 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.10 Median:165.50 stddev:2.47 (1.5%) stddev-sans-first:2.60 16:50:26 INFO - PROCESS | 16574 | Values: 166.0 164.0 162.0 163.0 164.0 166.0 170.0 163.0 168.0 165.0 16:50:26 INFO - PROCESS | 16574 | 16:50:26 INFO - PROCESS | 16574 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 16:50:26 INFO - PROCESS | 16574 | Values: 66.0 66.0 67.0 68.0 67.0 66.0 66.0 66.0 66.0 65.0 16:50:26 INFO - PROCESS | 16574 | -------- Summary: end -------- 16:50:26 INFO - PROCESS | 16574 | 16:50:27 INFO - TEST-INFO | 16574: exit 0 16:50:27 INFO - TEST-OK | kraken | took 62637ms 16:50:27 INFO - SUITE-END | took 1181s 16:50:27 INFO - Completed test suite (00:19:41) 16:50:27 INFO - javascript benchmark 16:50:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2588.844669319841, 2593.105378644367, 2635.156749840051, 2638.6833579970407, 2642.8017898657595, 4493.977530112353, 4597.954020459794, 4625.5836974672275, 4657.231556793129, 4659.044895796362, 4624.05206932578, 4624.514425985273, 4647.07058588282, 4653.115907977482, 4687.179743544874, 4234.618884300412, 4242.363645453192, 4280.250956082688, 4320.675949303799, 4337.154254920292, 4346.6522678185665, 4380.364847097185, 4413.6910416270875, 4426.490953540352, 4437.5784300491405, 4243.554426785174, 4284.892877678052, 4289.571042895685, 4303.117860838552, 4354.608085272341, 4263.339182426729, 4268.850590229327, 4287.957120428787, 4301.698881078326, 4329.393884856091, 4288.24955632763, 4335.804888780012, 4351.042770590465, 4359.367891655692, 4392.121575684876, 4568.634509239286, 4720.693154944917, 4729.196036673739, 4758.690685105492, 4759.357486739281, 4883.780229889663, 4923.18767403378, 4923.606111511071, 4958.677685950402, 4982.227754698028, 4970.403551573798, 5010.023045506127, 5026.29631851541, 5028.044671512401, 5044.621653375974, 4568.794404251817, 4667.183242932473, 4669.719816810934, 4715.41057367829, 4725.976370118127, 5127.256547800548, 5133.25567792668, 5154.690718556899, 5200.63595548308, 5225.712585807817, 4939.259111133287, 4953.752312384367, 5000.774965126505, 5000.849974500771, 5004.199328107558, 4918.3360246367, 4948.777305021283, 4992.525710057539, 5034.622403319766, 5088.72012039334, 4949.0, 4956.578690811321, 4966.404031516241, 4974.0, 5004.84985450437, 4953.083679519301, 4956.256561515802, 4956.479569645207, 4964.528369804862, 4993.251012348177, 4250.936235956463, 4299.032717638569, 4303.63419109373, 4349.325854492558, 4351.238533256667, 3691.483192353073, 3717.126475278307, 3734.066483379155, 3744.8689295874515, 3798.753081049723, 4288.592583704543, 4291.270484017725, 4293.484781826201, 4330.307150855848, 4344.978275108604, 4984.40187177541, 4984.925226121611, 4986.152354099811, 4990.850274491771, 5023.723695196726, 4187.853425130106, 4336.631386332134, 4378.737275763465, 4389.0, 4423.889402764892, 4608.654350923694, 4611.492735799059, 4613.331066995334, 4616.053708989649, 4659.254519276899, 4684.695494792828, 4784.641151913551, 4827.758612069382, 4834.20235661112, 4861.124997500484, 5088.236764485357, 5150.97424512875, 5157.355330583677, 5179.249008893765, 5180.9740951295, 4826.686265392738, 4857.101436234308, 4859.878503037311, 4859.975700121477, 4900.068986892479, 4829.9275510868065, 4937.802487900443, 5069.898602027865, 5073.974630126826, 5125.974370128126, 5043.319151914445, 5052.040112378636, 5074.517920797666, 5074.720890351066, 5124.5131712487255, 4938.0, 4947.455779864284, 4951.9752401237765, 4952.628552858622, 4994.900101998012, 4752.097101550694, 4856.781444835062, 4902.1911384621135, 4920.5571498565305, 4982.626303027215, 4876.731779752147, 4883.755812209446, 4910.558049775537, 4916.237983112624, 4927.433345165352, 4935.309056732132, 4957.702537847741, 4960.751962401793, 4963.677360971526, 5007.349044624176, 4617.58441740245, 4724.71651700899, 4811.1820990431015, 4833.830815921426, 4866.513348665105], "value": 4622.49481436234, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [547.0254904527687, 584.6813486649776, 588.7792077970761, 592.718458732102, 597.5607928172792, 39254.607453925455, 40980.180396392054, 41124.0, 41169.58830411695, 45213.09573808522, 41477.37783933243, 41837.0, 41861.581384186145, 41987.79006104973, 42010.159796804044, 7847.058352997634, 7982.28159465648, 7993.320567751748, 7993.44045916786, 7994.0, 7849.332806711408, 7897.644605992716, 7911.169327220645, 7935.444518883681, 7940.602969851484, 7959.204079592052, 7991.440599158062, 7991.880121798177, 7994.0, 7996.0404751429905, 7914.0, 7938.563379014152, 7956.562389068599, 7956.801079972989, 7958.880616790695, 7823.921760782433, 7866.0, 7868.724594639218, 7878.7787892876695, 7878.960605196995, 39013.02467438337, 39181.41227881584, 39249.21501569981, 39254.803725981474, 39263.41104883429, 3218.8873389431255, 3229.6931791480006, 3364.327134573095, 3515.5078289039557, 3837.9808100959594, 30037.699623003708, 30235.697643023508, 30257.394852103054, 30972.535411968838, 31045.22386940322, 3252.414565378231, 3270.3622793555264, 3289.276359200972, 3341.281624450755, 3352.614449338333, 283522.0, 286644.0, 288562.0, 406640.0, 407331.0, 30798.230044249074, 56667.0, 56926.715366422904, 57064.71467642635, 57303.42696573064, 32575.185620359207, 34425.65574344224, 34606.30787384238, 34634.307313853584, 34647.0, 14144.434222631186, 14373.568792936228, 14382.496612618508, 14385.424583016771, 14407.63980900465, 11692.29846209213, 11879.643610691694, 11880.524779008916, 11886.28682279066, 11892.227005244631, 8014.358851291882, 8020.11778704342, 8024.518528888286, 8116.066652335057, 8117.837643247102, 3064.1420402287395, 3065.371598822236, 3095.7987730797427, 3104.9223769405494, 3109.595752552154, 880.190224993008, 923.4367036107968, 948.6347756113946, 978.2076518020426, 978.8629591857145, 27075.323116922234, 27596.448071038467, 28517.42965140727, 28751.712482875322, 28864.71135288662, 926.8331700293877, 947.9478628675352, 996.7358649957768, 1026.5329275179774, 1062.3519653011656, 14688.41246350134, 14692.26538673062, 15088.622784430258, 15135.848641513665, 15250.313735881911, 399641.0, 403213.0, 403215.0, 403382.0, 403488.0, 49446.752766236656, 50057.0, 50112.498875011515, 50221.475637679825, 50242.0, 1043.8851726310252, 1109.6227282723912, 1138.1350173867754, 1155.930644161353, 1190.9761804763857, 26779.33051673724, 28897.711022890344, 29481.262968425104, 29948.70051299459, 30009.549856752605, 40187.0, 40329.79835100806, 41017.79491102525, 41054.38418423786, 41069.0, 251152.0, 251415.0, 251926.0, 252178.0, 252859.0, 22372.888135559217, 23955.760442395353, 24936.750632494168, 25959.610605841306, 25998.87000564985, 41645.58354416538, 41689.0, 42037.36943945904, 42600.36099458449, 42738.78630606827, 35827.462588061724, 35929.46105808362, 36356.454653179695, 36629.63370366369, 36636.45045324269, 35818.0, 36413.635863642085, 36630.45054324134, 37041.25917481689, 37142.628573714996, 34485.48271775875, 34957.65042349544, 35181.648183517835, 35570.0, 35871.0, 31588.684113158575, 32522.51216231711, 32585.837070815443, 32718.672813272515, 32888.67111328952, 24478.63282050735, 25019.499610007333, 25228.369290767143, 25414.745852541237, 25578.23265302044], "value": 16743.182309985878, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.88726163996705, 367.2947939955287, 374.11708368250925, 377.7469095705878, 380.1086452269427, 13008.219506829597, 16007.19964001801, 16811.411600593965, 17524.474265772016, 18585.163667634955, 16064.518064458056, 16371.099589522571, 16939.237734301954, 17386.304547818072, 17696.38062667807, 4407.625351845081, 5065.113605119099, 5255.106631872572, 5268.394134674523, 5268.683878967274, 5221.660592061504, 5270.39390470097, 5277.947220527803, 5284.577233821305, 5287.735613219343, 5026.472220416858, 5229.241759944805, 5229.947700523003, 5237.554807841338, 5241.606879484054, 5195.922061169085, 5212.5308722214995, 5213.165893457029, 5223.451537588575, 5223.921641175367, 4548.636109111263, 4611.469680986663, 4672.742999135046, 4678.719276843401, 4679.906401871977, 21094.156233750786, 21482.462938426506, 21697.34907952764, 22087.116515339367, 22146.114155433763, 1839.5217243516647, 1862.9254829806791, 1894.2423030787656, 1944.4555524453176, 1985.3349128042123, 13521.932390337985, 13544.525941591997, 13600.31998400086, 13840.861591384159, 13930.651733706638, 1721.0276193950378, 1746.8515176210037, 1800.297883825309, 1859.665260253154, 1998.580298137393, 67166.66416667934, 68784.6560767193, 69508.0, 70370.0, 71046.0, 8410.747677569683, 8886.422382545114, 9010.27917766577, 9586.424814511152, 9784.0, 12475.189112707645, 13350.799738003938, 13355.866441335462, 13363.799543006862, 13934.790978135336, 7251.818704532323, 7333.2766799802, 7408.296211859864, 7612.657430415645, 7742.380609551223, 7306.707731690779, 7391.778246652609, 7479.70081196746, 7496.325330720261, 7517.699292028367, 4296.3555466680245, 4373.890652733707, 4391.714538554984, 4479.328100784843, 4483.058557702853, 1809.9819001810076, 1842.6591080650123, 1849.713294439364, 1856.925722971066, 1862.0059971361354, 829.7344849648055, 856.4604299291531, 864.5504337744463, 914.6707185413246, 922.423485321674, 17135.400260990806, 17323.48029559115, 17705.0, 17801.910990445223, 17927.641447170983, 810.785141937387, 869.3045563549135, 874.4578361415963, 906.7461110888962, 910.7540963939832, 9620.663276785279, 9928.50357482123, 9999.550020249106, 10032.949835250776, 10164.949175254076, 377483.0, 395213.0, 395415.0, 395560.0, 396011.0, 76659.61670191614, 78678.0, 80308.0, 80630.59684701657, 80748.59625701833, 836.5900708652716, 878.2885862451435, 888.2538667519315, 899.3344924755634, 900.090908182727, 15069.773953390928, 15107.244637768288, 15342.309596068428, 15603.609909752347, 15696.0, 35992.64007359998, 36654.0, 37977.4303385455, 38077.809610951765, 38667.613323866404, 50426.74786626043, 50787.746061269456, 51500.742496287276, 51959.74020129875, 52943.20585191302, 7477.744807121575, 7595.3164215220895, 7650.273224043707, 7733.729319473791, 7948.920510794818, 11147.163962702993, 11981.940090299493, 12064.155509114278, 12113.515459381879, 12262.4481898313, 10125.088742013255, 10283.84574231402, 10423.114035307084, 10457.895421045996, 10656.946715266375, 10389.0, 10479.842802357818, 10535.894641053492, 10552.841707374242, 10582.153427725609, 10484.947575262075, 10488.580456781949, 10554.2084343673, 10685.465726713788, 10713.839292410465, 7558.33716582351, 7628.23717628255, 7644.732434364771, 7761.0, 7947.801304967422, 7779.988601481998, 9128.954355228181, 10408.427536485566, 10488.790224195322, 10736.141108711128], "value": 8289.432472468125, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1258.3708145927037, 1360.9523666671669, 1402.2918426194774, 1459.7299499592575, 1677.2955358749332, 6631.933680663198, 7600.695972161114, 7870.370370370357, 7873.212678732124, 8100.149484304152, 7854.293113619773, 8005.279524842763, 8010.038795344567, 8013.158618345077, 8020.919790802091, 2173.217641649005, 2208.8012078912893, 2209.3482422685265, 2210.6462965925457, 2214.9114035438565, 2140.1867290429614, 2172.8587641803233, 2186.1911092895652, 2189.2994241842616, 2191.2549733090746, 2132.6907598398225, 2146.2273581510644, 2156.277646988261, 2158.0936006877073, 2161.3948094533557, 2111.5460176062143, 2114.608797372491, 2117.14255726431, 2117.3859580721573, 2118.2374345235703, 2039.2454791727157, 2087.039961617658, 2103.4741314671332, 2106.24175296893, 2109.261758384553, 7610.467267291292, 7894.368450524002, 7927.5243485391065, 7979.680812767482, 7995.840083198362, 4602.079584083163, 4700.177468942921, 4708.223143181371, 4708.434987801452, 4711.670183087185, 2767.640206773127, 2868.584055311989, 2897.826130432181, 2907.2731817045737, 2908.8691008904652, 942.2509105261333, 949.586929685589, 951.743029382063, 954.6420092465326, 960.9231261499064, 10634.308769930007, 10744.14046876248, 10744.516496757664, 10760.784784304271, 10764.031237188612, 3903.4730311407598, 4072.6131017553284, 4126.257273690764, 4133.937990930079, 4217.072244106292, 8416.579171041545, 8462.11147829469, 8485.95757021211, 8488.74533763988, 8490.702825401082, 4697.084068606658, 4698.906021879543, 4699.365585645894, 4699.882502937454, 4700.153972284953, 3727.552693676776, 3769.208466222124, 3940.566537680853, 4336.067745434747, 4365.978170109129, 3330.0675810772536, 3368.6294507604143, 3393.8133402662597, 3421.1447138215444, 3444.603870554868, 1000.2845120618175, 1031.9638812641522, 1045.3780000899453, 1051.1853313681804, 1068.8022715797601, 849.4011721736161, 849.9150084991452, 870.6386849457405, 878.9208971192623, 890.2432932007871, 5438.782448702008, 5517.724113794295, 5546.168074788814, 5677.460641239076, 5700.657960522382, 887.8046829697456, 901.2114399900088, 902.9992829414264, 906.1527471813876, 913.7669894177076, 3651.8721844735305, 3675.933979146071, 3724.7578907370935, 3740.0, 3845.115623406632, 9598.712038638852, 9694.660686875926, 9823.607055717692, 9900.504974751379, 9907.851382229272, 9733.2213422926, 9813.01869813013, 9841.753956151011, 9847.261455390875, 9860.852087218698, 7178.066851309296, 7243.963780181065, 7247.855042899218, 7302.525335853259, 7427.071616047994, 5270.552003079782, 5330.813421530228, 5332.70670113132, 5368.2484452177505, 5547.1679248113105, 8814.294856411343, 8972.506512141634, 9045.954770226106, 9046.411983221069, 9059.501727405055, 7849.568273744999, 8538.231559159705, 8924.776880578038, 8950.552472376225, 9010.549472526216, 6371.74513019474, 6522.412982831568, 6544.28012918569, 6561.80314590563, 6667.66661666905, 7377.77866664001, 7425.8143546411775, 7432.293932076444, 7435.776926692208, 7494.662740176596, 6784.93215067843, 6836.453083753388, 6869.416099631388, 6872.656367181521, 6921.0, 6596.109525214035, 6791.524593278423, 6841.521093523405, 6845.247022827584, 6911.274316196727, 6424.839379015375, 6481.027845823164, 6500.837479063061, 6506.837329066811, 6518.934810652023, 4602.838900638461, 4829.05833362505, 4833.709977401367, 4862.4651288357545, 4872.0, 4682.157211701926, 4699.788509517011, 4707.788149533348, 4729.196036673705, 4792.041591681747], "value": 4136.167620530578, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.5447284345047, 297.55366949575637, 298.0804218984433, 299.7781641585226, 304.9618797650293, 4690.90618187636, 4713.717176969384, 4737.715737055779, 4742.739149346785, 4757.42910850698, 4402.273624851896, 4558.247889098295, 4560.9087818243615, 4579.519150489201, 4679.532046795319, 3606.296772129436, 3640.7451478396456, 3719.3119272934596, 3742.345089609321, 3759.793211373373, 3715.386961151407, 3729.291434627412, 3735.2716220337047, 3747.8126093695205, 3748.5314335708035, 3614.3313487004857, 3695.5380577427823, 3704.9259014819822, 3713.6100709425527, 3722.7207959403017, 3693.169036966688, 3704.240485769265, 3706.406245314514, 3709.295233905563, 3709.7217708671956, 3284.3759685659647, 3287.3589650018257, 3288.3752087103376, 3290.7038366546885, 3300.092474569489, 4494.213512635276, 4554.908901821978, 4558.179527685015, 4576.542345765397, 4579.67942244043, 4022.9195416091807, 4224.155168966219, 4230.873073807791, 4232.978835105805, 4268.0, 4909.3126962225315, 4914.754262286907, 4918.483559226265, 4925.827596034122, 4931.5944955687455, 3619.8416506717863, 3624.1483251435893, 3624.3476174288617, 3627.4740162676526, 3630.8729194478065, 4886.511348865156, 5103.413107492612, 5144.56271216947, 5154.175331946871, 5162.406323272834, 3551.1654761131113, 3573.3210689968823, 3577.266660334625, 3578.6063533011347, 3579.5167652367118, 3629.491871138043, 3630.6913912317223, 3631.94552081719, 3635.1457407509215, 3638.4178531434845, 4157.708960372805, 4157.91684166321, 4159.91680166395, 4160.958390416057, 4163.7293575917465, 4694.95305046952, 4925.374626373288, 4930.063287975273, 4951.480094590088, 4964.379452568429, 1242.8260043593905, 1244.8506179258547, 1246.9151837901084, 1250.6935800728697, 1258.3205069262497, 786.6853258696567, 838.6393850644281, 853.6457370191425, 858.1461445861407, 862.3230763850347, 4293.97853010733, 4300.354946757949, 4309.633681137076, 4320.65434765218, 4321.222180007629, 4981.580249628839, 4988.478514053176, 4988.6757360771435, 4989.126902791997, 5033.899322013613, 4159.1265834175165, 4332.523422423531, 4333.826646934087, 4337.37108119321, 4394.846525202692, 2704.3104008478103, 2714.3078514978556, 2717.1169369954846, 2718.7824974002033, 2735.480258750831, 4834.830780922651, 4836.903261934812, 4844.515548445198, 4853.136649376885, 4863.440704319049, 4968.552830245295, 5190.844274671766, 5194.870128246824, 5211.374635043819, 5226.372835259793, 4786.497417771084, 4838.153323168361, 4839.467658557556, 4849.75751212445, 4856.562909338177, 5111.284420181252, 5111.437741848468, 5116.2372318947355, 5118.38579370478, 5123.923141152811, 4708.529147085264, 5019.941823852203, 5020.698758074527, 5044.293798868235, 5052.69683818972, 4838.637102217418, 4850.0, 4865.756712164307, 4867.610591152628, 4873.439554451142, 3612.422012477991, 3616.65641764032, 3617.113807117273, 3617.3669607818, 3636.3818150914935, 5060.952719656959, 5073.416557095834, 5104.979004199101, 5116.641835071509, 5125.2568377585785, 4976.552110310089, 4977.850664479926, 4996.675216110941, 5005.399352077774, 5018.946021335414, 4959.504049595012, 4998.875028124326, 5002.399712034579, 5013.824516142064, 5026.346574945233, 4703.858884233478, 4767.499412561631, 4921.753912304299, 4938.456769755252, 4942.975285123552, 4876.439539347375, 4966.950330496648, 4988.650794444354, 5000.649954503296, 5011.398632164163, 4837.298591704253, 4871.34236878017, 4893.681910675795, 4899.877503062452, 4907.877303067452], "value": 3732.6265238359915, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.1515302810924, 650.9316521765214, 659.3921042277789, 670.9060731497591, 720.8233982674245, 11574.189806713533, 12578.496860125595, 12916.354182290872, 12927.224366538003, 14273.2863356832, 14311.069780464288, 14344.497942572012, 14357.78463323051, 14390.0, 14437.061590996604, 725.3725527418771, 746.8767653337193, 747.4730315127849, 752.4582300743456, 753.137657382297, 606.6329870428393, 742.7251916790794, 760.8782594784835, 760.9847803043936, 765.418282105601, 806.379088102161, 807.5840941914919, 809.550699361854, 810.8905297784812, 811.3995643224031, 797.0515087046397, 798.0941631248517, 800.9639566219535, 802.4583406200791, 802.6428239433454, 588.5968111843358, 588.7703795519751, 589.4989259129749, 589.9646121057517, 590.3152343281772, 12288.385580720927, 12303.384830758516, 12312.630621081382, 12324.260544367276, 12327.753444931051, 13183.340832958409, 13561.525346612822, 13645.385957631832, 13766.31168441574, 13806.17162970215, 10655.04009078284, 10690.0, 10732.356058636507, 10871.891281087166, 11237.83143252852, 3110.09538950715, 3127.9061628151194, 3161.2413020875056, 3168.588083549124, 3229.418704633165, 58490.257244454275, 58973.41026589722, 59718.40281597216, 60521.39478605201, 61099.083513747326, 9676.70969870905, 9733.318667693335, 9775.462349570842, 9816.165625921734, 10145.543450544596, 17176.39882604103, 18249.726254106197, 18268.908655456904, 18271.0, 18705.438836834917, 7275.0542429484885, 7295.197528271886, 7387.298206670358, 7424.591647459441, 7455.5148648178565, 3194.377096466213, 3692.298463291966, 3771.792551409644, 3779.60314167014, 3822.5604055533413, 2348.166400927646, 3040.4071206115036, 3202.0, 3226.0, 3237.7733558650666, 485.3375142929875, 491.42994126812727, 501.55361728062053, 501.74411050364574, 503.26523276016695, 577.4600748300353, 583.1049339264209, 588.8439563515672, 616.2635650397822, 621.7948077134535, 8556.358273129541, 8556.401051926305, 8753.781155471164, 8801.0, 8864.20222180007, 596.6181643748005, 598.0461164442706, 607.341034977046, 611.626907586372, 612.9693515324216, 4123.2165888481095, 4315.654747620246, 4336.3278691802825, 4387.210302145644, 4482.529334419904, 93899.53050234706, 103777.48111259396, 106446.0, 107330.0, 107891.46054269835, 51746.48253517492, 52689.0, 71476.64261678659, 89489.0, 90342.54828725815, 79515.60242198867, 80515.0, 80562.59718701369, 80928.0, 81137.99999999882, 7823.374130069695, 8267.255946964802, 8303.709370172015, 8322.20939010793, 8371.790705232173, 45432.31851522164, 46662.76668616635, 47495.33766318162, 47901.28148077851, 48440.5155948436, 47297.527024729316, 54435.45564544462, 56725.0, 63617.04574431295, 63872.36127638664, 11040.723981900517, 11643.0, 11677.240979336317, 11834.585789497327, 11837.289762614271, 8504.840515661055, 13385.063045586716, 13581.388837502534, 13868.306584670525, 14080.507182248983, 12163.695907602381, 12228.144029918176, 12329.69175770577, 12487.06347023995, 12938.805917911428, 11764.176507644725, 11884.881151188378, 11884.940575297069, 11890.702732431759, 12475.563355282522, 11459.828102578302, 11705.0, 11823.940880295544, 12068.275903445821, 12141.514339426323, 9700.514974251118, 9959.900400996186, 10106.747331316776, 10253.33353332031, 10325.690229293134, 9672.613095476103, 9830.557624906753, 10169.745756356151, 10328.483575821028, 10747.838782418114], "value": 6587.368116839376, "unit": "score"}], "name": "dromaeo_css", "value": 6345.055880818487, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 114.0, 113.0, 114.0, 114.0, 112.0, 112.0, 113.0, 112.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 122.0, 126.0, 180.0, 123.0, 123.0, 148.0, 122.0, 187.0, 130.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 163.0, 161.0, 162.0, 162.0, 162.0, 165.0, 163.0, 161.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 108.0, 77.0, 78.0, 77.0, 75.0, 104.0, 76.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 121.0, 86.0, 85.0, 85.0, 124.0, 84.0, 85.0, 127.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 148.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 62.0, 62.0, 63.0, 64.0, 63.0, 64.0, 62.0], "value": 63.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 66.0, 66.0, 65.0, 65.0, 66.0, 67.0, 67.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 77.0, 77.0, 78.0, 78.0, 78.0, 78.0, 78.0, 80.0], "value": 77.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 131.0, 129.0, 139.0, 134.0, 132.0, 137.0, 129.0, 224.0, 148.0], "value": 143.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 162.0, 163.0, 164.0, 166.0, 170.0, 163.0, 168.0, 165.0], "value": 165.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 68.0, 67.0, 66.0, 66.0, 66.0, 66.0, 65.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1534.7, "alertThreshold": 2.0}]} 16:50:27 INFO - Return code: 0 16:50:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:50:27 INFO - # TBPL SUCCESS # 16:50:27 INFO - Running post-action listener: _resource_record_post_action 16:50:27 INFO - ##### 16:50:27 INFO - ##### Finished run-tests step (success) 16:50:27 INFO - ##### 16:50:27 INFO - Running post-run listener: _resource_record_post_run 16:50:27 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 552452096; Read time: 16; Write time: 424692 16:50:27 INFO - TinderboxPrint: CPU usage
12.9% 16:50:27 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:50:27 INFO - TinderboxPrint: I/O write bytes / time
552,452,096 / 424,692 16:50:27 INFO - TinderboxPrint: CPU idle
8,323.9 (87.0%) 16:50:27 INFO - TinderboxPrint: CPU user
1,198.4 (12.5%) 16:50:27 INFO - TinderboxPrint: Swap in / out
0 / 0 16:50:27 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 262582272; Read time: 0; Write time: 227368 16:50:27 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 0; Write bytes: 289460224; Read time: 0; Write time: 197296 16:50:28 INFO - Running post-run listener: _upload_blobber_files 16:50:28 INFO - Blob upload gear active. 16:50:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:50:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:50:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:50:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:50:28 INFO - (blobuploader) - INFO - Open directory for files ... 16:50:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:50:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:50:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:29 INFO - (blobuploader) - INFO - Done attempting. 16:50:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:50:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:50:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:32 INFO - (blobuploader) - INFO - Done attempting. 16:50:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:50:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:50:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:33 INFO - (blobuploader) - INFO - Done attempting. 16:50:33 INFO - (blobuploader) - INFO - Iteration through files over. 16:50:33 INFO - Return code: 0 16:50:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:50:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:50:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7eb5194798208571ef9e3a5c73aa99005a8374eccb5943f71e2c7c174fa8a41e78dfe664068a4028a71cd0a31cce6a7130c5199720bb0e057b567f7d3443198b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b3d29f686ef89b3a4085617e129c9434b3f54cf3ab39b8454c68f88ebebe92a1c07ebba3417eccab5acf241a82d928332bd3f2073dff0c2ecdb1daf85b7a7661", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/197ef974ead9c734e82647dc666810a652fd051814297c087ce58272367927124fed49c8f66c7609c3ab64e72759174a13c85c399d95434925353fb20cbf0c41"} 16:50:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:50:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:50:33 INFO - Contents: 16:50:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7eb5194798208571ef9e3a5c73aa99005a8374eccb5943f71e2c7c174fa8a41e78dfe664068a4028a71cd0a31cce6a7130c5199720bb0e057b567f7d3443198b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b3d29f686ef89b3a4085617e129c9434b3f54cf3ab39b8454c68f88ebebe92a1c07ebba3417eccab5acf241a82d928332bd3f2073dff0c2ecdb1daf85b7a7661", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/197ef974ead9c734e82647dc666810a652fd051814297c087ce58272367927124fed49c8f66c7609c3ab64e72759174a13c85c399d95434925353fb20cbf0c41"} 16:50:33 INFO - Running post-run listener: copy_logs_to_upload_dir 16:50:33 INFO - Copying logs to upload dir... 16:50:33 INFO - mkdir: /builds/slave/test/build/upload/logs 16:50:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.327034 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-07-18 16:50:33.573000) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 16:50:33.576827) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7eb5194798208571ef9e3a5c73aa99005a8374eccb5943f71e2c7c174fa8a41e78dfe664068a4028a71cd0a31cce6a7130c5199720bb0e057b567f7d3443198b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b3d29f686ef89b3a4085617e129c9434b3f54cf3ab39b8454c68f88ebebe92a1c07ebba3417eccab5acf241a82d928332bd3f2073dff0c2ecdb1daf85b7a7661", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/197ef974ead9c734e82647dc666810a652fd051814297c087ce58272367927124fed49c8f66c7609c3ab64e72759174a13c85c399d95434925353fb20cbf0c41"} build_url:https://queue.taskcluster.net/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011361 build_url: 'https://queue.taskcluster.net/v1/task/XzYuZkuaR82dK4SF7FgYQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7eb5194798208571ef9e3a5c73aa99005a8374eccb5943f71e2c7c174fa8a41e78dfe664068a4028a71cd0a31cce6a7130c5199720bb0e057b567f7d3443198b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b3d29f686ef89b3a4085617e129c9434b3f54cf3ab39b8454c68f88ebebe92a1c07ebba3417eccab5acf241a82d928332bd3f2073dff0c2ecdb1daf85b7a7661", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/197ef974ead9c734e82647dc666810a652fd051814297c087ce58272367927124fed49c8f66c7609c3ab64e72759174a13c85c399d95434925353fb20cbf0c41"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 16:50:33.605002) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 16:50:33.605440) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1468884584.20288-489147940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004439 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 16:50:33.687435) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 16:50:33.687793) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 16:50:33.688132) ========= ========= Total master_lag: 0.18 =========