builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1471943751.36 results: success (0) buildid: 20160822233001 builduid: fac8281ff5cc4f86b4a1cbca21930ad6 revision: ed793a550dd602631e25febe9fc39f3bab41e80a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 02:15:51.362409) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 02:15:51.363283) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 02:15:51.363609) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 02:15:51.412173) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 02:15:51.412455) ========= 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=c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-23 02:15:51-- 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% 222M=0s 2016-08-23 02:15:51 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088678 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 02:15:51.515098) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 02:15:51.515438) ========= 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=c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022372 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 02:15:51.568225) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 02:15:51.568632) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ed793a550dd602631e25febe9fc39f3bab41e80a --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 ed793a550dd602631e25febe9fc39f3bab41e80a --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=c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-23 02:15:52,063 truncating revision to first 12 chars 2016-08-23 02:15:52,063 Setting DEBUG logging. 2016-08-23 02:15:52,063 attempt 1/10 2016-08-23 02:15:52,063 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ed793a550dd6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-23 02:15:52,326 unpacking tar archive at: fx-team-ed793a550dd6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.535146 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 02:15:52.119679) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 02:15:52.120000) ========= script_repo_revision: ed793a550dd602631e25febe9fc39f3bab41e80a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 02:15:52.120412) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 02:15:52.120715) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 02:15:52.131457) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-08-23 02:15:52.131766) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:15:52 INFO - MultiFileLogger online at 20160823 02:15:52 in /builds/slave/test 02:15:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 02:15:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:15:52 INFO - {'append_to_log': False, 02:15:52 INFO - 'base_work_dir': '/builds/slave/test', 02:15:52 INFO - 'blob_upload_branch': 'Fx-Team', 02:15:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:15:52 INFO - 'branch': 'Fx-Team', 02:15:52 INFO - 'buildbot_json_path': 'buildprops.json', 02:15:52 INFO - 'config_files': ('talos/linux_config.py',), 02:15:52 INFO - 'default_actions': ('clobber', 02:15:52 INFO - 'read-buildbot-config', 02:15:52 INFO - 'download-and-extract', 02:15:52 INFO - 'populate-webroot', 02:15:52 INFO - 'create-virtualenv', 02:15:52 INFO - 'install', 02:15:52 INFO - 'run-tests'), 02:15:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:15:52 INFO - 'download_minidump_stackwalk': True, 02:15:52 INFO - 'download_symbols': 'ondemand', 02:15:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:15:52 INFO - 'tooltool.py': '/tools/tooltool.py', 02:15:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:15:52 INFO - '/tools/misc-python/virtualenv.py')}, 02:15:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:15:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:15:52 INFO - 'installer_path': 'installer.exe', 02:15:52 INFO - 'log_level': 'info', 02:15:52 INFO - 'log_name': 'talos', 02:15:52 INFO - 'log_to_console': True, 02:15:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:15:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:15:52 INFO - 'opt_config_files': (), 02:15:52 INFO - 'pip_index': False, 02:15:52 INFO - 'sps_profile': False, 02:15:52 INFO - 'sps_profile_interval': 0, 02:15:52 INFO - 'suite': 'dromaeojs', 02:15:52 INFO - 'system_bits': '32', 02:15:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:15:52 INFO - 'title': 'talos-linux64-ix-004', 02:15:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:15:52 INFO - 'use_talos_json': True, 02:15:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:15:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:15:52 INFO - 'work_dir': 'build'} 02:15:52 INFO - [mozharness: 2016-08-23 09:15:52.724218Z] Running clobber step. 02:15:52 INFO - Running pre-action listener: _resource_record_pre_action 02:15:52 INFO - Running main action method: clobber 02:15:52 INFO - rmtree: /builds/slave/test/build 02:15:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:15:53 INFO - Running post-action listener: _resource_record_post_action 02:15:53 INFO - [mozharness: 2016-08-23 09:15:53.184035Z] Finished clobber step (success) 02:15:53 INFO - [mozharness: 2016-08-23 09:15:53.184136Z] Running read-buildbot-config step. 02:15:53 INFO - Running pre-action listener: _resource_record_pre_action 02:15:53 INFO - Running main action method: read_buildbot_config 02:15:53 INFO - Using buildbot properties: 02:15:53 INFO - { 02:15:53 INFO - "project": "", 02:15:53 INFO - "product": "firefox", 02:15:53 INFO - "script_repo_revision": "production", 02:15:53 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 02:15:53 INFO - "repository": "", 02:15:53 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 02:15:53 INFO - "buildid": "20160822233001", 02:15:53 INFO - "pgo_build": "True", 02:15:53 INFO - "basedir": "/builds/slave/test", 02:15:53 INFO - "buildnumber": 1001, 02:15:53 INFO - "slavename": "talos-linux64-ix-004", 02:15:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:15:53 INFO - "platform": "ubuntu64_hw", 02:15:53 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 02:15:53 INFO - "branch": "fx-team", 02:15:53 INFO - "repo_path": "integration/fx-team", 02:15:53 INFO - "revision": "ed793a550dd602631e25febe9fc39f3bab41e80a", 02:15:53 INFO - "stage_platform": "linux64-pgo", 02:15:53 INFO - "builduid": "fac8281ff5cc4f86b4a1cbca21930ad6", 02:15:53 INFO - "slavebuilddir": "test" 02:15:53 INFO - } 02:15:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 02:15:53 INFO - Running post-action listener: _resource_record_post_action 02:15:53 INFO - [mozharness: 2016-08-23 09:15:53.186529Z] Finished read-buildbot-config step (success) 02:15:53 INFO - [mozharness: 2016-08-23 09:15:53.186618Z] Running download-and-extract step. 02:15:53 INFO - Running pre-action listener: _resource_record_pre_action 02:15:53 INFO - Running main action method: download_and_extract 02:15:53 INFO - mkdir: /builds/slave/test/build/tests 02:15:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:15:53 INFO - trying https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 02:15:53 INFO - Downloading https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 02:15:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 02:15:53 INFO - Downloaded 1361 bytes. 02:15:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 02:15:53 INFO - Using the following test package requirements: 02:15:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 02:15:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:15:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 02:15:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:15:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 02:15:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:15:53 INFO - u'jsshell-linux-x86_64.zip'], 02:15:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:15:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 02:15:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 02:15:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:15:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 02:15:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:15:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 02:15:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:15:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 02:15:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 02:15:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 02:15:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:15:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:15:53 INFO - trying https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 02:15:53 INFO - Downloading https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 02:15:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:15:54 INFO - Downloaded 21921122 bytes. 02:15:54 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 02:15:59 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 02:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:15:59 INFO - trying https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 02:15:59 INFO - Downloading https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 02:15:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:16:00 INFO - Downloaded 21921122 bytes. 02:16:00 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 02:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:04 INFO - trying https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 02:16:04 INFO - Downloading https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 02:16:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:16:05 INFO - Downloaded 14413981 bytes. 02:16:05 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 02:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:05 INFO - trying https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:16:05 INFO - Downloading https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:16:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 02:16:08 INFO - Downloaded 59777418 bytes. 02:16:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:16:08 INFO - mkdir: /builds/slave/test/properties 02:16:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:16:08 INFO - Writing to file /builds/slave/test/properties/build_url 02:16:08 INFO - Contents: 02:16:08 INFO - build_url:https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 02:16:09 INFO - Running post-action listener: _resource_record_post_action 02:16:09 INFO - Running post-action listener: set_extra_try_arguments 02:16:09 INFO - [mozharness: 2016-08-23 09:16:09.086675Z] Finished download-and-extract step (success) 02:16:09 INFO - [mozharness: 2016-08-23 09:16:09.086899Z] Running populate-webroot step. 02:16:09 INFO - Running pre-action listener: _resource_record_pre_action 02:16:09 INFO - Running main action method: populate_webroot 02:16:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:16:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:16:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:16:09 INFO - '--tppagecycles', 02:16:09 INFO - '7'], 02:16:09 INFO - 'tests': ['tsvgm']}}, 02:16:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:16:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:16:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:16:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:16:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:16:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:16:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:09 INFO - 'talos_options': ['--disable-e10s'], 02:16:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:16:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:16:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:09 INFO - 'talos_options': ['--disable-e10s'], 02:16:09 INFO - 'tests': ['damp', 'tps']}, 02:16:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:09 INFO - 'tests': ['damp', 'tps']}, 02:16:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:16:09 INFO - 'tests': ['dromaeo_dom']}, 02:16:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:16:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:16:09 INFO - 'tests': ['basic_compositor_video']}, 02:16:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:16:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:16:09 INFO - 'tests': ['a11yr', 02:16:09 INFO - 'ts_paint', 02:16:09 INFO - 'tpaint', 02:16:09 INFO - 'sessionrestore', 02:16:09 INFO - 'sessionrestore_no_auto_restore', 02:16:09 INFO - 'tabpaint']}, 02:16:09 INFO - 'other-e10s': {'tests': ['a11yr', 02:16:09 INFO - 'ts_paint', 02:16:09 INFO - 'tpaint', 02:16:09 INFO - 'sessionrestore', 02:16:09 INFO - 'sessionrestore_no_auto_restore', 02:16:09 INFO - 'tabpaint']}, 02:16:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:16:09 INFO - 'ts_paint', 02:16:09 INFO - 'tpaint', 02:16:09 INFO - 'sessionrestore', 02:16:09 INFO - 'sessionrestore_no_auto_restore', 02:16:09 INFO - 'tabpaint']}, 02:16:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:16:09 INFO - 'ts_paint', 02:16:09 INFO - 'tpaint', 02:16:09 INFO - 'sessionrestore', 02:16:09 INFO - 'sessionrestore_no_auto_restore', 02:16:09 INFO - 'tabpaint']}, 02:16:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:16:09 INFO - 'tests': ['a11yr', 02:16:09 INFO - 'ts_paint', 02:16:09 INFO - 'tpaint', 02:16:09 INFO - 'sessionrestore', 02:16:09 INFO - 'sessionrestore_no_auto_restore', 02:16:09 INFO - 'tabpaint']}, 02:16:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:16:09 INFO - 'tests': ['a11yr', 02:16:09 INFO - 'ts_paint', 02:16:09 INFO - 'tpaint', 02:16:09 INFO - 'sessionrestore', 02:16:09 INFO - 'sessionrestore_no_auto_restore', 02:16:09 INFO - 'tabpaint']}, 02:16:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:16:09 INFO - 'tests': ['tsvgx', 02:16:09 INFO - 'tsvgr_opacity', 02:16:09 INFO - 'tart', 02:16:09 INFO - 'tscrollx', 02:16:09 INFO - 'cart']}, 02:16:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:16:09 INFO - 'tsvgr_opacity', 02:16:09 INFO - 'tart', 02:16:09 INFO - 'tscrollx', 02:16:09 INFO - 'cart']}, 02:16:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:09 INFO - 'talos_options': ['--disable-e10s'], 02:16:09 INFO - 'tests': ['tp5o']}, 02:16:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:09 INFO - 'tests': ['tp5o']}, 02:16:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:09 INFO - 'talos_options': ['--disable-e10s', 02:16:09 INFO - '--xperf_path', 02:16:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:16:09 INFO - 'tests': ['tp5n']}, 02:16:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:09 INFO - 'talos_options': ['--xperf_path', 02:16:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:16:09 INFO - 'tests': ['tp5n']}}, 02:16:09 INFO - 'talos.zip': {'path': '', 02:16:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:16:09 INFO - Running post-action listener: _resource_record_post_action 02:16:09 INFO - [mozharness: 2016-08-23 09:16:09.103169Z] Finished populate-webroot step (success) 02:16:09 INFO - [mozharness: 2016-08-23 09:16:09.103257Z] Running create-virtualenv step. 02:16:09 INFO - Running pre-action listener: _resource_record_pre_action 02:16:09 INFO - Running main action method: create_virtualenv 02:16:09 INFO - Creating virtualenv /builds/slave/test/build/venv 02:16:09 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 02:16:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:16:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:16:09 INFO - Using real prefix '/usr' 02:16:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:16:09 INFO - Installing distribute.............................................................................................................................................................................................done. 02:16:11 INFO - Installing pip.................done. 02:16:11 INFO - Return code: 0 02:16:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f56ef4adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb370>, '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 0x1eee8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eeed80>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', '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 02:16:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:16:11 INFO - Using env: {'DISPLAY': ':0', 02:16:11 INFO - 'HOME': '/home/cltbld', 02:16:11 INFO - 'LANG': 'en_US.UTF-8', 02:16:11 INFO - 'LANGUAGE': 'en_US:en', 02:16:11 INFO - 'LOGNAME': 'cltbld', 02:16:11 INFO - 'MAIL': '/var/mail/cltbld', 02:16:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:11 INFO - 'MOZ_NO_REMOTE': '1', 02:16:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:11 INFO - 'NO_EM_RESTART': '1', 02:16:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:11 INFO - 'PWD': '/builds/slave/test', 02:16:11 INFO - 'SHELL': '/bin/bash', 02:16:11 INFO - 'SHLVL': '1', 02:16:11 INFO - 'TERM': 'linux', 02:16:11 INFO - 'TMOUT': '86400', 02:16:11 INFO - 'USER': 'cltbld', 02:16:11 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', 02:16:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:11 INFO - '_': '/tools/buildbot/bin/python'} 02:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:11 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 02:16:11 INFO - Cleaning up... 02:16:11 INFO - Return code: 0 02:16:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f56ef4adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb370>, '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 0x1eee8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eeed80>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', '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 02:16:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:16:11 INFO - Using env: {'DISPLAY': ':0', 02:16:11 INFO - 'HOME': '/home/cltbld', 02:16:11 INFO - 'LANG': 'en_US.UTF-8', 02:16:11 INFO - 'LANGUAGE': 'en_US:en', 02:16:11 INFO - 'LOGNAME': 'cltbld', 02:16:11 INFO - 'MAIL': '/var/mail/cltbld', 02:16:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:11 INFO - 'MOZ_NO_REMOTE': '1', 02:16:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:11 INFO - 'NO_EM_RESTART': '1', 02:16:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:11 INFO - 'PWD': '/builds/slave/test', 02:16:11 INFO - 'SHELL': '/bin/bash', 02:16:11 INFO - 'SHLVL': '1', 02:16:11 INFO - 'TERM': 'linux', 02:16:11 INFO - 'TMOUT': '86400', 02:16:11 INFO - 'USER': 'cltbld', 02:16:11 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', 02:16:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:11 INFO - '_': '/tools/buildbot/bin/python'} 02:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:12 INFO - Downloading/unpacking psutil>=3.1.1 02:16:12 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 02:16:12 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 02:16:12 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 02:16:12 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 02:16:12 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 02:16:12 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 02:16:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:16:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:16:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:16:14 INFO - Installing collected packages: psutil 02:16:14 INFO - Running setup.py install for psutil 02:16:14 INFO - building 'psutil._psutil_linux' extension 02:16:14 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 02:16:14 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 02:16:14 INFO - building 'psutil._psutil_posix' extension 02:16:14 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 02:16:14 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 02:16:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:16:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:16:14 INFO - Successfully installed psutil 02:16:14 INFO - Cleaning up... 02:16:14 INFO - Return code: 0 02:16:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:16:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:14 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 0x7f56ef4adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb370>, '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 0x1eee8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eeed80>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', '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 02:16:14 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 02:16:14 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 02:16:14 INFO - Using env: {'DISPLAY': ':0', 02:16:14 INFO - 'HOME': '/home/cltbld', 02:16:14 INFO - 'LANG': 'en_US.UTF-8', 02:16:14 INFO - 'LANGUAGE': 'en_US:en', 02:16:14 INFO - 'LOGNAME': 'cltbld', 02:16:14 INFO - 'MAIL': '/var/mail/cltbld', 02:16:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:14 INFO - 'MOZ_NO_REMOTE': '1', 02:16:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:14 INFO - 'NO_EM_RESTART': '1', 02:16:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:14 INFO - 'PWD': '/builds/slave/test', 02:16:14 INFO - 'SHELL': '/bin/bash', 02:16:14 INFO - 'SHLVL': '1', 02:16:14 INFO - 'TERM': 'linux', 02:16:14 INFO - 'TMOUT': '86400', 02:16:14 INFO - 'USER': 'cltbld', 02:16:14 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', 02:16:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:14 INFO - '_': '/tools/buildbot/bin/python'} 02:16:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:16:14 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 02:16:14 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 02:16:14 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 02:16:14 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 02:16:14 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 02:16:14 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 02:16:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:16:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:16:16 INFO - Installing collected packages: mozsystemmonitor 02:16:16 INFO - Running setup.py install for mozsystemmonitor 02:16:16 INFO - Successfully installed mozsystemmonitor 02:16:16 INFO - Cleaning up... 02:16:16 INFO - Return code: 0 02:16:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:16 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 0x7f56ef4adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb370>, '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 0x1eee8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eeed80>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', '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 02:16:16 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 02:16:16 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 02:16:16 INFO - Using env: {'DISPLAY': ':0', 02:16:16 INFO - 'HOME': '/home/cltbld', 02:16:16 INFO - 'LANG': 'en_US.UTF-8', 02:16:16 INFO - 'LANGUAGE': 'en_US:en', 02:16:16 INFO - 'LOGNAME': 'cltbld', 02:16:16 INFO - 'MAIL': '/var/mail/cltbld', 02:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:16 INFO - 'MOZ_NO_REMOTE': '1', 02:16:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:16 INFO - 'NO_EM_RESTART': '1', 02:16:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:16 INFO - 'PWD': '/builds/slave/test', 02:16:16 INFO - 'SHELL': '/bin/bash', 02:16:16 INFO - 'SHLVL': '1', 02:16:16 INFO - 'TERM': 'linux', 02:16:16 INFO - 'TMOUT': '86400', 02:16:16 INFO - 'USER': 'cltbld', 02:16:16 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', 02:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:16 INFO - '_': '/tools/buildbot/bin/python'} 02:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:17 INFO - Downloading/unpacking jsonschema==2.5.1 02:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:16:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:19 INFO - Downloading functools32-3.2.3-2.tar.gz 02:16:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:16:19 INFO - warning: no files found matching '*.txt' 02:16:19 INFO - no previously-included directories found matching 'build' 02:16:19 INFO - no previously-included directories found matching 'dist' 02:16:19 INFO - no previously-included directories found matching '.git*' 02:16:19 INFO - Installing collected packages: jsonschema, functools32 02:16:19 INFO - Running setup.py install for functools32 02:16:19 INFO - warning: no files found matching '*.txt' 02:16:19 INFO - no previously-included directories found matching 'build' 02:16:19 INFO - no previously-included directories found matching 'dist' 02:16:19 INFO - no previously-included directories found matching '.git*' 02:16:19 INFO - Successfully installed jsonschema functools32 02:16:19 INFO - Cleaning up... 02:16:19 INFO - Return code: 0 02:16:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f56ef4adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb370>, '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 0x1eee8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eeed80>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', '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 02:16:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:16:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:16:19 INFO - Using env: {'DISPLAY': ':0', 02:16:19 INFO - 'HOME': '/home/cltbld', 02:16:19 INFO - 'LANG': 'en_US.UTF-8', 02:16:19 INFO - 'LANGUAGE': 'en_US:en', 02:16:19 INFO - 'LOGNAME': 'cltbld', 02:16:19 INFO - 'MAIL': '/var/mail/cltbld', 02:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:19 INFO - 'MOZ_NO_REMOTE': '1', 02:16:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:19 INFO - 'NO_EM_RESTART': '1', 02:16:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:19 INFO - 'PWD': '/builds/slave/test', 02:16:19 INFO - 'SHELL': '/bin/bash', 02:16:19 INFO - 'SHLVL': '1', 02:16:19 INFO - 'TERM': 'linux', 02:16:19 INFO - 'TMOUT': '86400', 02:16:19 INFO - 'USER': 'cltbld', 02:16:19 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', 02:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:19 INFO - '_': '/tools/buildbot/bin/python'} 02:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:16:19 INFO - Cleaning up... 02:16:19 INFO - Return code: 0 02:16:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:19 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 0x7f56ef4adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb370>, '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 0x1eee8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eeed80>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', '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 02:16:19 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 02:16:19 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 02:16:19 INFO - Using env: {'DISPLAY': ':0', 02:16:19 INFO - 'HOME': '/home/cltbld', 02:16:19 INFO - 'LANG': 'en_US.UTF-8', 02:16:19 INFO - 'LANGUAGE': 'en_US:en', 02:16:19 INFO - 'LOGNAME': 'cltbld', 02:16:19 INFO - 'MAIL': '/var/mail/cltbld', 02:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:19 INFO - 'MOZ_NO_REMOTE': '1', 02:16:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:19 INFO - 'NO_EM_RESTART': '1', 02:16:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:19 INFO - 'PWD': '/builds/slave/test', 02:16:19 INFO - 'SHELL': '/bin/bash', 02:16:19 INFO - 'SHLVL': '1', 02:16:19 INFO - 'TERM': 'linux', 02:16:19 INFO - 'TMOUT': '86400', 02:16:19 INFO - 'USER': 'cltbld', 02:16:19 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', 02:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:19 INFO - '_': '/tools/buildbot/bin/python'} 02:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:19 INFO - Downloading/unpacking blobuploader==1.2.4 02:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:21 INFO - Downloading blobuploader-1.2.4.tar.gz 02:16:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:16:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:16:21 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 02:16:21 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 02:16:21 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 02:16:21 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 02:16:21 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 02:16:21 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 02:16:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:16:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:16:22 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 02:16:22 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 02:16:22 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 02:16:22 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 02:16:22 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 02:16:22 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 02:16:22 INFO - Downloading docopt-0.6.1.tar.gz 02:16:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:16:22 INFO - Installing collected packages: blobuploader, requests, docopt 02:16:22 INFO - Running setup.py install for blobuploader 02:16:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:16:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:16:22 INFO - Running setup.py install for requests 02:16:23 INFO - Running setup.py install for docopt 02:16:23 INFO - Successfully installed blobuploader requests docopt 02:16:23 INFO - Cleaning up... 02:16:23 INFO - Return code: 0 02:16:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:16: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:23 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 0x7f56ef4adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb370>, '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 0x1eee8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eeed80>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', '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 02:16:23 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 02:16:23 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 02:16:23 INFO - Using env: {'DISPLAY': ':0', 02:16:23 INFO - 'HOME': '/home/cltbld', 02:16:23 INFO - 'LANG': 'en_US.UTF-8', 02:16:23 INFO - 'LANGUAGE': 'en_US:en', 02:16:23 INFO - 'LOGNAME': 'cltbld', 02:16:23 INFO - 'MAIL': '/var/mail/cltbld', 02:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:23 INFO - 'MOZ_NO_REMOTE': '1', 02:16:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:23 INFO - 'NO_EM_RESTART': '1', 02:16:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:23 INFO - 'PWD': '/builds/slave/test', 02:16:23 INFO - 'SHELL': '/bin/bash', 02:16:23 INFO - 'SHLVL': '1', 02:16:23 INFO - 'TERM': 'linux', 02:16:23 INFO - 'TMOUT': '86400', 02:16:23 INFO - 'USER': 'cltbld', 02:16:23 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', 02:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:23 INFO - '_': '/tools/buildbot/bin/python'} 02:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:16:23 INFO - Running setup.py (path:/tmp/pip-1q8nNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:16:23 INFO - Running setup.py (path:/tmp/pip-fSsGmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:16:23 INFO - Running setup.py (path:/tmp/pip-6LOeVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:16:23 INFO - Running setup.py (path:/tmp/pip-_FaoJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:16:23 INFO - Running setup.py (path:/tmp/pip-0kFFBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:16:23 INFO - Running setup.py (path:/tmp/pip-EuwRt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:16:23 INFO - Running setup.py (path:/tmp/pip-8Mi4Bt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:16:23 INFO - Running setup.py (path:/tmp/pip-Re1OIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:16:23 INFO - Running setup.py (path:/tmp/pip-OQ1tyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:16:24 INFO - Running setup.py (path:/tmp/pip-1RwCMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:16:24 INFO - Running setup.py (path:/tmp/pip-sSvlYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:16:24 INFO - Running setup.py (path:/tmp/pip-5VWRfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:16:24 INFO - Running setup.py (path:/tmp/pip-F0nQs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:16:24 INFO - Running setup.py (path:/tmp/pip-Vbv4Mn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:24 INFO - Running setup.py (path:/tmp/pip-cOlZn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:16:24 INFO - Running setup.py (path:/tmp/pip-Y6r_mW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:16:24 INFO - Running setup.py (path:/tmp/pip-nmZKkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:16:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:16:24 INFO - Running setup.py install for manifestparser 02:16:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:16:24 INFO - Running setup.py install for mozcrash 02:16:24 INFO - Running setup.py install for mozdebug 02:16:24 INFO - Running setup.py install for mozdevice 02:16:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:16:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:16:25 INFO - Running setup.py install for mozfile 02:16:25 INFO - Running setup.py install for mozhttpd 02:16:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:16:25 INFO - Running setup.py install for mozinfo 02:16:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:16:25 INFO - Running setup.py install for mozInstall 02:16:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:16:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:16:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:16:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:16:25 INFO - Running setup.py install for mozleak 02:16:25 INFO - Running setup.py install for mozlog 02:16:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:16:25 INFO - Running setup.py install for moznetwork 02:16:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:16:25 INFO - Running setup.py install for mozprocess 02:16:25 INFO - Running setup.py install for mozprofile 02:16:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:16:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:16:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:16:25 INFO - Running setup.py install for mozrunner 02:16:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:16:26 INFO - Running setup.py install for mozscreenshot 02:16:26 INFO - Running setup.py install for moztest 02:16:26 INFO - Running setup.py install for mozversion 02:16:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:16:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:16:26 INFO - Cleaning up... 02:16:26 INFO - Return code: 0 02:16:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:26 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 0x7f56ef4adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb370>, '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 0x1eee8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eeed80>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', '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 02:16:26 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 02:16:26 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 02:16:26 INFO - Using env: {'DISPLAY': ':0', 02:16:26 INFO - 'HOME': '/home/cltbld', 02:16:26 INFO - 'LANG': 'en_US.UTF-8', 02:16:26 INFO - 'LANGUAGE': 'en_US:en', 02:16:26 INFO - 'LOGNAME': 'cltbld', 02:16:26 INFO - 'MAIL': '/var/mail/cltbld', 02:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:26 INFO - 'MOZ_NO_REMOTE': '1', 02:16:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:26 INFO - 'NO_EM_RESTART': '1', 02:16:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:26 INFO - 'PWD': '/builds/slave/test', 02:16:26 INFO - 'SHELL': '/bin/bash', 02:16:26 INFO - 'SHLVL': '1', 02:16:26 INFO - 'TERM': 'linux', 02:16:26 INFO - 'TMOUT': '86400', 02:16:26 INFO - 'USER': 'cltbld', 02:16:26 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', 02:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:26 INFO - '_': '/tools/buildbot/bin/python'} 02:16:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:16:26 INFO - Running setup.py (path:/tmp/pip-k8Q6ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:16:26 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)) 02:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:16:26 INFO - Running setup.py (path:/tmp/pip-9xaMpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:16:26 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)) 02:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:16:26 INFO - Running setup.py (path:/tmp/pip-ypBBsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:16:26 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)) 02:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:16:26 INFO - Running setup.py (path:/tmp/pip-OKMqCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:16:26 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)) 02:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:16:26 INFO - Running setup.py (path:/tmp/pip-R3U9oO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:16:27 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)) 02:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:16:27 INFO - Running setup.py (path:/tmp/pip-1iYI6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:16:27 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)) 02:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:16:27 INFO - Running setup.py (path:/tmp/pip-4C4y63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:16:27 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)) 02:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:16:27 INFO - Running setup.py (path:/tmp/pip-FLruC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:16:27 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)) 02:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:16:27 INFO - Running setup.py (path:/tmp/pip-nb7s5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:16:27 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)) 02:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:16:27 INFO - Running setup.py (path:/tmp/pip-lCOrn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:16:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:16:27 INFO - Running setup.py (path:/tmp/pip-Zod0qJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:16:27 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)) 02:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:16:27 INFO - Running setup.py (path:/tmp/pip-kDB0UJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:16:27 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)) 02:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:16:27 INFO - Running setup.py (path:/tmp/pip-rajq3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:16:27 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)) 02:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:16:27 INFO - Running setup.py (path:/tmp/pip-z_a2Rn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:16:27 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)) 02:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:27 INFO - Running setup.py (path:/tmp/pip-WQ9bPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:27 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)) 02:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:16:27 INFO - Running setup.py (path:/tmp/pip-g5Ipx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:16:27 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)) 02:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:16:27 INFO - Running setup.py (path:/tmp/pip-OPu5z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:16:27 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)) 02:16:27 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)) 02:16:27 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)) 02:16:27 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)) 02:16:27 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)) 02:16:27 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)) 02:16:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:16:27 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 02:16:27 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 02:16:27 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 02:16:27 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 02:16:27 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 02:16:27 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 02:16:29 INFO - Downloading blessings-1.6.tar.gz 02:16:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:16:29 INFO - Installing collected packages: blessings 02:16:29 INFO - Running setup.py install for blessings 02:16:29 INFO - Successfully installed blessings 02:16:29 INFO - Cleaning up... 02:16:30 INFO - Return code: 0 02:16:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:16:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:16:30 INFO - Reading from file tmpfile_stdout 02:16:30 INFO - Current package versions: 02:16:30 INFO - argparse == 1.2.1 02:16:30 INFO - blessings == 1.6 02:16:30 INFO - blobuploader == 1.2.4 02:16:30 INFO - docopt == 0.6.1 02:16:30 INFO - functools32 == 3.2.3-2 02:16:30 INFO - jsonschema == 2.5.1 02:16:30 INFO - manifestparser == 1.1 02:16:30 INFO - mozInstall == 1.12 02:16:30 INFO - mozcrash == 0.17 02:16:30 INFO - mozdebug == 0.1 02:16:30 INFO - mozdevice == 0.48 02:16:30 INFO - mozfile == 1.2 02:16:30 INFO - mozhttpd == 0.7 02:16:30 INFO - mozinfo == 0.9 02:16:30 INFO - mozleak == 0.1 02:16:30 INFO - mozlog == 3.3 02:16:30 INFO - moznetwork == 0.27 02:16:30 INFO - mozprocess == 0.23 02:16:30 INFO - mozprofile == 0.28 02:16:30 INFO - mozrunner == 6.12 02:16:30 INFO - mozscreenshot == 0.1 02:16:30 INFO - mozsystemmonitor == 0.3 02:16:30 INFO - moztest == 0.7 02:16:30 INFO - mozversion == 1.4 02:16:30 INFO - psutil == 3.1.1 02:16:30 INFO - requests == 1.2.3 02:16:30 INFO - wsgiref == 0.1.2 02:16:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:30 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 0x7f56ef4adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb370>, '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 0x1eee8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eeed80>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', '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 02:16:30 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 02:16:30 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 02:16:30 INFO - Using env: {'DISPLAY': ':0', 02:16:30 INFO - 'HOME': '/home/cltbld', 02:16:30 INFO - 'LANG': 'en_US.UTF-8', 02:16:30 INFO - 'LANGUAGE': 'en_US:en', 02:16:30 INFO - 'LOGNAME': 'cltbld', 02:16:30 INFO - 'MAIL': '/var/mail/cltbld', 02:16:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:30 INFO - 'MOZ_NO_REMOTE': '1', 02:16:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:30 INFO - 'NO_EM_RESTART': '1', 02:16:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:30 INFO - 'PWD': '/builds/slave/test', 02:16:30 INFO - 'SHELL': '/bin/bash', 02:16:30 INFO - 'SHLVL': '1', 02:16:30 INFO - 'TERM': 'linux', 02:16:30 INFO - 'TMOUT': '86400', 02:16:30 INFO - 'USER': 'cltbld', 02:16:30 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', 02:16:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:30 INFO - '_': '/tools/buildbot/bin/python'} 02:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:30 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)) 02:16:30 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)) 02:16:30 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)) 02:16:30 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)) 02:16:30 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)) 02:16:30 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)) 02:16:30 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)) 02:16:30 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)) 02:16:30 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)) 02:16:30 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)) 02:16:30 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)) 02:16:30 INFO - Cleaning up... 02:16:30 INFO - Return code: 0 02:16:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:30 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 0x7f56ef4adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef0850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb370>, '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 0x1eee8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eeed80>, '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': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', '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 02:16:30 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 02:16:30 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 02:16:30 INFO - Using env: {'DISPLAY': ':0', 02:16:30 INFO - 'HOME': '/home/cltbld', 02:16:30 INFO - 'LANG': 'en_US.UTF-8', 02:16:30 INFO - 'LANGUAGE': 'en_US:en', 02:16:30 INFO - 'LOGNAME': 'cltbld', 02:16:30 INFO - 'MAIL': '/var/mail/cltbld', 02:16:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:30 INFO - 'MOZ_NO_REMOTE': '1', 02:16:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:30 INFO - 'NO_EM_RESTART': '1', 02:16:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:30 INFO - 'PWD': '/builds/slave/test', 02:16:30 INFO - 'SHELL': '/bin/bash', 02:16:30 INFO - 'SHLVL': '1', 02:16:30 INFO - 'TERM': 'linux', 02:16:30 INFO - 'TMOUT': '86400', 02:16:30 INFO - 'USER': 'cltbld', 02:16:30 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', 02:16:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:30 INFO - '_': '/tools/buildbot/bin/python'} 02:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:16:30 INFO - Cleaning up... 02:16:30 INFO - Return code: 0 02:16:30 INFO - Running post-action listener: _resource_record_post_action 02:16:30 INFO - Running post-action listener: _start_resource_monitoring 02:16:30 INFO - Starting resource monitoring. 02:16:30 INFO - [mozharness: 2016-08-23 09:16:30.717678Z] Finished create-virtualenv step (success) 02:16:30 INFO - [mozharness: 2016-08-23 09:16:30.718136Z] Running install step. 02:16:30 INFO - Running pre-action listener: _resource_record_pre_action 02:16:30 INFO - Running main action method: install 02:16:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:16:30 INFO - Reading from file tmpfile_stdout 02:16:30 INFO - Detecting whether we're running mozinstall >=1.0... 02:16:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:16:30 INFO - Reading from file tmpfile_stdout 02:16:30 INFO - Output received: 02:16:30 INFO - Usage: mozinstall [options] installer 02:16:30 INFO - Options: 02:16:30 INFO - -h, --help show this help message and exit 02:16:30 INFO - -d DEST, --destination=DEST 02:16:30 INFO - Directory to install application into. [default: 02:16:30 INFO - "/builds/slave/test"] 02:16:30 INFO - --app=APP Application being installed. [default: firefox] 02:16:30 INFO - mkdir: /builds/slave/test/build/application 02:16:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 02:16:48 INFO - Reading from file tmpfile_stdout 02:16:48 INFO - Output received: 02:16:48 INFO - /builds/slave/test/build/application/firefox/firefox 02:16:48 INFO - Running post-action listener: _resource_record_post_action 02:16:48 INFO - [mozharness: 2016-08-23 09:16:48.013253Z] Finished install step (success) 02:16:48 INFO - [mozharness: 2016-08-23 09:16:48.013500Z] Running run-tests step. 02:16:48 INFO - Running pre-action listener: _resource_record_pre_action 02:16:48 INFO - Running main action method: run_tests 02:16:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:16:48 INFO - Python 2.7.3 02:16:48 INFO - Return code: 0 02:16:48 INFO - grabbing minidump binary from tooltool 02:16: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:48 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 0x1eeb370>, '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 0x1eee8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eeed80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:16:48 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 02:16:48 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 02:16:48 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 02:16:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:16:48 INFO - Return code: 0 02:16:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:16:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:16:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:16:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:16:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:16:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 02:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 02:16:48 INFO - Using env: {'DISPLAY': ':0', 02:16:48 INFO - 'HOME': '/home/cltbld', 02:16:48 INFO - 'LANG': 'en_US.UTF-8', 02:16:48 INFO - 'LANGUAGE': 'en_US:en', 02:16:48 INFO - 'LOGNAME': 'cltbld', 02:16:48 INFO - 'MAIL': '/var/mail/cltbld', 02:16:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:16:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:48 INFO - 'MOZ_NO_REMOTE': '1', 02:16:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:48 INFO - 'NO_EM_RESTART': '1', 02:16:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:48 INFO - 'PWD': '/builds/slave/test', 02:16:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:16:48 INFO - 'SHELL': '/bin/bash', 02:16:48 INFO - 'SHLVL': '1', 02:16:48 INFO - 'TERM': 'linux', 02:16:48 INFO - 'TMOUT': '86400', 02:16:48 INFO - 'USER': 'cltbld', 02:16:48 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338', 02:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:48 INFO - '_': '/tools/buildbot/bin/python'} 02:16:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 02:16:48 INFO - mozversion application_buildid: 20160822233001 02:16:48 INFO - mozversion application_changeset: ed793a550dd602631e25febe9fc39f3bab41e80a 02:16:48 INFO - mozversion application_display_name: Nightly 02:16:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:16:48 INFO - mozversion application_name: Firefox 02:16:48 INFO - mozversion application_remotingname: firefox 02:16:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:16:48 INFO - mozversion application_vendor: Mozilla 02:16:48 INFO - mozversion application_version: 51.0a1 02:16:48 INFO - mozversion platform_buildid: 20160822233001 02:16:48 INFO - mozversion platform_changeset: ed793a550dd602631e25febe9fc39f3bab41e80a 02:16:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:16:48 INFO - mozversion platform_version: 51.0a1 02:16:48 INFO - using testdate: 1471943808 02:16:48 INFO - actual date: 1471943808 02:16:48 INFO - starting webserver on 'localhost:37141' 02:16:48 INFO - SUITE-START | Running 2 tests 02:16:48 INFO - TEST-START | dromaeo_css 02:16:48 INFO - operating with platform_type : linux_ 02:16:48 INFO - Initialising browser for dromaeo_css test... 02:16:48 INFO - TEST-INFO | started process 8070 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnbrghk/profile http://localhost:37141/getInfo.html) 02:16:52 INFO - PROCESS | 8070 | __metrics Screen width/height:1600/1200 02:16:52 INFO - PROCESS | 8070 | colorDepth:24 02:16:52 INFO - PROCESS | 8070 | Browser inner width/height: 1024/697 02:16:52 INFO - PROCESS | 8070 | __metrics 02:16:53 INFO - PROCESS | 8070 | 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" 02:16:53 INFO - PROCESS | 8070 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:16:53 INFO - PROCESS | 8070 | console.error: PushService: 02:16:53 INFO - PROCESS | 8070 | stateChangeProcessEnqueue: Error transitioning state 02:16:53 INFO - PROCESS | 8070 | UnknownError 02:16:54 INFO - TEST-INFO | 8070: exit 0 02:16:54 INFO - Browser initialized. 02:16:54 INFO - Running cycle 1/1 for dromaeo_css test... 02:16:54 INFO - TEST-INFO | started process 8151 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnbrghk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:17:06 INFO - PROCESS | 8151 | RSS: Main: 174170112 02:17:06 INFO - PROCESS | 8151 | 02:17:57 INFO - PROCESS | 8151 | 1471943877694 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] 02:20:06 INFO - PROCESS | 8151 | Cycle 1(1): loaded http://localhost:37141/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37141/tests/dromaeo/cssquery-ext.html) 02:20:07 INFO - PROCESS | 8151 | RSS: Main: 278147072 02:20:07 INFO - PROCESS | 8151 | 02:23:10 INFO - PROCESS | 8151 | Cycle 1(1): loaded http://localhost:37141/tests/dromaeo/cssquery-ext.html (next: http://localhost:37141/tests/dromaeo/cssquery-jquery.html) 02:23:10 INFO - PROCESS | 8151 | RSS: Main: 278208512 02:23:10 INFO - PROCESS | 8151 | 02:26:13 INFO - PROCESS | 8151 | Cycle 1(1): loaded http://localhost:37141/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37141/tests/dromaeo/cssquery-mootools.html) 02:26:13 INFO - PROCESS | 8151 | RSS: Main: 220110848 02:26:13 INFO - PROCESS | 8151 | 02:29:15 INFO - PROCESS | 8151 | Cycle 1(1): loaded http://localhost:37141/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37141/tests/dromaeo/cssquery-prototype.html) 02:29:16 INFO - PROCESS | 8151 | RSS: Main: 246472704 02:29:16 INFO - PROCESS | 8151 | 02:32:19 INFO - PROCESS | 8151 | Cycle 1(1): loaded http://localhost:37141/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37141/tests/dromaeo/cssquery-yui.html) 02:32:19 INFO - PROCESS | 8151 | RSS: Main: 221069312 02:32:19 INFO - PROCESS | 8151 | 02:35:22 INFO - PROCESS | 8151 | Cycle 1(1): loaded http://localhost:37141/tests/dromaeo/cssquery-yui.html (next: http://localhost:37141/tests/dromaeo/cssquery-dojo.html) 02:35:23 INFO - PROCESS | 8151 | RSS: Main: 302686208 02:35:23 INFO - PROCESS | 8151 | 02:35:23 INFO - PROCESS | 8151 | __start_tp_report 02:35:23 INFO - PROCESS | 8151 | _x_x_mozilla_page_load 02:35:23 INFO - PROCESS | 8151 | _x_x_mozilla_page_load_details 02:35:23 INFO - PROCESS | 8151 | |i|pagename|runs| 02:35:23 INFO - PROCESS | 8151 | |0;dojo.html;2991.4914464541025;3034.6661867194603;3043.93462288199;3045.9390812183756;3058.1284333964823;4926.261060840876;5061.746912654371;5098.745062746862;5190.247414124954;5197.4542673019305;5081.187010078388;5085.830259040423;5091.363579552547;5128.512791284822;5167.199084141955;4680.5787479126875;4684.625229981611;4686.250199968006;4692.296155576657;4786.329913812069;4727.881802954919;4744.217204161309;4771.689840160396;4781.90436191276;4790.88022799431;4559.703619264737;4586.289125185584;4587.7017993830295;4617.676762626617;4718.127146477896;4656.650751193674;4663.88340291492;4665.346851440801;4666.836660716858;4697.224957881946;4608.377868987711;4630.768461576941;4647.3958385410015;4662.883427914296;4669.859904202845;4953.380827396575;4961.900761984776;5031.84904452867;5032.119379108678;5063.620228482841;5426.186072089179;5446.291982042309;5449.264349312832;5452.59105567084;5452.863678408032;5438.510534051954;5449.209864569655;5451.591130665176;5466.125419932831;5466.644668096561;5046.84859454217;5076.492350764931;5133.897322053538;5138.152204886227;5159.948400515985;5676.489115979582;5678.0915053591225;5681.34664513578;5682.602217844794;5689.715514224272;5454.3454785426;5457.727113644302;5458.426865179099;5461.563074953994;5469.453054694499;5330.413654498002;5393.2449457076045;5422.945770542323;5429.131338985743;5513.007658621486;5483.972580137153;5492.780288788484;5493.807716729895;5500.037493438632;5507.476789704971;5350.304460420199;5365.543928766098;5408.242846001563;5408.3509978802795;5421.5391691706645;4252.638525725286;4270.893227669333;4281.486221653359;4352.064306174188;4379.84670536536;3881.84472621092;3919.627635374635;3924.5879182685976;3924.6271604197555;3963.1875465529497;5475.123980163155;5476.452354764571;5477.75350109246;5480.013597552479;5482.533984611353;5342.465753424626;5342.786288548415;5510.0632892408385;5517.806876759374;5518.668879867221;5348.277982472317;5418.539424148913;5419.403865574784;5429.022775900376;5436.864078398072;4909.582685471703;4917.409910810726;4917.950820491821;4923.458419573844;4925.261210818406;5651.576131790214;5715.914261286167;5830.621009634445;5895.115732640138;5904.261967254093;5317.734113294242;5368.651037682538;5428.972855135699;5431.728413579384;5435.266239057834;5835.9708201458725;5836.824895253149;5841.1530328101335;5841.532677385884;5843.7370318336625;5237.607179461632;5300.867478313073;5313.123334649739;5374.11327131019;5376.758045888023;5346.144616861284;5418.322709661293;5448.7548060336585;5450.781968721207;5452.018636645442;5203.16749320107;5259.289995850511;5261.7895284189735;5264.605154613343;5271.341082364705;5148.871278218075;5211.244369566468;5254.816081437131;5255.816046438356;5256.763445644879;5154.407243166934;5200.089984252665;5206.479352064764;5209.843704688866;5246.632735708617;5328.307320048524;5330.387005494263;5332.093544097435;5333.280007198979;5334.039872822929;5136.743162841918;5172.120739474223;5181.974090129676;5203.947960520346;5207.505286997729 02:35:23 INFO - PROCESS | 8151 | |1;ext.html;560.7728869807727;598.4863572993428;601.2574470528896;601.8525461261992;603.882242962622;43084;44280.33579496306;44345;47279.52720472794;47387;45623.31565026527;46179;46319.53680463194;46387.304190437084;46394.30408543866;8833.07252738463;9010.279177665803;9024.458532488072;9026.909730902673;9029;9020.188183063523;9088.727338179866;9121;9122.77193070172;9126.087391260888;9202.907970920305;9231.907680923205;9234.445933244027;9240.768980775501;9251.53742312885;9012.549372531346;9128.087191280852;9144.954275228582;9157.404768690014;9157.633694652206;8829.867551986725;8893;8937.865932011026;8944.016158222656;8946.150115738994;42715.786421068005;43098.35352469715;43104.137917241795;43128;43136;3637.3634613942454;3668.8349024293702;4007.318755811519;4181.289180839234;4285.764282964436;32678.34643307144;32783;32817.17957051069;32914.17714557131;34542.65457345445;4347.782610869475;4426.579474949875;4452.287633978548;4467.128909862578;4469.955300447019;381865;382511;385590.7291125083;386049;388761.05619472003;59397.406025939184;59416.70291648514;59441.40558594359;59947.700261498416;59959.40040599625;63955.36044639587;64230.67884660547;64320;64401.677991609744;64411;16216.675666486604;16320.102394368423;16366;16453.91773041127;16458.177091145393;12601.66869129214;12702;12706.174098683556;12718.364081795873;12799.360031998363;16441.64650590067;18816.247350106118;18872.433826985212;18903.716444253347;19083.713744293847;6784.7964561063245;6875.759348422781;7017.228104908456;7024.71696698575;7065.43476521877;890.692711014699;1029.897010298979;1052.2108418685987;1093.6117678224275;1156.8206927926185;31286;32356;33766.831165844014;34878.302433951685;36303;1077.8975997280245;1086.3687998849225;1098.780243951213;1103.4041617526445;1159.6231224851956;15680.843191568167;16927.830721692626;16953;16984.40554580584;17008.404705835237;607657;612262;614712;615115;616809;56438.71780641071;56750.43249567534;56915.715421422625;57108.42891571031;57116;1255.234307072685;1346.6633341664583;1353.905226634146;1392.1229625335973;1392.3595146232644;31017.224569385948;32609.34781304408;32941.67058329386;33257.83371083129;33631.83184084064;50744.23883641674;51362.74318628383;51509.227361590354;51513;51565.742171288905;436631;436839;437417;439012;441853;24859.12993045235;26393.340166495993;27481.587776182972;27487.725122748518;29212;45993.77003115097;46449;47834.76082619565;48339.75830120827;48468.5153148464;39570.406443902786;40014.79992600018;40766;40794.817105152;41823.37264941089;39663;39725;40163.397549036206;40759;41875.371869421375;39418.40872386859;39928.800355998035;40790;41276.1744765097;41398.1720365597;32813;35695.28609427849;36433;36446.817765911;36911.44632830456;26996.73003270021;27108.864455678384;27638.723612763613;27811.721882781723;27825.860870695516 02:35:23 INFO - PROCESS | 8151 | |2;jquery.html;381.5154753463102;384.3542848015333;393.8365578285011;394.4123256348042;417.8662827895073;12925.77967286729;18958.810411895876;20879.164833406685;20883.68674469882;21137.894310528463;16125.758113628275;16388.508344749647;16970.83029169711;19705.211791528356;20349.28777492788;5513;5546.251256080438;5678.94627300567;5729.28383952006;5731.054376027951;5586.748596313156;5619.6066275360745;5623.184638227454;5627.2684551008315;5631.042722737145;5600.691961942091;5604.523615492688;5608.46719561643;5614.185943038257;5627.212190293362;5595.4964053235;5598.8600284993;5601.8599535011745;5604.299462567179;5605.187247849039;4855.295982082578;4973.477784832577;5019.121653710586;5071.1632580624155;5074.923876141861;23867.28398148041;24091.39771505709;24207.394815129588;24364.87817560901;24426.389340266458;2184.56308738253;2334.8248881333966;2780.819246748955;3061.785675002751;3133.5926329577237;10382.480875956247;10684.572617095308;10692.893071069268;10700.411477368742;10826.404547749871;1882.9623407531772;1951.6389467948475;1991.8107779760899;2018.9596208075757;2090.153487837413;67813.32186678119;77810.6109469449;80383;81486.59256703679;81728;10709.250352475408;11000.944995274973;11120.165987550967;11296.887031129583;11352.148588855704;15598.610034748996;15602.141882196362;15604.375824967101;15636.296366663528;15645.374185032699;8069.515829050276;8091.271785539329;8092.393070519617;8257.8348433031;8273;7717.112532058773;7805.33654639362;7993.720219792395;8009.719659811995;8065.7176998805935;4605.700629459075;4743.264793956942;4764.2615394613895;4815.590674792612;4930.852074437773;1929.8552608554135;1978.5844972555924;1992.8604997650025;1997.540565669904;2005.428452890919;879.4811061473633;955.6655170690203;956.9665061722807;1003.3127307794184;1014.4876837197169;18675.626487470447;20495.69256461154;20586.970651467367;20611;21000.264990725253;946.3328353510758;962.5860879821604;983.5033308179467;1014.7158795537262;1024.1732817423815;10475.895241047645;11297.774044519063;11326.093912486982;11382.829257561143;11391;371450;373718;375749;505831;510847;87703;92108;92480;94235.52882235682;94491;1054.1145437832258;1055.7466208109997;1108.8724796648328;1110.5224753355974;1111.4498323330004;17856.01791901458;17871.195796188942;18659.16033778456;18674.253029879197;19067.663158947405;40551.1889762209;41403.378949315185;41526;42315.57684423116;42545.78727106345;58631;58738.412615875;58921;59253;59779;7709.691612335668;7787.844243115219;7793.37652987771;7890;8070.435069545076;9492.345447002815;12783.296918669563;12799.616011519669;12866.228026318451;13676.794848077088;11644;11828.585999489977;12177.878221217674;12209.389530523616;12270.877291226974;10803.945980270048;11464.31214127155;11536.134789890624;11686.357250350971;11869.881301186877;9597.856032159663;11832.408379581158;11960.820587691018;12118.333491658044;12238.87761122413;8321.542315172723;8486.787830304293;8548.91451085506;8557.871631925402;8830.867536986822;10192.694219173436;11482.77034459323;11760.176787624785;11916.344601047025;12024.158308918291 02:35:23 INFO - PROCESS | 8151 | |3;mootools.html;1380.9378577963992;1470.7867359232912;1508.2232650185156;1627.170143226954;1802.6755184066878;8346.624401901914;8753.343499237559;8800.427972181804;9108.225800806922;9142.542872856347;8890.599923003465;8990.280777537797;9009.459432434058;9015.233705135055;9024.548772561378;2325.337278875525;2358.41039740065;2359.3393849722106;2363.6690863279155;2368.2539999899977;2286.7141607299086;2342;2356.8585884846966;2357.705286839145;2358.929232123039;2313.595120853844;2324.8488848224897;2334.533093381322;2350.74141844397;2356.39911822485;2266.3427605994243;2287.085165933623;2295.0934380919525;2296.8966396512114;2302.3898666853297;2277.3851060213815;2278.40761402035;2279.4073540879326;2281.0989659084735;2284.383216531544;8786.912130878738;8922.375433719642;8956.507392093432;8969.820603587958;8982.146696063863;5238.528532432099;5396.406395296514;5437.34751829779;5439.700816455093;5440.102383106782;3406.9659303406897;3455.3953058214965;3461.6019157796923;3519.419295816187;3526.4710293455933;1134.0474001838495;1153.2503872482869;1159.4434671357797;1189.3636904256182;1189.8215267709827;12389.318587477685;12442.502299907992;12523.499060037497;12525.56160534386;12533.310667913262;4472.709273897186;4558.700770280454;4741.762911854463;4748.6438517111355;4755.524447555217;9851.310408271496;9855.901440985499;9859.112679858847;9917.752056198653;9938.105570498545;5423.3491980962535;5431.728413579384;5432.212329212242;5433;5433.293671822637;4213.220554197484;4429.844955426544;4586;4880;5079;3668.1013151777984;3673.1368128489785;3777.433384992273;3792.1467669774634;3838.405047217686;1160.5415860734963;1164.5050853387277;1168.462507246659;1177.844544483593;1184.2184158455377;949.5110018340561;954.9379290346105;958.6309270930602;958.68363440066;995.9751006224902;5668.121441176624;5668.7165641718575;5687.687177205294;5743.1385292206505;5968.432998865101;467.33638233708217;467.483430808958;470.5247699823203;478.17514786992854;480.512280035764;3972.920541589152;4096.549379568245;4097.303458412077;4117.3206420940205;4145.253854306254;10264.076233139054;10546.894531054744;10670.199735019905;10677.89322106769;10698.304610200468;10614;10616.203784716177;10635.149188064936;10676.572937082585;10681.198910081619;8055.583325002476;8130.756077317572;8134.75595732129;8142.145074767064;8147.877781833277;5938.2280303560265;5987.520998320037;6158.969205153946;6294.213223347082;6403;9921.255905807238;10327.586896524055;10340.37957722539;10366.429846358522;10503.107235885036;8935.821283574422;9757.902420975699;10248.077673009466;10255.538500767334;10472.90034546392;7809.1409944907155;7910.050793904768;8000.839983200187;8003.239692229229;8202.876956845772;8269.379796515164;8680.696175633822;8682;8734.30125589964;8815.515146666994;7839.568823714522;7893.842123157619;7897.802554936173;7906.486078404885;8155.63299651529;7606.581638009962;7607.391408687403;7666.4633475656165;7711.807204819925;7728.613569321624;6905.136857892763;6912.101426814482;6914.343137401939;6995.965020174866;7004.614746189009;5061.139606267017;5085.084684756632;5154.123798954111;5214.530692237717;5254.42201357858;5138.614603904647;5202.713850738246;5343.225232341367;5356.1697936819855;5396.9730151348995 02:35:23 INFO - PROCESS | 8151 | |4;prototype.html;322.3601151713849;325.6173995555223;334.4949126818505;335.1019268360792;339.63658884993066;4747.382840230774;4872.658913876021;4876.317315575822;4919.72941488218;4925.5813255873245;4888.828890988816;4912.1018599026565;4926.359573255471;4937.2840938063955;4954.554090131887;3932.7836968966694;3951.604839516047;3974.940375894364;3975.483187185662;3995.3407687731487;3860.5560360558475;4016.3373043447796;4023.396490526415;4027.7715296834454;4033.1933613277315;3903.746256493333;3914.354131568288;3922.0979174789813;3924.5682974872743;3945.940810887839;3899.941500877461;3899.941500877489;3904.9414258786283;3918.2163567286657;3956.5054368203973;3496.4755286707195;3497.8600855965733;3511.8244087795506;3515.9472607910902;3520.084777957724;4622.19111655459;4820.807167713322;4855.344528488644;4873.34209881665;4909.551682253744;4378.649708023382;4384.188925048844;4390.736555806662;4394.62645675118;4418.4256046714045;5081.644284900059;5084.3644544431945;5112.718800465973;5118.566801295643;5122.3853137623355;3610.1877077657578;3772.6981841452616;3775.5846856845724;3779.263043706478;3790.8673196437994;5355.919661205085;5361.973190134064;5378.569714422876;5387.245785589981;5418.160185171305;3894.883153505399;3895.7662540247675;3906.257811015898;3909.3354129798004;3910.6089391061237;3938.428927805452;3941.2905676977844;3972.9471690002174;3977.721559490808;3983.1237127831832;4222.577742225814;4376.14665140301;4424.469063712375;4428.468583769968;4450.666200035011;5174.275601415805;5174.689518628894;5181.093308670967;5212.218167274939;5225.372955245395;1292.1665525735848;1293.2176033499786;1317.637649646355;1322.2066759944148;1354.7358265138203;921.3642586615214;934.7522906429665;952.8094381123665;958.8274110660149;963.5952899781968;5107.055194788976;5156.473328069219;5157.174852023658;5164.92252616211;5166;5162.780004597984;5172.094883395391;5173.758298008526;5201.349831271091;5202.843914682566;5048.7475626219275;5077.543021128043;5089.244210747286;5114.43741188469;5173.120569503193;2414.263649586893;2455.226603619854;2457.557639624849;2461.200109964269;2482.7641374069435;4957.537526429712;4983.25251212321;4993.350864387607;4998.500149984972;5000.699958002531;4573.30787608587;4752.500987396343;4783;4811;4828.5412885775795;4913.901721965541;4949.331840201527;4950.8762280943265;4952.925706114411;4964.616983847845;4561.908761824811;4645;4647.55848194421;4670.883227919193;4679.438467383936;4449.879912181157;4450.510443851109;4585.358049873087;4626.421697287839;4678.976605116953;4895.363602731622;4905.503821334527;4918.926216106833;4925.266477105241;4930.865900842898;3536.7170626350344;3543.025667941337;3555.6622120898473;3590.694790942799;3597.6402359763815;4691.296305554194;4714.4106986626675;4741.217699079613;4755.429348478205;5093.592512598909;4500.842470513517;5108.259302401206;5135;5138.4604616514725;5142.845714628567;5152.3044389007;5159.845204643867;5168.819091331786;5174.482551744795;5175.508326708957;4987.326710894128;5130;5140.100482415637;5148.716820574755;5157.200633901752;5097.515736005073;5132.230165475208;5166.715830629201;5188.195829646411;5209.270702101633;5068.847934561969;5072.695638261716;5084.415292241291;5084.771185296744;5101.69389836611 02:35:23 INFO - PROCESS | 8151 | |5;yui.html;763.2748888555873;819.0089991110756;830.3398797955625;842.64608864277;853.6670698427611;14594.343254553543;14643.926780366097;14717.558473245794;14720.190389528572;15260.923695381536;13640.522581709642;13853.238071906042;13861.861381386208;13874.722505549882;13874.722505549882;811.0915774332741;823.8269963307713;825.9215374539409;826.0665448043702;826.8098337382405;674.7537148940651;806.6410447350931;817.337956255435;829.6473998550622;832.50881979632;873.4409977614332;874.8425283448977;875.299760191844;875.5534677314552;875.6453636277317;868.6395146014396;869.9995005743383;872.2629378175463;872.3500991761147;872.5288344294073;512.7564406906727;525.8790478190037;527.6517498451004;614.1862032806505;628.9465395441393;15952.680946381124;15997.600059998476;16029.198540073066;16031;16031.679366412724;14392.424303027867;14852.628684282872;15001.849981500154;15174.772378414333;15339.693206135815;13752.93123534376;13906.51327203553;13929.860701392958;14151.603615132397;14812;4482.977585112054;4529.071540334224;4554.38515800366;4557.088582283556;4564.315352697089;79029.20970790276;81572;81914;82746.58626706888;85353.57323213406;11602.303861768321;11700.70748231301;11959.80451375547;12030.157888947864;12609.054320925967;22977.310680679606;23159.189428370264;23265.651015234784;23349.41626459352;23413.531729365317;8967.955160224157;9020.594073266719;9027.4132181408;9112.271018318519;9139.451632902048;4974.626902982291;4979.651424400329;5009.474005229471;5119.078565858182;5146.819861304836;3555.982220088883;3636.1636823530207;3671.7980511071614;3683.263347330491;3694.07648087978;596.8836076965036;600.0968542343745;609.1624016976657;614.1555361378105;621.201755743867;566.9262995810518;692.8579641173548;708.3518580498888;718.7125149940066;748.7566540874237;9468.14786669203;9901.504924753877;9953.751156221153;10320.225983051301;10473.109785668152;598.4763332084426;603.4448307557058;689.9793006209821;719.251977942944;745.9179490256067;4769.976150119227;4931.950680493149;4959.2809042688605;4982.209962420983;5025.949740502549;127003.36498317635;128108.35945820212;129909;131218;131512;62772.68613656903;64942.35057649457;80060;85426.5728671365;106597.46701266599;97749;98081.50959245158;98812.01187988171;101572;102437.48781256046;9032.63869445215;9128.771780705536;9328.83389576303;9367.250619950251;9439.716808495756;56464.717676411354;56602;56996;57121;58132.709336453045;80843.19156808357;83265.58367208368;85907.57046214979;86919.56540217258;88795.55602221948;11954.342511161969;12095.818562721743;12243.938780306042;12641;13007.089503734647;12677.619671409873;13010;13053.934730326288;13084.80372794428;13976.231307278198;12066.879331206575;12716.554920577735;12739.745205096031;13085.607431777062;13369.732605348034;11860.822087668865;12149.514019439477;12257.938710306391;12282.201656892274;12652.683682907633;12510;12530.812037819622;12714.61856144317;12723.745525089631;13133.73732525325;10581.947090264499;10806.243562950833;11111.333320000826;11126.721831954266;11312.717182070513;10709.357438553712;11038.889611103787;11255.549778008788;11426.542938282708;11556.826647600461 02:35:23 INFO - PROCESS | 8151 | __end_tp_report 02:35:23 INFO - PROCESS | 8151 | __start_cc_report 02:35:23 INFO - PROCESS | 8151 | _x_x_mozilla_cycle_collect,917 02:35:23 INFO - PROCESS | 8151 | __end_cc_report 02:35:23 INFO - PROCESS | 8151 | __startTimestamp1471944923151__endTimestamp 02:35:23 INFO - PROCESS | 8151 | 02:35:23 INFO - PROCESS | 8151 | ------- Summary: start ------- 02:35:23 INFO - PROCESS | 8151 | Number of tests: 6 02:35:23 INFO - PROCESS | 8151 | 02:35:23 INFO - PROCESS | 8151 | [#0] dojo.html Cycles:165 Average:5107.88 Median:5259.29 stddev:554.24 (10.5%) stddev-sans-first:530.49 02:35:23 INFO - PROCESS | 8151 | Values: 2991.5 3034.7 3043.9 3045.9 3058.1 4926.3 5061.7 5098.7 5190.2 5197.5 5081.2 5085.8 5091.4 5128.5 5167.2 4680.6 4684.6 4686.3 4692.3 4786.3 4727.9 4744.2 4771.7 4781.9 4790.9 4559.7 4586.3 4587.7 4617.7 4718.1 4656.7 4663.9 4665.3 4666.8 4697.2 4608.4 4630.8 4647.4 4662.9 4669.9 4953.4 4961.9 5031.8 5032.1 5063.6 5426.2 5446.3 5449.3 5452.6 5452.9 5438.5 5449.2 5451.6 5466.1 5466.6 5046.8 5076.5 5133.9 5138.2 5159.9 5676.5 5678.1 5681.3 5682.6 5689.7 5454.3 5457.7 5458.4 5461.6 5469.5 5330.4 5393.2 5422.9 5429.1 5513.0 5484.0 5492.8 5493.8 5500.0 5507.5 5350.3 5365.5 5408.2 5408.4 5421.5 4252.6 4270.9 4281.5 4352.1 4379.8 3881.8 3919.6 3924.6 3924.6 3963.2 5475.1 5476.5 5477.8 5480.0 5482.5 5342.5 5342.8 5510.1 5517.8 5518.7 5348.3 5418.5 5419.4 5429.0 5436.9 4909.6 4917.4 4918.0 4923.5 4925.3 5651.6 5715.9 5830.6 5895.1 5904.3 5317.7 5368.7 5429.0 5431.7 5435.3 5836.0 5836.8 5841.2 5841.5 5843.7 5237.6 5300.9 5313.1 5374.1 5376.8 5346.1 5418.3 5448.8 5450.8 5452.0 5203.2 5259.3 5261.8 5264.6 5271.3 5148.9 5211.2 5254.8 5255.8 5256.8 5154.4 5200.1 5206.5 5209.8 5246.6 5328.3 5330.4 5332.1 5333.3 5334.0 5136.7 5172.1 5182.0 5203.9 5207.5 02:35:23 INFO - PROCESS | 8151 | 02:35:23 INFO - PROCESS | 8151 | [#1] ext.html Cycles:180 Average:63638.34 Median:43091.18 stddev:130010.00 (301.7%) stddev-sans-first:130288.46 02:35:23 INFO - PROCESS | 8151 | Values: 560.8 598.5 601.3 601.9 603.9 43084.0 44280.3 44345.0 47279.5 47387.0 45623.3 46179.0 46319.5 46387.3 46394.3 8833.1 9010.3 9024.5 9026.9 9029.0 9020.2 9088.7 9121.0 9122.8 9126.1 9202.9 9231.9 9234.4 9240.8 9251.5 9012.5 9128.1 9145.0 9157.4 9157.6 8829.9 8893.0 8937.9 8944.0 8946.2 42715.8 43098.4 43104.1 43128.0 43136.0 3637.4 3668.8 4007.3 4181.3 4285.8 32678.3 32783.0 32817.2 32914.2 34542.7 4347.8 4426.6 4452.3 4467.1 4470.0 381865.0 382511.0 385590.7 386049.0 388761.1 59397.4 59416.7 59441.4 59947.7 59959.4 63955.4 64230.7 64320.0 64401.7 64411.0 16216.7 16320.1 16366.0 16453.9 16458.2 12601.7 12702.0 12706.2 12718.4 12799.4 16441.6 18816.2 18872.4 18903.7 19083.7 6784.8 6875.8 7017.2 7024.7 7065.4 890.7 1029.9 1052.2 1093.6 1156.8 31286.0 32356.0 33766.8 34878.3 36303.0 1077.9 1086.4 1098.8 1103.4 1159.6 15680.8 16927.8 16953.0 16984.4 17008.4 607657.0 612262.0 614712.0 615115.0 616809.0 56438.7 56750.4 56915.7 57108.4 57116.0 1255.2 1346.7 1353.9 1392.1 1392.4 31017.2 32609.3 32941.7 33257.8 33631.8 50744.2 51362.7 51509.2 51513.0 51565.7 436631.0 436839.0 437417.0 439012.0 441853.0 24859.1 26393.3 27481.6 27487.7 29212.0 45993.8 46449.0 47834.8 48339.8 48468.5 39570.4 40014.8 40766.0 40794.8 41823.4 39663.0 39725.0 40163.4 40759.0 41875.4 39418.4 39928.8 40790.0 41276.2 41398.2 32813.0 35695.3 36433.0 36446.8 36911.4 26996.7 27108.9 27638.7 27811.7 27825.9 02:35:23 INFO - PROCESS | 8151 | 02:35:23 INFO - PROCESS | 8151 | [#2] jquery.html Cycles:180 Average:27229.46 Median:3097.69 stddev:71700.81 (2314.7%) stddev-sans-first:71873.61 02:35:23 INFO - PROCESS | 8151 | Values: 381.5 384.4 393.8 394.4 417.9 12925.8 18958.8 20879.2 20883.7 21137.9 16125.8 16388.5 16970.8 19705.2 20349.3 5513.0 5546.3 5678.9 5729.3 5731.1 5586.7 5619.6 5623.2 5627.3 5631.0 5600.7 5604.5 5608.5 5614.2 5627.2 5595.5 5598.9 5601.9 5604.3 5605.2 4855.3 4973.5 5019.1 5071.2 5074.9 23867.3 24091.4 24207.4 24364.9 24426.4 2184.6 2334.8 2780.8 3061.8 3133.6 10382.5 10684.6 10692.9 10700.4 10826.4 1883.0 1951.6 1991.8 2019.0 2090.2 67813.3 77810.6 80383.0 81486.6 81728.0 10709.3 11000.9 11120.2 11296.9 11352.1 15598.6 15602.1 15604.4 15636.3 15645.4 8069.5 8091.3 8092.4 8257.8 8273.0 7717.1 7805.3 7993.7 8009.7 8065.7 4605.7 4743.3 4764.3 4815.6 4930.9 1929.9 1978.6 1992.9 1997.5 2005.4 879.5 955.7 957.0 1003.3 1014.5 18675.6 20495.7 20587.0 20611.0 21000.3 946.3 962.6 983.5 1014.7 1024.2 10475.9 11297.8 11326.1 11382.8 11391.0 371450.0 373718.0 375749.0 505831.0 510847.0 87703.0 92108.0 92480.0 94235.5 94491.0 1054.1 1055.7 1108.9 1110.5 1111.4 17856.0 17871.2 18659.2 18674.3 19067.7 40551.2 41403.4 41526.0 42315.6 42545.8 58631.0 58738.4 58921.0 59253.0 59779.0 7709.7 7787.8 7793.4 7890.0 8070.4 9492.3 12783.3 12799.6 12866.2 13676.8 11644.0 11828.6 12177.9 12209.4 12270.9 10803.9 11464.3 11536.1 11686.4 11869.9 9597.9 11832.4 11960.8 12118.3 12238.9 8321.5 8486.8 8548.9 8557.9 8830.9 10192.7 11482.8 11760.2 11916.3 12024.2 02:35:23 INFO - PROCESS | 8151 | 02:35:23 INFO - PROCESS | 8151 | [#3] mootools.html Cycles:180 Average:5792.85 Median:4970.57 stddev:3308.21 (66.6%) stddev-sans-first:3300.87 02:35:23 INFO - PROCESS | 8151 | Values: 1380.9 1470.8 1508.2 1627.2 1802.7 8346.6 8753.3 8800.4 9108.2 9142.5 8890.6 8990.3 9009.5 9015.2 9024.5 2325.3 2358.4 2359.3 2363.7 2368.3 2286.7 2342.0 2356.9 2357.7 2358.9 2313.6 2324.8 2334.5 2350.7 2356.4 2266.3 2287.1 2295.1 2296.9 2302.4 2277.4 2278.4 2279.4 2281.1 2284.4 8786.9 8922.4 8956.5 8969.8 8982.1 5238.5 5396.4 5437.3 5439.7 5440.1 3407.0 3455.4 3461.6 3519.4 3526.5 1134.0 1153.3 1159.4 1189.4 1189.8 12389.3 12442.5 12523.5 12525.6 12533.3 4472.7 4558.7 4741.8 4748.6 4755.5 9851.3 9855.9 9859.1 9917.8 9938.1 5423.3 5431.7 5432.2 5433.0 5433.3 4213.2 4429.8 4586.0 4880.0 5079.0 3668.1 3673.1 3777.4 3792.1 3838.4 1160.5 1164.5 1168.5 1177.8 1184.2 949.5 954.9 958.6 958.7 996.0 5668.1 5668.7 5687.7 5743.1 5968.4 467.3 467.5 470.5 478.2 480.5 3972.9 4096.5 4097.3 4117.3 4145.3 10264.1 10546.9 10670.2 10677.9 10698.3 10614.0 10616.2 10635.1 10676.6 10681.2 8055.6 8130.8 8134.8 8142.1 8147.9 5938.2 5987.5 6159.0 6294.2 6403.0 9921.3 10327.6 10340.4 10366.4 10503.1 8935.8 9757.9 10248.1 10255.5 10472.9 7809.1 7910.1 8000.8 8003.2 8202.9 8269.4 8680.7 8682.0 8734.3 8815.5 7839.6 7893.8 7897.8 7906.5 8155.6 7606.6 7607.4 7666.5 7711.8 7728.6 6905.1 6912.1 6914.3 6996.0 7004.6 5061.1 5085.1 5154.1 5214.5 5254.4 5138.6 5202.7 5343.2 5356.2 5397.0 02:35:23 INFO - PROCESS | 8151 | 02:35:23 INFO - PROCESS | 8151 | [#4] prototype.html Cycles:180 Average:4254.01 Median:4815.90 stddev:1211.35 (25.2%) stddev-sans-first:1178.26 02:35:23 INFO - PROCESS | 8151 | Values: 322.4 325.6 334.5 335.1 339.6 4747.4 4872.7 4876.3 4919.7 4925.6 4888.8 4912.1 4926.4 4937.3 4954.6 3932.8 3951.6 3974.9 3975.5 3995.3 3860.6 4016.3 4023.4 4027.8 4033.2 3903.7 3914.4 3922.1 3924.6 3945.9 3899.9 3899.9 3904.9 3918.2 3956.5 3496.5 3497.9 3511.8 3515.9 3520.1 4622.2 4820.8 4855.3 4873.3 4909.6 4378.6 4384.2 4390.7 4394.6 4418.4 5081.6 5084.4 5112.7 5118.6 5122.4 3610.2 3772.7 3775.6 3779.3 3790.9 5355.9 5362.0 5378.6 5387.2 5418.2 3894.9 3895.8 3906.3 3909.3 3910.6 3938.4 3941.3 3972.9 3977.7 3983.1 4222.6 4376.1 4424.5 4428.5 4450.7 5174.3 5174.7 5181.1 5212.2 5225.4 1292.2 1293.2 1317.6 1322.2 1354.7 921.4 934.8 952.8 958.8 963.6 5107.1 5156.5 5157.2 5164.9 5166.0 5162.8 5172.1 5173.8 5201.3 5202.8 5048.7 5077.5 5089.2 5114.4 5173.1 2414.3 2455.2 2457.6 2461.2 2482.8 4957.5 4983.3 4993.4 4998.5 5000.7 4573.3 4752.5 4783.0 4811.0 4828.5 4913.9 4949.3 4950.9 4952.9 4964.6 4561.9 4645.0 4647.6 4670.9 4679.4 4449.9 4450.5 4585.4 4626.4 4679.0 4895.4 4905.5 4918.9 4925.3 4930.9 3536.7 3543.0 3555.7 3590.7 3597.6 4691.3 4714.4 4741.2 4755.4 5093.6 4500.8 5108.3 5135.0 5138.5 5142.8 5152.3 5159.8 5168.8 5174.5 5175.5 4987.3 5130.0 5140.1 5148.7 5157.2 5097.5 5132.2 5166.7 5188.2 5209.3 5068.8 5072.7 5084.4 5084.8 5101.7 02:35:23 INFO - PROCESS | 8151 | 02:35:23 INFO - PROCESS | 8151 | [#5] yui.html Cycles:180 Average:21699.24 Median:4850.96 stddev:32092.78 (661.6%) stddev-sans-first:32144.31 02:35:23 INFO - PROCESS | 8151 | Values: 763.3 819.0 830.3 842.6 853.7 14594.3 14643.9 14717.6 14720.2 15260.9 13640.5 13853.2 13861.9 13874.7 13874.7 811.1 823.8 825.9 826.1 826.8 674.8 806.6 817.3 829.6 832.5 873.4 874.8 875.3 875.6 875.6 868.6 870.0 872.3 872.4 872.5 512.8 525.9 527.7 614.2 628.9 15952.7 15997.6 16029.2 16031.0 16031.7 14392.4 14852.6 15001.8 15174.8 15339.7 13752.9 13906.5 13929.9 14151.6 14812.0 4483.0 4529.1 4554.4 4557.1 4564.3 79029.2 81572.0 81914.0 82746.6 85353.6 11602.3 11700.7 11959.8 12030.2 12609.1 22977.3 23159.2 23265.7 23349.4 23413.5 8968.0 9020.6 9027.4 9112.3 9139.5 4974.6 4979.7 5009.5 5119.1 5146.8 3556.0 3636.2 3671.8 3683.3 3694.1 596.9 600.1 609.2 614.2 621.2 566.9 692.9 708.4 718.7 748.8 9468.1 9901.5 9953.8 10320.2 10473.1 598.5 603.4 690.0 719.3 745.9 4770.0 4932.0 4959.3 4982.2 5025.9 127003.4 128108.4 129909.0 131218.0 131512.0 62772.7 64942.4 80060.0 85426.6 106597.5 97749.0 98081.5 98812.0 101572.0 102437.5 9032.6 9128.8 9328.8 9367.3 9439.7 56464.7 56602.0 56996.0 57121.0 58132.7 80843.2 83265.6 85907.6 86919.6 88795.6 11954.3 12095.8 12243.9 12641.0 13007.1 12677.6 13010.0 13053.9 13084.8 13976.2 12066.9 12716.6 12739.7 13085.6 13369.7 11860.8 12149.5 12257.9 12282.2 12652.7 12510.0 12530.8 12714.6 12723.7 13133.7 10581.9 10806.2 11111.3 11126.7 11312.7 10709.4 11038.9 11255.5 11426.5 11556.8 02:35:23 INFO - PROCESS | 8151 | -------- Summary: end -------- 02:35:23 INFO - PROCESS | 8151 | 02:35:24 INFO - TEST-INFO | 8151: exit 0 02:35:24 INFO - TEST-OK | dromaeo_css | took 1115821ms 02:35:24 INFO - TEST-START | kraken 02:35:24 INFO - operating with platform_type : linux_ 02:35:24 INFO - Initialising browser for kraken test... 02:35:24 INFO - TEST-INFO | started process 8465 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1TTaYL/profile http://localhost:37141/getInfo.html) 02:35:28 INFO - PROCESS | 8465 | __metrics Screen width/height:1600/1200 02:35:28 INFO - PROCESS | 8465 | colorDepth:24 02:35:28 INFO - PROCESS | 8465 | Browser inner width/height: 1024/697 02:35:28 INFO - PROCESS | 8465 | __metrics 02:35:28 INFO - PROCESS | 8465 | 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" 02:35:28 INFO - PROCESS | 8465 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:35:29 INFO - TEST-INFO | 8465: exit 0 02:35:29 INFO - Browser initialized. 02:35:29 INFO - Running cycle 1/1 for kraken test... 02:35:29 INFO - TEST-INFO | started process 8548 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1TTaYL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:35:42 INFO - PROCESS | 8548 | RSS: Main: 173944832 02:35:42 INFO - PROCESS | 8548 | 02:35:44 INFO - PROCESS | 8548 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37141/tests/kraken/driver.html?testName=audio-beat-detection) 02:35:45 INFO - PROCESS | 8548 | RSS: Main: 243159040 02:35:45 INFO - PROCESS | 8548 | 02:35:48 INFO - PROCESS | 8548 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37141/tests/kraken/driver.html?testName=audio-dft) 02:35:48 INFO - PROCESS | 8548 | RSS: Main: 225202176 02:35:48 INFO - PROCESS | 8548 | 02:35:51 INFO - PROCESS | 8548 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37141/tests/kraken/driver.html?testName=audio-fft) 02:35:52 INFO - PROCESS | 8548 | RSS: Main: 302440448 02:35:52 INFO - PROCESS | 8548 | 02:35:54 INFO - PROCESS | 8548 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37141/tests/kraken/driver.html?testName=audio-oscillator) 02:35:55 INFO - PROCESS | 8548 | RSS: Main: 209690624 02:35:55 INFO - PROCESS | 8548 | 02:35:57 INFO - PROCESS | 8548 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37141/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:35:58 INFO - PROCESS | 8548 | RSS: Main: 213475328 02:35:58 INFO - PROCESS | 8548 | 02:36:01 INFO - PROCESS | 8548 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37141/tests/kraken/driver.html?testName=imaging-darkroom) 02:36:01 INFO - PROCESS | 8548 | RSS: Main: 249712640 02:36:01 INFO - PROCESS | 8548 | 02:36:05 INFO - PROCESS | 8548 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37141/tests/kraken/driver.html?testName=imaging-desaturate) 02:36:05 INFO - PROCESS | 8548 | RSS: Main: 248635392 02:36:05 INFO - PROCESS | 8548 | 02:36:08 INFO - PROCESS | 8548 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37141/tests/kraken/driver.html?testName=json-parse-financial) 02:36:09 INFO - PROCESS | 8548 | RSS: Main: 248135680 02:36:09 INFO - PROCESS | 8548 | 02:36:10 INFO - PROCESS | 8548 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37141/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:36:11 INFO - PROCESS | 8548 | RSS: Main: 211152896 02:36:11 INFO - PROCESS | 8548 | 02:36:13 INFO - PROCESS | 8548 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37141/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:36:13 INFO - PROCESS | 8548 | RSS: Main: 218112000 02:36:13 INFO - PROCESS | 8548 | 02:36:15 INFO - PROCESS | 8548 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37141/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:36:16 INFO - PROCESS | 8548 | RSS: Main: 223051776 02:36:16 INFO - PROCESS | 8548 | 02:36:19 INFO - PROCESS | 8548 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37141/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:36:19 INFO - PROCESS | 8548 | RSS: Main: 218529792 02:36:19 INFO - PROCESS | 8548 | 02:36:22 INFO - PROCESS | 8548 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37141/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:36:23 INFO - PROCESS | 8548 | RSS: Main: 221745152 02:36:23 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | Cycle 1(1): loaded http://localhost:37141/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37141/tests/kraken/driver.html?testName=ai-astar) 02:36:25 INFO - PROCESS | 8548 | RSS: Main: 272068608 02:36:25 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | __start_tp_report 02:36:25 INFO - PROCESS | 8548 | _x_x_mozilla_page_load 02:36:25 INFO - PROCESS | 8548 | _x_x_mozilla_page_load_details 02:36:25 INFO - PROCESS | 8548 | |i|pagename|runs| 02:36:25 INFO - PROCESS | 8548 | |0;ai-astar;101;112;109;112;113;111;116;111;111;112 02:36:25 INFO - PROCESS | 8548 | |1;audio-beat-detection;145;116;149;187;118;154;152;120;177;118 02:36:25 INFO - PROCESS | 8548 | |2;audio-dft;161;162;161;160;161;162;161;161;161;161 02:36:25 INFO - PROCESS | 8548 | |3;audio-fft;76;74;76;170;75;75;75;76;167;77 02:36:25 INFO - PROCESS | 8548 | |4;audio-oscillator;87;87;160;86;86;86;139;85;85;147 02:36:25 INFO - PROCESS | 8548 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;118;117 02:36:25 INFO - PROCESS | 8548 | |6;imaging-darkroom;147;147;147;148;148;147;147;147;147;147 02:36:25 INFO - PROCESS | 8548 | |7;imaging-desaturate;95;94;95;95;95;95;95;95;94;95 02:36:25 INFO - PROCESS | 8548 | |8;json-parse-financial;52;50;51;51;50;50;51;50;51;51 02:36:25 INFO - PROCESS | 8548 | |9;json-stringify-tinderbox;63;63;63;63;64;63;64;63;63;63 02:36:25 INFO - PROCESS | 8548 | |10;stanford-crypto-aes;79;78;79;79;87;80;83;80;79;79 02:36:25 INFO - PROCESS | 8548 | |11;stanford-crypto-ccm;126;135;125;128;122;122;129;128;147;128 02:36:25 INFO - PROCESS | 8548 | |12;stanford-crypto-pbkdf2;161;165;164;161;163;170;166;174;165;161 02:36:25 INFO - PROCESS | 8548 | |13;stanford-crypto-sha256-iterative;68;65;66;63;65;67;65;65;64;63 02:36:25 INFO - PROCESS | 8548 | __end_tp_report 02:36:25 INFO - PROCESS | 8548 | __start_cc_report 02:36:25 INFO - PROCESS | 8548 | _x_x_mozilla_cycle_collect,1732 02:36:25 INFO - PROCESS | 8548 | __end_cc_report 02:36:25 INFO - PROCESS | 8548 | __startTimestamp1471944985356__endTimestamp 02:36:25 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | ------- Summary: start ------- 02:36:25 INFO - PROCESS | 8548 | Number of tests: 14 02:36:25 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.00 stddev:3.88 (3.5%) stddev-sans-first:1.90 02:36:25 INFO - PROCESS | 8548 | Values: 101.0 112.0 109.0 112.0 113.0 111.0 116.0 111.0 111.0 112.0 02:36:25 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | [#1] audio-beat-detection Cycles:10 Average:143.60 Median:150.50 stddev:25.47 (16.9%) stddev-sans-first:27.01 02:36:25 INFO - PROCESS | 8548 | Values: 145.0 116.0 149.0 187.0 118.0 154.0 152.0 120.0 177.0 118.0 02:36:25 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 02:36:25 INFO - PROCESS | 8548 | Values: 161.0 162.0 161.0 160.0 161.0 162.0 161.0 161.0 161.0 161.0 02:36:25 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | [#3] audio-fft Cycles:10 Average:94.10 Median:75.50 stddev:39.23 (52.0%) stddev-sans-first:41.06 02:36:25 INFO - PROCESS | 8548 | Values: 76.0 74.0 76.0 170.0 75.0 75.0 75.0 76.0 167.0 77.0 02:36:25 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | [#4] audio-oscillator Cycles:10 Average:104.80 Median:86.00 stddev:30.69 (35.7%) stddev-sans-first:31.87 02:36:25 INFO - PROCESS | 8548 | Values: 87.0 87.0 160.0 86.0 86.0 86.0 139.0 85.0 85.0 147.0 02:36:25 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 02:36:25 INFO - PROCESS | 8548 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 02:36:25 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 02:36:25 INFO - PROCESS | 8548 | Values: 147.0 147.0 147.0 148.0 148.0 147.0 147.0 147.0 147.0 147.0 02:36:25 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 02:36:25 INFO - PROCESS | 8548 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 02:36:25 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:0.67 (1.3%) stddev-sans-first:0.53 02:36:25 INFO - PROCESS | 8548 | Values: 52.0 50.0 51.0 51.0 50.0 50.0 51.0 50.0 51.0 51.0 02:36:25 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 02:36:25 INFO - PROCESS | 8548 | Values: 63.0 63.0 63.0 63.0 64.0 63.0 64.0 63.0 63.0 63.0 02:36:25 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:79.50 stddev:2.71 (3.4%) stddev-sans-first:2.83 02:36:25 INFO - PROCESS | 8548 | Values: 79.0 78.0 79.0 79.0 87.0 80.0 83.0 80.0 79.0 79.0 02:36:25 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | [#11] stanford-crypto-ccm Cycles:10 Average:129.00 Median:128.00 stddev:7.35 (5.7%) stddev-sans-first:7.71 02:36:25 INFO - PROCESS | 8548 | Values: 126.0 135.0 125.0 128.0 122.0 122.0 129.0 128.0 147.0 128.0 02:36:25 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:165.00 stddev:4.22 (2.6%) stddev-sans-first:4.22 02:36:25 INFO - PROCESS | 8548 | Values: 161.0 165.0 164.0 161.0 163.0 170.0 166.0 174.0 165.0 161.0 02:36:25 INFO - PROCESS | 8548 | 02:36:25 INFO - PROCESS | 8548 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.00 stddev:1.60 (2.5%) stddev-sans-first:1.30 02:36:25 INFO - PROCESS | 8548 | Values: 68.0 65.0 66.0 63.0 65.0 67.0 65.0 65.0 64.0 63.0 02:36:25 INFO - PROCESS | 8548 | -------- Summary: end -------- 02:36:25 INFO - PROCESS | 8548 | 02:36:26 INFO - TEST-INFO | 8548: exit 0 02:36:26 INFO - TEST-OK | kraken | took 62461ms 02:36:26 INFO - SUITE-END | took 1178s 02:36:26 INFO - Completed test suite (00:19:38) 02:36:26 INFO - javascript benchmark 02:36:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2991.4914464541025, 3034.6661867194603, 3043.93462288199, 3045.9390812183756, 3058.1284333964823, 4926.261060840876, 5061.746912654371, 5098.745062746862, 5190.247414124954, 5197.4542673019305, 5081.187010078388, 5085.830259040423, 5091.363579552547, 5128.512791284822, 5167.199084141955, 4680.5787479126875, 4684.625229981611, 4686.250199968006, 4692.296155576657, 4786.329913812069, 4727.881802954919, 4744.217204161309, 4771.689840160396, 4781.90436191276, 4790.88022799431, 4559.703619264737, 4586.289125185584, 4587.7017993830295, 4617.676762626617, 4718.127146477896, 4656.650751193674, 4663.88340291492, 4665.346851440801, 4666.836660716858, 4697.224957881946, 4608.377868987711, 4630.768461576941, 4647.3958385410015, 4662.883427914296, 4669.859904202845, 4953.380827396575, 4961.900761984776, 5031.84904452867, 5032.119379108678, 5063.620228482841, 5426.186072089179, 5446.291982042309, 5449.264349312832, 5452.59105567084, 5452.863678408032, 5438.510534051954, 5449.209864569655, 5451.591130665176, 5466.125419932831, 5466.644668096561, 5046.84859454217, 5076.492350764931, 5133.897322053538, 5138.152204886227, 5159.948400515985, 5676.489115979582, 5678.0915053591225, 5681.34664513578, 5682.602217844794, 5689.715514224272, 5454.3454785426, 5457.727113644302, 5458.426865179099, 5461.563074953994, 5469.453054694499, 5330.413654498002, 5393.2449457076045, 5422.945770542323, 5429.131338985743, 5513.007658621486, 5483.972580137153, 5492.780288788484, 5493.807716729895, 5500.037493438632, 5507.476789704971, 5350.304460420199, 5365.543928766098, 5408.242846001563, 5408.3509978802795, 5421.5391691706645, 4252.638525725286, 4270.893227669333, 4281.486221653359, 4352.064306174188, 4379.84670536536, 3881.84472621092, 3919.627635374635, 3924.5879182685976, 3924.6271604197555, 3963.1875465529497, 5475.123980163155, 5476.452354764571, 5477.75350109246, 5480.013597552479, 5482.533984611353, 5342.465753424626, 5342.786288548415, 5510.0632892408385, 5517.806876759374, 5518.668879867221, 5348.277982472317, 5418.539424148913, 5419.403865574784, 5429.022775900376, 5436.864078398072, 4909.582685471703, 4917.409910810726, 4917.950820491821, 4923.458419573844, 4925.261210818406, 5651.576131790214, 5715.914261286167, 5830.621009634445, 5895.115732640138, 5904.261967254093, 5317.734113294242, 5368.651037682538, 5428.972855135699, 5431.728413579384, 5435.266239057834, 5835.9708201458725, 5836.824895253149, 5841.1530328101335, 5841.532677385884, 5843.7370318336625, 5237.607179461632, 5300.867478313073, 5313.123334649739, 5374.11327131019, 5376.758045888023, 5346.144616861284, 5418.322709661293, 5448.7548060336585, 5450.781968721207, 5452.018636645442, 5203.16749320107, 5259.289995850511, 5261.7895284189735, 5264.605154613343, 5271.341082364705, 5148.871278218075, 5211.244369566468, 5254.816081437131, 5255.816046438356, 5256.763445644879, 5154.407243166934, 5200.089984252665, 5206.479352064764, 5209.843704688866, 5246.632735708617, 5328.307320048524, 5330.387005494263, 5332.093544097435, 5333.280007198979, 5334.039872822929, 5136.743162841918, 5172.120739474223, 5181.974090129676, 5203.947960520346, 5207.505286997729], "value": 5072.572508446281, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.7728869807727, 598.4863572993428, 601.2574470528896, 601.8525461261992, 603.882242962622, 43084.0, 44280.33579496306, 44345.0, 47279.52720472794, 47387.0, 45623.31565026527, 46179.0, 46319.53680463194, 46387.304190437084, 46394.30408543866, 8833.07252738463, 9010.279177665803, 9024.458532488072, 9026.909730902673, 9029.0, 9020.188183063523, 9088.727338179866, 9121.0, 9122.77193070172, 9126.087391260888, 9202.907970920305, 9231.907680923205, 9234.445933244027, 9240.768980775501, 9251.53742312885, 9012.549372531346, 9128.087191280852, 9144.954275228582, 9157.404768690014, 9157.633694652206, 8829.867551986725, 8893.0, 8937.865932011026, 8944.016158222656, 8946.150115738994, 42715.786421068005, 43098.35352469715, 43104.137917241795, 43128.0, 43136.0, 3637.3634613942454, 3668.8349024293702, 4007.318755811519, 4181.289180839234, 4285.764282964436, 32678.34643307144, 32783.0, 32817.17957051069, 32914.17714557131, 34542.65457345445, 4347.782610869475, 4426.579474949875, 4452.287633978548, 4467.128909862578, 4469.955300447019, 381865.0, 382511.0, 385590.7291125083, 386049.0, 388761.05619472003, 59397.406025939184, 59416.70291648514, 59441.40558594359, 59947.700261498416, 59959.40040599625, 63955.36044639587, 64230.67884660547, 64320.0, 64401.677991609744, 64411.0, 16216.675666486604, 16320.102394368423, 16366.0, 16453.91773041127, 16458.177091145393, 12601.66869129214, 12702.0, 12706.174098683556, 12718.364081795873, 12799.360031998363, 16441.64650590067, 18816.247350106118, 18872.433826985212, 18903.716444253347, 19083.713744293847, 6784.7964561063245, 6875.759348422781, 7017.228104908456, 7024.71696698575, 7065.43476521877, 890.692711014699, 1029.897010298979, 1052.2108418685987, 1093.6117678224275, 1156.8206927926185, 31286.0, 32356.0, 33766.831165844014, 34878.302433951685, 36303.0, 1077.8975997280245, 1086.3687998849225, 1098.780243951213, 1103.4041617526445, 1159.6231224851956, 15680.843191568167, 16927.830721692626, 16953.0, 16984.40554580584, 17008.404705835237, 607657.0, 612262.0, 614712.0, 615115.0, 616809.0, 56438.71780641071, 56750.43249567534, 56915.715421422625, 57108.42891571031, 57116.0, 1255.234307072685, 1346.6633341664583, 1353.905226634146, 1392.1229625335973, 1392.3595146232644, 31017.224569385948, 32609.34781304408, 32941.67058329386, 33257.83371083129, 33631.83184084064, 50744.23883641674, 51362.74318628383, 51509.227361590354, 51513.0, 51565.742171288905, 436631.0, 436839.0, 437417.0, 439012.0, 441853.0, 24859.12993045235, 26393.340166495993, 27481.587776182972, 27487.725122748518, 29212.0, 45993.77003115097, 46449.0, 47834.76082619565, 48339.75830120827, 48468.5153148464, 39570.406443902786, 40014.79992600018, 40766.0, 40794.817105152, 41823.37264941089, 39663.0, 39725.0, 40163.397549036206, 40759.0, 41875.371869421375, 39418.40872386859, 39928.800355998035, 40790.0, 41276.1744765097, 41398.1720365597, 32813.0, 35695.28609427849, 36433.0, 36446.817765911, 36911.44632830456, 26996.73003270021, 27108.864455678384, 27638.723612763613, 27811.721882781723, 27825.860870695516], "value": 20405.554136431896, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.5154753463102, 384.3542848015333, 393.8365578285011, 394.4123256348042, 417.8662827895073, 12925.77967286729, 18958.810411895876, 20879.164833406685, 20883.68674469882, 21137.894310528463, 16125.758113628275, 16388.508344749647, 16970.83029169711, 19705.211791528356, 20349.28777492788, 5513.0, 5546.251256080438, 5678.94627300567, 5729.28383952006, 5731.054376027951, 5586.748596313156, 5619.6066275360745, 5623.184638227454, 5627.2684551008315, 5631.042722737145, 5600.691961942091, 5604.523615492688, 5608.46719561643, 5614.185943038257, 5627.212190293362, 5595.4964053235, 5598.8600284993, 5601.8599535011745, 5604.299462567179, 5605.187247849039, 4855.295982082578, 4973.477784832577, 5019.121653710586, 5071.1632580624155, 5074.923876141861, 23867.28398148041, 24091.39771505709, 24207.394815129588, 24364.87817560901, 24426.389340266458, 2184.56308738253, 2334.8248881333966, 2780.819246748955, 3061.785675002751, 3133.5926329577237, 10382.480875956247, 10684.572617095308, 10692.893071069268, 10700.411477368742, 10826.404547749871, 1882.9623407531772, 1951.6389467948475, 1991.8107779760899, 2018.9596208075757, 2090.153487837413, 67813.32186678119, 77810.6109469449, 80383.0, 81486.59256703679, 81728.0, 10709.250352475408, 11000.944995274973, 11120.165987550967, 11296.887031129583, 11352.148588855704, 15598.610034748996, 15602.141882196362, 15604.375824967101, 15636.296366663528, 15645.374185032699, 8069.515829050276, 8091.271785539329, 8092.393070519617, 8257.8348433031, 8273.0, 7717.112532058773, 7805.33654639362, 7993.720219792395, 8009.719659811995, 8065.7176998805935, 4605.700629459075, 4743.264793956942, 4764.2615394613895, 4815.590674792612, 4930.852074437773, 1929.8552608554135, 1978.5844972555924, 1992.8604997650025, 1997.540565669904, 2005.428452890919, 879.4811061473633, 955.6655170690203, 956.9665061722807, 1003.3127307794184, 1014.4876837197169, 18675.626487470447, 20495.69256461154, 20586.970651467367, 20611.0, 21000.264990725253, 946.3328353510758, 962.5860879821604, 983.5033308179467, 1014.7158795537262, 1024.1732817423815, 10475.895241047645, 11297.774044519063, 11326.093912486982, 11382.829257561143, 11391.0, 371450.0, 373718.0, 375749.0, 505831.0, 510847.0, 87703.0, 92108.0, 92480.0, 94235.52882235682, 94491.0, 1054.1145437832258, 1055.7466208109997, 1108.8724796648328, 1110.5224753355974, 1111.4498323330004, 17856.01791901458, 17871.195796188942, 18659.16033778456, 18674.253029879197, 19067.663158947405, 40551.1889762209, 41403.378949315185, 41526.0, 42315.57684423116, 42545.78727106345, 58631.0, 58738.412615875, 58921.0, 59253.0, 59779.0, 7709.691612335668, 7787.844243115219, 7793.37652987771, 7890.0, 8070.435069545076, 9492.345447002815, 12783.296918669563, 12799.616011519669, 12866.228026318451, 13676.794848077088, 11644.0, 11828.585999489977, 12177.878221217674, 12209.389530523616, 12270.877291226974, 10803.945980270048, 11464.31214127155, 11536.134789890624, 11686.357250350971, 11869.881301186877, 9597.856032159663, 11832.408379581158, 11960.820587691018, 12118.333491658044, 12238.87761122413, 8321.542315172723, 8486.787830304293, 8548.91451085506, 8557.871631925402, 8830.867536986822, 10192.694219173436, 11482.77034459323, 11760.176787624785, 11916.344601047025, 12024.158308918291], "value": 9192.479795538306, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1380.9378577963992, 1470.7867359232912, 1508.2232650185156, 1627.170143226954, 1802.6755184066878, 8346.624401901914, 8753.343499237559, 8800.427972181804, 9108.225800806922, 9142.542872856347, 8890.599923003465, 8990.280777537797, 9009.459432434058, 9015.233705135055, 9024.548772561378, 2325.337278875525, 2358.41039740065, 2359.3393849722106, 2363.6690863279155, 2368.2539999899977, 2286.7141607299086, 2342.0, 2356.8585884846966, 2357.705286839145, 2358.929232123039, 2313.595120853844, 2324.8488848224897, 2334.533093381322, 2350.74141844397, 2356.39911822485, 2266.3427605994243, 2287.085165933623, 2295.0934380919525, 2296.8966396512114, 2302.3898666853297, 2277.3851060213815, 2278.40761402035, 2279.4073540879326, 2281.0989659084735, 2284.383216531544, 8786.912130878738, 8922.375433719642, 8956.507392093432, 8969.820603587958, 8982.146696063863, 5238.528532432099, 5396.406395296514, 5437.34751829779, 5439.700816455093, 5440.102383106782, 3406.9659303406897, 3455.3953058214965, 3461.6019157796923, 3519.419295816187, 3526.4710293455933, 1134.0474001838495, 1153.2503872482869, 1159.4434671357797, 1189.3636904256182, 1189.8215267709827, 12389.318587477685, 12442.502299907992, 12523.499060037497, 12525.56160534386, 12533.310667913262, 4472.709273897186, 4558.700770280454, 4741.762911854463, 4748.6438517111355, 4755.524447555217, 9851.310408271496, 9855.901440985499, 9859.112679858847, 9917.752056198653, 9938.105570498545, 5423.3491980962535, 5431.728413579384, 5432.212329212242, 5433.0, 5433.293671822637, 4213.220554197484, 4429.844955426544, 4586.0, 4880.0, 5079.0, 3668.1013151777984, 3673.1368128489785, 3777.433384992273, 3792.1467669774634, 3838.405047217686, 1160.5415860734963, 1164.5050853387277, 1168.462507246659, 1177.844544483593, 1184.2184158455377, 949.5110018340561, 954.9379290346105, 958.6309270930602, 958.68363440066, 995.9751006224902, 5668.121441176624, 5668.7165641718575, 5687.687177205294, 5743.1385292206505, 5968.432998865101, 467.33638233708217, 467.483430808958, 470.5247699823203, 478.17514786992854, 480.512280035764, 3972.920541589152, 4096.549379568245, 4097.303458412077, 4117.3206420940205, 4145.253854306254, 10264.076233139054, 10546.894531054744, 10670.199735019905, 10677.89322106769, 10698.304610200468, 10614.0, 10616.203784716177, 10635.149188064936, 10676.572937082585, 10681.198910081619, 8055.583325002476, 8130.756077317572, 8134.75595732129, 8142.145074767064, 8147.877781833277, 5938.2280303560265, 5987.520998320037, 6158.969205153946, 6294.213223347082, 6403.0, 9921.255905807238, 10327.586896524055, 10340.37957722539, 10366.429846358522, 10503.107235885036, 8935.821283574422, 9757.902420975699, 10248.077673009466, 10255.538500767334, 10472.90034546392, 7809.1409944907155, 7910.050793904768, 8000.839983200187, 8003.239692229229, 8202.876956845772, 8269.379796515164, 8680.696175633822, 8682.0, 8734.30125589964, 8815.515146666994, 7839.568823714522, 7893.842123157619, 7897.802554936173, 7906.486078404885, 8155.63299651529, 7606.581638009962, 7607.391408687403, 7666.4633475656165, 7711.807204819925, 7728.613569321624, 6905.136857892763, 6912.101426814482, 6914.343137401939, 6995.965020174866, 7004.614746189009, 5061.139606267017, 5085.084684756632, 5154.123798954111, 5214.530692237717, 5254.42201357858, 5138.614603904647, 5202.713850738246, 5343.225232341367, 5356.1697936819855, 5396.9730151348995], "value": 4554.17077114664, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.3601151713849, 325.6173995555223, 334.4949126818505, 335.1019268360792, 339.63658884993066, 4747.382840230774, 4872.658913876021, 4876.317315575822, 4919.72941488218, 4925.5813255873245, 4888.828890988816, 4912.1018599026565, 4926.359573255471, 4937.2840938063955, 4954.554090131887, 3932.7836968966694, 3951.604839516047, 3974.940375894364, 3975.483187185662, 3995.3407687731487, 3860.5560360558475, 4016.3373043447796, 4023.396490526415, 4027.7715296834454, 4033.1933613277315, 3903.746256493333, 3914.354131568288, 3922.0979174789813, 3924.5682974872743, 3945.940810887839, 3899.941500877461, 3899.941500877489, 3904.9414258786283, 3918.2163567286657, 3956.5054368203973, 3496.4755286707195, 3497.8600855965733, 3511.8244087795506, 3515.9472607910902, 3520.084777957724, 4622.19111655459, 4820.807167713322, 4855.344528488644, 4873.34209881665, 4909.551682253744, 4378.649708023382, 4384.188925048844, 4390.736555806662, 4394.62645675118, 4418.4256046714045, 5081.644284900059, 5084.3644544431945, 5112.718800465973, 5118.566801295643, 5122.3853137623355, 3610.1877077657578, 3772.6981841452616, 3775.5846856845724, 3779.263043706478, 3790.8673196437994, 5355.919661205085, 5361.973190134064, 5378.569714422876, 5387.245785589981, 5418.160185171305, 3894.883153505399, 3895.7662540247675, 3906.257811015898, 3909.3354129798004, 3910.6089391061237, 3938.428927805452, 3941.2905676977844, 3972.9471690002174, 3977.721559490808, 3983.1237127831832, 4222.577742225814, 4376.14665140301, 4424.469063712375, 4428.468583769968, 4450.666200035011, 5174.275601415805, 5174.689518628894, 5181.093308670967, 5212.218167274939, 5225.372955245395, 1292.1665525735848, 1293.2176033499786, 1317.637649646355, 1322.2066759944148, 1354.7358265138203, 921.3642586615214, 934.7522906429665, 952.8094381123665, 958.8274110660149, 963.5952899781968, 5107.055194788976, 5156.473328069219, 5157.174852023658, 5164.92252616211, 5166.0, 5162.780004597984, 5172.094883395391, 5173.758298008526, 5201.349831271091, 5202.843914682566, 5048.7475626219275, 5077.543021128043, 5089.244210747286, 5114.43741188469, 5173.120569503193, 2414.263649586893, 2455.226603619854, 2457.557639624849, 2461.200109964269, 2482.7641374069435, 4957.537526429712, 4983.25251212321, 4993.350864387607, 4998.500149984972, 5000.699958002531, 4573.30787608587, 4752.500987396343, 4783.0, 4811.0, 4828.5412885775795, 4913.901721965541, 4949.331840201527, 4950.8762280943265, 4952.925706114411, 4964.616983847845, 4561.908761824811, 4645.0, 4647.55848194421, 4670.883227919193, 4679.438467383936, 4449.879912181157, 4450.510443851109, 4585.358049873087, 4626.421697287839, 4678.976605116953, 4895.363602731622, 4905.503821334527, 4918.926216106833, 4925.266477105241, 4930.865900842898, 3536.7170626350344, 3543.025667941337, 3555.6622120898473, 3590.694790942799, 3597.6402359763815, 4691.296305554194, 4714.4106986626675, 4741.217699079613, 4755.429348478205, 5093.592512598909, 4500.842470513517, 5108.259302401206, 5135.0, 5138.4604616514725, 5142.845714628567, 5152.3044389007, 5159.845204643867, 5168.819091331786, 5174.482551744795, 5175.508326708957, 4987.326710894128, 5130.0, 5140.100482415637, 5148.716820574755, 5157.200633901752, 5097.515736005073, 5132.230165475208, 5166.715830629201, 5188.195829646411, 5209.270702101633, 5068.847934561969, 5072.695638261716, 5084.415292241291, 5084.771185296744, 5101.69389836611], "value": 3879.153052141335, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.2748888555873, 819.0089991110756, 830.3398797955625, 842.64608864277, 853.6670698427611, 14594.343254553543, 14643.926780366097, 14717.558473245794, 14720.190389528572, 15260.923695381536, 13640.522581709642, 13853.238071906042, 13861.861381386208, 13874.722505549882, 13874.722505549882, 811.0915774332741, 823.8269963307713, 825.9215374539409, 826.0665448043702, 826.8098337382405, 674.7537148940651, 806.6410447350931, 817.337956255435, 829.6473998550622, 832.50881979632, 873.4409977614332, 874.8425283448977, 875.299760191844, 875.5534677314552, 875.6453636277317, 868.6395146014396, 869.9995005743383, 872.2629378175463, 872.3500991761147, 872.5288344294073, 512.7564406906727, 525.8790478190037, 527.6517498451004, 614.1862032806505, 628.9465395441393, 15952.680946381124, 15997.600059998476, 16029.198540073066, 16031.0, 16031.679366412724, 14392.424303027867, 14852.628684282872, 15001.849981500154, 15174.772378414333, 15339.693206135815, 13752.93123534376, 13906.51327203553, 13929.860701392958, 14151.603615132397, 14812.0, 4482.977585112054, 4529.071540334224, 4554.38515800366, 4557.088582283556, 4564.315352697089, 79029.20970790276, 81572.0, 81914.0, 82746.58626706888, 85353.57323213406, 11602.303861768321, 11700.70748231301, 11959.80451375547, 12030.157888947864, 12609.054320925967, 22977.310680679606, 23159.189428370264, 23265.651015234784, 23349.41626459352, 23413.531729365317, 8967.955160224157, 9020.594073266719, 9027.4132181408, 9112.271018318519, 9139.451632902048, 4974.626902982291, 4979.651424400329, 5009.474005229471, 5119.078565858182, 5146.819861304836, 3555.982220088883, 3636.1636823530207, 3671.7980511071614, 3683.263347330491, 3694.07648087978, 596.8836076965036, 600.0968542343745, 609.1624016976657, 614.1555361378105, 621.201755743867, 566.9262995810518, 692.8579641173548, 708.3518580498888, 718.7125149940066, 748.7566540874237, 9468.14786669203, 9901.504924753877, 9953.751156221153, 10320.225983051301, 10473.109785668152, 598.4763332084426, 603.4448307557058, 689.9793006209821, 719.251977942944, 745.9179490256067, 4769.976150119227, 4931.950680493149, 4959.2809042688605, 4982.209962420983, 5025.949740502549, 127003.36498317635, 128108.35945820212, 129909.0, 131218.0, 131512.0, 62772.68613656903, 64942.35057649457, 80060.0, 85426.5728671365, 106597.46701266599, 97749.0, 98081.50959245158, 98812.01187988171, 101572.0, 102437.48781256046, 9032.63869445215, 9128.771780705536, 9328.83389576303, 9367.250619950251, 9439.716808495756, 56464.717676411354, 56602.0, 56996.0, 57121.0, 58132.709336453045, 80843.19156808357, 83265.58367208368, 85907.57046214979, 86919.56540217258, 88795.55602221948, 11954.342511161969, 12095.818562721743, 12243.938780306042, 12641.0, 13007.089503734647, 12677.619671409873, 13010.0, 13053.934730326288, 13084.80372794428, 13976.231307278198, 12066.879331206575, 12716.554920577735, 12739.745205096031, 13085.607431777062, 13369.732605348034, 11860.822087668865, 12149.514019439477, 12257.938710306391, 12282.201656892274, 12652.683682907633, 12510.0, 12530.812037819622, 12714.61856144317, 12723.745525089631, 13133.73732525325, 10581.947090264499, 10806.243562950833, 11111.333320000826, 11126.721831954266, 11312.717182070513, 10709.357438553712, 11038.889611103787, 11255.549778008788, 11426.542938282708, 11556.826647600461], "value": 7670.180477733219, "unit": "score"}], "name": "dromaeo_css", "value": 7107.803867899369, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 109.0, 112.0, 113.0, 111.0, 116.0, 111.0, 111.0, 112.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 149.0, 187.0, 118.0, 154.0, 152.0, 120.0, 177.0, 118.0], "value": 143.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 160.0, 161.0, 162.0, 161.0, 161.0, 161.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 76.0, 170.0, 75.0, 75.0, 75.0, 76.0, 167.0, 77.0], "value": 94.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 160.0, 86.0, 86.0, 86.0, 139.0, 85.0, 85.0, 147.0], "value": 104.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 148.0, 148.0, 147.0, 147.0, 147.0, 147.0, 147.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 51.0, 51.0, 50.0, 50.0, 51.0, 50.0, 51.0, 51.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 64.0, 63.0, 64.0, 63.0, 63.0, 63.0], "value": 63.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 79.0, 79.0, 87.0, 80.0, 83.0, 80.0, 79.0, 79.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 135.0, 125.0, 128.0, 122.0, 122.0, 129.0, 128.0, 147.0, 128.0], "value": 129.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 165.0, 164.0, 161.0, 163.0, 170.0, 166.0, 174.0, 165.0, 161.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 66.0, 63.0, 65.0, 67.0, 65.0, 65.0, 64.0, 63.0], "value": 65.1, "unit": "score"}], "name": "kraken", "value": 1526.6999999999998, "alertThreshold": 2.0}]} 02:36:27 INFO - Return code: 0 02:36:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:36:27 INFO - # TBPL SUCCESS # 02:36:27 INFO - Running post-action listener: _resource_record_post_action 02:36:27 INFO - [mozharness: 2016-08-23 09:36:27.156484Z] Finished run-tests step (success) 02:36:27 INFO - Running post-run listener: _resource_record_post_run 02:36:27 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 418275328; Read time: 8; Write time: 378560 02:36:27 INFO - TinderboxPrint: CPU usage
13.3% 02:36:27 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 02:36:27 INFO - TinderboxPrint: I/O write bytes / time
418,275,328 / 378,560 02:36:27 INFO - TinderboxPrint: CPU idle
8,269.3 (86.7%) 02:36:27 INFO - TinderboxPrint: CPU user
1,204.9 (12.6%) 02:36:27 INFO - TinderboxPrint: Swap in / out
0 / 0 02:36:27 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 129798144; Read time: 0; Write time: 116928 02:36:27 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 288104448; Read time: 0; Write time: 261632 02:36:27 INFO - Running post-run listener: _upload_blobber_files 02:36:27 INFO - Blob upload gear active. 02:36:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:36:27 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 02:36:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:36:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:36:28 INFO - (blobuploader) - INFO - Open directory for files ... 02:36:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:36:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:36:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:36:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:36:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:36:28 INFO - (blobuploader) - INFO - Done attempting. 02:36:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:36:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:36:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:36:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:36:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:36:29 INFO - (blobuploader) - INFO - Done attempting. 02:36:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:36:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:36:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:36:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:36:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:36:34 INFO - (blobuploader) - INFO - Done attempting. 02:36:34 INFO - (blobuploader) - INFO - Iteration through files over. 02:36:34 INFO - Return code: 0 02:36:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:36:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:36:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ec21b1aa737abb8740ad4a7072d29e0e73b90843555b805c3b71756d3fde5cea3fdf5b007cd780602ae825bd3235db2f948d454a97a7bc8f948d28b871ba21b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/06ed7220c33dea1baa0dd36ba6dde3ea22adf1241432652a606db47480bdfa63cbba060523076991bb24317a8a7497cfd3a7ccd8fa945ccfeff928f96e38e961", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/31782d7da23bbe5307e4e3697dbcbe8213232d55f386b25b0d36d51d7a9fc65c7cd4c2268b285ae4f5c8b39513686f0d2406195a97d227814b875e0d451b12d3"} 02:36:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:36:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:36:34 INFO - Contents: 02:36:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ec21b1aa737abb8740ad4a7072d29e0e73b90843555b805c3b71756d3fde5cea3fdf5b007cd780602ae825bd3235db2f948d454a97a7bc8f948d28b871ba21b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/06ed7220c33dea1baa0dd36ba6dde3ea22adf1241432652a606db47480bdfa63cbba060523076991bb24317a8a7497cfd3a7ccd8fa945ccfeff928f96e38e961", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/31782d7da23bbe5307e4e3697dbcbe8213232d55f386b25b0d36d51d7a9fc65c7cd4c2268b285ae4f5c8b39513686f0d2406195a97d227814b875e0d451b12d3"} 02:36:34 INFO - Running post-run listener: copy_logs_to_upload_dir 02:36:34 INFO - Copying logs to upload dir... 02:36:34 INFO - mkdir: /builds/slave/test/build/upload/logs 02:36:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.529723 ========= master_lag: 1.32 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-08-23 02:36:34.983195) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 02:36:34.987368) ========= 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=c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ec21b1aa737abb8740ad4a7072d29e0e73b90843555b805c3b71756d3fde5cea3fdf5b007cd780602ae825bd3235db2f948d454a97a7bc8f948d28b871ba21b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/06ed7220c33dea1baa0dd36ba6dde3ea22adf1241432652a606db47480bdfa63cbba060523076991bb24317a8a7497cfd3a7ccd8fa945ccfeff928f96e38e961", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/31782d7da23bbe5307e4e3697dbcbe8213232d55f386b25b0d36d51d7a9fc65c7cd4c2268b285ae4f5c8b39513686f0d2406195a97d227814b875e0d451b12d3"} build_url:https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011394 build_url: 'https://queue.taskcluster.net/v1/task/ALZTqVsKTOOcRZ9bZQcPbw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ec21b1aa737abb8740ad4a7072d29e0e73b90843555b805c3b71756d3fde5cea3fdf5b007cd780602ae825bd3235db2f948d454a97a7bc8f948d28b871ba21b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/06ed7220c33dea1baa0dd36ba6dde3ea22adf1241432652a606db47480bdfa63cbba060523076991bb24317a8a7497cfd3a7ccd8fa945ccfeff928f96e38e961", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/31782d7da23bbe5307e4e3697dbcbe8213232d55f386b25b0d36d51d7a9fc65c7cd4c2268b285ae4f5c8b39513686f0d2406195a97d227814b875e0d451b12d3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 02:36:35.011861) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 02:36:35.012164) ========= 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=c6927cf182745ad25b1d38b9000001fe-1471941221.450280-1215924338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004581 ========= master_lag: 0.35 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 02:36:35.369935) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 02:36:35.370248) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 02:36:35.370512) ========= ========= Total master_lag: 1.75 =========