builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-011 starttime: 1472846810.83 results: success (0) buildid: 20160902113848 builduid: 3b805e35373b42a4a682c9c9c7729921 revision: aefced02ac1e5ad12bb81e6e252ec1c2c031363c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 13:06:50.834582) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 13:06:50.836617) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 13:06:50.836902) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 13:06:50.851183) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 13:06:50.851452) ========= 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=5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-02 13:06:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 58.2K=0.2s 2016-09-02 13:06:51 (58.2 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.313612 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 13:06:51.178705) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 13:06:51.179018) ========= 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=5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014468 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 13:06:51.228706) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 13:06:51.229067) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev aefced02ac1e5ad12bb81e6e252ec1c2c031363c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev aefced02ac1e5ad12bb81e6e252ec1c2c031363c --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=5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-02 13:06:51,505 truncating revision to first 12 chars 2016-09-02 13:06:51,505 Setting DEBUG logging. 2016-09-02 13:06:51,505 attempt 1/10 2016-09-02 13:06:51,505 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/aefced02ac1e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-02 13:06:51,805 unpacking tar archive at: autoland-aefced02ac1e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.622484 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 13:06:51.868404) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 13:06:51.868739) ========= script_repo_revision: aefced02ac1e5ad12bb81e6e252ec1c2c031363c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 13:06:51.869146) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 13:06:51.869440) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 13:06:51.879877) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-09-02 13:06:51.880178) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:06:52 INFO - MultiFileLogger online at 20160902 13:06:52 in /builds/slave/test 13:06:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 13:06:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:06:52 INFO - {'append_to_log': False, 13:06:52 INFO - 'base_work_dir': '/builds/slave/test', 13:06:52 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 13:06:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:06:52 INFO - 'branch': 'Autoland-Non-PGO', 13:06:52 INFO - 'buildbot_json_path': 'buildprops.json', 13:06:52 INFO - 'config_files': ('talos/linux_config.py',), 13:06:52 INFO - 'default_actions': ('clobber', 13:06:52 INFO - 'read-buildbot-config', 13:06:52 INFO - 'download-and-extract', 13:06:52 INFO - 'populate-webroot', 13:06:52 INFO - 'create-virtualenv', 13:06:52 INFO - 'install', 13:06:52 INFO - 'run-tests'), 13:06:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:06:52 INFO - 'download_minidump_stackwalk': True, 13:06:52 INFO - 'download_symbols': 'ondemand', 13:06:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:06:52 INFO - 'tooltool.py': '/tools/tooltool.py', 13:06:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:06:52 INFO - '/tools/misc-python/virtualenv.py')}, 13:06:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:06:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:06:52 INFO - 'installer_path': 'installer.exe', 13:06:52 INFO - 'log_level': 'info', 13:06:52 INFO - 'log_name': 'talos', 13:06:52 INFO - 'log_to_console': True, 13:06:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:06:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:06:52 INFO - 'opt_config_files': (), 13:06:52 INFO - 'pip_index': False, 13:06:52 INFO - 'sps_profile': False, 13:06:52 INFO - 'sps_profile_interval': 0, 13:06:52 INFO - 'suite': 'dromaeojs', 13:06:52 INFO - 'system_bits': '32', 13:06:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:06:52 INFO - 'title': 'talos-linux64-ix-011', 13:06:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:06:52 INFO - 'use_talos_json': True, 13:06:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:06:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:06:52 INFO - 'work_dir': 'build'} 13:06:52 INFO - [mozharness: 2016-09-02 20:06:52.252552Z] Running clobber step. 13:06:52 INFO - Running pre-action listener: _resource_record_pre_action 13:06:52 INFO - Running main action method: clobber 13:06:52 INFO - rmtree: /builds/slave/test/build 13:06:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:06:52 INFO - Running post-action listener: _resource_record_post_action 13:06:52 INFO - [mozharness: 2016-09-02 20:06:52.715401Z] Finished clobber step (success) 13:06:52 INFO - [mozharness: 2016-09-02 20:06:52.715501Z] Running read-buildbot-config step. 13:06:52 INFO - Running pre-action listener: _resource_record_pre_action 13:06:52 INFO - Running main action method: read_buildbot_config 13:06:52 INFO - Using buildbot properties: 13:06:52 INFO - { 13:06:52 INFO - "project": "", 13:06:52 INFO - "product": "firefox", 13:06:52 INFO - "script_repo_revision": "production", 13:06:52 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 13:06:52 INFO - "repository": "", 13:06:52 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 13:06:52 INFO - "buildid": "20160902113848", 13:06:52 INFO - "pgo_build": "False", 13:06:52 INFO - "basedir": "/builds/slave/test", 13:06:52 INFO - "buildnumber": 603, 13:06:52 INFO - "slavename": "talos-linux64-ix-011", 13:06:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:06:52 INFO - "platform": "ubuntu64_hw", 13:06:52 INFO - "scheduler": "tests-autoland-linux64-talos", 13:06:52 INFO - "branch": "autoland", 13:06:52 INFO - "repo_path": "integration/autoland", 13:06:52 INFO - "revision": "aefced02ac1e5ad12bb81e6e252ec1c2c031363c", 13:06:52 INFO - "stage_platform": "linux64", 13:06:52 INFO - "builduid": "3b805e35373b42a4a682c9c9c7729921", 13:06:52 INFO - "slavebuilddir": "test" 13:06:52 INFO - } 13:06:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 13:06:52 INFO - Running post-action listener: _resource_record_post_action 13:06:52 INFO - [mozharness: 2016-09-02 20:06:52.717927Z] Finished read-buildbot-config step (success) 13:06:52 INFO - [mozharness: 2016-09-02 20:06:52.718017Z] Running download-and-extract step. 13:06:52 INFO - Running pre-action listener: _resource_record_pre_action 13:06:52 INFO - Running main action method: download_and_extract 13:06:52 INFO - mkdir: /builds/slave/test/build/tests 13:06: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')]} 13:06:52 INFO - trying https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 13:06:52 INFO - Downloading https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/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 13:06:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/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 13:06:53 INFO - Downloaded 1361 bytes. 13:06:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 13:06:53 INFO - Using the following test package requirements: 13:06:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 13:06:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:06:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 13:06:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:06:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 13:06:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:06:53 INFO - u'jsshell-linux-x86_64.zip'], 13:06:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:06:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 13:06:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 13:06:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:06:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 13:06:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:06:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 13:06:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:06:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 13:06:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:06:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 13:06:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:06: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')]} 13:06:53 INFO - trying https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 13:06:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/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 13:06:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/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 13:06:55 INFO - Downloaded 20335050 bytes. 13:06:55 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 13:06:59 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 13:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:59 INFO - trying https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 13:06:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/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 13:06:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/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 13:07:00 INFO - Downloaded 20335050 bytes. 13:07:00 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 13:07: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')]} 13:07:04 INFO - trying https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 13:07:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/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 13:07:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/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 13:07:06 INFO - Downloaded 14413998 bytes. 13:07:06 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 13:07: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')]} 13:07:06 INFO - trying https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 13:07:06 INFO - Downloading https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/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 13:07:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/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 13:07:08 INFO - Downloaded 55880886 bytes. 13:07:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 13:07:08 INFO - mkdir: /builds/slave/test/properties 13:07:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:07:08 INFO - Writing to file /builds/slave/test/properties/build_url 13:07:08 INFO - Contents: 13:07:08 INFO - build_url:https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 13:07:09 INFO - Running post-action listener: _resource_record_post_action 13:07:09 INFO - Running post-action listener: set_extra_try_arguments 13:07:09 INFO - [mozharness: 2016-09-02 20:07:09.210049Z] Finished download-and-extract step (success) 13:07:09 INFO - [mozharness: 2016-09-02 20:07:09.210276Z] Running populate-webroot step. 13:07:09 INFO - Running pre-action listener: _resource_record_pre_action 13:07:09 INFO - Running main action method: populate_webroot 13:07:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:07:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:07:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:07:09 INFO - '--tppagecycles', 13:07:09 INFO - '7'], 13:07:09 INFO - 'tests': ['tsvgm']}}, 13:07:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:07:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:07:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:07:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:07:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:07:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:07:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:09 INFO - 'talos_options': ['--disable-e10s'], 13:07:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:07:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:07:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:09 INFO - 'talos_options': ['--disable-e10s'], 13:07:09 INFO - 'tests': ['damp', 'tps']}, 13:07:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:09 INFO - 'tests': ['damp', 'tps']}, 13:07:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:07:09 INFO - 'tests': ['dromaeo_dom']}, 13:07:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:07:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:07:09 INFO - 'tests': ['basic_compositor_video']}, 13:07:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:07:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:07:09 INFO - 'tests': ['a11yr', 13:07:09 INFO - 'ts_paint', 13:07:09 INFO - 'tpaint', 13:07:09 INFO - 'sessionrestore', 13:07:09 INFO - 'sessionrestore_no_auto_restore', 13:07:09 INFO - 'tabpaint']}, 13:07:09 INFO - 'other-e10s': {'tests': ['a11yr', 13:07:09 INFO - 'ts_paint', 13:07:09 INFO - 'tpaint', 13:07:09 INFO - 'sessionrestore', 13:07:09 INFO - 'sessionrestore_no_auto_restore', 13:07:09 INFO - 'tabpaint']}, 13:07:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:07:09 INFO - 'ts_paint', 13:07:09 INFO - 'tpaint', 13:07:09 INFO - 'sessionrestore', 13:07:09 INFO - 'sessionrestore_no_auto_restore', 13:07:09 INFO - 'tabpaint']}, 13:07:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:07:09 INFO - 'ts_paint', 13:07:09 INFO - 'tpaint', 13:07:09 INFO - 'sessionrestore', 13:07:09 INFO - 'sessionrestore_no_auto_restore', 13:07:09 INFO - 'tabpaint']}, 13:07:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:07:09 INFO - 'tests': ['a11yr', 13:07:09 INFO - 'ts_paint', 13:07:09 INFO - 'tpaint', 13:07:09 INFO - 'sessionrestore', 13:07:09 INFO - 'sessionrestore_no_auto_restore', 13:07:09 INFO - 'tabpaint']}, 13:07:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:07:09 INFO - 'tests': ['a11yr', 13:07:09 INFO - 'ts_paint', 13:07:09 INFO - 'tpaint', 13:07:09 INFO - 'sessionrestore', 13:07:09 INFO - 'sessionrestore_no_auto_restore', 13:07:09 INFO - 'tabpaint']}, 13:07:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:07:09 INFO - 'tests': ['tsvgx', 13:07:09 INFO - 'tsvgr_opacity', 13:07:09 INFO - 'tart', 13:07:09 INFO - 'tscrollx', 13:07:09 INFO - 'cart']}, 13:07:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:07:09 INFO - 'tsvgr_opacity', 13:07:09 INFO - 'tart', 13:07:09 INFO - 'tscrollx', 13:07:09 INFO - 'cart']}, 13:07:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:09 INFO - 'talos_options': ['--disable-e10s'], 13:07:09 INFO - 'tests': ['tp5o']}, 13:07:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:09 INFO - 'tests': ['tp5o']}, 13:07:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:09 INFO - 'talos_options': ['--disable-e10s', 13:07:09 INFO - '--xperf_path', 13:07:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:07:09 INFO - 'tests': ['tp5n']}, 13:07:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:07:09 INFO - 'talos_options': ['--xperf_path', 13:07:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:07:09 INFO - 'tests': ['tp5n']}}, 13:07:09 INFO - 'talos.zip': {'path': '', 13:07:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:07:09 INFO - Running post-action listener: _resource_record_post_action 13:07:09 INFO - [mozharness: 2016-09-02 20:07:09.229150Z] Finished populate-webroot step (success) 13:07:09 INFO - [mozharness: 2016-09-02 20:07:09.229239Z] Running create-virtualenv step. 13:07:09 INFO - Running pre-action listener: _resource_record_pre_action 13:07:09 INFO - Running main action method: create_virtualenv 13:07:09 INFO - Creating virtualenv /builds/slave/test/build/venv 13:07: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 13:07:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:07:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:07:09 INFO - Using real prefix '/usr' 13:07:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:07:09 INFO - Installing distribute.............................................................................................................................................................................................done. 13:07:11 INFO - Installing pip.................done. 13:07:11 INFO - Return code: 0 13:07:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:07: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')]} 13:07:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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')]} 13:07:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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 0x7f9b3fb63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994090>, '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 0x19979b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1997e40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', '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 13:07: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 13:07: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 13:07:11 INFO - Using env: {'DISPLAY': ':0', 13:07:11 INFO - 'HOME': '/home/cltbld', 13:07:11 INFO - 'LANG': 'en_US.UTF-8', 13:07:11 INFO - 'LANGUAGE': 'en_US:en', 13:07:11 INFO - 'LOGNAME': 'cltbld', 13:07:11 INFO - 'MAIL': '/var/mail/cltbld', 13:07:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:11 INFO - 'MOZ_NO_REMOTE': '1', 13:07:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:11 INFO - 'NO_EM_RESTART': '1', 13:07:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:11 INFO - 'PWD': '/builds/slave/test', 13:07:11 INFO - 'SHELL': '/bin/bash', 13:07:11 INFO - 'SHLVL': '1', 13:07:11 INFO - 'TERM': 'linux', 13:07:11 INFO - 'TMOUT': '86400', 13:07:11 INFO - 'USER': 'cltbld', 13:07:11 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', 13:07:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:11 INFO - '_': '/tools/buildbot/bin/python'} 13:07:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07: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 13:07:12 INFO - Cleaning up... 13:07:12 INFO - Return code: 0 13:07:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:07: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')]} 13:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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')]} 13:07:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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 0x7f9b3fb63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994090>, '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 0x19979b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1997e40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', '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 13:07: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 13:07: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 13:07:12 INFO - Using env: {'DISPLAY': ':0', 13:07:12 INFO - 'HOME': '/home/cltbld', 13:07:12 INFO - 'LANG': 'en_US.UTF-8', 13:07:12 INFO - 'LANGUAGE': 'en_US:en', 13:07:12 INFO - 'LOGNAME': 'cltbld', 13:07:12 INFO - 'MAIL': '/var/mail/cltbld', 13:07:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:12 INFO - 'MOZ_NO_REMOTE': '1', 13:07:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:12 INFO - 'NO_EM_RESTART': '1', 13:07:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:12 INFO - 'PWD': '/builds/slave/test', 13:07:12 INFO - 'SHELL': '/bin/bash', 13:07:12 INFO - 'SHLVL': '1', 13:07:12 INFO - 'TERM': 'linux', 13:07:12 INFO - 'TMOUT': '86400', 13:07:12 INFO - 'USER': 'cltbld', 13:07:12 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', 13:07:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:12 INFO - '_': '/tools/buildbot/bin/python'} 13:07:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07:12 INFO - Downloading/unpacking psutil>=3.1.1 13:07: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 13:07: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 13:07: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 13:07: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 13:07: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 13:07: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 13:07:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:07:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:07:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:07:14 INFO - Installing collected packages: psutil 13:07:14 INFO - Running setup.py install for psutil 13:07:14 INFO - building 'psutil._psutil_linux' extension 13:07: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 13:07: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 13:07:14 INFO - building 'psutil._psutil_posix' extension 13:07: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 13:07: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 13:07:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:07:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:07:14 INFO - Successfully installed psutil 13:07:14 INFO - Cleaning up... 13:07:14 INFO - Return code: 0 13:07:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:07: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')]} 13:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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')]} 13:07:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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 0x7f9b3fb63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994090>, '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 0x19979b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1997e40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', '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 13:07: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 13:07: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 13:07:14 INFO - Using env: {'DISPLAY': ':0', 13:07:14 INFO - 'HOME': '/home/cltbld', 13:07:14 INFO - 'LANG': 'en_US.UTF-8', 13:07:14 INFO - 'LANGUAGE': 'en_US:en', 13:07:14 INFO - 'LOGNAME': 'cltbld', 13:07:14 INFO - 'MAIL': '/var/mail/cltbld', 13:07:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:14 INFO - 'MOZ_NO_REMOTE': '1', 13:07:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:14 INFO - 'NO_EM_RESTART': '1', 13:07:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:14 INFO - 'PWD': '/builds/slave/test', 13:07:14 INFO - 'SHELL': '/bin/bash', 13:07:14 INFO - 'SHLVL': '1', 13:07:14 INFO - 'TERM': 'linux', 13:07:14 INFO - 'TMOUT': '86400', 13:07:14 INFO - 'USER': 'cltbld', 13:07:14 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', 13:07:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:14 INFO - '_': '/tools/buildbot/bin/python'} 13:07:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:07:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:07:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:07:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:07:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:07:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:07:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:07:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:07:16 INFO - Installing collected packages: mozsystemmonitor 13:07:16 INFO - Running setup.py install for mozsystemmonitor 13:07:17 INFO - Successfully installed mozsystemmonitor 13:07:17 INFO - Cleaning up... 13:07:17 INFO - Return code: 0 13:07:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:07: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')]} 13:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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')]} 13:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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 0x7f9b3fb63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994090>, '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 0x19979b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1997e40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', '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 13:07: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 13:07: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 13:07:17 INFO - Using env: {'DISPLAY': ':0', 13:07:17 INFO - 'HOME': '/home/cltbld', 13:07:17 INFO - 'LANG': 'en_US.UTF-8', 13:07:17 INFO - 'LANGUAGE': 'en_US:en', 13:07:17 INFO - 'LOGNAME': 'cltbld', 13:07:17 INFO - 'MAIL': '/var/mail/cltbld', 13:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:17 INFO - 'MOZ_NO_REMOTE': '1', 13:07:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:17 INFO - 'NO_EM_RESTART': '1', 13:07:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:17 INFO - 'PWD': '/builds/slave/test', 13:07:17 INFO - 'SHELL': '/bin/bash', 13:07:17 INFO - 'SHLVL': '1', 13:07:17 INFO - 'TERM': 'linux', 13:07:17 INFO - 'TMOUT': '86400', 13:07:17 INFO - 'USER': 'cltbld', 13:07:17 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', 13:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:17 INFO - '_': '/tools/buildbot/bin/python'} 13:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07:17 INFO - Downloading/unpacking jsonschema==2.5.1 13:07: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 13:07: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 13:07: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 13:07: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 13:07: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 13:07: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 13:07:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:07:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:07: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 13:07: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 13:07: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 13:07: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 13:07: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 13:07: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 13:07:19 INFO - Downloading functools32-3.2.3-2.tar.gz 13:07:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:07:19 INFO - warning: no files found matching '*.txt' 13:07:19 INFO - no previously-included directories found matching 'build' 13:07:19 INFO - no previously-included directories found matching 'dist' 13:07:19 INFO - no previously-included directories found matching '.git*' 13:07:19 INFO - Installing collected packages: jsonschema, functools32 13:07:19 INFO - Running setup.py install for functools32 13:07:19 INFO - warning: no files found matching '*.txt' 13:07:19 INFO - no previously-included directories found matching 'build' 13:07:19 INFO - no previously-included directories found matching 'dist' 13:07:19 INFO - no previously-included directories found matching '.git*' 13:07:19 INFO - Successfully installed jsonschema functools32 13:07:19 INFO - Cleaning up... 13:07:19 INFO - Return code: 0 13:07:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:07: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')]} 13:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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')]} 13:07:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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 0x7f9b3fb63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994090>, '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 0x19979b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1997e40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', '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 13:07: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 13:07: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 13:07:19 INFO - Using env: {'DISPLAY': ':0', 13:07:19 INFO - 'HOME': '/home/cltbld', 13:07:19 INFO - 'LANG': 'en_US.UTF-8', 13:07:19 INFO - 'LANGUAGE': 'en_US:en', 13:07:19 INFO - 'LOGNAME': 'cltbld', 13:07:19 INFO - 'MAIL': '/var/mail/cltbld', 13:07:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:19 INFO - 'MOZ_NO_REMOTE': '1', 13:07:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:19 INFO - 'NO_EM_RESTART': '1', 13:07:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:19 INFO - 'PWD': '/builds/slave/test', 13:07:19 INFO - 'SHELL': '/bin/bash', 13:07:19 INFO - 'SHLVL': '1', 13:07:19 INFO - 'TERM': 'linux', 13:07:19 INFO - 'TMOUT': '86400', 13:07:19 INFO - 'USER': 'cltbld', 13:07:19 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', 13:07:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:19 INFO - '_': '/tools/buildbot/bin/python'} 13:07:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:07:19 INFO - Cleaning up... 13:07:19 INFO - Return code: 0 13:07:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:07: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')]} 13:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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')]} 13:07:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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 0x7f9b3fb63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994090>, '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 0x19979b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1997e40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', '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 13:07: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 13:07: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 13:07:19 INFO - Using env: {'DISPLAY': ':0', 13:07:19 INFO - 'HOME': '/home/cltbld', 13:07:19 INFO - 'LANG': 'en_US.UTF-8', 13:07:19 INFO - 'LANGUAGE': 'en_US:en', 13:07:19 INFO - 'LOGNAME': 'cltbld', 13:07:19 INFO - 'MAIL': '/var/mail/cltbld', 13:07:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:19 INFO - 'MOZ_NO_REMOTE': '1', 13:07:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:19 INFO - 'NO_EM_RESTART': '1', 13:07:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:19 INFO - 'PWD': '/builds/slave/test', 13:07:19 INFO - 'SHELL': '/bin/bash', 13:07:19 INFO - 'SHLVL': '1', 13:07:19 INFO - 'TERM': 'linux', 13:07:19 INFO - 'TMOUT': '86400', 13:07:19 INFO - 'USER': 'cltbld', 13:07:19 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', 13:07:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:19 INFO - '_': '/tools/buildbot/bin/python'} 13:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07:20 INFO - Downloading/unpacking blobuploader==1.2.4 13:07: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 13:07: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 13:07: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 13:07: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 13:07: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 13:07: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 13:07:21 INFO - Downloading blobuploader-1.2.4.tar.gz 13:07:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:07:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:07: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 13:07: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 13:07: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 13:07: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 13:07: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 13:07: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 13:07:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:07:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:07: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 13:07: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 13:07: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 13:07: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 13:07: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 13:07: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 13:07:22 INFO - Downloading docopt-0.6.1.tar.gz 13:07:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:07:22 INFO - Installing collected packages: blobuploader, requests, docopt 13:07:22 INFO - Running setup.py install for blobuploader 13:07:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:07:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:07:22 INFO - Running setup.py install for requests 13:07:23 INFO - Running setup.py install for docopt 13:07:23 INFO - Successfully installed blobuploader requests docopt 13:07:23 INFO - Cleaning up... 13:07:23 INFO - Return code: 0 13:07:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:07: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')]} 13:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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')]} 13:07:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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 0x7f9b3fb63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994090>, '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 0x19979b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1997e40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', '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 13:07: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 13:07: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 13:07:23 INFO - Using env: {'DISPLAY': ':0', 13:07:23 INFO - 'HOME': '/home/cltbld', 13:07:23 INFO - 'LANG': 'en_US.UTF-8', 13:07:23 INFO - 'LANGUAGE': 'en_US:en', 13:07:23 INFO - 'LOGNAME': 'cltbld', 13:07:23 INFO - 'MAIL': '/var/mail/cltbld', 13:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:23 INFO - 'MOZ_NO_REMOTE': '1', 13:07:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:23 INFO - 'NO_EM_RESTART': '1', 13:07:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:23 INFO - 'PWD': '/builds/slave/test', 13:07:23 INFO - 'SHELL': '/bin/bash', 13:07:23 INFO - 'SHLVL': '1', 13:07:23 INFO - 'TERM': 'linux', 13:07:23 INFO - 'TMOUT': '86400', 13:07:23 INFO - 'USER': 'cltbld', 13:07:23 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', 13:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:23 INFO - '_': '/tools/buildbot/bin/python'} 13:07:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:07:23 INFO - Running setup.py (path:/tmp/pip-zlG5eD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:07:23 INFO - Running setup.py (path:/tmp/pip-T8qlpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:07:23 INFO - Running setup.py (path:/tmp/pip-1zTgv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:07:23 INFO - Running setup.py (path:/tmp/pip-rKA7NG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:07:23 INFO - Running setup.py (path:/tmp/pip-Fuy9fl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:07:23 INFO - Running setup.py (path:/tmp/pip-0YkYnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:07:24 INFO - Running setup.py (path:/tmp/pip-Sy2zOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:07:24 INFO - Running setup.py (path:/tmp/pip-JFKXQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:07:24 INFO - Running setup.py (path:/tmp/pip-vNyxty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:07:24 INFO - Running setup.py (path:/tmp/pip-YJEoDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:07:24 INFO - Running setup.py (path:/tmp/pip-jN5bXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:07:24 INFO - Running setup.py (path:/tmp/pip-RPnYdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:07:24 INFO - Running setup.py (path:/tmp/pip-z0pu_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:07:24 INFO - Running setup.py (path:/tmp/pip-PkU4m7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:07:24 INFO - Running setup.py (path:/tmp/pip-7rf9SE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:07:24 INFO - Running setup.py (path:/tmp/pip-NotbAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:07:24 INFO - Running setup.py (path:/tmp/pip-9951R8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:07:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:07:24 INFO - Running setup.py install for manifestparser 13:07:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:07:24 INFO - Running setup.py install for mozcrash 13:07:24 INFO - Running setup.py install for mozdebug 13:07:25 INFO - Running setup.py install for mozdevice 13:07:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:07:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:07:25 INFO - Running setup.py install for mozfile 13:07:25 INFO - Running setup.py install for mozhttpd 13:07:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:07:25 INFO - Running setup.py install for mozinfo 13:07:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:07:25 INFO - Running setup.py install for mozInstall 13:07:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:07:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:07:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:07:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:07:25 INFO - Running setup.py install for mozleak 13:07:25 INFO - Running setup.py install for mozlog 13:07:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:07:25 INFO - Running setup.py install for moznetwork 13:07:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:07:25 INFO - Running setup.py install for mozprocess 13:07:26 INFO - Running setup.py install for mozprofile 13:07:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:07:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:07:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:07:26 INFO - Running setup.py install for mozrunner 13:07:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:07:26 INFO - Running setup.py install for mozscreenshot 13:07:26 INFO - Running setup.py install for moztest 13:07:26 INFO - Running setup.py install for mozversion 13:07:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:07:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:07:26 INFO - Cleaning up... 13:07:26 INFO - Return code: 0 13:07:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:07: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')]} 13:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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')]} 13:07:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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 0x7f9b3fb63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994090>, '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 0x19979b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1997e40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', '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 13:07: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 13:07: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 13:07:26 INFO - Using env: {'DISPLAY': ':0', 13:07:26 INFO - 'HOME': '/home/cltbld', 13:07:26 INFO - 'LANG': 'en_US.UTF-8', 13:07:26 INFO - 'LANGUAGE': 'en_US:en', 13:07:26 INFO - 'LOGNAME': 'cltbld', 13:07:26 INFO - 'MAIL': '/var/mail/cltbld', 13:07:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:26 INFO - 'MOZ_NO_REMOTE': '1', 13:07:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:26 INFO - 'NO_EM_RESTART': '1', 13:07:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:26 INFO - 'PWD': '/builds/slave/test', 13:07:26 INFO - 'SHELL': '/bin/bash', 13:07:26 INFO - 'SHLVL': '1', 13:07:26 INFO - 'TERM': 'linux', 13:07:26 INFO - 'TMOUT': '86400', 13:07:26 INFO - 'USER': 'cltbld', 13:07:26 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', 13:07:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:26 INFO - '_': '/tools/buildbot/bin/python'} 13:07:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:07:26 INFO - Running setup.py (path:/tmp/pip-Lhr3PU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:07: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)) 13:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:07:26 INFO - Running setup.py (path:/tmp/pip-nrRjZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:07:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:07:26 INFO - Running setup.py (path:/tmp/pip-kETs6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:07: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)) 13:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:07:27 INFO - Running setup.py (path:/tmp/pip-V4zxPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:07: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)) 13:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:07:27 INFO - Running setup.py (path:/tmp/pip-zHpoIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:07: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)) 13:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:07:27 INFO - Running setup.py (path:/tmp/pip-e4wqdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:07: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)) 13:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:07:27 INFO - Running setup.py (path:/tmp/pip-wVU6ms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:07: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)) 13:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:07:27 INFO - Running setup.py (path:/tmp/pip-sArOyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:07: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)) 13:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:07:27 INFO - Running setup.py (path:/tmp/pip-KCVSUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:07: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)) 13:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:07:27 INFO - Running setup.py (path:/tmp/pip-ghajZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:07: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)) 13:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:07:27 INFO - Running setup.py (path:/tmp/pip-sdSFd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:07: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)) 13:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:07:27 INFO - Running setup.py (path:/tmp/pip-X4TVsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:07: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)) 13:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:07:27 INFO - Running setup.py (path:/tmp/pip-d0RAG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:07: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)) 13:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:07:27 INFO - Running setup.py (path:/tmp/pip-WVdlIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:07: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)) 13:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:07:27 INFO - Running setup.py (path:/tmp/pip-GNmASN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:07:27 INFO - Running setup.py (path:/tmp/pip-WlIJzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:07:27 INFO - Running setup.py (path:/tmp/pip-Kt9IDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:07: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)) 13:07: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)) 13:07: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)) 13:07: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)) 13:07: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)) 13:07: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)) 13:07:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:07: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 13:07: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 13:07: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 13:07: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 13:07: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 13:07: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 13:07:29 INFO - Downloading blessings-1.6.tar.gz 13:07:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:07:30 INFO - Installing collected packages: blessings 13:07:30 INFO - Running setup.py install for blessings 13:07:30 INFO - Successfully installed blessings 13:07:30 INFO - Cleaning up... 13:07:30 INFO - Return code: 0 13:07:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:07:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:07:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:07:30 INFO - Reading from file tmpfile_stdout 13:07:30 INFO - Current package versions: 13:07:30 INFO - argparse == 1.2.1 13:07:30 INFO - blessings == 1.6 13:07:30 INFO - blobuploader == 1.2.4 13:07:30 INFO - docopt == 0.6.1 13:07:30 INFO - functools32 == 3.2.3-2 13:07:30 INFO - jsonschema == 2.5.1 13:07:30 INFO - manifestparser == 1.1 13:07:30 INFO - mozInstall == 1.12 13:07:30 INFO - mozcrash == 0.17 13:07:30 INFO - mozdebug == 0.1 13:07:30 INFO - mozdevice == 0.48 13:07:30 INFO - mozfile == 1.2 13:07:30 INFO - mozhttpd == 0.7 13:07:30 INFO - mozinfo == 0.9 13:07:30 INFO - mozleak == 0.1 13:07:30 INFO - mozlog == 3.3 13:07:30 INFO - moznetwork == 0.27 13:07:30 INFO - mozprocess == 0.23 13:07:30 INFO - mozprofile == 0.28 13:07:30 INFO - mozrunner == 6.12 13:07:30 INFO - mozscreenshot == 0.1 13:07:30 INFO - mozsystemmonitor == 0.3 13:07:30 INFO - moztest == 0.7 13:07:30 INFO - mozversion == 1.4 13:07:30 INFO - psutil == 3.1.1 13:07:30 INFO - requests == 1.2.3 13:07:30 INFO - wsgiref == 0.1.2 13:07:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:07: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')]} 13:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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')]} 13:07:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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 0x7f9b3fb63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994090>, '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 0x19979b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1997e40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', '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 13:07: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 13:07: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 13:07:30 INFO - Using env: {'DISPLAY': ':0', 13:07:30 INFO - 'HOME': '/home/cltbld', 13:07:30 INFO - 'LANG': 'en_US.UTF-8', 13:07:30 INFO - 'LANGUAGE': 'en_US:en', 13:07:30 INFO - 'LOGNAME': 'cltbld', 13:07:30 INFO - 'MAIL': '/var/mail/cltbld', 13:07:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:30 INFO - 'MOZ_NO_REMOTE': '1', 13:07:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:30 INFO - 'NO_EM_RESTART': '1', 13:07:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:30 INFO - 'PWD': '/builds/slave/test', 13:07:30 INFO - 'SHELL': '/bin/bash', 13:07:30 INFO - 'SHLVL': '1', 13:07:30 INFO - 'TERM': 'linux', 13:07:30 INFO - 'TMOUT': '86400', 13:07:30 INFO - 'USER': 'cltbld', 13:07:30 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', 13:07:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:30 INFO - '_': '/tools/buildbot/bin/python'} 13:07:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07: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)) 13:07: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)) 13:07: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)) 13:07: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)) 13:07: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)) 13:07: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)) 13:07: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)) 13:07: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)) 13:07: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)) 13:07: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)) 13:07: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)) 13:07:30 INFO - Cleaning up... 13:07:30 INFO - Return code: 0 13:07:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:07: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')]} 13:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:07:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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')]} 13:07:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:07:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:07: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 0x7f9b3fb63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x199a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1994090>, '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 0x19979b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1997e40>, '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': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', '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 13:07: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 13:07: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 13:07:30 INFO - Using env: {'DISPLAY': ':0', 13:07:30 INFO - 'HOME': '/home/cltbld', 13:07:30 INFO - 'LANG': 'en_US.UTF-8', 13:07:30 INFO - 'LANGUAGE': 'en_US:en', 13:07:30 INFO - 'LOGNAME': 'cltbld', 13:07:30 INFO - 'MAIL': '/var/mail/cltbld', 13:07:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:30 INFO - 'MOZ_NO_REMOTE': '1', 13:07:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:30 INFO - 'NO_EM_RESTART': '1', 13:07:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:30 INFO - 'PWD': '/builds/slave/test', 13:07:30 INFO - 'SHELL': '/bin/bash', 13:07:30 INFO - 'SHLVL': '1', 13:07:30 INFO - 'TERM': 'linux', 13:07:30 INFO - 'TMOUT': '86400', 13:07:30 INFO - 'USER': 'cltbld', 13:07:30 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', 13:07:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:30 INFO - '_': '/tools/buildbot/bin/python'} 13:07:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:07:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:07:30 INFO - Cleaning up... 13:07:30 INFO - Return code: 0 13:07:30 INFO - Running post-action listener: _resource_record_post_action 13:07:30 INFO - Running post-action listener: _start_resource_monitoring 13:07:30 INFO - Starting resource monitoring. 13:07:30 INFO - [mozharness: 2016-09-02 20:07:30.874046Z] Finished create-virtualenv step (success) 13:07:30 INFO - [mozharness: 2016-09-02 20:07:30.874510Z] Running install step. 13:07:30 INFO - Running pre-action listener: _resource_record_pre_action 13:07:30 INFO - Running main action method: install 13:07:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:07:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:07:31 INFO - Reading from file tmpfile_stdout 13:07:31 INFO - Detecting whether we're running mozinstall >=1.0... 13:07:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:07:31 INFO - Reading from file tmpfile_stdout 13:07:31 INFO - Output received: 13:07:31 INFO - Usage: mozinstall [options] installer 13:07:31 INFO - Options: 13:07:31 INFO - -h, --help show this help message and exit 13:07:31 INFO - -d DEST, --destination=DEST 13:07:31 INFO - Directory to install application into. [default: 13:07:31 INFO - "/builds/slave/test"] 13:07:31 INFO - --app=APP Application being installed. [default: firefox] 13:07:31 INFO - mkdir: /builds/slave/test/build/application 13:07: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'] 13:07: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 13:07:47 INFO - Reading from file tmpfile_stdout 13:07:47 INFO - Output received: 13:07:47 INFO - /builds/slave/test/build/application/firefox/firefox 13:07:47 INFO - Running post-action listener: _resource_record_post_action 13:07:47 INFO - [mozharness: 2016-09-02 20:07:47.311548Z] Finished install step (success) 13:07:47 INFO - [mozharness: 2016-09-02 20:07:47.311834Z] Running run-tests step. 13:07:47 INFO - Running pre-action listener: _resource_record_pre_action 13:07:47 INFO - Running main action method: run_tests 13:07:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:07:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:07:47 INFO - Python 2.7.3 13:07:47 INFO - Return code: 0 13:07:47 INFO - grabbing minidump binary from tooltool 13:07:47 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')]} 13:07:47 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 0x1994090>, '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 0x19979b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1997e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:07:47 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 13:07:47 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 13:07:47 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 13:07:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:07:47 INFO - Return code: 0 13:07:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:07:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:07:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:07:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:07:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:07:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 13:07:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 13:07:47 INFO - Using env: {'DISPLAY': ':0', 13:07:47 INFO - 'HOME': '/home/cltbld', 13:07:47 INFO - 'LANG': 'en_US.UTF-8', 13:07:47 INFO - 'LANGUAGE': 'en_US:en', 13:07:47 INFO - 'LOGNAME': 'cltbld', 13:07:47 INFO - 'MAIL': '/var/mail/cltbld', 13:07:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:07:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:07:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:47 INFO - 'MOZ_NO_REMOTE': '1', 13:07:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:07:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:47 INFO - 'NO_EM_RESTART': '1', 13:07:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:47 INFO - 'PWD': '/builds/slave/test', 13:07:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:07:47 INFO - 'SHELL': '/bin/bash', 13:07:47 INFO - 'SHLVL': '1', 13:07:47 INFO - 'TERM': 'linux', 13:07:47 INFO - 'TMOUT': '86400', 13:07:47 INFO - 'USER': 'cltbld', 13:07:47 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217', 13:07:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:47 INFO - '_': '/tools/buildbot/bin/python'} 13:07:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 13:07:47 INFO - mozversion application_buildid: 20160902113848 13:07:47 INFO - mozversion application_changeset: aefced02ac1e5ad12bb81e6e252ec1c2c031363c 13:07:47 INFO - mozversion application_display_name: Nightly 13:07:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:07:47 INFO - mozversion application_name: Firefox 13:07:47 INFO - mozversion application_remotingname: firefox 13:07:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 13:07:47 INFO - mozversion application_vendor: Mozilla 13:07:47 INFO - mozversion application_version: 51.0a1 13:07:47 INFO - mozversion platform_buildid: 20160902113848 13:07:47 INFO - mozversion platform_changeset: aefced02ac1e5ad12bb81e6e252ec1c2c031363c 13:07:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 13:07:47 INFO - mozversion platform_version: 51.0a1 13:07:47 INFO - using testdate: 1472846867 13:07:47 INFO - actual date: 1472846867 13:07:47 INFO - starting webserver on 'localhost:53918' 13:07:47 INFO - SUITE-START | Running 2 tests 13:07:47 INFO - TEST-START | dromaeo_css 13:07:47 INFO - operating with platform_type : linux_ 13:07:47 INFO - Initialising browser for dromaeo_css test... 13:07:47 INFO - TEST-INFO | started process 19084 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUtMPHG/profile http://localhost:53918/getInfo.html) 13:07:52 INFO - PROCESS | 19084 | __metrics Screen width/height:1600/1200 13:07:52 INFO - PROCESS | 19084 | colorDepth:24 13:07:52 INFO - PROCESS | 19084 | Browser inner width/height: 1024/697 13:07:52 INFO - PROCESS | 19084 | __metrics 13:07:53 INFO - PROCESS | 19084 | 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" 13:07:53 INFO - PROCESS | 19084 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:07:54 INFO - PROCESS | 19084 | console.error: PushService: 13:07:54 INFO - PROCESS | 19084 | stateChangeProcessEnqueue: Error transitioning state 13:07:54 INFO - PROCESS | 19084 | UnknownError 13:07:54 INFO - TEST-INFO | 19084: exit 0 13:07:54 INFO - Browser initialized. 13:07:54 INFO - Running cycle 1/1 for dromaeo_css test... 13:07:54 INFO - TEST-INFO | started process 19165 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUtMPHG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:08:07 INFO - PROCESS | 19165 | RSS: Main: 181030912 13:08:07 INFO - PROCESS | 19165 | 13:08:58 INFO - PROCESS | 19165 | 1472846938270 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] 13:11:12 INFO - PROCESS | 19165 | Cycle 1(1): loaded http://localhost:53918/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53918/tests/dromaeo/cssquery-ext.html) 13:11:13 INFO - PROCESS | 19165 | RSS: Main: 285634560 13:11:13 INFO - PROCESS | 19165 | 13:14:15 INFO - PROCESS | 19165 | Cycle 1(1): loaded http://localhost:53918/tests/dromaeo/cssquery-ext.html (next: http://localhost:53918/tests/dromaeo/cssquery-jquery.html) 13:14:16 INFO - PROCESS | 19165 | RSS: Main: 271618048 13:14:16 INFO - PROCESS | 19165 | 13:17:18 INFO - PROCESS | 19165 | Cycle 1(1): loaded http://localhost:53918/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53918/tests/dromaeo/cssquery-mootools.html) 13:17:19 INFO - PROCESS | 19165 | RSS: Main: 249659392 13:17:19 INFO - PROCESS | 19165 | 13:20:21 INFO - PROCESS | 19165 | Cycle 1(1): loaded http://localhost:53918/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53918/tests/dromaeo/cssquery-prototype.html) 13:20:22 INFO - PROCESS | 19165 | RSS: Main: 218677248 13:20:22 INFO - PROCESS | 19165 | 13:23:25 INFO - PROCESS | 19165 | Cycle 1(1): loaded http://localhost:53918/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53918/tests/dromaeo/cssquery-yui.html) 13:23:25 INFO - PROCESS | 19165 | RSS: Main: 219201536 13:23:25 INFO - PROCESS | 19165 | 13:26:28 INFO - PROCESS | 19165 | Cycle 1(1): loaded http://localhost:53918/tests/dromaeo/cssquery-yui.html (next: http://localhost:53918/tests/dromaeo/cssquery-dojo.html) 13:26:29 INFO - PROCESS | 19165 | RSS: Main: 309583872 13:26:29 INFO - PROCESS | 19165 | 13:26:29 INFO - PROCESS | 19165 | __start_tp_report 13:26:29 INFO - PROCESS | 19165 | _x_x_mozilla_page_load 13:26:29 INFO - PROCESS | 19165 | _x_x_mozilla_page_load_details 13:26:29 INFO - PROCESS | 19165 | |i|pagename|runs| 13:26:29 INFO - PROCESS | 19165 | |0;dojo.html;2589.824547380201;2606.4265861510466;2619.436821083467;2660.3482146874017;2671.7461841125087;4698.671093023481;4727.479977202505;4736.59738922192;4791.7604119794005;4873.7806798694055;4783.545563171493;4789.688670236432;4822.300766388871;4825.78283977221;4848.660593758438;4355.956440435602;4377.737335759849;4442.800073996662;4512.300593408027;4570.657200709943;4480.215962206617;4517.661175411841;4534.909301813962;4555.385023021883;4566.6575006874455;4285.185814695198;4308.504521979981;4327.870163895088;4382.846600368972;4424.115176964588;4332.458442694663;4351.238533256699;4360.716553424018;4397.494288156871;4417.094495628388;4300.333448315485;4370.38814565961;4379.540148284448;4380.277254253045;4384.467307016655;4660.603848672867;4793.856184314476;4796.616270698336;4797.30439086334;4804.567588917014;5085.415177254589;5101.974490127563;5120.052790233819;5131.871703207413;5142.125838607409;5143.665661731976;5146.974265128688;5150.922736158923;5169.224616307546;5180.689158650455;4652.325412815123;4734.692245004064;4786.282057691375;4787.305840653085;4793.448753393404;5219.712915789668;5226.843194704165;5243.9213411798855;5304.628675992721;5315.627906046617;5017.096922553976;5026.723530205873;5027.622928280393;5028.120078986163;5119.129747942859;4946.08497427977;5082.720450375191;5109.821156259513;5111.361079865017;5120.052790233819;5088.567471764942;5114.43741188469;5115;5171.974140129275;5173.896522069613;5075.289459475676;5090.41460232078;5090.858717538926;5096.337476128154;5156.4070131935305;4164;4189.350650649154;4296.570342965679;4313.547077556874;4357.302831546937;3773.1699026214187;3790.223004284115;3797.145642230476;3845.2309538091936;3898.2983063048355;4487.573680500347;4488.483824360241;4539.636829053734;4542.704724192917;4560.17916774977;4984.850454486371;5235.921461178085;5239.816606418757;5249.238860365302;5252.947470525322;4381.978090109593;4403.053343531156;4406.206882761134;4427.291633338715;4482.260427029569;4550.795214215368;4577.633789296849;4579.473360563511;4580.587747102777;4639.884002899887;4655.6973796703105;4822.252550854623;4832.323474713613;4833.4441539223435;4882.609391248691;5158.896822063462;5189.299444574934;5189.377274726906;5195.584353251656;5278.4721527846905;4877.731724755173;4888.2178851383605;4905.337779399735;4908.9754551227015;4959.057779021974;4945.23348880924;5125.974370128126;5132.255822905732;5139.049275883974;5141.2288156776785;5054.59563234933;5082.669626474268;5091.71995540234;5101.20931255656;5140.3831540215415;4969.82605608802;4972.105021096093;4975.402951645826;4976.477469865612;5033.421156566895;4870.732109733999;4953.18272485036;4981.900361992813;4982.377202849644;5044.293798868235;4977.726225057657;5020.673656212334;5022.045811295842;5026.698398096126;5035.395752509722;5134.332536770196;5135.3324067870935;5147.597457878829;5147.819826306061;5200.5059519345605;4725.031368569504;4764.78558464863;4956.702597844141;4966.776495057803;4969.652124351261 13:26:29 INFO - PROCESS | 19165 | |1;ext.html;515.5591968866619;522.2218893848162;527.6543863769232;537.5726297593413;537.8547792096134;24155.154569590068;32045.679543204562;33349.66650333499;33376.66623333772;36267.637323626754;33906.66093339066;34000.149996250104;34238.65761342386;34292.65707342926;34349.31301373971;7032.085828842269;7420.257974202591;7544.4718869679145;7557.886631700529;7559.395248380117;9257.6296948122;9499;9507.429554226768;9729.416235025885;9731.708048758548;9635.566399512003;9734.707958761248;9744;9744.269179811507;9746.51267436629;9498.050194980447;9519.762005949837;9563;9571.856422153673;9579.23366130715;9389.953050234773;9454.101860323259;9461.621535138585;9480.383775054599;9483.573239204252;31553;31863;31891.6810831891;31894.202644934063;31943.361132777445;2389.390705370137;2482.056818408993;2493.9750602493923;2502.5745623244093;2781.5271403861393;24995;25088.749112508824;25344.49311013788;25532.36169095769;26195.345116372053;3414.6243913169533;3481.4603736420895;3491.3366460372704;3498.2303893143467;3573.8213089345427;179995;180095;180327;180440;181350;41630.79184604058;41878.581214188074;41970.16059678789;41980.37029444561;42011.579884200764;28484.71515284862;29150.12549623515;29234.122976310744;43932;44003.559964399945;12097.879021209676;12178.330191839357;12178.695532611755;12188.939055304843;12198.51205951752;9388.95305523468;9641.951790241144;9684.176844968115;9806.75483112928;9924.255680823968;14404.279786010868;14511.492097776527;14518.128912265087;14618.853811461962;14678.706425871424;5380.354357477049;5385.192221166778;5393;5454.672719636835;5470.316210473691;641.2369280556123;664.6012392564519;755.6372940988247;764.9579273140031;808.1998821167002;23133.30600082;24519;24524.50950981006;24679.62980555293;24686.876565617058;783.3733013589106;809.874469457235;811.1401913971091;816.1634324816991;863.5380071661631;11654.242474239147;12615;12660.493580256687;12870.806937895939;12889.162204456681;413165;413386;413607;414159;414516;38565.22869542649;38952.61047389547;38967.22065558673;38976.6102338973;38981.61018389837;916.0793402630314;959.5490119643756;1009.914157296638;1012.589901090045;1013.1590779652723;24108.041327501465;24689.629655555542;25952.61071083973;26044.479110417305;26191.083312083985;34840;35331.29337413289;35352.469712954844;35391.292174156886;35392.646073538934;321775;322404;323459;324060;385320;30461.695383045884;32611.836940815145;32644.34711305713;32954.727243641864;33223.66776332206;34870.12824679403;35031.64968350284;35174.29651407008;35463.290734185684;36358.454623181206;30197;30857.228569285948;30952;30987.380252394374;31331.53002705007;30348;30783;31006.37987240288;31171.84414077915;31956;30115.397692046474;30342.393152137276;30646.540301895042;30793.538096928114;30933;26873;26903.730962690122;28808.135755927357;29136.27159321034;29174.854125729235;22030.88984555067;22058.33824985253;22406.439839003502;22602.434939126655;22698.886505567367 13:26:29 INFO - PROCESS | 19165 | |2;jquery.html;331.8058935522719;338.6478062814673;346.76766566400516;355.0856544398175;357.751362802852;11790.941045294772;14270.143791372511;15027.248637568133;15121.6975660487;16438.342466301332;14163.716725665507;14217;14238.145711257332;14245.786313205284;14482.348294326754;4799.13615549201;5020.046191223656;5022.648414610979;5023.321851550048;5024.522670346311;4895.41877375146;4906.656534042619;5021.673591216578;5039.470855560168;5045.142325804603;5018.021485810268;5020.3724534433195;5021.447640759514;5025.422076461199;5026.246063090547;4784.928226076611;4904.950950490485;4929.704217746911;4932.753362331869;5007.824726134586;4452.910941781146;4455.596487106551;4456.532064133252;4456.532064133252;4456.754878481682;18534.81465185358;19142.61714765697;19178.328758493524;19324.613507729766;19332.42002739906;1794.47063116381;2193.605151072806;2418.6130219164847;2443.1571107967725;2459.0409740201335;12059.155859089868;12231;12419.44112514939;13119.344032798323;13151.53969611059;1666.8583170430527;1680.973709252332;1701.3534856754477;1702.5232934778157;1708.453294945618;70503.64748176277;71231;71577;72005;72079.63960180218;9032.593533291018;9082.591283392263;9368.484733339732;9485.762855928657;9788.070133337322;12855.550055748005;12868.549600763929;12885.61343159688;12890.097693161577;12895.226286422845;6679.532432729662;6866.485013623998;7023.227444981048;7025.964870175617;7036.296370362923;6418.293987661354;6462.289148193695;6505.772297969643;6603.867922641521;6642.7342906284175;5598.608097433222;5637.689927054052;5709.34342550612;5714.542836573064;5747;1617.4444377882917;1697.804752453459;1705.5395043338228;1709.4615196213153;1730.6279149982563;738.5605564689001;764.411403219529;782.5774081995659;817.5666896544841;824.4229039672254;14527.92736036313;14826.481073162387;15201.163935983626;15449.5365139046;16163.191840407933;746.8692978728701;762.5462849604477;779.3609240422912;792.3304807437706;798.4690181029666;8577.571121443903;8675.21923026918;9156.954215228881;9160.6793762218;9389.859152112724;363405;375128;376744;378481;379114;42497.13802946465;43660.345094822966;67945.32054679489;70677.64661176661;74449.6277518609;792.4452882981935;807.9394045446497;811.0429692962362;811.8741595052894;812.7480481050893;14285.500007499688;14306.213158276394;14550.563483095524;14575.927120364331;15112.471063512725;28003.579946300415;29942.011597680117;34883.30233395369;35868.28263434769;37285.0678733023;44151.77924110487;45245.54754452545;45251.54748452473;45433.77283113696;45461.31808022948;7446.590437525988;7538.924610753822;7581.658825352983;7589.1651918290045;7601.885971710318;9760.658376957057;10992.780144396907;11163.553457861595;11269.549218031187;11746.177767556532;9786.217102631916;9992.500374981368;10002.299839011199;10200.489975501343;10321.74195645115;9825.55784989663;9839.163671087752;10021.448820314952;10057.899421005988;10134.986501349806;9637.37357071788;9684.85472717924;9842.606295748375;9873.703788886345;10061.491890163725;7186.7125314986815;7419;7441.590712510864;7486;7817.218278172138;8573.657053717781;9368.484733339732;9393.530323483936;9722.75693107678;9788.265880059167 13:26:29 INFO - PROCESS | 19165 | |3;mootools.html;1064.680595821254;1065.6802959112267;1086.4676308608784;1130.7003644034332;1195.5994741761515;7327.780166595004;7489.550626962386;7542.736004239852;7543.094828620573;7863;7670.194629563885;7758.8448231035345;7768.533887966726;7769.61151942402;7775.1447340792465;2195.549912267981;2224.243757122578;2228.6099932511825;2232.475368288451;2232.966505502419;2167.869927804337;2202.944926376845;2218.245796429214;2228.1533017453344;2232.0290673557056;2185.704929834476;2197.703310053146;2210.1490925993526;2213.55728854229;2215.7562668106493;2161.870287782738;2164.188429338998;2165.621016322153;2167.826573874086;2167.8916054197225;2109.947251318714;2144.345974477784;2145.957080858374;2146.034284571949;2147.699322094908;7685.538867667958;7696.9615151924445;7756.146823849372;7757.379409647215;7772.650230739574;4263.97868010661;4442.2226110430865;4443.066955939256;4450.399196108517;4453.51011388747;2850.4156647887135;2965.747911427531;3025.1378357168323;3033.1810411189076;3044.421559903611;896.2650626486219;900.2213085680839;907.732218995398;914.3325372478129;918.2929144558719;10661.78676426467;10708.51811668469;10736.892631073668;10741.83887241692;10761.515731792166;3806.333891568964;4007.2987227235117;4109.239790638741;4146.771927544014;4161.146964872194;8227;8239.95880020596;8244.54654993981;8250.133735957757;8255.339572834158;4456.286994080996;4457.108578284357;4468.329750537446;4468.55314468557;4469.73181609098;3747.2318174774105;4097.692673049533;4143.937840932388;4167.812448439828;4212.7472351659;3261.7931365394957;3328.4008878401637;3419.076849250688;3436;3478.513008178857;1017.7048655889857;1030.5362586835954;1055.7518983038979;1061.6337363609543;1065.717584840018;602.208096353294;604.3412680178626;711.0862541633976;712.291270186168;735.5108852612952;4975.353204083518;5057.216131499624;5076.593872490192;5154.639175257696;5171.2501687255135;700.3346820520526;714.7891372045259;747.1594456236734;758.2606958215806;761.4974117082699;3264.2981758921946;3268.362669279468;3369.7809642373168;3453.4647129694936;3574.7855128692363;9360.578773955189;9759.902400976041;9763.121319081316;9768.316217864682;9774.12032917041;9275.16523512887;9525.714228573153;9736.9026309736;9742.9025709742;9748.366356186825;6881.8967715484305;7074.575525468488;7079.89380159298;7085.468589855678;7085.822854428578;5085.6185786066935;5248.895022099613;5312.9734351328;5322.09524380864;5453.454654534515;8733.606987685442;9002.684906028258;9136;9297.72106836796;9302.767430814283;8779.868301975603;8899.110088991049;8960.731178064669;9233.261339092722;9281.628734850801;6018.608790428608;6413.326600706859;6696.698648560729;6899.6205208712;6901.206361268519;7301.598412087386;7678.424118191047;7681.539107653559;7692.0384951881015;7732.265434783687;6648.5678430901835;6656;6714.328567143247;6742.42689371409;6961.582305061713;6639.53523253387;6757.729690812509;6846.3153684633135;6926.480513961574;6936.167659880846;6183.752649893954;6273.62358258506;6284.465820405317;6285.905711414241;6289.339619339903;4265.637420819266;4307.418498502662;4532.478764941942;4606.055758569418;4623.190941585141;4365.650747940221;4443.266860967904;4534;4554.2257816172;4640.744759038285 13:26:29 INFO - PROCESS | 19165 | |4;prototype.html;285.56166284752896;285.61156826715666;293.186407718581;295.1794012644848;295.85059544929834;4840.192688240821;4862.756862156887;4877.829275975341;4889.6064621582855;4895.559399654035;4783.234682450809;4798.328234047236;4836;4836.758162091899;4843.975780121094;3598.0824889653168;3686.7972261525606;3693.722970777189;3714.4614030965495;3739.2708421857747;3626.8911932642063;3768.2651882882847;3786.33401303427;3789.7347185697013;3813.3898576227807;3728.9254214915686;3734.7759134451885;3736.364817980936;3757.455169000507;3806.3148633246005;3578.4632305154173;3755.830987605537;3760.435934609803;3765.755225910307;3782.508273924386;3308.751843611715;3314.4034073866696;3318.1372843060735;3323.717484013862;3345.3142105868237;4649.604783593365;4671.649626278043;4676.953230467686;4688.953110468886;4701.78841952113;4338.175746608166;4346.1525002624185;4351.97824010878;4364;4382.758948257844;4979.626528010377;5055.747212639353;5065.46812584677;5069.163588007974;5097.2608971699265;3609.458581212813;3630.382834918044;3632.146445585285;3639.1084184375;3639.3631114554846;5251.947480525184;5272.182811664198;5276.604254680876;5290.206469029637;5297.496737809864;3629.709623230188;3641.253543023674;3694.833732482045;3722.646348596879;3734.663880250736;3759.1353988582778;3760.3231418344963;3761.3793724035777;3767.6420740029653;3768.9057773555332;4239.745615263094;4267.615914567704;4272.914541709149;4308.267594508941;4321.524632290446;4861.51384861511;5035.949640503622;5042.294078828967;5063.594912406998;5100.897982040338;1279.6736832107754;1299.9805002924963;1300.174389262825;1301.9283939383236;1310.2138716770053;839.7648658375665;840.7940054686641;857.4940784936917;862.7109918177481;870.2907130688595;4360.71655342408;4458.554144585581;4461.776911154494;4462.553744625577;4479.529649386767;5093.23601459784;5195.48045195485;5197.518707168439;5210.557102646318;5229.08491014071;4483.237849565582;4496.303073023687;4500.257457519538;4500.504944456107;4515.796789144496;2609.6038619338565;2732.570623867034;2747.409306999005;2750.0787236275673;2770.891643342639;4902.240152776325;4903.092927808367;4914.852554423373;4915.410150781929;4931.876703082452;5135.35529202461;5314.441983591744;5321;5334.626576139633;5346.679199248058;4863.076015557032;4865.489123641966;4896.290037944407;4915.58217551505;4925.482824303396;5188.299579556711;5192.24712416705;5199.584033277404;5199.792008319776;5204.9739751301;4974.696887192625;5083.618728595444;5107.668001579886;5108.157154069537;5143.254228136974;4834.20235661112;4881.145799485147;4918.163912134874;4919.335889654851;4941.555260026615;3735.252949410183;3739.4764732936865;3741.3452645786333;3759.154190307159;3770.4721339012012;4948.653594248368;5185.611079169153;5203.687778733288;5230;5241.213817927342;5073.822416215415;5079.6698214615935;5081;5089.618278629044;5093.337866077534;5059.848204553869;5075.441701412916;5099.515546023122;5103.744812759422;5117.974410127926;4837.7581120944515;4982.775775090057;5038.294638750651;5047.722375269395;5057.393112826484;4869.829555965524;5017;5077.695338279715;5080.796768129233;5098.464661210668;4809.567138957511;4817.8313759018265;4837;4844.491328410607;4910.582600479 13:26:29 INFO - PROCESS | 19165 | |5;yui.html;496.4861368483619;523.6543881038515;544.4637032522962;554.0829926471689;581.1834372706346;11361.772764544705;12194.634160975185;12194.69513262169;12206.572769953042;12235.32705701186;10822.13422926166;11082.05802506788;11105.167112466557;11130.833037504424;11462.770744585103;465.34386515013836;540.4325458268821;552.4723888686315;555.1533910786063;555.16447746142;534.7353060235189;552.0807854921571;554.7170942819151;555.2642748358421;555.8665920179149;577.1371799160262;579.3482332376077;579.79707102514;579.8086631411645;580.0371383503385;484.35580677698925;579.7536047179974;579.8405438504403;580.4195804195804;580.5036693626972;409.12243238254075;418.75502830843993;421.57842157842157;423.0734691026642;423.2022637328627;12362.876371236352;12368.81446778299;12421.503139874394;12423.937880310541;12425.19236249641;11586.88413115875;11958.342291173982;12204.816927746091;12232.81650775239;12375.62873113808;10849.457527123612;10944.23390362675;10978.890211097947;11283.717907052307;11404.657860264206;3937.803109844525;3973.1854969731135;3986.1629057897876;4032.536258330271;4110.280700877364;65085;65367.3463265366;65375.3462465374;65449.67275163641;68678.96981545331;8989.311701423878;9160.312976526788;9203.907960920438;9286.535673216313;9414.964353921063;19237.711434328496;19250.326238581583;19276.421707348803;19281;19356.516087097934;5954.880902382015;6025.0661147522205;6054.576179667381;6100.328963813977;6232.376762323732;3422;3453.32660131277;3468.289000754839;3482;3513.2622149348413;2532.354249666323;2636.7495087966613;2641.616965539986;2644.457886133338;2645.5105805425674;454.3684278852398;456.9383133276965;461.1721959083453;461.66759932848294;465.2137886971008;397.92041591681203;410.2287699125625;468.49402645143164;480.67554400779335;482.16103979076854;6858.794236172923;6915.446764258847;6968.44252459802;7057.92942070583;7216.061911951518;409.2735394674417;439.20723094814167;467.61889060415655;472.29863652476047;474.3217199404815;3131.6242050954033;3165.2245199926165;3181.618205815271;3218.5172224166563;3309.6028476582965;93805.53097234607;94063;99148.5042574797;102667;103041;69929;71693;72280.27719722841;81260;87797;63025.68487157627;70940;72014;73351.63324183345;73451.63274183594;7362.595057271901;7625.313721765067;7765.339946104645;7933.523988560705;8010.118886922317;41523.79238103911;42135.7893210532;42216.36675449809;42455.7877210612;43936.560634394526;56692;63295.6835215821;63901;66718.33281667122;67202.2315760004;9657.855132172883;9672.322937394596;9833.213342932691;10048.748781280527;10101.141402980535;10934.398608076632;11230.494627741607;11325.433728313716;11365.431728413381;12034;7429.405647548291;10169.694909152737;10380.377177369382;10541.841872371768;11005.779884402427;10917.344959302467;11088.445577721242;11264.197728363422;12383.380830958597;12390.256584604953;10307.330023548446;10369.014943580649;10429.478526073817;11003.779924401628;11724.413779311171;9283.210927071275;9380.906190938276;9583.568739406604;9606.27952903521;9650.806983860424;9265.53672316368;9329.440233585736;9399.827416907268;9412;9543.713688589354 13:26:29 INFO - PROCESS | 19165 | __end_tp_report 13:26:29 INFO - PROCESS | 19165 | __start_cc_report 13:26:29 INFO - PROCESS | 19165 | _x_x_mozilla_cycle_collect,1028 13:26:29 INFO - PROCESS | 19165 | __end_cc_report 13:26:29 INFO - PROCESS | 19165 | __startTimestamp1472847989083__endTimestamp 13:26:29 INFO - PROCESS | 19165 | 13:26:29 INFO - PROCESS | 19165 | ------- Summary: start ------- 13:26:29 INFO - PROCESS | 19165 | Number of tests: 6 13:26:29 INFO - PROCESS | 19165 | 13:26:29 INFO - PROCESS | 19165 | [#0] dojo.html Cycles:165 Average:4745.29 Median:4877.73 stddev:509.35 (10.4%) stddev-sans-first:482.03 13:26:29 INFO - PROCESS | 19165 | Values: 2589.8 2606.4 2619.4 2660.3 2671.7 4698.7 4727.5 4736.6 4791.8 4873.8 4783.5 4789.7 4822.3 4825.8 4848.7 4356.0 4377.7 4442.8 4512.3 4570.7 4480.2 4517.7 4534.9 4555.4 4566.7 4285.2 4308.5 4327.9 4382.8 4424.1 4332.5 4351.2 4360.7 4397.5 4417.1 4300.3 4370.4 4379.5 4380.3 4384.5 4660.6 4793.9 4796.6 4797.3 4804.6 5085.4 5102.0 5120.1 5131.9 5142.1 5143.7 5147.0 5150.9 5169.2 5180.7 4652.3 4734.7 4786.3 4787.3 4793.4 5219.7 5226.8 5243.9 5304.6 5315.6 5017.1 5026.7 5027.6 5028.1 5119.1 4946.1 5082.7 5109.8 5111.4 5120.1 5088.6 5114.4 5115.0 5172.0 5173.9 5075.3 5090.4 5090.9 5096.3 5156.4 4164.0 4189.4 4296.6 4313.5 4357.3 3773.2 3790.2 3797.1 3845.2 3898.3 4487.6 4488.5 4539.6 4542.7 4560.2 4984.9 5235.9 5239.8 5249.2 5252.9 4382.0 4403.1 4406.2 4427.3 4482.3 4550.8 4577.6 4579.5 4580.6 4639.9 4655.7 4822.3 4832.3 4833.4 4882.6 5158.9 5189.3 5189.4 5195.6 5278.5 4877.7 4888.2 4905.3 4909.0 4959.1 4945.2 5126.0 5132.3 5139.0 5141.2 5054.6 5082.7 5091.7 5101.2 5140.4 4969.8 4972.1 4975.4 4976.5 5033.4 4870.7 4953.2 4981.9 4982.4 5044.3 4977.7 5020.7 5022.0 5026.7 5035.4 5134.3 5135.3 5147.6 5147.8 5200.5 4725.0 4764.8 4956.7 4966.8 4969.7 13:26:29 INFO - PROCESS | 19165 | 13:26:29 INFO - PROCESS | 19165 | [#1] ext.html Cycles:180 Average:43707.92 Median:18707.39 stddev:86282.68 (461.2%) stddev-sans-first:86463.78 13:26:29 INFO - PROCESS | 19165 | Values: 515.6 522.2 527.7 537.6 537.9 24155.2 32045.7 33349.7 33376.7 36267.6 33906.7 34000.1 34238.7 34292.7 34349.3 7032.1 7420.3 7544.5 7557.9 7559.4 9257.6 9499.0 9507.4 9729.4 9731.7 9635.6 9734.7 9744.0 9744.3 9746.5 9498.1 9519.8 9563.0 9571.9 9579.2 9390.0 9454.1 9461.6 9480.4 9483.6 31553.0 31863.0 31891.7 31894.2 31943.4 2389.4 2482.1 2494.0 2502.6 2781.5 24995.0 25088.7 25344.5 25532.4 26195.3 3414.6 3481.5 3491.3 3498.2 3573.8 179995.0 180095.0 180327.0 180440.0 181350.0 41630.8 41878.6 41970.2 41980.4 42011.6 28484.7 29150.1 29234.1 43932.0 44003.6 12097.9 12178.3 12178.7 12188.9 12198.5 9389.0 9642.0 9684.2 9806.8 9924.3 14404.3 14511.5 14518.1 14618.9 14678.7 5380.4 5385.2 5393.0 5454.7 5470.3 641.2 664.6 755.6 765.0 808.2 23133.3 24519.0 24524.5 24679.6 24686.9 783.4 809.9 811.1 816.2 863.5 11654.2 12615.0 12660.5 12870.8 12889.2 413165.0 413386.0 413607.0 414159.0 414516.0 38565.2 38952.6 38967.2 38976.6 38981.6 916.1 959.5 1009.9 1012.6 1013.2 24108.0 24689.6 25952.6 26044.5 26191.1 34840.0 35331.3 35352.5 35391.3 35392.6 321775.0 322404.0 323459.0 324060.0 385320.0 30461.7 32611.8 32644.3 32954.7 33223.7 34870.1 35031.6 35174.3 35463.3 36358.5 30197.0 30857.2 30952.0 30987.4 31331.5 30348.0 30783.0 31006.4 31171.8 31956.0 30115.4 30342.4 30646.5 30793.5 30933.0 26873.0 26903.7 28808.1 29136.3 29174.9 22030.9 22058.3 22406.4 22602.4 22698.9 13:26:29 INFO - PROCESS | 19165 | 13:26:29 INFO - PROCESS | 19165 | [#2] jquery.html Cycles:180 Average:22855.66 Median:4900.18 stddev:61671.33 (1258.6%) stddev-sans-first:61821.15 13:26:29 INFO - PROCESS | 19165 | Values: 331.8 338.6 346.8 355.1 357.8 11790.9 14270.1 15027.2 15121.7 16438.3 14163.7 14217.0 14238.1 14245.8 14482.3 4799.1 5020.0 5022.6 5023.3 5024.5 4895.4 4906.7 5021.7 5039.5 5045.1 5018.0 5020.4 5021.4 5025.4 5026.2 4784.9 4905.0 4929.7 4932.8 5007.8 4452.9 4455.6 4456.5 4456.5 4456.8 18534.8 19142.6 19178.3 19324.6 19332.4 1794.5 2193.6 2418.6 2443.2 2459.0 12059.2 12231.0 12419.4 13119.3 13151.5 1666.9 1681.0 1701.4 1702.5 1708.5 70503.6 71231.0 71577.0 72005.0 72079.6 9032.6 9082.6 9368.5 9485.8 9788.1 12855.6 12868.5 12885.6 12890.1 12895.2 6679.5 6866.5 7023.2 7026.0 7036.3 6418.3 6462.3 6505.8 6603.9 6642.7 5598.6 5637.7 5709.3 5714.5 5747.0 1617.4 1697.8 1705.5 1709.5 1730.6 738.6 764.4 782.6 817.6 824.4 14527.9 14826.5 15201.2 15449.5 16163.2 746.9 762.5 779.4 792.3 798.5 8577.6 8675.2 9157.0 9160.7 9389.9 363405.0 375128.0 376744.0 378481.0 379114.0 42497.1 43660.3 67945.3 70677.6 74449.6 792.4 807.9 811.0 811.9 812.7 14285.5 14306.2 14550.6 14575.9 15112.5 28003.6 29942.0 34883.3 35868.3 37285.1 44151.8 45245.5 45251.5 45433.8 45461.3 7446.6 7538.9 7581.7 7589.2 7601.9 9760.7 10992.8 11163.6 11269.5 11746.2 9786.2 9992.5 10002.3 10200.5 10321.7 9825.6 9839.2 10021.4 10057.9 10135.0 9637.4 9684.9 9842.6 9873.7 10061.5 7186.7 7419.0 7441.6 7486.0 7817.2 8573.7 9368.5 9393.5 9722.8 9788.3 13:26:29 INFO - PROCESS | 19165 | 13:26:29 INFO - PROCESS | 19165 | [#3] mootools.html Cycles:180 Average:5055.56 Median:5210.07 stddev:2890.47 (55.5%) stddev-sans-first:2883.02 13:26:29 INFO - PROCESS | 19165 | Values: 1064.7 1065.7 1086.5 1130.7 1195.6 7327.8 7489.6 7542.7 7543.1 7863.0 7670.2 7758.8 7768.5 7769.6 7775.1 2195.5 2224.2 2228.6 2232.5 2233.0 2167.9 2202.9 2218.2 2228.2 2232.0 2185.7 2197.7 2210.1 2213.6 2215.8 2161.9 2164.2 2165.6 2167.8 2167.9 2109.9 2144.3 2146.0 2146.0 2147.7 7685.5 7697.0 7756.1 7757.4 7772.7 4264.0 4442.2 4443.1 4450.4 4453.5 2850.4 2965.7 3025.1 3033.2 3044.4 896.3 900.2 907.7 914.3 918.3 10661.8 10708.5 10736.9 10741.8 10761.5 3806.3 4007.3 4109.2 4146.8 4161.1 8227.0 8240.0 8244.5 8250.1 8255.3 4456.3 4457.1 4468.3 4468.6 4469.7 3747.2 4097.7 4143.9 4167.8 4212.7 3261.8 3328.4 3419.1 3436.0 3478.5 1017.7 1030.5 1055.8 1061.6 1065.7 602.2 604.3 711.1 712.3 735.5 4975.4 5057.2 5076.6 5154.6 5171.3 700.3 714.8 747.2 758.3 761.5 3264.3 3268.4 3369.8 3453.5 3574.8 9360.6 9759.9 9763.1 9768.3 9774.1 9275.2 9525.7 9736.9 9742.9 9748.4 6881.9 7074.6 7079.9 7085.5 7085.8 5085.6 5248.9 5313.0 5322.1 5453.5 8733.6 9002.7 9136.0 9297.7 9302.8 8779.9 8899.1 8960.7 9233.3 9281.6 6018.6 6413.3 6696.7 6899.6 6901.2 7301.6 7678.4 7681.5 7692.0 7732.3 6648.6 6656.0 6714.3 6742.4 6961.6 6639.5 6757.7 6846.3 6926.5 6936.2 6183.8 6273.6 6284.5 6285.9 6289.3 4265.6 4307.4 4532.5 4606.1 4623.2 4365.7 4443.3 4534.0 4554.2 4640.7 13:26:29 INFO - PROCESS | 19165 | 13:26:29 INFO - PROCESS | 19165 | [#4] prototype.html Cycles:180 Average:4200.24 Median:4836.38 stddev:1219.49 (25.2%) stddev-sans-first:1186.98 13:26:29 INFO - PROCESS | 19165 | Values: 285.6 285.6 293.2 295.2 295.9 4840.2 4862.8 4877.8 4889.6 4895.6 4783.2 4798.3 4836.0 4836.8 4844.0 3598.1 3686.8 3693.7 3714.5 3739.3 3626.9 3768.3 3786.3 3789.7 3813.4 3728.9 3734.8 3736.4 3757.5 3806.3 3578.5 3755.8 3760.4 3765.8 3782.5 3308.8 3314.4 3318.1 3323.7 3345.3 4649.6 4671.6 4677.0 4689.0 4701.8 4338.2 4346.2 4352.0 4364.0 4382.8 4979.6 5055.7 5065.5 5069.2 5097.3 3609.5 3630.4 3632.1 3639.1 3639.4 5251.9 5272.2 5276.6 5290.2 5297.5 3629.7 3641.3 3694.8 3722.6 3734.7 3759.1 3760.3 3761.4 3767.6 3768.9 4239.7 4267.6 4272.9 4308.3 4321.5 4861.5 5035.9 5042.3 5063.6 5100.9 1279.7 1300.0 1300.2 1301.9 1310.2 839.8 840.8 857.5 862.7 870.3 4360.7 4458.6 4461.8 4462.6 4479.5 5093.2 5195.5 5197.5 5210.6 5229.1 4483.2 4496.3 4500.3 4500.5 4515.8 2609.6 2732.6 2747.4 2750.1 2770.9 4902.2 4903.1 4914.9 4915.4 4931.9 5135.4 5314.4 5321.0 5334.6 5346.7 4863.1 4865.5 4896.3 4915.6 4925.5 5188.3 5192.2 5199.6 5199.8 5205.0 4974.7 5083.6 5107.7 5108.2 5143.3 4834.2 4881.1 4918.2 4919.3 4941.6 3735.3 3739.5 3741.3 3759.2 3770.5 4948.7 5185.6 5203.7 5230.0 5241.2 5073.8 5079.7 5081.0 5089.6 5093.3 5059.8 5075.4 5099.5 5103.7 5118.0 4837.8 4982.8 5038.3 5047.7 5057.4 4869.8 5017.0 5077.7 5080.8 5098.5 4809.6 4817.8 4837.0 4844.5 4910.6 13:26:29 INFO - PROCESS | 19165 | 13:26:29 INFO - PROCESS | 19165 | [#5] yui.html Cycles:180 Average:17110.70 Median:463.44 stddev:25017.01 (5398.1%) stddev-sans-first:25056.08 13:26:29 INFO - PROCESS | 19165 | Values: 496.5 523.7 544.5 554.1 581.2 11361.8 12194.6 12194.7 12206.6 12235.3 10822.1 11082.1 11105.2 11130.8 11462.8 465.3 540.4 552.5 555.2 555.2 534.7 552.1 554.7 555.3 555.9 577.1 579.3 579.8 579.8 580.0 484.4 579.8 579.8 580.4 580.5 409.1 418.8 421.6 423.1 423.2 12362.9 12368.8 12421.5 12423.9 12425.2 11586.9 11958.3 12204.8 12232.8 12375.6 10849.5 10944.2 10978.9 11283.7 11404.7 3937.8 3973.2 3986.2 4032.5 4110.3 65085.0 65367.3 65375.3 65449.7 68679.0 8989.3 9160.3 9203.9 9286.5 9415.0 19237.7 19250.3 19276.4 19281.0 19356.5 5954.9 6025.1 6054.6 6100.3 6232.4 3422.0 3453.3 3468.3 3482.0 3513.3 2532.4 2636.7 2641.6 2644.5 2645.5 454.4 456.9 461.2 461.7 465.2 397.9 410.2 468.5 480.7 482.2 6858.8 6915.4 6968.4 7057.9 7216.1 409.3 439.2 467.6 472.3 474.3 3131.6 3165.2 3181.6 3218.5 3309.6 93805.5 94063.0 99148.5 102667.0 103041.0 69929.0 71693.0 72280.3 81260.0 87797.0 63025.7 70940.0 72014.0 73351.6 73451.6 7362.6 7625.3 7765.3 7933.5 8010.1 41523.8 42135.8 42216.4 42455.8 43936.6 56692.0 63295.7 63901.0 66718.3 67202.2 9657.9 9672.3 9833.2 10048.7 10101.1 10934.4 11230.5 11325.4 11365.4 12034.0 7429.4 10169.7 10380.4 10541.8 11005.8 10917.3 11088.4 11264.2 12383.4 12390.3 10307.3 10369.0 10429.5 11003.8 11724.4 9283.2 9380.9 9583.6 9606.3 9650.8 9265.5 9329.4 9399.8 9412.0 9543.7 13:26:29 INFO - PROCESS | 19165 | -------- Summary: end -------- 13:26:29 INFO - PROCESS | 19165 | 13:26:30 INFO - TEST-INFO | 19165: exit 0 13:26:30 INFO - TEST-OK | dromaeo_css | took 1122532ms 13:26:30 INFO - TEST-START | kraken 13:26:30 INFO - operating with platform_type : linux_ 13:26:30 INFO - Initialising browser for kraken test... 13:26:30 INFO - TEST-INFO | started process 19474 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEI_8FH/profile http://localhost:53918/getInfo.html) 13:26:35 INFO - PROCESS | 19474 | __metrics Screen width/height:1600/1200 13:26:35 INFO - PROCESS | 19474 | colorDepth:24 13:26:35 INFO - PROCESS | 19474 | Browser inner width/height: 1024/697 13:26:35 INFO - PROCESS | 19474 | __metrics 13:26:35 INFO - PROCESS | 19474 | 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" 13:26:35 INFO - PROCESS | 19474 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:26:36 INFO - PROCESS | 19474 | console.error: PushService: 13:26:36 INFO - PROCESS | 19474 | stateChangeProcessEnqueue: Error transitioning state 13:26:36 INFO - PROCESS | 19474 | UnknownError 13:26:37 INFO - TEST-INFO | 19474: exit 0 13:26:37 INFO - Browser initialized. 13:26:37 INFO - Running cycle 1/1 for kraken test... 13:26:37 INFO - TEST-INFO | started process 19554 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEI_8FH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:26:49 INFO - PROCESS | 19554 | RSS: Main: 180830208 13:26:49 INFO - PROCESS | 19554 | 13:26:51 INFO - PROCESS | 19554 | Cycle 1(1): loaded http://localhost:53918/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53918/tests/kraken/driver.html?testName=audio-beat-detection) 13:26:52 INFO - PROCESS | 19554 | RSS: Main: 235151360 13:26:52 INFO - PROCESS | 19554 | 13:26:55 INFO - PROCESS | 19554 | Cycle 1(1): loaded http://localhost:53918/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53918/tests/kraken/driver.html?testName=audio-dft) 13:26:56 INFO - PROCESS | 19554 | RSS: Main: 223432704 13:26:56 INFO - PROCESS | 19554 | 13:26:59 INFO - PROCESS | 19554 | Cycle 1(1): loaded http://localhost:53918/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53918/tests/kraken/driver.html?testName=audio-fft) 13:27:00 INFO - PROCESS | 19554 | RSS: Main: 303177728 13:27:00 INFO - PROCESS | 19554 | 13:27:02 INFO - PROCESS | 19554 | Cycle 1(1): loaded http://localhost:53918/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53918/tests/kraken/driver.html?testName=audio-oscillator) 13:27:02 INFO - PROCESS | 19554 | RSS: Main: 213946368 13:27:02 INFO - PROCESS | 19554 | 13:27:05 INFO - PROCESS | 19554 | Cycle 1(1): loaded http://localhost:53918/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53918/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:27:05 INFO - PROCESS | 19554 | RSS: Main: 215035904 13:27:05 INFO - PROCESS | 19554 | 13:27:08 INFO - PROCESS | 19554 | Cycle 1(1): loaded http://localhost:53918/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53918/tests/kraken/driver.html?testName=imaging-darkroom) 13:27:09 INFO - PROCESS | 19554 | RSS: Main: 253509632 13:27:09 INFO - PROCESS | 19554 | 13:27:12 INFO - PROCESS | 19554 | Cycle 1(1): loaded http://localhost:53918/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53918/tests/kraken/driver.html?testName=imaging-desaturate) 13:27:13 INFO - PROCESS | 19554 | RSS: Main: 246870016 13:27:13 INFO - PROCESS | 19554 | 13:27:16 INFO - PROCESS | 19554 | Cycle 1(1): loaded http://localhost:53918/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53918/tests/kraken/driver.html?testName=json-parse-financial) 13:27:17 INFO - PROCESS | 19554 | RSS: Main: 250564608 13:27:17 INFO - PROCESS | 19554 | 13:27:18 INFO - PROCESS | 19554 | Cycle 1(1): loaded http://localhost:53918/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53918/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:27:19 INFO - PROCESS | 19554 | RSS: Main: 214429696 13:27:19 INFO - PROCESS | 19554 | 13:27:21 INFO - PROCESS | 19554 | Cycle 1(1): loaded http://localhost:53918/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53918/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:27:21 INFO - PROCESS | 19554 | RSS: Main: 222384128 13:27:21 INFO - PROCESS | 19554 | 13:27:24 INFO - PROCESS | 19554 | Cycle 1(1): loaded http://localhost:53918/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53918/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:27:24 INFO - PROCESS | 19554 | RSS: Main: 222982144 13:27:24 INFO - PROCESS | 19554 | 13:27:27 INFO - PROCESS | 19554 | Cycle 1(1): loaded http://localhost:53918/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53918/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:27:28 INFO - PROCESS | 19554 | RSS: Main: 223502336 13:27:28 INFO - PROCESS | 19554 | 13:27:31 INFO - PROCESS | 19554 | Cycle 1(1): loaded http://localhost:53918/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53918/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:27:31 INFO - PROCESS | 19554 | RSS: Main: 222011392 13:27:31 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | Cycle 1(1): loaded http://localhost:53918/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53918/tests/kraken/driver.html?testName=ai-astar) 13:27:33 INFO - PROCESS | 19554 | RSS: Main: 272879616 13:27:33 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | __start_tp_report 13:27:33 INFO - PROCESS | 19554 | _x_x_mozilla_page_load 13:27:33 INFO - PROCESS | 19554 | _x_x_mozilla_page_load_details 13:27:33 INFO - PROCESS | 19554 | |i|pagename|runs| 13:27:33 INFO - PROCESS | 19554 | |0;ai-astar;113;112;119;114;115;116;113;111;113;128 13:27:33 INFO - PROCESS | 19554 | |1;audio-beat-detection;153;127;158;179;125;153;151;122;178;123 13:27:33 INFO - PROCESS | 19554 | |2;audio-dft;162;162;161;162;161;162;161;161;161;160 13:27:33 INFO - PROCESS | 19554 | |3;audio-fft;76;78;75;159;76;75;77;77;167;77 13:27:33 INFO - PROCESS | 19554 | |4;audio-oscillator;85;82;139;83;83;83;143;82;82;137 13:27:33 INFO - PROCESS | 19554 | |5;imaging-gaussian-blur;117;117;117;116;116;116;117;116;116;117 13:27:33 INFO - PROCESS | 19554 | |6;imaging-darkroom;147;146;149;146;146;146;147;146;146;146 13:27:33 INFO - PROCESS | 19554 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;94;95 13:27:33 INFO - PROCESS | 19554 | |8;json-parse-financial;65;67;66;66;66;66;65;67;67;66 13:27:33 INFO - PROCESS | 19554 | |9;json-stringify-tinderbox;67;66;66;66;66;66;66;68;67;66 13:27:33 INFO - PROCESS | 19554 | |10;stanford-crypto-aes;82;81;83;81;82;82;84;82;83;82 13:27:33 INFO - PROCESS | 19554 | |11;stanford-crypto-ccm;137;132;148;135;141;147;133;134;176;150 13:27:33 INFO - PROCESS | 19554 | |12;stanford-crypto-pbkdf2;181;180;176;180;180;175;175;174;177;173 13:27:33 INFO - PROCESS | 19554 | |13;stanford-crypto-sha256-iterative;69;74;70;70;72;68;69;70;69;69 13:27:33 INFO - PROCESS | 19554 | __end_tp_report 13:27:33 INFO - PROCESS | 19554 | __start_cc_report 13:27:33 INFO - PROCESS | 19554 | _x_x_mozilla_cycle_collect,1958 13:27:33 INFO - PROCESS | 19554 | __end_cc_report 13:27:33 INFO - PROCESS | 19554 | __startTimestamp1472848053934__endTimestamp 13:27:33 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | ------- Summary: start ------- 13:27:33 INFO - PROCESS | 19554 | Number of tests: 14 13:27:33 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | [#0] ai-astar Cycles:10 Average:115.40 Median:114.50 stddev:4.97 (4.3%) stddev-sans-first:5.20 13:27:33 INFO - PROCESS | 19554 | Values: 113.0 112.0 119.0 114.0 115.0 116.0 113.0 111.0 113.0 128.0 13:27:33 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | [#1] audio-beat-detection Cycles:10 Average:146.90 Median:153.00 stddev:21.80 (14.3%) stddev-sans-first:23.02 13:27:33 INFO - PROCESS | 19554 | Values: 153.0 127.0 158.0 179.0 125.0 153.0 151.0 122.0 178.0 123.0 13:27:33 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.67 (0.4%) stddev-sans-first:0.67 13:27:33 INFO - PROCESS | 19554 | Values: 162.0 162.0 161.0 162.0 161.0 162.0 161.0 161.0 161.0 160.0 13:27:33 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | [#3] audio-fft Cycles:10 Average:93.70 Median:76.50 stddev:36.58 (47.8%) stddev-sans-first:38.24 13:27:33 INFO - PROCESS | 19554 | Values: 76.0 78.0 75.0 159.0 76.0 75.0 77.0 77.0 167.0 77.0 13:27:33 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | [#4] audio-oscillator Cycles:10 Average:99.90 Median:82.50 stddev:27.49 (33.3%) stddev-sans-first:28.63 13:27:33 INFO - PROCESS | 19554 | Values: 85.0 82.0 139.0 83.0 83.0 83.0 143.0 82.0 82.0 137.0 13:27:33 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 13:27:33 INFO - PROCESS | 19554 | Values: 117.0 117.0 117.0 116.0 116.0 116.0 117.0 116.0 116.0 117.0 13:27:33 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:146.00 stddev:0.97 (0.7%) stddev-sans-first:1.01 13:27:33 INFO - PROCESS | 19554 | Values: 147.0 146.0 149.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 13:27:33 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 13:27:33 INFO - PROCESS | 19554 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 13:27:33 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | [#8] json-parse-financial Cycles:10 Average:66.10 Median:66.00 stddev:0.74 (1.1%) stddev-sans-first:0.67 13:27:33 INFO - PROCESS | 19554 | Values: 65.0 67.0 66.0 66.0 66.0 66.0 65.0 67.0 67.0 66.0 13:27:33 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 13:27:33 INFO - PROCESS | 19554 | Values: 67.0 66.0 66.0 66.0 66.0 66.0 66.0 68.0 67.0 66.0 13:27:33 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:82.00 stddev:0.92 (1.1%) stddev-sans-first:0.97 13:27:33 INFO - PROCESS | 19554 | Values: 82.0 81.0 83.0 81.0 82.0 82.0 84.0 82.0 83.0 82.0 13:27:33 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | [#11] stanford-crypto-ccm Cycles:10 Average:143.30 Median:144.00 stddev:13.27 (9.2%) stddev-sans-first:13.87 13:27:33 INFO - PROCESS | 19554 | Values: 137.0 132.0 148.0 135.0 141.0 147.0 133.0 134.0 176.0 150.0 13:27:33 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.10 Median:178.50 stddev:2.92 (1.6%) stddev-sans-first:2.74 13:27:33 INFO - PROCESS | 19554 | Values: 181.0 180.0 176.0 180.0 180.0 175.0 175.0 174.0 177.0 173.0 13:27:33 INFO - PROCESS | 19554 | 13:27:33 INFO - PROCESS | 19554 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:70.00 stddev:1.76 (2.5%) stddev-sans-first:1.83 13:27:33 INFO - PROCESS | 19554 | Values: 69.0 74.0 70.0 70.0 72.0 68.0 69.0 70.0 69.0 69.0 13:27:33 INFO - PROCESS | 19554 | -------- Summary: end -------- 13:27:33 INFO - PROCESS | 19554 | 13:27:35 INFO - TEST-INFO | 19554: exit 0 13:27:35 INFO - TEST-OK | kraken | took 65185ms 13:27:35 INFO - SUITE-END | took 1187s 13:27:35 INFO - Completed test suite (00:19:47) 13:27:35 INFO - javascript benchmark 13:27:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2589.824547380201, 2606.4265861510466, 2619.436821083467, 2660.3482146874017, 2671.7461841125087, 4698.671093023481, 4727.479977202505, 4736.59738922192, 4791.7604119794005, 4873.7806798694055, 4783.545563171493, 4789.688670236432, 4822.300766388871, 4825.78283977221, 4848.660593758438, 4355.956440435602, 4377.737335759849, 4442.800073996662, 4512.300593408027, 4570.657200709943, 4480.215962206617, 4517.661175411841, 4534.909301813962, 4555.385023021883, 4566.6575006874455, 4285.185814695198, 4308.504521979981, 4327.870163895088, 4382.846600368972, 4424.115176964588, 4332.458442694663, 4351.238533256699, 4360.716553424018, 4397.494288156871, 4417.094495628388, 4300.333448315485, 4370.38814565961, 4379.540148284448, 4380.277254253045, 4384.467307016655, 4660.603848672867, 4793.856184314476, 4796.616270698336, 4797.30439086334, 4804.567588917014, 5085.415177254589, 5101.974490127563, 5120.052790233819, 5131.871703207413, 5142.125838607409, 5143.665661731976, 5146.974265128688, 5150.922736158923, 5169.224616307546, 5180.689158650455, 4652.325412815123, 4734.692245004064, 4786.282057691375, 4787.305840653085, 4793.448753393404, 5219.712915789668, 5226.843194704165, 5243.9213411798855, 5304.628675992721, 5315.627906046617, 5017.096922553976, 5026.723530205873, 5027.622928280393, 5028.120078986163, 5119.129747942859, 4946.08497427977, 5082.720450375191, 5109.821156259513, 5111.361079865017, 5120.052790233819, 5088.567471764942, 5114.43741188469, 5115.0, 5171.974140129275, 5173.896522069613, 5075.289459475676, 5090.41460232078, 5090.858717538926, 5096.337476128154, 5156.4070131935305, 4164.0, 4189.350650649154, 4296.570342965679, 4313.547077556874, 4357.302831546937, 3773.1699026214187, 3790.223004284115, 3797.145642230476, 3845.2309538091936, 3898.2983063048355, 4487.573680500347, 4488.483824360241, 4539.636829053734, 4542.704724192917, 4560.17916774977, 4984.850454486371, 5235.921461178085, 5239.816606418757, 5249.238860365302, 5252.947470525322, 4381.978090109593, 4403.053343531156, 4406.206882761134, 4427.291633338715, 4482.260427029569, 4550.795214215368, 4577.633789296849, 4579.473360563511, 4580.587747102777, 4639.884002899887, 4655.6973796703105, 4822.252550854623, 4832.323474713613, 4833.4441539223435, 4882.609391248691, 5158.896822063462, 5189.299444574934, 5189.377274726906, 5195.584353251656, 5278.4721527846905, 4877.731724755173, 4888.2178851383605, 4905.337779399735, 4908.9754551227015, 4959.057779021974, 4945.23348880924, 5125.974370128126, 5132.255822905732, 5139.049275883974, 5141.2288156776785, 5054.59563234933, 5082.669626474268, 5091.71995540234, 5101.20931255656, 5140.3831540215415, 4969.82605608802, 4972.105021096093, 4975.402951645826, 4976.477469865612, 5033.421156566895, 4870.732109733999, 4953.18272485036, 4981.900361992813, 4982.377202849644, 5044.293798868235, 4977.726225057657, 5020.673656212334, 5022.045811295842, 5026.698398096126, 5035.395752509722, 5134.332536770196, 5135.3324067870935, 5147.597457878829, 5147.819826306061, 5200.5059519345605, 4725.031368569504, 4764.78558464863, 4956.702597844141, 4966.776495057803, 4969.652124351261], "value": 4711.793548863679, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [515.5591968866619, 522.2218893848162, 527.6543863769232, 537.5726297593413, 537.8547792096134, 24155.154569590068, 32045.679543204562, 33349.66650333499, 33376.66623333772, 36267.637323626754, 33906.66093339066, 34000.149996250104, 34238.65761342386, 34292.65707342926, 34349.31301373971, 7032.085828842269, 7420.257974202591, 7544.4718869679145, 7557.886631700529, 7559.395248380117, 9257.6296948122, 9499.0, 9507.429554226768, 9729.416235025885, 9731.708048758548, 9635.566399512003, 9734.707958761248, 9744.0, 9744.269179811507, 9746.51267436629, 9498.050194980447, 9519.762005949837, 9563.0, 9571.856422153673, 9579.23366130715, 9389.953050234773, 9454.101860323259, 9461.621535138585, 9480.383775054599, 9483.573239204252, 31553.0, 31863.0, 31891.6810831891, 31894.202644934063, 31943.361132777445, 2389.390705370137, 2482.056818408993, 2493.9750602493923, 2502.5745623244093, 2781.5271403861393, 24995.0, 25088.749112508824, 25344.49311013788, 25532.36169095769, 26195.345116372053, 3414.6243913169533, 3481.4603736420895, 3491.3366460372704, 3498.2303893143467, 3573.8213089345427, 179995.0, 180095.0, 180327.0, 180440.0, 181350.0, 41630.79184604058, 41878.581214188074, 41970.16059678789, 41980.37029444561, 42011.579884200764, 28484.71515284862, 29150.12549623515, 29234.122976310744, 43932.0, 44003.559964399945, 12097.879021209676, 12178.330191839357, 12178.695532611755, 12188.939055304843, 12198.51205951752, 9388.95305523468, 9641.951790241144, 9684.176844968115, 9806.75483112928, 9924.255680823968, 14404.279786010868, 14511.492097776527, 14518.128912265087, 14618.853811461962, 14678.706425871424, 5380.354357477049, 5385.192221166778, 5393.0, 5454.672719636835, 5470.316210473691, 641.2369280556123, 664.6012392564519, 755.6372940988247, 764.9579273140031, 808.1998821167002, 23133.30600082, 24519.0, 24524.50950981006, 24679.62980555293, 24686.876565617058, 783.3733013589106, 809.874469457235, 811.1401913971091, 816.1634324816991, 863.5380071661631, 11654.242474239147, 12615.0, 12660.493580256687, 12870.806937895939, 12889.162204456681, 413165.0, 413386.0, 413607.0, 414159.0, 414516.0, 38565.22869542649, 38952.61047389547, 38967.22065558673, 38976.6102338973, 38981.61018389837, 916.0793402630314, 959.5490119643756, 1009.914157296638, 1012.589901090045, 1013.1590779652723, 24108.041327501465, 24689.629655555542, 25952.61071083973, 26044.479110417305, 26191.083312083985, 34840.0, 35331.29337413289, 35352.469712954844, 35391.292174156886, 35392.646073538934, 321775.0, 322404.0, 323459.0, 324060.0, 385320.0, 30461.695383045884, 32611.836940815145, 32644.34711305713, 32954.727243641864, 33223.66776332206, 34870.12824679403, 35031.64968350284, 35174.29651407008, 35463.290734185684, 36358.454623181206, 30197.0, 30857.228569285948, 30952.0, 30987.380252394374, 31331.53002705007, 30348.0, 30783.0, 31006.37987240288, 31171.84414077915, 31956.0, 30115.397692046474, 30342.393152137276, 30646.540301895042, 30793.538096928114, 30933.0, 26873.0, 26903.730962690122, 28808.135755927357, 29136.27159321034, 29174.854125729235, 22030.88984555067, 22058.33824985253, 22406.439839003502, 22602.434939126655, 22698.886505567367], "value": 15706.497367254924, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.8058935522719, 338.6478062814673, 346.76766566400516, 355.0856544398175, 357.751362802852, 11790.941045294772, 14270.143791372511, 15027.248637568133, 15121.6975660487, 16438.342466301332, 14163.716725665507, 14217.0, 14238.145711257332, 14245.786313205284, 14482.348294326754, 4799.13615549201, 5020.046191223656, 5022.648414610979, 5023.321851550048, 5024.522670346311, 4895.41877375146, 4906.656534042619, 5021.673591216578, 5039.470855560168, 5045.142325804603, 5018.021485810268, 5020.3724534433195, 5021.447640759514, 5025.422076461199, 5026.246063090547, 4784.928226076611, 4904.950950490485, 4929.704217746911, 4932.753362331869, 5007.824726134586, 4452.910941781146, 4455.596487106551, 4456.532064133252, 4456.532064133252, 4456.754878481682, 18534.81465185358, 19142.61714765697, 19178.328758493524, 19324.613507729766, 19332.42002739906, 1794.47063116381, 2193.605151072806, 2418.6130219164847, 2443.1571107967725, 2459.0409740201335, 12059.155859089868, 12231.0, 12419.44112514939, 13119.344032798323, 13151.53969611059, 1666.8583170430527, 1680.973709252332, 1701.3534856754477, 1702.5232934778157, 1708.453294945618, 70503.64748176277, 71231.0, 71577.0, 72005.0, 72079.63960180218, 9032.593533291018, 9082.591283392263, 9368.484733339732, 9485.762855928657, 9788.070133337322, 12855.550055748005, 12868.549600763929, 12885.61343159688, 12890.097693161577, 12895.226286422845, 6679.532432729662, 6866.485013623998, 7023.227444981048, 7025.964870175617, 7036.296370362923, 6418.293987661354, 6462.289148193695, 6505.772297969643, 6603.867922641521, 6642.7342906284175, 5598.608097433222, 5637.689927054052, 5709.34342550612, 5714.542836573064, 5747.0, 1617.4444377882917, 1697.804752453459, 1705.5395043338228, 1709.4615196213153, 1730.6279149982563, 738.5605564689001, 764.411403219529, 782.5774081995659, 817.5666896544841, 824.4229039672254, 14527.92736036313, 14826.481073162387, 15201.163935983626, 15449.5365139046, 16163.191840407933, 746.8692978728701, 762.5462849604477, 779.3609240422912, 792.3304807437706, 798.4690181029666, 8577.571121443903, 8675.21923026918, 9156.954215228881, 9160.6793762218, 9389.859152112724, 363405.0, 375128.0, 376744.0, 378481.0, 379114.0, 42497.13802946465, 43660.345094822966, 67945.32054679489, 70677.64661176661, 74449.6277518609, 792.4452882981935, 807.9394045446497, 811.0429692962362, 811.8741595052894, 812.7480481050893, 14285.500007499688, 14306.213158276394, 14550.563483095524, 14575.927120364331, 15112.471063512725, 28003.579946300415, 29942.011597680117, 34883.30233395369, 35868.28263434769, 37285.0678733023, 44151.77924110487, 45245.54754452545, 45251.54748452473, 45433.77283113696, 45461.31808022948, 7446.590437525988, 7538.924610753822, 7581.658825352983, 7589.1651918290045, 7601.885971710318, 9760.658376957057, 10992.780144396907, 11163.553457861595, 11269.549218031187, 11746.177767556532, 9786.217102631916, 9992.500374981368, 10002.299839011199, 10200.489975501343, 10321.74195645115, 9825.55784989663, 9839.163671087752, 10021.448820314952, 10057.899421005988, 10134.986501349806, 9637.37357071788, 9684.85472717924, 9842.606295748375, 9873.703788886345, 10061.491890163725, 7186.7125314986815, 7419.0, 7441.590712510864, 7486.0, 7817.218278172138, 8573.657053717781, 9368.484733339732, 9393.530323483936, 9722.75693107678, 9788.265880059167], "value": 7795.764148127782, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1064.680595821254, 1065.6802959112267, 1086.4676308608784, 1130.7003644034332, 1195.5994741761515, 7327.780166595004, 7489.550626962386, 7542.736004239852, 7543.094828620573, 7863.0, 7670.194629563885, 7758.8448231035345, 7768.533887966726, 7769.61151942402, 7775.1447340792465, 2195.549912267981, 2224.243757122578, 2228.6099932511825, 2232.475368288451, 2232.966505502419, 2167.869927804337, 2202.944926376845, 2218.245796429214, 2228.1533017453344, 2232.0290673557056, 2185.704929834476, 2197.703310053146, 2210.1490925993526, 2213.55728854229, 2215.7562668106493, 2161.870287782738, 2164.188429338998, 2165.621016322153, 2167.826573874086, 2167.8916054197225, 2109.947251318714, 2144.345974477784, 2145.957080858374, 2146.034284571949, 2147.699322094908, 7685.538867667958, 7696.9615151924445, 7756.146823849372, 7757.379409647215, 7772.650230739574, 4263.97868010661, 4442.2226110430865, 4443.066955939256, 4450.399196108517, 4453.51011388747, 2850.4156647887135, 2965.747911427531, 3025.1378357168323, 3033.1810411189076, 3044.421559903611, 896.2650626486219, 900.2213085680839, 907.732218995398, 914.3325372478129, 918.2929144558719, 10661.78676426467, 10708.51811668469, 10736.892631073668, 10741.83887241692, 10761.515731792166, 3806.333891568964, 4007.2987227235117, 4109.239790638741, 4146.771927544014, 4161.146964872194, 8227.0, 8239.95880020596, 8244.54654993981, 8250.133735957757, 8255.339572834158, 4456.286994080996, 4457.108578284357, 4468.329750537446, 4468.55314468557, 4469.73181609098, 3747.2318174774105, 4097.692673049533, 4143.937840932388, 4167.812448439828, 4212.7472351659, 3261.7931365394957, 3328.4008878401637, 3419.076849250688, 3436.0, 3478.513008178857, 1017.7048655889857, 1030.5362586835954, 1055.7518983038979, 1061.6337363609543, 1065.717584840018, 602.208096353294, 604.3412680178626, 711.0862541633976, 712.291270186168, 735.5108852612952, 4975.353204083518, 5057.216131499624, 5076.593872490192, 5154.639175257696, 5171.2501687255135, 700.3346820520526, 714.7891372045259, 747.1594456236734, 758.2606958215806, 761.4974117082699, 3264.2981758921946, 3268.362669279468, 3369.7809642373168, 3453.4647129694936, 3574.7855128692363, 9360.578773955189, 9759.902400976041, 9763.121319081316, 9768.316217864682, 9774.12032917041, 9275.16523512887, 9525.714228573153, 9736.9026309736, 9742.9025709742, 9748.366356186825, 6881.8967715484305, 7074.575525468488, 7079.89380159298, 7085.468589855678, 7085.822854428578, 5085.6185786066935, 5248.895022099613, 5312.9734351328, 5322.09524380864, 5453.454654534515, 8733.606987685442, 9002.684906028258, 9136.0, 9297.72106836796, 9302.767430814283, 8779.868301975603, 8899.110088991049, 8960.731178064669, 9233.261339092722, 9281.628734850801, 6018.608790428608, 6413.326600706859, 6696.698648560729, 6899.6205208712, 6901.206361268519, 7301.598412087386, 7678.424118191047, 7681.539107653559, 7692.0384951881015, 7732.265434783687, 6648.5678430901835, 6656.0, 6714.328567143247, 6742.42689371409, 6961.582305061713, 6639.53523253387, 6757.729690812509, 6846.3153684633135, 6926.480513961574, 6936.167659880846, 6183.752649893954, 6273.62358258506, 6284.465820405317, 6285.905711414241, 6289.339619339903, 4265.637420819266, 4307.418498502662, 4532.478764941942, 4606.055758569418, 4623.190941585141, 4365.650747940221, 4443.266860967904, 4534.0, 4554.2257816172, 4640.744759038285], "value": 4004.31364991922, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.56166284752896, 285.61156826715666, 293.186407718581, 295.1794012644848, 295.85059544929834, 4840.192688240821, 4862.756862156887, 4877.829275975341, 4889.6064621582855, 4895.559399654035, 4783.234682450809, 4798.328234047236, 4836.0, 4836.758162091899, 4843.975780121094, 3598.0824889653168, 3686.7972261525606, 3693.722970777189, 3714.4614030965495, 3739.2708421857747, 3626.8911932642063, 3768.2651882882847, 3786.33401303427, 3789.7347185697013, 3813.3898576227807, 3728.9254214915686, 3734.7759134451885, 3736.364817980936, 3757.455169000507, 3806.3148633246005, 3578.4632305154173, 3755.830987605537, 3760.435934609803, 3765.755225910307, 3782.508273924386, 3308.751843611715, 3314.4034073866696, 3318.1372843060735, 3323.717484013862, 3345.3142105868237, 4649.604783593365, 4671.649626278043, 4676.953230467686, 4688.953110468886, 4701.78841952113, 4338.175746608166, 4346.1525002624185, 4351.97824010878, 4364.0, 4382.758948257844, 4979.626528010377, 5055.747212639353, 5065.46812584677, 5069.163588007974, 5097.2608971699265, 3609.458581212813, 3630.382834918044, 3632.146445585285, 3639.1084184375, 3639.3631114554846, 5251.947480525184, 5272.182811664198, 5276.604254680876, 5290.206469029637, 5297.496737809864, 3629.709623230188, 3641.253543023674, 3694.833732482045, 3722.646348596879, 3734.663880250736, 3759.1353988582778, 3760.3231418344963, 3761.3793724035777, 3767.6420740029653, 3768.9057773555332, 4239.745615263094, 4267.615914567704, 4272.914541709149, 4308.267594508941, 4321.524632290446, 4861.51384861511, 5035.949640503622, 5042.294078828967, 5063.594912406998, 5100.897982040338, 1279.6736832107754, 1299.9805002924963, 1300.174389262825, 1301.9283939383236, 1310.2138716770053, 839.7648658375665, 840.7940054686641, 857.4940784936917, 862.7109918177481, 870.2907130688595, 4360.71655342408, 4458.554144585581, 4461.776911154494, 4462.553744625577, 4479.529649386767, 5093.23601459784, 5195.48045195485, 5197.518707168439, 5210.557102646318, 5229.08491014071, 4483.237849565582, 4496.303073023687, 4500.257457519538, 4500.504944456107, 4515.796789144496, 2609.6038619338565, 2732.570623867034, 2747.409306999005, 2750.0787236275673, 2770.891643342639, 4902.240152776325, 4903.092927808367, 4914.852554423373, 4915.410150781929, 4931.876703082452, 5135.35529202461, 5314.441983591744, 5321.0, 5334.626576139633, 5346.679199248058, 4863.076015557032, 4865.489123641966, 4896.290037944407, 4915.58217551505, 4925.482824303396, 5188.299579556711, 5192.24712416705, 5199.584033277404, 5199.792008319776, 5204.9739751301, 4974.696887192625, 5083.618728595444, 5107.668001579886, 5108.157154069537, 5143.254228136974, 4834.20235661112, 4881.145799485147, 4918.163912134874, 4919.335889654851, 4941.555260026615, 3735.252949410183, 3739.4764732936865, 3741.3452645786333, 3759.154190307159, 3770.4721339012012, 4948.653594248368, 5185.611079169153, 5203.687778733288, 5230.0, 5241.213817927342, 5073.822416215415, 5079.6698214615935, 5081.0, 5089.618278629044, 5093.337866077534, 5059.848204553869, 5075.441701412916, 5099.515546023122, 5103.744812759422, 5117.974410127926, 4837.7581120944515, 4982.775775090057, 5038.294638750651, 5047.722375269395, 5057.393112826484, 4869.829555965524, 5017.0, 5077.695338279715, 5080.796768129233, 5098.464661210668, 4809.567138957511, 4817.8313759018265, 4837.0, 4844.491328410607, 4910.582600479], "value": 3809.548607027007, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.4861368483619, 523.6543881038515, 544.4637032522962, 554.0829926471689, 581.1834372706346, 11361.772764544705, 12194.634160975185, 12194.69513262169, 12206.572769953042, 12235.32705701186, 10822.13422926166, 11082.05802506788, 11105.167112466557, 11130.833037504424, 11462.770744585103, 465.34386515013836, 540.4325458268821, 552.4723888686315, 555.1533910786063, 555.16447746142, 534.7353060235189, 552.0807854921571, 554.7170942819151, 555.2642748358421, 555.8665920179149, 577.1371799160262, 579.3482332376077, 579.79707102514, 579.8086631411645, 580.0371383503385, 484.35580677698925, 579.7536047179974, 579.8405438504403, 580.4195804195804, 580.5036693626972, 409.12243238254075, 418.75502830843993, 421.57842157842157, 423.0734691026642, 423.2022637328627, 12362.876371236352, 12368.81446778299, 12421.503139874394, 12423.937880310541, 12425.19236249641, 11586.88413115875, 11958.342291173982, 12204.816927746091, 12232.81650775239, 12375.62873113808, 10849.457527123612, 10944.23390362675, 10978.890211097947, 11283.717907052307, 11404.657860264206, 3937.803109844525, 3973.1854969731135, 3986.1629057897876, 4032.536258330271, 4110.280700877364, 65085.0, 65367.3463265366, 65375.3462465374, 65449.67275163641, 68678.96981545331, 8989.311701423878, 9160.312976526788, 9203.907960920438, 9286.535673216313, 9414.964353921063, 19237.711434328496, 19250.326238581583, 19276.421707348803, 19281.0, 19356.516087097934, 5954.880902382015, 6025.0661147522205, 6054.576179667381, 6100.328963813977, 6232.376762323732, 3422.0, 3453.32660131277, 3468.289000754839, 3482.0, 3513.2622149348413, 2532.354249666323, 2636.7495087966613, 2641.616965539986, 2644.457886133338, 2645.5105805425674, 454.3684278852398, 456.9383133276965, 461.1721959083453, 461.66759932848294, 465.2137886971008, 397.92041591681203, 410.2287699125625, 468.49402645143164, 480.67554400779335, 482.16103979076854, 6858.794236172923, 6915.446764258847, 6968.44252459802, 7057.92942070583, 7216.061911951518, 409.2735394674417, 439.20723094814167, 467.61889060415655, 472.29863652476047, 474.3217199404815, 3131.6242050954033, 3165.2245199926165, 3181.618205815271, 3218.5172224166563, 3309.6028476582965, 93805.53097234607, 94063.0, 99148.5042574797, 102667.0, 103041.0, 69929.0, 71693.0, 72280.27719722841, 81260.0, 87797.0, 63025.68487157627, 70940.0, 72014.0, 73351.63324183345, 73451.63274183594, 7362.595057271901, 7625.313721765067, 7765.339946104645, 7933.523988560705, 8010.118886922317, 41523.79238103911, 42135.7893210532, 42216.36675449809, 42455.7877210612, 43936.560634394526, 56692.0, 63295.6835215821, 63901.0, 66718.33281667122, 67202.2315760004, 9657.855132172883, 9672.322937394596, 9833.213342932691, 10048.748781280527, 10101.141402980535, 10934.398608076632, 11230.494627741607, 11325.433728313716, 11365.431728413381, 12034.0, 7429.405647548291, 10169.694909152737, 10380.377177369382, 10541.841872371768, 11005.779884402427, 10917.344959302467, 11088.445577721242, 11264.197728363422, 12383.380830958597, 12390.256584604953, 10307.330023548446, 10369.014943580649, 10429.478526073817, 11003.779924401628, 11724.413779311171, 9283.210927071275, 9380.906190938276, 9583.568739406604, 9606.27952903521, 9650.806983860424, 9265.53672316368, 9329.440233585736, 9399.827416907268, 9412.0, 9543.713688589354], "value": 5828.923970472173, "unit": "score"}], "name": "dromaeo_css", "value": 6095.741609196723, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 119.0, 114.0, 115.0, 116.0, 113.0, 111.0, 113.0, 128.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 127.0, 158.0, 179.0, 125.0, 153.0, 151.0, 122.0, 178.0, 123.0], "value": 146.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 162.0, 161.0, 162.0, 161.0, 161.0, 161.0, 160.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 75.0, 159.0, 76.0, 75.0, 77.0, 77.0, 167.0, 77.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 139.0, 83.0, 83.0, 83.0, 143.0, 82.0, 82.0, 137.0], "value": 99.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 149.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 66.0, 66.0, 66.0, 66.0, 65.0, 67.0, 67.0, 66.0], "value": 66.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 68.0, 67.0, 66.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 83.0, 81.0, 82.0, 82.0, 84.0, 82.0, 83.0, 82.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 132.0, 148.0, 135.0, 141.0, 147.0, 133.0, 134.0, 176.0, 150.0], "value": 143.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 180.0, 176.0, 180.0, 180.0, 175.0, 175.0, 174.0, 177.0, 173.0], "value": 177.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 70.0, 70.0, 72.0, 68.0, 69.0, 70.0, 69.0, 69.0], "value": 70.0, "unit": "score"}], "name": "kraken", "value": 1580.2, "alertThreshold": 2.0}]} 13:27:35 INFO - Return code: 0 13:27:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:27:35 INFO - # TBPL SUCCESS # 13:27:35 INFO - Running post-action listener: _resource_record_post_action 13:27:35 INFO - [mozharness: 2016-09-02 20:27:35.739433Z] Finished run-tests step (success) 13:27:35 INFO - Running post-run listener: _resource_record_post_run 13:27:35 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 4096; Write bytes: 416104448; Read time: 20; Write time: 594116 13:27:35 INFO - TinderboxPrint: CPU usage
13.1% 13:27:35 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 13:27:35 INFO - TinderboxPrint: I/O write bytes / time
416,104,448 / 594,116 13:27:35 INFO - TinderboxPrint: CPU idle
8,351.3 (86.9%) 13:27:35 INFO - TinderboxPrint: CPU user
1,201.5 (12.5%) 13:27:35 INFO - TinderboxPrint: Swap in / out
0 / 0 13:27:35 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 113971200; Read time: 0; Write time: 229032 13:27:35 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 290201600; Read time: 0; Write time: 363048 13:27:36 INFO - Running post-run listener: _upload_blobber_files 13:27:36 INFO - Blob upload gear active. 13:27:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:27:36 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 13:27:36 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:27:36 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:27:36 INFO - (blobuploader) - INFO - Open directory for files ... 13:27:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:27:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:27:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:27:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:27:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:27:37 INFO - (blobuploader) - INFO - Done attempting. 13:27:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:27:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:27:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:27:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:27:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:27:38 INFO - (blobuploader) - INFO - Done attempting. 13:27:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:27:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:27:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:27:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:27:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:27:41 INFO - (blobuploader) - INFO - Done attempting. 13:27:41 INFO - (blobuploader) - INFO - Iteration through files over. 13:27:41 INFO - Return code: 0 13:27:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:27:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:27:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ea270acb8fd25782ad867283956f5b80f271d3f953792722d976025e915db1b1f1f6cb2f146c1b8ded762f23ed14f4b322181f5565663be1dafdc344bfd530d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ec8a6891ffb205f246557bb1605f624f810384384b0d7f7e77464f7ca256e3bb07ff764390e291357ff3127c750f2b32377bd0d9c8f57703e799562d881d8c23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0e30d2d7907ee60344b4ba4494a8f0223c8613b4a821c488a9c1944b4c912ab62237a85adaa91eaa087a2134ce64bdf8baf71ad17c2130f8441e5ef8bac1f0ff"} 13:27:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:27:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:27:41 INFO - Contents: 13:27:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ea270acb8fd25782ad867283956f5b80f271d3f953792722d976025e915db1b1f1f6cb2f146c1b8ded762f23ed14f4b322181f5565663be1dafdc344bfd530d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ec8a6891ffb205f246557bb1605f624f810384384b0d7f7e77464f7ca256e3bb07ff764390e291357ff3127c750f2b32377bd0d9c8f57703e799562d881d8c23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0e30d2d7907ee60344b4ba4494a8f0223c8613b4a821c488a9c1944b4c912ab62237a85adaa91eaa087a2134ce64bdf8baf71ad17c2130f8441e5ef8bac1f0ff"} 13:27:41 INFO - Running post-run listener: copy_logs_to_upload_dir 13:27:41 INFO - Copying logs to upload dir... 13:27:41 INFO - mkdir: /builds/slave/test/build/upload/logs 13:27:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.708289 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-09-02 13:27:41.607488) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 13:27:41.612425) ========= 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=5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ea270acb8fd25782ad867283956f5b80f271d3f953792722d976025e915db1b1f1f6cb2f146c1b8ded762f23ed14f4b322181f5565663be1dafdc344bfd530d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ec8a6891ffb205f246557bb1605f624f810384384b0d7f7e77464f7ca256e3bb07ff764390e291357ff3127c750f2b32377bd0d9c8f57703e799562d881d8c23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0e30d2d7907ee60344b4ba4494a8f0223c8613b4a821c488a9c1944b4c912ab62237a85adaa91eaa087a2134ce64bdf8baf71ad17c2130f8441e5ef8bac1f0ff"} build_url:https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011407 build_url: 'https://queue.taskcluster.net/v1/task/Aw8BcJX_RBqJSCeypEotlw/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/Autoland-Non-PGO/sha512/ea270acb8fd25782ad867283956f5b80f271d3f953792722d976025e915db1b1f1f6cb2f146c1b8ded762f23ed14f4b322181f5565663be1dafdc344bfd530d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ec8a6891ffb205f246557bb1605f624f810384384b0d7f7e77464f7ca256e3bb07ff764390e291357ff3127c750f2b32377bd0d9c8f57703e799562d881d8c23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/0e30d2d7907ee60344b4ba4494a8f0223c8613b4a821c488a9c1944b4c912ab62237a85adaa91eaa087a2134ce64bdf8baf71ad17c2130f8441e5ef8bac1f0ff"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 13:27:41.640620) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 13:27:41.641022) ========= 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=5cf47e208febbcd81da23b2e0000026f-1472846145.170527-562843217 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004512 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 13:27:41.723801) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 13:27:41.727158) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 13:27:41.727448) ========= ========= Total master_lag: 0.18 =========