builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1472872911.24 results: success (0) buildid: 20160902173000 builduid: b1f1f8f0cd08465482253e73563d76d1 revision: 18ab41c28e2e073d7a8d1854b565236b44ce9ba7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 20:21:51.240052) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 20:21:51.240641) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 20:21:51.241010) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 20:21:51.272883) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 20:21:51.273135) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-02 20:21: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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 89.0M=0s 2016-09-02 20:21:51 (89.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106221 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 20:21:51.397536) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 20:21:51.398110) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018510 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 20:21:51.451089) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 20:21:51.451521) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 18ab41c28e2e073d7a8d1854b565236b44ce9ba7 --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 18ab41c28e2e073d7a8d1854b565236b44ce9ba7 --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=d6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-02 20:21:51,735 truncating revision to first 12 chars 2016-09-02 20:21:51,735 Setting DEBUG logging. 2016-09-02 20:21:51,735 attempt 1/10 2016-09-02 20:21:51,735 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/18ab41c28e2e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-02 20:21:52,051 unpacking tar archive at: fx-team-18ab41c28e2e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.667769 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 20:21:52.132704) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 20:21:52.133003) ========= script_repo_revision: 18ab41c28e2e073d7a8d1854b565236b44ce9ba7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 20:21:52.133409) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 20:21:52.133736) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 20:21:52.149294) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-09-02 20:21:52.149598) ========= /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=d6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:21:52 INFO - MultiFileLogger online at 20160902 20:21:52 in /builds/slave/test 20:21: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 20:21:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:21:52 INFO - {'append_to_log': False, 20:21:52 INFO - 'base_work_dir': '/builds/slave/test', 20:21:52 INFO - 'blob_upload_branch': 'Fx-Team', 20:21:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:21:52 INFO - 'branch': 'Fx-Team', 20:21:52 INFO - 'buildbot_json_path': 'buildprops.json', 20:21:52 INFO - 'config_files': ('talos/linux_config.py',), 20:21:52 INFO - 'default_actions': ('clobber', 20:21:52 INFO - 'read-buildbot-config', 20:21:52 INFO - 'download-and-extract', 20:21:52 INFO - 'populate-webroot', 20:21:52 INFO - 'create-virtualenv', 20:21:52 INFO - 'install', 20:21:52 INFO - 'run-tests'), 20:21:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:21:52 INFO - 'download_minidump_stackwalk': True, 20:21:52 INFO - 'download_symbols': 'ondemand', 20:21:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:21:52 INFO - 'tooltool.py': '/tools/tooltool.py', 20:21:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:21:52 INFO - '/tools/misc-python/virtualenv.py')}, 20:21:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:21:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:21:52 INFO - 'installer_path': 'installer.exe', 20:21:52 INFO - 'log_level': 'info', 20:21:52 INFO - 'log_name': 'talos', 20:21:52 INFO - 'log_to_console': True, 20:21:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:21:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:21:52 INFO - 'opt_config_files': (), 20:21:52 INFO - 'pip_index': False, 20:21:52 INFO - 'sps_profile': False, 20:21:52 INFO - 'sps_profile_interval': 0, 20:21:52 INFO - 'suite': 'dromaeojs', 20:21:52 INFO - 'system_bits': '32', 20:21:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:21:52 INFO - 'title': 'talos-linux64-ix-034', 20:21:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:21:52 INFO - 'use_talos_json': True, 20:21:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:21:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:21:52 INFO - 'work_dir': 'build'} 20:21:52 INFO - [mozharness: 2016-09-03 03:21:52.523024Z] Running clobber step. 20:21:52 INFO - Running pre-action listener: _resource_record_pre_action 20:21:52 INFO - Running main action method: clobber 20:21:52 INFO - rmtree: /builds/slave/test/build 20:21:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:21:52 INFO - Running post-action listener: _resource_record_post_action 20:21:52 INFO - [mozharness: 2016-09-03 03:21:52.977716Z] Finished clobber step (success) 20:21:52 INFO - [mozharness: 2016-09-03 03:21:52.977818Z] Running read-buildbot-config step. 20:21:52 INFO - Running pre-action listener: _resource_record_pre_action 20:21:52 INFO - Running main action method: read_buildbot_config 20:21:52 INFO - Using buildbot properties: 20:21:52 INFO - { 20:21:52 INFO - "project": "", 20:21:52 INFO - "product": "firefox", 20:21:52 INFO - "script_repo_revision": "production", 20:21:52 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 20:21:52 INFO - "repository": "", 20:21:52 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 20:21:52 INFO - "buildid": "20160902173000", 20:21:52 INFO - "pgo_build": "True", 20:21:52 INFO - "basedir": "/builds/slave/test", 20:21:52 INFO - "buildnumber": 1027, 20:21:52 INFO - "slavename": "talos-linux64-ix-034", 20:21:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 20:21:52 INFO - "platform": "ubuntu64_hw", 20:21:52 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 20:21:52 INFO - "branch": "fx-team", 20:21:52 INFO - "repo_path": "integration/fx-team", 20:21:52 INFO - "revision": "18ab41c28e2e073d7a8d1854b565236b44ce9ba7", 20:21:52 INFO - "stage_platform": "linux64-pgo", 20:21:52 INFO - "builduid": "b1f1f8f0cd08465482253e73563d76d1", 20:21:52 INFO - "slavebuilddir": "test" 20:21:52 INFO - } 20:21:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 20:21:52 INFO - Running post-action listener: _resource_record_post_action 20:21:52 INFO - [mozharness: 2016-09-03 03:21:52.980241Z] Finished read-buildbot-config step (success) 20:21:52 INFO - [mozharness: 2016-09-03 03:21:52.980331Z] Running download-and-extract step. 20:21:52 INFO - Running pre-action listener: _resource_record_pre_action 20:21:52 INFO - Running main action method: download_and_extract 20:21:52 INFO - mkdir: /builds/slave/test/build/tests 20:21:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:21:52 INFO - trying https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:21:52 INFO - Downloading https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/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 20:21:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/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 20:21:53 INFO - Downloaded 1361 bytes. 20:21:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:21:53 INFO - Using the following test package requirements: 20:21:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 20:21:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:21:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:21:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:21:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:21:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:21:53 INFO - u'jsshell-linux-x86_64.zip'], 20:21:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:21:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:21:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 20:21:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:21:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:21:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:21:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:21:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:21:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:21:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:21:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:21:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:21: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')]} 20:21:53 INFO - trying https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:21:53 INFO - Downloading https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/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 20:21:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/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 20:21:54 INFO - Downloaded 21906553 bytes. 20:21: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 20:21:58 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 20:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:21:58 INFO - trying https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:21:58 INFO - Downloading https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/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 20:21:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/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 20:21:59 INFO - Downloaded 21906553 bytes. 20:21:59 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 20:22: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')]} 20:22:04 INFO - trying https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 20:22:04 INFO - Downloading https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/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 20:22:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/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 20:22:05 INFO - Downloaded 14413998 bytes. 20:22: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 20:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:06 INFO - trying https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:22:06 INFO - Downloading https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/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 20:22:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/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 20:22:08 INFO - Downloaded 59707429 bytes. 20:22:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:22:08 INFO - mkdir: /builds/slave/test/properties 20:22:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:22:08 INFO - Writing to file /builds/slave/test/properties/build_url 20:22:08 INFO - Contents: 20:22:08 INFO - build_url:https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:22:09 INFO - Running post-action listener: _resource_record_post_action 20:22:09 INFO - Running post-action listener: set_extra_try_arguments 20:22:09 INFO - [mozharness: 2016-09-03 03:22:09.372621Z] Finished download-and-extract step (success) 20:22:09 INFO - [mozharness: 2016-09-03 03:22:09.372848Z] Running populate-webroot step. 20:22:09 INFO - Running pre-action listener: _resource_record_pre_action 20:22:09 INFO - Running main action method: populate_webroot 20:22:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:22:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:22:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:22:09 INFO - '--tppagecycles', 20:22:09 INFO - '7'], 20:22:09 INFO - 'tests': ['tsvgm']}}, 20:22:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:22:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:22:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:22:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:22:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:22:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:22:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:09 INFO - 'talos_options': ['--disable-e10s'], 20:22:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:22:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:22:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:09 INFO - 'talos_options': ['--disable-e10s'], 20:22:09 INFO - 'tests': ['damp', 'tps']}, 20:22:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:09 INFO - 'tests': ['damp', 'tps']}, 20:22:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:22:09 INFO - 'tests': ['dromaeo_dom']}, 20:22:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:22:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:22:09 INFO - 'tests': ['basic_compositor_video']}, 20:22:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:22:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:22:09 INFO - 'tests': ['a11yr', 20:22:09 INFO - 'ts_paint', 20:22:09 INFO - 'tpaint', 20:22:09 INFO - 'sessionrestore', 20:22:09 INFO - 'sessionrestore_no_auto_restore', 20:22:09 INFO - 'tabpaint']}, 20:22:09 INFO - 'other-e10s': {'tests': ['a11yr', 20:22:09 INFO - 'ts_paint', 20:22:09 INFO - 'tpaint', 20:22:09 INFO - 'sessionrestore', 20:22:09 INFO - 'sessionrestore_no_auto_restore', 20:22:09 INFO - 'tabpaint']}, 20:22:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:22:09 INFO - 'ts_paint', 20:22:09 INFO - 'tpaint', 20:22:09 INFO - 'sessionrestore', 20:22:09 INFO - 'sessionrestore_no_auto_restore', 20:22:09 INFO - 'tabpaint']}, 20:22:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:22:09 INFO - 'ts_paint', 20:22:09 INFO - 'tpaint', 20:22:09 INFO - 'sessionrestore', 20:22:09 INFO - 'sessionrestore_no_auto_restore', 20:22:09 INFO - 'tabpaint']}, 20:22:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:22:09 INFO - 'tests': ['a11yr', 20:22:09 INFO - 'ts_paint', 20:22:09 INFO - 'tpaint', 20:22:09 INFO - 'sessionrestore', 20:22:09 INFO - 'sessionrestore_no_auto_restore', 20:22:09 INFO - 'tabpaint']}, 20:22:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:22:09 INFO - 'tests': ['a11yr', 20:22:09 INFO - 'ts_paint', 20:22:09 INFO - 'tpaint', 20:22:09 INFO - 'sessionrestore', 20:22:09 INFO - 'sessionrestore_no_auto_restore', 20:22:09 INFO - 'tabpaint']}, 20:22:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:22:09 INFO - 'tests': ['tsvgx', 20:22:09 INFO - 'tsvgr_opacity', 20:22:09 INFO - 'tart', 20:22:09 INFO - 'tscrollx', 20:22:09 INFO - 'cart']}, 20:22:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:22:09 INFO - 'tsvgr_opacity', 20:22:09 INFO - 'tart', 20:22:09 INFO - 'tscrollx', 20:22:09 INFO - 'cart']}, 20:22:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:09 INFO - 'talos_options': ['--disable-e10s'], 20:22:09 INFO - 'tests': ['tp5o']}, 20:22:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:09 INFO - 'tests': ['tp5o']}, 20:22:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:09 INFO - 'talos_options': ['--disable-e10s', 20:22:09 INFO - '--xperf_path', 20:22:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:22:09 INFO - 'tests': ['tp5n']}, 20:22:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:09 INFO - 'talos_options': ['--xperf_path', 20:22:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:22:09 INFO - 'tests': ['tp5n']}}, 20:22:09 INFO - 'talos.zip': {'path': '', 20:22:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:22:09 INFO - Running post-action listener: _resource_record_post_action 20:22:09 INFO - [mozharness: 2016-09-03 03:22:09.391684Z] Finished populate-webroot step (success) 20:22:09 INFO - [mozharness: 2016-09-03 03:22:09.391773Z] Running create-virtualenv step. 20:22:09 INFO - Running pre-action listener: _resource_record_pre_action 20:22:09 INFO - Running main action method: create_virtualenv 20:22:09 INFO - Creating virtualenv /builds/slave/test/build/venv 20:22: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 20:22:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:22:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:22:09 INFO - Using real prefix '/usr' 20:22:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:22:09 INFO - Installing distribute.............................................................................................................................................................................................done. 20:22:11 INFO - Installing pip.................done. 20:22:11 INFO - Return code: 0 20:22:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:22: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')]} 20:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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')]} 20:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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 0x7ffa25cbcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1953850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x194e370>, '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 0x19518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1951d80>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', '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 20:22: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 20:22: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 20:22:11 INFO - Using env: {'DISPLAY': ':0', 20:22:11 INFO - 'HOME': '/home/cltbld', 20:22:11 INFO - 'LANG': 'en_US.UTF-8', 20:22:11 INFO - 'LANGUAGE': 'en_US:en', 20:22:11 INFO - 'LOGNAME': 'cltbld', 20:22:11 INFO - 'MAIL': '/var/mail/cltbld', 20:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:11 INFO - 'MOZ_NO_REMOTE': '1', 20:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:11 INFO - 'NO_EM_RESTART': '1', 20:22:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:11 INFO - 'PWD': '/builds/slave/test', 20:22:11 INFO - 'SHELL': '/bin/bash', 20:22:11 INFO - 'SHLVL': '1', 20:22:11 INFO - 'TERM': 'linux', 20:22:11 INFO - 'TMOUT': '86400', 20:22:11 INFO - 'USER': 'cltbld', 20:22:11 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', 20:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:11 INFO - '_': '/tools/buildbot/bin/python'} 20:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:12 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 20:22:12 INFO - Cleaning up... 20:22:12 INFO - Return code: 0 20:22:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:22:12 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')]} 20:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:12 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')]} 20:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:12 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 0x7ffa25cbcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1953850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x194e370>, '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 0x19518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1951d80>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', '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 20:22:12 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 20:22:12 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 20:22:12 INFO - Using env: {'DISPLAY': ':0', 20:22:12 INFO - 'HOME': '/home/cltbld', 20:22:12 INFO - 'LANG': 'en_US.UTF-8', 20:22:12 INFO - 'LANGUAGE': 'en_US:en', 20:22:12 INFO - 'LOGNAME': 'cltbld', 20:22:12 INFO - 'MAIL': '/var/mail/cltbld', 20:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:12 INFO - 'MOZ_NO_REMOTE': '1', 20:22:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:12 INFO - 'NO_EM_RESTART': '1', 20:22:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:12 INFO - 'PWD': '/builds/slave/test', 20:22:12 INFO - 'SHELL': '/bin/bash', 20:22:12 INFO - 'SHLVL': '1', 20:22:12 INFO - 'TERM': 'linux', 20:22:12 INFO - 'TMOUT': '86400', 20:22:12 INFO - 'USER': 'cltbld', 20:22:12 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', 20:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:12 INFO - '_': '/tools/buildbot/bin/python'} 20:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:12 INFO - Downloading/unpacking psutil>=3.1.1 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:22:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:22:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:22:14 INFO - Installing collected packages: psutil 20:22:14 INFO - Running setup.py install for psutil 20:22:14 INFO - building 'psutil._psutil_linux' extension 20:22: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 20:22: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 20:22:14 INFO - building 'psutil._psutil_posix' extension 20:22: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 20:22: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 20:22:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:22:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:22:14 INFO - Successfully installed psutil 20:22:14 INFO - Cleaning up... 20:22:14 INFO - Return code: 0 20:22:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:22: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')]} 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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')]} 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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 0x7ffa25cbcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1953850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x194e370>, '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 0x19518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1951d80>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', '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 20:22: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 20:22: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 20:22:14 INFO - Using env: {'DISPLAY': ':0', 20:22:14 INFO - 'HOME': '/home/cltbld', 20:22:14 INFO - 'LANG': 'en_US.UTF-8', 20:22:14 INFO - 'LANGUAGE': 'en_US:en', 20:22:14 INFO - 'LOGNAME': 'cltbld', 20:22:14 INFO - 'MAIL': '/var/mail/cltbld', 20:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:14 INFO - 'MOZ_NO_REMOTE': '1', 20:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:14 INFO - 'NO_EM_RESTART': '1', 20:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:14 INFO - 'PWD': '/builds/slave/test', 20:22:14 INFO - 'SHELL': '/bin/bash', 20:22:14 INFO - 'SHLVL': '1', 20:22:14 INFO - 'TERM': 'linux', 20:22:14 INFO - 'TMOUT': '86400', 20:22:14 INFO - 'USER': 'cltbld', 20:22:14 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', 20:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:14 INFO - '_': '/tools/buildbot/bin/python'} 20:22:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:22:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:22:17 INFO - Installing collected packages: mozsystemmonitor 20:22:17 INFO - Running setup.py install for mozsystemmonitor 20:22:17 INFO - Successfully installed mozsystemmonitor 20:22:17 INFO - Cleaning up... 20:22:17 INFO - Return code: 0 20:22:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:17 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 0x7ffa25cbcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1953850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x194e370>, '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 0x19518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1951d80>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', '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 20:22:17 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 20:22:17 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 20:22:17 INFO - Using env: {'DISPLAY': ':0', 20:22:17 INFO - 'HOME': '/home/cltbld', 20:22:17 INFO - 'LANG': 'en_US.UTF-8', 20:22:17 INFO - 'LANGUAGE': 'en_US:en', 20:22:17 INFO - 'LOGNAME': 'cltbld', 20:22:17 INFO - 'MAIL': '/var/mail/cltbld', 20:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:17 INFO - 'MOZ_NO_REMOTE': '1', 20:22:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:17 INFO - 'NO_EM_RESTART': '1', 20:22:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:17 INFO - 'PWD': '/builds/slave/test', 20:22:17 INFO - 'SHELL': '/bin/bash', 20:22:17 INFO - 'SHLVL': '1', 20:22:17 INFO - 'TERM': 'linux', 20:22:17 INFO - 'TMOUT': '86400', 20:22:17 INFO - 'USER': 'cltbld', 20:22:17 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', 20:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:17 INFO - '_': '/tools/buildbot/bin/python'} 20:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:17 INFO - Downloading/unpacking jsonschema==2.5.1 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:22:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22:19 INFO - Downloading functools32-3.2.3-2.tar.gz 20:22:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:22:19 INFO - warning: no files found matching '*.txt' 20:22:19 INFO - no previously-included directories found matching 'build' 20:22:19 INFO - no previously-included directories found matching 'dist' 20:22:19 INFO - no previously-included directories found matching '.git*' 20:22:19 INFO - Installing collected packages: jsonschema, functools32 20:22:19 INFO - Running setup.py install for functools32 20:22:19 INFO - warning: no files found matching '*.txt' 20:22:19 INFO - no previously-included directories found matching 'build' 20:22:19 INFO - no previously-included directories found matching 'dist' 20:22:19 INFO - no previously-included directories found matching '.git*' 20:22:19 INFO - Successfully installed jsonschema functools32 20:22:19 INFO - Cleaning up... 20:22:19 INFO - Return code: 0 20:22:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:22: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')]} 20:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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')]} 20:22:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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 0x7ffa25cbcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1953850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x194e370>, '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 0x19518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1951d80>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', '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 20:22: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 20:22: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 20:22:19 INFO - Using env: {'DISPLAY': ':0', 20:22:19 INFO - 'HOME': '/home/cltbld', 20:22:19 INFO - 'LANG': 'en_US.UTF-8', 20:22:19 INFO - 'LANGUAGE': 'en_US:en', 20:22:19 INFO - 'LOGNAME': 'cltbld', 20:22:19 INFO - 'MAIL': '/var/mail/cltbld', 20:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:19 INFO - 'MOZ_NO_REMOTE': '1', 20:22:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:19 INFO - 'NO_EM_RESTART': '1', 20:22:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:19 INFO - 'PWD': '/builds/slave/test', 20:22:19 INFO - 'SHELL': '/bin/bash', 20:22:19 INFO - 'SHLVL': '1', 20:22:19 INFO - 'TERM': 'linux', 20:22:19 INFO - 'TMOUT': '86400', 20:22:19 INFO - 'USER': 'cltbld', 20:22:19 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', 20:22:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:19 INFO - '_': '/tools/buildbot/bin/python'} 20:22:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:22:19 INFO - Cleaning up... 20:22:19 INFO - Return code: 0 20:22:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:22: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')]} 20:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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')]} 20:22:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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 0x7ffa25cbcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1953850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x194e370>, '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 0x19518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1951d80>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', '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 20:22: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 20:22: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 20:22:19 INFO - Using env: {'DISPLAY': ':0', 20:22:19 INFO - 'HOME': '/home/cltbld', 20:22:19 INFO - 'LANG': 'en_US.UTF-8', 20:22:19 INFO - 'LANGUAGE': 'en_US:en', 20:22:19 INFO - 'LOGNAME': 'cltbld', 20:22:19 INFO - 'MAIL': '/var/mail/cltbld', 20:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:19 INFO - 'MOZ_NO_REMOTE': '1', 20:22:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:19 INFO - 'NO_EM_RESTART': '1', 20:22:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:19 INFO - 'PWD': '/builds/slave/test', 20:22:19 INFO - 'SHELL': '/bin/bash', 20:22:19 INFO - 'SHLVL': '1', 20:22:19 INFO - 'TERM': 'linux', 20:22:19 INFO - 'TMOUT': '86400', 20:22:19 INFO - 'USER': 'cltbld', 20:22:19 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', 20:22:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:19 INFO - '_': '/tools/buildbot/bin/python'} 20:22:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:20 INFO - Downloading/unpacking blobuploader==1.2.4 20:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:22 INFO - Downloading blobuploader-1.2.4.tar.gz 20:22:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:22:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:22:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22:22 INFO - Downloading docopt-0.6.1.tar.gz 20:22:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:22:22 INFO - Installing collected packages: blobuploader, requests, docopt 20:22:22 INFO - Running setup.py install for blobuploader 20:22:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:22:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:22:22 INFO - Running setup.py install for requests 20:22:23 INFO - Running setup.py install for docopt 20:22:23 INFO - Successfully installed blobuploader requests docopt 20:22:23 INFO - Cleaning up... 20:22:23 INFO - Return code: 0 20:22:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22: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')]} 20:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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')]} 20:22:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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 0x7ffa25cbcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1953850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x194e370>, '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 0x19518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1951d80>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', '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 20:22: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 20:22: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 20:22:23 INFO - Using env: {'DISPLAY': ':0', 20:22:23 INFO - 'HOME': '/home/cltbld', 20:22:23 INFO - 'LANG': 'en_US.UTF-8', 20:22:23 INFO - 'LANGUAGE': 'en_US:en', 20:22:23 INFO - 'LOGNAME': 'cltbld', 20:22:23 INFO - 'MAIL': '/var/mail/cltbld', 20:22:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:23 INFO - 'MOZ_NO_REMOTE': '1', 20:22:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:23 INFO - 'NO_EM_RESTART': '1', 20:22:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:23 INFO - 'PWD': '/builds/slave/test', 20:22:23 INFO - 'SHELL': '/bin/bash', 20:22:23 INFO - 'SHLVL': '1', 20:22:23 INFO - 'TERM': 'linux', 20:22:23 INFO - 'TMOUT': '86400', 20:22:23 INFO - 'USER': 'cltbld', 20:22:23 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', 20:22:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:23 INFO - '_': '/tools/buildbot/bin/python'} 20:22:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:22:23 INFO - Running setup.py (path:/tmp/pip-ATFgIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:22:23 INFO - Running setup.py (path:/tmp/pip-pwa93Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:22:23 INFO - Running setup.py (path:/tmp/pip-ou0rg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:22:23 INFO - Running setup.py (path:/tmp/pip-8wCQ5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:22:23 INFO - Running setup.py (path:/tmp/pip-0OdD86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:22:24 INFO - Running setup.py (path:/tmp/pip-ZY3GSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:22:24 INFO - Running setup.py (path:/tmp/pip-a8JLd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:22:24 INFO - Running setup.py (path:/tmp/pip-EZhg72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:22:24 INFO - Running setup.py (path:/tmp/pip-UmQxE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:22:24 INFO - Running setup.py (path:/tmp/pip-m7Sj33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:22:24 INFO - Running setup.py (path:/tmp/pip-_SzqKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:22:24 INFO - Running setup.py (path:/tmp/pip-BWG6tI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:22:24 INFO - Running setup.py (path:/tmp/pip-pQnyGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:22:24 INFO - Running setup.py (path:/tmp/pip-belbVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:24 INFO - Running setup.py (path:/tmp/pip-ibvZ03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:22:24 INFO - Running setup.py (path:/tmp/pip-_l8vfM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:22:24 INFO - Running setup.py (path:/tmp/pip-VtxHhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:22:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:22:24 INFO - Running setup.py install for manifestparser 20:22:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:22:25 INFO - Running setup.py install for mozcrash 20:22:25 INFO - Running setup.py install for mozdebug 20:22:25 INFO - Running setup.py install for mozdevice 20:22:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:22:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:22:25 INFO - Running setup.py install for mozfile 20:22:25 INFO - Running setup.py install for mozhttpd 20:22:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:22:25 INFO - Running setup.py install for mozinfo 20:22:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:22:25 INFO - Running setup.py install for mozInstall 20:22:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:22:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:22:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:22:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:22:25 INFO - Running setup.py install for mozleak 20:22:25 INFO - Running setup.py install for mozlog 20:22:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:22:25 INFO - Running setup.py install for moznetwork 20:22:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:22:26 INFO - Running setup.py install for mozprocess 20:22:26 INFO - Running setup.py install for mozprofile 20:22:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:22:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:22:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:22:26 INFO - Running setup.py install for mozrunner 20:22:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:22:26 INFO - Running setup.py install for mozscreenshot 20:22:26 INFO - Running setup.py install for moztest 20:22:26 INFO - Running setup.py install for mozversion 20:22:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:22:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:22:26 INFO - Cleaning up... 20:22:26 INFO - Return code: 0 20:22:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22: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')]} 20:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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')]} 20:22:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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 0x7ffa25cbcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1953850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x194e370>, '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 0x19518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1951d80>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', '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 20:22: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 20:22: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 20:22:26 INFO - Using env: {'DISPLAY': ':0', 20:22:26 INFO - 'HOME': '/home/cltbld', 20:22:26 INFO - 'LANG': 'en_US.UTF-8', 20:22:26 INFO - 'LANGUAGE': 'en_US:en', 20:22:26 INFO - 'LOGNAME': 'cltbld', 20:22:26 INFO - 'MAIL': '/var/mail/cltbld', 20:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:26 INFO - 'MOZ_NO_REMOTE': '1', 20:22:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:26 INFO - 'NO_EM_RESTART': '1', 20:22:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:26 INFO - 'PWD': '/builds/slave/test', 20:22:26 INFO - 'SHELL': '/bin/bash', 20:22:26 INFO - 'SHLVL': '1', 20:22:26 INFO - 'TERM': 'linux', 20:22:26 INFO - 'TMOUT': '86400', 20:22:26 INFO - 'USER': 'cltbld', 20:22:26 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', 20:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:26 INFO - '_': '/tools/buildbot/bin/python'} 20:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:22:26 INFO - Running setup.py (path:/tmp/pip-Uv02yq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:22: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)) 20:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:22:26 INFO - Running setup.py (path:/tmp/pip-JfPenR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:22:27 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)) 20:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:22:27 INFO - Running setup.py (path:/tmp/pip-WY7Ixi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:22:27 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)) 20:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:22:27 INFO - Running setup.py (path:/tmp/pip-Gge5rW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:22:27 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)) 20:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:22:27 INFO - Running setup.py (path:/tmp/pip-BHptf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:22: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)) 20:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:22:27 INFO - Running setup.py (path:/tmp/pip-_cODrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:22: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)) 20:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:22:27 INFO - Running setup.py (path:/tmp/pip-BNUWtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:22: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)) 20:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:22:27 INFO - Running setup.py (path:/tmp/pip-sm6E9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:22: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)) 20:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:22:27 INFO - Running setup.py (path:/tmp/pip-G_cBuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:22: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)) 20:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:22:27 INFO - Running setup.py (path:/tmp/pip-f02wNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:22: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)) 20:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:22:27 INFO - Running setup.py (path:/tmp/pip-fdfQn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:22: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)) 20:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:22:27 INFO - Running setup.py (path:/tmp/pip-uioOja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:22: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)) 20:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:22:27 INFO - Running setup.py (path:/tmp/pip-o6C7IU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:22: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)) 20:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:22:27 INFO - Running setup.py (path:/tmp/pip-LUHeIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:22: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)) 20:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:27 INFO - Running setup.py (path:/tmp/pip-RXc6U6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:28 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)) 20:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:22:28 INFO - Running setup.py (path:/tmp/pip-RCry9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:22:28 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)) 20:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:22:28 INFO - Running setup.py (path:/tmp/pip-8GNVd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:22:28 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)) 20:22:28 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)) 20:22:28 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)) 20:22:28 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)) 20:22:28 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)) 20:22:28 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)) 20:22:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:22:28 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 20:22:28 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 20:22:28 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 20:22:28 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 20:22:28 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 20:22:28 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 20:22:30 INFO - Downloading blessings-1.6.tar.gz 20:22:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:22:30 INFO - Installing collected packages: blessings 20:22:30 INFO - Running setup.py install for blessings 20:22:30 INFO - Successfully installed blessings 20:22:30 INFO - Cleaning up... 20:22:30 INFO - Return code: 0 20:22:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:22:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:22:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:22:30 INFO - Reading from file tmpfile_stdout 20:22:30 INFO - Current package versions: 20:22:30 INFO - argparse == 1.2.1 20:22:30 INFO - blessings == 1.6 20:22:30 INFO - blobuploader == 1.2.4 20:22:30 INFO - docopt == 0.6.1 20:22:30 INFO - functools32 == 3.2.3-2 20:22:30 INFO - jsonschema == 2.5.1 20:22:30 INFO - manifestparser == 1.1 20:22:30 INFO - mozInstall == 1.12 20:22:30 INFO - mozcrash == 0.17 20:22:30 INFO - mozdebug == 0.1 20:22:30 INFO - mozdevice == 0.48 20:22:30 INFO - mozfile == 1.2 20:22:30 INFO - mozhttpd == 0.7 20:22:30 INFO - mozinfo == 0.9 20:22:30 INFO - mozleak == 0.1 20:22:30 INFO - mozlog == 3.3 20:22:30 INFO - moznetwork == 0.27 20:22:30 INFO - mozprocess == 0.23 20:22:30 INFO - mozprofile == 0.28 20:22:30 INFO - mozrunner == 6.12 20:22:30 INFO - mozscreenshot == 0.1 20:22:30 INFO - mozsystemmonitor == 0.3 20:22:30 INFO - moztest == 0.7 20:22:30 INFO - mozversion == 1.4 20:22:30 INFO - psutil == 3.1.1 20:22:30 INFO - requests == 1.2.3 20:22:30 INFO - wsgiref == 0.1.2 20:22:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22: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')]} 20:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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')]} 20:22:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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 0x7ffa25cbcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1953850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x194e370>, '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 0x19518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1951d80>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', '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 20:22: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 20:22: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 20:22:30 INFO - Using env: {'DISPLAY': ':0', 20:22:30 INFO - 'HOME': '/home/cltbld', 20:22:30 INFO - 'LANG': 'en_US.UTF-8', 20:22:30 INFO - 'LANGUAGE': 'en_US:en', 20:22:30 INFO - 'LOGNAME': 'cltbld', 20:22:30 INFO - 'MAIL': '/var/mail/cltbld', 20:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:30 INFO - 'MOZ_NO_REMOTE': '1', 20:22:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:30 INFO - 'NO_EM_RESTART': '1', 20:22:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:30 INFO - 'PWD': '/builds/slave/test', 20:22:30 INFO - 'SHELL': '/bin/bash', 20:22:30 INFO - 'SHLVL': '1', 20:22:30 INFO - 'TERM': 'linux', 20:22:30 INFO - 'TMOUT': '86400', 20:22:30 INFO - 'USER': 'cltbld', 20:22:30 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', 20:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:30 INFO - '_': '/tools/buildbot/bin/python'} 20:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22: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)) 20:22: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)) 20:22: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)) 20:22: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)) 20:22: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)) 20:22: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)) 20:22: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)) 20:22: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)) 20:22: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)) 20:22: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)) 20:22: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)) 20:22:30 INFO - Cleaning up... 20:22:30 INFO - Return code: 0 20:22:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:22: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')]} 20:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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')]} 20:22:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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 0x7ffa25cbcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1953850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x194e370>, '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 0x19518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1951d80>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', '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 20:22: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 20:22: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 20:22:30 INFO - Using env: {'DISPLAY': ':0', 20:22:30 INFO - 'HOME': '/home/cltbld', 20:22:30 INFO - 'LANG': 'en_US.UTF-8', 20:22:30 INFO - 'LANGUAGE': 'en_US:en', 20:22:30 INFO - 'LOGNAME': 'cltbld', 20:22:30 INFO - 'MAIL': '/var/mail/cltbld', 20:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:30 INFO - 'MOZ_NO_REMOTE': '1', 20:22:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:30 INFO - 'NO_EM_RESTART': '1', 20:22:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:30 INFO - 'PWD': '/builds/slave/test', 20:22:30 INFO - 'SHELL': '/bin/bash', 20:22:30 INFO - 'SHLVL': '1', 20:22:30 INFO - 'TERM': 'linux', 20:22:30 INFO - 'TMOUT': '86400', 20:22:30 INFO - 'USER': 'cltbld', 20:22:30 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', 20:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:30 INFO - '_': '/tools/buildbot/bin/python'} 20:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:22:30 INFO - Cleaning up... 20:22:30 INFO - Return code: 0 20:22:30 INFO - Running post-action listener: _resource_record_post_action 20:22:30 INFO - Running post-action listener: _start_resource_monitoring 20:22:30 INFO - Starting resource monitoring. 20:22:30 INFO - [mozharness: 2016-09-03 03:22:30.996424Z] Finished create-virtualenv step (success) 20:22:30 INFO - [mozharness: 2016-09-03 03:22:30.996878Z] Running install step. 20:22:30 INFO - Running pre-action listener: _resource_record_pre_action 20:22:30 INFO - Running main action method: install 20:22:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:22:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:22:31 INFO - Reading from file tmpfile_stdout 20:22:31 INFO - Detecting whether we're running mozinstall >=1.0... 20:22:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:22:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:22:31 INFO - Reading from file tmpfile_stdout 20:22:31 INFO - Output received: 20:22:31 INFO - Usage: mozinstall [options] installer 20:22:31 INFO - Options: 20:22:31 INFO - -h, --help show this help message and exit 20:22:31 INFO - -d DEST, --destination=DEST 20:22:31 INFO - Directory to install application into. [default: 20:22:31 INFO - "/builds/slave/test"] 20:22:31 INFO - --app=APP Application being installed. [default: firefox] 20:22:31 INFO - mkdir: /builds/slave/test/build/application 20:22: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'] 20:22: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 20:22:48 INFO - Reading from file tmpfile_stdout 20:22:48 INFO - Output received: 20:22:48 INFO - /builds/slave/test/build/application/firefox/firefox 20:22:48 INFO - Running post-action listener: _resource_record_post_action 20:22:48 INFO - [mozharness: 2016-09-03 03:22:48.288777Z] Finished install step (success) 20:22:48 INFO - [mozharness: 2016-09-03 03:22:48.289037Z] Running run-tests step. 20:22:48 INFO - Running pre-action listener: _resource_record_pre_action 20:22:48 INFO - Running main action method: run_tests 20:22:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:22:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:22:48 INFO - Python 2.7.3 20:22:48 INFO - Return code: 0 20:22:48 INFO - grabbing minidump binary from tooltool 20:22: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')]} 20:22: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 0x194e370>, '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 0x19518f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1951d80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:22: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 20:22: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 20:22: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 20:22:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:22:48 INFO - Return code: 0 20:22:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:22:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:22:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:22:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:22:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:22: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/bmYdf9QYQs-m-k8D5J2Kvg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 20:22: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/bmYdf9QYQs-m-k8D5J2Kvg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 20:22:48 INFO - Using env: {'DISPLAY': ':0', 20:22:48 INFO - 'HOME': '/home/cltbld', 20:22:48 INFO - 'LANG': 'en_US.UTF-8', 20:22:48 INFO - 'LANGUAGE': 'en_US:en', 20:22:48 INFO - 'LOGNAME': 'cltbld', 20:22:48 INFO - 'MAIL': '/var/mail/cltbld', 20:22:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:22:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:22:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:48 INFO - 'MOZ_NO_REMOTE': '1', 20:22:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:22:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:48 INFO - 'NO_EM_RESTART': '1', 20:22:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:48 INFO - 'PWD': '/builds/slave/test', 20:22:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:22:48 INFO - 'SHELL': '/bin/bash', 20:22:48 INFO - 'SHLVL': '1', 20:22:48 INFO - 'TERM': 'linux', 20:22:48 INFO - 'TMOUT': '86400', 20:22:48 INFO - 'USER': 'cltbld', 20:22:48 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050', 20:22:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:48 INFO - '_': '/tools/buildbot/bin/python'} 20:22: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/bmYdf9QYQs-m-k8D5J2Kvg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 20:22:48 INFO - mozversion application_buildid: 20160902173000 20:22:48 INFO - mozversion application_changeset: 18ab41c28e2e073d7a8d1854b565236b44ce9ba7 20:22:48 INFO - mozversion application_display_name: Nightly 20:22:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:22:48 INFO - mozversion application_name: Firefox 20:22:48 INFO - mozversion application_remotingname: firefox 20:22:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:22:48 INFO - mozversion application_vendor: Mozilla 20:22:48 INFO - mozversion application_version: 51.0a1 20:22:48 INFO - mozversion platform_buildid: 20160902173000 20:22:48 INFO - mozversion platform_changeset: 18ab41c28e2e073d7a8d1854b565236b44ce9ba7 20:22:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:22:48 INFO - mozversion platform_version: 51.0a1 20:22:48 INFO - using testdate: 1472872968 20:22:48 INFO - actual date: 1472872968 20:22:48 INFO - starting webserver on 'localhost:44010' 20:22:48 INFO - SUITE-START | Running 2 tests 20:22:48 INFO - TEST-START | dromaeo_css 20:22:48 INFO - operating with platform_type : linux_ 20:22:48 INFO - Initialising browser for dromaeo_css test... 20:22:48 INFO - TEST-INFO | started process 12197 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdqnEQN/profile http://localhost:44010/getInfo.html) 20:22:52 INFO - PROCESS | 12197 | __metrics Screen width/height:1600/1200 20:22:52 INFO - PROCESS | 12197 | colorDepth:24 20:22:52 INFO - PROCESS | 12197 | Browser inner width/height: 1024/697 20:22:52 INFO - PROCESS | 12197 | __metrics 20:22:53 INFO - PROCESS | 12197 | 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" 20:22:53 INFO - PROCESS | 12197 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:22:53 INFO - PROCESS | 12197 | console.error: PushService: 20:22:53 INFO - PROCESS | 12197 | stateChangeProcessEnqueue: Error transitioning state 20:22:53 INFO - PROCESS | 12197 | UnknownError 20:22:54 INFO - TEST-INFO | 12197: exit 0 20:22:54 INFO - Browser initialized. 20:22:54 INFO - Running cycle 1/1 for dromaeo_css test... 20:22:54 INFO - TEST-INFO | started process 12280 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdqnEQN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:23:06 INFO - PROCESS | 12280 | RSS: Main: 178913280 20:23:06 INFO - PROCESS | 12280 | 20:23:58 INFO - PROCESS | 12280 | 1472873038217 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] 20:26:07 INFO - PROCESS | 12280 | Cycle 1(1): loaded http://localhost:44010/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44010/tests/dromaeo/cssquery-ext.html) 20:26:09 INFO - PROCESS | 12280 | RSS: Main: 318124032 20:26:09 INFO - PROCESS | 12280 | 20:29:11 INFO - PROCESS | 12280 | Cycle 1(1): loaded http://localhost:44010/tests/dromaeo/cssquery-ext.html (next: http://localhost:44010/tests/dromaeo/cssquery-jquery.html) 20:29:12 INFO - PROCESS | 12280 | RSS: Main: 270180352 20:29:12 INFO - PROCESS | 12280 | 20:32:14 INFO - PROCESS | 12280 | Cycle 1(1): loaded http://localhost:44010/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44010/tests/dromaeo/cssquery-mootools.html) 20:32:14 INFO - PROCESS | 12280 | RSS: Main: 254902272 20:32:14 INFO - PROCESS | 12280 | 20:35:16 INFO - PROCESS | 12280 | Cycle 1(1): loaded http://localhost:44010/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44010/tests/dromaeo/cssquery-prototype.html) 20:35:17 INFO - PROCESS | 12280 | RSS: Main: 267997184 20:35:17 INFO - PROCESS | 12280 | 20:38:20 INFO - PROCESS | 12280 | Cycle 1(1): loaded http://localhost:44010/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44010/tests/dromaeo/cssquery-yui.html) 20:38:20 INFO - PROCESS | 12280 | RSS: Main: 214409216 20:38:20 INFO - PROCESS | 12280 | 20:41:23 INFO - PROCESS | 12280 | Cycle 1(1): loaded http://localhost:44010/tests/dromaeo/cssquery-yui.html (next: http://localhost:44010/tests/dromaeo/cssquery-dojo.html) 20:41:23 INFO - PROCESS | 12280 | RSS: Main: 298614784 20:41:23 INFO - PROCESS | 12280 | 20:41:23 INFO - PROCESS | 12280 | __start_tp_report 20:41:23 INFO - PROCESS | 12280 | _x_x_mozilla_page_load 20:41:23 INFO - PROCESS | 12280 | _x_x_mozilla_page_load_details 20:41:23 INFO - PROCESS | 12280 | |i|pagename|runs| 20:41:23 INFO - PROCESS | 12280 | |0;dojo.html;3063.509838425852;3064.555639432282;3090.984545077274;3124.4376012317803;3140.13646247282;5335.6265061445665;5337;5363.4904684055;5380.192971054344;5412.3505179378435;5344.118220493614;5389.434109418504;5412.675239485624;5415.702136382497;5502.147167189082;4837.226043832988;4840.5159484051665;4901.705897646153;4912.508749125077;5007.4491805901325;4939.950600494003;4987.950120498803;4994.750262486879;5007.84976450707;5008.724520151391;4781.617470602361;4807.134715751164;4824.614030877521;4874.46380898101;4948.703077815325;4852.393450818648;4862.41651001882;4863.148948933957;4903.65674402792;4974.577160941324;4738.147133515966;4762.380890484214;4782.402199725035;4836.588889944359;4934.605231581465;5318.490415335471;5346.144616861322;5354.384245811742;5360.088784906575;5399.244105825188;5598.888022239573;5702.515286200636;5727.341355744101;5743.10981797822;5749.9712501437225;5806.245188125559;5812.418758124196;5824.737886795105;5835.533157347402;5863.5016023638045;5436.2661040759385;5439.401665816757;5440.646357986758;5441.1838224266285;5441.7279136043835;5745.3105627324985;5905.320888097838;5947.345791962881;5950.047992321208;5957.255343082114;5733.426657334317;5734;5734.283214598176;5737.168110623936;5792.681402522818;5797.565182611237;5806.506446952057;5809.912851307234;5810.041343178413;5815.244018277597;5594.692291923983;5630.662160270315;5669.064604340238;5696.886062278814;5754.74103665336;5719.685417302005;5767.653940763567;5774.41203668986;5782.913256301159;5792.189093526909;4384.364267181305;4414.381986521889;4422.7346359218545;4432.955670443319;4475.5748203920575;3907.238088572758;3935.586763389803;3951.4863067801;3958.3270843956598;3975.0857302820514;5361.383440904268;5389.27244821944;5392.919106213331;5479;5493.533049690741;5742.052561327418;5761.798337058267;5762.337331206881;5770.538356931435;5820.126980952807;5416.133418652997;5418.8916221675345;5421.620486565903;5425.37608175057;5426.294581704353;4823.107725070803;4970.080535101018;4995.275685025651;5006;5011.1481048221885;5971.134185543072;5979.402059794073;5980.611260268069;5984.611000284967;6068.878622427526;5828.7959921404245;5852.092925596369;5860.970695146497;5863.882722345615;5881.676507791942;5665.3201615806365;5945.435183657545;5990.520758339409;5992.64044157352;6058.666773327509;5707.372189059283;5723.370429252695;5742.224799652161;5774.855628609318;5786.537077033911;5816.47651711348;5824.970875145597;5825.126231065205;5825.446582574648;5826.475617194473;5443.183522471661;5628.155776633538;5654.434556544313;5665.943340566541;5695.316562012585;5649.576281778801;5698.743556539883;5698.914516282342;5752.913706294493;5760.567957403127;5634.183043458757;5640.915386269127;5641.464060914206;5644.294463192101;5644.9435505644415;5580.051391263414;5656.038473459439;5677.914831277451;5706.229658995982;5767.826965191051;5571.303587051619;5571.721413929368;5591.27313449249;5645.943540564542;5667.093265077568 20:41:23 INFO - PROCESS | 12280 | |1;ext.html;546.2025097659628;598.7545106506333;611.2695329081746;613.9171211886394;633.8890694128532;40423.19153616926;41877.79061104698;42007.57992420075;45946;46080;42970.785146074304;43651.56348436523;43744.56255437453;43756;43773.7811310943;8969.910300896989;9102.13529714676;9120.589573469177;9122.908770912305;9123.771905702377;8876.6005529751;8972.147645973622;8974.910250897472;8976.46141231525;8977.192052715254;8949.910500894974;9021.95489022554;9023;9026.684066057689;9031;8965.237954773853;8997.190252877272;9003;9010;9014.18872301496;8971.596278167499;9035.774105647346;9040.050794666597;9042.728718138467;9044.3668943174;42798;43110.78444607757;43115.56884431178;43161;43178.35232471484;3408.522806807024;3911.9804400977814;3985;4024.4969378827645;4109.794510274474;33392.6660733392;33864.15339616505;33972.49041264383;34327.14182145441;35685.821570892236;4337.392765012901;4358.956410435887;4364.912701745979;4377.846775362879;4488.102379524108;349835;351988;353089.99999999744;353570;354388;61024.69487652533;61428.078578821354;61450.38549614536;61488.38511614916;61614.691926540974;35738;36473;36607.81696091503;38429.423558646085;38439.61560384416;17546.38587649426;17713.557161071076;17722.291108355523;17725.822741772674;17727;13544.796828047587;13646.727065458635;13655.522056727967;13655.726885462234;13657.112287701466;18880.905595471933;18931.716024259647;18950.336738214097;18984.525386865163;18990.05049747507;6797.844366457714;6980.359615490355;7020.5787652741;7091.82270443243;7238.601876896716;826.5660528222732;882.179572997106;938.5776400619749;975.9072888075622;977.8973207813219;31220.531692024637;31464;32316.35367292641;32418.837905810793;32686.346273074407;1010.4038617215878;1015.3907655406697;1033.2973577966904;1055.3773273768513;1113.2708076210095;15390.153541555099;16160.434384796476;16168.838311616968;16289.624319355882;16363.83636163647;572370;575443;576120;577075;577220;56646.716766415906;57125;57256.71371643115;57266.42733572694;57321.426785732445;1221.3465795799204;1240.3116270469975;1309;1345.172718777964;1365.3855764905634;30168.84915575408;32642.836785815918;33912.32175356529;34049;34141;49868.50131498639;50254.74872625613;50402.49597503978;50413.24380134227;50465.49534504755;421232;421598;422142;422989;425547;24730.629040564767;27079.59380609253;27758.16725498238;27852.860735697002;29180;45506.77246613746;46463;46467.3029904545;46848.531514684415;46865;39601.80199098986;39677.80161099176;39871.80064099661;40247;41497.37753933633;39380.606193937696;40000;41053.25254464437;43911.34132987944;44505.516429583586;40483;41442;41504.37743433911;41736.79131604323;41800;34008;36806.63193368032;37169.4424583626;37557.81221093877;37647.435288470144;27199.320016999733;27714.584281236203;28131.71868281291;28185;28374 20:41:23 INFO - PROCESS | 12280 | |2;jquery.html;381.66413556070654;384.45215567815853;395.499692888496;401.4399912122589;412.2723393210983;14482.348294326754;16598.66802663946;17897.10514474276;19321.806781932195;19375.41873743788;15495.225238738074;16135.273912673929;18534.721979170325;19087.618247635073;19609.803901960975;5500.477454641803;5687.573431992597;5699.601027928046;5706.77172913083;5709.115087161496;5484.095124304505;5610.8597285068;5669.376368599451;5674.687892165929;5682.403347648499;5611.971940140294;5656.4060773618585;5662.207290979266;5668.376478587352;5671.6880571568545;5484.862878428071;5627.184058311522;5668.724536979167;5670.943290567104;5672.319321681383;5028.547430731252;5033.697978121324;5035.496450354935;5035.622328325354;5038.773255203525;21701.565968680698;22432.66351004736;22568;23395.649065264035;23641.408964775845;2197.340797760681;2277.840551161419;2303.0672577606097;2546.745325467457;2784.7772178225896;9351.485668288242;10047.497625118714;10609.84085238722;10632.840507392395;15199.468018619405;1859.2191279662575;1875.3904980881268;1891.3758459708263;1892.224188082893;1953.2479995201807;79509.60245198794;79765.60117199435;80695.59652201702;81728.59135704284;81746;10350.792984140426;10465.738356541147;10699;10734.892651073389;10834.620788272372;14377.353019114165;14414.684622536875;14447.711045779026;14492.202928838804;14527.128372297697;7860.606969651609;8005.599720013976;8006.519608823489;8040.71857484997;8207.220314070153;7808.921910780933;7823.608819558999;8028.799280018046;8037.718679846177;8050.919490805134;4124.566920473367;4318.244307246219;4444.577765112309;4545.613622842029;4612.400387949612;1921.7501724775689;2008.2167954497756;2032.979670203279;2063.5769667218183;2067.410787925434;887.38326862831;920.8250432417819;925.8518637018179;1006.758377989292;1029.6807989523122;18722.906385468257;18957.241710331433;19716.126967938024;19774.802251977297;20061;937.6015193542853;954.1412728544366;957.3490026781716;986.2750878104588;1019.607451131305;10592.15262778976;10594.4702764863;11322.886771132347;11570.652880413601;11940.701482462835;471635;472073;477221;480311;484905;64295;95238.52380738052;96344.51827740816;97831;100326.49836750915;1035.290825784325;1068.1027936533505;1118.4463690473267;1131.4682099413262;1135.0465608888574;17796.644067118843;17977.10114494244;18521.444356669323;18525.90737046306;18642.627147457246;40266.396004060545;44394.77802610966;44428.111437770414;44763.77618111888;45030.54969450264;49608;56667.433325667866;58466.70766646139;58949.51555328825;60464;5695.686737229326;7954.284114429729;8010.676903698095;8016;8251;7826.804329891798;11585.768284634429;12220.45007974661;12653.87346126527;13211.14127581704;11067.612633557786;11070.667879963614;11409.3724845131;11542.94228528852;11661.883381166419;10699.251052426256;11083.445827708743;11086.61196858106;11130.276532025393;11149.721256968316;11376.829347559627;11395.943020284845;11457;11687.941560292144;12050.75898482043;8225.588720564068;8226.177382261725;8358.665653373797;8421.4947103174;8461.534615596202;9542.666006689733;11424.657260282205;11426.886026085202;11565.785592512664;11586.13104017219 20:41:23 INFO - PROCESS | 12280 | |3;mootools.html;1359.5853264754246;1471.343198775874;1492.9999999999998;1641.3270559070777;1789.4899953513243;8443.746687599374;8715.520646364448;8795.032546419889;9077.727668169966;9087.091290870909;8896.911030889689;9010.954945225281;9011.234045106157;9021.142991415822;9025.052369501207;2136.839737019722;2356.69362982812;2453.6687547181127;2482.950340993179;2486.042873493709;2461.963070553943;2497.213377786001;2497.2383423055962;2499.1627804685363;2502.461970676304;2343.757768607365;2363.7872591466767;2364.160722943356;2366.7041619797524;2368.1711401009593;2300.18343488061;2310.0297874892503;2329.77867102625;2416.4925365673053;2421.6125419932728;2405.494846082325;2434.0263894442364;2454.0551887523434;2454.435479839647;2454.742252063543;8998.59506322217;9136.908630913673;9143.268538516902;9147.542622868896;9159.542022898895;5297.735113244361;5502.889942201174;5505.449455054502;5507.476789704971;5513.062779327524;3291.7202037826814;3369.595648522193;3419.982900085484;3435.0725304167736;3469.3061387722555;1092.9617463388822;1134.353418551426;1142.6229344316355;1157.5196293538172;1161.895429411357;11810.283634763966;11850.051995840398;11853.881461185363;11855.58505452305;11865.050795936304;4312.633426158749;4399.450068741407;4515.096980603892;4533.048059907391;4688.859334219979;9442.339036267533;9458.52707364629;9471.526423678788;9475.573599188054;9482.620695172116;5373.086575282211;5375.462453754593;5377.327834020747;5383.300170977826;5386.353637563439;4482.887927801766;5011.824586139468;5050.646454748133;5113.846584602468;5148.433672296044;3780.6219378061974;3882.2041481496226;3883.514560679915;3937.5078115235597;4012.25773231953;1130.5364800431782;1139.2651739627893;1166.2302880099094;1172.876847930972;1202.458893497912;787.4251796188814;796.9601519923981;843.8607629741147;858.2533196119416;871.2550769092461;5910.438508341701;6007.729652165663;6066.48434883031;6258.530610204162;6321.146645202931;479.80567870012703;481.04992639536636;489.05856226763336;492.14121358229403;493.7333839726579;4184.769837658897;4239.1521695660995;4250.766207858535;4298.935515967263;4307.956920430755;10941.507632156572;11212.831807522894;11250.156238282161;11300.039496642714;11340.262882912584;10544.630937917289;10940.617078402218;10956.835647465294;11283.323000619988;11290.661280161608;8202.507849529047;8444.61999210037;8447.619857106445;8449.155084491502;8459.19637634436;6136.386361363828;6189.566730328834;6220.502359811295;6287.874242515032;6460.095586617783;10290.485475726333;10436.58253669875;10440.112590429595;10489.947550262199;10550;10374.688759337232;10613;10646.361218326925;10703.946480267548;10849.945750271198;7373.668184931798;7867.17394673551;7918.960405197937;8087.0295564532635;8123.268905798506;8903.109689031045;8945.19493245611;8959.910400895908;8969.327300452362;8989.370744047854;7480.625968701652;7649.502782319354;7679.308862202205;7937.484063535851;7986.880196796937;7425.146108197627;7467.663955121924;7497.325240728361;7502.849943001001;7577.128630207597;7170.677319520739;7273.599952002691;7275.345218930322;7288.817779555554;7315.231900650568;4930.383702037246;5057.291979122852;5100.5409513143995;5141.33162688863;5365.865853353697;5147.639665223548;5223.947760522498;5264.4735526448585;5274.156135018378;5362.65142765719 20:41:23 INFO - PROCESS | 12280 | |4;prototype.html;313.7129526483268;316.9492881139017;330.2552743563264;331.8689117798468;333.4481433228008;5114.156164232897;5246.422893481724;5303.310569625948;5318.707471089094;5338.439463856297;5175.948240517594;5181.35233095863;5230.529252367286;5236.8690782730455;5269;4116.567760385161;4123.463949686537;4126.2160189563965;4155.542890282081;4173.853915112972;4035.535913369971;4134.648554872824;4205.390218418327;4210.852620158295;4218.472690913636;4059.5331536873196;4059.7564146151326;4071.409645601386;4080.6327430531246;4082.3060079786505;4060.817263223162;4076.7961601919933;4077.408775727503;4079.7144199906165;4117.505899292074;3621.5654121505327;3645.963540364589;3654.4152935530456;3667.4132138857653;3685.594584595692;4920.8031678732805;5158.509941555584;5171.922421163686;5180.352455943007;5260.053190425722;4668.089722504113;4691.906161876777;4692.671512994092;4695.5304469552775;4697.295405689174;5383.919241211385;5438.809641662523;5471.644343117685;5489.3412790465;5492.340919089695;3819.3698039823394;3885.7862817545024;3887.358585833334;3892.2604705106223;3895.3377925752407;5327.653702509363;5523.28197334348;5655.632383895075;5663.631863928873;5665.575081868877;3982.820773065219;3993.740406873544;3997.560268370477;4004.5594984551676;4025.396190571379;3812.752171108869;3917.216556688674;4005.5193376794386;4026.8993275168355;4029.6171863672903;4568.6116680081905;4571.2000399929875;4594.264917613166;4594.931076033862;4615.861524154281;5472.753726082336;5474.698891560923;5491.187907990354;5557.666540007613;5610;1345.4460098586148;1365.5971488410942;1380.7366259872103;1418.0215651200638;1430.7210094031564;898.5911410308294;916.3402350307767;922.6908985489936;923.8152369526122;928.2017464980112;4733.360996265529;4737.265723812815;4748.406449193851;4756.6670333077045;4757.8096876125255;4959.834438906854;4970.868352937626;4984.27727979441;4990.650654454226;5006.945763084515;4584.781411309372;4628.560286772751;4679.245283018868;4681.812727490862;4727.527247275245;2611.903000739694;2627.0691042084054;2648.8675566221614;2649.9072532461273;2655.831434168998;5041.722705251246;5054.570361519312;5079.466656001141;5102.69383836971;5122.436531981479;4963.8262660806695;4979.029089327619;4997.450280469146;5093.8726531837;5411.918821217685;5857.4435428634215;5868.853278668067;5874.794382196688;5875.4124587541755;5897.203877476486;5682.801101961411;5706.061378390215;5720.399358067343;5734.627249228787;5742.224799651994;5213.687178769134;5377;5523.171524271231;5580.135079062752;5620.269364982526;5826.388229236039;5844.597296648859;5852.736626851887;5853.531717462678;5888.352281248974;4098.733582317259;4149.688773341951;4155.646770024461;4156.397322388298;4157.8336866525;5429.511343979061;5662.66024038559;5680.886382272414;5690.118031705092;5691.658500489983;5522.686388167068;5539.25220095282;5544.390117087037;5564.360098588714;5599.104143337209;5322.33470816148;5451.700156491431;5572.275604171432;5572.3034620672415;5576.996140694714;5499.615026948235;5505.724713764376;5531.419200983839;5532.28080349552;5532.336119665666;5561.3882472927135;5587.6088673794075;5596.712756066209;5616.466435688603;5628.634138780966;5218.269442278008;5467.758219130137;5481.5340696041285;5490.121580547093;5500.80747173847 20:41:23 INFO - PROCESS | 12280 | |5;yui.html;767.3285874859498;768.3891306411405;773.9496932699375;836.5482639374734;848.1857416879795;13311.733765324687;14671.633209169777;15139.091654500724;15145.77281340778;15156;14651.487197948074;14713.779293310608;14781.704365912676;14782.408703651841;14789.92605036976;652.1587152573175;784.2040329066002;803.1606970715616;805.3637626275236;805.4241217529466;661.8014595621319;668.9899651505232;669.5480550628331;772.7063715787992;796.2555011064674;837.5728378526965;842.0737189092011;846.031294168177;846.238385453091;846.3356265331715;827.5572568675759;832.4755404095441;833.0295206084904;835.7826964989085;839.6599377252193;495.66294919454754;596.3350863786847;608.7686679061973;615.5321955313993;615.6829232945037;13455.192688438725;13515.662108447368;13526.526571570046;13558.98307626932;13561.389737461837;12850.67873303175;13527.256000919946;14428.20644864532;14455.927720361331;14522.570615962128;13448.260345680983;13495.122817016863;13580.932095339558;13617.523386681418;14027.719445611132;4521.359680658498;4669.089527542131;4670.369500117475;4682.531746825324;4685.625149987993;87068.56465717695;87409.56295218483;87802.56098719465;88047.67928481141;88770;11387.351148990396;11456.656300311004;11770.823437648442;12008.939955300168;12401.069919756055;21474.463138421354;21658.78341216599;21659.566808663738;21717.67423488649;22148.2248121315;7825.569593672402;8069.757907262791;8081.710440116794;8112.918870811334;8162.10216876155;4484.2376795944765;4591.9540804591525;4597.08058388317;4683.625309975194;4806.519348065165;3150.1966998415255;3241.38413701396;3253.967460325414;3262.2170679037336;3266.738660907122;595.9165716799652;600.7356763024255;600.9549283803644;604.2658170322991;608.0514397539852;625.7027911741977;647.5693663713672;702.6346299924011;711.3206887422499;743.5018537579834;9517.90482095184;9850.60597576089;10014.50657764012;10085.747856303504;10198.077859606246;689.648279377521;718.3821913154683;732.578767208857;738.1216352540567;740.9407247420194;4544.18204723153;4616.261398176276;4652.139354219413;4657.394538709946;4672.672912896064;129278.59720891507;129367.35316323546;130785.34607326903;131655.3417232927;131856.3407182958;109293;109906.45046774716;112068.4396578012;112148;115658.4217078926;96823;96990.51504742431;97674.51162744283;97912;102519.97480025253;8941.776455588662;8970.461772293684;9215.124563166488;9309.906900931175;9325.715199776098;46031.309530356404;57130.714346428;57140.42859571351;57221.71389143028;58265;62903;72285.27714722996;73494.63252683703;74160.62919685582;78080.21919780957;13644.181349119084;13764.862351376358;13803.9697561265;13916.721665566834;14057.94565407578;14623.195724234844;14688.926555367154;14886.925565372538;15352.385904563693;15675.05949643025;14213.93607114947;14338.354774035402;15100.471483498024;15196.696066078837;15356.001859879072;13009.804852927024;13740;14281.64295892611;14436.422543098159;14532.346044428237;13893.58319250424;14153.716925661634;14250.572482825532;14350.282485875874;14496.275186240857;12040.397980100784;12086.576969806014;12383.814242786546;12592.496300147892;12649;11651.30092194471;11653.417329133339;11833.171677982802;12097.75804483923;12330.136890417585 20:41:23 INFO - PROCESS | 12280 | __end_tp_report 20:41:23 INFO - PROCESS | 12280 | __start_cc_report 20:41:23 INFO - PROCESS | 12280 | _x_x_mozilla_cycle_collect,1120 20:41:23 INFO - PROCESS | 12280 | __end_cc_report 20:41:23 INFO - PROCESS | 12280 | __startTimestamp1472874083739__endTimestamp 20:41:23 INFO - PROCESS | 12280 | 20:41:23 INFO - PROCESS | 12280 | ------- Summary: start ------- 20:41:23 INFO - PROCESS | 12280 | Number of tests: 6 20:41:23 INFO - PROCESS | 12280 | 20:41:23 INFO - PROCESS | 12280 | [#0] dojo.html Cycles:165 Average:5366.51 Median:5628.16 stddev:619.11 (11.0%) stddev-sans-first:594.06 20:41:23 INFO - PROCESS | 12280 | Values: 3063.5 3064.6 3091.0 3124.4 3140.1 5335.6 5337.0 5363.5 5380.2 5412.4 5344.1 5389.4 5412.7 5415.7 5502.1 4837.2 4840.5 4901.7 4912.5 5007.4 4940.0 4988.0 4994.8 5007.8 5008.7 4781.6 4807.1 4824.6 4874.5 4948.7 4852.4 4862.4 4863.1 4903.7 4974.6 4738.1 4762.4 4782.4 4836.6 4934.6 5318.5 5346.1 5354.4 5360.1 5399.2 5598.9 5702.5 5727.3 5743.1 5750.0 5806.2 5812.4 5824.7 5835.5 5863.5 5436.3 5439.4 5440.6 5441.2 5441.7 5745.3 5905.3 5947.3 5950.0 5957.3 5733.4 5734.0 5734.3 5737.2 5792.7 5797.6 5806.5 5809.9 5810.0 5815.2 5594.7 5630.7 5669.1 5696.9 5754.7 5719.7 5767.7 5774.4 5782.9 5792.2 4384.4 4414.4 4422.7 4433.0 4475.6 3907.2 3935.6 3951.5 3958.3 3975.1 5361.4 5389.3 5392.9 5479.0 5493.5 5742.1 5761.8 5762.3 5770.5 5820.1 5416.1 5418.9 5421.6 5425.4 5426.3 4823.1 4970.1 4995.3 5006.0 5011.1 5971.1 5979.4 5980.6 5984.6 6068.9 5828.8 5852.1 5861.0 5863.9 5881.7 5665.3 5945.4 5990.5 5992.6 6058.7 5707.4 5723.4 5742.2 5774.9 5786.5 5816.5 5825.0 5825.1 5825.4 5826.5 5443.2 5628.2 5654.4 5665.9 5695.3 5649.6 5698.7 5698.9 5752.9 5760.6 5634.2 5640.9 5641.5 5644.3 5644.9 5580.1 5656.0 5677.9 5706.2 5767.8 5571.3 5571.7 5591.3 5645.9 5667.1 20:41:23 INFO - PROCESS | 12280 | 20:41:23 INFO - PROCESS | 12280 | [#1] ext.html Cycles:180 Average:60507.48 Median:231619.79 stddev:122147.79 (52.7%) stddev-sans-first:122407.49 20:41:23 INFO - PROCESS | 12280 | Values: 546.2 598.8 611.3 613.9 633.9 40423.2 41877.8 42007.6 45946.0 46080.0 42970.8 43651.6 43744.6 43756.0 43773.8 8969.9 9102.1 9120.6 9122.9 9123.8 8876.6 8972.1 8974.9 8976.5 8977.2 8949.9 9022.0 9023.0 9026.7 9031.0 8965.2 8997.2 9003.0 9010.0 9014.2 8971.6 9035.8 9040.1 9042.7 9044.4 42798.0 43110.8 43115.6 43161.0 43178.4 3408.5 3912.0 3985.0 4024.5 4109.8 33392.7 33864.2 33972.5 34327.1 35685.8 4337.4 4359.0 4364.9 4377.8 4488.1 349835.0 351988.0 353090.0 353570.0 354388.0 61024.7 61428.1 61450.4 61488.4 61614.7 35738.0 36473.0 36607.8 38429.4 38439.6 17546.4 17713.6 17722.3 17725.8 17727.0 13544.8 13646.7 13655.5 13655.7 13657.1 18880.9 18931.7 18950.3 18984.5 18990.1 6797.8 6980.4 7020.6 7091.8 7238.6 826.6 882.2 938.6 975.9 977.9 31220.5 31464.0 32316.4 32418.8 32686.3 1010.4 1015.4 1033.3 1055.4 1113.3 15390.2 16160.4 16168.8 16289.6 16363.8 572370.0 575443.0 576120.0 577075.0 577220.0 56646.7 57125.0 57256.7 57266.4 57321.4 1221.3 1240.3 1309.0 1345.2 1365.4 30168.8 32642.8 33912.3 34049.0 34141.0 49868.5 50254.7 50402.5 50413.2 50465.5 421232.0 421598.0 422142.0 422989.0 425547.0 24730.6 27079.6 27758.2 27852.9 29180.0 45506.8 46463.0 46467.3 46848.5 46865.0 39601.8 39677.8 39871.8 40247.0 41497.4 39380.6 40000.0 41053.3 43911.3 44505.5 40483.0 41442.0 41504.4 41736.8 41800.0 34008.0 36806.6 37169.4 37557.8 37647.4 27199.3 27714.6 28131.7 28185.0 28374.0 20:41:23 INFO - PROCESS | 12280 | 20:41:23 INFO - PROCESS | 12280 | [#2] jquery.html Cycles:180 Average:28411.40 Median:1583.22 stddev:78807.42 (4977.7%) stddev-sans-first:79000.39 20:41:23 INFO - PROCESS | 12280 | Values: 381.7 384.5 395.5 401.4 412.3 14482.3 16598.7 17897.1 19321.8 19375.4 15495.2 16135.3 18534.7 19087.6 19609.8 5500.5 5687.6 5699.6 5706.8 5709.1 5484.1 5610.9 5669.4 5674.7 5682.4 5612.0 5656.4 5662.2 5668.4 5671.7 5484.9 5627.2 5668.7 5670.9 5672.3 5028.5 5033.7 5035.5 5035.6 5038.8 21701.6 22432.7 22568.0 23395.6 23641.4 2197.3 2277.8 2303.1 2546.7 2784.8 9351.5 10047.5 10609.8 10632.8 15199.5 1859.2 1875.4 1891.4 1892.2 1953.2 79509.6 79765.6 80695.6 81728.6 81746.0 10350.8 10465.7 10699.0 10734.9 10834.6 14377.4 14414.7 14447.7 14492.2 14527.1 7860.6 8005.6 8006.5 8040.7 8207.2 7808.9 7823.6 8028.8 8037.7 8050.9 4124.6 4318.2 4444.6 4545.6 4612.4 1921.8 2008.2 2033.0 2063.6 2067.4 887.4 920.8 925.9 1006.8 1029.7 18722.9 18957.2 19716.1 19774.8 20061.0 937.6 954.1 957.3 986.3 1019.6 10592.2 10594.5 11322.9 11570.7 11940.7 471635.0 472073.0 477221.0 480311.0 484905.0 64295.0 95238.5 96344.5 97831.0 100326.5 1035.3 1068.1 1118.4 1131.5 1135.0 17796.6 17977.1 18521.4 18525.9 18642.6 40266.4 44394.8 44428.1 44763.8 45030.5 49608.0 56667.4 58466.7 58949.5 60464.0 5695.7 7954.3 8010.7 8016.0 8251.0 7826.8 11585.8 12220.5 12653.9 13211.1 11067.6 11070.7 11409.4 11542.9 11661.9 10699.3 11083.4 11086.6 11130.3 11149.7 11376.8 11395.9 11457.0 11687.9 12050.8 8225.6 8226.2 8358.7 8421.5 8461.5 9542.7 11424.7 11426.9 11565.8 11586.1 20:41:23 INFO - PROCESS | 12280 | 20:41:23 INFO - PROCESS | 12280 | [#3] mootools.html Cycles:180 Average:5863.00 Median:2712.06 stddev:3339.13 (123.1%) stddev-sans-first:3331.35 20:41:23 INFO - PROCESS | 12280 | Values: 1359.6 1471.3 1493.0 1641.3 1789.5 8443.7 8715.5 8795.0 9077.7 9087.1 8896.9 9011.0 9011.2 9021.1 9025.1 2136.8 2356.7 2453.7 2483.0 2486.0 2462.0 2497.2 2497.2 2499.2 2502.5 2343.8 2363.8 2364.2 2366.7 2368.2 2300.2 2310.0 2329.8 2416.5 2421.6 2405.5 2434.0 2454.1 2454.4 2454.7 8998.6 9136.9 9143.3 9147.5 9159.5 5297.7 5502.9 5505.4 5507.5 5513.1 3291.7 3369.6 3420.0 3435.1 3469.3 1093.0 1134.4 1142.6 1157.5 1161.9 11810.3 11850.1 11853.9 11855.6 11865.1 4312.6 4399.5 4515.1 4533.0 4688.9 9442.3 9458.5 9471.5 9475.6 9482.6 5373.1 5375.5 5377.3 5383.3 5386.4 4482.9 5011.8 5050.6 5113.8 5148.4 3780.6 3882.2 3883.5 3937.5 4012.3 1130.5 1139.3 1166.2 1172.9 1202.5 787.4 797.0 843.9 858.3 871.3 5910.4 6007.7 6066.5 6258.5 6321.1 479.8 481.0 489.1 492.1 493.7 4184.8 4239.2 4250.8 4298.9 4308.0 10941.5 11212.8 11250.2 11300.0 11340.3 10544.6 10940.6 10956.8 11283.3 11290.7 8202.5 8444.6 8447.6 8449.2 8459.2 6136.4 6189.6 6220.5 6287.9 6460.1 10290.5 10436.6 10440.1 10489.9 10550.0 10374.7 10613.0 10646.4 10703.9 10849.9 7373.7 7867.2 7919.0 8087.0 8123.3 8903.1 8945.2 8959.9 8969.3 8989.4 7480.6 7649.5 7679.3 7937.5 7986.9 7425.1 7467.7 7497.3 7502.8 7577.1 7170.7 7273.6 7275.3 7288.8 7315.2 4930.4 5057.3 5100.5 5141.3 5365.9 5147.6 5223.9 5264.5 5274.2 5362.7 20:41:23 INFO - PROCESS | 12280 | 20:41:23 INFO - PROCESS | 12280 | [#4] prototype.html Cycles:180 Average:4541.70 Median:5067.02 stddev:1338.64 (26.4%) stddev-sans-first:1304.23 20:41:23 INFO - PROCESS | 12280 | Values: 313.7 316.9 330.3 331.9 333.4 5114.2 5246.4 5303.3 5318.7 5338.4 5175.9 5181.4 5230.5 5236.9 5269.0 4116.6 4123.5 4126.2 4155.5 4173.9 4035.5 4134.6 4205.4 4210.9 4218.5 4059.5 4059.8 4071.4 4080.6 4082.3 4060.8 4076.8 4077.4 4079.7 4117.5 3621.6 3646.0 3654.4 3667.4 3685.6 4920.8 5158.5 5171.9 5180.4 5260.1 4668.1 4691.9 4692.7 4695.5 4697.3 5383.9 5438.8 5471.6 5489.3 5492.3 3819.4 3885.8 3887.4 3892.3 3895.3 5327.7 5523.3 5655.6 5663.6 5665.6 3982.8 3993.7 3997.6 4004.6 4025.4 3812.8 3917.2 4005.5 4026.9 4029.6 4568.6 4571.2 4594.3 4594.9 4615.9 5472.8 5474.7 5491.2 5557.7 5610.0 1345.4 1365.6 1380.7 1418.0 1430.7 898.6 916.3 922.7 923.8 928.2 4733.4 4737.3 4748.4 4756.7 4757.8 4959.8 4970.9 4984.3 4990.7 5006.9 4584.8 4628.6 4679.2 4681.8 4727.5 2611.9 2627.1 2648.9 2649.9 2655.8 5041.7 5054.6 5079.5 5102.7 5122.4 4963.8 4979.0 4997.5 5093.9 5411.9 5857.4 5868.9 5874.8 5875.4 5897.2 5682.8 5706.1 5720.4 5734.6 5742.2 5213.7 5377.0 5523.2 5580.1 5620.3 5826.4 5844.6 5852.7 5853.5 5888.4 4098.7 4149.7 4155.6 4156.4 4157.8 5429.5 5662.7 5680.9 5690.1 5691.7 5522.7 5539.3 5544.4 5564.4 5599.1 5322.3 5451.7 5572.3 5572.3 5577.0 5499.6 5505.7 5531.4 5532.3 5532.3 5561.4 5587.6 5596.7 5616.5 5628.6 5218.3 5467.8 5481.5 5490.1 5500.8 20:41:23 INFO - PROCESS | 12280 | 20:41:23 INFO - PROCESS | 12280 | [#5] yui.html Cycles:180 Average:22418.97 Median:4532.77 stddev:33606.52 (741.4%) stddev-sans-first:33661.48 20:41:23 INFO - PROCESS | 12280 | Values: 767.3 768.4 773.9 836.5 848.2 13311.7 14671.6 15139.1 15145.8 15156.0 14651.5 14713.8 14781.7 14782.4 14789.9 652.2 784.2 803.2 805.4 805.4 661.8 669.0 669.5 772.7 796.3 837.6 842.1 846.0 846.2 846.3 827.6 832.5 833.0 835.8 839.7 495.7 596.3 608.8 615.5 615.7 13455.2 13515.7 13526.5 13559.0 13561.4 12850.7 13527.3 14428.2 14455.9 14522.6 13448.3 13495.1 13580.9 13617.5 14027.7 4521.4 4669.1 4670.4 4682.5 4685.6 87068.6 87409.6 87802.6 88047.7 88770.0 11387.4 11456.7 11770.8 12008.9 12401.1 21474.5 21658.8 21659.6 21717.7 22148.2 7825.6 8069.8 8081.7 8112.9 8162.1 4484.2 4592.0 4597.1 4683.6 4806.5 3150.2 3241.4 3254.0 3262.2 3266.7 595.9 600.7 601.0 604.3 608.1 625.7 647.6 702.6 711.3 743.5 9517.9 9850.6 10014.5 10085.7 10198.1 689.6 718.4 732.6 738.1 740.9 4544.2 4616.3 4652.1 4657.4 4672.7 129278.6 129367.4 130785.3 131655.3 131856.3 109293.0 109906.5 112068.4 112148.0 115658.4 96823.0 96990.5 97674.5 97912.0 102520.0 8941.8 8970.5 9215.1 9309.9 9325.7 46031.3 57130.7 57140.4 57221.7 58265.0 62903.0 72285.3 73494.6 74160.6 78080.2 13644.2 13764.9 13804.0 13916.7 14057.9 14623.2 14688.9 14886.9 15352.4 15675.1 14213.9 14338.4 15100.5 15196.7 15356.0 13009.8 13740.0 14281.6 14436.4 14532.3 13893.6 14153.7 14250.6 14350.3 14496.3 12040.4 12086.6 12383.8 12592.5 12649.0 11651.3 11653.4 11833.2 12097.8 12330.1 20:41:23 INFO - PROCESS | 12280 | -------- Summary: end -------- 20:41:23 INFO - PROCESS | 12280 | 20:41:24 INFO - TEST-INFO | 12280: exit 0 20:41:24 INFO - TEST-OK | dromaeo_css | took 1116037ms 20:41:24 INFO - TEST-START | kraken 20:41:24 INFO - operating with platform_type : linux_ 20:41:24 INFO - Initialising browser for kraken test... 20:41:24 INFO - TEST-INFO | started process 12586 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN7WCLI/profile http://localhost:44010/getInfo.html) 20:41:28 INFO - PROCESS | 12586 | __metrics Screen width/height:1600/1200 20:41:28 INFO - PROCESS | 12586 | colorDepth:24 20:41:28 INFO - PROCESS | 12586 | Browser inner width/height: 1024/697 20:41:28 INFO - PROCESS | 12586 | __metrics 20:41:29 INFO - PROCESS | 12586 | 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" 20:41:29 INFO - PROCESS | 12586 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:41:29 INFO - PROCESS | 12586 | console.error: PushService: 20:41:29 INFO - PROCESS | 12586 | stateChangeProcessEnqueue: Error transitioning state 20:41:29 INFO - PROCESS | 12586 | UnknownError 20:41:30 INFO - TEST-INFO | 12586: exit 0 20:41:30 INFO - Browser initialized. 20:41:30 INFO - Running cycle 1/1 for kraken test... 20:41:30 INFO - TEST-INFO | started process 12667 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN7WCLI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:41:42 INFO - PROCESS | 12667 | RSS: Main: 178352128 20:41:42 INFO - PROCESS | 12667 | 20:41:45 INFO - PROCESS | 12667 | Cycle 1(1): loaded http://localhost:44010/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44010/tests/kraken/driver.html?testName=audio-beat-detection) 20:41:46 INFO - PROCESS | 12667 | RSS: Main: 233234432 20:41:46 INFO - PROCESS | 12667 | 20:41:48 INFO - PROCESS | 12667 | Cycle 1(1): loaded http://localhost:44010/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44010/tests/kraken/driver.html?testName=audio-dft) 20:41:49 INFO - PROCESS | 12667 | RSS: Main: 223547392 20:41:49 INFO - PROCESS | 12667 | 20:41:52 INFO - PROCESS | 12667 | Cycle 1(1): loaded http://localhost:44010/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44010/tests/kraken/driver.html?testName=audio-fft) 20:41:53 INFO - PROCESS | 12667 | RSS: Main: 301215744 20:41:53 INFO - PROCESS | 12667 | 20:41:55 INFO - PROCESS | 12667 | Cycle 1(1): loaded http://localhost:44010/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44010/tests/kraken/driver.html?testName=audio-oscillator) 20:41:55 INFO - PROCESS | 12667 | RSS: Main: 211996672 20:41:55 INFO - PROCESS | 12667 | 20:41:57 INFO - PROCESS | 12667 | Cycle 1(1): loaded http://localhost:44010/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44010/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:41:58 INFO - PROCESS | 12667 | RSS: Main: 213819392 20:41:58 INFO - PROCESS | 12667 | 20:42:01 INFO - PROCESS | 12667 | Cycle 1(1): loaded http://localhost:44010/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44010/tests/kraken/driver.html?testName=imaging-darkroom) 20:42:02 INFO - PROCESS | 12667 | RSS: Main: 251416576 20:42:02 INFO - PROCESS | 12667 | 20:42:05 INFO - PROCESS | 12667 | Cycle 1(1): loaded http://localhost:44010/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44010/tests/kraken/driver.html?testName=imaging-desaturate) 20:42:06 INFO - PROCESS | 12667 | RSS: Main: 250654720 20:42:06 INFO - PROCESS | 12667 | 20:42:08 INFO - PROCESS | 12667 | Cycle 1(1): loaded http://localhost:44010/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44010/tests/kraken/driver.html?testName=json-parse-financial) 20:42:09 INFO - PROCESS | 12667 | RSS: Main: 251342848 20:42:09 INFO - PROCESS | 12667 | 20:42:10 INFO - PROCESS | 12667 | Cycle 1(1): loaded http://localhost:44010/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44010/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:42:11 INFO - PROCESS | 12667 | RSS: Main: 213688320 20:42:11 INFO - PROCESS | 12667 | 20:42:13 INFO - PROCESS | 12667 | Cycle 1(1): loaded http://localhost:44010/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44010/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:42:13 INFO - PROCESS | 12667 | RSS: Main: 221786112 20:42:13 INFO - PROCESS | 12667 | 20:42:15 INFO - PROCESS | 12667 | Cycle 1(1): loaded http://localhost:44010/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44010/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:42:16 INFO - PROCESS | 12667 | RSS: Main: 222048256 20:42:16 INFO - PROCESS | 12667 | 20:42:19 INFO - PROCESS | 12667 | Cycle 1(1): loaded http://localhost:44010/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44010/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:42:19 INFO - PROCESS | 12667 | RSS: Main: 222314496 20:42:19 INFO - PROCESS | 12667 | 20:42:22 INFO - PROCESS | 12667 | Cycle 1(1): loaded http://localhost:44010/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44010/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:42:23 INFO - PROCESS | 12667 | RSS: Main: 220418048 20:42:23 INFO - PROCESS | 12667 | 20:42:24 INFO - PROCESS | 12667 | Cycle 1(1): loaded http://localhost:44010/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44010/tests/kraken/driver.html?testName=ai-astar) 20:42:25 INFO - PROCESS | 12667 | RSS: Main: 272408576 20:42:25 INFO - PROCESS | 12667 | 20:42:25 INFO - PROCESS | 12667 | __start_tp_report 20:42:25 INFO - PROCESS | 12667 | _x_x_mozilla_page_load 20:42:25 INFO - PROCESS | 12667 | _x_x_mozilla_page_load_details 20:42:25 INFO - PROCESS | 12667 | |i|pagename|runs| 20:42:25 INFO - PROCESS | 12667 | |0;ai-astar;113;112;118;113;112;112;111;111;112;128 20:42:25 INFO - PROCESS | 12667 | |1;audio-beat-detection;147;118;145;183;118;154;144;118;187;119 20:42:25 INFO - PROCESS | 12667 | |2;audio-dft;159;160;160;158;162;160;161;160;160;163 20:42:25 INFO - PROCESS | 12667 | |3;audio-fft;76;75;75;173;75;75;74;77;166;75 20:42:25 INFO - PROCESS | 12667 | |4;audio-oscillator;82;82;142;82;82;82;143;82;81;146 20:42:25 INFO - PROCESS | 12667 | |5;imaging-gaussian-blur;117;116;117;117;118;116;116;116;116;116 20:42:25 INFO - PROCESS | 12667 | |6;imaging-darkroom;146;147;146;147;147;146;146;146;146;147 20:42:25 INFO - PROCESS | 12667 | |7;imaging-desaturate;95;95;96;96;96;96;96;95;95;96 20:42:25 INFO - PROCESS | 12667 | |8;json-parse-financial;52;52;52;52;52;53;53;53;51;52 20:42:25 INFO - PROCESS | 12667 | |9;json-stringify-tinderbox;61;62;62;61;61;60;61;62;61;62 20:42:25 INFO - PROCESS | 12667 | |10;stanford-crypto-aes;79;78;81;84;78;85;81;79;81;77 20:42:25 INFO - PROCESS | 12667 | |11;stanford-crypto-ccm;124;125;121;125;124;124;127;121;158;144 20:42:25 INFO - PROCESS | 12667 | |12;stanford-crypto-pbkdf2;164;169;169;167;168;165;162;161;161;163 20:42:25 INFO - PROCESS | 12667 | |13;stanford-crypto-sha256-iterative;65;65;67;65;64;66;64;65;64;64 20:42:25 INFO - PROCESS | 12667 | __end_tp_report 20:42:25 INFO - PROCESS | 12667 | __start_cc_report 20:42:25 INFO - PROCESS | 12667 | _x_x_mozilla_cycle_collect,1667 20:42:25 INFO - PROCESS | 12667 | __end_cc_report 20:42:25 INFO - PROCESS | 12667 | __startTimestamp1472874145100__endTimestamp 20:42:25 INFO - PROCESS | 12667 | 20:42:25 INFO - PROCESS | 12667 | ------- Summary: start ------- 20:42:25 INFO - PROCESS | 12667 | Number of tests: 14 20:42:25 INFO - PROCESS | 12667 | 20:42:25 INFO - PROCESS | 12667 | [#0] ai-astar Cycles:10 Average:114.20 Median:112.50 stddev:5.25 (4.7%) stddev-sans-first:5.55 20:42:25 INFO - PROCESS | 12667 | Values: 113.0 112.0 118.0 113.0 112.0 112.0 111.0 111.0 112.0 128.0 20:42:25 INFO - PROCESS | 12667 | 20:42:25 INFO - PROCESS | 12667 | [#1] audio-beat-detection Cycles:10 Average:143.30 Median:146.00 stddev:26.09 (17.9%) stddev-sans-first:27.64 20:42:25 INFO - PROCESS | 12667 | Values: 147.0 118.0 145.0 183.0 118.0 154.0 144.0 118.0 187.0 119.0 20:42:25 INFO - PROCESS | 12667 | 20:42:25 INFO - PROCESS | 12667 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.00 stddev:1.42 (0.9%) stddev-sans-first:1.42 20:42:25 INFO - PROCESS | 12667 | Values: 159.0 160.0 160.0 158.0 162.0 160.0 161.0 160.0 160.0 163.0 20:42:25 INFO - PROCESS | 12667 | 20:42:25 INFO - PROCESS | 12667 | [#3] audio-fft Cycles:10 Average:94.10 Median:75.00 stddev:39.78 (53.0%) stddev-sans-first:41.65 20:42:25 INFO - PROCESS | 12667 | Values: 76.0 75.0 75.0 173.0 75.0 75.0 74.0 77.0 166.0 75.0 20:42:25 INFO - PROCESS | 12667 | 20:42:25 INFO - PROCESS | 12667 | [#4] audio-oscillator Cycles:10 Average:100.40 Median:82.00 stddev:29.87 (36.4%) stddev-sans-first:30.94 20:42:25 INFO - PROCESS | 12667 | Values: 82.0 82.0 142.0 82.0 82.0 82.0 143.0 82.0 81.0 146.0 20:42:25 INFO - PROCESS | 12667 | 20:42:25 INFO - PROCESS | 12667 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:116.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 20:42:25 INFO - PROCESS | 12667 | Values: 117.0 116.0 117.0 117.0 118.0 116.0 116.0 116.0 116.0 116.0 20:42:25 INFO - PROCESS | 12667 | 20:42:25 INFO - PROCESS | 12667 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 20:42:25 INFO - PROCESS | 12667 | Values: 146.0 147.0 146.0 147.0 147.0 146.0 146.0 146.0 146.0 147.0 20:42:25 INFO - PROCESS | 12667 | 20:42:25 INFO - PROCESS | 12667 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 20:42:25 INFO - PROCESS | 12667 | Values: 95.0 95.0 96.0 96.0 96.0 96.0 96.0 95.0 95.0 96.0 20:42:25 INFO - PROCESS | 12667 | 20:42:25 INFO - PROCESS | 12667 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:52.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 20:42:25 INFO - PROCESS | 12667 | Values: 52.0 52.0 52.0 52.0 52.0 53.0 53.0 53.0 51.0 52.0 20:42:25 INFO - PROCESS | 12667 | 20:42:25 INFO - PROCESS | 12667 | [#9] json-stringify-tinderbox Cycles:10 Average:61.30 Median:61.50 stddev:0.67 (1.1%) stddev-sans-first:0.71 20:42:25 INFO - PROCESS | 12667 | Values: 61.0 62.0 62.0 61.0 61.0 60.0 61.0 62.0 61.0 62.0 20:42:25 INFO - PROCESS | 12667 | 20:42:25 INFO - PROCESS | 12667 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:81.00 stddev:2.63 (3.2%) stddev-sans-first:2.74 20:42:25 INFO - PROCESS | 12667 | Values: 79.0 78.0 81.0 84.0 78.0 85.0 81.0 79.0 81.0 77.0 20:42:25 INFO - PROCESS | 12667 | 20:42:25 INFO - PROCESS | 12667 | [#11] stanford-crypto-ccm Cycles:10 Average:129.30 Median:125.00 stddev:12.04 (9.6%) stddev-sans-first:12.61 20:42:25 INFO - PROCESS | 12667 | Values: 124.0 125.0 121.0 125.0 124.0 124.0 127.0 121.0 158.0 144.0 20:42:25 INFO - PROCESS | 12667 | 20:42:25 INFO - PROCESS | 12667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.90 Median:166.00 stddev:3.18 (1.9%) stddev-sans-first:3.35 20:42:25 INFO - PROCESS | 12667 | Values: 164.0 169.0 169.0 167.0 168.0 165.0 162.0 161.0 161.0 163.0 20:42:25 INFO - PROCESS | 12667 | 20:42:25 INFO - PROCESS | 12667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:0.99 (1.5%) stddev-sans-first:1.05 20:42:25 INFO - PROCESS | 12667 | Values: 65.0 65.0 67.0 65.0 64.0 66.0 64.0 65.0 64.0 64.0 20:42:25 INFO - PROCESS | 12667 | -------- Summary: end -------- 20:42:25 INFO - PROCESS | 12667 | 20:42:26 INFO - TEST-INFO | 12667: exit 0 20:42:26 INFO - TEST-OK | kraken | took 61700ms 20:42:26 INFO - SUITE-END | took 1177s 20:42:26 INFO - Completed test suite (00:19:38) 20:42:26 INFO - javascript benchmark 20:42:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3063.509838425852, 3064.555639432282, 3090.984545077274, 3124.4376012317803, 3140.13646247282, 5335.6265061445665, 5337.0, 5363.4904684055, 5380.192971054344, 5412.3505179378435, 5344.118220493614, 5389.434109418504, 5412.675239485624, 5415.702136382497, 5502.147167189082, 4837.226043832988, 4840.5159484051665, 4901.705897646153, 4912.508749125077, 5007.4491805901325, 4939.950600494003, 4987.950120498803, 4994.750262486879, 5007.84976450707, 5008.724520151391, 4781.617470602361, 4807.134715751164, 4824.614030877521, 4874.46380898101, 4948.703077815325, 4852.393450818648, 4862.41651001882, 4863.148948933957, 4903.65674402792, 4974.577160941324, 4738.147133515966, 4762.380890484214, 4782.402199725035, 4836.588889944359, 4934.605231581465, 5318.490415335471, 5346.144616861322, 5354.384245811742, 5360.088784906575, 5399.244105825188, 5598.888022239573, 5702.515286200636, 5727.341355744101, 5743.10981797822, 5749.9712501437225, 5806.245188125559, 5812.418758124196, 5824.737886795105, 5835.533157347402, 5863.5016023638045, 5436.2661040759385, 5439.401665816757, 5440.646357986758, 5441.1838224266285, 5441.7279136043835, 5745.3105627324985, 5905.320888097838, 5947.345791962881, 5950.047992321208, 5957.255343082114, 5733.426657334317, 5734.0, 5734.283214598176, 5737.168110623936, 5792.681402522818, 5797.565182611237, 5806.506446952057, 5809.912851307234, 5810.041343178413, 5815.244018277597, 5594.692291923983, 5630.662160270315, 5669.064604340238, 5696.886062278814, 5754.74103665336, 5719.685417302005, 5767.653940763567, 5774.41203668986, 5782.913256301159, 5792.189093526909, 4384.364267181305, 4414.381986521889, 4422.7346359218545, 4432.955670443319, 4475.5748203920575, 3907.238088572758, 3935.586763389803, 3951.4863067801, 3958.3270843956598, 3975.0857302820514, 5361.383440904268, 5389.27244821944, 5392.919106213331, 5479.0, 5493.533049690741, 5742.052561327418, 5761.798337058267, 5762.337331206881, 5770.538356931435, 5820.126980952807, 5416.133418652997, 5418.8916221675345, 5421.620486565903, 5425.37608175057, 5426.294581704353, 4823.107725070803, 4970.080535101018, 4995.275685025651, 5006.0, 5011.1481048221885, 5971.134185543072, 5979.402059794073, 5980.611260268069, 5984.611000284967, 6068.878622427526, 5828.7959921404245, 5852.092925596369, 5860.970695146497, 5863.882722345615, 5881.676507791942, 5665.3201615806365, 5945.435183657545, 5990.520758339409, 5992.64044157352, 6058.666773327509, 5707.372189059283, 5723.370429252695, 5742.224799652161, 5774.855628609318, 5786.537077033911, 5816.47651711348, 5824.970875145597, 5825.126231065205, 5825.446582574648, 5826.475617194473, 5443.183522471661, 5628.155776633538, 5654.434556544313, 5665.943340566541, 5695.316562012585, 5649.576281778801, 5698.743556539883, 5698.914516282342, 5752.913706294493, 5760.567957403127, 5634.183043458757, 5640.915386269127, 5641.464060914206, 5644.294463192101, 5644.9435505644415, 5580.051391263414, 5656.038473459439, 5677.914831277451, 5706.229658995982, 5767.826965191051, 5571.303587051619, 5571.721413929368, 5591.27313449249, 5645.943540564542, 5667.093265077568], "value": 5323.956468361105, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.2025097659628, 598.7545106506333, 611.2695329081746, 613.9171211886394, 633.8890694128532, 40423.19153616926, 41877.79061104698, 42007.57992420075, 45946.0, 46080.0, 42970.785146074304, 43651.56348436523, 43744.56255437453, 43756.0, 43773.7811310943, 8969.910300896989, 9102.13529714676, 9120.589573469177, 9122.908770912305, 9123.771905702377, 8876.6005529751, 8972.147645973622, 8974.910250897472, 8976.46141231525, 8977.192052715254, 8949.910500894974, 9021.95489022554, 9023.0, 9026.684066057689, 9031.0, 8965.237954773853, 8997.190252877272, 9003.0, 9010.0, 9014.18872301496, 8971.596278167499, 9035.774105647346, 9040.050794666597, 9042.728718138467, 9044.3668943174, 42798.0, 43110.78444607757, 43115.56884431178, 43161.0, 43178.35232471484, 3408.522806807024, 3911.9804400977814, 3985.0, 4024.4969378827645, 4109.794510274474, 33392.6660733392, 33864.15339616505, 33972.49041264383, 34327.14182145441, 35685.821570892236, 4337.392765012901, 4358.956410435887, 4364.912701745979, 4377.846775362879, 4488.102379524108, 349835.0, 351988.0, 353089.99999999744, 353570.0, 354388.0, 61024.69487652533, 61428.078578821354, 61450.38549614536, 61488.38511614916, 61614.691926540974, 35738.0, 36473.0, 36607.81696091503, 38429.423558646085, 38439.61560384416, 17546.38587649426, 17713.557161071076, 17722.291108355523, 17725.822741772674, 17727.0, 13544.796828047587, 13646.727065458635, 13655.522056727967, 13655.726885462234, 13657.112287701466, 18880.905595471933, 18931.716024259647, 18950.336738214097, 18984.525386865163, 18990.05049747507, 6797.844366457714, 6980.359615490355, 7020.5787652741, 7091.82270443243, 7238.601876896716, 826.5660528222732, 882.179572997106, 938.5776400619749, 975.9072888075622, 977.8973207813219, 31220.531692024637, 31464.0, 32316.35367292641, 32418.837905810793, 32686.346273074407, 1010.4038617215878, 1015.3907655406697, 1033.2973577966904, 1055.3773273768513, 1113.2708076210095, 15390.153541555099, 16160.434384796476, 16168.838311616968, 16289.624319355882, 16363.83636163647, 572370.0, 575443.0, 576120.0, 577075.0, 577220.0, 56646.716766415906, 57125.0, 57256.71371643115, 57266.42733572694, 57321.426785732445, 1221.3465795799204, 1240.3116270469975, 1309.0, 1345.172718777964, 1365.3855764905634, 30168.84915575408, 32642.836785815918, 33912.32175356529, 34049.0, 34141.0, 49868.50131498639, 50254.74872625613, 50402.49597503978, 50413.24380134227, 50465.49534504755, 421232.0, 421598.0, 422142.0, 422989.0, 425547.0, 24730.629040564767, 27079.59380609253, 27758.16725498238, 27852.860735697002, 29180.0, 45506.77246613746, 46463.0, 46467.3029904545, 46848.531514684415, 46865.0, 39601.80199098986, 39677.80161099176, 39871.80064099661, 40247.0, 41497.37753933633, 39380.606193937696, 40000.0, 41053.25254464437, 43911.34132987944, 44505.516429583586, 40483.0, 41442.0, 41504.37743433911, 41736.79131604323, 41800.0, 34008.0, 36806.63193368032, 37169.4424583626, 37557.81221093877, 37647.435288470144, 27199.320016999733, 27714.584281236203, 28131.71868281291, 28185.0, 28374.0], "value": 19912.515714254663, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.66413556070654, 384.45215567815853, 395.499692888496, 401.4399912122589, 412.2723393210983, 14482.348294326754, 16598.66802663946, 17897.10514474276, 19321.806781932195, 19375.41873743788, 15495.225238738074, 16135.273912673929, 18534.721979170325, 19087.618247635073, 19609.803901960975, 5500.477454641803, 5687.573431992597, 5699.601027928046, 5706.77172913083, 5709.115087161496, 5484.095124304505, 5610.8597285068, 5669.376368599451, 5674.687892165929, 5682.403347648499, 5611.971940140294, 5656.4060773618585, 5662.207290979266, 5668.376478587352, 5671.6880571568545, 5484.862878428071, 5627.184058311522, 5668.724536979167, 5670.943290567104, 5672.319321681383, 5028.547430731252, 5033.697978121324, 5035.496450354935, 5035.622328325354, 5038.773255203525, 21701.565968680698, 22432.66351004736, 22568.0, 23395.649065264035, 23641.408964775845, 2197.340797760681, 2277.840551161419, 2303.0672577606097, 2546.745325467457, 2784.7772178225896, 9351.485668288242, 10047.497625118714, 10609.84085238722, 10632.840507392395, 15199.468018619405, 1859.2191279662575, 1875.3904980881268, 1891.3758459708263, 1892.224188082893, 1953.2479995201807, 79509.60245198794, 79765.60117199435, 80695.59652201702, 81728.59135704284, 81746.0, 10350.792984140426, 10465.738356541147, 10699.0, 10734.892651073389, 10834.620788272372, 14377.353019114165, 14414.684622536875, 14447.711045779026, 14492.202928838804, 14527.128372297697, 7860.606969651609, 8005.599720013976, 8006.519608823489, 8040.71857484997, 8207.220314070153, 7808.921910780933, 7823.608819558999, 8028.799280018046, 8037.718679846177, 8050.919490805134, 4124.566920473367, 4318.244307246219, 4444.577765112309, 4545.613622842029, 4612.400387949612, 1921.7501724775689, 2008.2167954497756, 2032.979670203279, 2063.5769667218183, 2067.410787925434, 887.38326862831, 920.8250432417819, 925.8518637018179, 1006.758377989292, 1029.6807989523122, 18722.906385468257, 18957.241710331433, 19716.126967938024, 19774.802251977297, 20061.0, 937.6015193542853, 954.1412728544366, 957.3490026781716, 986.2750878104588, 1019.607451131305, 10592.15262778976, 10594.4702764863, 11322.886771132347, 11570.652880413601, 11940.701482462835, 471635.0, 472073.0, 477221.0, 480311.0, 484905.0, 64295.0, 95238.52380738052, 96344.51827740816, 97831.0, 100326.49836750915, 1035.290825784325, 1068.1027936533505, 1118.4463690473267, 1131.4682099413262, 1135.0465608888574, 17796.644067118843, 17977.10114494244, 18521.444356669323, 18525.90737046306, 18642.627147457246, 40266.396004060545, 44394.77802610966, 44428.111437770414, 44763.77618111888, 45030.54969450264, 49608.0, 56667.433325667866, 58466.70766646139, 58949.51555328825, 60464.0, 5695.686737229326, 7954.284114429729, 8010.676903698095, 8016.0, 8251.0, 7826.804329891798, 11585.768284634429, 12220.45007974661, 12653.87346126527, 13211.14127581704, 11067.612633557786, 11070.667879963614, 11409.3724845131, 11542.94228528852, 11661.883381166419, 10699.251052426256, 11083.445827708743, 11086.61196858106, 11130.276532025393, 11149.721256968316, 11376.829347559627, 11395.943020284845, 11457.0, 11687.941560292144, 12050.75898482043, 8225.588720564068, 8226.177382261725, 8358.665653373797, 8421.4947103174, 8461.534615596202, 9542.666006689733, 11424.657260282205, 11426.886026085202, 11565.785592512664, 11586.13104017219], "value": 9040.75341702093, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.5853264754246, 1471.343198775874, 1492.9999999999998, 1641.3270559070777, 1789.4899953513243, 8443.746687599374, 8715.520646364448, 8795.032546419889, 9077.727668169966, 9087.091290870909, 8896.911030889689, 9010.954945225281, 9011.234045106157, 9021.142991415822, 9025.052369501207, 2136.839737019722, 2356.69362982812, 2453.6687547181127, 2482.950340993179, 2486.042873493709, 2461.963070553943, 2497.213377786001, 2497.2383423055962, 2499.1627804685363, 2502.461970676304, 2343.757768607365, 2363.7872591466767, 2364.160722943356, 2366.7041619797524, 2368.1711401009593, 2300.18343488061, 2310.0297874892503, 2329.77867102625, 2416.4925365673053, 2421.6125419932728, 2405.494846082325, 2434.0263894442364, 2454.0551887523434, 2454.435479839647, 2454.742252063543, 8998.59506322217, 9136.908630913673, 9143.268538516902, 9147.542622868896, 9159.542022898895, 5297.735113244361, 5502.889942201174, 5505.449455054502, 5507.476789704971, 5513.062779327524, 3291.7202037826814, 3369.595648522193, 3419.982900085484, 3435.0725304167736, 3469.3061387722555, 1092.9617463388822, 1134.353418551426, 1142.6229344316355, 1157.5196293538172, 1161.895429411357, 11810.283634763966, 11850.051995840398, 11853.881461185363, 11855.58505452305, 11865.050795936304, 4312.633426158749, 4399.450068741407, 4515.096980603892, 4533.048059907391, 4688.859334219979, 9442.339036267533, 9458.52707364629, 9471.526423678788, 9475.573599188054, 9482.620695172116, 5373.086575282211, 5375.462453754593, 5377.327834020747, 5383.300170977826, 5386.353637563439, 4482.887927801766, 5011.824586139468, 5050.646454748133, 5113.846584602468, 5148.433672296044, 3780.6219378061974, 3882.2041481496226, 3883.514560679915, 3937.5078115235597, 4012.25773231953, 1130.5364800431782, 1139.2651739627893, 1166.2302880099094, 1172.876847930972, 1202.458893497912, 787.4251796188814, 796.9601519923981, 843.8607629741147, 858.2533196119416, 871.2550769092461, 5910.438508341701, 6007.729652165663, 6066.48434883031, 6258.530610204162, 6321.146645202931, 479.80567870012703, 481.04992639536636, 489.05856226763336, 492.14121358229403, 493.7333839726579, 4184.769837658897, 4239.1521695660995, 4250.766207858535, 4298.935515967263, 4307.956920430755, 10941.507632156572, 11212.831807522894, 11250.156238282161, 11300.039496642714, 11340.262882912584, 10544.630937917289, 10940.617078402218, 10956.835647465294, 11283.323000619988, 11290.661280161608, 8202.507849529047, 8444.61999210037, 8447.619857106445, 8449.155084491502, 8459.19637634436, 6136.386361363828, 6189.566730328834, 6220.502359811295, 6287.874242515032, 6460.095586617783, 10290.485475726333, 10436.58253669875, 10440.112590429595, 10489.947550262199, 10550.0, 10374.688759337232, 10613.0, 10646.361218326925, 10703.946480267548, 10849.945750271198, 7373.668184931798, 7867.17394673551, 7918.960405197937, 8087.0295564532635, 8123.268905798506, 8903.109689031045, 8945.19493245611, 8959.910400895908, 8969.327300452362, 8989.370744047854, 7480.625968701652, 7649.502782319354, 7679.308862202205, 7937.484063535851, 7986.880196796937, 7425.146108197627, 7467.663955121924, 7497.325240728361, 7502.849943001001, 7577.128630207597, 7170.677319520739, 7273.599952002691, 7275.345218930322, 7288.817779555554, 7315.231900650568, 4930.383702037246, 5057.291979122852, 5100.5409513143995, 5141.33162688863, 5365.865853353697, 5147.639665223548, 5223.947760522498, 5264.4735526448585, 5274.156135018378, 5362.65142765719], "value": 4597.510966396344, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.7129526483268, 316.9492881139017, 330.2552743563264, 331.8689117798468, 333.4481433228008, 5114.156164232897, 5246.422893481724, 5303.310569625948, 5318.707471089094, 5338.439463856297, 5175.948240517594, 5181.35233095863, 5230.529252367286, 5236.8690782730455, 5269.0, 4116.567760385161, 4123.463949686537, 4126.2160189563965, 4155.542890282081, 4173.853915112972, 4035.535913369971, 4134.648554872824, 4205.390218418327, 4210.852620158295, 4218.472690913636, 4059.5331536873196, 4059.7564146151326, 4071.409645601386, 4080.6327430531246, 4082.3060079786505, 4060.817263223162, 4076.7961601919933, 4077.408775727503, 4079.7144199906165, 4117.505899292074, 3621.5654121505327, 3645.963540364589, 3654.4152935530456, 3667.4132138857653, 3685.594584595692, 4920.8031678732805, 5158.509941555584, 5171.922421163686, 5180.352455943007, 5260.053190425722, 4668.089722504113, 4691.906161876777, 4692.671512994092, 4695.5304469552775, 4697.295405689174, 5383.919241211385, 5438.809641662523, 5471.644343117685, 5489.3412790465, 5492.340919089695, 3819.3698039823394, 3885.7862817545024, 3887.358585833334, 3892.2604705106223, 3895.3377925752407, 5327.653702509363, 5523.28197334348, 5655.632383895075, 5663.631863928873, 5665.575081868877, 3982.820773065219, 3993.740406873544, 3997.560268370477, 4004.5594984551676, 4025.396190571379, 3812.752171108869, 3917.216556688674, 4005.5193376794386, 4026.8993275168355, 4029.6171863672903, 4568.6116680081905, 4571.2000399929875, 4594.264917613166, 4594.931076033862, 4615.861524154281, 5472.753726082336, 5474.698891560923, 5491.187907990354, 5557.666540007613, 5610.0, 1345.4460098586148, 1365.5971488410942, 1380.7366259872103, 1418.0215651200638, 1430.7210094031564, 898.5911410308294, 916.3402350307767, 922.6908985489936, 923.8152369526122, 928.2017464980112, 4733.360996265529, 4737.265723812815, 4748.406449193851, 4756.6670333077045, 4757.8096876125255, 4959.834438906854, 4970.868352937626, 4984.27727979441, 4990.650654454226, 5006.945763084515, 4584.781411309372, 4628.560286772751, 4679.245283018868, 4681.812727490862, 4727.527247275245, 2611.903000739694, 2627.0691042084054, 2648.8675566221614, 2649.9072532461273, 2655.831434168998, 5041.722705251246, 5054.570361519312, 5079.466656001141, 5102.69383836971, 5122.436531981479, 4963.8262660806695, 4979.029089327619, 4997.450280469146, 5093.8726531837, 5411.918821217685, 5857.4435428634215, 5868.853278668067, 5874.794382196688, 5875.4124587541755, 5897.203877476486, 5682.801101961411, 5706.061378390215, 5720.399358067343, 5734.627249228787, 5742.224799651994, 5213.687178769134, 5377.0, 5523.171524271231, 5580.135079062752, 5620.269364982526, 5826.388229236039, 5844.597296648859, 5852.736626851887, 5853.531717462678, 5888.352281248974, 4098.733582317259, 4149.688773341951, 4155.646770024461, 4156.397322388298, 4157.8336866525, 5429.511343979061, 5662.66024038559, 5680.886382272414, 5690.118031705092, 5691.658500489983, 5522.686388167068, 5539.25220095282, 5544.390117087037, 5564.360098588714, 5599.104143337209, 5322.33470816148, 5451.700156491431, 5572.275604171432, 5572.3034620672415, 5576.996140694714, 5499.615026948235, 5505.724713764376, 5531.419200983839, 5532.28080349552, 5532.336119665666, 5561.3882472927135, 5587.6088673794075, 5596.712756066209, 5616.466435688603, 5628.634138780966, 5218.269442278008, 5467.758219130137, 5481.5340696041285, 5490.121580547093, 5500.80747173847], "value": 4115.289167115079, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.3285874859498, 768.3891306411405, 773.9496932699375, 836.5482639374734, 848.1857416879795, 13311.733765324687, 14671.633209169777, 15139.091654500724, 15145.77281340778, 15156.0, 14651.487197948074, 14713.779293310608, 14781.704365912676, 14782.408703651841, 14789.92605036976, 652.1587152573175, 784.2040329066002, 803.1606970715616, 805.3637626275236, 805.4241217529466, 661.8014595621319, 668.9899651505232, 669.5480550628331, 772.7063715787992, 796.2555011064674, 837.5728378526965, 842.0737189092011, 846.031294168177, 846.238385453091, 846.3356265331715, 827.5572568675759, 832.4755404095441, 833.0295206084904, 835.7826964989085, 839.6599377252193, 495.66294919454754, 596.3350863786847, 608.7686679061973, 615.5321955313993, 615.6829232945037, 13455.192688438725, 13515.662108447368, 13526.526571570046, 13558.98307626932, 13561.389737461837, 12850.67873303175, 13527.256000919946, 14428.20644864532, 14455.927720361331, 14522.570615962128, 13448.260345680983, 13495.122817016863, 13580.932095339558, 13617.523386681418, 14027.719445611132, 4521.359680658498, 4669.089527542131, 4670.369500117475, 4682.531746825324, 4685.625149987993, 87068.56465717695, 87409.56295218483, 87802.56098719465, 88047.67928481141, 88770.0, 11387.351148990396, 11456.656300311004, 11770.823437648442, 12008.939955300168, 12401.069919756055, 21474.463138421354, 21658.78341216599, 21659.566808663738, 21717.67423488649, 22148.2248121315, 7825.569593672402, 8069.757907262791, 8081.710440116794, 8112.918870811334, 8162.10216876155, 4484.2376795944765, 4591.9540804591525, 4597.08058388317, 4683.625309975194, 4806.519348065165, 3150.1966998415255, 3241.38413701396, 3253.967460325414, 3262.2170679037336, 3266.738660907122, 595.9165716799652, 600.7356763024255, 600.9549283803644, 604.2658170322991, 608.0514397539852, 625.7027911741977, 647.5693663713672, 702.6346299924011, 711.3206887422499, 743.5018537579834, 9517.90482095184, 9850.60597576089, 10014.50657764012, 10085.747856303504, 10198.077859606246, 689.648279377521, 718.3821913154683, 732.578767208857, 738.1216352540567, 740.9407247420194, 4544.18204723153, 4616.261398176276, 4652.139354219413, 4657.394538709946, 4672.672912896064, 129278.59720891507, 129367.35316323546, 130785.34607326903, 131655.3417232927, 131856.3407182958, 109293.0, 109906.45046774716, 112068.4396578012, 112148.0, 115658.4217078926, 96823.0, 96990.51504742431, 97674.51162744283, 97912.0, 102519.97480025253, 8941.776455588662, 8970.461772293684, 9215.124563166488, 9309.906900931175, 9325.715199776098, 46031.309530356404, 57130.714346428, 57140.42859571351, 57221.71389143028, 58265.0, 62903.0, 72285.27714722996, 73494.63252683703, 74160.62919685582, 78080.21919780957, 13644.181349119084, 13764.862351376358, 13803.9697561265, 13916.721665566834, 14057.94565407578, 14623.195724234844, 14688.926555367154, 14886.925565372538, 15352.385904563693, 15675.05949643025, 14213.93607114947, 14338.354774035402, 15100.471483498024, 15196.696066078837, 15356.001859879072, 13009.804852927024, 13740.0, 14281.64295892611, 14436.422543098159, 14532.346044428237, 13893.58319250424, 14153.716925661634, 14250.572482825532, 14350.282485875874, 14496.275186240857, 12040.397980100784, 12086.576969806014, 12383.814242786546, 12592.496300147892, 12649.0, 11651.30092194471, 11653.417329133339, 11833.171677982802, 12097.75804483923, 12330.136890417585], "value": 7705.007457559665, "unit": "score"}], "name": "dromaeo_css", "value": 7203.627501965257, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 118.0, 113.0, 112.0, 112.0, 111.0, 111.0, 112.0, 128.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 118.0, 145.0, 183.0, 118.0, 154.0, 144.0, 118.0, 187.0, 119.0], "value": 143.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 160.0, 158.0, 162.0, 160.0, 161.0, 160.0, 160.0, 163.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 173.0, 75.0, 75.0, 74.0, 77.0, 166.0, 75.0], "value": 94.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 142.0, 82.0, 82.0, 82.0, 143.0, 82.0, 81.0, 146.0], "value": 100.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 118.0, 116.0, 116.0, 116.0, 116.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 52.0, 52.0, 53.0, 53.0, 53.0, 51.0, 52.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 61.0, 61.0, 60.0, 61.0, 62.0, 61.0, 62.0], "value": 61.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 81.0, 84.0, 78.0, 85.0, 81.0, 79.0, 81.0, 77.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 125.0, 121.0, 125.0, 124.0, 124.0, 127.0, 121.0, 158.0, 144.0], "value": 129.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 169.0, 169.0, 167.0, 168.0, 165.0, 162.0, 161.0, 161.0, 163.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 67.0, 65.0, 64.0, 66.0, 64.0, 65.0, 64.0, 64.0], "value": 64.9, "unit": "score"}], "name": "kraken", "value": 1523.7, "alertThreshold": 2.0}]} 20:42:26 INFO - Return code: 0 20:42:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:42:26 INFO - # TBPL SUCCESS # 20:42:26 INFO - Running post-action listener: _resource_record_post_action 20:42:26 INFO - [mozharness: 2016-09-03 03:42:26.927694Z] Finished run-tests step (success) 20:42:26 INFO - Running post-run listener: _resource_record_post_run 20:42:26 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 143360; Write bytes: 417910784; Read time: 184; Write time: 305280 20:42:26 INFO - TinderboxPrint: CPU usage
13.0% 20:42:26 INFO - TinderboxPrint: I/O read bytes / time
143,360 / 184 20:42:26 INFO - TinderboxPrint: I/O write bytes / time
417,910,784 / 305,280 20:42:27 INFO - TinderboxPrint: CPU idle
8,290.7 (86.9%) 20:42:27 INFO - TinderboxPrint: CPU user
1,193.0 (12.5%) 20:42:27 INFO - TinderboxPrint: Swap in / out
0 / 0 20:42:27 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 118337536; Read time: 168; Write time: 103520 20:42:27 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 139264; Write bytes: 288251904; Read time: 16; Write time: 197604 20:42:27 INFO - Running post-run listener: _upload_blobber_files 20:42:27 INFO - Blob upload gear active. 20:42:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:42: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 20:42: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'] 20:42: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 20:42:27 INFO - (blobuploader) - INFO - Open directory for files ... 20:42:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:42:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:42:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:28 INFO - (blobuploader) - INFO - Done attempting. 20:42:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:42:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:42:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:29 INFO - (blobuploader) - INFO - Done attempting. 20:42:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:42:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:42:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:33 INFO - (blobuploader) - INFO - Done attempting. 20:42:33 INFO - (blobuploader) - INFO - Iteration through files over. 20:42:33 INFO - Return code: 0 20:42:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:42:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:42:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b2da7a57326f545283ed8493a73c4c0b04edbbeffaa5a0650cf5aea1ad9e7054b81eb5ed8cae5a4ce50bac73885ca289fe2425566a405bd576fc52347055ab78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2fe1d4b2db33d3928591e0633e2353fa2a61dc8c853e1a946cb6fde638c37a0aa2adcc34b08eb222ab0f351e522e0ac49e28938bcd6441e615256b104da1eb62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2ce1c2d0a6ba7b83da43f8251169252ff3b659be1a97f4677680e6da12580543945212b99b586687e6018270729740cf5c04b74708a7bc23ead9812858dd4a7b"} 20:42:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:42:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:42:33 INFO - Contents: 20:42:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b2da7a57326f545283ed8493a73c4c0b04edbbeffaa5a0650cf5aea1ad9e7054b81eb5ed8cae5a4ce50bac73885ca289fe2425566a405bd576fc52347055ab78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2fe1d4b2db33d3928591e0633e2353fa2a61dc8c853e1a946cb6fde638c37a0aa2adcc34b08eb222ab0f351e522e0ac49e28938bcd6441e615256b104da1eb62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2ce1c2d0a6ba7b83da43f8251169252ff3b659be1a97f4677680e6da12580543945212b99b586687e6018270729740cf5c04b74708a7bc23ead9812858dd4a7b"} 20:42:33 INFO - Running post-run listener: copy_logs_to_upload_dir 20:42:33 INFO - Copying logs to upload dir... 20:42:33 INFO - mkdir: /builds/slave/test/build/upload/logs 20:42:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.532834 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-09-02 20:42:33.689447) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 20:42:33.693433) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050 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/b2da7a57326f545283ed8493a73c4c0b04edbbeffaa5a0650cf5aea1ad9e7054b81eb5ed8cae5a4ce50bac73885ca289fe2425566a405bd576fc52347055ab78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2fe1d4b2db33d3928591e0633e2353fa2a61dc8c853e1a946cb6fde638c37a0aa2adcc34b08eb222ab0f351e522e0ac49e28938bcd6441e615256b104da1eb62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2ce1c2d0a6ba7b83da43f8251169252ff3b659be1a97f4677680e6da12580543945212b99b586687e6018270729740cf5c04b74708a7bc23ead9812858dd4a7b"} build_url:https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011267 build_url: 'https://queue.taskcluster.net/v1/task/bmYdf9QYQs-m-k8D5J2Kvg/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/b2da7a57326f545283ed8493a73c4c0b04edbbeffaa5a0650cf5aea1ad9e7054b81eb5ed8cae5a4ce50bac73885ca289fe2425566a405bd576fc52347055ab78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2fe1d4b2db33d3928591e0633e2353fa2a61dc8c853e1a946cb6fde638c37a0aa2adcc34b08eb222ab0f351e522e0ac49e28938bcd6441e615256b104da1eb62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2ce1c2d0a6ba7b83da43f8251169252ff3b659be1a97f4677680e6da12580543945212b99b586687e6018270729740cf5c04b74708a7bc23ead9812858dd4a7b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 20:42:33.718640) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 20:42:33.718975) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1472870174.518150-1576286050 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004521 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 20:42:33.806027) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 20:42:33.806362) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 20:42:33.806649) ========= ========= Total master_lag: 0.17 =========