builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-036 starttime: 1468997509.24 results: success (0) buildid: 20160719231520 builduid: c79212d2e6d04a6bb6b140c022938d30 revision: b53dd977b6d65fb49d56a8da063d63311ef11f0a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 23:51:49.240129) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 23:51:49.240889) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 23:51:49.241370) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 23:51:49.260496) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 23:51:49.261121) ========= 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=14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-19 23:51:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.0M=0s 2016-07-19 23:51:49 (70.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.084334 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 23:51:49.362034) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 23:51:49.362816) ========= 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=14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014822 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 23:51:49.414283) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 23:51:49.414680) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b53dd977b6d65fb49d56a8da063d63311ef11f0a --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 b53dd977b6d65fb49d56a8da063d63311ef11f0a --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=14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-19 23:51:49,687 truncating revision to first 12 chars 2016-07-19 23:51:49,687 Setting DEBUG logging. 2016-07-19 23:51:49,687 attempt 1/10 2016-07-19 23:51:49,687 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b53dd977b6d6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 23:51:50,001 unpacking tar archive at: fx-team-b53dd977b6d6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.641781 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 23:51:50.070667) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 23:51:50.071027) ========= script_repo_revision: b53dd977b6d65fb49d56a8da063d63311ef11f0a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 23:51:50.071512) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 23:51:50.071811) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 23:51:50.083685) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-07-19 23:51:50.084107) ========= /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=14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:51:50 INFO - MultiFileLogger online at 20160719 23:51:50 in /builds/slave/test 23:51:50 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 23:51:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:51:50 INFO - {'append_to_log': False, 23:51:50 INFO - 'base_work_dir': '/builds/slave/test', 23:51:50 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 23:51:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:51:50 INFO - 'branch': 'Fx-Team-Non-PGO', 23:51:50 INFO - 'buildbot_json_path': 'buildprops.json', 23:51:50 INFO - 'config_files': ('talos/linux_config.py',), 23:51:50 INFO - 'default_actions': ('clobber', 23:51:50 INFO - 'read-buildbot-config', 23:51:50 INFO - 'download-and-extract', 23:51:50 INFO - 'populate-webroot', 23:51:50 INFO - 'create-virtualenv', 23:51:50 INFO - 'install', 23:51:50 INFO - 'run-tests'), 23:51:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:51:50 INFO - 'download_minidump_stackwalk': True, 23:51:50 INFO - 'download_symbols': 'ondemand', 23:51:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:51:50 INFO - 'tooltool.py': '/tools/tooltool.py', 23:51:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:51:50 INFO - '/tools/misc-python/virtualenv.py')}, 23:51:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:51:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:51:50 INFO - 'installer_path': 'installer.exe', 23:51:50 INFO - 'log_level': 'info', 23:51:50 INFO - 'log_name': 'talos', 23:51:50 INFO - 'log_to_console': True, 23:51:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:51:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:51:50 INFO - 'opt_config_files': (), 23:51:50 INFO - 'pip_index': False, 23:51:50 INFO - 'sps_profile': False, 23:51:50 INFO - 'sps_profile_interval': 0, 23:51:50 INFO - 'suite': 'dromaeojs', 23:51:50 INFO - 'system_bits': '32', 23:51:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:51:50 INFO - 'title': 'talos-linux64-ix-036', 23:51:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:51:50 INFO - 'use_talos_json': True, 23:51:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:51:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:51:50 INFO - 'work_dir': 'build'} 23:51:50 INFO - ##### 23:51:50 INFO - ##### Running clobber step. 23:51:50 INFO - ##### 23:51:50 INFO - Running pre-action listener: _resource_record_pre_action 23:51:50 INFO - Running main action method: clobber 23:51:50 INFO - rmtree: /builds/slave/test/build 23:51:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:51:50 INFO - Running post-action listener: _resource_record_post_action 23:51:50 INFO - ##### 23:51:50 INFO - ##### Finished clobber step (success) 23:51:50 INFO - ##### 23:51:50 INFO - ##### 23:51:50 INFO - ##### Running read-buildbot-config step. 23:51:50 INFO - ##### 23:51:50 INFO - Running pre-action listener: _resource_record_pre_action 23:51:50 INFO - Running main action method: read_buildbot_config 23:51:50 INFO - Using buildbot properties: 23:51:50 INFO - { 23:51:50 INFO - "project": "", 23:51:50 INFO - "product": "firefox", 23:51:50 INFO - "script_repo_revision": "production", 23:51:50 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:51:50 INFO - "repository": "", 23:51:50 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 23:51:50 INFO - "buildid": "20160719231520", 23:51:50 INFO - "pgo_build": "False", 23:51:50 INFO - "basedir": "/builds/slave/test", 23:51:50 INFO - "buildnumber": 2003, 23:51:50 INFO - "slavename": "talos-linux64-ix-036", 23:51:50 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 23:51:50 INFO - "platform": "ubuntu64_hw", 23:51:50 INFO - "scheduler": "tests-fx-team-linux64-talos", 23:51:50 INFO - "branch": "fx-team", 23:51:50 INFO - "repo_path": "integration/fx-team", 23:51:50 INFO - "revision": "b53dd977b6d65fb49d56a8da063d63311ef11f0a", 23:51:50 INFO - "stage_platform": "linux64", 23:51:50 INFO - "builduid": "c79212d2e6d04a6bb6b140c022938d30", 23:51:50 INFO - "slavebuilddir": "test" 23:51:50 INFO - } 23:51:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 23:51:50 INFO - Running post-action listener: _resource_record_post_action 23:51:50 INFO - ##### 23:51:50 INFO - ##### Finished read-buildbot-config step (success) 23:51:50 INFO - ##### 23:51:50 INFO - ##### 23:51:50 INFO - ##### Running download-and-extract step. 23:51:50 INFO - ##### 23:51:50 INFO - Running pre-action listener: _resource_record_pre_action 23:51:50 INFO - Running main action method: download_and_extract 23:51:50 INFO - mkdir: /builds/slave/test/build/tests 23:51:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:51:50 INFO - https://queue.taskcluster.net/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:51:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:51:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:51:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/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 23:51:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/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 23:51:51 INFO - Downloaded 1361 bytes. 23:51:51 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:51:51 INFO - Using the following test package requirements: 23:51:51 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:51:51 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:51:51 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:51:51 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:51:51 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:51:51 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:51:51 INFO - u'jsshell-linux-x86_64.zip'], 23:51:51 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:51:51 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:51:51 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:51:51 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:51:51 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:51:51 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:51:51 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:51:51 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:51:51 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:51:51 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:51:51 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:51:51 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:51: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')]} 23:51:51 INFO - https://queue.taskcluster.net/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:51:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:51:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:51:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/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 23:51:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/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 23:51:52 INFO - Downloaded 22763363 bytes. 23:51:52 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'] 23:51:52 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 23:51:53 INFO - Return code: 0 23:51:53 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 23:51:53 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')]} 23:51:53 INFO - https://queue.taskcluster.net/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:51:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:51:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:51:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/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 23:51:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/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 23:51:54 INFO - Downloaded 22763363 bytes. 23:51:54 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'] 23:51:54 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 23:51:56 INFO - Return code: 0 23:51:56 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')]} 23:51:56 INFO - https://queue.taskcluster.net/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:51:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:51:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:51:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/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 23:51:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/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 23:51:58 INFO - Downloaded 14403317 bytes. 23:51:58 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'] 23:51:58 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 23:51:58 INFO - Return code: 0 23:51:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:51:58 INFO - https://queue.taskcluster.net/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:51:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:51:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:51:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/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 23:51:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/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 23:51:59 INFO - Downloaded 57125784 bytes. 23:51:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:51:59 INFO - mkdir: /builds/slave/test/properties 23:51:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:51:59 INFO - Writing to file /builds/slave/test/properties/build_url 23:51:59 INFO - Contents: 23:51:59 INFO - build_url:https://queue.taskcluster.net/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:52:00 INFO - Running post-action listener: _resource_record_post_action 23:52:00 INFO - Running post-action listener: set_extra_try_arguments 23:52:00 INFO - ##### 23:52:00 INFO - ##### Finished download-and-extract step (success) 23:52:00 INFO - ##### 23:52:00 INFO - ##### 23:52:00 INFO - ##### Running populate-webroot step. 23:52:00 INFO - ##### 23:52:00 INFO - Running pre-action listener: _resource_record_pre_action 23:52:00 INFO - Running main action method: populate_webroot 23:52:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:52:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:52:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:52:00 INFO - '--tppagecycles', 23:52:00 INFO - '7'], 23:52:00 INFO - 'tests': ['tsvgm']}}, 23:52:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:52:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:52:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:52:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:52:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:52:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:52:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:52:00 INFO - 'talos_options': ['--disable-e10s'], 23:52:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:52:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:52:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:52:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:52:00 INFO - 'talos_options': ['--disable-e10s'], 23:52:00 INFO - 'tests': ['damp', 'tps']}, 23:52:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:52:00 INFO - 'tests': ['damp', 'tps']}, 23:52:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:52:00 INFO - 'tests': ['dromaeo_dom']}, 23:52:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:52:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:52:00 INFO - 'tests': ['basic_compositor_video']}, 23:52:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:52:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:52:00 INFO - 'tests': ['a11yr', 23:52:00 INFO - 'ts_paint', 23:52:00 INFO - 'tpaint', 23:52:00 INFO - 'sessionrestore', 23:52:00 INFO - 'sessionrestore_no_auto_restore', 23:52:00 INFO - 'tabpaint']}, 23:52:00 INFO - 'other-e10s': {'tests': ['a11yr', 23:52:00 INFO - 'ts_paint', 23:52:00 INFO - 'tpaint', 23:52:00 INFO - 'sessionrestore', 23:52:00 INFO - 'sessionrestore_no_auto_restore', 23:52:00 INFO - 'tabpaint']}, 23:52:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:52:00 INFO - 'ts_paint', 23:52:00 INFO - 'tpaint', 23:52:00 INFO - 'sessionrestore', 23:52:00 INFO - 'sessionrestore_no_auto_restore', 23:52:00 INFO - 'tabpaint']}, 23:52:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:52:00 INFO - 'ts_paint', 23:52:00 INFO - 'tpaint', 23:52:00 INFO - 'sessionrestore', 23:52:00 INFO - 'sessionrestore_no_auto_restore', 23:52:00 INFO - 'tabpaint']}, 23:52:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:52:00 INFO - 'tests': ['a11yr', 23:52:00 INFO - 'ts_paint', 23:52:00 INFO - 'tpaint', 23:52:00 INFO - 'sessionrestore', 23:52:00 INFO - 'sessionrestore_no_auto_restore', 23:52:00 INFO - 'tabpaint']}, 23:52:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:52:00 INFO - 'tests': ['a11yr', 23:52:00 INFO - 'ts_paint', 23:52:00 INFO - 'tpaint', 23:52:00 INFO - 'sessionrestore', 23:52:00 INFO - 'sessionrestore_no_auto_restore', 23:52:00 INFO - 'tabpaint']}, 23:52:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:52:00 INFO - 'tests': ['tsvgx', 23:52:00 INFO - 'tsvgr_opacity', 23:52:00 INFO - 'tart', 23:52:00 INFO - 'tscrollx', 23:52:00 INFO - 'cart']}, 23:52:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:52:00 INFO - 'tsvgr_opacity', 23:52:00 INFO - 'tart', 23:52:00 INFO - 'tscrollx', 23:52:00 INFO - 'cart']}, 23:52:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:52:00 INFO - 'talos_options': ['--disable-e10s'], 23:52:00 INFO - 'tests': ['tp5o']}, 23:52:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:52:00 INFO - 'tests': ['tp5o']}, 23:52:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:52:00 INFO - 'talos_options': ['--disable-e10s', 23:52:00 INFO - '--xperf_path', 23:52:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:52:00 INFO - 'tests': ['tp5n']}, 23:52:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:52:00 INFO - 'talos_options': ['--xperf_path', 23:52:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:52:00 INFO - 'tests': ['tp5n']}}, 23:52:00 INFO - 'talos.zip': {'path': '', 23:52:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:52:00 INFO - Running post-action listener: _resource_record_post_action 23:52:00 INFO - ##### 23:52:00 INFO - ##### Finished populate-webroot step (success) 23:52:00 INFO - ##### 23:52:00 INFO - ##### 23:52:00 INFO - ##### Running create-virtualenv step. 23:52:00 INFO - ##### 23:52:00 INFO - Running pre-action listener: _resource_record_pre_action 23:52:00 INFO - Running main action method: create_virtualenv 23:52:00 INFO - Creating virtualenv /builds/slave/test/build/venv 23:52:00 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 23:52:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:52:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:52:00 INFO - Using real prefix '/usr' 23:52:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:52:01 INFO - Installing distribute.............................................................................................................................................................................................done. 23:52:03 INFO - Installing pip.................done. 23:52:03 INFO - Return code: 0 23:52:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:52:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:52:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:52:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52:03 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 0x1e88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21473b0>, '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 0x21482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2148740>, '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': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', '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 23:52:03 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 23:52:03 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 23:52:03 INFO - Using env: {'DISPLAY': ':0', 23:52:03 INFO - 'HOME': '/home/cltbld', 23:52:03 INFO - 'LANG': 'en_US.UTF-8', 23:52:03 INFO - 'LANGUAGE': 'en_US:en', 23:52:03 INFO - 'LOGNAME': 'cltbld', 23:52:03 INFO - 'MAIL': '/var/mail/cltbld', 23:52:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:52:03 INFO - 'MOZ_NO_REMOTE': '1', 23:52:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:52:03 INFO - 'NO_EM_RESTART': '1', 23:52:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:52:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:52:03 INFO - 'PWD': '/builds/slave/test', 23:52:03 INFO - 'SHELL': '/bin/bash', 23:52:03 INFO - 'SHLVL': '1', 23:52:03 INFO - 'TERM': 'linux', 23:52:03 INFO - 'TMOUT': '86400', 23:52:03 INFO - 'USER': 'cltbld', 23:52:03 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', 23:52:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:52:03 INFO - '_': '/tools/buildbot/bin/python'} 23:52:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:52:03 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 23:52:03 INFO - Cleaning up... 23:52:03 INFO - Return code: 0 23:52:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:52:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:52:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:52:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52:03 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 0x1e88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21473b0>, '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 0x21482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2148740>, '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': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', '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 23:52:03 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 23:52:03 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 23:52:03 INFO - Using env: {'DISPLAY': ':0', 23:52:03 INFO - 'HOME': '/home/cltbld', 23:52:03 INFO - 'LANG': 'en_US.UTF-8', 23:52:03 INFO - 'LANGUAGE': 'en_US:en', 23:52:03 INFO - 'LOGNAME': 'cltbld', 23:52:03 INFO - 'MAIL': '/var/mail/cltbld', 23:52:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:52:03 INFO - 'MOZ_NO_REMOTE': '1', 23:52:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:52:03 INFO - 'NO_EM_RESTART': '1', 23:52:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:52:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:52:03 INFO - 'PWD': '/builds/slave/test', 23:52:03 INFO - 'SHELL': '/bin/bash', 23:52:03 INFO - 'SHLVL': '1', 23:52:03 INFO - 'TERM': 'linux', 23:52:03 INFO - 'TMOUT': '86400', 23:52:03 INFO - 'USER': 'cltbld', 23:52:03 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', 23:52:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:52:03 INFO - '_': '/tools/buildbot/bin/python'} 23:52:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:52:03 INFO - Downloading/unpacking psutil>=3.1.1 23:52:03 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 23:52:03 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 23:52:03 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 23:52:03 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 23:52:03 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 23:52:03 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 23:52:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:52:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:52:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:52:05 INFO - Installing collected packages: psutil 23:52:05 INFO - Running setup.py install for psutil 23:52:05 INFO - building 'psutil._psutil_linux' extension 23:52:05 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 23:52:05 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 23:52:05 INFO - building 'psutil._psutil_posix' extension 23:52:05 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 23:52:06 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 23:52:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:52:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:52:06 INFO - Successfully installed psutil 23:52:06 INFO - Cleaning up... 23:52:06 INFO - Return code: 0 23:52:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:52:06 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')]} 23:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:52:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52:06 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')]} 23:52:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:52:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52:06 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 0x1e88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21473b0>, '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 0x21482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2148740>, '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': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', '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 23:52:06 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 23:52:06 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 23:52:06 INFO - Using env: {'DISPLAY': ':0', 23:52:06 INFO - 'HOME': '/home/cltbld', 23:52:06 INFO - 'LANG': 'en_US.UTF-8', 23:52:06 INFO - 'LANGUAGE': 'en_US:en', 23:52:06 INFO - 'LOGNAME': 'cltbld', 23:52:06 INFO - 'MAIL': '/var/mail/cltbld', 23:52:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:52:06 INFO - 'MOZ_NO_REMOTE': '1', 23:52:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:52:06 INFO - 'NO_EM_RESTART': '1', 23:52:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:52:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:52:06 INFO - 'PWD': '/builds/slave/test', 23:52:06 INFO - 'SHELL': '/bin/bash', 23:52:06 INFO - 'SHLVL': '1', 23:52:06 INFO - 'TERM': 'linux', 23:52:06 INFO - 'TMOUT': '86400', 23:52:06 INFO - 'USER': 'cltbld', 23:52:06 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', 23:52:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:52:06 INFO - '_': '/tools/buildbot/bin/python'} 23:52:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:52:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:52:06 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 23:52:06 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 23:52:06 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 23:52:06 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 23:52:06 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 23:52:06 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 23:52:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:52:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:52:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:52:08 INFO - Installing collected packages: mozsystemmonitor 23:52:08 INFO - Running setup.py install for mozsystemmonitor 23:52:08 INFO - Successfully installed mozsystemmonitor 23:52:08 INFO - Cleaning up... 23:52:08 INFO - Return code: 0 23:52:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:52:08 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')]} 23:52:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:52:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52:08 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')]} 23:52:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:52:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52:08 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 0x1e88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21473b0>, '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 0x21482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2148740>, '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': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', '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 23:52:08 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 23:52:08 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 23:52:08 INFO - Using env: {'DISPLAY': ':0', 23:52:08 INFO - 'HOME': '/home/cltbld', 23:52:08 INFO - 'LANG': 'en_US.UTF-8', 23:52:08 INFO - 'LANGUAGE': 'en_US:en', 23:52:08 INFO - 'LOGNAME': 'cltbld', 23:52:08 INFO - 'MAIL': '/var/mail/cltbld', 23:52:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:52:08 INFO - 'MOZ_NO_REMOTE': '1', 23:52:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:52:08 INFO - 'NO_EM_RESTART': '1', 23:52:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:52:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:52:08 INFO - 'PWD': '/builds/slave/test', 23:52:08 INFO - 'SHELL': '/bin/bash', 23:52:08 INFO - 'SHLVL': '1', 23:52:08 INFO - 'TERM': 'linux', 23:52:08 INFO - 'TMOUT': '86400', 23:52:08 INFO - 'USER': 'cltbld', 23:52:08 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', 23:52:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:52:08 INFO - '_': '/tools/buildbot/bin/python'} 23:52:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:52:08 INFO - Downloading/unpacking jsonschema==2.5.1 23:52: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 23:52: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 23:52: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 23:52: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 23:52: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 23:52: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 23:52:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:52:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:52: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 23:52: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 23:52: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 23:52: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 23:52: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 23:52: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 23:52:10 INFO - Downloading functools32-3.2.3-2.tar.gz 23:52:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:52:10 INFO - warning: no files found matching '*.txt' 23:52:10 INFO - no previously-included directories found matching 'build' 23:52:10 INFO - no previously-included directories found matching 'dist' 23:52:10 INFO - no previously-included directories found matching '.git*' 23:52:10 INFO - Installing collected packages: jsonschema, functools32 23:52:10 INFO - Running setup.py install for functools32 23:52:11 INFO - warning: no files found matching '*.txt' 23:52:11 INFO - no previously-included directories found matching 'build' 23:52:11 INFO - no previously-included directories found matching 'dist' 23:52:11 INFO - no previously-included directories found matching '.git*' 23:52:11 INFO - Successfully installed jsonschema functools32 23:52:11 INFO - Cleaning up... 23:52:11 INFO - Return code: 0 23:52:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:52: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')]} 23:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:52:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52: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')]} 23:52:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:52:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52:11 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 0x1e88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21473b0>, '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 0x21482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2148740>, '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': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', '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 23:52:11 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 23:52:11 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 23:52:11 INFO - Using env: {'DISPLAY': ':0', 23:52:11 INFO - 'HOME': '/home/cltbld', 23:52:11 INFO - 'LANG': 'en_US.UTF-8', 23:52:11 INFO - 'LANGUAGE': 'en_US:en', 23:52:11 INFO - 'LOGNAME': 'cltbld', 23:52:11 INFO - 'MAIL': '/var/mail/cltbld', 23:52:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:52:11 INFO - 'MOZ_NO_REMOTE': '1', 23:52:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:52:11 INFO - 'NO_EM_RESTART': '1', 23:52:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:52:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:52:11 INFO - 'PWD': '/builds/slave/test', 23:52:11 INFO - 'SHELL': '/bin/bash', 23:52:11 INFO - 'SHLVL': '1', 23:52:11 INFO - 'TERM': 'linux', 23:52:11 INFO - 'TMOUT': '86400', 23:52:11 INFO - 'USER': 'cltbld', 23:52:11 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', 23:52:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:52:11 INFO - '_': '/tools/buildbot/bin/python'} 23:52:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:52:11 INFO - Downloading/unpacking blobuploader==1.2.4 23:52:11 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 23:52:11 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 23:52:11 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 23:52:11 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 23:52:11 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 23:52:11 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 23:52:13 INFO - Downloading blobuploader-1.2.4.tar.gz 23:52:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:52:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:52:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:52:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:52:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:52:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:52:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:52:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:52:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:52:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:52:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:52:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:52:13 INFO - Downloading docopt-0.6.1.tar.gz 23:52:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:52:13 INFO - Installing collected packages: blobuploader, requests, docopt 23:52:13 INFO - Running setup.py install for blobuploader 23:52:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:52:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:52:14 INFO - Running setup.py install for requests 23:52:14 INFO - Running setup.py install for docopt 23:52:14 INFO - Successfully installed blobuploader requests docopt 23:52:14 INFO - Cleaning up... 23:52:14 INFO - Return code: 0 23:52:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:52: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')]} 23:52:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:52:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52: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')]} 23:52:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:52:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52:14 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 0x1e88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21473b0>, '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 0x21482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2148740>, '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': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', '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 23:52:14 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 23:52:14 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 23:52:14 INFO - Using env: {'DISPLAY': ':0', 23:52:14 INFO - 'HOME': '/home/cltbld', 23:52:14 INFO - 'LANG': 'en_US.UTF-8', 23:52:14 INFO - 'LANGUAGE': 'en_US:en', 23:52:14 INFO - 'LOGNAME': 'cltbld', 23:52:14 INFO - 'MAIL': '/var/mail/cltbld', 23:52:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:52:14 INFO - 'MOZ_NO_REMOTE': '1', 23:52:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:52:14 INFO - 'NO_EM_RESTART': '1', 23:52:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:52:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:52:14 INFO - 'PWD': '/builds/slave/test', 23:52:14 INFO - 'SHELL': '/bin/bash', 23:52:14 INFO - 'SHLVL': '1', 23:52:14 INFO - 'TERM': 'linux', 23:52:14 INFO - 'TMOUT': '86400', 23:52:14 INFO - 'USER': 'cltbld', 23:52:14 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', 23:52:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:52:14 INFO - '_': '/tools/buildbot/bin/python'} 23:52:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:52:14 INFO - Running setup.py (path:/tmp/pip-31slGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:52:14 INFO - Running setup.py (path:/tmp/pip-dTP_Dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:52:14 INFO - Running setup.py (path:/tmp/pip-7jls2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:52:15 INFO - Running setup.py (path:/tmp/pip-StiOt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:52:15 INFO - Running setup.py (path:/tmp/pip-RvF1wM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:52:15 INFO - Running setup.py (path:/tmp/pip-Qj1eRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:52:15 INFO - Running setup.py (path:/tmp/pip-1kHRC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:52:15 INFO - Running setup.py (path:/tmp/pip-JEq6kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:52:15 INFO - Running setup.py (path:/tmp/pip-OrSKuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:52:15 INFO - Running setup.py (path:/tmp/pip-mOQNKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:52:15 INFO - Running setup.py (path:/tmp/pip-uTkQ9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:52:15 INFO - Running setup.py (path:/tmp/pip-IobZme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:52:15 INFO - Running setup.py (path:/tmp/pip-dSKbOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:52:15 INFO - Running setup.py (path:/tmp/pip-CObvZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:52:15 INFO - Running setup.py (path:/tmp/pip-dE9ynu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:52:15 INFO - Running setup.py (path:/tmp/pip-GvwOg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:52:15 INFO - Running setup.py (path:/tmp/pip-McxkJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:52:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:52:16 INFO - Running setup.py install for manifestparser 23:52:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:52:16 INFO - Running setup.py install for mozcrash 23:52:16 INFO - Running setup.py install for mozdebug 23:52:16 INFO - Running setup.py install for mozdevice 23:52:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:52:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:52:16 INFO - Running setup.py install for mozfile 23:52:16 INFO - Running setup.py install for mozhttpd 23:52:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:52:16 INFO - Running setup.py install for mozinfo 23:52:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:52:16 INFO - Running setup.py install for mozInstall 23:52:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:52:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:52:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:52:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:52:16 INFO - Running setup.py install for mozleak 23:52:16 INFO - Running setup.py install for mozlog 23:52:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:52:17 INFO - Running setup.py install for moznetwork 23:52:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:52:17 INFO - Running setup.py install for mozprocess 23:52:17 INFO - Running setup.py install for mozprofile 23:52:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:52:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:52:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:52:17 INFO - Running setup.py install for mozrunner 23:52:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:52:17 INFO - Running setup.py install for mozscreenshot 23:52:17 INFO - Running setup.py install for moztest 23:52:17 INFO - Running setup.py install for mozversion 23:52:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:52:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:52:17 INFO - Cleaning up... 23:52:17 INFO - Return code: 0 23:52:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:52:17 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')]} 23:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:52:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52:17 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')]} 23:52:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:52:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52:17 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 0x1e88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21473b0>, '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 0x21482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2148740>, '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': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', '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 23:52:17 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 23:52:17 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 23:52:17 INFO - Using env: {'DISPLAY': ':0', 23:52:17 INFO - 'HOME': '/home/cltbld', 23:52:17 INFO - 'LANG': 'en_US.UTF-8', 23:52:17 INFO - 'LANGUAGE': 'en_US:en', 23:52:17 INFO - 'LOGNAME': 'cltbld', 23:52:17 INFO - 'MAIL': '/var/mail/cltbld', 23:52:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:52:17 INFO - 'MOZ_NO_REMOTE': '1', 23:52:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:52:17 INFO - 'NO_EM_RESTART': '1', 23:52:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:52:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:52:17 INFO - 'PWD': '/builds/slave/test', 23:52:17 INFO - 'SHELL': '/bin/bash', 23:52:17 INFO - 'SHLVL': '1', 23:52:17 INFO - 'TERM': 'linux', 23:52:17 INFO - 'TMOUT': '86400', 23:52:17 INFO - 'USER': 'cltbld', 23:52:17 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', 23:52:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:52:17 INFO - '_': '/tools/buildbot/bin/python'} 23:52:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:52:18 INFO - Running setup.py (path:/tmp/pip-apyx_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:52:18 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)) 23:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:52:18 INFO - Running setup.py (path:/tmp/pip-d9e5fC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:52:18 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)) 23:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:52:18 INFO - Running setup.py (path:/tmp/pip-QeQijw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:52:18 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)) 23:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:52:18 INFO - Running setup.py (path:/tmp/pip-uhkPCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:52:18 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)) 23:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:52:18 INFO - Running setup.py (path:/tmp/pip-KlCXUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:52:18 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)) 23:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:52:18 INFO - Running setup.py (path:/tmp/pip-bWpYPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:52:18 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)) 23:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:52:18 INFO - Running setup.py (path:/tmp/pip-9H37MG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:52:18 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)) 23:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:52:18 INFO - Running setup.py (path:/tmp/pip-MS4vI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:52:18 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)) 23:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:52:18 INFO - Running setup.py (path:/tmp/pip-2g_bJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:52:18 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)) 23:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:52:18 INFO - Running setup.py (path:/tmp/pip-qze7yl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:52:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:52:18 INFO - Running setup.py (path:/tmp/pip-1VotVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:52:18 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)) 23:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:52:18 INFO - Running setup.py (path:/tmp/pip-J5eiDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:52:18 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)) 23:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:52:18 INFO - Running setup.py (path:/tmp/pip-qCAOdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:52:18 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)) 23:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:52:18 INFO - Running setup.py (path:/tmp/pip-vwvW54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:52:19 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)) 23:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:52:19 INFO - Running setup.py (path:/tmp/pip-6gEl0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:52:19 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)) 23:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:52:19 INFO - Running setup.py (path:/tmp/pip-ZxQ2Yh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:52:19 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)) 23:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:52:19 INFO - Running setup.py (path:/tmp/pip-bLyzI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:52:19 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)) 23:52:19 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)) 23:52:19 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)) 23:52:19 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)) 23:52:19 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)) 23:52:19 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)) 23:52:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:52:19 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 23:52:19 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 23:52:19 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 23:52:19 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 23:52:19 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 23:52:19 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 23:52:21 INFO - Downloading blessings-1.6.tar.gz 23:52:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:52:21 INFO - Installing collected packages: blessings 23:52:21 INFO - Running setup.py install for blessings 23:52:21 INFO - Successfully installed blessings 23:52:21 INFO - Cleaning up... 23:52:21 INFO - Return code: 0 23:52:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:52:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:52:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:52:21 INFO - Reading from file tmpfile_stdout 23:52:21 INFO - Current package versions: 23:52:21 INFO - argparse == 1.2.1 23:52:21 INFO - blessings == 1.6 23:52:21 INFO - blobuploader == 1.2.4 23:52:21 INFO - docopt == 0.6.1 23:52:21 INFO - functools32 == 3.2.3-2 23:52:21 INFO - jsonschema == 2.5.1 23:52:21 INFO - manifestparser == 1.1 23:52:21 INFO - mozInstall == 1.12 23:52:21 INFO - mozcrash == 0.17 23:52:21 INFO - mozdebug == 0.1 23:52:21 INFO - mozdevice == 0.48 23:52:21 INFO - mozfile == 1.2 23:52:21 INFO - mozhttpd == 0.7 23:52:21 INFO - mozinfo == 0.9 23:52:21 INFO - mozleak == 0.1 23:52:21 INFO - mozlog == 3.2 23:52:21 INFO - moznetwork == 0.27 23:52:21 INFO - mozprocess == 0.23 23:52:21 INFO - mozprofile == 0.28 23:52:21 INFO - mozrunner == 6.12 23:52:21 INFO - mozscreenshot == 0.1 23:52:21 INFO - mozsystemmonitor == 0.3 23:52:21 INFO - moztest == 0.7 23:52:21 INFO - mozversion == 1.4 23:52:21 INFO - psutil == 3.1.1 23:52:21 INFO - requests == 1.2.3 23:52:21 INFO - wsgiref == 0.1.2 23:52:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:52:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:52:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:52:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:52:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:52:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52:21 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 0x1e88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21473b0>, '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 0x21482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2148740>, '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': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', '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 23:52:21 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 23:52:21 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 23:52:21 INFO - Using env: {'DISPLAY': ':0', 23:52:21 INFO - 'HOME': '/home/cltbld', 23:52:21 INFO - 'LANG': 'en_US.UTF-8', 23:52:21 INFO - 'LANGUAGE': 'en_US:en', 23:52:21 INFO - 'LOGNAME': 'cltbld', 23:52:21 INFO - 'MAIL': '/var/mail/cltbld', 23:52:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:52:21 INFO - 'MOZ_NO_REMOTE': '1', 23:52:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:52:21 INFO - 'NO_EM_RESTART': '1', 23:52:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:52:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:52:21 INFO - 'PWD': '/builds/slave/test', 23:52:21 INFO - 'SHELL': '/bin/bash', 23:52:21 INFO - 'SHLVL': '1', 23:52:21 INFO - 'TERM': 'linux', 23:52:21 INFO - 'TMOUT': '86400', 23:52:21 INFO - 'USER': 'cltbld', 23:52:21 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', 23:52:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:52:21 INFO - '_': '/tools/buildbot/bin/python'} 23:52:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:52:21 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)) 23:52:21 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)) 23:52:21 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)) 23:52:21 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)) 23:52:21 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)) 23:52:21 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)) 23:52:21 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)) 23:52:21 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)) 23:52:21 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)) 23:52:21 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)) 23:52:21 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)) 23:52:21 INFO - Cleaning up... 23:52:21 INFO - Return code: 0 23:52:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:52:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:52:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:52:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:52:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:52:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:52:21 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 0x1e88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2149180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21473b0>, '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 0x21482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2148740>, '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': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', '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 23:52:21 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 23:52:21 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 23:52:21 INFO - Using env: {'DISPLAY': ':0', 23:52:21 INFO - 'HOME': '/home/cltbld', 23:52:21 INFO - 'LANG': 'en_US.UTF-8', 23:52:21 INFO - 'LANGUAGE': 'en_US:en', 23:52:21 INFO - 'LOGNAME': 'cltbld', 23:52:21 INFO - 'MAIL': '/var/mail/cltbld', 23:52:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:52:21 INFO - 'MOZ_NO_REMOTE': '1', 23:52:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:52:21 INFO - 'NO_EM_RESTART': '1', 23:52:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:52:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:52:21 INFO - 'PWD': '/builds/slave/test', 23:52:21 INFO - 'SHELL': '/bin/bash', 23:52:21 INFO - 'SHLVL': '1', 23:52:21 INFO - 'TERM': 'linux', 23:52:21 INFO - 'TMOUT': '86400', 23:52:21 INFO - 'USER': 'cltbld', 23:52:21 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', 23:52:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:52:21 INFO - '_': '/tools/buildbot/bin/python'} 23:52:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:52:22 INFO - Cleaning up... 23:52:22 INFO - Return code: 0 23:52:22 INFO - Running post-action listener: _resource_record_post_action 23:52:22 INFO - Running post-action listener: _start_resource_monitoring 23:52:22 INFO - Starting resource monitoring. 23:52:22 INFO - ##### 23:52:22 INFO - ##### Finished create-virtualenv step (success) 23:52:22 INFO - ##### 23:52:22 INFO - ##### 23:52:22 INFO - ##### Running install step. 23:52:22 INFO - ##### 23:52:22 INFO - Running pre-action listener: _resource_record_pre_action 23:52:22 INFO - Running main action method: install 23:52:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:52:22 INFO - Reading from file tmpfile_stdout 23:52:22 INFO - Detecting whether we're running mozinstall >=1.0... 23:52:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:52:22 INFO - Reading from file tmpfile_stdout 23:52:22 INFO - Output received: 23:52:22 INFO - Usage: mozinstall [options] installer 23:52:22 INFO - Options: 23:52:22 INFO - -h, --help show this help message and exit 23:52:22 INFO - -d DEST, --destination=DEST 23:52:22 INFO - Directory to install application into. [default: 23:52:22 INFO - "/builds/slave/test"] 23:52:22 INFO - --app=APP Application being installed. [default: firefox] 23:52:22 INFO - mkdir: /builds/slave/test/build/application 23:52:22 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'] 23:52:22 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 23:52:38 INFO - Reading from file tmpfile_stdout 23:52:38 INFO - Output received: 23:52:38 INFO - /builds/slave/test/build/application/firefox/firefox 23:52:38 INFO - Running post-action listener: _resource_record_post_action 23:52:38 INFO - ##### 23:52:38 INFO - ##### Finished install step (success) 23:52:38 INFO - ##### 23:52:38 INFO - ##### 23:52:38 INFO - ##### Running run-tests step. 23:52:38 INFO - ##### 23:52:38 INFO - Running pre-action listener: _resource_record_pre_action 23:52:38 INFO - Running main action method: run_tests 23:52:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:52:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:52:38 INFO - Python 2.7.3 23:52:38 INFO - Return code: 0 23:52:38 INFO - grabbing minidump binary from tooltool 23:52:38 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')]} 23:52:38 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 0x21473b0>, '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 0x21482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2148740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:52:38 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 23:52:38 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 23:52:38 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 23:52:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:52:38 INFO - Return code: 0 23:52:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:52:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:52:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:52:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:52:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:52:38 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/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 23:52:38 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/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --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 23:52:38 INFO - Using env: {'DISPLAY': ':0', 23:52:38 INFO - 'HOME': '/home/cltbld', 23:52:38 INFO - 'LANG': 'en_US.UTF-8', 23:52:38 INFO - 'LANGUAGE': 'en_US:en', 23:52:38 INFO - 'LOGNAME': 'cltbld', 23:52:38 INFO - 'MAIL': '/var/mail/cltbld', 23:52:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:52:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:52:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:52:38 INFO - 'MOZ_NO_REMOTE': '1', 23:52:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:52:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:52:38 INFO - 'NO_EM_RESTART': '1', 23:52:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:52:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:52:38 INFO - 'PWD': '/builds/slave/test', 23:52:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:52:38 INFO - 'SHELL': '/bin/bash', 23:52:38 INFO - 'SHLVL': '1', 23:52:38 INFO - 'TERM': 'linux', 23:52:38 INFO - 'TMOUT': '86400', 23:52:38 INFO - 'USER': 'cltbld', 23:52:38 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501', 23:52:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:52:38 INFO - '_': '/tools/buildbot/bin/python'} 23:52:38 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/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 23:52:39 INFO - mozversion application_buildid: 20160719231520 23:52:39 INFO - mozversion application_changeset: b53dd977b6d65fb49d56a8da063d63311ef11f0a 23:52:39 INFO - mozversion application_display_name: Nightly 23:52:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:52:39 INFO - mozversion application_name: Firefox 23:52:39 INFO - mozversion application_remotingname: firefox 23:52:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:52:39 INFO - mozversion application_vendor: Mozilla 23:52:39 INFO - mozversion application_version: 50.0a1 23:52:39 INFO - mozversion platform_buildid: 20160719231520 23:52:39 INFO - mozversion platform_changeset: b53dd977b6d65fb49d56a8da063d63311ef11f0a 23:52:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:52:39 INFO - mozversion platform_version: 50.0a1 23:52:39 INFO - using testdate: 1468997559 23:52:39 INFO - actual date: 1468997559 23:52:39 INFO - starting webserver on 'localhost:60688' 23:52:39 INFO - SUITE-START | Running 2 tests 23:52:39 INFO - TEST-START | dromaeo_css 23:52:39 INFO - operating with platform_type : linux_ 23:52:39 INFO - Initialising browser for dromaeo_css test... 23:52:39 INFO - TEST-INFO | started process 19285 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLI3Off/profile http://localhost:60688/getInfo.html) 23:52:43 INFO - PROCESS | 19285 | __metrics Screen width/height:1600/1200 23:52:43 INFO - PROCESS | 19285 | colorDepth:24 23:52:43 INFO - PROCESS | 19285 | Browser inner width/height: 1024/697 23:52:43 INFO - PROCESS | 19285 | __metrics 23:52:44 INFO - PROCESS | 19285 | 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" 23:52:44 INFO - PROCESS | 19285 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:52:44 INFO - PROCESS | 19285 | console.error: PushService: 23:52:44 INFO - PROCESS | 19285 | stateChangeProcessEnqueue: Error transitioning state 23:52:44 INFO - PROCESS | 19285 | UnknownError 23:52:45 INFO - TEST-INFO | 19285: exit 0 23:52:45 INFO - Browser initialized. 23:52:45 INFO - Running cycle 1/1 for dromaeo_css test... 23:52:45 INFO - TEST-INFO | started process 19365 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLI3Off/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:52:57 INFO - PROCESS | 19365 | RSS: Main: 159219712 23:52:57 INFO - PROCESS | 19365 | 23:53:49 INFO - PROCESS | 19365 | 1468997629024 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] 23:56:00 INFO - PROCESS | 19365 | Cycle 1(1): loaded http://localhost:60688/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60688/tests/dromaeo/cssquery-ext.html) 23:56:01 INFO - PROCESS | 19365 | RSS: Main: 271876096 23:56:01 INFO - PROCESS | 19365 | 23:59:03 INFO - PROCESS | 19365 | Cycle 1(1): loaded http://localhost:60688/tests/dromaeo/cssquery-ext.html (next: http://localhost:60688/tests/dromaeo/cssquery-jquery.html) 23:59:04 INFO - PROCESS | 19365 | RSS: Main: 246620160 23:59:04 INFO - PROCESS | 19365 | 00:02:06 INFO - PROCESS | 19365 | Cycle 1(1): loaded http://localhost:60688/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60688/tests/dromaeo/cssquery-mootools.html) 00:02:07 INFO - PROCESS | 19365 | RSS: Main: 241991680 00:02:07 INFO - PROCESS | 19365 | 00:05:09 INFO - PROCESS | 19365 | Cycle 1(1): loaded http://localhost:60688/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60688/tests/dromaeo/cssquery-prototype.html) 00:05:10 INFO - PROCESS | 19365 | RSS: Main: 242384896 00:05:10 INFO - PROCESS | 19365 | 00:08:13 INFO - PROCESS | 19365 | Cycle 1(1): loaded http://localhost:60688/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60688/tests/dromaeo/cssquery-yui.html) 00:08:13 INFO - PROCESS | 19365 | RSS: Main: 215093248 00:08:13 INFO - PROCESS | 19365 | 00:11:16 INFO - PROCESS | 19365 | Cycle 1(1): loaded http://localhost:60688/tests/dromaeo/cssquery-yui.html (next: http://localhost:60688/tests/dromaeo/cssquery-dojo.html) 00:11:16 INFO - PROCESS | 19365 | RSS: Main: 280993792 00:11:16 INFO - PROCESS | 19365 | 00:11:16 INFO - PROCESS | 19365 | __start_tp_report 00:11:16 INFO - PROCESS | 19365 | _x_x_mozilla_page_load 00:11:16 INFO - PROCESS | 19365 | _x_x_mozilla_page_load_details 00:11:16 INFO - PROCESS | 19365 | |i|pagename|runs| 00:11:16 INFO - PROCESS | 19365 | |0;dojo.html;2605.3616863868356;2616.7252438493956;2653.376456532715;2656.8671566421676;2665.5202063628553;4580.335851301559;4606.216943119669;4660.766961651917;4689.48415674276;4703.082898834728;4641.187792136379;4646.790894409752;4649.279361698942;4677.719336839784;4711.693739906904;4262.488501379838;4276.636485898687;4327.74033557986;4347.586979236975;4360.956390436087;68.26647670777498;4371.169477799224;4372.672049596277;4380.233459144652;4394.143142087294;4245.87262382129;4347.2826983547675;4377.584129507723;4380.189664912002;4383.649308055348;4325.762083085429;4355.085432059272;4355.956440435587;4395.758233297167;4406.4271644686305;4185.497740271187;4358.106588149421;4366.650667946588;4372.081862808814;4415;4532.773361331921;4609.331646911212;4613.100445413146;4614.8154073837;4666.2067448533835;5098.107831129574;5129.6409251352425;5162.406323272834;5179.922301165486;5186.688798672092;5195.896082078375;5212.869678258036;5219.8434046978655;5225.895482090375;5227.424983251839;4646.721196728207;4660.720356778605;4681.485036645966;4856.660033797635;4860.927086093747;5170.870728231825;5171.120909445403;5214.426413094557;5216.686998780086;5225.634205605571;4928.28539861718;4931.876703082452;4973.776180071905;4978.751062446936;4982.60139188864;4866.099771542276;4921.212605983025;4945.4312754033745;4948.134076536592;5080.5681517070725;4889.535494128052;4911.18965370717;4913.21388577833;4921.089598424303;4959.528844759814;4958.884251043486;4959.90080198394;4966.503349665077;4970.2793095001025;5017.623678224075;4138.813753381105;4236.343366778155;4238.470191226097;4259.254630439661;4291.892702682396;3758.3986562149926;3764.9247015059545;3773.6981041516615;3813.9046523836573;3825.0628595993603;4346.174226896879;4362.17118747437;4363.52001279859;4404.493483249467;4407.515173330931;4913.164761990471;4937.012597480447;4996.300517927493;5038.748062596856;5051;4272.359146128106;4274.123804620108;4297.871063868089;4333.090051089306;4338.978305108454;4515.458145022619;4517.390152329459;4524.5927866492175;4529.91282092302;4566.589006989387;4669.673122881366;4694.765261736968;4701.623870090384;4709.62323014158;4753.1444340019125;5062.772175252197;5064.569511591556;5069.087564238473;5078.695278283315;5109.386873575195;4788.760561971818;4794.017226468635;4798.928016079832;4800.207965685623;4831.903361932671;4742.596879265301;4952.579030782424;4992.850214493571;4996.575291100297;5059.215821547666;5041.470645582161;5063.063333283354;5067.113255180255;5072.771725272298;5131.153359695758;4947.48051454592;4955.975220124021;4973.278874563241;4974.527419895104;5006.69959802413;4827.831025914076;4895.559399654048;4925.408950925912;4948.901021979468;4986.102501549611;4862.440819305825;4885.939816964452;4891.144049791344;4946.332245147003;4955.306257124078;4836.588889944394;4985.551300382983;5002.749862506933;5003.824866129668;5054.014467178865;4760.619150468024;4792.7124372537755;4803.423589169322;4822.662413631012;4829.179039563212 00:11:16 INFO - PROCESS | 19365 | |1;ext.html;564.8757273399851;565.5532129617604;572.0818086970415;577.5985689945487;578.9838832848352;27182.048628297987;27640.861795691042;38711.41932871009;39929.40105898414;40024;40388.19223615526;41052.17895642085;41141.177176456455;41147.17705645885;41151.38272925909;7774.49465784725;7892.447528672995;7893.763187104396;7894.447388682795;7895.289423951843;7841.411894107965;7931.682732690685;7934.127246002935;7934.285914267714;7935.444518883681;7901.960490197541;7960.323372513343;7961.641726122339;7962.323202527792;7962.641681124334;7911.723089691862;7958;7960.1243863175005;7963.920360796434;7965.123836377994;7881.8029549261155;7938.603069846542;7939.285464308182;7943.642536085832;7945.364370850318;37551.624483755084;37589.24821503582;37658.24683506342;37676;37969;3119.1890108571706;3144.402563512925;3162.683731626842;3197.8720851165926;3333.949805811186;27804.16587502358;28102.437951241067;28546.842746648803;29281.104383145157;29492;3512.7541072124964;3524.682778549917;3531.1878267998504;3546.3971124908826;3559.2347645256395;264219;265256;265512;266222.6688866563;266744;27632.171034868985;50935.745321273156;51757;51819.481805181465;51952.740236298574;33556.83221583877;33567.49648755271;33690.49464258038;33709.83145084259;33725;13235.742604452562;13410.530631427853;13445.386553613433;13575.389107490186;13632.318384080756;11714.297142171497;11872.584459543874;11915.344656044;11931.821022684666;11934.701632459259;7806.960965195138;7883.684652613946;7941.722039728582;7943.443958922935;7947.205279472007;3115.0966219796005;3128.6402063762503;3163.9367212655616;3174.0636512228484;3190.7606929479916;871.3943809052774;891.7681402835179;970.7621632700034;977.5014742481386;1033.075397519231;26531.33671658224;26629.86685066601;28103.859480702467;28215.43569128647;28249;777.6383981448654;948.5399581203111;951.110711484764;951.1439704266215;962.2206013129388;13912.513062042983;14416.13503189812;14478.420863165566;14760.778588321184;14765.40938362475;362850;366543;370662;371137;371590;47362;47726.28410573844;47851.76074119607;47911.28133078007;47992;1056.2183983851894;1106.2090605217147;1130.949107290174;1150.0856818829052;1187.6733898177897;24771.380715482257;28083.297917552223;28343.716562834106;28666;28851.56722649204;40569.391459128725;40898.59101408948;41100.1779964405;41123;41158.1768364637;283216;283738;283777;284501;284890;22022;24229.394265143514;25006.37484062913;25082.74917250877;26348.736512635394;39952;40077.799611001756;40095;41048;41051.1789764209;34263.31473370469;34454.65545344514;34921.301573968885;35414.114647134025;35473.46789798207;34548.618055278515;34624.653753462146;34764;34806.47790283198;35158;31822.52266216055;32496.187595310308;32541.511877322333;32630;32707.34585308328;30509.389812204077;32519.512207316435;32560.185995350304;32594.83702581472;32641;24148.275551733477;24490.63264051005;24512.75487245105;24945.75054249434;25152.24543263705 00:11:16 INFO - PROCESS | 19365 | |2;jquery.html;343.4937797756839;372.5752641988134;372.7483948334874;373.91773809761844;389.92591407632545;12900.741985160303;13198.472061117567;13386.567859159675;14187.574372768808;14268.785968210484;16246.350145994145;16472.670546589092;17168.656626867454;17186.570335741613;17218.913905430487;4945.901081978358;5030.7987680492915;5080.898382032357;5088.084144853925;5097;3712.7401081924277;5866.970665146669;6205.658688772116;6229.968850155743;6324.968375158118;5790.68151251681;6112.532992081913;6207.379262073801;6232.657203853786;6244.406781355764;5972.522198224132;5972.850678733023;5979.611325263887;6004.5196384289375;6018.789342372996;5125.846224613268;5258.81594144207;5265.605079619006;5395.136778115482;5437.293151890229;20384.694229586567;21171.68242476364;21213.257535986166;21248.681269780966;21434.57130857374;1872.419549939523;1893.7538120044444;1923.740295060177;1974.5754662747433;1996.0818023709112;12932.935335323264;13151.86848131516;13239;13597.824348104356;13940.233287169303;1705.7867766529184;1718.9054601996886;1727.378143868219;1766.7173252279574;1784.2060283173994;49867.50132498701;50715.49284507144;57099;66950.66524667345;68326.3167368325;9592.232621390272;9719.90280097204;9751.463669498246;9787.265955053399;9992.65025724096;12664.303463309423;12729.299888506035;12754.04344674153;12781.360931953552;12801.551945681857;6958.8956165657555;7012.684429200698;7021.736087504299;7138.143422789299;7212.062431883822;6507.772227971998;6617.272100068989;6621.238557565845;6650.20197576294;6696.5647232929705;5983.940160598426;6072.089186622042;6128.3258841527395;6132.63204207749;6171.722272497748;1696.16040060171;1724.3275122701905;1748.2866790545083;1760.7710997570234;1779.6084861330487;868.431177578687;879.872418499314;899.970566372825;954.0411886054471;981.3130808434124;17056.914715426345;17312.53749250155;17544.473665790047;18204.36284730028;18240.361587344643;801.463019776751;874.3224001398839;878.449536567958;883.6156272021562;904.8054668246359;8787.560621968876;9569.473678947585;9606.27952903535;9646.372985755903;9831.410115393099;412630.9263712618;421601;430811;431259;433330;81295;85090;85314;85317.57341213254;85640;916.2303664921498;930.7853251506714;938.666773295484;944.6976967370513;954.7706106242002;15879.841201587837;16055.919720401324;16122.596935076248;16189.67620647604;16343.427980020642;36942.891713248646;38361.42457863079;38441.80779096087;38710;39604.01979901051;52534.47465525449;53885.19172212498;54284;55154.44845551493;55711;7560.848783024198;7737.1102323233545;7751.147373788766;7801.141874393926;7846.411519135973;7436.888446673413;11603.013743831869;12206.511739530319;12359.443825028075;12401.13192076546;10388.948055259676;10449.477526123816;10471.581136754445;10623.256372053882;10629.8937010632;8482.915170848213;10049.346792458467;10328.586856525655;10344.844827327446;10407.58369665205;10465.843012354668;10818.783624327627;10880.564777408816;10962.123030157409;10999.23005389647;6748.122744043243;6901.930980690129;7059.929400705927;7688.231176882267;7769.184235655175;10327.586896524055;10723.356598604109;10741.838872417076;10887.292325998786;11157.386343751172 00:11:16 INFO - PROCESS | 19365 | |3;mootools.html;1185.12300897336;1232.3591732299205;1334.906556541042;1345.7241265540565;1709.4444305600675;6616.603003819768;7375.852482950338;7679.57762323072;7698.34564062054;7984.520928744279;7693.7307194248215;7817.843643127134;7820.569868657221;7821.687132514692;7847.293743563078;1816.6457540779554;1885.3966730646198;1889.0177107903883;2181.1057466438765;2208.3485371815354;1915.1764741161296;2090.362439455965;2182.9454263643456;2186.7813218678166;2199.14233448955;2105.4420578546697;2125.6705210692367;2132.4775430019668;2135.487483004076;2135.6369417199035;1786;2133.839962002848;2142.036083762305;2142.0360837623134;2146.6350720377573;2055.9794402055936;2134.6264403729283;2135.402087415511;2146.37755051035;2150.817180539656;7787.260210280071;7829.412794040412;7830.804229894241;7832.216778322179;7845.607719613997;4578.702384345007;4692.389989301403;4709.340692303081;4719.032598317337;4725.834595789165;2812.887484500638;2872.138358492444;2924.4589750896153;2949.911502654924;2954.793164478487;869.9260562852165;886.9246114080311;895.8342706599235;911.3028533172129;912.1471424218377;10402.47987600617;10454.268201225917;10455.895441045568;10462.058414742709;10474.895251047468;3984.7409918355215;4013.8595149169637;4131.400946862748;4137.54487006429;4345.326474396474;8921.553922303989;8926.152015558511;8926.375153739176;8943.642254309885;8952.23905967987;4751.287306903992;4759.904801903943;4762.9523704762505;4766.928496072562;4769.856904292877;3706.09200745819;3765.9246815063543;4369.2790689536505;4375.27807911691;4376.38730577713;3623.5289412376223;3678.4666223397458;3797.810109494514;3800.1829606634706;3801.9354580717445;1227.5274019502556;1259.6158171757518;1261.1109168036512;1272.45284527654;1278.2458349573797;683.8871586188322;855.4781583234222;865.312076898867;865.359633870931;874.1363174767416;5160.161910948963;5381.730913454312;5382.650127741685;5461.399246082928;5559.527440167551;393.4668524149736;393.73422939516286;395.45032404957135;400.31546056243565;898.833715762586;3656.3966945453703;3877.1470276539117;3892.824822882977;3921.9803900980883;3951.031997160714;9474.194693450996;9730.41617502952;9737.659181928599;9744.853827192597;9811.852822207673;8835.293176545862;9631.229501639851;9655.275854310954;9681.903180968242;9706;7150.642467876585;7438.479306448497;7440.888386674204;7506.512076714996;7512.32389084985;5488.643238189505;5546.084895992116;5663.235463212413;5704.971475142598;5731.054376028075;8465.449745766506;8793.736187914374;8796.47221166732;8967.148120928501;9039.548022598712;7791.181925897699;8858.29133669292;9069.274458043472;9086.54567271647;9254.213391861767;6599.142111525471;6793.048973143863;6818.659067046528;6993.440524758109;7006.579605223703;7398.408127349691;7442.330190282901;7474.551526908185;7484.962575187089;7494.5503269803985;6815.454763618799;6830.7609233676585;6841.110655614738;6854.725810967506;7014.579125252501;6614.239362473377;6620.635865027277;6689.698963546554;6749.865002700017;6808.183018037669;6350.523710721806;6429.807105786646;6442.323556026738;6446.258680251831;6514.609123452608;4570.291604801261;4715.457722361835;4723.929141062956;4786.95213047865;4811.663183577116;4613.700109492872;4616.792244348945;4647.186742320013;4714.104320179155;4734.431868175841 00:11:16 INFO - PROCESS | 19365 | |4;prototype.html;296.87827990523886;300.2269156920929;304.9542568614707;305.5249087668677;308.82551358482453;4720.622350211984;4797.520247975201;4811.59101476375;4825.90348193036;4848;4723.394046024351;4725.7164570125815;4763.7618119093995;4765.8570242892765;4784.688995215309;3545.4327307630783;3666.808287306623;3686.7972261525606;3695.5380577427823;3698.0754811297174;3665.890023299305;3723.2553489302113;3799.715021373408;3815.637514436138;3826.4834247376666;3680.8773324136264;3744;3772.9434058489146;3800.771953682774;3802.847886084567;3508.6491350864962;3711.0907827582146;3761.210145869357;3783.375743002415;3794.2411517696296;3215.115843143131;3235.498497732855;3235.773495855291;3236.96763032369;3239.7408207343356;4634.6524010698995;4657.790399432034;4662.062900372777;4669.34629151919;4691.225947718623;4029.37544680575;4196.265653510654;4205.474315710536;4206.221848957953;4219.38818871265;4900.975495122537;4928.778204980748;4943.8516844494725;4957.603391728653;4958.305837182797;3528.2237907660274;3563.8396272167815;3565.841101641977;3573.4818451324672;3576;5080.374280230328;5159.529534082769;5170.405403378584;5210.723372773695;5225.477452254782;3608.116011577128;3640.360738464645;3651.2149887773726;3653.4702468141973;3665.1020499977676;3678.1172518595345;3694.796786176786;3703.129764505339;3707.647773461517;3708.7033037356946;4223.472065991751;4226.154769046203;4231.624721965373;4234.301340278881;4250.978745106255;4854;4987.007795322777;4991.276264941564;5008.624353173526;5009.44896061433;1261.958883920769;1268.422867595242;1271.8108568488492;1271.841019872516;1273.936303184837;791.9326857217086;801.5070731500202;818.9640105266842;827.9047909490365;831.6465502161557;4306.834476659653;4409.735415875058;4410.206162890647;4420.115976804652;4427.29163333865;5076.0863044652315;5107.744612769421;5111.3355263815465;5112.590992720573;5132.153194722829;4381.9342709859375;4382.123575284892;4385.539518350527;4388.407564978687;4413.183561041217;2579.703334116563;2719.265798234466;2722.6460560127057;2727.7135900730536;2736.822106563067;4799.848036471291;4806.327114204014;4808.326834243208;4824.710517368969;4827.831025914076;5027.497250274943;5172.586193104543;5179.2749015137915;5201.973990130025;5207.583393328525;4787.8563643090765;4795.712257264575;4800.759962001886;4816.783244754065;4836.879078023077;5046.571041461517;5063.215201643751;5070.949290507196;5078.314427552233;5117.2835802986865;4797.584712509748;4980.925286120783;4998.350214472095;5008.899822003613;5016.523430274118;4833.758312084452;4841.8547443576745;4841.975790121027;4858.82994095205;4872.661122661213;3659.158393569547;3687.631236876291;3702.5927148014234;3729.8321575528626;3733.170746334331;4901.921617194725;4989.326440930573;5029;5057.696538207572;5066.898662026812;4870.878228044327;4882.438519570295;4888.853334399973;4894.388201474816;4895.681780684244;4845.8303959362665;4878.804847806048;4892.4373697023875;4908.165611846066;4930.235813448921;4814.446338671098;4836.274558816206;4840.540148685869;4848.757562121809;4848.781804818721;4771.5467030632035;4801.542889119828;4899.456671148577;5025.673331233458;5028.170351891897;4882.267659850909;4885.169521181337;4899.510048995215;4906.460289368238;4913.164761990399 00:11:16 INFO - PROCESS | 19365 | |5;yui.html;608.4280776070493;626.5426238845646;638.5881106686187;719.7768691705571;737.1854101218155;12711.237325760449;13542.593722188325;13562.118462299944;13574.185548867075;13580.320983950813;12362.505499779998;12777.10560260782;12785.808212876815;12923.806142907864;12977.675558111052;747.0400535312111;771.5370777533491;771.5602106799126;774.0324594257179;774.7288449042818;776.6621519638946;779.76996785948;779.8947142135825;779.9376049916021;780;821.720614990903;822.6997146041712;824.983500329993;825.0223485170093;825.471698113208;820.8399362124388;821.2034326703118;821.2772759971247;821.4619424277108;821.880827280047;596.5347029317139;600.2287061126432;600.5645906717639;600.7506884642868;600.8287638023143;13078;13106.344682765919;13166.473341066347;13182.340882955814;13222.801657975042;13093.607191784262;13162.934185329108;13209;13261.73476530464;13535.255560944144;10159.695209143738;10165.288429809916;10213.897861021444;10260.74348141295;10436.425996570186;3338.382399256145;3396.015155604872;3404.9659503405146;3408.2842603053386;3482.233908540117;36080.45879311812;41528.3770743436;43160;51750.48249517457;56975.14537281944;10739.624113156002;10812.56749730002;10965.671029869116;11086.279391839505;11304.15218858589;16728.581785455463;17353.56616084583;17368;18156.27374904989;18168.909155454137;6953.165620125617;7001.754938577125;7004.859902801915;7079.858402831915;7207.819804504929;3711.2763011212555;3757.1922036762226;3857.228554289153;3866.574676785551;3904.082540602956;2840.730130637586;2965.1993961630237;2965.303153758865;2966.925826854346;2968.792184547061;453.58496975267263;458.5139751863036;463.39758314191414;464.5725932142437;465.834628706811;574.0528128587863;581.5434883616341;587.216066551159;629.4492319220682;639.1084437210066;8343.499390036617;8456;8473.322134229247;8526.360522960802;8799.340049496186;570.9200711900337;573.166043406842;579.2787978966214;607.8329607154275;611.3702886027398;4061.5126184858004;4122.979385103115;4138.627523522837;4363.781810909441;4368.563143685606;87296.563517182;87400;92699.53650231706;93062;93152.53423732838;47093.52906470891;57375;58699;61978.380216198166;68095;75926;76332.23667763363;76920.61539692266;77305.61347193229;78516.60741696255;8542.231199191854;8682.305415566865;8793.91206087931;8841.55792210374;8883;43232.56767432411;43652.12695746131;43906.56093439025;44478;45073.32390014218;56386;58155;65333;65921.34078659152;66175.66912165408;11255.606053788078;11535.480903359201;11568.479418426023;11721.296722196696;11888.34614096233;12823.551175708806;13098.869011310146;13515.324233788075;13693.520726774515;13806.585802425943;11418.885811141783;11667.124965627441;11753.647390578297;11990.580329688419;12075.51697932073;12206.938965305117;12364.62906112818;12719.720353646833;12839.10126291188;13137.277449740355;12064;12576.87423125757;12625.747485050431;12906.677333066373;13101;9318.34771566011;9378.249740020645;9548.474833884204;9655.517224138624;9675.758106047406;8923.553822308728;9016.954915225382;9169.40398874071;9731.12419882214;9979.301448898508 00:11:16 INFO - PROCESS | 19365 | __end_tp_report 00:11:16 INFO - PROCESS | 19365 | __start_cc_report 00:11:16 INFO - PROCESS | 19365 | _x_x_mozilla_cycle_collect,1052 00:11:16 INFO - PROCESS | 19365 | __end_cc_report 00:11:16 INFO - PROCESS | 19365 | __startTimestamp1468998676820__endTimestamp 00:11:16 INFO - PROCESS | 19365 | 00:11:16 INFO - PROCESS | 19365 | ------- Summary: start ------- 00:11:16 INFO - PROCESS | 19365 | Number of tests: 6 00:11:16 INFO - PROCESS | 19365 | 00:11:16 INFO - PROCESS | 19365 | [#0] dojo.html Cycles:165 Average:4635.24 Median:4798.93 stddev:606.84 (12.6%) stddev-sans-first:587.44 00:11:16 INFO - PROCESS | 19365 | Values: 2605.4 2616.7 2653.4 2656.9 2665.5 4580.3 4606.2 4660.8 4689.5 4703.1 4641.2 4646.8 4649.3 4677.7 4711.7 4262.5 4276.6 4327.7 4347.6 4361.0 68.3 4371.2 4372.7 4380.2 4394.1 4245.9 4347.3 4377.6 4380.2 4383.6 4325.8 4355.1 4356.0 4395.8 4406.4 4185.5 4358.1 4366.7 4372.1 4415.0 4532.8 4609.3 4613.1 4614.8 4666.2 5098.1 5129.6 5162.4 5179.9 5186.7 5195.9 5212.9 5219.8 5225.9 5227.4 4646.7 4660.7 4681.5 4856.7 4860.9 5170.9 5171.1 5214.4 5216.7 5225.6 4928.3 4931.9 4973.8 4978.8 4982.6 4866.1 4921.2 4945.4 4948.1 5080.6 4889.5 4911.2 4913.2 4921.1 4959.5 4958.9 4959.9 4966.5 4970.3 5017.6 4138.8 4236.3 4238.5 4259.3 4291.9 3758.4 3764.9 3773.7 3813.9 3825.1 4346.2 4362.2 4363.5 4404.5 4407.5 4913.2 4937.0 4996.3 5038.7 5051.0 4272.4 4274.1 4297.9 4333.1 4339.0 4515.5 4517.4 4524.6 4529.9 4566.6 4669.7 4694.8 4701.6 4709.6 4753.1 5062.8 5064.6 5069.1 5078.7 5109.4 4788.8 4794.0 4798.9 4800.2 4831.9 4742.6 4952.6 4992.9 4996.6 5059.2 5041.5 5063.1 5067.1 5072.8 5131.2 4947.5 4956.0 4973.3 4974.5 5006.7 4827.8 4895.6 4925.4 4948.9 4986.1 4862.4 4885.9 4891.1 4946.3 4955.3 4836.6 4985.6 5002.7 5003.8 5054.0 4760.6 4792.7 4803.4 4822.7 4829.2 00:11:16 INFO - PROCESS | 19365 | 00:11:16 INFO - PROCESS | 19365 | [#1] ext.html Cycles:180 Average:44704.63 Median:19217.03 stddev:81447.25 (423.8%) stddev-sans-first:81608.31 00:11:16 INFO - PROCESS | 19365 | Values: 564.9 565.6 572.1 577.6 579.0 27182.0 27640.9 38711.4 39929.4 40024.0 40388.2 41052.2 41141.2 41147.2 41151.4 7774.5 7892.4 7893.8 7894.4 7895.3 7841.4 7931.7 7934.1 7934.3 7935.4 7902.0 7960.3 7961.6 7962.3 7962.6 7911.7 7958.0 7960.1 7963.9 7965.1 7881.8 7938.6 7939.3 7943.6 7945.4 37551.6 37589.2 37658.2 37676.0 37969.0 3119.2 3144.4 3162.7 3197.9 3333.9 27804.2 28102.4 28546.8 29281.1 29492.0 3512.8 3524.7 3531.2 3546.4 3559.2 264219.0 265256.0 265512.0 266222.7 266744.0 27632.2 50935.7 51757.0 51819.5 51952.7 33556.8 33567.5 33690.5 33709.8 33725.0 13235.7 13410.5 13445.4 13575.4 13632.3 11714.3 11872.6 11915.3 11931.8 11934.7 7807.0 7883.7 7941.7 7943.4 7947.2 3115.1 3128.6 3163.9 3174.1 3190.8 871.4 891.8 970.8 977.5 1033.1 26531.3 26629.9 28103.9 28215.4 28249.0 777.6 948.5 951.1 951.1 962.2 13912.5 14416.1 14478.4 14760.8 14765.4 362850.0 366543.0 370662.0 371137.0 371590.0 47362.0 47726.3 47851.8 47911.3 47992.0 1056.2 1106.2 1130.9 1150.1 1187.7 24771.4 28083.3 28343.7 28666.0 28851.6 40569.4 40898.6 41100.2 41123.0 41158.2 283216.0 283738.0 283777.0 284501.0 284890.0 22022.0 24229.4 25006.4 25082.7 26348.7 39952.0 40077.8 40095.0 41048.0 41051.2 34263.3 34454.7 34921.3 35414.1 35473.5 34548.6 34624.7 34764.0 34806.5 35158.0 31822.5 32496.2 32541.5 32630.0 32707.3 30509.4 32519.5 32560.2 32594.8 32641.0 24148.3 24490.6 24512.8 24945.8 25152.2 00:11:16 INFO - PROCESS | 19365 | 00:11:16 INFO - PROCESS | 19365 | [#2] jquery.html Cycles:180 Average:25474.82 Median:27449.15 stddev:70151.85 (255.6%) stddev-sans-first:70323.26 00:11:16 INFO - PROCESS | 19365 | Values: 343.5 372.6 372.7 373.9 389.9 12900.7 13198.5 13386.6 14187.6 14268.8 16246.4 16472.7 17168.7 17186.6 17218.9 4945.9 5030.8 5080.9 5088.1 5097.0 3712.7 5867.0 6205.7 6230.0 6325.0 5790.7 6112.5 6207.4 6232.7 6244.4 5972.5 5972.9 5979.6 6004.5 6018.8 5125.8 5258.8 5265.6 5395.1 5437.3 20384.7 21171.7 21213.3 21248.7 21434.6 1872.4 1893.8 1923.7 1974.6 1996.1 12932.9 13151.9 13239.0 13597.8 13940.2 1705.8 1718.9 1727.4 1766.7 1784.2 49867.5 50715.5 57099.0 66950.7 68326.3 9592.2 9719.9 9751.5 9787.3 9992.7 12664.3 12729.3 12754.0 12781.4 12801.6 6958.9 7012.7 7021.7 7138.1 7212.1 6507.8 6617.3 6621.2 6650.2 6696.6 5983.9 6072.1 6128.3 6132.6 6171.7 1696.2 1724.3 1748.3 1760.8 1779.6 868.4 879.9 900.0 954.0 981.3 17056.9 17312.5 17544.5 18204.4 18240.4 801.5 874.3 878.4 883.6 904.8 8787.6 9569.5 9606.3 9646.4 9831.4 412630.9 421601.0 430811.0 431259.0 433330.0 81295.0 85090.0 85314.0 85317.6 85640.0 916.2 930.8 938.7 944.7 954.8 15879.8 16055.9 16122.6 16189.7 16343.4 36942.9 38361.4 38441.8 38710.0 39604.0 52534.5 53885.2 54284.0 55154.4 55711.0 7560.8 7737.1 7751.1 7801.1 7846.4 7436.9 11603.0 12206.5 12359.4 12401.1 10388.9 10449.5 10471.6 10623.3 10629.9 8482.9 10049.3 10328.6 10344.8 10407.6 10465.8 10818.8 10880.6 10962.1 10999.2 6748.1 6901.9 7059.9 7688.2 7769.2 10327.6 10723.4 10741.8 10887.3 11157.4 00:11:16 INFO - PROCESS | 19365 | 00:11:16 INFO - PROCESS | 19365 | [#3] mootools.html Cycles:180 Average:5135.85 Median:5382.19 stddev:2877.96 (53.5%) stddev-sans-first:2870.72 00:11:16 INFO - PROCESS | 19365 | Values: 1185.1 1232.4 1334.9 1345.7 1709.4 6616.6 7375.9 7679.6 7698.3 7984.5 7693.7 7817.8 7820.6 7821.7 7847.3 1816.6 1885.4 1889.0 2181.1 2208.3 1915.2 2090.4 2182.9 2186.8 2199.1 2105.4 2125.7 2132.5 2135.5 2135.6 1786.0 2133.8 2142.0 2142.0 2146.6 2056.0 2134.6 2135.4 2146.4 2150.8 7787.3 7829.4 7830.8 7832.2 7845.6 4578.7 4692.4 4709.3 4719.0 4725.8 2812.9 2872.1 2924.5 2949.9 2954.8 869.9 886.9 895.8 911.3 912.1 10402.5 10454.3 10455.9 10462.1 10474.9 3984.7 4013.9 4131.4 4137.5 4345.3 8921.6 8926.2 8926.4 8943.6 8952.2 4751.3 4759.9 4763.0 4766.9 4769.9 3706.1 3765.9 4369.3 4375.3 4376.4 3623.5 3678.5 3797.8 3800.2 3801.9 1227.5 1259.6 1261.1 1272.5 1278.2 683.9 855.5 865.3 865.4 874.1 5160.2 5381.7 5382.7 5461.4 5559.5 393.5 393.7 395.5 400.3 898.8 3656.4 3877.1 3892.8 3922.0 3951.0 9474.2 9730.4 9737.7 9744.9 9811.9 8835.3 9631.2 9655.3 9681.9 9706.0 7150.6 7438.5 7440.9 7506.5 7512.3 5488.6 5546.1 5663.2 5705.0 5731.1 8465.4 8793.7 8796.5 8967.1 9039.5 7791.2 8858.3 9069.3 9086.5 9254.2 6599.1 6793.0 6818.7 6993.4 7006.6 7398.4 7442.3 7474.6 7485.0 7494.6 6815.5 6830.8 6841.1 6854.7 7014.6 6614.2 6620.6 6689.7 6749.9 6808.2 6350.5 6429.8 6442.3 6446.3 6514.6 4570.3 4715.5 4723.9 4787.0 4811.7 4613.7 4616.8 4647.2 4714.1 4734.4 00:11:16 INFO - PROCESS | 19365 | 00:11:16 INFO - PROCESS | 19365 | [#4] prototype.html Cycles:180 Average:4125.07 Median:4778.12 stddev:1190.14 (24.9%) stddev-sans-first:1158.27 00:11:16 INFO - PROCESS | 19365 | Values: 296.9 300.2 305.0 305.5 308.8 4720.6 4797.5 4811.6 4825.9 4848.0 4723.4 4725.7 4763.8 4765.9 4784.7 3545.4 3666.8 3686.8 3695.5 3698.1 3665.9 3723.3 3799.7 3815.6 3826.5 3680.9 3744.0 3772.9 3800.8 3802.8 3508.6 3711.1 3761.2 3783.4 3794.2 3215.1 3235.5 3235.8 3237.0 3239.7 4634.7 4657.8 4662.1 4669.3 4691.2 4029.4 4196.3 4205.5 4206.2 4219.4 4901.0 4928.8 4943.9 4957.6 4958.3 3528.2 3563.8 3565.8 3573.5 3576.0 5080.4 5159.5 5170.4 5210.7 5225.5 3608.1 3640.4 3651.2 3653.5 3665.1 3678.1 3694.8 3703.1 3707.6 3708.7 4223.5 4226.2 4231.6 4234.3 4251.0 4854.0 4987.0 4991.3 5008.6 5009.4 1262.0 1268.4 1271.8 1271.8 1273.9 791.9 801.5 819.0 827.9 831.6 4306.8 4409.7 4410.2 4420.1 4427.3 5076.1 5107.7 5111.3 5112.6 5132.2 4381.9 4382.1 4385.5 4388.4 4413.2 2579.7 2719.3 2722.6 2727.7 2736.8 4799.8 4806.3 4808.3 4824.7 4827.8 5027.5 5172.6 5179.3 5202.0 5207.6 4787.9 4795.7 4800.8 4816.8 4836.9 5046.6 5063.2 5070.9 5078.3 5117.3 4797.6 4980.9 4998.4 5008.9 5016.5 4833.8 4841.9 4842.0 4858.8 4872.7 3659.2 3687.6 3702.6 3729.8 3733.2 4901.9 4989.3 5029.0 5057.7 5066.9 4870.9 4882.4 4888.9 4894.4 4895.7 4845.8 4878.8 4892.4 4908.2 4930.2 4814.4 4836.3 4840.5 4848.8 4848.8 4771.5 4801.5 4899.5 5025.7 5028.2 4882.3 4885.2 4899.5 4906.5 4913.2 00:11:16 INFO - PROCESS | 19365 | 00:11:16 INFO - PROCESS | 19365 | [#5] yui.html Cycles:180 Average:16550.98 Median:463.99 stddev:22573.07 (4865.0%) stddev-sans-first:22604.65 00:11:16 INFO - PROCESS | 19365 | Values: 608.4 626.5 638.6 719.8 737.2 12711.2 13542.6 13562.1 13574.2 13580.3 12362.5 12777.1 12785.8 12923.8 12977.7 747.0 771.5 771.6 774.0 774.7 776.7 779.8 779.9 779.9 780.0 821.7 822.7 825.0 825.0 825.5 820.8 821.2 821.3 821.5 821.9 596.5 600.2 600.6 600.8 600.8 13078.0 13106.3 13166.5 13182.3 13222.8 13093.6 13162.9 13209.0 13261.7 13535.3 10159.7 10165.3 10213.9 10260.7 10436.4 3338.4 3396.0 3405.0 3408.3 3482.2 36080.5 41528.4 43160.0 51750.5 56975.1 10739.6 10812.6 10965.7 11086.3 11304.2 16728.6 17353.6 17368.0 18156.3 18168.9 6953.2 7001.8 7004.9 7079.9 7207.8 3711.3 3757.2 3857.2 3866.6 3904.1 2840.7 2965.2 2965.3 2966.9 2968.8 453.6 458.5 463.4 464.6 465.8 574.1 581.5 587.2 629.4 639.1 8343.5 8456.0 8473.3 8526.4 8799.3 570.9 573.2 579.3 607.8 611.4 4061.5 4123.0 4138.6 4363.8 4368.6 87296.6 87400.0 92699.5 93062.0 93152.5 47093.5 57375.0 58699.0 61978.4 68095.0 75926.0 76332.2 76920.6 77305.6 78516.6 8542.2 8682.3 8793.9 8841.6 8883.0 43232.6 43652.1 43906.6 44478.0 45073.3 56386.0 58155.0 65333.0 65921.3 66175.7 11255.6 11535.5 11568.5 11721.3 11888.3 12823.6 13098.9 13515.3 13693.5 13806.6 11418.9 11667.1 11753.6 11990.6 12075.5 12206.9 12364.6 12719.7 12839.1 13137.3 12064.0 12576.9 12625.7 12906.7 13101.0 9318.3 9378.2 9548.5 9655.5 9675.8 8923.6 9017.0 9169.4 9731.1 9979.3 00:11:16 INFO - PROCESS | 19365 | -------- Summary: end -------- 00:11:16 INFO - PROCESS | 19365 | 00:11:17 INFO - TEST-INFO | 19365: exit 0 00:11:17 INFO - TEST-OK | dromaeo_css | took 1118593ms 00:11:17 INFO - TEST-START | kraken 00:11:17 INFO - operating with platform_type : linux_ 00:11:17 INFO - Initialising browser for kraken test... 00:11:17 INFO - TEST-INFO | started process 19555 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphn2LPc/profile http://localhost:60688/getInfo.html) 00:11:21 INFO - PROCESS | 19555 | __metrics Screen width/height:1600/1200 00:11:21 INFO - PROCESS | 19555 | colorDepth:24 00:11:21 INFO - PROCESS | 19555 | Browser inner width/height: 1024/697 00:11:21 INFO - PROCESS | 19555 | __metrics 00:11:22 INFO - PROCESS | 19555 | 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" 00:11:22 INFO - PROCESS | 19555 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:11:23 INFO - PROCESS | 19555 | console.error: PushService: 00:11:23 INFO - PROCESS | 19555 | stateChangeProcessEnqueue: Error transitioning state 00:11:23 INFO - PROCESS | 19555 | UnknownError 00:11:23 INFO - TEST-INFO | 19555: exit 0 00:11:23 INFO - Browser initialized. 00:11:23 INFO - Running cycle 1/1 for kraken test... 00:11:23 INFO - TEST-INFO | started process 19640 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphn2LPc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:11:35 INFO - PROCESS | 19640 | RSS: Main: 159256576 00:11:35 INFO - PROCESS | 19640 | 00:11:38 INFO - PROCESS | 19640 | Cycle 1(1): loaded http://localhost:60688/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60688/tests/kraken/driver.html?testName=audio-beat-detection) 00:11:39 INFO - PROCESS | 19640 | RSS: Main: 217907200 00:11:39 INFO - PROCESS | 19640 | 00:11:42 INFO - PROCESS | 19640 | Cycle 1(1): loaded http://localhost:60688/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60688/tests/kraken/driver.html?testName=audio-dft) 00:11:42 INFO - PROCESS | 19640 | RSS: Main: 207163392 00:11:42 INFO - PROCESS | 19640 | 00:11:45 INFO - PROCESS | 19640 | Cycle 1(1): loaded http://localhost:60688/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60688/tests/kraken/driver.html?testName=audio-fft) 00:11:46 INFO - PROCESS | 19640 | RSS: Main: 272826368 00:11:46 INFO - PROCESS | 19640 | 00:11:48 INFO - PROCESS | 19640 | Cycle 1(1): loaded http://localhost:60688/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60688/tests/kraken/driver.html?testName=audio-oscillator) 00:11:49 INFO - PROCESS | 19640 | RSS: Main: 182558720 00:11:49 INFO - PROCESS | 19640 | 00:11:51 INFO - PROCESS | 19640 | Cycle 1(1): loaded http://localhost:60688/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60688/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:11:52 INFO - PROCESS | 19640 | RSS: Main: 186142720 00:11:52 INFO - PROCESS | 19640 | 00:11:55 INFO - PROCESS | 19640 | Cycle 1(1): loaded http://localhost:60688/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60688/tests/kraken/driver.html?testName=imaging-darkroom) 00:11:55 INFO - PROCESS | 19640 | RSS: Main: 222441472 00:11:55 INFO - PROCESS | 19640 | 00:11:59 INFO - PROCESS | 19640 | Cycle 1(1): loaded http://localhost:60688/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60688/tests/kraken/driver.html?testName=imaging-desaturate) 00:12:00 INFO - PROCESS | 19640 | RSS: Main: 218025984 00:12:00 INFO - PROCESS | 19640 | 00:12:02 INFO - PROCESS | 19640 | Cycle 1(1): loaded http://localhost:60688/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60688/tests/kraken/driver.html?testName=json-parse-financial) 00:12:03 INFO - PROCESS | 19640 | RSS: Main: 220647424 00:12:03 INFO - PROCESS | 19640 | 00:12:05 INFO - PROCESS | 19640 | Cycle 1(1): loaded http://localhost:60688/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60688/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:12:05 INFO - PROCESS | 19640 | RSS: Main: 210874368 00:12:05 INFO - PROCESS | 19640 | 00:12:07 INFO - PROCESS | 19640 | Cycle 1(1): loaded http://localhost:60688/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60688/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:12:08 INFO - PROCESS | 19640 | RSS: Main: 214900736 00:12:08 INFO - PROCESS | 19640 | 00:12:10 INFO - PROCESS | 19640 | Cycle 1(1): loaded http://localhost:60688/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60688/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:12:11 INFO - PROCESS | 19640 | RSS: Main: 211816448 00:12:11 INFO - PROCESS | 19640 | 00:12:14 INFO - PROCESS | 19640 | Cycle 1(1): loaded http://localhost:60688/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60688/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:12:14 INFO - PROCESS | 19640 | RSS: Main: 223862784 00:12:14 INFO - PROCESS | 19640 | 00:12:17 INFO - PROCESS | 19640 | Cycle 1(1): loaded http://localhost:60688/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60688/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:12:18 INFO - PROCESS | 19640 | RSS: Main: 224227328 00:12:18 INFO - PROCESS | 19640 | 00:12:19 INFO - PROCESS | 19640 | Cycle 1(1): loaded http://localhost:60688/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60688/tests/kraken/driver.html?testName=ai-astar) 00:12:20 INFO - PROCESS | 19640 | RSS: Main: 266670080 00:12:20 INFO - PROCESS | 19640 | 00:12:20 INFO - PROCESS | 19640 | __start_tp_report 00:12:20 INFO - PROCESS | 19640 | _x_x_mozilla_page_load 00:12:20 INFO - PROCESS | 19640 | _x_x_mozilla_page_load_details 00:12:20 INFO - PROCESS | 19640 | |i|pagename|runs| 00:12:20 INFO - PROCESS | 19640 | |0;ai-astar;106;116;115;116;114;114;113;115;113;115 00:12:20 INFO - PROCESS | 19640 | |1;audio-beat-detection;148;120;123;179;125;123;144;122;156;124 00:12:20 INFO - PROCESS | 19640 | |2;audio-dft;162;161;161;161;161;161;161;162;161;160 00:12:20 INFO - PROCESS | 19640 | |3;audio-fft;76;76;76;114;79;76;75;77;116;76 00:12:20 INFO - PROCESS | 19640 | |4;audio-oscillator;84;87;121;85;85;86;115;85;85;125 00:12:20 INFO - PROCESS | 19640 | |5;imaging-gaussian-blur;117;117;117;117;118;116;117;117;117;117 00:12:20 INFO - PROCESS | 19640 | |6;imaging-darkroom;146;145;146;151;145;145;146;146;146;146 00:12:20 INFO - PROCESS | 19640 | |7;imaging-desaturate;95;94;95;95;95;95;95;95;95;96 00:12:20 INFO - PROCESS | 19640 | |8;json-parse-financial;65;62;67;63;69;64;76;63;63;64 00:12:20 INFO - PROCESS | 19640 | |9;json-stringify-tinderbox;66;67;67;66;66;66;67;66;67;66 00:12:20 INFO - PROCESS | 19640 | |10;stanford-crypto-aes;79;79;78;86;79;80;87;81;78;82 00:12:20 INFO - PROCESS | 19640 | |11;stanford-crypto-ccm;129;129;139;141;127;137;130;131;171;131 00:12:20 INFO - PROCESS | 19640 | |12;stanford-crypto-pbkdf2;168;165;169;165;169;172;165;166;166;161 00:12:20 INFO - PROCESS | 19640 | |13;stanford-crypto-sha256-iterative;67;66;66;65;69;65;66;65;66;67 00:12:20 INFO - PROCESS | 19640 | __end_tp_report 00:12:20 INFO - PROCESS | 19640 | __start_cc_report 00:12:20 INFO - PROCESS | 19640 | _x_x_mozilla_cycle_collect,1933 00:12:20 INFO - PROCESS | 19640 | __end_cc_report 00:12:20 INFO - PROCESS | 19640 | __startTimestamp1468998740071__endTimestamp 00:12:20 INFO - PROCESS | 19640 | 00:12:20 INFO - PROCESS | 19640 | ------- Summary: start ------- 00:12:20 INFO - PROCESS | 19640 | Number of tests: 14 00:12:20 INFO - PROCESS | 19640 | 00:12:20 INFO - PROCESS | 19640 | [#0] ai-astar Cycles:10 Average:113.70 Median:115.00 stddev:2.91 (2.5%) stddev-sans-first:1.13 00:12:20 INFO - PROCESS | 19640 | Values: 106.0 116.0 115.0 116.0 114.0 114.0 113.0 115.0 113.0 115.0 00:12:20 INFO - PROCESS | 19640 | 00:12:20 INFO - PROCESS | 19640 | [#1] audio-beat-detection Cycles:10 Average:136.40 Median:134.50 stddev:19.75 (14.7%) stddev-sans-first:20.50 00:12:20 INFO - PROCESS | 19640 | Values: 148.0 120.0 123.0 179.0 125.0 123.0 144.0 122.0 156.0 124.0 00:12:20 INFO - PROCESS | 19640 | 00:12:20 INFO - PROCESS | 19640 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 00:12:20 INFO - PROCESS | 19640 | Values: 162.0 161.0 161.0 161.0 161.0 161.0 161.0 162.0 161.0 160.0 00:12:20 INFO - PROCESS | 19640 | 00:12:20 INFO - PROCESS | 19640 | [#3] audio-fft Cycles:10 Average:84.10 Median:76.00 stddev:16.33 (21.5%) stddev-sans-first:17.05 00:12:20 INFO - PROCESS | 19640 | Values: 76.0 76.0 76.0 114.0 79.0 76.0 75.0 77.0 116.0 76.0 00:12:20 INFO - PROCESS | 19640 | 00:12:20 INFO - PROCESS | 19640 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:85.00 stddev:17.11 (20.1%) stddev-sans-first:17.61 00:12:20 INFO - PROCESS | 19640 | Values: 84.0 87.0 121.0 85.0 85.0 86.0 115.0 85.0 85.0 125.0 00:12:20 INFO - PROCESS | 19640 | 00:12:20 INFO - PROCESS | 19640 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 00:12:20 INFO - PROCESS | 19640 | Values: 117.0 117.0 117.0 117.0 118.0 116.0 117.0 117.0 117.0 117.0 00:12:20 INFO - PROCESS | 19640 | 00:12:20 INFO - PROCESS | 19640 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:1.75 (1.2%) stddev-sans-first:1.86 00:12:20 INFO - PROCESS | 19640 | Values: 146.0 145.0 146.0 151.0 145.0 145.0 146.0 146.0 146.0 146.0 00:12:20 INFO - PROCESS | 19640 | 00:12:20 INFO - PROCESS | 19640 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 00:12:20 INFO - PROCESS | 19640 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 00:12:20 INFO - PROCESS | 19640 | 00:12:20 INFO - PROCESS | 19640 | [#8] json-parse-financial Cycles:10 Average:65.60 Median:64.50 stddev:4.22 (6.5%) stddev-sans-first:4.47 00:12:20 INFO - PROCESS | 19640 | Values: 65.0 62.0 67.0 63.0 69.0 64.0 76.0 63.0 63.0 64.0 00:12:20 INFO - PROCESS | 19640 | 00:12:20 INFO - PROCESS | 19640 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 00:12:20 INFO - PROCESS | 19640 | Values: 66.0 67.0 67.0 66.0 66.0 66.0 67.0 66.0 67.0 66.0 00:12:20 INFO - PROCESS | 19640 | 00:12:20 INFO - PROCESS | 19640 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:80.50 stddev:3.21 (4.0%) stddev-sans-first:3.33 00:12:20 INFO - PROCESS | 19640 | Values: 79.0 79.0 78.0 86.0 79.0 80.0 87.0 81.0 78.0 82.0 00:12:20 INFO - PROCESS | 19640 | 00:12:20 INFO - PROCESS | 19640 | [#11] stanford-crypto-ccm Cycles:10 Average:136.50 Median:134.00 stddev:13.01 (9.7%) stddev-sans-first:13.51 00:12:20 INFO - PROCESS | 19640 | Values: 129.0 129.0 139.0 141.0 127.0 137.0 130.0 131.0 171.0 131.0 00:12:20 INFO - PROCESS | 19640 | 00:12:20 INFO - PROCESS | 19640 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:167.00 stddev:3.03 (1.8%) stddev-sans-first:3.17 00:12:20 INFO - PROCESS | 19640 | Values: 168.0 165.0 169.0 165.0 169.0 172.0 165.0 166.0 166.0 161.0 00:12:20 INFO - PROCESS | 19640 | 00:12:20 INFO - PROCESS | 19640 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:1.23 (1.9%) stddev-sans-first:1.27 00:12:20 INFO - PROCESS | 19640 | Values: 67.0 66.0 66.0 65.0 69.0 65.0 66.0 65.0 66.0 67.0 00:12:20 INFO - PROCESS | 19640 | -------- Summary: end -------- 00:12:20 INFO - PROCESS | 19640 | 00:12:21 INFO - TEST-INFO | 19640: exit 0 00:12:21 INFO - TEST-OK | kraken | took 63437ms 00:12:21 INFO - SUITE-END | took 1182s 00:12:21 INFO - Completed test suite (00:19:42) 00:12:21 INFO - javascript benchmark 00:12:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2605.3616863868356, 2616.7252438493956, 2653.376456532715, 2656.8671566421676, 2665.5202063628553, 4580.335851301559, 4606.216943119669, 4660.766961651917, 4689.48415674276, 4703.082898834728, 4641.187792136379, 4646.790894409752, 4649.279361698942, 4677.719336839784, 4711.693739906904, 4262.488501379838, 4276.636485898687, 4327.74033557986, 4347.586979236975, 4360.956390436087, 68.26647670777498, 4371.169477799224, 4372.672049596277, 4380.233459144652, 4394.143142087294, 4245.87262382129, 4347.2826983547675, 4377.584129507723, 4380.189664912002, 4383.649308055348, 4325.762083085429, 4355.085432059272, 4355.956440435587, 4395.758233297167, 4406.4271644686305, 4185.497740271187, 4358.106588149421, 4366.650667946588, 4372.081862808814, 4415.0, 4532.773361331921, 4609.331646911212, 4613.100445413146, 4614.8154073837, 4666.2067448533835, 5098.107831129574, 5129.6409251352425, 5162.406323272834, 5179.922301165486, 5186.688798672092, 5195.896082078375, 5212.869678258036, 5219.8434046978655, 5225.895482090375, 5227.424983251839, 4646.721196728207, 4660.720356778605, 4681.485036645966, 4856.660033797635, 4860.927086093747, 5170.870728231825, 5171.120909445403, 5214.426413094557, 5216.686998780086, 5225.634205605571, 4928.28539861718, 4931.876703082452, 4973.776180071905, 4978.751062446936, 4982.60139188864, 4866.099771542276, 4921.212605983025, 4945.4312754033745, 4948.134076536592, 5080.5681517070725, 4889.535494128052, 4911.18965370717, 4913.21388577833, 4921.089598424303, 4959.528844759814, 4958.884251043486, 4959.90080198394, 4966.503349665077, 4970.2793095001025, 5017.623678224075, 4138.813753381105, 4236.343366778155, 4238.470191226097, 4259.254630439661, 4291.892702682396, 3758.3986562149926, 3764.9247015059545, 3773.6981041516615, 3813.9046523836573, 3825.0628595993603, 4346.174226896879, 4362.17118747437, 4363.52001279859, 4404.493483249467, 4407.515173330931, 4913.164761990471, 4937.012597480447, 4996.300517927493, 5038.748062596856, 5051.0, 4272.359146128106, 4274.123804620108, 4297.871063868089, 4333.090051089306, 4338.978305108454, 4515.458145022619, 4517.390152329459, 4524.5927866492175, 4529.91282092302, 4566.589006989387, 4669.673122881366, 4694.765261736968, 4701.623870090384, 4709.62323014158, 4753.1444340019125, 5062.772175252197, 5064.569511591556, 5069.087564238473, 5078.695278283315, 5109.386873575195, 4788.760561971818, 4794.017226468635, 4798.928016079832, 4800.207965685623, 4831.903361932671, 4742.596879265301, 4952.579030782424, 4992.850214493571, 4996.575291100297, 5059.215821547666, 5041.470645582161, 5063.063333283354, 5067.113255180255, 5072.771725272298, 5131.153359695758, 4947.48051454592, 4955.975220124021, 4973.278874563241, 4974.527419895104, 5006.69959802413, 4827.831025914076, 4895.559399654048, 4925.408950925912, 4948.901021979468, 4986.102501549611, 4862.440819305825, 4885.939816964452, 4891.144049791344, 4946.332245147003, 4955.306257124078, 4836.588889944394, 4985.551300382983, 5002.749862506933, 5003.824866129668, 5054.014467178865, 4760.619150468024, 4792.7124372537755, 4803.423589169322, 4822.662413631012, 4829.179039563212], "value": 4599.443435106802, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.8757273399851, 565.5532129617604, 572.0818086970415, 577.5985689945487, 578.9838832848352, 27182.048628297987, 27640.861795691042, 38711.41932871009, 39929.40105898414, 40024.0, 40388.19223615526, 41052.17895642085, 41141.177176456455, 41147.17705645885, 41151.38272925909, 7774.49465784725, 7892.447528672995, 7893.763187104396, 7894.447388682795, 7895.289423951843, 7841.411894107965, 7931.682732690685, 7934.127246002935, 7934.285914267714, 7935.444518883681, 7901.960490197541, 7960.323372513343, 7961.641726122339, 7962.323202527792, 7962.641681124334, 7911.723089691862, 7958.0, 7960.1243863175005, 7963.920360796434, 7965.123836377994, 7881.8029549261155, 7938.603069846542, 7939.285464308182, 7943.642536085832, 7945.364370850318, 37551.624483755084, 37589.24821503582, 37658.24683506342, 37676.0, 37969.0, 3119.1890108571706, 3144.402563512925, 3162.683731626842, 3197.8720851165926, 3333.949805811186, 27804.16587502358, 28102.437951241067, 28546.842746648803, 29281.104383145157, 29492.0, 3512.7541072124964, 3524.682778549917, 3531.1878267998504, 3546.3971124908826, 3559.2347645256395, 264219.0, 265256.0, 265512.0, 266222.6688866563, 266744.0, 27632.171034868985, 50935.745321273156, 51757.0, 51819.481805181465, 51952.740236298574, 33556.83221583877, 33567.49648755271, 33690.49464258038, 33709.83145084259, 33725.0, 13235.742604452562, 13410.530631427853, 13445.386553613433, 13575.389107490186, 13632.318384080756, 11714.297142171497, 11872.584459543874, 11915.344656044, 11931.821022684666, 11934.701632459259, 7806.960965195138, 7883.684652613946, 7941.722039728582, 7943.443958922935, 7947.205279472007, 3115.0966219796005, 3128.6402063762503, 3163.9367212655616, 3174.0636512228484, 3190.7606929479916, 871.3943809052774, 891.7681402835179, 970.7621632700034, 977.5014742481386, 1033.075397519231, 26531.33671658224, 26629.86685066601, 28103.859480702467, 28215.43569128647, 28249.0, 777.6383981448654, 948.5399581203111, 951.110711484764, 951.1439704266215, 962.2206013129388, 13912.513062042983, 14416.13503189812, 14478.420863165566, 14760.778588321184, 14765.40938362475, 362850.0, 366543.0, 370662.0, 371137.0, 371590.0, 47362.0, 47726.28410573844, 47851.76074119607, 47911.28133078007, 47992.0, 1056.2183983851894, 1106.2090605217147, 1130.949107290174, 1150.0856818829052, 1187.6733898177897, 24771.380715482257, 28083.297917552223, 28343.716562834106, 28666.0, 28851.56722649204, 40569.391459128725, 40898.59101408948, 41100.1779964405, 41123.0, 41158.1768364637, 283216.0, 283738.0, 283777.0, 284501.0, 284890.0, 22022.0, 24229.394265143514, 25006.37484062913, 25082.74917250877, 26348.736512635394, 39952.0, 40077.799611001756, 40095.0, 41048.0, 41051.1789764209, 34263.31473370469, 34454.65545344514, 34921.301573968885, 35414.114647134025, 35473.46789798207, 34548.618055278515, 34624.653753462146, 34764.0, 34806.47790283198, 35158.0, 31822.52266216055, 32496.187595310308, 32541.511877322333, 32630.0, 32707.34585308328, 30509.389812204077, 32519.512207316435, 32560.185995350304, 32594.83702581472, 32641.0, 24148.275551733477, 24490.63264051005, 24512.75487245105, 24945.75054249434, 25152.24543263705], "value": 16210.30873258391, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.4937797756839, 372.5752641988134, 372.7483948334874, 373.91773809761844, 389.92591407632545, 12900.741985160303, 13198.472061117567, 13386.567859159675, 14187.574372768808, 14268.785968210484, 16246.350145994145, 16472.670546589092, 17168.656626867454, 17186.570335741613, 17218.913905430487, 4945.901081978358, 5030.7987680492915, 5080.898382032357, 5088.084144853925, 5097.0, 3712.7401081924277, 5866.970665146669, 6205.658688772116, 6229.968850155743, 6324.968375158118, 5790.68151251681, 6112.532992081913, 6207.379262073801, 6232.657203853786, 6244.406781355764, 5972.522198224132, 5972.850678733023, 5979.611325263887, 6004.5196384289375, 6018.789342372996, 5125.846224613268, 5258.81594144207, 5265.605079619006, 5395.136778115482, 5437.293151890229, 20384.694229586567, 21171.68242476364, 21213.257535986166, 21248.681269780966, 21434.57130857374, 1872.419549939523, 1893.7538120044444, 1923.740295060177, 1974.5754662747433, 1996.0818023709112, 12932.935335323264, 13151.86848131516, 13239.0, 13597.824348104356, 13940.233287169303, 1705.7867766529184, 1718.9054601996886, 1727.378143868219, 1766.7173252279574, 1784.2060283173994, 49867.50132498701, 50715.49284507144, 57099.0, 66950.66524667345, 68326.3167368325, 9592.232621390272, 9719.90280097204, 9751.463669498246, 9787.265955053399, 9992.65025724096, 12664.303463309423, 12729.299888506035, 12754.04344674153, 12781.360931953552, 12801.551945681857, 6958.8956165657555, 7012.684429200698, 7021.736087504299, 7138.143422789299, 7212.062431883822, 6507.772227971998, 6617.272100068989, 6621.238557565845, 6650.20197576294, 6696.5647232929705, 5983.940160598426, 6072.089186622042, 6128.3258841527395, 6132.63204207749, 6171.722272497748, 1696.16040060171, 1724.3275122701905, 1748.2866790545083, 1760.7710997570234, 1779.6084861330487, 868.431177578687, 879.872418499314, 899.970566372825, 954.0411886054471, 981.3130808434124, 17056.914715426345, 17312.53749250155, 17544.473665790047, 18204.36284730028, 18240.361587344643, 801.463019776751, 874.3224001398839, 878.449536567958, 883.6156272021562, 904.8054668246359, 8787.560621968876, 9569.473678947585, 9606.27952903535, 9646.372985755903, 9831.410115393099, 412630.9263712618, 421601.0, 430811.0, 431259.0, 433330.0, 81295.0, 85090.0, 85314.0, 85317.57341213254, 85640.0, 916.2303664921498, 930.7853251506714, 938.666773295484, 944.6976967370513, 954.7706106242002, 15879.841201587837, 16055.919720401324, 16122.596935076248, 16189.67620647604, 16343.427980020642, 36942.891713248646, 38361.42457863079, 38441.80779096087, 38710.0, 39604.01979901051, 52534.47465525449, 53885.19172212498, 54284.0, 55154.44845551493, 55711.0, 7560.848783024198, 7737.1102323233545, 7751.147373788766, 7801.141874393926, 7846.411519135973, 7436.888446673413, 11603.013743831869, 12206.511739530319, 12359.443825028075, 12401.13192076546, 10388.948055259676, 10449.477526123816, 10471.581136754445, 10623.256372053882, 10629.8937010632, 8482.915170848213, 10049.346792458467, 10328.586856525655, 10344.844827327446, 10407.58369665205, 10465.843012354668, 10818.783624327627, 10880.564777408816, 10962.123030157409, 10999.23005389647, 6748.122744043243, 6901.930980690129, 7059.929400705927, 7688.231176882267, 7769.184235655175, 10327.586896524055, 10723.356598604109, 10741.838872417076, 10887.292325998786, 11157.386343751172], "value": 8400.483038669332, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1185.12300897336, 1232.3591732299205, 1334.906556541042, 1345.7241265540565, 1709.4444305600675, 6616.603003819768, 7375.852482950338, 7679.57762323072, 7698.34564062054, 7984.520928744279, 7693.7307194248215, 7817.843643127134, 7820.569868657221, 7821.687132514692, 7847.293743563078, 1816.6457540779554, 1885.3966730646198, 1889.0177107903883, 2181.1057466438765, 2208.3485371815354, 1915.1764741161296, 2090.362439455965, 2182.9454263643456, 2186.7813218678166, 2199.14233448955, 2105.4420578546697, 2125.6705210692367, 2132.4775430019668, 2135.487483004076, 2135.6369417199035, 1786.0, 2133.839962002848, 2142.036083762305, 2142.0360837623134, 2146.6350720377573, 2055.9794402055936, 2134.6264403729283, 2135.402087415511, 2146.37755051035, 2150.817180539656, 7787.260210280071, 7829.412794040412, 7830.804229894241, 7832.216778322179, 7845.607719613997, 4578.702384345007, 4692.389989301403, 4709.340692303081, 4719.032598317337, 4725.834595789165, 2812.887484500638, 2872.138358492444, 2924.4589750896153, 2949.911502654924, 2954.793164478487, 869.9260562852165, 886.9246114080311, 895.8342706599235, 911.3028533172129, 912.1471424218377, 10402.47987600617, 10454.268201225917, 10455.895441045568, 10462.058414742709, 10474.895251047468, 3984.7409918355215, 4013.8595149169637, 4131.400946862748, 4137.54487006429, 4345.326474396474, 8921.553922303989, 8926.152015558511, 8926.375153739176, 8943.642254309885, 8952.23905967987, 4751.287306903992, 4759.904801903943, 4762.9523704762505, 4766.928496072562, 4769.856904292877, 3706.09200745819, 3765.9246815063543, 4369.2790689536505, 4375.27807911691, 4376.38730577713, 3623.5289412376223, 3678.4666223397458, 3797.810109494514, 3800.1829606634706, 3801.9354580717445, 1227.5274019502556, 1259.6158171757518, 1261.1109168036512, 1272.45284527654, 1278.2458349573797, 683.8871586188322, 855.4781583234222, 865.312076898867, 865.359633870931, 874.1363174767416, 5160.161910948963, 5381.730913454312, 5382.650127741685, 5461.399246082928, 5559.527440167551, 393.4668524149736, 393.73422939516286, 395.45032404957135, 400.31546056243565, 898.833715762586, 3656.3966945453703, 3877.1470276539117, 3892.824822882977, 3921.9803900980883, 3951.031997160714, 9474.194693450996, 9730.41617502952, 9737.659181928599, 9744.853827192597, 9811.852822207673, 8835.293176545862, 9631.229501639851, 9655.275854310954, 9681.903180968242, 9706.0, 7150.642467876585, 7438.479306448497, 7440.888386674204, 7506.512076714996, 7512.32389084985, 5488.643238189505, 5546.084895992116, 5663.235463212413, 5704.971475142598, 5731.054376028075, 8465.449745766506, 8793.736187914374, 8796.47221166732, 8967.148120928501, 9039.548022598712, 7791.181925897699, 8858.29133669292, 9069.274458043472, 9086.54567271647, 9254.213391861767, 6599.142111525471, 6793.048973143863, 6818.659067046528, 6993.440524758109, 7006.579605223703, 7398.408127349691, 7442.330190282901, 7474.551526908185, 7484.962575187089, 7494.5503269803985, 6815.454763618799, 6830.7609233676585, 6841.110655614738, 6854.725810967506, 7014.579125252501, 6614.239362473377, 6620.635865027277, 6689.698963546554, 6749.865002700017, 6808.183018037669, 6350.523710721806, 6429.807105786646, 6442.323556026738, 6446.258680251831, 6514.609123452608, 4570.291604801261, 4715.457722361835, 4723.929141062956, 4786.95213047865, 4811.663183577116, 4613.700109492872, 4616.792244348945, 4647.186742320013, 4714.104320179155, 4734.431868175841], "value": 4073.102248440072, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.87827990523886, 300.2269156920929, 304.9542568614707, 305.5249087668677, 308.82551358482453, 4720.622350211984, 4797.520247975201, 4811.59101476375, 4825.90348193036, 4848.0, 4723.394046024351, 4725.7164570125815, 4763.7618119093995, 4765.8570242892765, 4784.688995215309, 3545.4327307630783, 3666.808287306623, 3686.7972261525606, 3695.5380577427823, 3698.0754811297174, 3665.890023299305, 3723.2553489302113, 3799.715021373408, 3815.637514436138, 3826.4834247376666, 3680.8773324136264, 3744.0, 3772.9434058489146, 3800.771953682774, 3802.847886084567, 3508.6491350864962, 3711.0907827582146, 3761.210145869357, 3783.375743002415, 3794.2411517696296, 3215.115843143131, 3235.498497732855, 3235.773495855291, 3236.96763032369, 3239.7408207343356, 4634.6524010698995, 4657.790399432034, 4662.062900372777, 4669.34629151919, 4691.225947718623, 4029.37544680575, 4196.265653510654, 4205.474315710536, 4206.221848957953, 4219.38818871265, 4900.975495122537, 4928.778204980748, 4943.8516844494725, 4957.603391728653, 4958.305837182797, 3528.2237907660274, 3563.8396272167815, 3565.841101641977, 3573.4818451324672, 3576.0, 5080.374280230328, 5159.529534082769, 5170.405403378584, 5210.723372773695, 5225.477452254782, 3608.116011577128, 3640.360738464645, 3651.2149887773726, 3653.4702468141973, 3665.1020499977676, 3678.1172518595345, 3694.796786176786, 3703.129764505339, 3707.647773461517, 3708.7033037356946, 4223.472065991751, 4226.154769046203, 4231.624721965373, 4234.301340278881, 4250.978745106255, 4854.0, 4987.007795322777, 4991.276264941564, 5008.624353173526, 5009.44896061433, 1261.958883920769, 1268.422867595242, 1271.8108568488492, 1271.841019872516, 1273.936303184837, 791.9326857217086, 801.5070731500202, 818.9640105266842, 827.9047909490365, 831.6465502161557, 4306.834476659653, 4409.735415875058, 4410.206162890647, 4420.115976804652, 4427.29163333865, 5076.0863044652315, 5107.744612769421, 5111.3355263815465, 5112.590992720573, 5132.153194722829, 4381.9342709859375, 4382.123575284892, 4385.539518350527, 4388.407564978687, 4413.183561041217, 2579.703334116563, 2719.265798234466, 2722.6460560127057, 2727.7135900730536, 2736.822106563067, 4799.848036471291, 4806.327114204014, 4808.326834243208, 4824.710517368969, 4827.831025914076, 5027.497250274943, 5172.586193104543, 5179.2749015137915, 5201.973990130025, 5207.583393328525, 4787.8563643090765, 4795.712257264575, 4800.759962001886, 4816.783244754065, 4836.879078023077, 5046.571041461517, 5063.215201643751, 5070.949290507196, 5078.314427552233, 5117.2835802986865, 4797.584712509748, 4980.925286120783, 4998.350214472095, 5008.899822003613, 5016.523430274118, 4833.758312084452, 4841.8547443576745, 4841.975790121027, 4858.82994095205, 4872.661122661213, 3659.158393569547, 3687.631236876291, 3702.5927148014234, 3729.8321575528626, 3733.170746334331, 4901.921617194725, 4989.326440930573, 5029.0, 5057.696538207572, 5066.898662026812, 4870.878228044327, 4882.438519570295, 4888.853334399973, 4894.388201474816, 4895.681780684244, 4845.8303959362665, 4878.804847806048, 4892.4373697023875, 4908.165611846066, 4930.235813448921, 4814.446338671098, 4836.274558816206, 4840.540148685869, 4848.757562121809, 4848.781804818721, 4771.5467030632035, 4801.542889119828, 4899.456671148577, 5025.673331233458, 5028.170351891897, 4882.267659850909, 4885.169521181337, 4899.510048995215, 4906.460289368238, 4913.164761990399], "value": 3746.3273020576307, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.4280776070493, 626.5426238845646, 638.5881106686187, 719.7768691705571, 737.1854101218155, 12711.237325760449, 13542.593722188325, 13562.118462299944, 13574.185548867075, 13580.320983950813, 12362.505499779998, 12777.10560260782, 12785.808212876815, 12923.806142907864, 12977.675558111052, 747.0400535312111, 771.5370777533491, 771.5602106799126, 774.0324594257179, 774.7288449042818, 776.6621519638946, 779.76996785948, 779.8947142135825, 779.9376049916021, 780.0, 821.720614990903, 822.6997146041712, 824.983500329993, 825.0223485170093, 825.471698113208, 820.8399362124388, 821.2034326703118, 821.2772759971247, 821.4619424277108, 821.880827280047, 596.5347029317139, 600.2287061126432, 600.5645906717639, 600.7506884642868, 600.8287638023143, 13078.0, 13106.344682765919, 13166.473341066347, 13182.340882955814, 13222.801657975042, 13093.607191784262, 13162.934185329108, 13209.0, 13261.73476530464, 13535.255560944144, 10159.695209143738, 10165.288429809916, 10213.897861021444, 10260.74348141295, 10436.425996570186, 3338.382399256145, 3396.015155604872, 3404.9659503405146, 3408.2842603053386, 3482.233908540117, 36080.45879311812, 41528.3770743436, 43160.0, 51750.48249517457, 56975.14537281944, 10739.624113156002, 10812.56749730002, 10965.671029869116, 11086.279391839505, 11304.15218858589, 16728.581785455463, 17353.56616084583, 17368.0, 18156.27374904989, 18168.909155454137, 6953.165620125617, 7001.754938577125, 7004.859902801915, 7079.858402831915, 7207.819804504929, 3711.2763011212555, 3757.1922036762226, 3857.228554289153, 3866.574676785551, 3904.082540602956, 2840.730130637586, 2965.1993961630237, 2965.303153758865, 2966.925826854346, 2968.792184547061, 453.58496975267263, 458.5139751863036, 463.39758314191414, 464.5725932142437, 465.834628706811, 574.0528128587863, 581.5434883616341, 587.216066551159, 629.4492319220682, 639.1084437210066, 8343.499390036617, 8456.0, 8473.322134229247, 8526.360522960802, 8799.340049496186, 570.9200711900337, 573.166043406842, 579.2787978966214, 607.8329607154275, 611.3702886027398, 4061.5126184858004, 4122.979385103115, 4138.627523522837, 4363.781810909441, 4368.563143685606, 87296.563517182, 87400.0, 92699.53650231706, 93062.0, 93152.53423732838, 47093.52906470891, 57375.0, 58699.0, 61978.380216198166, 68095.0, 75926.0, 76332.23667763363, 76920.61539692266, 77305.61347193229, 78516.60741696255, 8542.231199191854, 8682.305415566865, 8793.91206087931, 8841.55792210374, 8883.0, 43232.56767432411, 43652.12695746131, 43906.56093439025, 44478.0, 45073.32390014218, 56386.0, 58155.0, 65333.0, 65921.34078659152, 66175.66912165408, 11255.606053788078, 11535.480903359201, 11568.479418426023, 11721.296722196696, 11888.34614096233, 12823.551175708806, 13098.869011310146, 13515.324233788075, 13693.520726774515, 13806.585802425943, 11418.885811141783, 11667.124965627441, 11753.647390578297, 11990.580329688419, 12075.51697932073, 12206.938965305117, 12364.62906112818, 12719.720353646833, 12839.10126291188, 13137.277449740355, 12064.0, 12576.87423125757, 12625.747485050431, 12906.677333066373, 13101.0, 9318.34771566011, 9378.249740020645, 9548.474833884204, 9655.517224138624, 9675.758106047406, 8923.553822308728, 9016.954915225382, 9169.40398874071, 9731.12419882214, 9979.301448898508], "value": 6495.743570903677, "unit": "score"}], "name": "dromaeo_css", "value": 6292.668064266027, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 115.0, 116.0, 114.0, 114.0, 113.0, 115.0, 113.0, 115.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 120.0, 123.0, 179.0, 125.0, 123.0, 144.0, 122.0, 156.0, 124.0], "value": 136.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 161.0, 161.0, 161.0, 161.0, 162.0, 161.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 114.0, 79.0, 76.0, 75.0, 77.0, 116.0, 76.0], "value": 84.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 121.0, 85.0, 85.0, 86.0, 115.0, 85.0, 85.0, 125.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 151.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 67.0, 63.0, 69.0, 64.0, 76.0, 63.0, 63.0, 64.0], "value": 65.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 66.0, 66.0, 67.0, 66.0, 67.0, 66.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 86.0, 79.0, 80.0, 87.0, 81.0, 78.0, 82.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 129.0, 139.0, 141.0, 127.0, 137.0, 130.0, 131.0, 171.0, 131.0], "value": 136.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 165.0, 169.0, 165.0, 169.0, 172.0, 165.0, 166.0, 166.0, 161.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 65.0, 69.0, 65.0, 66.0, 65.0, 66.0, 67.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1531.5, "alertThreshold": 2.0}]} 00:12:21 INFO - Return code: 0 00:12:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:12:21 INFO - # TBPL SUCCESS # 00:12:21 INFO - Running post-action listener: _resource_record_post_action 00:12:21 INFO - ##### 00:12:21 INFO - ##### Finished run-tests step (success) 00:12:21 INFO - ##### 00:12:21 INFO - Running post-run listener: _resource_record_post_run 00:12:21 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 8192; Write bytes: 540905472; Read time: 20; Write time: 427144 00:12:21 INFO - TinderboxPrint: CPU usage
13.0% 00:12:21 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 20 00:12:21 INFO - TinderboxPrint: I/O write bytes / time
540,905,472 / 427,144 00:12:21 INFO - TinderboxPrint: CPU idle
8,328.8 (87.0%) 00:12:21 INFO - TinderboxPrint: CPU user
1,199.0 (12.5%) 00:12:21 INFO - TinderboxPrint: Swap in / out
0 / 0 00:12:21 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 246845440; Read time: 0; Write time: 201972 00:12:21 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283430912; Read time: 4; Write time: 222196 00:12:22 INFO - Running post-run listener: _upload_blobber_files 00:12:22 INFO - Blob upload gear active. 00:12:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:12:22 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 00:12:22 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'] 00:12:22 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 00:12:22 INFO - (blobuploader) - INFO - Open directory for files ... 00:12:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:12:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:12:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:12:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:12:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:12:23 INFO - (blobuploader) - INFO - Done attempting. 00:12:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:12:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:12:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:12:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:12:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:12:23 INFO - (blobuploader) - INFO - Done attempting. 00:12:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:12:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:12:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:12:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:12:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:12:34 INFO - (blobuploader) - INFO - Done attempting. 00:12:34 INFO - (blobuploader) - INFO - Iteration through files over. 00:12:34 INFO - Return code: 0 00:12:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:12:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:12:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/269cd8e9f83461ef2625a95d5686bc659e43f295d8c1b94c9bab7b29eeaedd798b66f7ff3b5dafff2dc0965d18a57909d4c759fa874aa7a0ea0d5c0c448fca51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/df0af5885c08f787e0c3762562572db4ffd499110d26fca0daec60fe458b14c3556b8436bab5148dacac44b29ba80858c15cf4ce9e57f3778745b7ff4941ddc4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f193a6c163c5aa6827803a9c00af1d80b3d5a2ddb9eaff412a912c366ab7d9a51944266b0bbdddf01abccd5296eb9a87b85b9f6d0d863bf149e1fc9ffa976991"} 00:12:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:12:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:12:34 INFO - Contents: 00:12:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/269cd8e9f83461ef2625a95d5686bc659e43f295d8c1b94c9bab7b29eeaedd798b66f7ff3b5dafff2dc0965d18a57909d4c759fa874aa7a0ea0d5c0c448fca51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/df0af5885c08f787e0c3762562572db4ffd499110d26fca0daec60fe458b14c3556b8436bab5148dacac44b29ba80858c15cf4ce9e57f3778745b7ff4941ddc4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f193a6c163c5aa6827803a9c00af1d80b3d5a2ddb9eaff412a912c366ab7d9a51944266b0bbdddf01abccd5296eb9a87b85b9f6d0d863bf149e1fc9ffa976991"} 00:12:34 INFO - Running post-run listener: copy_logs_to_upload_dir 00:12:34 INFO - Copying logs to upload dir... 00:12:34 INFO - mkdir: /builds/slave/test/build/upload/logs 00:12:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.624367 ========= master_lag: 1.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-07-20 00:12:35.731555) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 00:12:35.734729) ========= 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=14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501 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/269cd8e9f83461ef2625a95d5686bc659e43f295d8c1b94c9bab7b29eeaedd798b66f7ff3b5dafff2dc0965d18a57909d4c759fa874aa7a0ea0d5c0c448fca51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/df0af5885c08f787e0c3762562572db4ffd499110d26fca0daec60fe458b14c3556b8436bab5148dacac44b29ba80858c15cf4ce9e57f3778745b7ff4941ddc4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f193a6c163c5aa6827803a9c00af1d80b3d5a2ddb9eaff412a912c366ab7d9a51944266b0bbdddf01abccd5296eb9a87b85b9f6d0d863bf149e1fc9ffa976991"} build_url:https://queue.taskcluster.net/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011391 build_url: 'https://queue.taskcluster.net/v1/task/Z9d5fDw_Rd6N_bMH_sNVyA/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/269cd8e9f83461ef2625a95d5686bc659e43f295d8c1b94c9bab7b29eeaedd798b66f7ff3b5dafff2dc0965d18a57909d4c759fa874aa7a0ea0d5c0c448fca51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/df0af5885c08f787e0c3762562572db4ffd499110d26fca0daec60fe458b14c3556b8436bab5148dacac44b29ba80858c15cf4ce9e57f3778745b7ff4941ddc4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f193a6c163c5aa6827803a9c00af1d80b3d5a2ddb9eaff412a912c366ab7d9a51944266b0bbdddf01abccd5296eb9a87b85b9f6d0d863bf149e1fc9ffa976991"}' ========= master_lag: 0.15 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 00:12:35.899481) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 00:12:35.899845) ========= 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=14ef55db36f7623abb31b9cf000001d5-1468997508.215055-1704647501 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004396 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 00:12:35.916546) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 00:12:35.916896) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 00:12:35.917255) ========= ========= Total master_lag: 1.26 =========