builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-048 starttime: 1468860559.74 results: success (0) buildid: 20160718083151 builduid: 8a38573466d94db9ae1025546a641135 revision: a321cbe19561bfe6e5b2b4d479f3eb0d84e26f2a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 09:49:19.735865) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 09:49:19.736353) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 09:49:19.736676) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 09:49:19.749162) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 09:49:19.749470) ========= 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=fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-18 09:49:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.8M=0s 2016-07-18 09:49:20 (45.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.074824 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 09:49:19.839710) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 09:49:19.840401) ========= 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=fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014839 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 09:49:19.893446) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 09:49:19.893792) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a321cbe19561bfe6e5b2b4d479f3eb0d84e26f2a --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 a321cbe19561bfe6e5b2b4d479f3eb0d84e26f2a --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=fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-18 09:49:20,286 truncating revision to first 12 chars 2016-07-18 09:49:20,287 Setting DEBUG logging. 2016-07-18 09:49:20,287 attempt 1/10 2016-07-18 09:49:20,287 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a321cbe19561?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 09:49:21,476 unpacking tar archive at: fx-team-a321cbe19561/testing/mozharness/ program finished with exit code 0 elapsedTime=1.610168 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 09:49:21.519118) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 09:49:21.519508) ========= script_repo_revision: a321cbe19561bfe6e5b2b4d479f3eb0d84e26f2a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 09:49:21.519977) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 09:49:21.520304) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 09:49:21.531221) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-18 09:49:21.531582) ========= /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=fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:49:21 INFO - MultiFileLogger online at 20160718 09:49:21 in /builds/slave/test 09:49:21 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 09:49:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:49:21 INFO - {'append_to_log': False, 09:49:21 INFO - 'base_work_dir': '/builds/slave/test', 09:49:21 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 09:49:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:49:21 INFO - 'branch': 'Fx-Team-Non-PGO', 09:49:21 INFO - 'buildbot_json_path': 'buildprops.json', 09:49:21 INFO - 'config_files': ('talos/linux_config.py',), 09:49:21 INFO - 'default_actions': ('clobber', 09:49:21 INFO - 'read-buildbot-config', 09:49:21 INFO - 'download-and-extract', 09:49:21 INFO - 'populate-webroot', 09:49:22 INFO - 'create-virtualenv', 09:49:22 INFO - 'install', 09:49:22 INFO - 'run-tests'), 09:49:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:49:22 INFO - 'download_minidump_stackwalk': True, 09:49:22 INFO - 'download_symbols': 'ondemand', 09:49:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:49:22 INFO - 'tooltool.py': '/tools/tooltool.py', 09:49:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:49:22 INFO - '/tools/misc-python/virtualenv.py')}, 09:49:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:49:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:49:22 INFO - 'installer_path': 'installer.exe', 09:49:22 INFO - 'log_level': 'info', 09:49:22 INFO - 'log_name': 'talos', 09:49:22 INFO - 'log_to_console': True, 09:49:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:49:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:49:22 INFO - 'opt_config_files': (), 09:49:22 INFO - 'pip_index': False, 09:49:22 INFO - 'sps_profile': False, 09:49:22 INFO - 'sps_profile_interval': 0, 09:49:22 INFO - 'suite': 'dromaeojs', 09:49:22 INFO - 'system_bits': '32', 09:49:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:49:22 INFO - 'title': 'talos-linux64-ix-048', 09:49:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:49:22 INFO - 'use_talos_json': True, 09:49:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:49:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:49:22 INFO - 'work_dir': 'build'} 09:49:22 INFO - ##### 09:49:22 INFO - ##### Running clobber step. 09:49:22 INFO - ##### 09:49:22 INFO - Running pre-action listener: _resource_record_pre_action 09:49:22 INFO - Running main action method: clobber 09:49:22 INFO - rmtree: /builds/slave/test/build 09:49:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:49:22 INFO - Running post-action listener: _resource_record_post_action 09:49:22 INFO - ##### 09:49:22 INFO - ##### Finished clobber step (success) 09:49:22 INFO - ##### 09:49:22 INFO - ##### 09:49:22 INFO - ##### Running read-buildbot-config step. 09:49:22 INFO - ##### 09:49:22 INFO - Running pre-action listener: _resource_record_pre_action 09:49:22 INFO - Running main action method: read_buildbot_config 09:49:22 INFO - Using buildbot properties: 09:49:22 INFO - { 09:49:22 INFO - "project": "", 09:49:22 INFO - "product": "firefox", 09:49:22 INFO - "script_repo_revision": "production", 09:49:22 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 09:49:22 INFO - "repository": "", 09:49:22 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 09:49:22 INFO - "buildid": "20160718083151", 09:49:22 INFO - "pgo_build": "False", 09:49:22 INFO - "basedir": "/builds/slave/test", 09:49:22 INFO - "buildnumber": 1998, 09:49:22 INFO - "slavename": "talos-linux64-ix-048", 09:49:22 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:49:22 INFO - "platform": "ubuntu64_hw", 09:49:22 INFO - "scheduler": "tests-fx-team-linux64-talos", 09:49:22 INFO - "branch": "fx-team", 09:49:22 INFO - "repo_path": "integration/fx-team", 09:49:22 INFO - "revision": "a321cbe19561bfe6e5b2b4d479f3eb0d84e26f2a", 09:49:22 INFO - "stage_platform": "linux64", 09:49:22 INFO - "builduid": "8a38573466d94db9ae1025546a641135", 09:49:22 INFO - "slavebuilddir": "test" 09:49:22 INFO - } 09:49:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 09:49:22 INFO - Running post-action listener: _resource_record_post_action 09:49:22 INFO - ##### 09:49:22 INFO - ##### Finished read-buildbot-config step (success) 09:49:22 INFO - ##### 09:49:22 INFO - ##### 09:49:22 INFO - ##### Running download-and-extract step. 09:49:22 INFO - ##### 09:49:22 INFO - Running pre-action listener: _resource_record_pre_action 09:49:22 INFO - Running main action method: download_and_extract 09:49:22 INFO - mkdir: /builds/slave/test/build/tests 09:49:22 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')]} 09:49:22 INFO - https://queue.taskcluster.net/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:49:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:49:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:49:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/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 09:49:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/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 09:49:23 INFO - Downloaded 1361 bytes. 09:49:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:49:23 INFO - Using the following test package requirements: 09:49:23 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:49:23 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:49:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:49:23 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:49:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:49:23 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:49:23 INFO - u'jsshell-linux-x86_64.zip'], 09:49:23 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:49:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:49:23 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:49:23 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:49:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:49:23 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:49:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:49:23 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:49:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:49:23 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:49:23 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:49:23 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:49:23 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')]} 09:49:23 INFO - https://queue.taskcluster.net/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:49:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:49:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:49:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/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 09:49:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/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 09:49:24 INFO - Downloaded 22763363 bytes. 09:49:24 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'] 09:49:24 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 09:49:25 INFO - Return code: 0 09:49:25 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 09:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:49:25 INFO - https://queue.taskcluster.net/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:49:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:49:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:49:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/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 09:49:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/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 09:49:26 INFO - Downloaded 22763363 bytes. 09:49:26 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'] 09:49:26 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 09:49:28 INFO - Return code: 0 09:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:49:28 INFO - https://queue.taskcluster.net/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:49:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:49:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:49:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/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 09:49:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/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 09:49:29 INFO - Downloaded 14403317 bytes. 09:49:29 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'] 09:49:29 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 09:49:29 INFO - Return code: 0 09:49:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:49:29 INFO - https://queue.taskcluster.net/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:49:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:49:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:49:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/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 09:49:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Syd1W6a3TW-fNxE34ukOyQ/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 09:49:30 INFO - Downloaded 57127029 bytes. 09:49:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:49:30 INFO - mkdir: /builds/slave/test/properties 09:49:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:49:30 INFO - Writing to file /builds/slave/test/properties/build_url 09:49:30 INFO - Contents: 09:49:30 INFO - build_url:https://queue.taskcluster.net/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:49:31 INFO - Running post-action listener: _resource_record_post_action 09:49:31 INFO - Running post-action listener: set_extra_try_arguments 09:49:31 INFO - ##### 09:49:31 INFO - ##### Finished download-and-extract step (success) 09:49:31 INFO - ##### 09:49:31 INFO - ##### 09:49:31 INFO - ##### Running populate-webroot step. 09:49:31 INFO - ##### 09:49:31 INFO - Running pre-action listener: _resource_record_pre_action 09:49:31 INFO - Running main action method: populate_webroot 09:49:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:49:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:49:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:49:31 INFO - '--tppagecycles', 09:49:31 INFO - '7'], 09:49:31 INFO - 'tests': ['tsvgm']}}, 09:49:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:49:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:49:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:49:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:49:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:49:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:49:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:31 INFO - 'talos_options': ['--disable-e10s'], 09:49:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:49:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:49:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:31 INFO - 'talos_options': ['--disable-e10s'], 09:49:31 INFO - 'tests': ['damp', 'tps']}, 09:49:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:31 INFO - 'tests': ['damp', 'tps']}, 09:49:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:49:31 INFO - 'tests': ['dromaeo_dom']}, 09:49:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:49:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:49:31 INFO - 'tests': ['basic_compositor_video']}, 09:49:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:49:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:49:31 INFO - 'tests': ['a11yr', 09:49:31 INFO - 'ts_paint', 09:49:31 INFO - 'tpaint', 09:49:31 INFO - 'sessionrestore', 09:49:31 INFO - 'sessionrestore_no_auto_restore', 09:49:31 INFO - 'tabpaint']}, 09:49:31 INFO - 'other-e10s': {'tests': ['a11yr', 09:49:31 INFO - 'ts_paint', 09:49:31 INFO - 'tpaint', 09:49:31 INFO - 'sessionrestore', 09:49:31 INFO - 'sessionrestore_no_auto_restore', 09:49:31 INFO - 'tabpaint']}, 09:49:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:49:31 INFO - 'ts_paint', 09:49:31 INFO - 'tpaint', 09:49:31 INFO - 'sessionrestore', 09:49:31 INFO - 'sessionrestore_no_auto_restore', 09:49:31 INFO - 'tabpaint']}, 09:49:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:49:31 INFO - 'ts_paint', 09:49:31 INFO - 'tpaint', 09:49:31 INFO - 'sessionrestore', 09:49:31 INFO - 'sessionrestore_no_auto_restore', 09:49:31 INFO - 'tabpaint']}, 09:49:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:49:31 INFO - 'tests': ['a11yr', 09:49:31 INFO - 'ts_paint', 09:49:31 INFO - 'tpaint', 09:49:31 INFO - 'sessionrestore', 09:49:31 INFO - 'sessionrestore_no_auto_restore', 09:49:31 INFO - 'tabpaint']}, 09:49:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:49:31 INFO - 'tests': ['a11yr', 09:49:31 INFO - 'ts_paint', 09:49:31 INFO - 'tpaint', 09:49:31 INFO - 'sessionrestore', 09:49:31 INFO - 'sessionrestore_no_auto_restore', 09:49:31 INFO - 'tabpaint']}, 09:49:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:49:31 INFO - 'tests': ['tsvgx', 09:49:31 INFO - 'tsvgr_opacity', 09:49:31 INFO - 'tart', 09:49:31 INFO - 'tscrollx', 09:49:31 INFO - 'cart']}, 09:49:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:49:31 INFO - 'tsvgr_opacity', 09:49:31 INFO - 'tart', 09:49:31 INFO - 'tscrollx', 09:49:31 INFO - 'cart']}, 09:49:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:31 INFO - 'talos_options': ['--disable-e10s'], 09:49:31 INFO - 'tests': ['tp5o']}, 09:49:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:31 INFO - 'tests': ['tp5o']}, 09:49:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:31 INFO - 'talos_options': ['--disable-e10s', 09:49:31 INFO - '--xperf_path', 09:49:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:49:31 INFO - 'tests': ['tp5n']}, 09:49:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:31 INFO - 'talos_options': ['--xperf_path', 09:49:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:49:31 INFO - 'tests': ['tp5n']}}, 09:49:31 INFO - 'talos.zip': {'path': '', 09:49:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:49:31 INFO - Running post-action listener: _resource_record_post_action 09:49:31 INFO - ##### 09:49:31 INFO - ##### Finished populate-webroot step (success) 09:49:31 INFO - ##### 09:49:31 INFO - ##### 09:49:31 INFO - ##### Running create-virtualenv step. 09:49:31 INFO - ##### 09:49:31 INFO - Running pre-action listener: _resource_record_pre_action 09:49:31 INFO - Running main action method: create_virtualenv 09:49:31 INFO - Creating virtualenv /builds/slave/test/build/venv 09:49:31 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 09:49:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:49:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:49:31 INFO - Using real prefix '/usr' 09:49:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:49:31 INFO - Installing distribute.............................................................................................................................................................................................done. 09:49:33 INFO - Installing pip.................done. 09:49:33 INFO - Return code: 0 09:49:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:49:33 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')]} 09:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:33 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')]} 09:49:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:33 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 0x14d0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1791180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f3b0>, '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 0x17902b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1790740>, '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': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', '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 09:49:33 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 09:49:33 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 09:49:33 INFO - Using env: {'DISPLAY': ':0', 09:49:33 INFO - 'HOME': '/home/cltbld', 09:49:33 INFO - 'LANG': 'en_US.UTF-8', 09:49:33 INFO - 'LANGUAGE': 'en_US:en', 09:49:33 INFO - 'LOGNAME': 'cltbld', 09:49:33 INFO - 'MAIL': '/var/mail/cltbld', 09:49:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:33 INFO - 'MOZ_NO_REMOTE': '1', 09:49:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:33 INFO - 'NO_EM_RESTART': '1', 09:49:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:33 INFO - 'PWD': '/builds/slave/test', 09:49:33 INFO - 'SHELL': '/bin/bash', 09:49:33 INFO - 'SHLVL': '1', 09:49:33 INFO - 'TERM': 'linux', 09:49:33 INFO - 'TMOUT': '86400', 09:49:33 INFO - 'USER': 'cltbld', 09:49:33 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', 09:49:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:33 INFO - '_': '/tools/buildbot/bin/python'} 09:49:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:34 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 09:49:34 INFO - Cleaning up... 09:49:34 INFO - Return code: 0 09:49:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:49:34 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')]} 09:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:34 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')]} 09:49:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:34 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 0x14d0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1791180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f3b0>, '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 0x17902b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1790740>, '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': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', '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 09:49:34 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 09:49:34 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 09:49:34 INFO - Using env: {'DISPLAY': ':0', 09:49:34 INFO - 'HOME': '/home/cltbld', 09:49:34 INFO - 'LANG': 'en_US.UTF-8', 09:49:34 INFO - 'LANGUAGE': 'en_US:en', 09:49:34 INFO - 'LOGNAME': 'cltbld', 09:49:34 INFO - 'MAIL': '/var/mail/cltbld', 09:49:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:34 INFO - 'MOZ_NO_REMOTE': '1', 09:49:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:34 INFO - 'NO_EM_RESTART': '1', 09:49:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:34 INFO - 'PWD': '/builds/slave/test', 09:49:34 INFO - 'SHELL': '/bin/bash', 09:49:34 INFO - 'SHLVL': '1', 09:49:34 INFO - 'TERM': 'linux', 09:49:34 INFO - 'TMOUT': '86400', 09:49:34 INFO - 'USER': 'cltbld', 09:49:34 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', 09:49:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:34 INFO - '_': '/tools/buildbot/bin/python'} 09:49:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:34 INFO - Downloading/unpacking psutil>=3.1.1 09:49:34 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 09:49:34 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 09:49:34 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 09:49:34 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 09:49:34 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 09:49:34 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 09:49:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:49:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:49:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:49:36 INFO - Installing collected packages: psutil 09:49:36 INFO - Running setup.py install for psutil 09:49:36 INFO - building 'psutil._psutil_linux' extension 09:49:36 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 09:49:36 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 09:49:36 INFO - building 'psutil._psutil_posix' extension 09:49:36 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 09:49:36 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 09:49:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:49:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:49:36 INFO - Successfully installed psutil 09:49:36 INFO - Cleaning up... 09:49:37 INFO - Return code: 0 09:49:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:49:37 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')]} 09:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:37 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')]} 09:49:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:37 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 0x14d0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1791180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f3b0>, '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 0x17902b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1790740>, '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': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', '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 09:49:37 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 09:49:37 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 09:49:37 INFO - Using env: {'DISPLAY': ':0', 09:49:37 INFO - 'HOME': '/home/cltbld', 09:49:37 INFO - 'LANG': 'en_US.UTF-8', 09:49:37 INFO - 'LANGUAGE': 'en_US:en', 09:49:37 INFO - 'LOGNAME': 'cltbld', 09:49:37 INFO - 'MAIL': '/var/mail/cltbld', 09:49:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:37 INFO - 'MOZ_NO_REMOTE': '1', 09:49:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:37 INFO - 'NO_EM_RESTART': '1', 09:49:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:37 INFO - 'PWD': '/builds/slave/test', 09:49:37 INFO - 'SHELL': '/bin/bash', 09:49:37 INFO - 'SHLVL': '1', 09:49:37 INFO - 'TERM': 'linux', 09:49:37 INFO - 'TMOUT': '86400', 09:49:37 INFO - 'USER': 'cltbld', 09:49:37 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', 09:49:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:37 INFO - '_': '/tools/buildbot/bin/python'} 09:49:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:49:37 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 09:49:37 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 09:49:37 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 09:49:37 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 09:49:37 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 09:49:37 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 09:49:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:49:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:49:39 INFO - Installing collected packages: mozsystemmonitor 09:49:39 INFO - Running setup.py install for mozsystemmonitor 09:49:39 INFO - Successfully installed mozsystemmonitor 09:49:39 INFO - Cleaning up... 09:49:39 INFO - Return code: 0 09:49:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:49:39 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')]} 09:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:39 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')]} 09:49:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:39 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 0x14d0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1791180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f3b0>, '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 0x17902b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1790740>, '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': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', '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 09:49:39 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 09:49:39 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 09:49:39 INFO - Using env: {'DISPLAY': ':0', 09:49:39 INFO - 'HOME': '/home/cltbld', 09:49:39 INFO - 'LANG': 'en_US.UTF-8', 09:49:39 INFO - 'LANGUAGE': 'en_US:en', 09:49:39 INFO - 'LOGNAME': 'cltbld', 09:49:39 INFO - 'MAIL': '/var/mail/cltbld', 09:49:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:39 INFO - 'MOZ_NO_REMOTE': '1', 09:49:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:39 INFO - 'NO_EM_RESTART': '1', 09:49:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:39 INFO - 'PWD': '/builds/slave/test', 09:49:39 INFO - 'SHELL': '/bin/bash', 09:49:39 INFO - 'SHLVL': '1', 09:49:39 INFO - 'TERM': 'linux', 09:49:39 INFO - 'TMOUT': '86400', 09:49:39 INFO - 'USER': 'cltbld', 09:49:39 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', 09:49:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:39 INFO - '_': '/tools/buildbot/bin/python'} 09:49:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:39 INFO - Downloading/unpacking jsonschema==2.5.1 09:49:39 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 09:49:39 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 09:49:39 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 09:49:39 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 09:49:39 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 09:49:39 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 09:49:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:49:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:49:41 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 09:49:41 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 09:49:41 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 09:49:41 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 09:49:41 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 09:49:41 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 09:49:41 INFO - Downloading functools32-3.2.3-2.tar.gz 09:49:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:49:41 INFO - warning: no files found matching '*.txt' 09:49:41 INFO - no previously-included directories found matching 'build' 09:49:41 INFO - no previously-included directories found matching 'dist' 09:49:41 INFO - no previously-included directories found matching '.git*' 09:49:41 INFO - Installing collected packages: jsonschema, functools32 09:49:41 INFO - Running setup.py install for functools32 09:49:41 INFO - warning: no files found matching '*.txt' 09:49:41 INFO - no previously-included directories found matching 'build' 09:49:41 INFO - no previously-included directories found matching 'dist' 09:49:41 INFO - no previously-included directories found matching '.git*' 09:49:41 INFO - Successfully installed jsonschema functools32 09:49:41 INFO - Cleaning up... 09:49:41 INFO - Return code: 0 09:49:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:49:41 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')]} 09:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:41 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')]} 09:49:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:41 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 0x14d0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1791180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f3b0>, '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 0x17902b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1790740>, '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': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', '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 09:49:41 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 09:49:41 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 09:49:41 INFO - Using env: {'DISPLAY': ':0', 09:49:41 INFO - 'HOME': '/home/cltbld', 09:49:41 INFO - 'LANG': 'en_US.UTF-8', 09:49:41 INFO - 'LANGUAGE': 'en_US:en', 09:49:41 INFO - 'LOGNAME': 'cltbld', 09:49:41 INFO - 'MAIL': '/var/mail/cltbld', 09:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:41 INFO - 'MOZ_NO_REMOTE': '1', 09:49:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:41 INFO - 'NO_EM_RESTART': '1', 09:49:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:41 INFO - 'PWD': '/builds/slave/test', 09:49:41 INFO - 'SHELL': '/bin/bash', 09:49:41 INFO - 'SHLVL': '1', 09:49:41 INFO - 'TERM': 'linux', 09:49:41 INFO - 'TMOUT': '86400', 09:49:41 INFO - 'USER': 'cltbld', 09:49:41 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', 09:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:41 INFO - '_': '/tools/buildbot/bin/python'} 09:49:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:42 INFO - Downloading/unpacking blobuploader==1.2.4 09:49:42 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 09:49:42 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 09:49:42 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 09:49:42 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 09:49:42 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 09:49:42 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 09:49:44 INFO - Downloading blobuploader-1.2.4.tar.gz 09:49:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:49:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:49:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:44 INFO - Downloading docopt-0.6.1.tar.gz 09:49:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:49:44 INFO - Installing collected packages: blobuploader, requests, docopt 09:49:44 INFO - Running setup.py install for blobuploader 09:49:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:49:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:49:44 INFO - Running setup.py install for requests 09:49:45 INFO - Running setup.py install for docopt 09:49:45 INFO - Successfully installed blobuploader requests docopt 09:49:45 INFO - Cleaning up... 09:49:45 INFO - Return code: 0 09:49:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:49:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:45 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 0x14d0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1791180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f3b0>, '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 0x17902b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1790740>, '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': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', '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 09:49:45 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 09:49:45 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 09:49:45 INFO - Using env: {'DISPLAY': ':0', 09:49:45 INFO - 'HOME': '/home/cltbld', 09:49:45 INFO - 'LANG': 'en_US.UTF-8', 09:49:45 INFO - 'LANGUAGE': 'en_US:en', 09:49:45 INFO - 'LOGNAME': 'cltbld', 09:49:45 INFO - 'MAIL': '/var/mail/cltbld', 09:49:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:45 INFO - 'MOZ_NO_REMOTE': '1', 09:49:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:45 INFO - 'NO_EM_RESTART': '1', 09:49:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:45 INFO - 'PWD': '/builds/slave/test', 09:49:45 INFO - 'SHELL': '/bin/bash', 09:49:45 INFO - 'SHLVL': '1', 09:49:45 INFO - 'TERM': 'linux', 09:49:45 INFO - 'TMOUT': '86400', 09:49:45 INFO - 'USER': 'cltbld', 09:49:45 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', 09:49:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:45 INFO - '_': '/tools/buildbot/bin/python'} 09:49:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:49:45 INFO - Running setup.py (path:/tmp/pip-lPcEZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:49:45 INFO - Running setup.py (path:/tmp/pip-QhZDpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:49:45 INFO - Running setup.py (path:/tmp/pip-tbXeOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:49:45 INFO - Running setup.py (path:/tmp/pip-Sp4Yxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:49:46 INFO - Running setup.py (path:/tmp/pip-wYxRzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:49:46 INFO - Running setup.py (path:/tmp/pip-7ILe7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:49:46 INFO - Running setup.py (path:/tmp/pip-754cnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:49:46 INFO - Running setup.py (path:/tmp/pip-ENjguk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:49:46 INFO - Running setup.py (path:/tmp/pip-y8H1XH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:49:46 INFO - Running setup.py (path:/tmp/pip-KIGQWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:49:46 INFO - Running setup.py (path:/tmp/pip-9dmksX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:49:46 INFO - Running setup.py (path:/tmp/pip-NM3kmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:49:46 INFO - Running setup.py (path:/tmp/pip-HbHQp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:49:46 INFO - Running setup.py (path:/tmp/pip-XmfOAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:46 INFO - Running setup.py (path:/tmp/pip-g33kcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:49:46 INFO - Running setup.py (path:/tmp/pip-SJ1Gru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:49:46 INFO - Running setup.py (path:/tmp/pip-m3YT2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:49:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:49:46 INFO - Running setup.py install for manifestparser 09:49:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:49:47 INFO - Running setup.py install for mozcrash 09:49:47 INFO - Running setup.py install for mozdebug 09:49:47 INFO - Running setup.py install for mozdevice 09:49:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:49:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:49:47 INFO - Running setup.py install for mozfile 09:49:47 INFO - Running setup.py install for mozhttpd 09:49:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:49:47 INFO - Running setup.py install for mozinfo 09:49:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:49:47 INFO - Running setup.py install for mozInstall 09:49:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:49:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:49:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:49:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:49:47 INFO - Running setup.py install for mozleak 09:49:47 INFO - Running setup.py install for mozlog 09:49:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:49:47 INFO - Running setup.py install for moznetwork 09:49:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:49:48 INFO - Running setup.py install for mozprocess 09:49:48 INFO - Running setup.py install for mozprofile 09:49:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:49:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:49:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:49:48 INFO - Running setup.py install for mozrunner 09:49:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:49:48 INFO - Running setup.py install for mozscreenshot 09:49:48 INFO - Running setup.py install for moztest 09:49:48 INFO - Running setup.py install for mozversion 09:49:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:49:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:49:48 INFO - Cleaning up... 09:49:48 INFO - Return code: 0 09:49:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:48 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')]} 09:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:48 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')]} 09:49:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:48 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 0x14d0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1791180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f3b0>, '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 0x17902b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1790740>, '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': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', '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 09:49:48 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 09:49:48 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 09:49:48 INFO - Using env: {'DISPLAY': ':0', 09:49:48 INFO - 'HOME': '/home/cltbld', 09:49:48 INFO - 'LANG': 'en_US.UTF-8', 09:49:48 INFO - 'LANGUAGE': 'en_US:en', 09:49:48 INFO - 'LOGNAME': 'cltbld', 09:49:48 INFO - 'MAIL': '/var/mail/cltbld', 09:49:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:48 INFO - 'MOZ_NO_REMOTE': '1', 09:49:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:48 INFO - 'NO_EM_RESTART': '1', 09:49:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:48 INFO - 'PWD': '/builds/slave/test', 09:49:48 INFO - 'SHELL': '/bin/bash', 09:49:48 INFO - 'SHLVL': '1', 09:49:48 INFO - 'TERM': 'linux', 09:49:48 INFO - 'TMOUT': '86400', 09:49:48 INFO - 'USER': 'cltbld', 09:49:48 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', 09:49:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:48 INFO - '_': '/tools/buildbot/bin/python'} 09:49:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:49:48 INFO - Running setup.py (path:/tmp/pip-4ztERk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:49:49 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)) 09:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:49:49 INFO - Running setup.py (path:/tmp/pip-2QpFl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:49:49 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)) 09:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:49:49 INFO - Running setup.py (path:/tmp/pip-DB_9zJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:49:49 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)) 09:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:49:49 INFO - Running setup.py (path:/tmp/pip-p017J1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:49:49 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)) 09:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:49:49 INFO - Running setup.py (path:/tmp/pip-YNxWvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:49:49 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)) 09:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:49:49 INFO - Running setup.py (path:/tmp/pip-5j8pva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:49:49 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)) 09:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:49:49 INFO - Running setup.py (path:/tmp/pip-hZcooD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:49:49 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)) 09:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:49:49 INFO - Running setup.py (path:/tmp/pip-YSfiGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:49:49 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)) 09:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:49:49 INFO - Running setup.py (path:/tmp/pip-2ui3zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:49:49 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)) 09:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:49:49 INFO - Running setup.py (path:/tmp/pip-3LjQX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:49:49 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)) 09:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:49:49 INFO - Running setup.py (path:/tmp/pip-rXDDUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:49:49 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)) 09:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:49:49 INFO - Running setup.py (path:/tmp/pip-27CRPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:49:49 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)) 09:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:49:49 INFO - Running setup.py (path:/tmp/pip-yPSyq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:49:49 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)) 09:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:49:49 INFO - Running setup.py (path:/tmp/pip-YLARa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:49:49 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)) 09:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:49 INFO - Running setup.py (path:/tmp/pip-qIaE2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:50 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)) 09:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:49:50 INFO - Running setup.py (path:/tmp/pip-VhlwEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:49:50 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)) 09:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:49:50 INFO - Running setup.py (path:/tmp/pip-2Qpkqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:49:50 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)) 09:49:50 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)) 09:49:50 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)) 09:49:50 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)) 09:49:50 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)) 09:49:50 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)) 09:49:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:49:50 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 09:49:50 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 09:49:50 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 09:49:50 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 09:49:50 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 09:49:50 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 09:49:52 INFO - Downloading blessings-1.6.tar.gz 09:49:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:49:52 INFO - Installing collected packages: blessings 09:49:52 INFO - Running setup.py install for blessings 09:49:52 INFO - Successfully installed blessings 09:49:52 INFO - Cleaning up... 09:49:52 INFO - Return code: 0 09:49:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:49:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:49:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:49:52 INFO - Reading from file tmpfile_stdout 09:49:52 INFO - Current package versions: 09:49:52 INFO - argparse == 1.2.1 09:49:52 INFO - blessings == 1.6 09:49:52 INFO - blobuploader == 1.2.4 09:49:52 INFO - docopt == 0.6.1 09:49:52 INFO - functools32 == 3.2.3-2 09:49:52 INFO - jsonschema == 2.5.1 09:49:52 INFO - manifestparser == 1.1 09:49:52 INFO - mozInstall == 1.12 09:49:52 INFO - mozcrash == 0.17 09:49:52 INFO - mozdebug == 0.1 09:49:52 INFO - mozdevice == 0.48 09:49:52 INFO - mozfile == 1.2 09:49:52 INFO - mozhttpd == 0.7 09:49:52 INFO - mozinfo == 0.9 09:49:52 INFO - mozleak == 0.1 09:49:52 INFO - mozlog == 3.2 09:49:52 INFO - moznetwork == 0.27 09:49:52 INFO - mozprocess == 0.23 09:49:52 INFO - mozprofile == 0.28 09:49:52 INFO - mozrunner == 6.12 09:49:52 INFO - mozscreenshot == 0.1 09:49:52 INFO - mozsystemmonitor == 0.3 09:49:52 INFO - moztest == 0.7 09:49:52 INFO - mozversion == 1.4 09:49:52 INFO - psutil == 3.1.1 09:49:52 INFO - requests == 1.2.3 09:49:52 INFO - wsgiref == 0.1.2 09:49:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:49:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:49:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:52 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 0x14d0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1791180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f3b0>, '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 0x17902b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1790740>, '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': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', '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 09:49:52 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 09:49:52 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 09:49:52 INFO - Using env: {'DISPLAY': ':0', 09:49:52 INFO - 'HOME': '/home/cltbld', 09:49:52 INFO - 'LANG': 'en_US.UTF-8', 09:49:52 INFO - 'LANGUAGE': 'en_US:en', 09:49:52 INFO - 'LOGNAME': 'cltbld', 09:49:52 INFO - 'MAIL': '/var/mail/cltbld', 09:49:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:52 INFO - 'MOZ_NO_REMOTE': '1', 09:49:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:52 INFO - 'NO_EM_RESTART': '1', 09:49:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:52 INFO - 'PWD': '/builds/slave/test', 09:49:52 INFO - 'SHELL': '/bin/bash', 09:49:52 INFO - 'SHLVL': '1', 09:49:52 INFO - 'TERM': 'linux', 09:49:52 INFO - 'TMOUT': '86400', 09:49:52 INFO - 'USER': 'cltbld', 09:49:52 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', 09:49:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:52 INFO - '_': '/tools/buildbot/bin/python'} 09:49:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:52 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)) 09:49:52 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)) 09:49:52 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)) 09:49:52 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)) 09:49:52 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)) 09:49:52 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)) 09:49:52 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)) 09:49:52 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)) 09:49:52 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)) 09:49:52 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)) 09:49:52 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)) 09:49:52 INFO - Cleaning up... 09:49:52 INFO - Return code: 0 09:49:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:49:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:49:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:49:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:52 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 0x14d0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16338a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1791180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f3b0>, '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 0x17902b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1790740>, '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': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', '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 09:49:52 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 09:49:52 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 09:49:52 INFO - Using env: {'DISPLAY': ':0', 09:49:52 INFO - 'HOME': '/home/cltbld', 09:49:52 INFO - 'LANG': 'en_US.UTF-8', 09:49:52 INFO - 'LANGUAGE': 'en_US:en', 09:49:52 INFO - 'LOGNAME': 'cltbld', 09:49:52 INFO - 'MAIL': '/var/mail/cltbld', 09:49:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:52 INFO - 'MOZ_NO_REMOTE': '1', 09:49:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:52 INFO - 'NO_EM_RESTART': '1', 09:49:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:52 INFO - 'PWD': '/builds/slave/test', 09:49:52 INFO - 'SHELL': '/bin/bash', 09:49:52 INFO - 'SHLVL': '1', 09:49:52 INFO - 'TERM': 'linux', 09:49:52 INFO - 'TMOUT': '86400', 09:49:52 INFO - 'USER': 'cltbld', 09:49:52 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', 09:49:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:52 INFO - '_': '/tools/buildbot/bin/python'} 09:49:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:49:52 INFO - Cleaning up... 09:49:52 INFO - Return code: 0 09:49:52 INFO - Running post-action listener: _resource_record_post_action 09:49:52 INFO - Running post-action listener: _start_resource_monitoring 09:49:53 INFO - Starting resource monitoring. 09:49:53 INFO - ##### 09:49:53 INFO - ##### Finished create-virtualenv step (success) 09:49:53 INFO - ##### 09:49:53 INFO - ##### 09:49:53 INFO - ##### Running install step. 09:49:53 INFO - ##### 09:49:53 INFO - Running pre-action listener: _resource_record_pre_action 09:49:53 INFO - Running main action method: install 09:49:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:49:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:49:53 INFO - Reading from file tmpfile_stdout 09:49:53 INFO - Detecting whether we're running mozinstall >=1.0... 09:49:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:49:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:49:53 INFO - Reading from file tmpfile_stdout 09:49:53 INFO - Output received: 09:49:53 INFO - Usage: mozinstall [options] installer 09:49:53 INFO - Options: 09:49:53 INFO - -h, --help show this help message and exit 09:49:53 INFO - -d DEST, --destination=DEST 09:49:53 INFO - Directory to install application into. [default: 09:49:53 INFO - "/builds/slave/test"] 09:49:53 INFO - --app=APP Application being installed. [default: firefox] 09:49:53 INFO - mkdir: /builds/slave/test/build/application 09:49:53 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'] 09:49:53 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 09:50:09 INFO - Reading from file tmpfile_stdout 09:50:09 INFO - Output received: 09:50:09 INFO - /builds/slave/test/build/application/firefox/firefox 09:50:09 INFO - Running post-action listener: _resource_record_post_action 09:50:09 INFO - ##### 09:50:09 INFO - ##### Finished install step (success) 09:50:09 INFO - ##### 09:50:09 INFO - ##### 09:50:09 INFO - ##### Running run-tests step. 09:50:09 INFO - ##### 09:50:09 INFO - Running pre-action listener: _resource_record_pre_action 09:50:09 INFO - Running main action method: run_tests 09:50:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:50:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:50:09 INFO - Python 2.7.3 09:50:09 INFO - Return code: 0 09:50:09 INFO - grabbing minidump binary from tooltool 09:50:09 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')]} 09:50:09 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 0x178f3b0>, '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 0x17902b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1790740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:50:09 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 09:50:09 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 09:50:09 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 09:50:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:50:09 INFO - Return code: 0 09:50:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:50:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:50:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:50:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:50:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:50:09 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/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 09:50:09 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/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-048 --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 09:50:09 INFO - Using env: {'DISPLAY': ':0', 09:50:09 INFO - 'HOME': '/home/cltbld', 09:50:09 INFO - 'LANG': 'en_US.UTF-8', 09:50:09 INFO - 'LANGUAGE': 'en_US:en', 09:50:09 INFO - 'LOGNAME': 'cltbld', 09:50:09 INFO - 'MAIL': '/var/mail/cltbld', 09:50:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:50:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:50:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:50:09 INFO - 'MOZ_NO_REMOTE': '1', 09:50:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:50:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:50:09 INFO - 'NO_EM_RESTART': '1', 09:50:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:50:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:50:09 INFO - 'PWD': '/builds/slave/test', 09:50:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:50:09 INFO - 'SHELL': '/bin/bash', 09:50:09 INFO - 'SHLVL': '1', 09:50:09 INFO - 'TERM': 'linux', 09:50:09 INFO - 'TMOUT': '86400', 09:50:09 INFO - 'USER': 'cltbld', 09:50:09 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699', 09:50:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:50:09 INFO - '_': '/tools/buildbot/bin/python'} 09:50:09 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/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 09:50:10 INFO - mozversion application_buildid: 20160718083151 09:50:10 INFO - mozversion application_changeset: a321cbe19561bfe6e5b2b4d479f3eb0d84e26f2a 09:50:10 INFO - mozversion application_display_name: Nightly 09:50:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:50:10 INFO - mozversion application_name: Firefox 09:50:10 INFO - mozversion application_remotingname: firefox 09:50:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:50:10 INFO - mozversion application_vendor: Mozilla 09:50:10 INFO - mozversion application_version: 50.0a1 09:50:10 INFO - mozversion platform_buildid: 20160718083151 09:50:10 INFO - mozversion platform_changeset: a321cbe19561bfe6e5b2b4d479f3eb0d84e26f2a 09:50:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:50:10 INFO - mozversion platform_version: 50.0a1 09:50:10 INFO - using testdate: 1468860610 09:50:10 INFO - actual date: 1468860610 09:50:10 INFO - starting webserver on 'localhost:46588' 09:50:10 INFO - SUITE-START | Running 2 tests 09:50:10 INFO - TEST-START | dromaeo_css 09:50:10 INFO - operating with platform_type : linux_ 09:50:10 INFO - Initialising browser for dromaeo_css test... 09:50:10 INFO - TEST-INFO | started process 29490 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdd6BSa/profile http://localhost:46588/getInfo.html) 09:50:14 INFO - PROCESS | 29490 | __metrics Screen width/height:1600/1200 09:50:14 INFO - PROCESS | 29490 | colorDepth:24 09:50:14 INFO - PROCESS | 29490 | Browser inner width/height: 1024/697 09:50:14 INFO - PROCESS | 29490 | __metrics 09:50:15 INFO - PROCESS | 29490 | 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" 09:50:15 INFO - PROCESS | 29490 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:50:15 INFO - PROCESS | 29490 | console.error: PushService: 09:50:15 INFO - PROCESS | 29490 | stateChangeProcessEnqueue: Error transitioning state 09:50:15 INFO - PROCESS | 29490 | UnknownError 09:50:16 INFO - TEST-INFO | 29490: exit 0 09:50:16 INFO - Browser initialized. 09:50:16 INFO - Running cycle 1/1 for dromaeo_css test... 09:50:16 INFO - TEST-INFO | started process 29570 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdd6BSa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:50:28 INFO - PROCESS | 29570 | RSS: Main: 159494144 09:50:28 INFO - PROCESS | 29570 | 09:51:19 INFO - PROCESS | 29570 | 1468860679574 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] 09:53:32 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:46588/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46588/tests/dromaeo/cssquery-ext.html) 09:53:33 INFO - PROCESS | 29570 | RSS: Main: 278425600 09:53:33 INFO - PROCESS | 29570 | 09:56:36 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:46588/tests/dromaeo/cssquery-ext.html (next: http://localhost:46588/tests/dromaeo/cssquery-jquery.html) 09:56:37 INFO - PROCESS | 29570 | RSS: Main: 249262080 09:56:37 INFO - PROCESS | 29570 | 09:59:39 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:46588/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46588/tests/dromaeo/cssquery-mootools.html) 09:59:39 INFO - PROCESS | 29570 | RSS: Main: 244801536 09:59:39 INFO - PROCESS | 29570 | 10:02:42 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:46588/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46588/tests/dromaeo/cssquery-prototype.html) 10:02:42 INFO - PROCESS | 29570 | RSS: Main: 245465088 10:02:42 INFO - PROCESS | 29570 | 10:05:45 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:46588/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46588/tests/dromaeo/cssquery-yui.html) 10:05:46 INFO - PROCESS | 29570 | RSS: Main: 222445568 10:05:46 INFO - PROCESS | 29570 | 10:08:49 INFO - PROCESS | 29570 | Cycle 1(1): loaded http://localhost:46588/tests/dromaeo/cssquery-yui.html (next: http://localhost:46588/tests/dromaeo/cssquery-dojo.html) 10:08:49 INFO - PROCESS | 29570 | RSS: Main: 280018944 10:08:49 INFO - PROCESS | 29570 | 10:08:49 INFO - PROCESS | 29570 | __start_tp_report 10:08:49 INFO - PROCESS | 29570 | _x_x_mozilla_page_load 10:08:49 INFO - PROCESS | 29570 | _x_x_mozilla_page_load_details 10:08:49 INFO - PROCESS | 29570 | |i|pagename|runs| 10:08:49 INFO - PROCESS | 29570 | |0;dojo.html;2636.156429942418;2658.322127857396;2696.2854843466484;2702.770264527514;2713.2131681812275;4611.74635395054;4691.601213896814;4749.572538471537;4821.590164835989;4869.683470574415;4697.1075495655805;4707.90584188316;4713.269443236295;4747.52524747526;4769.379980602517;4119.814608342632;4273.529911709709;4373;4402.031553058322;4511.887202819939;4431.26884064129;4470.597646211841;4484.8206071757095;4497.775111244441;4510.571495707903;4285.335772955165;4345.9348109778375;4346.76092814895;4353.738775673438;4359.738415695068;4305.870823875289;4309.375140604595;4318.417013703172;4380.956190438087;4493.5281795411665;4327.0913108247305;4340.457442819647;4344.326629372421;4378.430803995492;4395.230834603932;4665.043666048452;4675.13510000651;4726.810927562893;4736.668433209677;4773.665843390964;5127.179651255781;5143.151380022292;5146.073706732786;5193.714345710984;5197.8960420791755;5148.124818780816;5164.302819119408;5174.637775355726;5177.197534382177;5213.139831927728;4654.464736555272;4731.905361892743;4737.123632128068;4838.927416088762;4882.658213925062;5288.867778305573;5301.54936830366;5306.681599103988;5313.256144139824;5315.734213289397;5087.465816089331;5092.388913330424;5096.082705113041;5096.286519887145;5098.668586541863;4929.06347793918;5101.7194054327365;5127.102757017507;5145.202493613496;5168.7415629218385;5069.7465126744255;5079.288899554065;5093.465186155475;5096.159133742965;5129.589632829439;5061.5950723942;5070.847874563769;5075.670081444694;5077.949220507748;5086.059079070384;4269.914601707948;4289.463817022872;4290.742555446684;4353.934690979638;4358;3802.372608519563;3804.8478060877255;3817.1220619257724;3838;3843.7693738375788;4361.258586040381;4362.454693163355;4364.192624364503;4366.519682834885;4368.759718215465;5101.770420331094;5141.305923700327;5143.254228136974;5152;5199.506046925536;4395.428594282723;4404.339349097661;4412.205802955435;4448.955510444919;4461.843835465743;4502.279635258408;4511.210538155809;4648.6745927785405;4654.953450465452;4655.4878963314;4805.783739731651;4818.759062046954;4822.662413631152;4836.395450568679;4849.95150048495;5129.256257842667;5140.717260550706;5143.794248230029;5149.124648809786;5157.716325602128;4631.745254010927;4733.479317275166;4796.544328288807;4823.5658790709;4877.439094504178;5035.043341765053;5061.544460998528;5068.695878247317;5082.085224659597;5118.872028199325;5105.336306280308;5124.282600436017;5129.948700513097;5139.357580302462;5183.533481986639;4840.636952228668;4873.951260487492;4904.779284932115;4993.600511958962;4993.700377977333;4946;4961.479044700254;4965.627577931597;4968.801247950041;4999.675021123615;4866;4883.072216278895;4932.679375840559;4933.186024305949;4965.280034395065;4913.656044077024;4917.188663870565;4963.478834722447;4980.526849949394;4981.950180498148;4862.805487780591;4882.658213924991;4887;4920.434150072644;4926.82756103549 10:08:49 INFO - PROCESS | 29570 | |1;ext.html;570.5093619487241;572.1046562130266;581.6946103295769;582.350678992923;586.2905883880503;26945.19164425068;38874.805625971865;40257.396139057935;40363.394549081786;44125.33811992814;39402.605973940255;39873.20253594926;39873.40189897154;39916;40003.59996400035;7450.590217538033;7910.762677119681;8045;8055.194480551956;8061.6372263248295;7511.399088072941;7581.09026916768;7586;9966.90033099667;10220.335678180933;10273.486325683685;10303;10326;10336.793264134712;10336.844947325797;10212.64255751049;10262.794744105076;10278.201314769885;10283.948580257125;10350.844737328945;7146.428285737128;7345.669444874993;7355.448341374365;9964.651237206663;10349.79300413995;38365.424518632244;38749.225015499534;38753.80623096895;38756;38769.22461550782;2954.9852250738822;3010.202296391458;3021.6827233140643;3087.6294844618787;3122.1726242545515;27263;28704.569431458545;28781;29076.563851542243;29305;3344.9498257526157;3370.9157271068175;3409.147713071732;3425.400554902907;3433.4678124890684;185136;185230;185667;185777.99999999866;185809;52459.73770131125;52929.735351323;52978;52983.73508132435;52998.470015300125;55213.44786552083;55505;55535.72232138814;55710.44289557134;55809;14331.78502322466;14823.258837058103;14824.925875370554;14827.703445931022;14829.555113346616;10647.30792498485;12177.69555761113;12218.20581662189;12238.877611223952;12249.816252756216;15337.156456394781;15803;15828.920855395649;16126.919365403099;16255.837441625668;6281.842953926189;6285.8742825143245;6383.872322553523;6402.839929001812;6403.263624683128;810.3233799777134;951.2057432044182;951.296040929707;964.6912987843963;1006.5369929832359;26830.731692683214;28002;28301.433971320457;28333.858330708215;28604.713952860206;932.9346945713735;993.4982833668951;995.9850602240972;1006.4967516241879;1017.1811691588194;14835.777463338058;14876.404943802343;15007.24963751808;15286.923565382325;15322.387104515918;377586;731709;758552;761181;761217;47066;47321.526784731715;47332.763336183096;51441.22838157431;51714.224286635734;1076.2466273608504;1082.7184931917916;1195.0678470792795;1214.4959841665786;1214.684182112657;26916.73083269142;29304;30126.84936575303;32049.67950320467;32842;46932.53067469282;47542.524574755196;47678.28482572695;47760;47887.521124788305;339748;340620;341225;341389;343080;30542.389152217274;31520.684793151773;38058.42912356372;38241.426378603785;38686.419703703905;42108;42308.57691423046;42483.15033699247;42838.35742463803;42845;33705.325893482484;36232.751056870904;36730.44904326384;36962.26075478421;37221.813890930374;36526.26947461089;36684.63315366919;36706.4494032595;36841.447378289886;37064.25871482609;35107.29785404226;35771.64228357683;36299.81850090733;36679.449808253434;36731.26537469289;30451;32155.196120097185;32251.677483224867;32259.67740322567;32406.51390229101;24307.029856785874;25046.74953250444;25089.87455062713;25365.492690146464;25492.872535637944 10:08:49 INFO - PROCESS | 29570 | |2;jquery.html;375.8383894925182;378.6610983170061;380.69924759440045;388.2468012056612;388.854179682619;15976.600584985366;16946.91526542367;17758.644827103468;17771.37800176994;17800.109994500257;11741.06071514279;15908.52274431766;16604.66790664186;16657.083860387673;17545.56136096598;4516.593506584415;4657.04530571232;4691.88270293242;4693.8357157499495;4696.342512048316;3503.0891968088354;3645;5118.104331741949;5358.223057656657;5361;5439.646422982513;5440.211169380437;5446.809361672343;5456.8090116845915;5463.262459567967;5175.197844334134;5387.0303345397815;5392.137258038695;5413.296271484682;5421.620486565942;4990.850274491771;5134;5141.997310524449;5143;5156.510131537535;20772.169113235454;20832.895835520878;20993;21013.47466313339;21063.262785802577;1674.9916250418669;1814.2561549764532;1848.5101448116125;1896.8387687046618;2443.144899285254;9310.627574896997;9346.859797103049;9733.902660973441;9736.464494452803;10567.73580660482;1736.782902137233;1746.9912650436793;1751.3152317550628;1827.972580411295;1890.1022014542982;53237.46762532364;60273.698631507;65839.67080164616;66770.66614666943;66985.33014669888;10116.696499105039;10152.746181345525;10175.084242418217;10233.641822536176;10413.895861041445;13871.167729936236;13929.373178207004;13936.86063139376;13982.440702371994;13985.44058237659;7227.13274407064;7264.164621068539;7319.74380896666;7323.231060738653;7349.191588925214;6971.163460384786;6977.372036516738;6992.545484543489;7087.1849737279845;7125.572465652078;4379.868603941887;4431.651224850834;4462.776861156929;4466.553344665507;4496;1704.514213449186;1772.485979066082;1833.6424397242677;1848.3068849181557;1877.3147801052517;759.4797563668794;859.9785005374915;860.8063185783149;882.2236431939851;895.9462432254086;16599.585010374838;16873.409430669868;17139.314427423014;17166.914165429094;17528.298868045134;844.0883845446903;853.3386625365391;857.4041041476239;862.98705519416;874.5364956573027;9309.301802364715;9646.9035309646;9711.9028809711;9794.65718699842;9802.686817800288;376122;383769;386529;386870;387018;73331;74172;75014.62492687501;75983.24016759761;76246.61876690581;863.9395242333102;876.6756300168978;886.2422628652538;896.789254525186;897.1073781587362;15109.47116850905;15173;15182.164980926154;15472;15752.763708544151;35980.56077756965;37302.44046339253;37311.81344093262;37619.81190094032;37698.81150594229;44426.77786611046;45176.548234517235;47160.76419618017;49084;50712.74643626758;7787.143414224318;7787.260210280015;7787.766367008999;7937.126916039347;8005.199480051948;10703.304285221435;11739.119566032752;12144.453499592362;12192.57325993586;12296.631101066982;5107.361579802525;10212.795744085226;10411.323263987817;10438.895611043792;10671.092957098423;10123;10355.378677279386;10459.267851250339;10482.68551943443;10482.790344192921;10146.746331341776;10692.305000174963;10784.622538211124;10788.298760580537;10796;7720.112187098555;7783.396083963287;7839.137694853675;8044.3162331200165;8055.838883222185;10594.788104237718;10708.839367409651;10765.30025548337;10801.621943231949;10808.189385796253 10:08:49 INFO - PROCESS | 29570 | |3;mootools.html;1195.1813008089464;1349.0624016308666;1394.9023568350217;1470.2501724120696;1698.2442812948243;6556.606603603781;7528.322450979411;7711.807204819876;7758.612069396536;7986.840263194733;7545.849083018336;7662.6551805168765;7681.155072941986;7704.229577042293;7707.845843083135;2185.9453513662165;2195.220696652689;2210.4694873230387;2211.159759291475;2215.235743668432;2121.692354608581;2160.049578185601;2180.073468775764;2182.0835249195297;2182.2471247419617;2176.325339144862;2191.9342419727354;2199.692043113965;2201.581699477102;2214.180753121347;2148.205164089289;2154.7845215478483;2154.924577639783;2157.4390658428765;2159.8272138228904;2109.430453777487;2157.503774131943;2162.4485756132244;2162.9567408651737;2165.2096984600666;7597.164311925684;7805.5706936118495;7839.608019599054;7841.176676448948;7850.136484986704;4614.607758340545;4792.137415265251;4794.137055330039;4795.424549054101;4799.112164249625;2805.186495916202;2865.9713402866123;2909.5199292116777;2917.5040243158714;2917.6352955880516;928.424376886328;935.4574346878794;941.7692665296978;943.0804965158984;950.7908260182749;10631.724193096801;10674.146068314516;10710.51802668874;10715.19636027301;10734.624288149955;3984.143409167043;4080.6123418275215;4157.189348077157;4273.017206042628;4275.337322714984;8386.87419688705;8452.57737113142;8456.915430845736;8462.788430289293;8469.237768600855;4703.905921881543;4706.811727530929;4708.4349878014855;4712.434507859079;4712.835050773207;3651.434027725707;3800.3919372900205;3872.6514613684903;4343.239933011773;4377.606015458624;3454.084667563098;3584.1577229351083;3632.2917031178667;3633.255182687543;3635.163912300186;1003.2575893838507;1044.5821671331528;1045.2526443592867;1055.5250137438184;1056.5668076088766;825.195434451405;856.0583358305938;862.2239984014437;878.8286284174525;888.1030159538783;5403.486668766461;5489.423610520837;5492.752826122834;5502.367227768857;5720.48515633595;387.6840375094289;393.06450647459434;394.02478864809365;402.36024720693655;416.895776055986;3820.675242604354;3949.30887094763;4060.959390406117;4084.8570300039355;4106.917861642751;9525.333226674065;9533.142017218484;9533.52332383378;9606;9655.903440965641;9554.952225238969;9560.28297877662;9591.088846559565;9595.28035397334;9606.759831004141;6723.7646682365885;7001.824954376181;7131.607761573059;7137.072180616487;7146.499745017799;5490.835274941758;5579.972100139474;5630.239917611234;5699.601027928005;5754.597178197653;8945.910540894507;8982.685606003759;9045.909540904508;9120.863187052331;9135.720999060004;8039.236272554098;8924.107589241283;9302.069793020644;9372.906270937476;9398.154166125081;6517.3808488193545;6686.83282917931;6716.429103526255;6887.104676392037;6892.620905850226;7281.19906810261;7281.854362912818;7294.343509084208;7295.4528410368375;7332.046833911558;6520.608763474207;6531.477481801539;6554.14796076507;6575.704093315908;6650.467962562887;6403;6421.550491465552;6644.069830223676;6694.364035416627;6898.241193468614;6088.178095956989;6156.26124865019;6374.90437643445;6379.904301435575;6382.744690212339;4635.605973492291;4717.622590192707;4753.9762301188275;4756.476787553297;4852.296417019583;4698.318743782201;4753.928691069567;4769.165396055607;4777.784999675093;4807.975960120177 10:08:49 INFO - PROCESS | 29570 | |4;prototype.html;294.76418864908067;296.169245267026;303.6811348084511;306.7408040206025;307.1691075640393;4533.9319910201375;4668.113058518879;4736.692115012522;4767.237242041274;4767.475577686456;4541.863744087683;4684.852211208259;4686.859394218171;4703.741294228825;4713.10451014307;3750.512433383656;3755.2864955658406;3765.6610905018542;3766.21104975136;3766.5291838520184;3599.9100022499383;3633.8001409922445;3767.905802354949;3791.4502397152396;3828.846846126152;3728.384816505287;3743.008102852746;3752.3620984432714;3771.15149091455;3773.3585290500682;3738.5513738351437;3753.6246375362516;3762.6671463692414;3777.58446570877;3784.2242340320445;3361.092505023654;3361.9159521011925;3365.125067482448;3370.5281260623533;3386.255023894764;4525.434320709911;4595.517470665565;4608.1705293047235;4613.976930115362;4635.258358662598;4270.715167385465;4281.892952676177;4285.271503844354;4292.948227684237;4318.481782186126;4968.925466117975;4969.155243608595;4969.801207951714;4973.861239666941;4988.575971042465;3410.6248312685584;3571.8749843755345;3587.551556055493;3664.13892735207;3680.411134218539;5201.323827902386;5203.869903252411;5204.271402003723;5226.843194704165;5233.764480598382;3657.9634203658156;3676.1544844685873;3684.8576941147794;3685.852565897334;3695.2055308108884;3737.7383583148917;3743.3155080214015;3743.737938344344;3744.4196149596855;3747.15688969985;4038.596140385938;4202.558731333227;4205.957940420618;4211.831526738896;4224.387463817729;4745.406824146981;4932.53140951609;4954.182559877652;4963.6277279204205;4968.90062198754;1214.2532342609231;1258.6349958511928;1261.8296529968336;1262.4003598290738;1270.6505710929569;798.596708662122;820.9122912141437;827.2554700769235;827.3091968206495;832.433944917462;3880.767153970771;3986.4817573715814;3995.980020099939;3996.1028254515245;4001.9599804001587;4501.459824821042;4502.5047244803045;4539.773011349419;4552.658550608718;4561.286034809284;3900.0444891001885;3915.941260881089;3950.9012274692786;3961.880393989202;3978.3634618461488;2332.591796435617;2362.3227508468835;2418.371223481872;2426.2963740515406;2440.7796101949025;4265.210935976854;4288;4304.89237769052;4306.978465107654;4308.849190278325;4574.336721175478;4576.6109880660515;4598.655100867449;4621.491635920046;4644.558766917137;4288.871333859989;4295.914081718348;4335.655946656547;4500.504944456107;4897.16748152822;5136.229565565195;5138.922916156335;5139.563137133236;5145.475349385682;5173.456787037458;5062.70900920263;5085.4406015337545;5091.6181286402925;5092.694438333712;5106.055379754757;4687.414073240845;4887.535684110004;4899.240617704261;4928.186849169847;4929.950700492949;3642.9817850910576;3643.653852883972;3647.4164133738473;3658.34149853029;3674;4937;5179.585633149414;5180.248863914787;5182.429932707475;5191.299174611379;4856.927146092882;4990.201567749143;4990.326305948651;4999.300097986212;5004.574611158092;4982.975085124551;4986.476419975996;4996.075725990703;4999.575206025002;5004.77478513475;4779.996200797871;4870.829520966749;4908.361912951294;4920.335754673217;4921.138800709933;4965.255211718271;5001.974595207901;5014.824481143289;5018.799248030038;5021.472745361684;4891.877703057452;4895.4859739728245;4897.236994681983;4898.559129678346;4902.901941961212 10:08:49 INFO - PROCESS | 29570 | |5;yui.html;522.2114606943519;561.7444062951357;604.3322129047402;707.6780065070393;719.2160545005943;11989.76020479591;12882.93558532206;12925.095243332971;12933.676658083552;12950.546730864422;12904.225746455195;12998.870011299885;13028.283444410554;13058.2817945013;13139;752.5108679358406;771.6951804037402;771.9421043421738;772.6368606754817;773.6750564762813;769.1885061260368;769.607500174912;770.4183341577129;770.6339488742858;771.0323543952331;794.4875555266849;797.8125140591985;798.0303930724169;798.7404093669552;802.5826570183501;804.7746630943345;805.1746959366667;806.0609390060572;806.2340776262574;806.3549160671469;599.7930713903739;600.2586805295457;600.3576173494363;600.4355905448864;600.8798240351904;10740.140788736882;10810;10839.566417343296;10853.620123295726;10855.077318427942;10418.322809017389;10732.640489252966;10991.503114525609;11135.443227838658;11200.66398008053;9421.905780942172;9468;11396.088312934946;11415.429228538624;12315.69210769729;3235.983820080908;3321.4021476134285;3347.7991320520846;3348.196432856121;3351.798892066484;43760.343594846105;44133;44239.11521769579;44459.777701111285;44635;9244.399114057565;9302.441853488812;9535;9635.421874687541;9659.420434773936;16629.6674066518;17734.645307093786;17817.821821781876;17848.643027139646;17854;4795.928061079087;5502.339719233718;5708.571857110734;6734.629595372302;6788.151481064867;3687.4100143976834;3707.4253490708984;3813.2945405100145;3820.102275965146;3945.704072194597;2717.904873329424;2845.01846862832;2845.7865660075577;2845.9715402845704;2856.3287627407544;507.32779067735396;541.3341589844513;543.2774410034644;565.5842955427757;603.0441749826574;468.94372675278504;611.4894063457064;613.2242712968099;621.8662987457718;633.866887953535;8189.631466584018;8454.704085356983;8644.22202001823;8676.045634980148;8746.43148195378;579.122629216737;583.1340459417761;583.4398976982145;599.9400059994053;604.081795670587;4243.681723870722;4259.488861336597;4272.786360681954;4294.699371044059;4362.6946113772365;59787.40212597905;76559.23440765521;78691.60654196692;78868.60565697135;93177;86582.13417865867;86678;87978.56010720033;88104;88164.5591772037;19650.410487685393;21445.78554214469;29794.55308170379;74366.62816685882;81118.59440702877;8754.12458754145;9072.682456114004;9142.451452912846;9142.680006199751;9248.491332976751;30999.380012399175;31180;39132.608673912895;45784.7710761444;46288;65365.67317163384;68385.3161468379;69127;69463;69974.65012674904;11125.407999523095;11419.828702569303;11506.367149806842;11997.700057498632;12151.210171338835;13183.67040823987;13199.604011879659;13223.867761322264;14009.929950350182;14253.216073116078;11700.824487632523;11766.352850593299;12101.636950891487;12191.461221821119;12340.444679989243;11698.76602467963;12413.944814690853;12687.492500299884;12740;12762.936185319015;11198.496067676811;12021.75956480883;12026.939865300617;12032.819507707216;12035.939820300842;10317.845232321672;10478.37129772216;10627.681169564925;10632.202584806326;10845.891541084487;9337.206337461392;9384.483853388128;9535.904640953779;10039.69880903574;10056.949715251378 10:08:49 INFO - PROCESS | 29570 | __end_tp_report 10:08:49 INFO - PROCESS | 29570 | __start_cc_report 10:08:49 INFO - PROCESS | 29570 | _x_x_mozilla_cycle_collect,1152 10:08:49 INFO - PROCESS | 29570 | __end_cc_report 10:08:49 INFO - PROCESS | 29570 | __startTimestamp1468861729513__endTimestamp 10:08:49 INFO - PROCESS | 29570 | 10:08:49 INFO - PROCESS | 29570 | ------- Summary: start ------- 10:08:49 INFO - PROCESS | 29570 | Number of tests: 6 10:08:49 INFO - PROCESS | 29570 | 10:08:49 INFO - PROCESS | 29570 | [#0] dojo.html Cycles:165 Average:4720.72 Median:4849.95 stddev:501.25 (10.3%) stddev-sans-first:475.36 10:08:49 INFO - PROCESS | 29570 | Values: 2636.2 2658.3 2696.3 2702.8 2713.2 4611.7 4691.6 4749.6 4821.6 4869.7 4697.1 4707.9 4713.3 4747.5 4769.4 4119.8 4273.5 4373.0 4402.0 4511.9 4431.3 4470.6 4484.8 4497.8 4510.6 4285.3 4345.9 4346.8 4353.7 4359.7 4305.9 4309.4 4318.4 4381.0 4493.5 4327.1 4340.5 4344.3 4378.4 4395.2 4665.0 4675.1 4726.8 4736.7 4773.7 5127.2 5143.2 5146.1 5193.7 5197.9 5148.1 5164.3 5174.6 5177.2 5213.1 4654.5 4731.9 4737.1 4838.9 4882.7 5288.9 5301.5 5306.7 5313.3 5315.7 5087.5 5092.4 5096.1 5096.3 5098.7 4929.1 5101.7 5127.1 5145.2 5168.7 5069.7 5079.3 5093.5 5096.2 5129.6 5061.6 5070.8 5075.7 5077.9 5086.1 4269.9 4289.5 4290.7 4353.9 4358.0 3802.4 3804.8 3817.1 3838.0 3843.8 4361.3 4362.5 4364.2 4366.5 4368.8 5101.8 5141.3 5143.3 5152.0 5199.5 4395.4 4404.3 4412.2 4449.0 4461.8 4502.3 4511.2 4648.7 4655.0 4655.5 4805.8 4818.8 4822.7 4836.4 4850.0 5129.3 5140.7 5143.8 5149.1 5157.7 4631.7 4733.5 4796.5 4823.6 4877.4 5035.0 5061.5 5068.7 5082.1 5118.9 5105.3 5124.3 5129.9 5139.4 5183.5 4840.6 4874.0 4904.8 4993.6 4993.7 4946.0 4961.5 4965.6 4968.8 4999.7 4866.0 4883.1 4932.7 4933.2 4965.3 4913.7 4917.2 4963.5 4980.5 4982.0 4862.8 4882.7 4887.0 4920.4 4926.8 10:08:49 INFO - PROCESS | 29570 | 10:08:49 INFO - PROCESS | 29570 | [#1] ext.html Cycles:180 Average:54908.51 Median:36002.20 stddev:124218.73 (345.0%) stddev-sans-first:124500.20 10:08:49 INFO - PROCESS | 29570 | Values: 570.5 572.1 581.7 582.4 586.3 26945.2 38874.8 40257.4 40363.4 44125.3 39402.6 39873.2 39873.4 39916.0 40003.6 7450.6 7910.8 8045.0 8055.2 8061.6 7511.4 7581.1 7586.0 9966.9 10220.3 10273.5 10303.0 10326.0 10336.8 10336.8 10212.6 10262.8 10278.2 10283.9 10350.8 7146.4 7345.7 7355.4 9964.7 10349.8 38365.4 38749.2 38753.8 38756.0 38769.2 2955.0 3010.2 3021.7 3087.6 3122.2 27263.0 28704.6 28781.0 29076.6 29305.0 3344.9 3370.9 3409.1 3425.4 3433.5 185136.0 185230.0 185667.0 185778.0 185809.0 52459.7 52929.7 52978.0 52983.7 52998.5 55213.4 55505.0 55535.7 55710.4 55809.0 14331.8 14823.3 14824.9 14827.7 14829.6 10647.3 12177.7 12218.2 12238.9 12249.8 15337.2 15803.0 15828.9 16126.9 16255.8 6281.8 6285.9 6383.9 6402.8 6403.3 810.3 951.2 951.3 964.7 1006.5 26830.7 28002.0 28301.4 28333.9 28604.7 932.9 993.5 996.0 1006.5 1017.2 14835.8 14876.4 15007.2 15286.9 15322.4 377586.0 731709.0 758552.0 761181.0 761217.0 47066.0 47321.5 47332.8 51441.2 51714.2 1076.2 1082.7 1195.1 1214.5 1214.7 26916.7 29304.0 30126.8 32049.7 32842.0 46932.5 47542.5 47678.3 47760.0 47887.5 339748.0 340620.0 341225.0 341389.0 343080.0 30542.4 31520.7 38058.4 38241.4 38686.4 42108.0 42308.6 42483.2 42838.4 42845.0 33705.3 36232.8 36730.4 36962.3 37221.8 36526.3 36684.6 36706.4 36841.4 37064.3 35107.3 35771.6 36299.8 36679.4 36731.3 30451.0 32155.2 32251.7 32259.7 32406.5 24307.0 25046.7 25089.9 25365.5 25492.9 10:08:49 INFO - PROCESS | 29570 | 10:08:49 INFO - PROCESS | 29570 | [#2] jquery.html Cycles:180 Average:23808.34 Median:388.55 stddev:63260.02 (16281.0%) stddev-sans-first:63413.02 10:08:49 INFO - PROCESS | 29570 | Values: 375.8 378.7 380.7 388.2 388.9 15976.6 16946.9 17758.6 17771.4 17800.1 11741.1 15908.5 16604.7 16657.1 17545.6 4516.6 4657.0 4691.9 4693.8 4696.3 3503.1 3645.0 5118.1 5358.2 5361.0 5439.6 5440.2 5446.8 5456.8 5463.3 5175.2 5387.0 5392.1 5413.3 5421.6 4990.9 5134.0 5142.0 5143.0 5156.5 20772.2 20832.9 20993.0 21013.5 21063.3 1675.0 1814.3 1848.5 1896.8 2443.1 9310.6 9346.9 9733.9 9736.5 10567.7 1736.8 1747.0 1751.3 1828.0 1890.1 53237.5 60273.7 65839.7 66770.7 66985.3 10116.7 10152.7 10175.1 10233.6 10413.9 13871.2 13929.4 13936.9 13982.4 13985.4 7227.1 7264.2 7319.7 7323.2 7349.2 6971.2 6977.4 6992.5 7087.2 7125.6 4379.9 4431.7 4462.8 4466.6 4496.0 1704.5 1772.5 1833.6 1848.3 1877.3 759.5 860.0 860.8 882.2 895.9 16599.6 16873.4 17139.3 17166.9 17528.3 844.1 853.3 857.4 863.0 874.5 9309.3 9646.9 9711.9 9794.7 9802.7 376122.0 383769.0 386529.0 386870.0 387018.0 73331.0 74172.0 75014.6 75983.2 76246.6 863.9 876.7 886.2 896.8 897.1 15109.5 15173.0 15182.2 15472.0 15752.8 35980.6 37302.4 37311.8 37619.8 37698.8 44426.8 45176.5 47160.8 49084.0 50712.7 7787.1 7787.3 7787.8 7937.1 8005.2 10703.3 11739.1 12144.5 12192.6 12296.6 5107.4 10212.8 10411.3 10438.9 10671.1 10123.0 10355.4 10459.3 10482.7 10482.8 10146.7 10692.3 10784.6 10788.3 10796.0 7720.1 7783.4 7839.1 8044.3 8055.8 10594.8 10708.8 10765.3 10801.6 10808.2 10:08:49 INFO - PROCESS | 29570 | 10:08:49 INFO - PROCESS | 29570 | [#3] mootools.html Cycles:180 Average:5114.45 Median:5446.46 stddev:2858.16 (52.5%) stddev-sans-first:2851.00 10:08:49 INFO - PROCESS | 29570 | Values: 1195.2 1349.1 1394.9 1470.3 1698.2 6556.6 7528.3 7711.8 7758.6 7986.8 7545.8 7662.7 7681.2 7704.2 7707.8 2185.9 2195.2 2210.5 2211.2 2215.2 2121.7 2160.0 2180.1 2182.1 2182.2 2176.3 2191.9 2199.7 2201.6 2214.2 2148.2 2154.8 2154.9 2157.4 2159.8 2109.4 2157.5 2162.4 2163.0 2165.2 7597.2 7805.6 7839.6 7841.2 7850.1 4614.6 4792.1 4794.1 4795.4 4799.1 2805.2 2866.0 2909.5 2917.5 2917.6 928.4 935.5 941.8 943.1 950.8 10631.7 10674.1 10710.5 10715.2 10734.6 3984.1 4080.6 4157.2 4273.0 4275.3 8386.9 8452.6 8456.9 8462.8 8469.2 4703.9 4706.8 4708.4 4712.4 4712.8 3651.4 3800.4 3872.7 4343.2 4377.6 3454.1 3584.2 3632.3 3633.3 3635.2 1003.3 1044.6 1045.3 1055.5 1056.6 825.2 856.1 862.2 878.8 888.1 5403.5 5489.4 5492.8 5502.4 5720.5 387.7 393.1 394.0 402.4 416.9 3820.7 3949.3 4061.0 4084.9 4106.9 9525.3 9533.1 9533.5 9606.0 9655.9 9555.0 9560.3 9591.1 9595.3 9606.8 6723.8 7001.8 7131.6 7137.1 7146.5 5490.8 5580.0 5630.2 5699.6 5754.6 8945.9 8982.7 9045.9 9120.9 9135.7 8039.2 8924.1 9302.1 9372.9 9398.2 6517.4 6686.8 6716.4 6887.1 6892.6 7281.2 7281.9 7294.3 7295.5 7332.0 6520.6 6531.5 6554.1 6575.7 6650.5 6403.0 6421.6 6644.1 6694.4 6898.2 6088.2 6156.3 6374.9 6379.9 6382.7 4635.6 4717.6 4754.0 4756.5 4852.3 4698.3 4753.9 4769.2 4777.8 4808.0 10:08:49 INFO - PROCESS | 29570 | 10:08:49 INFO - PROCESS | 29570 | [#4] prototype.html Cycles:180 Average:4058.70 Median:4513.97 stddev:1177.85 (26.1%) stddev-sans-first:1146.77 10:08:49 INFO - PROCESS | 29570 | Values: 294.8 296.2 303.7 306.7 307.2 4533.9 4668.1 4736.7 4767.2 4767.5 4541.9 4684.9 4686.9 4703.7 4713.1 3750.5 3755.3 3765.7 3766.2 3766.5 3599.9 3633.8 3767.9 3791.5 3828.8 3728.4 3743.0 3752.4 3771.2 3773.4 3738.6 3753.6 3762.7 3777.6 3784.2 3361.1 3361.9 3365.1 3370.5 3386.3 4525.4 4595.5 4608.2 4614.0 4635.3 4270.7 4281.9 4285.3 4292.9 4318.5 4968.9 4969.2 4969.8 4973.9 4988.6 3410.6 3571.9 3587.6 3664.1 3680.4 5201.3 5203.9 5204.3 5226.8 5233.8 3658.0 3676.2 3684.9 3685.9 3695.2 3737.7 3743.3 3743.7 3744.4 3747.2 4038.6 4202.6 4206.0 4211.8 4224.4 4745.4 4932.5 4954.2 4963.6 4968.9 1214.3 1258.6 1261.8 1262.4 1270.7 798.6 820.9 827.3 827.3 832.4 3880.8 3986.5 3996.0 3996.1 4002.0 4501.5 4502.5 4539.8 4552.7 4561.3 3900.0 3915.9 3950.9 3961.9 3978.4 2332.6 2362.3 2418.4 2426.3 2440.8 4265.2 4288.0 4304.9 4307.0 4308.8 4574.3 4576.6 4598.7 4621.5 4644.6 4288.9 4295.9 4335.7 4500.5 4897.2 5136.2 5138.9 5139.6 5145.5 5173.5 5062.7 5085.4 5091.6 5092.7 5106.1 4687.4 4887.5 4899.2 4928.2 4930.0 3643.0 3643.7 3647.4 3658.3 3674.0 4937.0 5179.6 5180.2 5182.4 5191.3 4856.9 4990.2 4990.3 4999.3 5004.6 4983.0 4986.5 4996.1 4999.6 5004.8 4780.0 4870.8 4908.4 4920.3 4921.1 4965.3 5002.0 5014.8 5018.8 5021.5 4891.9 4895.5 4897.2 4898.6 4902.9 10:08:49 INFO - PROCESS | 29570 | 10:08:49 INFO - PROCESS | 29570 | [#5] yui.html Cycles:180 Average:15904.57 Median:45209.89 stddev:22368.27 (49.5%) stddev-sans-first:22401.19 10:08:49 INFO - PROCESS | 29570 | Values: 522.2 561.7 604.3 707.7 719.2 11989.8 12882.9 12925.1 12933.7 12950.5 12904.2 12998.9 13028.3 13058.3 13139.0 752.5 771.7 771.9 772.6 773.7 769.2 769.6 770.4 770.6 771.0 794.5 797.8 798.0 798.7 802.6 804.8 805.2 806.1 806.2 806.4 599.8 600.3 600.4 600.4 600.9 10740.1 10810.0 10839.6 10853.6 10855.1 10418.3 10732.6 10991.5 11135.4 11200.7 9421.9 9468.0 11396.1 11415.4 12315.7 3236.0 3321.4 3347.8 3348.2 3351.8 43760.3 44133.0 44239.1 44459.8 44635.0 9244.4 9302.4 9535.0 9635.4 9659.4 16629.7 17734.6 17817.8 17848.6 17854.0 4795.9 5502.3 5708.6 6734.6 6788.2 3687.4 3707.4 3813.3 3820.1 3945.7 2717.9 2845.0 2845.8 2846.0 2856.3 507.3 541.3 543.3 565.6 603.0 468.9 611.5 613.2 621.9 633.9 8189.6 8454.7 8644.2 8676.0 8746.4 579.1 583.1 583.4 599.9 604.1 4243.7 4259.5 4272.8 4294.7 4362.7 59787.4 76559.2 78691.6 78868.6 93177.0 86582.1 86678.0 87978.6 88104.0 88164.6 19650.4 21445.8 29794.6 74366.6 81118.6 8754.1 9072.7 9142.5 9142.7 9248.5 30999.4 31180.0 39132.6 45784.8 46288.0 65365.7 68385.3 69127.0 69463.0 69974.7 11125.4 11419.8 11506.4 11997.7 12151.2 13183.7 13199.6 13223.9 14009.9 14253.2 11700.8 11766.4 12101.6 12191.5 12340.4 11698.8 12413.9 12687.5 12740.0 12762.9 11198.5 12021.8 12026.9 12032.8 12035.9 10317.8 10478.4 10627.7 10632.2 10845.9 9337.2 9384.5 9535.9 10039.7 10056.9 10:08:49 INFO - PROCESS | 29570 | -------- Summary: end -------- 10:08:49 INFO - PROCESS | 29570 | 10:08:50 INFO - TEST-INFO | 29570: exit 0 10:08:50 INFO - TEST-OK | dromaeo_css | took 1120375ms 10:08:50 INFO - TEST-START | kraken 10:08:50 INFO - operating with platform_type : linux_ 10:08:50 INFO - Initialising browser for kraken test... 10:08:50 INFO - TEST-INFO | started process 29758 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGXIZzs/profile http://localhost:46588/getInfo.html) 10:08:54 INFO - PROCESS | 29758 | __metrics Screen width/height:1600/1200 10:08:54 INFO - PROCESS | 29758 | colorDepth:24 10:08:54 INFO - PROCESS | 29758 | Browser inner width/height: 1024/697 10:08:54 INFO - PROCESS | 29758 | __metrics 10:08:55 INFO - PROCESS | 29758 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:08:55 INFO - PROCESS | 29758 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:08:56 INFO - TEST-INFO | 29758: exit 0 10:08:56 INFO - Browser initialized. 10:08:56 INFO - Running cycle 1/1 for kraken test... 10:08:56 INFO - TEST-INFO | started process 29841 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGXIZzs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:09:08 INFO - PROCESS | 29841 | RSS: Main: 159342592 10:09:08 INFO - PROCESS | 29841 | 10:09:11 INFO - PROCESS | 29841 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46588/tests/kraken/driver.html?testName=audio-beat-detection) 10:09:12 INFO - PROCESS | 29841 | RSS: Main: 217870336 10:09:12 INFO - PROCESS | 29841 | 10:09:15 INFO - PROCESS | 29841 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46588/tests/kraken/driver.html?testName=audio-dft) 10:09:15 INFO - PROCESS | 29841 | RSS: Main: 206426112 10:09:15 INFO - PROCESS | 29841 | 10:09:18 INFO - PROCESS | 29841 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46588/tests/kraken/driver.html?testName=audio-fft) 10:09:19 INFO - PROCESS | 29841 | RSS: Main: 271552512 10:09:19 INFO - PROCESS | 29841 | 10:09:21 INFO - PROCESS | 29841 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46588/tests/kraken/driver.html?testName=audio-oscillator) 10:09:22 INFO - PROCESS | 29841 | RSS: Main: 182734848 10:09:22 INFO - PROCESS | 29841 | 10:09:24 INFO - PROCESS | 29841 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46588/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:09:25 INFO - PROCESS | 29841 | RSS: Main: 186073088 10:09:25 INFO - PROCESS | 29841 | 10:09:28 INFO - PROCESS | 29841 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46588/tests/kraken/driver.html?testName=imaging-darkroom) 10:09:28 INFO - PROCESS | 29841 | RSS: Main: 222437376 10:09:28 INFO - PROCESS | 29841 | 10:09:32 INFO - PROCESS | 29841 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46588/tests/kraken/driver.html?testName=imaging-desaturate) 10:09:32 INFO - PROCESS | 29841 | RSS: Main: 218472448 10:09:32 INFO - PROCESS | 29841 | 10:09:35 INFO - PROCESS | 29841 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46588/tests/kraken/driver.html?testName=json-parse-financial) 10:09:36 INFO - PROCESS | 29841 | RSS: Main: 220876800 10:09:36 INFO - PROCESS | 29841 | 10:09:38 INFO - PROCESS | 29841 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46588/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:09:38 INFO - PROCESS | 29841 | RSS: Main: 210849792 10:09:38 INFO - PROCESS | 29841 | 10:09:40 INFO - PROCESS | 29841 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46588/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:09:41 INFO - PROCESS | 29841 | RSS: Main: 213401600 10:09:41 INFO - PROCESS | 29841 | 10:09:43 INFO - PROCESS | 29841 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46588/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:09:44 INFO - PROCESS | 29841 | RSS: Main: 216506368 10:09:44 INFO - PROCESS | 29841 | 10:09:46 INFO - PROCESS | 29841 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46588/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:09:47 INFO - PROCESS | 29841 | RSS: Main: 227758080 10:09:47 INFO - PROCESS | 29841 | 10:09:50 INFO - PROCESS | 29841 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46588/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:09:50 INFO - PROCESS | 29841 | RSS: Main: 227323904 10:09:50 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | Cycle 1(1): loaded http://localhost:46588/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46588/tests/kraken/driver.html?testName=ai-astar) 10:09:52 INFO - PROCESS | 29841 | RSS: Main: 269529088 10:09:52 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | __start_tp_report 10:09:52 INFO - PROCESS | 29841 | _x_x_mozilla_page_load 10:09:52 INFO - PROCESS | 29841 | _x_x_mozilla_page_load_details 10:09:52 INFO - PROCESS | 29841 | |i|pagename|runs| 10:09:52 INFO - PROCESS | 29841 | |0;ai-astar;104;116;114;114;115;112;113;114;114;115 10:09:52 INFO - PROCESS | 29841 | |1;audio-beat-detection;153;127;128;187;123;123;154;125;157;122 10:09:52 INFO - PROCESS | 29841 | |2;audio-dft;162;160;160;160;161;162;160;162;160;159 10:09:52 INFO - PROCESS | 29841 | |3;audio-fft;75;89;77;108;76;76;75;76;109;77 10:09:52 INFO - PROCESS | 29841 | |4;audio-oscillator;85;86;112;85;85;85;123;84;85;128 10:09:52 INFO - PROCESS | 29841 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;116;117;117 10:09:52 INFO - PROCESS | 29841 | |6;imaging-darkroom;146;147;147;146;147;145;147;145;146;147 10:09:52 INFO - PROCESS | 29841 | |7;imaging-desaturate;94;95;95;94;95;95;95;95;95;95 10:09:52 INFO - PROCESS | 29841 | |8;json-parse-financial;67;64;64;63;63;70;63;63;64;62 10:09:52 INFO - PROCESS | 29841 | |9;json-stringify-tinderbox;66;65;66;65;66;66;65;66;67;70 10:09:52 INFO - PROCESS | 29841 | |10;stanford-crypto-aes;78;78;79;88;82;85;79;86;78;86 10:09:52 INFO - PROCESS | 29841 | |11;stanford-crypto-ccm;139;129;132;134;126;128;132;128;171;134 10:09:52 INFO - PROCESS | 29841 | |12;stanford-crypto-pbkdf2;166;168;170;163;160;170;165;166;169;165 10:09:52 INFO - PROCESS | 29841 | |13;stanford-crypto-sha256-iterative;70;66;70;68;68;67;66;70;66;67 10:09:52 INFO - PROCESS | 29841 | __end_tp_report 10:09:52 INFO - PROCESS | 29841 | __start_cc_report 10:09:52 INFO - PROCESS | 29841 | _x_x_mozilla_cycle_collect,1920 10:09:52 INFO - PROCESS | 29841 | __end_cc_report 10:09:52 INFO - PROCESS | 29841 | __startTimestamp1468861792958__endTimestamp 10:09:52 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | ------- Summary: start ------- 10:09:52 INFO - PROCESS | 29841 | Number of tests: 14 10:09:52 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:3.38 (3.0%) stddev-sans-first:1.17 10:09:52 INFO - PROCESS | 29841 | Values: 104.0 116.0 114.0 114.0 115.0 112.0 113.0 114.0 114.0 115.0 10:09:52 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | [#1] audio-beat-detection Cycles:10 Average:139.90 Median:140.50 stddev:21.87 (15.6%) stddev-sans-first:22.67 10:09:52 INFO - PROCESS | 29841 | Values: 153.0 127.0 128.0 187.0 123.0 123.0 154.0 125.0 157.0 122.0 10:09:52 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | [#2] audio-dft Cycles:10 Average:160.60 Median:160.50 stddev:1.07 (0.7%) stddev-sans-first:1.01 10:09:52 INFO - PROCESS | 29841 | Values: 162.0 160.0 160.0 160.0 161.0 162.0 160.0 162.0 160.0 159.0 10:09:52 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | [#3] audio-fft Cycles:10 Average:83.80 Median:76.00 stddev:13.65 (18.0%) stddev-sans-first:14.10 10:09:52 INFO - PROCESS | 29841 | Values: 75.0 89.0 77.0 108.0 76.0 76.0 75.0 76.0 109.0 77.0 10:09:52 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:85.00 stddev:17.82 (21.0%) stddev-sans-first:18.47 10:09:52 INFO - PROCESS | 29841 | Values: 85.0 86.0 112.0 85.0 85.0 85.0 123.0 84.0 85.0 128.0 10:09:52 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 10:09:52 INFO - PROCESS | 29841 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 10:09:52 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 10:09:52 INFO - PROCESS | 29841 | Values: 146.0 147.0 147.0 146.0 147.0 145.0 147.0 145.0 146.0 147.0 10:09:52 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 10:09:52 INFO - PROCESS | 29841 | Values: 94.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 10:09:52 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.00 stddev:2.41 (3.8%) stddev-sans-first:2.35 10:09:52 INFO - PROCESS | 29841 | Values: 67.0 64.0 64.0 63.0 63.0 70.0 63.0 63.0 64.0 62.0 10:09:52 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:66.00 stddev:1.48 (2.2%) stddev-sans-first:1.56 10:09:52 INFO - PROCESS | 29841 | Values: 66.0 65.0 66.0 65.0 66.0 66.0 65.0 66.0 67.0 70.0 10:09:52 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:83.50 stddev:3.98 (4.8%) stddev-sans-first:3.97 10:09:52 INFO - PROCESS | 29841 | Values: 78.0 78.0 79.0 88.0 82.0 85.0 79.0 86.0 78.0 86.0 10:09:52 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | [#11] stanford-crypto-ccm Cycles:10 Average:135.30 Median:133.00 stddev:13.11 (9.9%) stddev-sans-first:13.83 10:09:52 INFO - PROCESS | 29841 | Values: 139.0 129.0 132.0 134.0 126.0 128.0 132.0 128.0 171.0 134.0 10:09:52 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.20 Median:167.00 stddev:3.19 (1.9%) stddev-sans-first:3.38 10:09:52 INFO - PROCESS | 29841 | Values: 166.0 168.0 170.0 163.0 160.0 170.0 165.0 166.0 169.0 165.0 10:09:52 INFO - PROCESS | 29841 | 10:09:52 INFO - PROCESS | 29841 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.69 (2.5%) stddev-sans-first:1.59 10:09:52 INFO - PROCESS | 29841 | Values: 70.0 66.0 70.0 68.0 68.0 67.0 66.0 70.0 66.0 67.0 10:09:52 INFO - PROCESS | 29841 | -------- Summary: end -------- 10:09:52 INFO - PROCESS | 29841 | 10:09:53 INFO - TEST-INFO | 29841: exit 0 10:09:54 INFO - TEST-OK | kraken | took 63620ms 10:09:54 INFO - SUITE-END | took 1183s 10:09:54 INFO - Completed test suite (00:19:44) 10:09:54 INFO - javascript benchmark 10:09:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2636.156429942418, 2658.322127857396, 2696.2854843466484, 2702.770264527514, 2713.2131681812275, 4611.74635395054, 4691.601213896814, 4749.572538471537, 4821.590164835989, 4869.683470574415, 4697.1075495655805, 4707.90584188316, 4713.269443236295, 4747.52524747526, 4769.379980602517, 4119.814608342632, 4273.529911709709, 4373.0, 4402.031553058322, 4511.887202819939, 4431.26884064129, 4470.597646211841, 4484.8206071757095, 4497.775111244441, 4510.571495707903, 4285.335772955165, 4345.9348109778375, 4346.76092814895, 4353.738775673438, 4359.738415695068, 4305.870823875289, 4309.375140604595, 4318.417013703172, 4380.956190438087, 4493.5281795411665, 4327.0913108247305, 4340.457442819647, 4344.326629372421, 4378.430803995492, 4395.230834603932, 4665.043666048452, 4675.13510000651, 4726.810927562893, 4736.668433209677, 4773.665843390964, 5127.179651255781, 5143.151380022292, 5146.073706732786, 5193.714345710984, 5197.8960420791755, 5148.124818780816, 5164.302819119408, 5174.637775355726, 5177.197534382177, 5213.139831927728, 4654.464736555272, 4731.905361892743, 4737.123632128068, 4838.927416088762, 4882.658213925062, 5288.867778305573, 5301.54936830366, 5306.681599103988, 5313.256144139824, 5315.734213289397, 5087.465816089331, 5092.388913330424, 5096.082705113041, 5096.286519887145, 5098.668586541863, 4929.06347793918, 5101.7194054327365, 5127.102757017507, 5145.202493613496, 5168.7415629218385, 5069.7465126744255, 5079.288899554065, 5093.465186155475, 5096.159133742965, 5129.589632829439, 5061.5950723942, 5070.847874563769, 5075.670081444694, 5077.949220507748, 5086.059079070384, 4269.914601707948, 4289.463817022872, 4290.742555446684, 4353.934690979638, 4358.0, 3802.372608519563, 3804.8478060877255, 3817.1220619257724, 3838.0, 3843.7693738375788, 4361.258586040381, 4362.454693163355, 4364.192624364503, 4366.519682834885, 4368.759718215465, 5101.770420331094, 5141.305923700327, 5143.254228136974, 5152.0, 5199.506046925536, 4395.428594282723, 4404.339349097661, 4412.205802955435, 4448.955510444919, 4461.843835465743, 4502.279635258408, 4511.210538155809, 4648.6745927785405, 4654.953450465452, 4655.4878963314, 4805.783739731651, 4818.759062046954, 4822.662413631152, 4836.395450568679, 4849.95150048495, 5129.256257842667, 5140.717260550706, 5143.794248230029, 5149.124648809786, 5157.716325602128, 4631.745254010927, 4733.479317275166, 4796.544328288807, 4823.5658790709, 4877.439094504178, 5035.043341765053, 5061.544460998528, 5068.695878247317, 5082.085224659597, 5118.872028199325, 5105.336306280308, 5124.282600436017, 5129.948700513097, 5139.357580302462, 5183.533481986639, 4840.636952228668, 4873.951260487492, 4904.779284932115, 4993.600511958962, 4993.700377977333, 4946.0, 4961.479044700254, 4965.627577931597, 4968.801247950041, 4999.675021123615, 4866.0, 4883.072216278895, 4932.679375840559, 4933.186024305949, 4965.280034395065, 4913.656044077024, 4917.188663870565, 4963.478834722447, 4980.526849949394, 4981.950180498148, 4862.805487780591, 4882.658213924991, 4887.0, 4920.434150072644, 4926.82756103549], "value": 4688.68626471089, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.5093619487241, 572.1046562130266, 581.6946103295769, 582.350678992923, 586.2905883880503, 26945.19164425068, 38874.805625971865, 40257.396139057935, 40363.394549081786, 44125.33811992814, 39402.605973940255, 39873.20253594926, 39873.40189897154, 39916.0, 40003.59996400035, 7450.590217538033, 7910.762677119681, 8045.0, 8055.194480551956, 8061.6372263248295, 7511.399088072941, 7581.09026916768, 7586.0, 9966.90033099667, 10220.335678180933, 10273.486325683685, 10303.0, 10326.0, 10336.793264134712, 10336.844947325797, 10212.64255751049, 10262.794744105076, 10278.201314769885, 10283.948580257125, 10350.844737328945, 7146.428285737128, 7345.669444874993, 7355.448341374365, 9964.651237206663, 10349.79300413995, 38365.424518632244, 38749.225015499534, 38753.80623096895, 38756.0, 38769.22461550782, 2954.9852250738822, 3010.202296391458, 3021.6827233140643, 3087.6294844618787, 3122.1726242545515, 27263.0, 28704.569431458545, 28781.0, 29076.563851542243, 29305.0, 3344.9498257526157, 3370.9157271068175, 3409.147713071732, 3425.400554902907, 3433.4678124890684, 185136.0, 185230.0, 185667.0, 185777.99999999866, 185809.0, 52459.73770131125, 52929.735351323, 52978.0, 52983.73508132435, 52998.470015300125, 55213.44786552083, 55505.0, 55535.72232138814, 55710.44289557134, 55809.0, 14331.78502322466, 14823.258837058103, 14824.925875370554, 14827.703445931022, 14829.555113346616, 10647.30792498485, 12177.69555761113, 12218.20581662189, 12238.877611223952, 12249.816252756216, 15337.156456394781, 15803.0, 15828.920855395649, 16126.919365403099, 16255.837441625668, 6281.842953926189, 6285.8742825143245, 6383.872322553523, 6402.839929001812, 6403.263624683128, 810.3233799777134, 951.2057432044182, 951.296040929707, 964.6912987843963, 1006.5369929832359, 26830.731692683214, 28002.0, 28301.433971320457, 28333.858330708215, 28604.713952860206, 932.9346945713735, 993.4982833668951, 995.9850602240972, 1006.4967516241879, 1017.1811691588194, 14835.777463338058, 14876.404943802343, 15007.24963751808, 15286.923565382325, 15322.387104515918, 377586.0, 731709.0, 758552.0, 761181.0, 761217.0, 47066.0, 47321.526784731715, 47332.763336183096, 51441.22838157431, 51714.224286635734, 1076.2466273608504, 1082.7184931917916, 1195.0678470792795, 1214.4959841665786, 1214.684182112657, 26916.73083269142, 29304.0, 30126.84936575303, 32049.67950320467, 32842.0, 46932.53067469282, 47542.524574755196, 47678.28482572695, 47760.0, 47887.521124788305, 339748.0, 340620.0, 341225.0, 341389.0, 343080.0, 30542.389152217274, 31520.684793151773, 38058.42912356372, 38241.426378603785, 38686.419703703905, 42108.0, 42308.57691423046, 42483.15033699247, 42838.35742463803, 42845.0, 33705.325893482484, 36232.751056870904, 36730.44904326384, 36962.26075478421, 37221.813890930374, 36526.26947461089, 36684.63315366919, 36706.4494032595, 36841.447378289886, 37064.25871482609, 35107.29785404226, 35771.64228357683, 36299.81850090733, 36679.449808253434, 36731.26537469289, 30451.0, 32155.196120097185, 32251.677483224867, 32259.67740322567, 32406.51390229101, 24307.029856785874, 25046.74953250444, 25089.87455062713, 25365.492690146464, 25492.872535637944], "value": 18242.970505964266, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.8383894925182, 378.6610983170061, 380.69924759440045, 388.2468012056612, 388.854179682619, 15976.600584985366, 16946.91526542367, 17758.644827103468, 17771.37800176994, 17800.109994500257, 11741.06071514279, 15908.52274431766, 16604.66790664186, 16657.083860387673, 17545.56136096598, 4516.593506584415, 4657.04530571232, 4691.88270293242, 4693.8357157499495, 4696.342512048316, 3503.0891968088354, 3645.0, 5118.104331741949, 5358.223057656657, 5361.0, 5439.646422982513, 5440.211169380437, 5446.809361672343, 5456.8090116845915, 5463.262459567967, 5175.197844334134, 5387.0303345397815, 5392.137258038695, 5413.296271484682, 5421.620486565942, 4990.850274491771, 5134.0, 5141.997310524449, 5143.0, 5156.510131537535, 20772.169113235454, 20832.895835520878, 20993.0, 21013.47466313339, 21063.262785802577, 1674.9916250418669, 1814.2561549764532, 1848.5101448116125, 1896.8387687046618, 2443.144899285254, 9310.627574896997, 9346.859797103049, 9733.902660973441, 9736.464494452803, 10567.73580660482, 1736.782902137233, 1746.9912650436793, 1751.3152317550628, 1827.972580411295, 1890.1022014542982, 53237.46762532364, 60273.698631507, 65839.67080164616, 66770.66614666943, 66985.33014669888, 10116.696499105039, 10152.746181345525, 10175.084242418217, 10233.641822536176, 10413.895861041445, 13871.167729936236, 13929.373178207004, 13936.86063139376, 13982.440702371994, 13985.44058237659, 7227.13274407064, 7264.164621068539, 7319.74380896666, 7323.231060738653, 7349.191588925214, 6971.163460384786, 6977.372036516738, 6992.545484543489, 7087.1849737279845, 7125.572465652078, 4379.868603941887, 4431.651224850834, 4462.776861156929, 4466.553344665507, 4496.0, 1704.514213449186, 1772.485979066082, 1833.6424397242677, 1848.3068849181557, 1877.3147801052517, 759.4797563668794, 859.9785005374915, 860.8063185783149, 882.2236431939851, 895.9462432254086, 16599.585010374838, 16873.409430669868, 17139.314427423014, 17166.914165429094, 17528.298868045134, 844.0883845446903, 853.3386625365391, 857.4041041476239, 862.98705519416, 874.5364956573027, 9309.301802364715, 9646.9035309646, 9711.9028809711, 9794.65718699842, 9802.686817800288, 376122.0, 383769.0, 386529.0, 386870.0, 387018.0, 73331.0, 74172.0, 75014.62492687501, 75983.24016759761, 76246.61876690581, 863.9395242333102, 876.6756300168978, 886.2422628652538, 896.789254525186, 897.1073781587362, 15109.47116850905, 15173.0, 15182.164980926154, 15472.0, 15752.763708544151, 35980.56077756965, 37302.44046339253, 37311.81344093262, 37619.81190094032, 37698.81150594229, 44426.77786611046, 45176.548234517235, 47160.76419618017, 49084.0, 50712.74643626758, 7787.143414224318, 7787.260210280015, 7787.766367008999, 7937.126916039347, 8005.199480051948, 10703.304285221435, 11739.119566032752, 12144.453499592362, 12192.57325993586, 12296.631101066982, 5107.361579802525, 10212.795744085226, 10411.323263987817, 10438.895611043792, 10671.092957098423, 10123.0, 10355.378677279386, 10459.267851250339, 10482.68551943443, 10482.790344192921, 10146.746331341776, 10692.305000174963, 10784.622538211124, 10788.298760580537, 10796.0, 7720.112187098555, 7783.396083963287, 7839.137694853675, 8044.3162331200165, 8055.838883222185, 10594.788104237718, 10708.839367409651, 10765.30025548337, 10801.621943231949, 10808.189385796253], "value": 8130.802735714379, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1195.1813008089464, 1349.0624016308666, 1394.9023568350217, 1470.2501724120696, 1698.2442812948243, 6556.606603603781, 7528.322450979411, 7711.807204819876, 7758.612069396536, 7986.840263194733, 7545.849083018336, 7662.6551805168765, 7681.155072941986, 7704.229577042293, 7707.845843083135, 2185.9453513662165, 2195.220696652689, 2210.4694873230387, 2211.159759291475, 2215.235743668432, 2121.692354608581, 2160.049578185601, 2180.073468775764, 2182.0835249195297, 2182.2471247419617, 2176.325339144862, 2191.9342419727354, 2199.692043113965, 2201.581699477102, 2214.180753121347, 2148.205164089289, 2154.7845215478483, 2154.924577639783, 2157.4390658428765, 2159.8272138228904, 2109.430453777487, 2157.503774131943, 2162.4485756132244, 2162.9567408651737, 2165.2096984600666, 7597.164311925684, 7805.5706936118495, 7839.608019599054, 7841.176676448948, 7850.136484986704, 4614.607758340545, 4792.137415265251, 4794.137055330039, 4795.424549054101, 4799.112164249625, 2805.186495916202, 2865.9713402866123, 2909.5199292116777, 2917.5040243158714, 2917.6352955880516, 928.424376886328, 935.4574346878794, 941.7692665296978, 943.0804965158984, 950.7908260182749, 10631.724193096801, 10674.146068314516, 10710.51802668874, 10715.19636027301, 10734.624288149955, 3984.143409167043, 4080.6123418275215, 4157.189348077157, 4273.017206042628, 4275.337322714984, 8386.87419688705, 8452.57737113142, 8456.915430845736, 8462.788430289293, 8469.237768600855, 4703.905921881543, 4706.811727530929, 4708.4349878014855, 4712.434507859079, 4712.835050773207, 3651.434027725707, 3800.3919372900205, 3872.6514613684903, 4343.239933011773, 4377.606015458624, 3454.084667563098, 3584.1577229351083, 3632.2917031178667, 3633.255182687543, 3635.163912300186, 1003.2575893838507, 1044.5821671331528, 1045.2526443592867, 1055.5250137438184, 1056.5668076088766, 825.195434451405, 856.0583358305938, 862.2239984014437, 878.8286284174525, 888.1030159538783, 5403.486668766461, 5489.423610520837, 5492.752826122834, 5502.367227768857, 5720.48515633595, 387.6840375094289, 393.06450647459434, 394.02478864809365, 402.36024720693655, 416.895776055986, 3820.675242604354, 3949.30887094763, 4060.959390406117, 4084.8570300039355, 4106.917861642751, 9525.333226674065, 9533.142017218484, 9533.52332383378, 9606.0, 9655.903440965641, 9554.952225238969, 9560.28297877662, 9591.088846559565, 9595.28035397334, 9606.759831004141, 6723.7646682365885, 7001.824954376181, 7131.607761573059, 7137.072180616487, 7146.499745017799, 5490.835274941758, 5579.972100139474, 5630.239917611234, 5699.601027928005, 5754.597178197653, 8945.910540894507, 8982.685606003759, 9045.909540904508, 9120.863187052331, 9135.720999060004, 8039.236272554098, 8924.107589241283, 9302.069793020644, 9372.906270937476, 9398.154166125081, 6517.3808488193545, 6686.83282917931, 6716.429103526255, 6887.104676392037, 6892.620905850226, 7281.19906810261, 7281.854362912818, 7294.343509084208, 7295.4528410368375, 7332.046833911558, 6520.608763474207, 6531.477481801539, 6554.14796076507, 6575.704093315908, 6650.467962562887, 6403.0, 6421.550491465552, 6644.069830223676, 6694.364035416627, 6898.241193468614, 6088.178095956989, 6156.26124865019, 6374.90437643445, 6379.904301435575, 6382.744690212339, 4635.605973492291, 4717.622590192707, 4753.9762301188275, 4756.476787553297, 4852.296417019583, 4698.318743782201, 4753.928691069567, 4769.165396055607, 4777.784999675093, 4807.975960120177], "value": 4047.3529473688254, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.76418864908067, 296.169245267026, 303.6811348084511, 306.7408040206025, 307.1691075640393, 4533.9319910201375, 4668.113058518879, 4736.692115012522, 4767.237242041274, 4767.475577686456, 4541.863744087683, 4684.852211208259, 4686.859394218171, 4703.741294228825, 4713.10451014307, 3750.512433383656, 3755.2864955658406, 3765.6610905018542, 3766.21104975136, 3766.5291838520184, 3599.9100022499383, 3633.8001409922445, 3767.905802354949, 3791.4502397152396, 3828.846846126152, 3728.384816505287, 3743.008102852746, 3752.3620984432714, 3771.15149091455, 3773.3585290500682, 3738.5513738351437, 3753.6246375362516, 3762.6671463692414, 3777.58446570877, 3784.2242340320445, 3361.092505023654, 3361.9159521011925, 3365.125067482448, 3370.5281260623533, 3386.255023894764, 4525.434320709911, 4595.517470665565, 4608.1705293047235, 4613.976930115362, 4635.258358662598, 4270.715167385465, 4281.892952676177, 4285.271503844354, 4292.948227684237, 4318.481782186126, 4968.925466117975, 4969.155243608595, 4969.801207951714, 4973.861239666941, 4988.575971042465, 3410.6248312685584, 3571.8749843755345, 3587.551556055493, 3664.13892735207, 3680.411134218539, 5201.323827902386, 5203.869903252411, 5204.271402003723, 5226.843194704165, 5233.764480598382, 3657.9634203658156, 3676.1544844685873, 3684.8576941147794, 3685.852565897334, 3695.2055308108884, 3737.7383583148917, 3743.3155080214015, 3743.737938344344, 3744.4196149596855, 3747.15688969985, 4038.596140385938, 4202.558731333227, 4205.957940420618, 4211.831526738896, 4224.387463817729, 4745.406824146981, 4932.53140951609, 4954.182559877652, 4963.6277279204205, 4968.90062198754, 1214.2532342609231, 1258.6349958511928, 1261.8296529968336, 1262.4003598290738, 1270.6505710929569, 798.596708662122, 820.9122912141437, 827.2554700769235, 827.3091968206495, 832.433944917462, 3880.767153970771, 3986.4817573715814, 3995.980020099939, 3996.1028254515245, 4001.9599804001587, 4501.459824821042, 4502.5047244803045, 4539.773011349419, 4552.658550608718, 4561.286034809284, 3900.0444891001885, 3915.941260881089, 3950.9012274692786, 3961.880393989202, 3978.3634618461488, 2332.591796435617, 2362.3227508468835, 2418.371223481872, 2426.2963740515406, 2440.7796101949025, 4265.210935976854, 4288.0, 4304.89237769052, 4306.978465107654, 4308.849190278325, 4574.336721175478, 4576.6109880660515, 4598.655100867449, 4621.491635920046, 4644.558766917137, 4288.871333859989, 4295.914081718348, 4335.655946656547, 4500.504944456107, 4897.16748152822, 5136.229565565195, 5138.922916156335, 5139.563137133236, 5145.475349385682, 5173.456787037458, 5062.70900920263, 5085.4406015337545, 5091.6181286402925, 5092.694438333712, 5106.055379754757, 4687.414073240845, 4887.535684110004, 4899.240617704261, 4928.186849169847, 4929.950700492949, 3642.9817850910576, 3643.653852883972, 3647.4164133738473, 3658.34149853029, 3674.0, 4937.0, 5179.585633149414, 5180.248863914787, 5182.429932707475, 5191.299174611379, 4856.927146092882, 4990.201567749143, 4990.326305948651, 4999.300097986212, 5004.574611158092, 4982.975085124551, 4986.476419975996, 4996.075725990703, 4999.575206025002, 5004.77478513475, 4779.996200797871, 4870.829520966749, 4908.361912951294, 4920.335754673217, 4921.138800709933, 4965.255211718271, 5001.974595207901, 5014.824481143289, 5018.799248030038, 5021.472745361684, 4891.877703057452, 4895.4859739728245, 4897.236994681983, 4898.559129678346, 4902.901941961212], "value": 3686.759285736261, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [522.2114606943519, 561.7444062951357, 604.3322129047402, 707.6780065070393, 719.2160545005943, 11989.76020479591, 12882.93558532206, 12925.095243332971, 12933.676658083552, 12950.546730864422, 12904.225746455195, 12998.870011299885, 13028.283444410554, 13058.2817945013, 13139.0, 752.5108679358406, 771.6951804037402, 771.9421043421738, 772.6368606754817, 773.6750564762813, 769.1885061260368, 769.607500174912, 770.4183341577129, 770.6339488742858, 771.0323543952331, 794.4875555266849, 797.8125140591985, 798.0303930724169, 798.7404093669552, 802.5826570183501, 804.7746630943345, 805.1746959366667, 806.0609390060572, 806.2340776262574, 806.3549160671469, 599.7930713903739, 600.2586805295457, 600.3576173494363, 600.4355905448864, 600.8798240351904, 10740.140788736882, 10810.0, 10839.566417343296, 10853.620123295726, 10855.077318427942, 10418.322809017389, 10732.640489252966, 10991.503114525609, 11135.443227838658, 11200.66398008053, 9421.905780942172, 9468.0, 11396.088312934946, 11415.429228538624, 12315.69210769729, 3235.983820080908, 3321.4021476134285, 3347.7991320520846, 3348.196432856121, 3351.798892066484, 43760.343594846105, 44133.0, 44239.11521769579, 44459.777701111285, 44635.0, 9244.399114057565, 9302.441853488812, 9535.0, 9635.421874687541, 9659.420434773936, 16629.6674066518, 17734.645307093786, 17817.821821781876, 17848.643027139646, 17854.0, 4795.928061079087, 5502.339719233718, 5708.571857110734, 6734.629595372302, 6788.151481064867, 3687.4100143976834, 3707.4253490708984, 3813.2945405100145, 3820.102275965146, 3945.704072194597, 2717.904873329424, 2845.01846862832, 2845.7865660075577, 2845.9715402845704, 2856.3287627407544, 507.32779067735396, 541.3341589844513, 543.2774410034644, 565.5842955427757, 603.0441749826574, 468.94372675278504, 611.4894063457064, 613.2242712968099, 621.8662987457718, 633.866887953535, 8189.631466584018, 8454.704085356983, 8644.22202001823, 8676.045634980148, 8746.43148195378, 579.122629216737, 583.1340459417761, 583.4398976982145, 599.9400059994053, 604.081795670587, 4243.681723870722, 4259.488861336597, 4272.786360681954, 4294.699371044059, 4362.6946113772365, 59787.40212597905, 76559.23440765521, 78691.60654196692, 78868.60565697135, 93177.0, 86582.13417865867, 86678.0, 87978.56010720033, 88104.0, 88164.5591772037, 19650.410487685393, 21445.78554214469, 29794.55308170379, 74366.62816685882, 81118.59440702877, 8754.12458754145, 9072.682456114004, 9142.451452912846, 9142.680006199751, 9248.491332976751, 30999.380012399175, 31180.0, 39132.608673912895, 45784.7710761444, 46288.0, 65365.67317163384, 68385.3161468379, 69127.0, 69463.0, 69974.65012674904, 11125.407999523095, 11419.828702569303, 11506.367149806842, 11997.700057498632, 12151.210171338835, 13183.67040823987, 13199.604011879659, 13223.867761322264, 14009.929950350182, 14253.216073116078, 11700.824487632523, 11766.352850593299, 12101.636950891487, 12191.461221821119, 12340.444679989243, 11698.76602467963, 12413.944814690853, 12687.492500299884, 12740.0, 12762.936185319015, 11198.496067676811, 12021.75956480883, 12026.939865300617, 12032.819507707216, 12035.939820300842, 10317.845232321672, 10478.37129772216, 10627.681169564925, 10632.202584806326, 10845.891541084487, 9337.206337461392, 9384.483853388128, 9535.904640953779, 10039.69880903574, 10056.949715251378], "value": 6328.041327136393, "unit": "score"}], "name": "dromaeo_css", "value": 6351.89204245876, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 114.0, 114.0, 115.0, 112.0, 113.0, 114.0, 114.0, 115.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 127.0, 128.0, 187.0, 123.0, 123.0, 154.0, 125.0, 157.0, 122.0], "value": 139.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 160.0, 161.0, 162.0, 160.0, 162.0, 160.0, 159.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 89.0, 77.0, 108.0, 76.0, 76.0, 75.0, 76.0, 109.0, 77.0], "value": 83.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 112.0, 85.0, 85.0, 85.0, 123.0, 84.0, 85.0, 128.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 146.0, 147.0, 145.0, 147.0, 145.0, 146.0, 147.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 64.0, 63.0, 63.0, 70.0, 63.0, 63.0, 64.0, 62.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 65.0, 66.0, 66.0, 65.0, 66.0, 67.0, 70.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 79.0, 88.0, 82.0, 85.0, 79.0, 86.0, 78.0, 86.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 129.0, 132.0, 134.0, 126.0, 128.0, 132.0, 128.0, 171.0, 134.0], "value": 135.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 168.0, 170.0, 163.0, 160.0, 170.0, 165.0, 166.0, 169.0, 165.0], "value": 166.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 70.0, 68.0, 68.0, 67.0, 66.0, 70.0, 66.0, 67.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1532.8, "alertThreshold": 2.0}]} 10:09:54 INFO - Return code: 0 10:09:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:09:54 INFO - # TBPL SUCCESS # 10:09:54 INFO - Running post-action listener: _resource_record_post_action 10:09:54 INFO - ##### 10:09:54 INFO - ##### Finished run-tests step (success) 10:09:54 INFO - ##### 10:09:54 INFO - Running post-run listener: _resource_record_post_run 10:09:54 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 546992128; Read time: 12; Write time: 683852 10:09:54 INFO - TinderboxPrint: CPU usage
13.0% 10:09:54 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 10:09:54 INFO - TinderboxPrint: I/O write bytes / time
546,992,128 / 683,852 10:09:54 INFO - TinderboxPrint: CPU idle
8,337.7 (87.0%) 10:09:54 INFO - TinderboxPrint: CPU user
1,202.7 (12.5%) 10:09:54 INFO - TinderboxPrint: Swap in / out
0 / 0 10:09:54 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 257859584; Read time: 0; Write time: 319592 10:09:54 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288854016; Read time: 12; Write time: 364236 10:09:54 INFO - Running post-run listener: _upload_blobber_files 10:09:54 INFO - Blob upload gear active. 10:09:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:09:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:09:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:09:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:09:55 INFO - (blobuploader) - INFO - Open directory for files ... 10:09:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:09:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:09:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:58 INFO - (blobuploader) - INFO - Done attempting. 10:09:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:09:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:09:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:59 INFO - (blobuploader) - INFO - Done attempting. 10:09:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:09:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:10:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:10:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:10:00 INFO - (blobuploader) - INFO - Done attempting. 10:10:00 INFO - (blobuploader) - INFO - Iteration through files over. 10:10:00 INFO - Return code: 0 10:10:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:10:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:10:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/856ab510926a6dab592d2ca10be467b4e0674d824d3906c2642c4e11fff4a516219c5f787301da3e942b79a11989fab5523b14cbb42a5744a73f85369988a2a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1e8f788bd40f3c083cf552a81e69a4b5856e1db19a3c98abb28059c683cfbabea231b60613b638a803a1b22301d2189740c9c65d25b5a328a6fbb1d8157f6bd0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6bce5f70b05f954f2a605693a1ccfe5ac8f3706cfc44409a1a7ba8a635ce3b46f3199cb2cf7109104a938165c3be703c88c161d9d6a451915978229fad028c58"} 10:10:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:10:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:10:00 INFO - Contents: 10:10:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/856ab510926a6dab592d2ca10be467b4e0674d824d3906c2642c4e11fff4a516219c5f787301da3e942b79a11989fab5523b14cbb42a5744a73f85369988a2a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1e8f788bd40f3c083cf552a81e69a4b5856e1db19a3c98abb28059c683cfbabea231b60613b638a803a1b22301d2189740c9c65d25b5a328a6fbb1d8157f6bd0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6bce5f70b05f954f2a605693a1ccfe5ac8f3706cfc44409a1a7ba8a635ce3b46f3199cb2cf7109104a938165c3be703c88c161d9d6a451915978229fad028c58"} 10:10:00 INFO - Running post-run listener: copy_logs_to_upload_dir 10:10:00 INFO - Copying logs to upload dir... 10:10:00 INFO - mkdir: /builds/slave/test/build/upload/logs 10:10:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.669558 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-18 10:10:00.216271) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 10:10:00.219959) ========= 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=fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699 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/856ab510926a6dab592d2ca10be467b4e0674d824d3906c2642c4e11fff4a516219c5f787301da3e942b79a11989fab5523b14cbb42a5744a73f85369988a2a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1e8f788bd40f3c083cf552a81e69a4b5856e1db19a3c98abb28059c683cfbabea231b60613b638a803a1b22301d2189740c9c65d25b5a328a6fbb1d8157f6bd0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6bce5f70b05f954f2a605693a1ccfe5ac8f3706cfc44409a1a7ba8a635ce3b46f3199cb2cf7109104a938165c3be703c88c161d9d6a451915978229fad028c58"} build_url:https://queue.taskcluster.net/v1/task/Syd1W6a3TW-fNxE34ukOyQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011333 build_url: 'https://queue.taskcluster.net/v1/task/Syd1W6a3TW-fNxE34ukOyQ/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/856ab510926a6dab592d2ca10be467b4e0674d824d3906c2642c4e11fff4a516219c5f787301da3e942b79a11989fab5523b14cbb42a5744a73f85369988a2a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1e8f788bd40f3c083cf552a81e69a4b5856e1db19a3c98abb28059c683cfbabea231b60613b638a803a1b22301d2189740c9c65d25b5a328a6fbb1d8157f6bd0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6bce5f70b05f954f2a605693a1ccfe5ac8f3706cfc44409a1a7ba8a635ce3b46f3199cb2cf7109104a938165c3be703c88c161d9d6a451915978229fad028c58"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 10:10:00.248572) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:10:00.249191) ========= 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=fa44defc139c4ba8574fe040000001db-1468860559.103588-161737699 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004413 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:10:00.332455) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 10:10:00.332919) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 10:10:00.333426) ========= ========= Total master_lag: 0.18 =========