builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-062 starttime: 1469120923.03 results: success (0) buildid: 20160721085121 builduid: 0b0ea61ef9fd4eb8a52d89742345b9ff revision: 4b83d342c9f79da4ec067392a1d5ed97e0edd683 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 10:08:43.032086) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 10:08:43.032576) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 10:08:43.032860) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 10:08:43.067775) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 10:08:43.068322) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-21 10:08:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.6M=0s 2016-07-21 10:08:43 (40.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113842 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 10:08:43.196713) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 10:08:43.197031) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016824 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 10:08:43.246698) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 10:08:43.247114) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 4b83d342c9f79da4ec067392a1d5ed97e0edd683 --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 4b83d342c9f79da4ec067392a1d5ed97e0edd683 --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=a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-21 10:08:43,330 truncating revision to first 12 chars 2016-07-21 10:08:43,330 Setting DEBUG logging. 2016-07-21 10:08:43,330 attempt 1/10 2016-07-21 10:08:43,330 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4b83d342c9f7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 10:08:44,168 unpacking tar archive at: fx-team-4b83d342c9f7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.199064 ========= master_lag: 0.32 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 10:08:44.769071) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 10:08:44.769448) ========= script_repo_revision: 4b83d342c9f79da4ec067392a1d5ed97e0edd683 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 10:08:44.769886) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 10:08:44.770155) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 10:08:45.330672) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-21 10:08:45.331165) ========= /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=a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:08:45 INFO - MultiFileLogger online at 20160721 10:08:45 in /builds/slave/test 10:08:45 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 10:08:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:08:45 INFO - {'append_to_log': False, 10:08:45 INFO - 'base_work_dir': '/builds/slave/test', 10:08:45 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 10:08:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:08:45 INFO - 'branch': 'Fx-Team-Non-PGO', 10:08:45 INFO - 'buildbot_json_path': 'buildprops.json', 10:08:45 INFO - 'config_files': ('talos/linux_config.py',), 10:08:45 INFO - 'default_actions': ('clobber', 10:08:45 INFO - 'read-buildbot-config', 10:08:45 INFO - 'download-and-extract', 10:08:45 INFO - 'populate-webroot', 10:08:45 INFO - 'create-virtualenv', 10:08:45 INFO - 'install', 10:08:45 INFO - 'run-tests'), 10:08:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:08:45 INFO - 'download_minidump_stackwalk': True, 10:08:45 INFO - 'download_symbols': 'ondemand', 10:08:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:08:45 INFO - 'tooltool.py': '/tools/tooltool.py', 10:08:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:08:45 INFO - '/tools/misc-python/virtualenv.py')}, 10:08:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:08:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:08:45 INFO - 'installer_path': 'installer.exe', 10:08:45 INFO - 'log_level': 'info', 10:08:45 INFO - 'log_name': 'talos', 10:08:45 INFO - 'log_to_console': True, 10:08:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:08:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:08:45 INFO - 'opt_config_files': (), 10:08:45 INFO - 'pip_index': False, 10:08:45 INFO - 'sps_profile': False, 10:08:45 INFO - 'sps_profile_interval': 0, 10:08:45 INFO - 'suite': 'dromaeojs', 10:08:45 INFO - 'system_bits': '32', 10:08:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:08:45 INFO - 'title': 'talos-linux64-ix-062', 10:08:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:08:45 INFO - 'use_talos_json': True, 10:08:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:08:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:08:45 INFO - 'work_dir': 'build'} 10:08:45 INFO - [mozharness: 2016-07-21 17:08:45.513114Z] Running clobber step. 10:08:45 INFO - Running pre-action listener: _resource_record_pre_action 10:08:45 INFO - Running main action method: clobber 10:08:45 INFO - rmtree: /builds/slave/test/build 10:08:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:08:45 INFO - Running post-action listener: _resource_record_post_action 10:08:45 INFO - [mozharness: 2016-07-21 17:08:45.976013Z] Finished clobber step (success) 10:08:45 INFO - [mozharness: 2016-07-21 17:08:45.976115Z] Running read-buildbot-config step. 10:08:45 INFO - Running pre-action listener: _resource_record_pre_action 10:08:45 INFO - Running main action method: read_buildbot_config 10:08:45 INFO - Using buildbot properties: 10:08:45 INFO - { 10:08:45 INFO - "project": "", 10:08:45 INFO - "product": "firefox", 10:08:45 INFO - "script_repo_revision": "production", 10:08:45 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 10:08:45 INFO - "repository": "", 10:08:45 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 10:08:45 INFO - "buildid": "20160721085121", 10:08:45 INFO - "pgo_build": "False", 10:08:45 INFO - "basedir": "/builds/slave/test", 10:08:45 INFO - "buildnumber": 3191, 10:08:45 INFO - "slavename": "talos-linux64-ix-062", 10:08:45 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:08:45 INFO - "platform": "ubuntu64_hw", 10:08:45 INFO - "scheduler": "tests-fx-team-linux64-talos", 10:08:45 INFO - "branch": "fx-team", 10:08:45 INFO - "repo_path": "integration/fx-team", 10:08:45 INFO - "revision": "4b83d342c9f79da4ec067392a1d5ed97e0edd683", 10:08:45 INFO - "stage_platform": "linux64", 10:08:45 INFO - "builduid": "0b0ea61ef9fd4eb8a52d89742345b9ff", 10:08:45 INFO - "slavebuilddir": "test" 10:08:45 INFO - } 10:08:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 10:08:45 INFO - Running post-action listener: _resource_record_post_action 10:08:45 INFO - [mozharness: 2016-07-21 17:08:45.978552Z] Finished read-buildbot-config step (success) 10:08:45 INFO - [mozharness: 2016-07-21 17:08:45.978642Z] Running download-and-extract step. 10:08:45 INFO - Running pre-action listener: _resource_record_pre_action 10:08:45 INFO - Running main action method: download_and_extract 10:08:45 INFO - mkdir: /builds/slave/test/build/tests 10:08:45 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')]} 10:08:45 INFO - https://queue.taskcluster.net/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:08:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 10:08:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 10:08:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/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 10:08:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/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 10:08:46 INFO - Downloaded 1361 bytes. 10:08:46 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 10:08:46 INFO - Using the following test package requirements: 10:08:46 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 10:08:46 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 10:08:46 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:08:46 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 10:08:46 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:08:46 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 10:08:46 INFO - u'jsshell-linux-x86_64.zip'], 10:08:46 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 10:08:46 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:08:46 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 10:08:46 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 10:08:46 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:08:46 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 10:08:46 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:08:46 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 10:08:46 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:08:46 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 10:08:46 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:08:46 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:08: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')]} 10:08:46 INFO - https://queue.taskcluster.net/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:08:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 10:08:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 10:08:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/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 10:08:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/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 10:08:47 INFO - Downloaded 22735533 bytes. 10:08:47 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'] 10:08:47 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 10:08:49 INFO - Return code: 0 10:08:49 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 10:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:08:49 INFO - https://queue.taskcluster.net/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:08:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 10:08:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 10:08:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/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 10:08:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/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 10:08:50 INFO - Downloaded 22735533 bytes. 10:08:50 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'] 10:08:50 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 10:08:51 INFO - Return code: 0 10:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:08:51 INFO - https://queue.taskcluster.net/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:08:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 10:08:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 10:08:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/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 10:08:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/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 10:08:54 INFO - Downloaded 14403317 bytes. 10:08:54 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'] 10:08:54 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 10:08:54 INFO - Return code: 0 10:08:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:08:54 INFO - https://queue.taskcluster.net/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:08:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 10:08:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 10:08:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/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 10:08:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxb9OOp_Roy6o66nFji2JA/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 10:08:55 INFO - Downloaded 57149744 bytes. 10:08:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 10:08:55 INFO - mkdir: /builds/slave/test/properties 10:08:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:08:55 INFO - Writing to file /builds/slave/test/properties/build_url 10:08:55 INFO - Contents: 10:08:55 INFO - build_url:https://queue.taskcluster.net/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 10:08:56 INFO - Running post-action listener: _resource_record_post_action 10:08:56 INFO - Running post-action listener: set_extra_try_arguments 10:08:56 INFO - [mozharness: 2016-07-21 17:08:56.454804Z] Finished download-and-extract step (success) 10:08:56 INFO - [mozharness: 2016-07-21 17:08:56.455040Z] Running populate-webroot step. 10:08:56 INFO - Running pre-action listener: _resource_record_pre_action 10:08:56 INFO - Running main action method: populate_webroot 10:08:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:08:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:08:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:08:56 INFO - '--tppagecycles', 10:08:56 INFO - '7'], 10:08:56 INFO - 'tests': ['tsvgm']}}, 10:08:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:08:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:08:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:08:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:08:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:08:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:08:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:08:56 INFO - 'talos_options': ['--disable-e10s'], 10:08:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:08:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:08:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:08:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:08:56 INFO - 'talos_options': ['--disable-e10s'], 10:08:56 INFO - 'tests': ['damp', 'tps']}, 10:08:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:08:56 INFO - 'tests': ['damp', 'tps']}, 10:08:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:08:56 INFO - 'tests': ['dromaeo_dom']}, 10:08:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:08:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:08:56 INFO - 'tests': ['basic_compositor_video']}, 10:08:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:08:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:08:56 INFO - 'tests': ['a11yr', 10:08:56 INFO - 'ts_paint', 10:08:56 INFO - 'tpaint', 10:08:56 INFO - 'sessionrestore', 10:08:56 INFO - 'sessionrestore_no_auto_restore', 10:08:56 INFO - 'tabpaint']}, 10:08:56 INFO - 'other-e10s': {'tests': ['a11yr', 10:08:56 INFO - 'ts_paint', 10:08:56 INFO - 'tpaint', 10:08:56 INFO - 'sessionrestore', 10:08:56 INFO - 'sessionrestore_no_auto_restore', 10:08:56 INFO - 'tabpaint']}, 10:08:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:08:56 INFO - 'ts_paint', 10:08:56 INFO - 'tpaint', 10:08:56 INFO - 'sessionrestore', 10:08:56 INFO - 'sessionrestore_no_auto_restore', 10:08:56 INFO - 'tabpaint']}, 10:08:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:08:56 INFO - 'ts_paint', 10:08:56 INFO - 'tpaint', 10:08:56 INFO - 'sessionrestore', 10:08:56 INFO - 'sessionrestore_no_auto_restore', 10:08:56 INFO - 'tabpaint']}, 10:08:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:08:56 INFO - 'tests': ['a11yr', 10:08:56 INFO - 'ts_paint', 10:08:56 INFO - 'tpaint', 10:08:56 INFO - 'sessionrestore', 10:08:56 INFO - 'sessionrestore_no_auto_restore', 10:08:56 INFO - 'tabpaint']}, 10:08:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:08:56 INFO - 'tests': ['a11yr', 10:08:56 INFO - 'ts_paint', 10:08:56 INFO - 'tpaint', 10:08:56 INFO - 'sessionrestore', 10:08:56 INFO - 'sessionrestore_no_auto_restore', 10:08:56 INFO - 'tabpaint']}, 10:08:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:08:56 INFO - 'tests': ['tsvgx', 10:08:56 INFO - 'tsvgr_opacity', 10:08:56 INFO - 'tart', 10:08:56 INFO - 'tscrollx', 10:08:56 INFO - 'cart']}, 10:08:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:08:56 INFO - 'tsvgr_opacity', 10:08:56 INFO - 'tart', 10:08:56 INFO - 'tscrollx', 10:08:56 INFO - 'cart']}, 10:08:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:08:56 INFO - 'talos_options': ['--disable-e10s'], 10:08:56 INFO - 'tests': ['tp5o']}, 10:08:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:08:56 INFO - 'tests': ['tp5o']}, 10:08:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:08:56 INFO - 'talos_options': ['--disable-e10s', 10:08:56 INFO - '--xperf_path', 10:08:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:08:56 INFO - 'tests': ['tp5n']}, 10:08:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:08:56 INFO - 'talos_options': ['--xperf_path', 10:08:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:08:56 INFO - 'tests': ['tp5n']}}, 10:08:56 INFO - 'talos.zip': {'path': '', 10:08:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:08:56 INFO - Running post-action listener: _resource_record_post_action 10:08:56 INFO - [mozharness: 2016-07-21 17:08:56.476919Z] Finished populate-webroot step (success) 10:08:56 INFO - [mozharness: 2016-07-21 17:08:56.477008Z] Running create-virtualenv step. 10:08:56 INFO - Running pre-action listener: _resource_record_pre_action 10:08:56 INFO - Running main action method: create_virtualenv 10:08:56 INFO - Creating virtualenv /builds/slave/test/build/venv 10:08:56 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 10:08:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:08:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:08:56 INFO - Using real prefix '/usr' 10:08:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:08:57 INFO - Installing distribute.............................................................................................................................................................................................done. 10:08:59 INFO - Installing pip.................done. 10:08:59 INFO - Return code: 0 10:08:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:08: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')]} 10:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08: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')]} 10:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:59 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 0xd34030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff33b0>, '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 0xff42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff4740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', '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 10:08:59 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 10:08:59 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 10:08:59 INFO - Using env: {'DISPLAY': ':0', 10:08:59 INFO - 'HOME': '/home/cltbld', 10:08:59 INFO - 'LANG': 'en_US.UTF-8', 10:08:59 INFO - 'LANGUAGE': 'en_US:en', 10:08:59 INFO - 'LOGNAME': 'cltbld', 10:08:59 INFO - 'MAIL': '/var/mail/cltbld', 10:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:08:59 INFO - 'MOZ_NO_REMOTE': '1', 10:08:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:08:59 INFO - 'NO_EM_RESTART': '1', 10:08:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:08:59 INFO - 'PWD': '/builds/slave/test', 10:08:59 INFO - 'SHELL': '/bin/bash', 10:08:59 INFO - 'SHLVL': '1', 10:08:59 INFO - 'TERM': 'linux', 10:08:59 INFO - 'TMOUT': '86400', 10:08:59 INFO - 'USER': 'cltbld', 10:08:59 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', 10:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:08:59 INFO - '_': '/tools/buildbot/bin/python'} 10:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:08:59 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 10:08:59 INFO - Cleaning up... 10:08:59 INFO - Return code: 0 10:08:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:08: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')]} 10:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08: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')]} 10:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:08:59 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 0xd34030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff33b0>, '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 0xff42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff4740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', '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 10:08:59 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 10:08:59 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 10:08:59 INFO - Using env: {'DISPLAY': ':0', 10:08:59 INFO - 'HOME': '/home/cltbld', 10:08:59 INFO - 'LANG': 'en_US.UTF-8', 10:08:59 INFO - 'LANGUAGE': 'en_US:en', 10:08:59 INFO - 'LOGNAME': 'cltbld', 10:08:59 INFO - 'MAIL': '/var/mail/cltbld', 10:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:08:59 INFO - 'MOZ_NO_REMOTE': '1', 10:08:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:08:59 INFO - 'NO_EM_RESTART': '1', 10:08:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:08:59 INFO - 'PWD': '/builds/slave/test', 10:08:59 INFO - 'SHELL': '/bin/bash', 10:08:59 INFO - 'SHLVL': '1', 10:08:59 INFO - 'TERM': 'linux', 10:08:59 INFO - 'TMOUT': '86400', 10:08:59 INFO - 'USER': 'cltbld', 10:08:59 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', 10:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:08:59 INFO - '_': '/tools/buildbot/bin/python'} 10:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:08:59 INFO - Downloading/unpacking psutil>=3.1.1 10:08:59 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 10:08:59 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 10:08:59 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 10:08:59 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 10:08:59 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 10:08:59 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 10:09:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:09:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:09:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:09:01 INFO - Installing collected packages: psutil 10:09:01 INFO - Running setup.py install for psutil 10:09:01 INFO - building 'psutil._psutil_linux' extension 10:09:01 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 10:09:01 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 10:09:01 INFO - building 'psutil._psutil_posix' extension 10:09:01 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 10:09:01 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 10:09:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:09:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:09:02 INFO - Successfully installed psutil 10:09:02 INFO - Cleaning up... 10:09:02 INFO - Return code: 0 10:09:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:02 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 0xd34030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff33b0>, '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 0xff42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff4740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', '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 10:09:02 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 10:09:02 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 10:09:02 INFO - Using env: {'DISPLAY': ':0', 10:09:02 INFO - 'HOME': '/home/cltbld', 10:09:02 INFO - 'LANG': 'en_US.UTF-8', 10:09:02 INFO - 'LANGUAGE': 'en_US:en', 10:09:02 INFO - 'LOGNAME': 'cltbld', 10:09:02 INFO - 'MAIL': '/var/mail/cltbld', 10:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:09:02 INFO - 'MOZ_NO_REMOTE': '1', 10:09:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:09:02 INFO - 'NO_EM_RESTART': '1', 10:09:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:09:02 INFO - 'PWD': '/builds/slave/test', 10:09:02 INFO - 'SHELL': '/bin/bash', 10:09:02 INFO - 'SHLVL': '1', 10:09:02 INFO - 'TERM': 'linux', 10:09:02 INFO - 'TMOUT': '86400', 10:09:02 INFO - 'USER': 'cltbld', 10:09:02 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', 10:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:09:02 INFO - '_': '/tools/buildbot/bin/python'} 10:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:09:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:09:02 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 10:09:02 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 10:09:02 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 10:09:02 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 10:09:02 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 10:09:02 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 10:09:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:09:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:09:04 INFO - Installing collected packages: mozsystemmonitor 10:09:04 INFO - Running setup.py install for mozsystemmonitor 10:09:04 INFO - Successfully installed mozsystemmonitor 10:09:04 INFO - Cleaning up... 10:09:05 INFO - Return code: 0 10:09:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:09: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')]} 10:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:09:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09: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')]} 10:09:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:09:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:05 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 0xd34030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff33b0>, '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 0xff42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff4740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', '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 10:09:05 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 10:09:05 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 10:09:05 INFO - Using env: {'DISPLAY': ':0', 10:09:05 INFO - 'HOME': '/home/cltbld', 10:09:05 INFO - 'LANG': 'en_US.UTF-8', 10:09:05 INFO - 'LANGUAGE': 'en_US:en', 10:09:05 INFO - 'LOGNAME': 'cltbld', 10:09:05 INFO - 'MAIL': '/var/mail/cltbld', 10:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:09:05 INFO - 'MOZ_NO_REMOTE': '1', 10:09:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:09:05 INFO - 'NO_EM_RESTART': '1', 10:09:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:09:05 INFO - 'PWD': '/builds/slave/test', 10:09:05 INFO - 'SHELL': '/bin/bash', 10:09:05 INFO - 'SHLVL': '1', 10:09:05 INFO - 'TERM': 'linux', 10:09:05 INFO - 'TMOUT': '86400', 10:09:05 INFO - 'USER': 'cltbld', 10:09:05 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', 10:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:09:05 INFO - '_': '/tools/buildbot/bin/python'} 10:09:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:09:05 INFO - Downloading/unpacking jsonschema==2.5.1 10:09:05 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 10:09:05 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 10:09:05 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 10:09:05 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 10:09:05 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 10:09:05 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 10:09:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:09:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:09:07 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 10:09:07 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 10:09:07 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 10:09:07 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 10:09:07 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 10:09:07 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 10:09:07 INFO - Downloading functools32-3.2.3-2.tar.gz 10:09:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:09:07 INFO - warning: no files found matching '*.txt' 10:09:07 INFO - no previously-included directories found matching 'build' 10:09:07 INFO - no previously-included directories found matching 'dist' 10:09:07 INFO - no previously-included directories found matching '.git*' 10:09:07 INFO - Installing collected packages: jsonschema, functools32 10:09:07 INFO - Running setup.py install for functools32 10:09:07 INFO - warning: no files found matching '*.txt' 10:09:07 INFO - no previously-included directories found matching 'build' 10:09:07 INFO - no previously-included directories found matching 'dist' 10:09:07 INFO - no previously-included directories found matching '.git*' 10:09:07 INFO - Successfully installed jsonschema functools32 10:09:07 INFO - Cleaning up... 10:09:07 INFO - Return code: 0 10:09:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:09:07 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')]} 10:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:09:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:07 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')]} 10:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:09:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:07 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 0xd34030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff33b0>, '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 0xff42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff4740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', '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 10:09:07 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 10:09:07 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 10:09:07 INFO - Using env: {'DISPLAY': ':0', 10:09:07 INFO - 'HOME': '/home/cltbld', 10:09:07 INFO - 'LANG': 'en_US.UTF-8', 10:09:07 INFO - 'LANGUAGE': 'en_US:en', 10:09:07 INFO - 'LOGNAME': 'cltbld', 10:09:07 INFO - 'MAIL': '/var/mail/cltbld', 10:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:09:07 INFO - 'MOZ_NO_REMOTE': '1', 10:09:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:09:07 INFO - 'NO_EM_RESTART': '1', 10:09:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:09:07 INFO - 'PWD': '/builds/slave/test', 10:09:07 INFO - 'SHELL': '/bin/bash', 10:09:07 INFO - 'SHLVL': '1', 10:09:07 INFO - 'TERM': 'linux', 10:09:07 INFO - 'TMOUT': '86400', 10:09:07 INFO - 'USER': 'cltbld', 10:09:07 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', 10:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:09:07 INFO - '_': '/tools/buildbot/bin/python'} 10:09:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:09:08 INFO - Downloading/unpacking blobuploader==1.2.4 10:09:08 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 10:09:08 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 10:09:08 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 10:09:08 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 10:09:08 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 10:09:08 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 10:09:09 INFO - Downloading blobuploader-1.2.4.tar.gz 10:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:09:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:09:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:09:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:09:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:09:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:09: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 10:09: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 10:09: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 10:09: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 10:09: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 10:09: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 10:09:10 INFO - Downloading docopt-0.6.1.tar.gz 10:09:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:09:10 INFO - Installing collected packages: blobuploader, requests, docopt 10:09:10 INFO - Running setup.py install for blobuploader 10:09:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:09:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:09:10 INFO - Running setup.py install for requests 10:09:11 INFO - Running setup.py install for docopt 10:09:11 INFO - Successfully installed blobuploader requests docopt 10:09:11 INFO - Cleaning up... 10:09:11 INFO - Return code: 0 10:09:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:11 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 0xd34030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff33b0>, '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 0xff42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff4740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', '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 10:09:11 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 10:09:11 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 10:09:11 INFO - Using env: {'DISPLAY': ':0', 10:09:11 INFO - 'HOME': '/home/cltbld', 10:09:11 INFO - 'LANG': 'en_US.UTF-8', 10:09:11 INFO - 'LANGUAGE': 'en_US:en', 10:09:11 INFO - 'LOGNAME': 'cltbld', 10:09:11 INFO - 'MAIL': '/var/mail/cltbld', 10:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:09:11 INFO - 'MOZ_NO_REMOTE': '1', 10:09:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:09:11 INFO - 'NO_EM_RESTART': '1', 10:09:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:09:11 INFO - 'PWD': '/builds/slave/test', 10:09:11 INFO - 'SHELL': '/bin/bash', 10:09:11 INFO - 'SHLVL': '1', 10:09:11 INFO - 'TERM': 'linux', 10:09:11 INFO - 'TMOUT': '86400', 10:09:11 INFO - 'USER': 'cltbld', 10:09:11 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', 10:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:09:11 INFO - '_': '/tools/buildbot/bin/python'} 10:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:09:11 INFO - Running setup.py (path:/tmp/pip-YeANLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:09:11 INFO - Running setup.py (path:/tmp/pip-g9a_ZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:09:11 INFO - Running setup.py (path:/tmp/pip-2dWX0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:09:11 INFO - Running setup.py (path:/tmp/pip-7lsvSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:09:11 INFO - Running setup.py (path:/tmp/pip-kJeK3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:09:11 INFO - Running setup.py (path:/tmp/pip-cBO80D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:09:11 INFO - Running setup.py (path:/tmp/pip-Q8jOO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:09:12 INFO - Running setup.py (path:/tmp/pip-CY_FyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:09:12 INFO - Running setup.py (path:/tmp/pip-d1oI5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:09:12 INFO - Running setup.py (path:/tmp/pip-KrKbX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:09:12 INFO - Running setup.py (path:/tmp/pip-QZAvT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:09:12 INFO - Running setup.py (path:/tmp/pip-VAjNTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:09:12 INFO - Running setup.py (path:/tmp/pip-1nsqkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:09:12 INFO - Running setup.py (path:/tmp/pip-Fj9yBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:09:12 INFO - Running setup.py (path:/tmp/pip-eZ0kui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:09:12 INFO - Running setup.py (path:/tmp/pip-p3GZoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:09:12 INFO - Running setup.py (path:/tmp/pip-EoQeMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:09:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:09:12 INFO - Running setup.py install for manifestparser 10:09:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:09:12 INFO - Running setup.py install for mozcrash 10:09:12 INFO - Running setup.py install for mozdebug 10:09:13 INFO - Running setup.py install for mozdevice 10:09:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:09:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:09:13 INFO - Running setup.py install for mozfile 10:09:13 INFO - Running setup.py install for mozhttpd 10:09:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:09:13 INFO - Running setup.py install for mozinfo 10:09:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:09:13 INFO - Running setup.py install for mozInstall 10:09:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:09:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:09:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:09:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:09:13 INFO - Running setup.py install for mozleak 10:09:13 INFO - Running setup.py install for mozlog 10:09:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:09:13 INFO - Running setup.py install for moznetwork 10:09:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:09:13 INFO - Running setup.py install for mozprocess 10:09:13 INFO - Running setup.py install for mozprofile 10:09:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:09:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:09:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:09:14 INFO - Running setup.py install for mozrunner 10:09:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:09:14 INFO - Running setup.py install for mozscreenshot 10:09:14 INFO - Running setup.py install for moztest 10:09:14 INFO - Running setup.py install for mozversion 10:09:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:09:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:09:14 INFO - Cleaning up... 10:09:14 INFO - Return code: 0 10:09:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:09:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:09:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:09:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:14 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 0xd34030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff33b0>, '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 0xff42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff4740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', '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 10:09:14 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 10:09:14 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 10:09:14 INFO - Using env: {'DISPLAY': ':0', 10:09:14 INFO - 'HOME': '/home/cltbld', 10:09:14 INFO - 'LANG': 'en_US.UTF-8', 10:09:14 INFO - 'LANGUAGE': 'en_US:en', 10:09:14 INFO - 'LOGNAME': 'cltbld', 10:09:14 INFO - 'MAIL': '/var/mail/cltbld', 10:09:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:09:14 INFO - 'MOZ_NO_REMOTE': '1', 10:09:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:09:14 INFO - 'NO_EM_RESTART': '1', 10:09:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:09:14 INFO - 'PWD': '/builds/slave/test', 10:09:14 INFO - 'SHELL': '/bin/bash', 10:09:14 INFO - 'SHLVL': '1', 10:09:14 INFO - 'TERM': 'linux', 10:09:14 INFO - 'TMOUT': '86400', 10:09:14 INFO - 'USER': 'cltbld', 10:09:14 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', 10:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:09:14 INFO - '_': '/tools/buildbot/bin/python'} 10:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:09:14 INFO - Running setup.py (path:/tmp/pip-y6LNsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:09:14 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)) 10:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:09:14 INFO - Running setup.py (path:/tmp/pip-cTqcsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:09:14 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)) 10:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:09:14 INFO - Running setup.py (path:/tmp/pip-MvI2rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:09:14 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)) 10:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:09:15 INFO - Running setup.py (path:/tmp/pip-0ybvit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:09:15 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)) 10:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:09:15 INFO - Running setup.py (path:/tmp/pip-EJ5ZrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:09:15 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)) 10:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:09:15 INFO - Running setup.py (path:/tmp/pip-_chmio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:09:15 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)) 10:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:09:15 INFO - Running setup.py (path:/tmp/pip-XfKH_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:09:15 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)) 10:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:09:15 INFO - Running setup.py (path:/tmp/pip-EL20bR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:09:15 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)) 10:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:09:15 INFO - Running setup.py (path:/tmp/pip-9UGjEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:09:15 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)) 10:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:09:15 INFO - Running setup.py (path:/tmp/pip-S9ky9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:09:15 INFO - Running setup.py (path:/tmp/pip-JQQCFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:09:15 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)) 10:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:09:15 INFO - Running setup.py (path:/tmp/pip-1vI9tb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:09:15 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)) 10:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:09:15 INFO - Running setup.py (path:/tmp/pip-Y6BIC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:09:15 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)) 10:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:09:15 INFO - Running setup.py (path:/tmp/pip-tqcsyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:09:15 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)) 10:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:09:15 INFO - Running setup.py (path:/tmp/pip-teAJtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:09:15 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)) 10:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:09:15 INFO - Running setup.py (path:/tmp/pip-rshoU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:09:15 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)) 10:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:09:15 INFO - Running setup.py (path:/tmp/pip-ig_MAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:09:16 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)) 10:09:16 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)) 10:09:16 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)) 10:09:16 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)) 10:09:16 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)) 10:09:16 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)) 10:09:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:09:16 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 10:09:16 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 10:09:16 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 10:09:16 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 10:09:16 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 10:09:16 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 10:09:17 INFO - Downloading blessings-1.6.tar.gz 10:09:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:09:17 INFO - Installing collected packages: blessings 10:09:17 INFO - Running setup.py install for blessings 10:09:18 INFO - Successfully installed blessings 10:09:18 INFO - Cleaning up... 10:09:18 INFO - Return code: 0 10:09:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:09:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:09:18 INFO - Reading from file tmpfile_stdout 10:09:18 INFO - Current package versions: 10:09:18 INFO - argparse == 1.2.1 10:09:18 INFO - blessings == 1.6 10:09:18 INFO - blobuploader == 1.2.4 10:09:18 INFO - docopt == 0.6.1 10:09:18 INFO - functools32 == 3.2.3-2 10:09:18 INFO - jsonschema == 2.5.1 10:09:18 INFO - manifestparser == 1.1 10:09:18 INFO - mozInstall == 1.12 10:09:18 INFO - mozcrash == 0.17 10:09:18 INFO - mozdebug == 0.1 10:09:18 INFO - mozdevice == 0.48 10:09:18 INFO - mozfile == 1.2 10:09:18 INFO - mozhttpd == 0.7 10:09:18 INFO - mozinfo == 0.9 10:09:18 INFO - mozleak == 0.1 10:09:18 INFO - mozlog == 3.3 10:09:18 INFO - moznetwork == 0.27 10:09:18 INFO - mozprocess == 0.23 10:09:18 INFO - mozprofile == 0.28 10:09:18 INFO - mozrunner == 6.12 10:09:18 INFO - mozscreenshot == 0.1 10:09:18 INFO - mozsystemmonitor == 0.3 10:09:18 INFO - moztest == 0.7 10:09:18 INFO - mozversion == 1.4 10:09:18 INFO - psutil == 3.1.1 10:09:18 INFO - requests == 1.2.3 10:09:18 INFO - wsgiref == 0.1.2 10:09:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:09: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')]} 10:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09: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')]} 10:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:18 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 0xd34030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff33b0>, '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 0xff42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff4740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', '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 10:09:18 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 10:09:18 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 10:09:18 INFO - Using env: {'DISPLAY': ':0', 10:09:18 INFO - 'HOME': '/home/cltbld', 10:09:18 INFO - 'LANG': 'en_US.UTF-8', 10:09:18 INFO - 'LANGUAGE': 'en_US:en', 10:09:18 INFO - 'LOGNAME': 'cltbld', 10:09:18 INFO - 'MAIL': '/var/mail/cltbld', 10:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:09:18 INFO - 'MOZ_NO_REMOTE': '1', 10:09:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:09:18 INFO - 'NO_EM_RESTART': '1', 10:09:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:09:18 INFO - 'PWD': '/builds/slave/test', 10:09:18 INFO - 'SHELL': '/bin/bash', 10:09:18 INFO - 'SHLVL': '1', 10:09:18 INFO - 'TERM': 'linux', 10:09:18 INFO - 'TMOUT': '86400', 10:09:18 INFO - 'USER': 'cltbld', 10:09:18 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', 10:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:09:18 INFO - '_': '/tools/buildbot/bin/python'} 10:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:09:18 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)) 10:09:18 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)) 10:09:18 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)) 10:09:18 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)) 10:09:18 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)) 10:09:18 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)) 10:09:18 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)) 10:09:18 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)) 10:09:18 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)) 10:09:18 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)) 10:09:18 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)) 10:09:18 INFO - Cleaning up... 10:09:18 INFO - Return code: 0 10:09:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:09: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')]} 10:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09: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')]} 10:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd34030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff33b0>, '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 0xff42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff4740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', '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 10:09: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', 'jsonschema'] in /builds/slave/test/build 10:09: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 jsonschema 10:09:18 INFO - Using env: {'DISPLAY': ':0', 10:09:18 INFO - 'HOME': '/home/cltbld', 10:09:18 INFO - 'LANG': 'en_US.UTF-8', 10:09:18 INFO - 'LANGUAGE': 'en_US:en', 10:09:18 INFO - 'LOGNAME': 'cltbld', 10:09:18 INFO - 'MAIL': '/var/mail/cltbld', 10:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:09:18 INFO - 'MOZ_NO_REMOTE': '1', 10:09:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:09:18 INFO - 'NO_EM_RESTART': '1', 10:09:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:09:18 INFO - 'PWD': '/builds/slave/test', 10:09:18 INFO - 'SHELL': '/bin/bash', 10:09:18 INFO - 'SHLVL': '1', 10:09:18 INFO - 'TERM': 'linux', 10:09:18 INFO - 'TMOUT': '86400', 10:09:18 INFO - 'USER': 'cltbld', 10:09:18 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', 10:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:09:18 INFO - '_': '/tools/buildbot/bin/python'} 10:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:09:18 INFO - Cleaning up... 10:09:18 INFO - Return code: 0 10:09:18 INFO - Running post-action listener: _resource_record_post_action 10:09:18 INFO - Running post-action listener: _start_resource_monitoring 10:09:18 INFO - Starting resource monitoring. 10:09:18 INFO - [mozharness: 2016-07-21 17:09:18.872676Z] Finished create-virtualenv step (success) 10:09:18 INFO - [mozharness: 2016-07-21 17:09:18.873120Z] Running install step. 10:09:18 INFO - Running pre-action listener: _resource_record_pre_action 10:09:18 INFO - Running main action method: install 10:09:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:09:19 INFO - Reading from file tmpfile_stdout 10:09:19 INFO - Detecting whether we're running mozinstall >=1.0... 10:09:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:09:19 INFO - Reading from file tmpfile_stdout 10:09:19 INFO - Output received: 10:09:19 INFO - Usage: mozinstall [options] installer 10:09:19 INFO - Options: 10:09:19 INFO - -h, --help show this help message and exit 10:09:19 INFO - -d DEST, --destination=DEST 10:09:19 INFO - Directory to install application into. [default: 10:09:19 INFO - "/builds/slave/test"] 10:09:19 INFO - --app=APP Application being installed. [default: firefox] 10:09:19 INFO - mkdir: /builds/slave/test/build/application 10:09:19 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'] 10:09:19 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 10:09:35 INFO - Reading from file tmpfile_stdout 10:09:35 INFO - Output received: 10:09:35 INFO - /builds/slave/test/build/application/firefox/firefox 10:09:35 INFO - Running post-action listener: _resource_record_post_action 10:09:35 INFO - [mozharness: 2016-07-21 17:09:35.535369Z] Finished install step (success) 10:09:35 INFO - [mozharness: 2016-07-21 17:09:35.535647Z] Running run-tests step. 10:09:35 INFO - Running pre-action listener: _resource_record_pre_action 10:09:35 INFO - Running main action method: run_tests 10:09:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:09:35 INFO - Python 2.7.3 10:09:35 INFO - Return code: 0 10:09:35 INFO - grabbing minidump binary from tooltool 10:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:09:35 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 0xff33b0>, '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 0xff42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff4740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:09:35 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 10:09:35 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 10:09:35 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 10:09:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:09:35 INFO - Return code: 0 10:09:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:09:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:09:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:09:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:09:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:09:35 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/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 10:09:35 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/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 10:09:35 INFO - Using env: {'DISPLAY': ':0', 10:09:35 INFO - 'HOME': '/home/cltbld', 10:09:35 INFO - 'LANG': 'en_US.UTF-8', 10:09:35 INFO - 'LANGUAGE': 'en_US:en', 10:09:35 INFO - 'LOGNAME': 'cltbld', 10:09:35 INFO - 'MAIL': '/var/mail/cltbld', 10:09:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:09:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:09:35 INFO - 'MOZ_NO_REMOTE': '1', 10:09:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:09:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:09:35 INFO - 'NO_EM_RESTART': '1', 10:09:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:09:35 INFO - 'PWD': '/builds/slave/test', 10:09:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:09:35 INFO - 'SHELL': '/bin/bash', 10:09:35 INFO - 'SHLVL': '1', 10:09:35 INFO - 'TERM': 'linux', 10:09:35 INFO - 'TMOUT': '86400', 10:09:35 INFO - 'USER': 'cltbld', 10:09:35 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927', 10:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:09:35 INFO - '_': '/tools/buildbot/bin/python'} 10:09:35 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/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 10:09:35 INFO - mozversion application_buildid: 20160721085121 10:09:35 INFO - mozversion application_changeset: 4b83d342c9f79da4ec067392a1d5ed97e0edd683 10:09:35 INFO - mozversion application_display_name: Nightly 10:09:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:09:35 INFO - mozversion application_name: Firefox 10:09:35 INFO - mozversion application_remotingname: firefox 10:09:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:09:35 INFO - mozversion application_vendor: Mozilla 10:09:35 INFO - mozversion application_version: 50.0a1 10:09:35 INFO - mozversion platform_buildid: 20160721085121 10:09:35 INFO - mozversion platform_changeset: 4b83d342c9f79da4ec067392a1d5ed97e0edd683 10:09:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:09:35 INFO - mozversion platform_version: 50.0a1 10:09:35 INFO - using testdate: 1469120975 10:09:35 INFO - actual date: 1469120975 10:09:35 INFO - starting webserver on 'localhost:48156' 10:09:35 INFO - SUITE-START | Running 2 tests 10:09:35 INFO - TEST-START | dromaeo_css 10:09:35 INFO - operating with platform_type : linux_ 10:09:35 INFO - Initialising browser for dromaeo_css test... 10:09:35 INFO - TEST-INFO | started process 4242 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0L8t5C/profile http://localhost:48156/getInfo.html) 10:09:39 INFO - PROCESS | 4242 | JavaScript error: , line 0: uncaught exception: undefined 10:09:40 INFO - PROCESS | 4242 | __metrics Screen width/height:1600/1200 10:09:40 INFO - PROCESS | 4242 | colorDepth:24 10:09:40 INFO - PROCESS | 4242 | Browser inner width/height: 1024/697 10:09:40 INFO - PROCESS | 4242 | __metrics 10:09:41 INFO - PROCESS | 4242 | 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" 10:09:41 INFO - PROCESS | 4242 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:09:42 INFO - PROCESS | 4242 | console.error: PushService: 10:09:42 INFO - PROCESS | 4242 | stateChangeProcessEnqueue: Error transitioning state 10:09:42 INFO - PROCESS | 4242 | UnknownError 10:09:42 INFO - TEST-INFO | 4242: exit 0 10:09:42 INFO - Browser initialized. 10:09:42 INFO - Running cycle 1/1 for dromaeo_css test... 10:09:42 INFO - TEST-INFO | started process 4325 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0L8t5C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:09:54 INFO - PROCESS | 4325 | RSS: Main: 158695424 10:09:54 INFO - PROCESS | 4325 | 10:10:46 INFO - PROCESS | 4325 | 1469121046677 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] 10:12:58 INFO - PROCESS | 4325 | Cycle 1(1): loaded http://localhost:48156/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48156/tests/dromaeo/cssquery-ext.html) 10:12:59 INFO - PROCESS | 4325 | RSS: Main: 238936064 10:12:59 INFO - PROCESS | 4325 | 10:16:02 INFO - PROCESS | 4325 | Cycle 1(1): loaded http://localhost:48156/tests/dromaeo/cssquery-ext.html (next: http://localhost:48156/tests/dromaeo/cssquery-jquery.html) 10:16:02 INFO - PROCESS | 4325 | RSS: Main: 250318848 10:16:02 INFO - PROCESS | 4325 | 10:19:05 INFO - PROCESS | 4325 | Cycle 1(1): loaded http://localhost:48156/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48156/tests/dromaeo/cssquery-mootools.html) 10:19:05 INFO - PROCESS | 4325 | RSS: Main: 191795200 10:19:05 INFO - PROCESS | 4325 | 10:22:07 INFO - PROCESS | 4325 | Cycle 1(1): loaded http://localhost:48156/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48156/tests/dromaeo/cssquery-prototype.html) 10:22:08 INFO - PROCESS | 4325 | RSS: Main: 212316160 10:22:08 INFO - PROCESS | 4325 | 10:25:11 INFO - PROCESS | 4325 | Cycle 1(1): loaded http://localhost:48156/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48156/tests/dromaeo/cssquery-yui.html) 10:25:12 INFO - PROCESS | 4325 | RSS: Main: 195788800 10:25:12 INFO - PROCESS | 4325 | 10:28:15 INFO - PROCESS | 4325 | Cycle 1(1): loaded http://localhost:48156/tests/dromaeo/cssquery-yui.html (next: http://localhost:48156/tests/dromaeo/cssquery-dojo.html) 10:28:15 INFO - PROCESS | 4325 | RSS: Main: 280580096 10:28:15 INFO - PROCESS | 4325 | 10:28:15 INFO - PROCESS | 4325 | __start_tp_report 10:28:15 INFO - PROCESS | 4325 | _x_x_mozilla_page_load 10:28:15 INFO - PROCESS | 4325 | _x_x_mozilla_page_load_details 10:28:15 INFO - PROCESS | 4325 | |i|pagename|runs| 10:28:15 INFO - PROCESS | 4325 | |0;dojo.html;2522.3820164059807;2558.974410255897;2566.1146904318;2569.1650213680555;2592.714801371849;4582.900103975038;4650.627949764019;4651.837185698501;4667.556582124693;4669.533046695329;4640.512746161655;4662.90674186516;4682.040181762739;4692.882677933053;4710.387649605555;4223.831046758126;4256.893577660552;4290.227759003378;4327.783610819462;4403.4495688039;4307.96608813884;4356.477222733276;4360.563943605645;4382.0797632497215;4389.144116897206;4220.134872351191;4220.894477638053;4245.150969806051;4268.807903644359;4279.743215407055;4282.250606143913;4288.33530802723;4290.871273861789;4294.634956028742;4299.871003869889;4345.34819777036;4346.6088052075465;4350.58669426404;4378.387025816356;4378.584034516716;4569.04050149469;4653.88365290867;4716.174669432869;4757.762111894426;4770.403699537558;4950.3069570260195;4974.651774375795;4980.676256043345;4995.175795993657;5048.848534543969;5009.298698182258;5017.046761115376;5024.598032157419;5037.0681423936685;5064.113780088506;4516.751578663205;4654.045920586272;4671.34601155835;4682.976585117052;4686.3204835299375;5137.8201762938115;5147.022065807485;5207.1668533034535;5217;5217.608679349064;4935.210366341368;4955.653104282737;4962.875928101754;4998.825041123543;5005.049040682259;4902.166631672624;4962.950370496321;5014.6991180529285;5077.517635824591;5092.032513822362;4865.270209468608;4914.852554423373;4926.014797040606;4949.059678661043;4963.875903102379;4893.657443978958;4911.066897289504;4931.7040977541465;4935.3090567320605;4974.676646017997;4107.445494858216;4183.6653067754505;4196.223698615705;4203.243416185055;4235.25882970479;3642.2715456908722;3655.8720444784303;3710.870119545803;3720.6093360197333;3771.792551409644;4336;4336.1978034063795;4336.566343365638;4338.869833904988;4388.1223755249075;5031.396232452056;5042.042012017705;5044.621653376012;5045.5459008689395;5083.085044691917;4217.662586993033;4255.659547236213;4266.850660226877;4289.6139347458875;4298.097399546068;4479.55204479556;4486.080353527519;4497.8200871964755;4501.347304640809;4552.430946131733;4608.470025946992;4619.053094115766;4619.722816631013;4628.051249493778;4664.696794708333;4904.362432883702;4946.406431228275;4952.925706114484;4963.131451995814;4995.700257984533;4589.196890544208;4649.372334734768;4649.442066951852;4664.626829853672;4665.2535594304745;4861.951380486292;4864.562189402829;4878.658493905393;4879.52314651952;4921.31101645777;4871.318015477765;4904.288878112582;4993.275974983534;4996.425411077773;5000.424951130666;4859.930815220646;4861.635377346784;4863.489333619919;4863.513648635108;4908.337374454403;4855.635827313036;4857.247126695368;4859.95140048595;4860.465348811557;4901.534354236484;4689.155951928686;4740.265258884879;4761.309610106446;4791.47293797689;4841.806327746992;4877.756112194447;4893.070316639827;4893.21708526634;4933.062718083553;4946.678465899705;4785.497522760199;4792;4793.880152996203;4808.447028591757;4824.831130910401 10:28:15 INFO - PROCESS | 4325 | |1;ext.html;571.5199232644578;573.7676241122344;590.3181824992133;591.9585629005971;602.16299343912;26869.462610747774;41519;42365;42926.78536607313;42978.355324670156;38777.61222387775;38925.805370973176;39061;39087;39226.215475690544;7543.057117860281;7674.5395276283325;7690.423218258625;7693.538387696728;7708.460407771458;7387.224341444152;9696.418214907093;9919.355241909252;9938.453385063818;10236.692899213036;10037.19702423808;10062.345947513388;10073.697789066304;10079.29444938851;10086.285331493627;7346.742863999734;9956.701298961007;10003.249756268237;10003.449810260432;10029.548670309854;9586.856197157049;9989.800203995952;9998.500074996222;10319.48402579868;10336.94831525845;37774.62225377739;38108.80945595282;38130.23739525222;38186.23627527462;38205.70765754064;3010.1722026442685;3017.6077109975563;3075.0621060576514;3363.3950669541764;3597.4603809428795;28407.85796071027;28719.28201794951;28800.279993000135;29109.126726198247;29853;3046.7105624965593;3056.1137270191616;3083.799553029068;3183.649798522161;3185.155933677577;256801;257308;257679;257718;258330.9999999981;31383.686163138533;32268.838655806572;32418.837905810793;51484.485155147966;52027.219591706154;33592;33700.32599347999;33749.325013500085;33750.324993500486;33755.662443375746;12312;12425.502979880885;12433.12968092243;12445;12460.439280232411;9075.50084745332;9218.078192180728;9987.550560224661;10033.09702126812;10057.245706572035;7478.9252107479315;7537.4723769336715;7679.577623230776;7859.6070196489945;7905.881411778828;3086.506159014547;3111;3151.5745374374164;3151.732102771244;3161.8933373319614;828.6312590896993;847.635516727813;880.9119088091139;989.6833013435776;1030.3302853145515;28147.577786333222;28169.295767605563;28563.71436285652;28773.28066798347;29019;921.995390023059;935.2284365398573;979.8579206015088;980.6910823090703;992.513668302541;14774.724396868474;14827.851721482863;14952.925235373754;15261.618459538378;15295.92352038255;374294;375547;378887;378904;379644;48822.75588622105;49377.75311123421;49413;49421.25868111981;49465.25802112971;1137.135776809614;1151.5739176504746;1190.8094704847183;1190.9761804763857;1201.303244118409;26335.34161645974;27137;28114;28447.857760711064;28587.428251434438;42547.5745242556;42835;42873.571264286955;42923;42927.356089659304;267426;267663;268322;268992;269590;22671.546569068858;22822.201222957116;23541.440156404326;24832.37919052038;25170.119045833308;35724;35781.82109089438;36185.63814361928;40714.59285407108;41426.792866035474;34123.48814767731;34384;35956.46065309075;36216.81891590525;36983.44524832076;36149.63850361568;36341.636583634885;36401.453978190875;36510.63489365178;36712.26575468529;35530;35781;35831.28337433289;35862;36435.45346819747;30054;31931.68068319287;32791;32858.67141328556;32938;23976;24178.153764618153;24420.877895610407;24579.754202457745;24764.38089047788 10:28:15 INFO - PROCESS | 4325 | |2;jquery.html;336.3021729910436;361.5282056915724;368.2892018404479;368.9704823614111;381.0682880357525;13248.47006119754;15978.440754573592;16286.185690715462;16807.411740589083;17915.91042044791;15681.451149209779;15721.84278157218;16230.269637866295;16282.75575866363;16523.50429487114;4413.227685155101;4997.2753950677225;5193.402758682762;5199.844004679847;5247.1866860636665;5130.692158470503;5197.636165468419;5200.843974680765;5203.427622961472;5203.713795741232;4943.555080042795;5078.949210507903;5109.872253193681;5196.558292545138;5198.116320225552;5062.417821950467;5096.821611243588;5109.20807274873;5117.437081920985;5119.104156772569;4364.170807546556;4478.0148367359125;4510.0979804039;4511.774411279423;4512.616427603625;21194.364169074954;21201.89399052995;21203.151873925024;21265;21485.67771483429;1924.3841970569424;2070.244360808309;2085.572457646179;2218.101668824129;2344.2146880794953;8705.608247628872;9164.495952722664;9418.340716149873;9687.66093186735;12886.484540618365;1656.2712406541084;1686.3676121454455;1706.3089448773267;1761.5331937036694;1817.6092140189924;70591.52929647437;72311.63844180798;72813.63593182054;73032.26967730308;73130.63434682846;9961.35251208669;10081.899181008243;10093.646722364681;10256.538455769509;10257.28199026061;13786.103903246458;13799.723045697549;13821.308934553232;13831.654208644663;13864.376103075387;6788.626625535544;6994.930050699428;7070.045543851515;7141.89287160693;7183.138023437221;6542.24764152119;6561.671916404161;6678.198616166092;6712.295209003082;6713.966430167818;4115.238680844053;4186.1418409226035;4192.224438478891;4299.505556861001;4304.5910638489295;1724.8016478105178;1741.8519425848692;1750.0899532243163;1783.9018853963157;1827.1412436154985;795.7334293011791;871.8517851965181;894.3560636341823;916.3585490156784;960.3325688646323;17166.39917602878;17240.827591724174;18301;18304.908475457538;18472.353467628836;821.8180730161431;825.1748251748252;828.9980540884077;835.3359079531665;842.4945032980286;9435.622575097057;10016.098551130433;10059.849102263472;10183.898161018444;10316.1747060235;384075;424196;426292;431478;437223;74868.62565687245;76459;82620.58689706514;83084;83359;404.3611094470729;425.1093958157655;425.5255390239922;425.72327986808824;426.8868749781248;9607.423554586747;10014.64948726791;10023.749406264902;10106.444145572064;10259.076683098558;26155.476890461705;26926.922923082857;28909.277268068465;35199.47200792041;39270.4109438353;51128.23307650314;51802.740986294826;51817.7409112952;53118.46881531135;53515;7553.471257011956;7563.621818909142;7616.543007419573;7732.226777322223;7855.764327070197;8296.045954715284;11208.159388046091;11986.160968732105;12079.154459188125;12671.619851404472;9829.4102353859;10188.337758045704;10219;10476.947615261875;10545.894541054491;10523.421211833424;10648.307859989076;10771.89228107709;10774.730631733955;10850.511726972463;10462.63380781669;10483.895161048293;10493.055624993787;10606.522706478072;10627.309224900357;7394.926050739639;7515.13575938774;7542.094948606203;7671.769846904602;7937;10221.540030698488;10224.53989570486;10351.482425878827;10458.843117353397;10469.214808889212 10:28:15 INFO - PROCESS | 4325 | |3;mootools.html;1236.344737289237;1388.9722205555886;1436.8922330825187;1470.3897882378812;1676.6311411489476;6784.728610855566;7850.842983140341;8161.91838081619;8191.959040204805;8418.32653387729;8247.340212782978;8302.045264794551;8302.045264794551;8322.542260175687;8326.95836520818;2201.7137772089645;2229.9219527316545;2231.3306008197555;2232.631615783394;2233.3188377544843;2197.4396528885113;2256.323103069081;2257.9661305080435;2260.0394832196334;2263.2644390573046;2193.638049721794;2194.846360754748;2209.9668504972437;2210.248515504728;2227.610168220563;2165.5019345550613;2189.8576592521435;2195.045155357425;2196.9450763730797;2199.2522542335523;2192.6710993350966;2199.373178644079;2201.768814274494;2202.2952655150357;2202.812760915324;8002.279794818494;8188;8256.876146857801;8268.131846156128;8298.585070746438;4536.818527258905;4653.976730116327;4657.534246575349;4658.138244424758;4668.253079507296;2814.437112577493;2892.638420197475;2959.556066590007;2960.467115919134;2962.22982024673;837.4723923927947;848.4357901995165;849.7535714642746;856.8157846063126;859.6991053131417;11259.042981346596;11349.716257093556;11436.085113190924;11451.026662733677;11467.19729618927;3936.842526298916;4106.67146628269;4145.108801607669;4237.364395340724;4252.362145678173;8906.643734250687;8909.599068041955;8916.974547926942;8929.10708929102;8929.642814287487;4513.729176249435;4530.388397566352;4540.84107056258;4541.455025396907;4544.272916333437;3583.802890841029;3968.0873399118364;4142.378643203483;4203.936940945889;4219.367094935784;3371.224618337796;3397.303552771676;3516.769130804198;3536.8231588420476;3546;984.6602921991902;991.861139440479;992.0773680477081;1001.2240513601871;1005.9899401006043;785.6621652689398;822.461287856455;834.4742812028384;835.1231207232503;846.995765021171;5186.714730689773;5206.7917283309;5370.194470829407;5389.9191512127345;5494.340679118532;390.4884601172473;392.297786961336;397.7474303817039;399.26535175277047;404.04846586289455;3443.5695538057744;3591;3672.8347224374966;3688.5389326334207;3782.924341513154;9930.652427165014;10239.436830974219;10311.742206444898;10322.896771032343;10326.638567650096;10254.948725256327;10260.640877569249;10269.0757831794;10271.435071071162;10287.794244115226;7537.359324457373;7668.309852113337;7702.730404435818;7785.182555831556;7804.102528209215;5279.340082489689;5365.517103460707;5467.234587157722;5509.3664228614225;5546.833594992157;9245.72262832116;9399.10708482693;9453.149216570542;9462.810743784948;9549.236061115233;8983.730488085368;9409.764755881159;9435.29235307341;9511.381760185843;9729.270304727032;6144.354842741626;6830.419414349836;6889.655517224019;7064.187618423947;7070.434365250665;7752.5348479091435;7904.802379940317;7997.200279971957;7998.240167184108;8025.638846251816;6874.346937040973;7157.284271572801;7180.210176880643;7193.604351760703;7323.487355885037;6605.1083103780375;6741.73033078691;6872.725090996504;6951.6871740770775;6982.615956122462;6128.540359473147;6171.352008039091;6174.876502470015;6181.443670069715;6425.646589437439;4627.074585083064;4666.160091183619;4669.533046695304;4750.78621462042;4846.709197448165;4499.280115181556;4570.154521413549;4689.507601701772;4741.525847415231;4756.738379389167 10:28:15 INFO - PROCESS | 4325 | |4;prototype.html;293.1790985241324;297.4868352092639;301.30098172240395;313.28102005896466;314.7403392201433;4671;4696.577308042271;4698.953010469894;4726.149293127236;4750.239961606135;4435.157320109177;4513.277875539914;4592.609628181609;4645.419322584677;4702.5062368451245;3708.295423869456;3721.9441708374397;3729.7762134271893;3735.7011439084945;3759.285735710206;3685.70514358852;3689.1883785567134;3709.4806727058235;3718.2377612589353;3721.776693398391;3541.982290088546;3542.946855797165;3640.5085313482605;3699.3341198584244;3709.7217708671956;3682.39240525313;3699.85200591976;3702.0559757261994;3708.7589306694977;3713.7029037677057;3247.6102867655795;3260.608726952757;3265.4122257993554;3272.7381809455187;3277.4919887417486;4437.933430998538;4526.343680166357;4586.289125185602;4586.633069354443;4593.977030114828;4164.5210800757695;4170.791460426966;4175.164967006581;4185.927965656989;4205.705600607958;4814.205656066745;4823.734694591796;4834.830780922686;4847.83032593861;4874.805007799684;3573.1960308930543;3583.2654305867236;3588.102974256436;3588.2105936693883;3593.6226696197045;4981.153203955337;5008.84973450797;5106.361704786902;5107.514786095315;5154.768035438415;3609.873654422083;3644.2529281497227;3644.669695561154;3653.762505437138;3655.6527129922615;3679.6504332088407;3702.2595480903387;3706.462562928414;3711.2020915503294;3715.1826598148364;4073.8593193905754;4192.706510544293;4196.51740049892;4199.307114326163;4202.579742025773;4870.902581948341;4877.804887804519;4894.486078961729;4897.510248975145;4898.68158569699;1213.6905089202196;1261.6593519749615;1275.9234445933273;1283.159530507519;1286.1382873474795;844.5903736687701;869.5261082709939;890.283321925856;900.62624011036;901.1304091551684;4306.267934451151;4332.1985432695055;4335.241332766753;4340.8480703175865;4346.6088052075465;4918.483559226302;4958.975205124024;4967.279744437036;4993;5015.84952451427;4129.91740165195;4320.2223599751715;4332.805023773938;4371.715838470489;4402.823887044482;2670.586059160833;2673.5187666219877;2673.639058727047;2694.151342327161;2694.9326266843095;4705.929411058837;4713.2458806590785;4714.0807542529565;4714.104320179155;4744.572988431058;5027.974860125676;5096.796128154832;5160.148575485003;5196.68819870809;5232.790688372423;4729.101470720552;4734.739589322551;4737.3130896020575;4737.620990320766;4742.810287588527;5074.031691460537;5090.32757802142;5106.770195341144;5119.513646203604;5133.28035108243;4755.79556642907;4929.408470983505;4970.8757281068265;4985.152524070989;5031.094403007493;4661.3707149534375;4662.69444555525;4672.34587157805;4678.228092364721;4680.121725139083;3534.8409321580075;3539.8230088494956;3547.627499112556;3550.3076900005285;3573.196030893132;4919.557239848431;5022.801400550413;5026.195808670595;5048.59611231108;5069.721165335942;4882.218844984927;4894.996525712149;4901.852944411673;4923.064617722621;4924.162892308388;4899.779509922133;4919.318971160655;4929.482404347636;4948.1835497142565;4956.925646115384;4700.318453824245;4794.664373493962;4840.6127509798425;4847.903041939071;4867.975660121677;4866.732329721899;4874.366332376769;4891.192953162688;4898.632602554751;4911.028827559275;4809.326694262736;4822.204336284473;4840.523640289745;4849.393825771778;4851.611871050378 10:28:15 INFO - PROCESS | 4325 | |5;yui.html;579.6319337220866;585.1164741240726;601.5548494114354;616.9691515424229;658.7233361987965;12667.49330026799;12700.936495317534;13304.600861974144;13311.467541298349;13439.462421503129;12646.810297845539;12700.555480558181;12845;12879.871201287984;12881.420336084875;627.0907184582351;736.3667246168304;752.5860776572863;752.9247075292482;753.1903204055635;747.0885519665999;785.0932173339802;785.5954183595453;792.2236208515658;795.3041089047084;809.3323008517996;810;816.4652152839899;817.4890693316677;820.0077905734069;662.2450406536552;811.395510344791;811.3995643224001;813.2558708781457;813.9633716482773;576.2969177603318;592.6444133519897;593.222878029778;593.2643522032691;594.5689375202986;11965.102617303737;11968.222065565711;12066.155369124164;12130.090243231705;12135.878641213563;12234.877651223464;12378.133530652858;12405.317707526083;12511.374431278491;13113.344332783417;10999.158278505258;11144.888551114465;11183.384913829817;11298.661040168809;11344.376059316734;3835.6547910688173;3917.2361389529055;3941.625545573166;3945.013706914936;3991.44119823225;58521.12218316771;59598.40401595972;59636.10545841816;59766.40233597652;60411.697941510014;9627.951860240793;9928.553215105338;10005.749856253506;10104.04011618895;10525.578976840994;17138.571535711708;18388.080595970147;18390.540236494195;18415.631687366178;18447.72328415075;6475.740970361133;6528.967355163194;6681.465482761368;6697.933020669731;6766.18805743314;3648.4709717090873;3658.2500587379523;3660.6156353583024;3691.7231207659534;3723.142773438498;2593.494268617639;2649;2680.624712540246;2681.597760335965;2689.7310268972947;480.97595120243847;490.394362961742;494.1845954175639;498.8952320012838;499.46557183813695;439.9978000109979;444.5754304639062;447.9283314669668;479.67142507382556;489.4395916675458;6805.523613347018;6858.897116543256;6880.483963702743;7066.823329416703;7294.051773269441;440.5682431217361;453.2702349217757;454.3616219212012;471.11900745605254;509.5083244668912;3227.3545290941906;3420.196253880336;3431.9656803432144;3519.9120021999656;3622.076370525499;88572.11427885767;96003;96804;98176.50911745396;98698.50650746844;48507.514924851006;52610.473895260555;78574;82944;84481;49266;72955.90566141512;75433;75900.24099759042;76157;6742.629155396304;6784.945808096932;6787.423069039187;7516;7674.53952762836;29612.851935740182;30901.381972360876;39327.80336098301;44261.672149835555;47635.52364476311;66773.66613167098;70433.6478317605;70828.64585677038;71700;73636.63181684057;10140.442275674908;10286.794264114526;10312.742181445225;10384.740381490523;10815.134789216687;10953.34279943206;11122.666320010412;11286.717832054264;11389.886101138882;12279.386030698251;10148.391096534231;10535;10622.946885265525;10626.52180651857;10952.178586606196;10548.472576371305;10666.520006599567;10981.17641176931;11015.449227538751;11178.329300242012;10044.648437304659;10261.538230779783;10306.948465257627;10344.534495947852;10360;8757.562121893752;9006.459612423276;9245.815083698422;9471.905280947378;9727.902720972983;8419.36854735885;8678.956605216934;8961;9117.17945384892;9255.583498742708 10:28:15 INFO - PROCESS | 4325 | __end_tp_report 10:28:15 INFO - PROCESS | 4325 | __start_cc_report 10:28:15 INFO - PROCESS | 4325 | _x_x_mozilla_cycle_collect,957 10:28:15 INFO - PROCESS | 4325 | __end_cc_report 10:28:15 INFO - PROCESS | 4325 | __startTimestamp1469122095244__endTimestamp 10:28:15 INFO - PROCESS | 4325 | 10:28:15 INFO - PROCESS | 4325 | ------- Summary: start ------- 10:28:15 INFO - PROCESS | 4325 | Number of tests: 6 10:28:15 INFO - PROCESS | 4325 | 10:28:15 INFO - PROCESS | 4325 | [#0] dojo.html Cycles:165 Average:4610.30 Median:4710.39 stddev:489.03 (10.4%) stddev-sans-first:462.28 10:28:15 INFO - PROCESS | 4325 | Values: 2522.4 2559.0 2566.1 2569.2 2592.7 4582.9 4650.6 4651.8 4667.6 4669.5 4640.5 4662.9 4682.0 4692.9 4710.4 4223.8 4256.9 4290.2 4327.8 4403.4 4308.0 4356.5 4360.6 4382.1 4389.1 4220.1 4220.9 4245.2 4268.8 4279.7 4282.3 4288.3 4290.9 4294.6 4299.9 4345.3 4346.6 4350.6 4378.4 4378.6 4569.0 4653.9 4716.2 4757.8 4770.4 4950.3 4974.7 4980.7 4995.2 5048.8 5009.3 5017.0 5024.6 5037.1 5064.1 4516.8 4654.0 4671.3 4683.0 4686.3 5137.8 5147.0 5207.2 5217.0 5217.6 4935.2 4955.7 4962.9 4998.8 5005.0 4902.2 4963.0 5014.7 5077.5 5092.0 4865.3 4914.9 4926.0 4949.1 4963.9 4893.7 4911.1 4931.7 4935.3 4974.7 4107.4 4183.7 4196.2 4203.2 4235.3 3642.3 3655.9 3710.9 3720.6 3771.8 4336.0 4336.2 4336.6 4338.9 4388.1 5031.4 5042.0 5044.6 5045.5 5083.1 4217.7 4255.7 4266.9 4289.6 4298.1 4479.6 4486.1 4497.8 4501.3 4552.4 4608.5 4619.1 4619.7 4628.1 4664.7 4904.4 4946.4 4952.9 4963.1 4995.7 4589.2 4649.4 4649.4 4664.6 4665.3 4862.0 4864.6 4878.7 4879.5 4921.3 4871.3 4904.3 4993.3 4996.4 5000.4 4859.9 4861.6 4863.5 4863.5 4908.3 4855.6 4857.2 4860.0 4860.5 4901.5 4689.2 4740.3 4761.3 4791.5 4841.8 4877.8 4893.1 4893.2 4933.1 4946.7 4785.5 4792.0 4793.9 4808.4 4824.8 10:28:15 INFO - PROCESS | 4325 | 10:28:15 INFO - PROCESS | 4325 | [#1] ext.html Cycles:180 Average:44486.91 Median:33752.99 stddev:80537.47 (238.6%) stddev-sans-first:80695.90 10:28:15 INFO - PROCESS | 4325 | Values: 571.5 573.8 590.3 592.0 602.2 26869.5 41519.0 42365.0 42926.8 42978.4 38777.6 38925.8 39061.0 39087.0 39226.2 7543.1 7674.5 7690.4 7693.5 7708.5 7387.2 9696.4 9919.4 9938.5 10236.7 10037.2 10062.3 10073.7 10079.3 10086.3 7346.7 9956.7 10003.2 10003.4 10029.5 9586.9 9989.8 9998.5 10319.5 10336.9 37774.6 38108.8 38130.2 38186.2 38205.7 3010.2 3017.6 3075.1 3363.4 3597.5 28407.9 28719.3 28800.3 29109.1 29853.0 3046.7 3056.1 3083.8 3183.6 3185.2 256801.0 257308.0 257679.0 257718.0 258331.0 31383.7 32268.8 32418.8 51484.5 52027.2 33592.0 33700.3 33749.3 33750.3 33755.7 12312.0 12425.5 12433.1 12445.0 12460.4 9075.5 9218.1 9987.6 10033.1 10057.2 7478.9 7537.5 7679.6 7859.6 7905.9 3086.5 3111.0 3151.6 3151.7 3161.9 828.6 847.6 880.9 989.7 1030.3 28147.6 28169.3 28563.7 28773.3 29019.0 922.0 935.2 979.9 980.7 992.5 14774.7 14827.9 14952.9 15261.6 15295.9 374294.0 375547.0 378887.0 378904.0 379644.0 48822.8 49377.8 49413.0 49421.3 49465.3 1137.1 1151.6 1190.8 1191.0 1201.3 26335.3 27137.0 28114.0 28447.9 28587.4 42547.6 42835.0 42873.6 42923.0 42927.4 267426.0 267663.0 268322.0 268992.0 269590.0 22671.5 22822.2 23541.4 24832.4 25170.1 35724.0 35781.8 36185.6 40714.6 41426.8 34123.5 34384.0 35956.5 36216.8 36983.4 36149.6 36341.6 36401.5 36510.6 36712.3 35530.0 35781.0 35831.3 35862.0 36435.5 30054.0 31931.7 32791.0 32858.7 32938.0 23976.0 24178.2 24420.9 24579.8 24764.4 10:28:15 INFO - PROCESS | 4325 | 10:28:15 INFO - PROCESS | 4325 | [#2] jquery.html Cycles:180 Average:25032.65 Median:214194.11 stddev:69509.01 (32.5%) stddev-sans-first:69679.27 10:28:15 INFO - PROCESS | 4325 | Values: 336.3 361.5 368.3 369.0 381.1 13248.5 15978.4 16286.2 16807.4 17915.9 15681.5 15721.8 16230.3 16282.8 16523.5 4413.2 4997.3 5193.4 5199.8 5247.2 5130.7 5197.6 5200.8 5203.4 5203.7 4943.6 5078.9 5109.9 5196.6 5198.1 5062.4 5096.8 5109.2 5117.4 5119.1 4364.2 4478.0 4510.1 4511.8 4512.6 21194.4 21201.9 21203.2 21265.0 21485.7 1924.4 2070.2 2085.6 2218.1 2344.2 8705.6 9164.5 9418.3 9687.7 12886.5 1656.3 1686.4 1706.3 1761.5 1817.6 70591.5 72311.6 72813.6 73032.3 73130.6 9961.4 10081.9 10093.6 10256.5 10257.3 13786.1 13799.7 13821.3 13831.7 13864.4 6788.6 6994.9 7070.0 7141.9 7183.1 6542.2 6561.7 6678.2 6712.3 6714.0 4115.2 4186.1 4192.2 4299.5 4304.6 1724.8 1741.9 1750.1 1783.9 1827.1 795.7 871.9 894.4 916.4 960.3 17166.4 17240.8 18301.0 18304.9 18472.4 821.8 825.2 829.0 835.3 842.5 9435.6 10016.1 10059.8 10183.9 10316.2 384075.0 424196.0 426292.0 431478.0 437223.0 74868.6 76459.0 82620.6 83084.0 83359.0 404.4 425.1 425.5 425.7 426.9 9607.4 10014.6 10023.7 10106.4 10259.1 26155.5 26926.9 28909.3 35199.5 39270.4 51128.2 51802.7 51817.7 53118.5 53515.0 7553.5 7563.6 7616.5 7732.2 7855.8 8296.0 11208.2 11986.2 12079.2 12671.6 9829.4 10188.3 10219.0 10476.9 10545.9 10523.4 10648.3 10771.9 10774.7 10850.5 10462.6 10483.9 10493.1 10606.5 10627.3 7394.9 7515.1 7542.1 7671.8 7937.0 10221.5 10224.5 10351.5 10458.8 10469.2 10:28:15 INFO - PROCESS | 4325 | 10:28:15 INFO - PROCESS | 4325 | [#3] mootools.html Cycles:180 Average:5269.86 Median:4679.52 stddev:3086.11 (65.9%) stddev-sans-first:3079.88 10:28:15 INFO - PROCESS | 4325 | Values: 1236.3 1389.0 1436.9 1470.4 1676.6 6784.7 7850.8 8161.9 8192.0 8418.3 8247.3 8302.0 8302.0 8322.5 8327.0 2201.7 2229.9 2231.3 2232.6 2233.3 2197.4 2256.3 2258.0 2260.0 2263.3 2193.6 2194.8 2210.0 2210.2 2227.6 2165.5 2189.9 2195.0 2196.9 2199.3 2192.7 2199.4 2201.8 2202.3 2202.8 8002.3 8188.0 8256.9 8268.1 8298.6 4536.8 4654.0 4657.5 4658.1 4668.3 2814.4 2892.6 2959.6 2960.5 2962.2 837.5 848.4 849.8 856.8 859.7 11259.0 11349.7 11436.1 11451.0 11467.2 3936.8 4106.7 4145.1 4237.4 4252.4 8906.6 8909.6 8917.0 8929.1 8929.6 4513.7 4530.4 4540.8 4541.5 4544.3 3583.8 3968.1 4142.4 4203.9 4219.4 3371.2 3397.3 3516.8 3536.8 3546.0 984.7 991.9 992.1 1001.2 1006.0 785.7 822.5 834.5 835.1 847.0 5186.7 5206.8 5370.2 5389.9 5494.3 390.5 392.3 397.7 399.3 404.0 3443.6 3591.0 3672.8 3688.5 3782.9 9930.7 10239.4 10311.7 10322.9 10326.6 10254.9 10260.6 10269.1 10271.4 10287.8 7537.4 7668.3 7702.7 7785.2 7804.1 5279.3 5365.5 5467.2 5509.4 5546.8 9245.7 9399.1 9453.1 9462.8 9549.2 8983.7 9409.8 9435.3 9511.4 9729.3 6144.4 6830.4 6889.7 7064.2 7070.4 7752.5 7904.8 7997.2 7998.2 8025.6 6874.3 7157.3 7180.2 7193.6 7323.5 6605.1 6741.7 6872.7 6951.7 6982.6 6128.5 6171.4 6174.9 6181.4 6425.6 4627.1 4666.2 4669.5 4750.8 4846.7 4499.3 4570.2 4689.5 4741.5 4756.7 10:28:15 INFO - PROCESS | 4325 | 10:28:15 INFO - PROCESS | 4325 | [#4] prototype.html Cycles:180 Average:4081.45 Median:4666.85 stddev:1166.49 (25.0%) stddev-sans-first:1134.58 10:28:15 INFO - PROCESS | 4325 | Values: 293.2 297.5 301.3 313.3 314.7 4671.0 4696.6 4699.0 4726.1 4750.2 4435.2 4513.3 4592.6 4645.4 4702.5 3708.3 3721.9 3729.8 3735.7 3759.3 3685.7 3689.2 3709.5 3718.2 3721.8 3542.0 3542.9 3640.5 3699.3 3709.7 3682.4 3699.9 3702.1 3708.8 3713.7 3247.6 3260.6 3265.4 3272.7 3277.5 4437.9 4526.3 4586.3 4586.6 4594.0 4164.5 4170.8 4175.2 4185.9 4205.7 4814.2 4823.7 4834.8 4847.8 4874.8 3573.2 3583.3 3588.1 3588.2 3593.6 4981.2 5008.8 5106.4 5107.5 5154.8 3609.9 3644.3 3644.7 3653.8 3655.7 3679.7 3702.3 3706.5 3711.2 3715.2 4073.9 4192.7 4196.5 4199.3 4202.6 4870.9 4877.8 4894.5 4897.5 4898.7 1213.7 1261.7 1275.9 1283.2 1286.1 844.6 869.5 890.3 900.6 901.1 4306.3 4332.2 4335.2 4340.8 4346.6 4918.5 4959.0 4967.3 4993.0 5015.8 4129.9 4320.2 4332.8 4371.7 4402.8 2670.6 2673.5 2673.6 2694.2 2694.9 4705.9 4713.2 4714.1 4714.1 4744.6 5028.0 5096.8 5160.1 5196.7 5232.8 4729.1 4734.7 4737.3 4737.6 4742.8 5074.0 5090.3 5106.8 5119.5 5133.3 4755.8 4929.4 4970.9 4985.2 5031.1 4661.4 4662.7 4672.3 4678.2 4680.1 3534.8 3539.8 3547.6 3550.3 3573.2 4919.6 5022.8 5026.2 5048.6 5069.7 4882.2 4895.0 4901.9 4923.1 4924.2 4899.8 4919.3 4929.5 4948.2 4956.9 4700.3 4794.7 4840.6 4847.9 4868.0 4866.7 4874.4 4891.2 4898.6 4911.0 4809.3 4822.2 4840.5 4849.4 4851.6 10:28:15 INFO - PROCESS | 4325 | 10:28:15 INFO - PROCESS | 4325 | [#5] yui.html Cycles:180 Average:16812.52 Median:480.32 stddev:24362.41 (5072.1%) stddev-sans-first:24400.27 10:28:15 INFO - PROCESS | 4325 | Values: 579.6 585.1 601.6 617.0 658.7 12667.5 12700.9 13304.6 13311.5 13439.5 12646.8 12700.6 12845.0 12879.9 12881.4 627.1 736.4 752.6 752.9 753.2 747.1 785.1 785.6 792.2 795.3 809.3 810.0 816.5 817.5 820.0 662.2 811.4 811.4 813.3 814.0 576.3 592.6 593.2 593.3 594.6 11965.1 11968.2 12066.2 12130.1 12135.9 12234.9 12378.1 12405.3 12511.4 13113.3 10999.2 11144.9 11183.4 11298.7 11344.4 3835.7 3917.2 3941.6 3945.0 3991.4 58521.1 59598.4 59636.1 59766.4 60411.7 9628.0 9928.6 10005.7 10104.0 10525.6 17138.6 18388.1 18390.5 18415.6 18447.7 6475.7 6529.0 6681.5 6697.9 6766.2 3648.5 3658.3 3660.6 3691.7 3723.1 2593.5 2649.0 2680.6 2681.6 2689.7 481.0 490.4 494.2 498.9 499.5 440.0 444.6 447.9 479.7 489.4 6805.5 6858.9 6880.5 7066.8 7294.1 440.6 453.3 454.4 471.1 509.5 3227.4 3420.2 3432.0 3519.9 3622.1 88572.1 96003.0 96804.0 98176.5 98698.5 48507.5 52610.5 78574.0 82944.0 84481.0 49266.0 72955.9 75433.0 75900.2 76157.0 6742.6 6784.9 6787.4 7516.0 7674.5 29612.9 30901.4 39327.8 44261.7 47635.5 66773.7 70433.6 70828.6 71700.0 73636.6 10140.4 10286.8 10312.7 10384.7 10815.1 10953.3 11122.7 11286.7 11389.9 12279.4 10148.4 10535.0 10622.9 10626.5 10952.2 10548.5 10666.5 10981.2 11015.4 11178.3 10044.6 10261.5 10306.9 10344.5 10360.0 8757.6 9006.5 9245.8 9471.9 9727.9 8419.4 8679.0 8961.0 9117.2 9255.6 10:28:15 INFO - PROCESS | 4325 | -------- Summary: end -------- 10:28:15 INFO - PROCESS | 4325 | 10:28:16 INFO - TEST-INFO | 4325: exit 0 10:28:16 INFO - TEST-OK | dromaeo_css | took 1120284ms 10:28:16 INFO - TEST-START | kraken 10:28:16 INFO - operating with platform_type : linux_ 10:28:16 INFO - Initialising browser for kraken test... 10:28:16 INFO - TEST-INFO | started process 4519 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTlRncy/profile http://localhost:48156/getInfo.html) 10:28:20 INFO - PROCESS | 4519 | JavaScript error: , line 0: uncaught exception: undefined 10:28:20 INFO - PROCESS | 4519 | __metrics Screen width/height:1600/1200 10:28:20 INFO - PROCESS | 4519 | colorDepth:24 10:28:20 INFO - PROCESS | 4519 | Browser inner width/height: 1024/697 10:28:20 INFO - PROCESS | 4519 | __metrics 10:28:21 INFO - PROCESS | 4519 | 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" 10:28:21 INFO - PROCESS | 4519 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:28:22 INFO - TEST-INFO | 4519: exit 0 10:28:22 INFO - Browser initialized. 10:28:22 INFO - Running cycle 1/1 for kraken test... 10:28:22 INFO - TEST-INFO | started process 4599 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTlRncy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:28:34 INFO - PROCESS | 4599 | RSS: Main: 158810112 10:28:34 INFO - PROCESS | 4599 | 10:28:37 INFO - PROCESS | 4599 | Cycle 1(1): loaded http://localhost:48156/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48156/tests/kraken/driver.html?testName=audio-beat-detection) 10:28:38 INFO - PROCESS | 4599 | RSS: Main: 215388160 10:28:38 INFO - PROCESS | 4599 | 10:28:40 INFO - PROCESS | 4599 | Cycle 1(1): loaded http://localhost:48156/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48156/tests/kraken/driver.html?testName=audio-dft) 10:28:41 INFO - PROCESS | 4599 | RSS: Main: 192700416 10:28:41 INFO - PROCESS | 4599 | 10:28:44 INFO - PROCESS | 4599 | Cycle 1(1): loaded http://localhost:48156/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48156/tests/kraken/driver.html?testName=audio-fft) 10:28:45 INFO - PROCESS | 4599 | RSS: Main: 273809408 10:28:45 INFO - PROCESS | 4599 | 10:28:47 INFO - PROCESS | 4599 | Cycle 1(1): loaded http://localhost:48156/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48156/tests/kraken/driver.html?testName=audio-oscillator) 10:28:48 INFO - PROCESS | 4599 | RSS: Main: 181886976 10:28:48 INFO - PROCESS | 4599 | 10:28:50 INFO - PROCESS | 4599 | Cycle 1(1): loaded http://localhost:48156/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48156/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:28:50 INFO - PROCESS | 4599 | RSS: Main: 185921536 10:28:50 INFO - PROCESS | 4599 | 10:28:54 INFO - PROCESS | 4599 | Cycle 1(1): loaded http://localhost:48156/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48156/tests/kraken/driver.html?testName=imaging-darkroom) 10:28:54 INFO - PROCESS | 4599 | RSS: Main: 222801920 10:28:54 INFO - PROCESS | 4599 | 10:28:58 INFO - PROCESS | 4599 | Cycle 1(1): loaded http://localhost:48156/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48156/tests/kraken/driver.html?testName=imaging-desaturate) 10:28:58 INFO - PROCESS | 4599 | RSS: Main: 222748672 10:28:58 INFO - PROCESS | 4599 | 10:29:01 INFO - PROCESS | 4599 | Cycle 1(1): loaded http://localhost:48156/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48156/tests/kraken/driver.html?testName=json-parse-financial) 10:29:02 INFO - PROCESS | 4599 | RSS: Main: 222023680 10:29:02 INFO - PROCESS | 4599 | 10:29:04 INFO - PROCESS | 4599 | Cycle 1(1): loaded http://localhost:48156/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48156/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:29:04 INFO - PROCESS | 4599 | RSS: Main: 185892864 10:29:04 INFO - PROCESS | 4599 | 10:29:06 INFO - PROCESS | 4599 | Cycle 1(1): loaded http://localhost:48156/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48156/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:29:07 INFO - PROCESS | 4599 | RSS: Main: 192286720 10:29:07 INFO - PROCESS | 4599 | 10:29:09 INFO - PROCESS | 4599 | Cycle 1(1): loaded http://localhost:48156/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48156/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:29:10 INFO - PROCESS | 4599 | RSS: Main: 196919296 10:29:10 INFO - PROCESS | 4599 | 10:29:12 INFO - PROCESS | 4599 | Cycle 1(1): loaded http://localhost:48156/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48156/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:29:13 INFO - PROCESS | 4599 | RSS: Main: 192012288 10:29:13 INFO - PROCESS | 4599 | 10:29:16 INFO - PROCESS | 4599 | Cycle 1(1): loaded http://localhost:48156/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48156/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:29:17 INFO - PROCESS | 4599 | RSS: Main: 194850816 10:29:17 INFO - PROCESS | 4599 | 10:29:18 INFO - PROCESS | 4599 | Cycle 1(1): loaded http://localhost:48156/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48156/tests/kraken/driver.html?testName=ai-astar) 10:29:19 INFO - PROCESS | 4599 | RSS: Main: 245641216 10:29:19 INFO - PROCESS | 4599 | 10:29:19 INFO - PROCESS | 4599 | __start_tp_report 10:29:19 INFO - PROCESS | 4599 | _x_x_mozilla_page_load 10:29:19 INFO - PROCESS | 4599 | _x_x_mozilla_page_load_details 10:29:19 INFO - PROCESS | 4599 | |i|pagename|runs| 10:29:19 INFO - PROCESS | 4599 | |0;ai-astar;104;114;113;113;112;112;113;112;112;113 10:29:19 INFO - PROCESS | 4599 | |1;audio-beat-detection;163;123;123;183;125;136;148;124;179;124 10:29:19 INFO - PROCESS | 4599 | |2;audio-dft;161;162;161;161;161;160;163;162;164;164 10:29:19 INFO - PROCESS | 4599 | |3;audio-fft;75;76;79;130;76;76;77;78;99;76 10:29:19 INFO - PROCESS | 4599 | |4;audio-oscillator;87;87;119;86;85;86;118;85;85;127 10:29:19 INFO - PROCESS | 4599 | |5;imaging-gaussian-blur;117;117;117;117;118;117;116;117;117;118 10:29:19 INFO - PROCESS | 4599 | |6;imaging-darkroom;148;147;146;147;147;146;146;147;148;147 10:29:19 INFO - PROCESS | 4599 | |7;imaging-desaturate;95;94;94;95;94;96;95;95;95;94 10:29:19 INFO - PROCESS | 4599 | |8;json-parse-financial;62;61;62;61;64;62;63;61;62;63 10:29:19 INFO - PROCESS | 4599 | |9;json-stringify-tinderbox;65;66;65;65;66;65;66;67;66;65 10:29:19 INFO - PROCESS | 4599 | |10;stanford-crypto-aes;80;80;79;81;79;80;80;79;79;79 10:29:19 INFO - PROCESS | 4599 | |11;stanford-crypto-ccm;132;136;133;144;132;128;137;132;179;134 10:29:19 INFO - PROCESS | 4599 | |12;stanford-crypto-pbkdf2;164;168;166;168;165;168;162;168;161;175 10:29:19 INFO - PROCESS | 4599 | |13;stanford-crypto-sha256-iterative;66;69;69;68;69;66;66;65;67;65 10:29:19 INFO - PROCESS | 4599 | __end_tp_report 10:29:19 INFO - PROCESS | 4599 | __start_cc_report 10:29:19 INFO - PROCESS | 4599 | _x_x_mozilla_cycle_collect,1949 10:29:19 INFO - PROCESS | 4599 | __end_cc_report 10:29:19 INFO - PROCESS | 4599 | __startTimestamp1469122159065__endTimestamp 10:29:19 INFO - PROCESS | 4599 | 10:29:19 INFO - PROCESS | 4599 | ------- Summary: start ------- 10:29:19 INFO - PROCESS | 4599 | Number of tests: 14 10:29:19 INFO - PROCESS | 4599 | 10:29:19 INFO - PROCESS | 4599 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:2.82 (2.5%) stddev-sans-first:0.71 10:29:19 INFO - PROCESS | 4599 | Values: 104.0 114.0 113.0 113.0 112.0 112.0 113.0 112.0 112.0 113.0 10:29:19 INFO - PROCESS | 4599 | 10:29:19 INFO - PROCESS | 4599 | [#1] audio-beat-detection Cycles:10 Average:142.80 Median:142.00 stddev:24.07 (17.0%) stddev-sans-first:24.40 10:29:19 INFO - PROCESS | 4599 | Values: 163.0 123.0 123.0 183.0 125.0 136.0 148.0 124.0 179.0 124.0 10:29:19 INFO - PROCESS | 4599 | 10:29:19 INFO - PROCESS | 4599 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:1.37 (0.8%) stddev-sans-first:1.41 10:29:19 INFO - PROCESS | 4599 | Values: 161.0 162.0 161.0 161.0 161.0 160.0 163.0 162.0 164.0 164.0 10:29:19 INFO - PROCESS | 4599 | 10:29:19 INFO - PROCESS | 4599 | [#3] audio-fft Cycles:10 Average:84.20 Median:76.50 stddev:17.60 (23.0%) stddev-sans-first:18.35 10:29:19 INFO - PROCESS | 4599 | Values: 75.0 76.0 79.0 130.0 76.0 76.0 77.0 78.0 99.0 76.0 10:29:19 INFO - PROCESS | 4599 | 10:29:19 INFO - PROCESS | 4599 | [#4] audio-oscillator Cycles:10 Average:96.50 Median:85.50 stddev:17.31 (20.2%) stddev-sans-first:18.01 10:29:19 INFO - PROCESS | 4599 | Values: 87.0 87.0 119.0 86.0 85.0 86.0 118.0 85.0 85.0 127.0 10:29:19 INFO - PROCESS | 4599 | 10:29:19 INFO - PROCESS | 4599 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 10:29:19 INFO - PROCESS | 4599 | Values: 117.0 117.0 117.0 117.0 118.0 117.0 116.0 117.0 117.0 118.0 10:29:19 INFO - PROCESS | 4599 | 10:29:19 INFO - PROCESS | 4599 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 10:29:19 INFO - PROCESS | 4599 | Values: 148.0 147.0 146.0 147.0 147.0 146.0 146.0 147.0 148.0 147.0 10:29:19 INFO - PROCESS | 4599 | 10:29:19 INFO - PROCESS | 4599 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 10:29:19 INFO - PROCESS | 4599 | Values: 95.0 94.0 94.0 95.0 94.0 96.0 95.0 95.0 95.0 94.0 10:29:19 INFO - PROCESS | 4599 | 10:29:19 INFO - PROCESS | 4599 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:62.00 stddev:0.99 (1.6%) stddev-sans-first:1.05 10:29:19 INFO - PROCESS | 4599 | Values: 62.0 61.0 62.0 61.0 64.0 62.0 63.0 61.0 62.0 63.0 10:29:19 INFO - PROCESS | 4599 | 10:29:19 INFO - PROCESS | 4599 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 10:29:19 INFO - PROCESS | 4599 | Values: 65.0 66.0 65.0 65.0 66.0 65.0 66.0 67.0 66.0 65.0 10:29:19 INFO - PROCESS | 4599 | 10:29:19 INFO - PROCESS | 4599 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:80.00 stddev:0.70 (0.9%) stddev-sans-first:0.73 10:29:19 INFO - PROCESS | 4599 | Values: 80.0 80.0 79.0 81.0 79.0 80.0 80.0 79.0 79.0 79.0 10:29:19 INFO - PROCESS | 4599 | 10:29:19 INFO - PROCESS | 4599 | [#11] stanford-crypto-ccm Cycles:10 Average:138.70 Median:135.00 stddev:14.78 (10.9%) stddev-sans-first:15.48 10:29:19 INFO - PROCESS | 4599 | Values: 132.0 136.0 133.0 144.0 132.0 128.0 137.0 132.0 179.0 134.0 10:29:19 INFO - PROCESS | 4599 | 10:29:19 INFO - PROCESS | 4599 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.50 Median:168.00 stddev:3.95 (2.4%) stddev-sans-first:4.09 10:29:19 INFO - PROCESS | 4599 | Values: 164.0 168.0 166.0 168.0 165.0 168.0 162.0 168.0 161.0 175.0 10:29:19 INFO - PROCESS | 4599 | 10:29:19 INFO - PROCESS | 4599 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.50 stddev:1.63 (2.4%) stddev-sans-first:1.69 10:29:19 INFO - PROCESS | 4599 | Values: 66.0 69.0 69.0 68.0 69.0 66.0 66.0 65.0 67.0 65.0 10:29:19 INFO - PROCESS | 4599 | -------- Summary: end -------- 10:29:19 INFO - PROCESS | 4599 | 10:29:20 INFO - TEST-INFO | 4599: exit 0 10:29:20 INFO - TEST-OK | kraken | took 64163ms 10:29:20 INFO - SUITE-END | took 1184s 10:29:20 INFO - Completed test suite (00:19:44) 10:29:20 INFO - javascript benchmark 10:29:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2522.3820164059807, 2558.974410255897, 2566.1146904318, 2569.1650213680555, 2592.714801371849, 4582.900103975038, 4650.627949764019, 4651.837185698501, 4667.556582124693, 4669.533046695329, 4640.512746161655, 4662.90674186516, 4682.040181762739, 4692.882677933053, 4710.387649605555, 4223.831046758126, 4256.893577660552, 4290.227759003378, 4327.783610819462, 4403.4495688039, 4307.96608813884, 4356.477222733276, 4360.563943605645, 4382.0797632497215, 4389.144116897206, 4220.134872351191, 4220.894477638053, 4245.150969806051, 4268.807903644359, 4279.743215407055, 4282.250606143913, 4288.33530802723, 4290.871273861789, 4294.634956028742, 4299.871003869889, 4345.34819777036, 4346.6088052075465, 4350.58669426404, 4378.387025816356, 4378.584034516716, 4569.04050149469, 4653.88365290867, 4716.174669432869, 4757.762111894426, 4770.403699537558, 4950.3069570260195, 4974.651774375795, 4980.676256043345, 4995.175795993657, 5048.848534543969, 5009.298698182258, 5017.046761115376, 5024.598032157419, 5037.0681423936685, 5064.113780088506, 4516.751578663205, 4654.045920586272, 4671.34601155835, 4682.976585117052, 4686.3204835299375, 5137.8201762938115, 5147.022065807485, 5207.1668533034535, 5217.0, 5217.608679349064, 4935.210366341368, 4955.653104282737, 4962.875928101754, 4998.825041123543, 5005.049040682259, 4902.166631672624, 4962.950370496321, 5014.6991180529285, 5077.517635824591, 5092.032513822362, 4865.270209468608, 4914.852554423373, 4926.014797040606, 4949.059678661043, 4963.875903102379, 4893.657443978958, 4911.066897289504, 4931.7040977541465, 4935.3090567320605, 4974.676646017997, 4107.445494858216, 4183.6653067754505, 4196.223698615705, 4203.243416185055, 4235.25882970479, 3642.2715456908722, 3655.8720444784303, 3710.870119545803, 3720.6093360197333, 3771.792551409644, 4336.0, 4336.1978034063795, 4336.566343365638, 4338.869833904988, 4388.1223755249075, 5031.396232452056, 5042.042012017705, 5044.621653376012, 5045.5459008689395, 5083.085044691917, 4217.662586993033, 4255.659547236213, 4266.850660226877, 4289.6139347458875, 4298.097399546068, 4479.55204479556, 4486.080353527519, 4497.8200871964755, 4501.347304640809, 4552.430946131733, 4608.470025946992, 4619.053094115766, 4619.722816631013, 4628.051249493778, 4664.696794708333, 4904.362432883702, 4946.406431228275, 4952.925706114484, 4963.131451995814, 4995.700257984533, 4589.196890544208, 4649.372334734768, 4649.442066951852, 4664.626829853672, 4665.2535594304745, 4861.951380486292, 4864.562189402829, 4878.658493905393, 4879.52314651952, 4921.31101645777, 4871.318015477765, 4904.288878112582, 4993.275974983534, 4996.425411077773, 5000.424951130666, 4859.930815220646, 4861.635377346784, 4863.489333619919, 4863.513648635108, 4908.337374454403, 4855.635827313036, 4857.247126695368, 4859.95140048595, 4860.465348811557, 4901.534354236484, 4689.155951928686, 4740.265258884879, 4761.309610106446, 4791.47293797689, 4841.806327746992, 4877.756112194447, 4893.070316639827, 4893.21708526634, 4933.062718083553, 4946.678465899705, 4785.497522760199, 4792.0, 4793.880152996203, 4808.447028591757, 4824.831130910401], "value": 4578.337653995581, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.5199232644578, 573.7676241122344, 590.3181824992133, 591.9585629005971, 602.16299343912, 26869.462610747774, 41519.0, 42365.0, 42926.78536607313, 42978.355324670156, 38777.61222387775, 38925.805370973176, 39061.0, 39087.0, 39226.215475690544, 7543.057117860281, 7674.5395276283325, 7690.423218258625, 7693.538387696728, 7708.460407771458, 7387.224341444152, 9696.418214907093, 9919.355241909252, 9938.453385063818, 10236.692899213036, 10037.19702423808, 10062.345947513388, 10073.697789066304, 10079.29444938851, 10086.285331493627, 7346.742863999734, 9956.701298961007, 10003.249756268237, 10003.449810260432, 10029.548670309854, 9586.856197157049, 9989.800203995952, 9998.500074996222, 10319.48402579868, 10336.94831525845, 37774.62225377739, 38108.80945595282, 38130.23739525222, 38186.23627527462, 38205.70765754064, 3010.1722026442685, 3017.6077109975563, 3075.0621060576514, 3363.3950669541764, 3597.4603809428795, 28407.85796071027, 28719.28201794951, 28800.279993000135, 29109.126726198247, 29853.0, 3046.7105624965593, 3056.1137270191616, 3083.799553029068, 3183.649798522161, 3185.155933677577, 256801.0, 257308.0, 257679.0, 257718.0, 258330.9999999981, 31383.686163138533, 32268.838655806572, 32418.837905810793, 51484.485155147966, 52027.219591706154, 33592.0, 33700.32599347999, 33749.325013500085, 33750.324993500486, 33755.662443375746, 12312.0, 12425.502979880885, 12433.12968092243, 12445.0, 12460.439280232411, 9075.50084745332, 9218.078192180728, 9987.550560224661, 10033.09702126812, 10057.245706572035, 7478.9252107479315, 7537.4723769336715, 7679.577623230776, 7859.6070196489945, 7905.881411778828, 3086.506159014547, 3111.0, 3151.5745374374164, 3151.732102771244, 3161.8933373319614, 828.6312590896993, 847.635516727813, 880.9119088091139, 989.6833013435776, 1030.3302853145515, 28147.577786333222, 28169.295767605563, 28563.71436285652, 28773.28066798347, 29019.0, 921.995390023059, 935.2284365398573, 979.8579206015088, 980.6910823090703, 992.513668302541, 14774.724396868474, 14827.851721482863, 14952.925235373754, 15261.618459538378, 15295.92352038255, 374294.0, 375547.0, 378887.0, 378904.0, 379644.0, 48822.75588622105, 49377.75311123421, 49413.0, 49421.25868111981, 49465.25802112971, 1137.135776809614, 1151.5739176504746, 1190.8094704847183, 1190.9761804763857, 1201.303244118409, 26335.34161645974, 27137.0, 28114.0, 28447.857760711064, 28587.428251434438, 42547.5745242556, 42835.0, 42873.571264286955, 42923.0, 42927.356089659304, 267426.0, 267663.0, 268322.0, 268992.0, 269590.0, 22671.546569068858, 22822.201222957116, 23541.440156404326, 24832.37919052038, 25170.119045833308, 35724.0, 35781.82109089438, 36185.63814361928, 40714.59285407108, 41426.792866035474, 34123.48814767731, 34384.0, 35956.46065309075, 36216.81891590525, 36983.44524832076, 36149.63850361568, 36341.636583634885, 36401.453978190875, 36510.63489365178, 36712.26575468529, 35530.0, 35781.0, 35831.28337433289, 35862.0, 36435.45346819747, 30054.0, 31931.68068319287, 32791.0, 32858.67141328556, 32938.0, 23976.0, 24178.153764618153, 24420.877895610407, 24579.754202457745, 24764.38089047788], "value": 16444.5529764161, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.3021729910436, 361.5282056915724, 368.2892018404479, 368.9704823614111, 381.0682880357525, 13248.47006119754, 15978.440754573592, 16286.185690715462, 16807.411740589083, 17915.91042044791, 15681.451149209779, 15721.84278157218, 16230.269637866295, 16282.75575866363, 16523.50429487114, 4413.227685155101, 4997.2753950677225, 5193.402758682762, 5199.844004679847, 5247.1866860636665, 5130.692158470503, 5197.636165468419, 5200.843974680765, 5203.427622961472, 5203.713795741232, 4943.555080042795, 5078.949210507903, 5109.872253193681, 5196.558292545138, 5198.116320225552, 5062.417821950467, 5096.821611243588, 5109.20807274873, 5117.437081920985, 5119.104156772569, 4364.170807546556, 4478.0148367359125, 4510.0979804039, 4511.774411279423, 4512.616427603625, 21194.364169074954, 21201.89399052995, 21203.151873925024, 21265.0, 21485.67771483429, 1924.3841970569424, 2070.244360808309, 2085.572457646179, 2218.101668824129, 2344.2146880794953, 8705.608247628872, 9164.495952722664, 9418.340716149873, 9687.66093186735, 12886.484540618365, 1656.2712406541084, 1686.3676121454455, 1706.3089448773267, 1761.5331937036694, 1817.6092140189924, 70591.52929647437, 72311.63844180798, 72813.63593182054, 73032.26967730308, 73130.63434682846, 9961.35251208669, 10081.899181008243, 10093.646722364681, 10256.538455769509, 10257.28199026061, 13786.103903246458, 13799.723045697549, 13821.308934553232, 13831.654208644663, 13864.376103075387, 6788.626625535544, 6994.930050699428, 7070.045543851515, 7141.89287160693, 7183.138023437221, 6542.24764152119, 6561.671916404161, 6678.198616166092, 6712.295209003082, 6713.966430167818, 4115.238680844053, 4186.1418409226035, 4192.224438478891, 4299.505556861001, 4304.5910638489295, 1724.8016478105178, 1741.8519425848692, 1750.0899532243163, 1783.9018853963157, 1827.1412436154985, 795.7334293011791, 871.8517851965181, 894.3560636341823, 916.3585490156784, 960.3325688646323, 17166.39917602878, 17240.827591724174, 18301.0, 18304.908475457538, 18472.353467628836, 821.8180730161431, 825.1748251748252, 828.9980540884077, 835.3359079531665, 842.4945032980286, 9435.622575097057, 10016.098551130433, 10059.849102263472, 10183.898161018444, 10316.1747060235, 384075.0, 424196.0, 426292.0, 431478.0, 437223.0, 74868.62565687245, 76459.0, 82620.58689706514, 83084.0, 83359.0, 404.3611094470729, 425.1093958157655, 425.5255390239922, 425.72327986808824, 426.8868749781248, 9607.423554586747, 10014.64948726791, 10023.749406264902, 10106.444145572064, 10259.076683098558, 26155.476890461705, 26926.922923082857, 28909.277268068465, 35199.47200792041, 39270.4109438353, 51128.23307650314, 51802.740986294826, 51817.7409112952, 53118.46881531135, 53515.0, 7553.471257011956, 7563.621818909142, 7616.543007419573, 7732.226777322223, 7855.764327070197, 8296.045954715284, 11208.159388046091, 11986.160968732105, 12079.154459188125, 12671.619851404472, 9829.4102353859, 10188.337758045704, 10219.0, 10476.947615261875, 10545.894541054491, 10523.421211833424, 10648.307859989076, 10771.89228107709, 10774.730631733955, 10850.511726972463, 10462.63380781669, 10483.895161048293, 10493.055624993787, 10606.522706478072, 10627.309224900357, 7394.926050739639, 7515.13575938774, 7542.094948606203, 7671.769846904602, 7937.0, 10221.540030698488, 10224.53989570486, 10351.482425878827, 10458.843117353397, 10469.214808889212], "value": 7881.3601310814365, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1236.344737289237, 1388.9722205555886, 1436.8922330825187, 1470.3897882378812, 1676.6311411489476, 6784.728610855566, 7850.842983140341, 8161.91838081619, 8191.959040204805, 8418.32653387729, 8247.340212782978, 8302.045264794551, 8302.045264794551, 8322.542260175687, 8326.95836520818, 2201.7137772089645, 2229.9219527316545, 2231.3306008197555, 2232.631615783394, 2233.3188377544843, 2197.4396528885113, 2256.323103069081, 2257.9661305080435, 2260.0394832196334, 2263.2644390573046, 2193.638049721794, 2194.846360754748, 2209.9668504972437, 2210.248515504728, 2227.610168220563, 2165.5019345550613, 2189.8576592521435, 2195.045155357425, 2196.9450763730797, 2199.2522542335523, 2192.6710993350966, 2199.373178644079, 2201.768814274494, 2202.2952655150357, 2202.812760915324, 8002.279794818494, 8188.0, 8256.876146857801, 8268.131846156128, 8298.585070746438, 4536.818527258905, 4653.976730116327, 4657.534246575349, 4658.138244424758, 4668.253079507296, 2814.437112577493, 2892.638420197475, 2959.556066590007, 2960.467115919134, 2962.22982024673, 837.4723923927947, 848.4357901995165, 849.7535714642746, 856.8157846063126, 859.6991053131417, 11259.042981346596, 11349.716257093556, 11436.085113190924, 11451.026662733677, 11467.19729618927, 3936.842526298916, 4106.67146628269, 4145.108801607669, 4237.364395340724, 4252.362145678173, 8906.643734250687, 8909.599068041955, 8916.974547926942, 8929.10708929102, 8929.642814287487, 4513.729176249435, 4530.388397566352, 4540.84107056258, 4541.455025396907, 4544.272916333437, 3583.802890841029, 3968.0873399118364, 4142.378643203483, 4203.936940945889, 4219.367094935784, 3371.224618337796, 3397.303552771676, 3516.769130804198, 3536.8231588420476, 3546.0, 984.6602921991902, 991.861139440479, 992.0773680477081, 1001.2240513601871, 1005.9899401006043, 785.6621652689398, 822.461287856455, 834.4742812028384, 835.1231207232503, 846.995765021171, 5186.714730689773, 5206.7917283309, 5370.194470829407, 5389.9191512127345, 5494.340679118532, 390.4884601172473, 392.297786961336, 397.7474303817039, 399.26535175277047, 404.04846586289455, 3443.5695538057744, 3591.0, 3672.8347224374966, 3688.5389326334207, 3782.924341513154, 9930.652427165014, 10239.436830974219, 10311.742206444898, 10322.896771032343, 10326.638567650096, 10254.948725256327, 10260.640877569249, 10269.0757831794, 10271.435071071162, 10287.794244115226, 7537.359324457373, 7668.309852113337, 7702.730404435818, 7785.182555831556, 7804.102528209215, 5279.340082489689, 5365.517103460707, 5467.234587157722, 5509.3664228614225, 5546.833594992157, 9245.72262832116, 9399.10708482693, 9453.149216570542, 9462.810743784948, 9549.236061115233, 8983.730488085368, 9409.764755881159, 9435.29235307341, 9511.381760185843, 9729.270304727032, 6144.354842741626, 6830.419414349836, 6889.655517224019, 7064.187618423947, 7070.434365250665, 7752.5348479091435, 7904.802379940317, 7997.200279971957, 7998.240167184108, 8025.638846251816, 6874.346937040973, 7157.284271572801, 7180.210176880643, 7193.604351760703, 7323.487355885037, 6605.1083103780375, 6741.73033078691, 6872.725090996504, 6951.6871740770775, 6982.615956122462, 6128.540359473147, 6171.352008039091, 6174.876502470015, 6181.443670069715, 6425.646589437439, 4627.074585083064, 4666.160091183619, 4669.533046695304, 4750.78621462042, 4846.709197448165, 4499.280115181556, 4570.154521413549, 4689.507601701772, 4741.525847415231, 4756.738379389167], "value": 4096.811723538749, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.1790985241324, 297.4868352092639, 301.30098172240395, 313.28102005896466, 314.7403392201433, 4671.0, 4696.577308042271, 4698.953010469894, 4726.149293127236, 4750.239961606135, 4435.157320109177, 4513.277875539914, 4592.609628181609, 4645.419322584677, 4702.5062368451245, 3708.295423869456, 3721.9441708374397, 3729.7762134271893, 3735.7011439084945, 3759.285735710206, 3685.70514358852, 3689.1883785567134, 3709.4806727058235, 3718.2377612589353, 3721.776693398391, 3541.982290088546, 3542.946855797165, 3640.5085313482605, 3699.3341198584244, 3709.7217708671956, 3682.39240525313, 3699.85200591976, 3702.0559757261994, 3708.7589306694977, 3713.7029037677057, 3247.6102867655795, 3260.608726952757, 3265.4122257993554, 3272.7381809455187, 3277.4919887417486, 4437.933430998538, 4526.343680166357, 4586.289125185602, 4586.633069354443, 4593.977030114828, 4164.5210800757695, 4170.791460426966, 4175.164967006581, 4185.927965656989, 4205.705600607958, 4814.205656066745, 4823.734694591796, 4834.830780922686, 4847.83032593861, 4874.805007799684, 3573.1960308930543, 3583.2654305867236, 3588.102974256436, 3588.2105936693883, 3593.6226696197045, 4981.153203955337, 5008.84973450797, 5106.361704786902, 5107.514786095315, 5154.768035438415, 3609.873654422083, 3644.2529281497227, 3644.669695561154, 3653.762505437138, 3655.6527129922615, 3679.6504332088407, 3702.2595480903387, 3706.462562928414, 3711.2020915503294, 3715.1826598148364, 4073.8593193905754, 4192.706510544293, 4196.51740049892, 4199.307114326163, 4202.579742025773, 4870.902581948341, 4877.804887804519, 4894.486078961729, 4897.510248975145, 4898.68158569699, 1213.6905089202196, 1261.6593519749615, 1275.9234445933273, 1283.159530507519, 1286.1382873474795, 844.5903736687701, 869.5261082709939, 890.283321925856, 900.62624011036, 901.1304091551684, 4306.267934451151, 4332.1985432695055, 4335.241332766753, 4340.8480703175865, 4346.6088052075465, 4918.483559226302, 4958.975205124024, 4967.279744437036, 4993.0, 5015.84952451427, 4129.91740165195, 4320.2223599751715, 4332.805023773938, 4371.715838470489, 4402.823887044482, 2670.586059160833, 2673.5187666219877, 2673.639058727047, 2694.151342327161, 2694.9326266843095, 4705.929411058837, 4713.2458806590785, 4714.0807542529565, 4714.104320179155, 4744.572988431058, 5027.974860125676, 5096.796128154832, 5160.148575485003, 5196.68819870809, 5232.790688372423, 4729.101470720552, 4734.739589322551, 4737.3130896020575, 4737.620990320766, 4742.810287588527, 5074.031691460537, 5090.32757802142, 5106.770195341144, 5119.513646203604, 5133.28035108243, 4755.79556642907, 4929.408470983505, 4970.8757281068265, 4985.152524070989, 5031.094403007493, 4661.3707149534375, 4662.69444555525, 4672.34587157805, 4678.228092364721, 4680.121725139083, 3534.8409321580075, 3539.8230088494956, 3547.627499112556, 3550.3076900005285, 3573.196030893132, 4919.557239848431, 5022.801400550413, 5026.195808670595, 5048.59611231108, 5069.721165335942, 4882.218844984927, 4894.996525712149, 4901.852944411673, 4923.064617722621, 4924.162892308388, 4899.779509922133, 4919.318971160655, 4929.482404347636, 4948.1835497142565, 4956.925646115384, 4700.318453824245, 4794.664373493962, 4840.6127509798425, 4847.903041939071, 4867.975660121677, 4866.732329721899, 4874.366332376769, 4891.192953162688, 4898.632602554751, 4911.028827559275, 4809.326694262736, 4822.204336284473, 4840.523640289745, 4849.393825771778, 4851.611871050378], "value": 3716.976536196122, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.6319337220866, 585.1164741240726, 601.5548494114354, 616.9691515424229, 658.7233361987965, 12667.49330026799, 12700.936495317534, 13304.600861974144, 13311.467541298349, 13439.462421503129, 12646.810297845539, 12700.555480558181, 12845.0, 12879.871201287984, 12881.420336084875, 627.0907184582351, 736.3667246168304, 752.5860776572863, 752.9247075292482, 753.1903204055635, 747.0885519665999, 785.0932173339802, 785.5954183595453, 792.2236208515658, 795.3041089047084, 809.3323008517996, 810.0, 816.4652152839899, 817.4890693316677, 820.0077905734069, 662.2450406536552, 811.395510344791, 811.3995643224001, 813.2558708781457, 813.9633716482773, 576.2969177603318, 592.6444133519897, 593.222878029778, 593.2643522032691, 594.5689375202986, 11965.102617303737, 11968.222065565711, 12066.155369124164, 12130.090243231705, 12135.878641213563, 12234.877651223464, 12378.133530652858, 12405.317707526083, 12511.374431278491, 13113.344332783417, 10999.158278505258, 11144.888551114465, 11183.384913829817, 11298.661040168809, 11344.376059316734, 3835.6547910688173, 3917.2361389529055, 3941.625545573166, 3945.013706914936, 3991.44119823225, 58521.12218316771, 59598.40401595972, 59636.10545841816, 59766.40233597652, 60411.697941510014, 9627.951860240793, 9928.553215105338, 10005.749856253506, 10104.04011618895, 10525.578976840994, 17138.571535711708, 18388.080595970147, 18390.540236494195, 18415.631687366178, 18447.72328415075, 6475.740970361133, 6528.967355163194, 6681.465482761368, 6697.933020669731, 6766.18805743314, 3648.4709717090873, 3658.2500587379523, 3660.6156353583024, 3691.7231207659534, 3723.142773438498, 2593.494268617639, 2649.0, 2680.624712540246, 2681.597760335965, 2689.7310268972947, 480.97595120243847, 490.394362961742, 494.1845954175639, 498.8952320012838, 499.46557183813695, 439.9978000109979, 444.5754304639062, 447.9283314669668, 479.67142507382556, 489.4395916675458, 6805.523613347018, 6858.897116543256, 6880.483963702743, 7066.823329416703, 7294.051773269441, 440.5682431217361, 453.2702349217757, 454.3616219212012, 471.11900745605254, 509.5083244668912, 3227.3545290941906, 3420.196253880336, 3431.9656803432144, 3519.9120021999656, 3622.076370525499, 88572.11427885767, 96003.0, 96804.0, 98176.50911745396, 98698.50650746844, 48507.514924851006, 52610.473895260555, 78574.0, 82944.0, 84481.0, 49266.0, 72955.90566141512, 75433.0, 75900.24099759042, 76157.0, 6742.629155396304, 6784.945808096932, 6787.423069039187, 7516.0, 7674.53952762836, 29612.851935740182, 30901.381972360876, 39327.80336098301, 44261.672149835555, 47635.52364476311, 66773.66613167098, 70433.6478317605, 70828.64585677038, 71700.0, 73636.63181684057, 10140.442275674908, 10286.794264114526, 10312.742181445225, 10384.740381490523, 10815.134789216687, 10953.34279943206, 11122.666320010412, 11286.717832054264, 11389.886101138882, 12279.386030698251, 10148.391096534231, 10535.0, 10622.946885265525, 10626.52180651857, 10952.178586606196, 10548.472576371305, 10666.520006599567, 10981.17641176931, 11015.449227538751, 11178.329300242012, 10044.648437304659, 10261.538230779783, 10306.948465257627, 10344.534495947852, 10360.0, 8757.562121893752, 9006.459612423276, 9245.815083698422, 9471.905280947378, 9727.902720972983, 8419.36854735885, 8678.956605216934, 8961.0, 9117.17945384892, 9255.583498742708], "value": 6160.831796917851, "unit": "score"}], "name": "dromaeo_css", "value": 6179.344031009778, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 113.0, 113.0, 112.0, 112.0, 113.0, 112.0, 112.0, 113.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 123.0, 123.0, 183.0, 125.0, 136.0, 148.0, 124.0, 179.0, 124.0], "value": 142.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 161.0, 161.0, 160.0, 163.0, 162.0, 164.0, 164.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 79.0, 130.0, 76.0, 76.0, 77.0, 78.0, 99.0, 76.0], "value": 84.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 119.0, 86.0, 85.0, 86.0, 118.0, 85.0, 85.0, 127.0], "value": 96.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 116.0, 117.0, 117.0, 118.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 146.0, 147.0, 147.0, 146.0, 146.0, 147.0, 148.0, 147.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 94.0, 96.0, 95.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 62.0, 61.0, 64.0, 62.0, 63.0, 61.0, 62.0, 63.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 66.0, 65.0, 66.0, 67.0, 66.0, 65.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 81.0, 79.0, 80.0, 80.0, 79.0, 79.0, 79.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 136.0, 133.0, 144.0, 132.0, 128.0, 137.0, 132.0, 179.0, 134.0], "value": 138.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 168.0, 166.0, 168.0, 165.0, 168.0, 162.0, 168.0, 161.0, 175.0], "value": 166.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 69.0, 68.0, 69.0, 66.0, 66.0, 65.0, 67.0, 65.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1535.4, "alertThreshold": 2.0}]} 10:29:20 INFO - Return code: 0 10:29:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:29:20 INFO - # TBPL SUCCESS # 10:29:20 INFO - Running post-action listener: _resource_record_post_action 10:29:20 INFO - [mozharness: 2016-07-21 17:29:20.861542Z] Finished run-tests step (success) 10:29:20 INFO - Running post-run listener: _resource_record_post_run 10:29:20 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 45056; Write bytes: 301912064; Read time: 88; Write time: 279996 10:29:20 INFO - TinderboxPrint: CPU usage
13.0% 10:29:20 INFO - TinderboxPrint: I/O read bytes / time
45,056 / 88 10:29:20 INFO - TinderboxPrint: I/O write bytes / time
301,912,064 / 279,996 10:29:20 INFO - TinderboxPrint: CPU idle
8,339.3 (87.0%) 10:29:20 INFO - TinderboxPrint: CPU user
1,199.7 (12.5%) 10:29:20 INFO - TinderboxPrint: Swap in / out
0 / 0 10:29:20 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 13967360; Read time: 0; Write time: 3632 10:29:20 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 40960; Write bytes: 287944704; Read time: 68; Write time: 276364 10:29:21 INFO - Running post-run listener: _upload_blobber_files 10:29:21 INFO - Blob upload gear active. 10:29:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:29:21 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 10:29:21 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'] 10:29:21 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 10:29:21 INFO - (blobuploader) - INFO - Open directory for files ... 10:29:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:29:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:29:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:29:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:29:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:29:33 INFO - (blobuploader) - INFO - Done attempting. 10:29:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:29:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:29:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:29:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:29:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:29:34 INFO - (blobuploader) - INFO - Done attempting. 10:29:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:29:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:29:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:29:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:29:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:29:35 INFO - (blobuploader) - INFO - Done attempting. 10:29:35 INFO - (blobuploader) - INFO - Iteration through files over. 10:29:35 INFO - Return code: 0 10:29:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:29:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:29:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3bdb5c83fbf1dc3849dc5d09ff23e64670716dcf5c8f5757eac3d78d715fa0f510c2833913c255c97af1039a4ab22ef9afcf7dd207ac668a06a1218a7b356c21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/933570ab825eed434dc02f85dd389d846e028383952f3fc182f6c3a5da678d2b2c390991b5886478955c5fd54853f86d98d441ec33d96b03baa534c18c6522d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/16812cab9445acaee1c18b67ed5270b4a98ed67b4e9fc615509fb4e725f1f69e262a965b8e3f74ddd992f40ed8d8c62342b0232529a1b1e64581a65576c5261f"} 10:29:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:29:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:29:35 INFO - Contents: 10:29:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3bdb5c83fbf1dc3849dc5d09ff23e64670716dcf5c8f5757eac3d78d715fa0f510c2833913c255c97af1039a4ab22ef9afcf7dd207ac668a06a1218a7b356c21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/933570ab825eed434dc02f85dd389d846e028383952f3fc182f6c3a5da678d2b2c390991b5886478955c5fd54853f86d98d441ec33d96b03baa534c18c6522d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/16812cab9445acaee1c18b67ed5270b4a98ed67b4e9fc615509fb4e725f1f69e262a965b8e3f74ddd992f40ed8d8c62342b0232529a1b1e64581a65576c5261f"} 10:29:35 INFO - Running post-run listener: copy_logs_to_upload_dir 10:29:35 INFO - Copying logs to upload dir... 10:29:35 INFO - mkdir: /builds/slave/test/build/upload/logs 10:29:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.124209 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-21 10:29:35.475125) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 10:29:35.478835) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927 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/3bdb5c83fbf1dc3849dc5d09ff23e64670716dcf5c8f5757eac3d78d715fa0f510c2833913c255c97af1039a4ab22ef9afcf7dd207ac668a06a1218a7b356c21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/933570ab825eed434dc02f85dd389d846e028383952f3fc182f6c3a5da678d2b2c390991b5886478955c5fd54853f86d98d441ec33d96b03baa534c18c6522d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/16812cab9445acaee1c18b67ed5270b4a98ed67b4e9fc615509fb4e725f1f69e262a965b8e3f74ddd992f40ed8d8c62342b0232529a1b1e64581a65576c5261f"} build_url:https://queue.taskcluster.net/v1/task/Cxb9OOp_Roy6o66nFji2JA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011300 build_url: 'https://queue.taskcluster.net/v1/task/Cxb9OOp_Roy6o66nFji2JA/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/3bdb5c83fbf1dc3849dc5d09ff23e64670716dcf5c8f5757eac3d78d715fa0f510c2833913c255c97af1039a4ab22ef9afcf7dd207ac668a06a1218a7b356c21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/933570ab825eed434dc02f85dd389d846e028383952f3fc182f6c3a5da678d2b2c390991b5886478955c5fd54853f86d98d441ec33d96b03baa534c18c6522d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/16812cab9445acaee1c18b67ed5270b4a98ed67b4e9fc615509fb4e725f1f69e262a965b8e3f74ddd992f40ed8d8c62342b0232529a1b1e64581a65576c5261f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 10:29:35.505735) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 10:29:35.506134) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1469120920.657050-846061927 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 10:29:35.591093) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 10:29:35.591525) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 10:29:35.591945) ========= ========= Total master_lag: 0.49 =========