builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0029 starttime: 1461973439.03 results: success (0) buildid: 20160429145857 builduid: 749ce1fb2185474388c6ad20f15a452c revision: 2b7c421063ad7e30b6491d62ed8480ca333b628a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 16:43:59.025715) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 16:43:59.026255) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 16:43:59.026552) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 16:43:59.058426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 16:43:59.058872) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uQhkVvWkcS/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-29 16:43:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.42M=0.002s 2016-04-29 16:43:59 (6.42 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085377 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 16:43:59.161434) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 16:43:59.161813) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uQhkVvWkcS/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020134 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 16:43:59.211171) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-29 16:43:59.211526) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2b7c421063ad7e30b6491d62ed8480ca333b628a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 2b7c421063ad7e30b6491d62ed8480ca333b628a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uQhkVvWkcS/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-29 16:43:59,280 truncating revision to first 12 chars 2016-04-29 16:43:59,280 Setting DEBUG logging. 2016-04-29 16:43:59,280 attempt 1/10 2016-04-29 16:43:59,280 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2b7c421063ad?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-29 16:44:00,104 unpacking tar archive at: mozilla-central-2b7c421063ad/testing/mozharness/ program finished with exit code 0 elapsedTime=1.139801 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-29 16:44:00.368516) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 16:44:00.368904) ========= script_repo_revision: 2b7c421063ad7e30b6491d62ed8480ca333b628a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 16:44:00.369329) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 16:44:00.369637) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 16:44:00.384205) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 13 secs) (at 2016-04-29 16:44:00.384601) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uQhkVvWkcS/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 16:44:00 INFO - MultiFileLogger online at 20160429 16:44:00 in /builds/slave/test 16:44:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:44:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:44:00 INFO - {'append_to_log': False, 16:44:00 INFO - 'base_work_dir': '/builds/slave/test', 16:44:00 INFO - 'blob_upload_branch': 'Firefox', 16:44:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:44:00 INFO - 'branch': 'Firefox', 16:44:00 INFO - 'buildbot_json_path': 'buildprops.json', 16:44:00 INFO - 'config_files': ('talos/mac_config.py',), 16:44:00 INFO - 'default_actions': ('clobber', 16:44:00 INFO - 'read-buildbot-config', 16:44:00 INFO - 'download-and-extract', 16:44:00 INFO - 'populate-webroot', 16:44:00 INFO - 'create-virtualenv', 16:44:00 INFO - 'install', 16:44:00 INFO - 'run-tests'), 16:44:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:44:00 INFO - 'download_minidump_stackwalk': True, 16:44:00 INFO - 'download_symbols': 'ondemand', 16:44:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:44:00 INFO - 'tooltool.py': '/tools/tooltool.py', 16:44:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:44:00 INFO - '/tools/misc-python/virtualenv.py')}, 16:44:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:44:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:44:00 INFO - 'installer_path': 'installer.exe', 16:44:00 INFO - 'log_level': 'info', 16:44:00 INFO - 'log_name': 'talos', 16:44:00 INFO - 'log_to_console': True, 16:44:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:44:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:44:00 INFO - 'opt_config_files': (), 16:44:00 INFO - 'pip_index': False, 16:44:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:44:00 INFO - 'cmd': ('bash', 16:44:00 INFO - '-c', 16:44:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:44:00 INFO - 'enabled': True, 16:44:00 INFO - 'halt_on_failure': False, 16:44:00 INFO - 'name': 'check_screen_resolution'},), 16:44:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:44:00 INFO - 'cmd': ('bash', 16:44:00 INFO - '-c', 16:44:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:44:00 INFO - 'enabled': True, 16:44:00 INFO - 'halt_on_failure': False, 16:44:00 INFO - 'name': 'check_screen_resolution'},), 16:44:00 INFO - 'run_cmd_checks_enabled': True, 16:44:00 INFO - 'sps_profile': False, 16:44:00 INFO - 'sps_profile_interval': 0, 16:44:00 INFO - 'suite': 'dromaeojs-e10s', 16:44:00 INFO - 'system_bits': '32', 16:44:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:44:00 INFO - 'title': 't-yosemite-r7-0029', 16:44:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:44:00 INFO - 'use_talos_json': True, 16:44:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:44:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:44:00 INFO - 'work_dir': 'build'} 16:44:00 INFO - ##### 16:44:00 INFO - ##### Running clobber step. 16:44:00 INFO - ##### 16:44:00 INFO - Running pre-action listener: _resource_record_pre_action 16:44:00 INFO - Running main action method: clobber 16:44:00 INFO - rmtree: /builds/slave/test/build 16:44:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:44:01 INFO - Running post-action listener: _resource_record_post_action 16:44:01 INFO - ##### 16:44:01 INFO - ##### Running read-buildbot-config step. 16:44:01 INFO - ##### 16:44:01 INFO - Running pre-action listener: _resource_record_pre_action 16:44:01 INFO - Running main action method: read_buildbot_config 16:44:01 INFO - Using buildbot properties: 16:44:01 INFO - { 16:44:01 INFO - "project": "", 16:44:01 INFO - "product": "firefox", 16:44:01 INFO - "script_repo_revision": "production", 16:44:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:44:01 INFO - "repository": "", 16:44:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:44:01 INFO - "buildid": "20160429145857", 16:44:01 INFO - "pgo_build": "False", 16:44:01 INFO - "basedir": "/builds/slave/test", 16:44:01 INFO - "buildnumber": 262, 16:44:01 INFO - "slavename": "t-yosemite-r7-0029", 16:44:01 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 16:44:01 INFO - "platform": "yosemite_r7", 16:44:01 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:44:01 INFO - "branch": "mozilla-central", 16:44:01 INFO - "repo_path": "mozilla-central", 16:44:01 INFO - "revision": "2b7c421063ad7e30b6491d62ed8480ca333b628a", 16:44:01 INFO - "stage_platform": "macosx64", 16:44:01 INFO - "builduid": "749ce1fb2185474388c6ad20f15a452c", 16:44:01 INFO - "slavebuilddir": "test" 16:44:01 INFO - } 16:44:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 16:44:01 INFO - Running post-action listener: _resource_record_post_action 16:44:01 INFO - ##### 16:44:01 INFO - ##### Running download-and-extract step. 16:44:01 INFO - ##### 16:44:01 INFO - Running pre-action listener: _resource_record_pre_action 16:44:01 INFO - Running main action method: download_and_extract 16:44:01 INFO - mkdir: /builds/slave/test/build/tests 16:44:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:01 INFO - https://queue.taskcluster.net/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:44:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:44:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:44:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:44:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 16:44:03 INFO - Downloaded 1199 bytes. 16:44:03 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:44:03 INFO - Using the following test package requirements: 16:44:03 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:44:03 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:44:03 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 16:44:03 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:44:03 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 16:44:03 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:44:03 INFO - u'jsshell-mac.zip'], 16:44:03 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:44:03 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 16:44:03 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:44:03 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:44:03 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 16:44:03 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:44:03 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 16:44:03 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:44:03 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 16:44:03 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:44:03 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 16:44:03 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:44:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:03 INFO - https://queue.taskcluster.net/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:44:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:44:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:44:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:44:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:44:04 INFO - Downloaded 18127760 bytes. 16:44:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:44:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:44:07 INFO - Return code: 0 16:44:07 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:44:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:07 INFO - https://queue.taskcluster.net/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:44:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:44:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:44:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:44:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:44:09 INFO - Downloaded 18127760 bytes. 16:44:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:44:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:44:12 INFO - Return code: 0 16:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:12 INFO - https://queue.taskcluster.net/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:44:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:44:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:44:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:44:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:44:13 INFO - Downloaded 10840152 bytes. 16:44:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:44:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:44:14 INFO - Return code: 0 16:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:14 INFO - https://queue.taskcluster.net/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:44:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:44:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:44:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 16:44:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 16:44:17 INFO - Downloaded 110522773 bytes. 16:44:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:44:17 INFO - mkdir: /builds/slave/test/properties 16:44:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:44:17 INFO - Writing to file /builds/slave/test/properties/build_url 16:44:17 INFO - Contents: 16:44:17 INFO - build_url:https://queue.taskcluster.net/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:44:18 INFO - Running post-action listener: _resource_record_post_action 16:44:18 INFO - Running post-action listener: set_extra_try_arguments 16:44:18 INFO - ##### 16:44:18 INFO - ##### Running populate-webroot step. 16:44:18 INFO - ##### 16:44:18 INFO - Running pre-action listener: _resource_record_pre_action 16:44:18 INFO - Running main action method: populate_webroot 16:44:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:44:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:44:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:44:18 INFO - '--tppagecycles', 16:44:18 INFO - '7'], 16:44:18 INFO - 'tests': ['tsvgm']}}, 16:44:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:44:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:44:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:44:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:44:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:44:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:44:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:18 INFO - 'talos_options': ['--disable-e10s'], 16:44:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:44:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:44:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:18 INFO - 'talos_options': ['--disable-e10s'], 16:44:18 INFO - 'tests': ['damp', 'tps']}, 16:44:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:18 INFO - 'tests': ['damp', 'tps']}, 16:44:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:44:18 INFO - 'tests': ['dromaeo_dom']}, 16:44:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:44:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:44:18 INFO - 'tests': ['a11yr', 16:44:18 INFO - 'ts_paint', 16:44:18 INFO - 'tpaint', 16:44:18 INFO - 'sessionrestore', 16:44:18 INFO - 'sessionrestore_no_auto_restore', 16:44:18 INFO - 'tabpaint']}, 16:44:18 INFO - 'other-e10s': {'tests': ['a11yr', 16:44:18 INFO - 'ts_paint', 16:44:18 INFO - 'tpaint', 16:44:18 INFO - 'sessionrestore', 16:44:18 INFO - 'sessionrestore_no_auto_restore', 16:44:18 INFO - 'tabpaint']}, 16:44:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:44:18 INFO - 'ts_paint', 16:44:18 INFO - 'tpaint', 16:44:18 INFO - 'sessionrestore', 16:44:18 INFO - 'sessionrestore_no_auto_restore', 16:44:18 INFO - 'tabpaint']}, 16:44:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:44:18 INFO - 'ts_paint', 16:44:18 INFO - 'tpaint', 16:44:18 INFO - 'sessionrestore', 16:44:18 INFO - 'sessionrestore_no_auto_restore', 16:44:18 INFO - 'tabpaint']}, 16:44:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:44:18 INFO - 'tests': ['a11yr', 16:44:18 INFO - 'ts_paint', 16:44:18 INFO - 'tpaint', 16:44:18 INFO - 'sessionrestore', 16:44:18 INFO - 'sessionrestore_no_auto_restore', 16:44:18 INFO - 'tabpaint']}, 16:44:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:44:18 INFO - 'tests': ['a11yr', 16:44:18 INFO - 'ts_paint', 16:44:18 INFO - 'tpaint', 16:44:18 INFO - 'sessionrestore', 16:44:18 INFO - 'sessionrestore_no_auto_restore', 16:44:18 INFO - 'tabpaint']}, 16:44:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:44:18 INFO - 'tests': ['tsvgx', 16:44:18 INFO - 'tsvgr_opacity', 16:44:18 INFO - 'tart', 16:44:18 INFO - 'tscrollx', 16:44:18 INFO - 'cart']}, 16:44:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:44:18 INFO - 'tsvgr_opacity', 16:44:18 INFO - 'tart', 16:44:18 INFO - 'tscrollx', 16:44:18 INFO - 'cart']}, 16:44:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:18 INFO - 'talos_options': ['--disable-e10s'], 16:44:18 INFO - 'tests': ['tp5o']}, 16:44:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:18 INFO - 'tests': ['tp5o']}, 16:44:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:18 INFO - 'talos_options': ['--disable-e10s', 16:44:18 INFO - '--xperf_path', 16:44:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:44:18 INFO - 'tests': ['tp5n']}, 16:44:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:18 INFO - 'talos_options': ['--xperf_path', 16:44:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:44:18 INFO - 'tests': ['tp5n']}}, 16:44:18 INFO - 'talos.zip': {'path': '', 16:44:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:44:18 INFO - Running post-action listener: _resource_record_post_action 16:44:18 INFO - ##### 16:44:18 INFO - ##### Running create-virtualenv step. 16:44:18 INFO - ##### 16:44:18 INFO - Running pre-action listener: _resource_record_pre_action 16:44:18 INFO - Running main action method: create_virtualenv 16:44:18 INFO - Creating virtualenv /builds/slave/test/build/venv 16:44:18 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 16:44:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:44:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:44:18 INFO - Using real prefix '/tools/python27' 16:44:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:44:19 INFO - Installing distribute.............................................................................................................................................................................................done. 16:44:22 INFO - Installing pip.................done. 16:44:22 INFO - Return code: 0 16:44:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:44:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:22 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 0x10636ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10637b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bf14a6470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620ae00>, '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 0x106073db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106205b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:22 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 16:44:22 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 16:44:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 16:44:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:22 INFO - 'HOME': '/Users/cltbld', 16:44:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:22 INFO - 'LOGNAME': 'cltbld', 16:44:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:22 INFO - 'MOZ_NO_REMOTE': '1', 16:44:22 INFO - 'NO_EM_RESTART': '1', 16:44:22 INFO - 'PAGER': '/bin/cat', 16:44:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:22 INFO - 'PWD': '/builds/slave/test', 16:44:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:22 INFO - 'SHELL': '/bin/bash', 16:44:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 16:44:22 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 16:44:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:22 INFO - 'USER': 'cltbld', 16:44:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:22 INFO - 'XPC_FLAGS': '0x0', 16:44:22 INFO - 'XPC_SERVICE_NAME': '0', 16:44:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:22 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 16:44:22 INFO - Cleaning up... 16:44:22 INFO - Return code: 0 16:44:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:44:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:22 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10636ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10637b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bf14a6470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620ae00>, '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 0x106073db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106205b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:22 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>=0.7.1'] in /builds/slave/test/build 16:44:22 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>=0.7.1 16:44:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 16:44:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:22 INFO - 'HOME': '/Users/cltbld', 16:44:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:22 INFO - 'LOGNAME': 'cltbld', 16:44:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:22 INFO - 'MOZ_NO_REMOTE': '1', 16:44:22 INFO - 'NO_EM_RESTART': '1', 16:44:22 INFO - 'PAGER': '/bin/cat', 16:44:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:22 INFO - 'PWD': '/builds/slave/test', 16:44:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:22 INFO - 'SHELL': '/bin/bash', 16:44:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 16:44:22 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 16:44:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:22 INFO - 'USER': 'cltbld', 16:44:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:22 INFO - 'XPC_FLAGS': '0x0', 16:44:22 INFO - 'XPC_SERVICE_NAME': '0', 16:44:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:22 INFO - Downloading/unpacking psutil>=0.7.1 16:44: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 16:44: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 16:44: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 16:44: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 16:44: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 16:44: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 16:44:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:44:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:44:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:44:25 INFO - Installing collected packages: psutil 16:44:25 INFO - Running setup.py install for psutil 16:44:25 INFO - building 'psutil._psutil_osx' extension 16:44:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:44:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:44:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:44:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:44:25 INFO - building 'psutil._psutil_posix' extension 16:44:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:44:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:44:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:44:25 INFO - ^ 16:44:25 INFO - 1 warning generated. 16:44:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:44:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:44:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:44:25 INFO - Successfully installed psutil 16:44:25 INFO - Cleaning up... 16:44:26 INFO - Return code: 0 16:44:26 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:26 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10636ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10637b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bf14a6470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620ae00>, '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 0x106073db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106205b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:26 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.0.0'] in /builds/slave/test/build 16:44:26 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.0.0 16:44:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 16:44:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:26 INFO - 'HOME': '/Users/cltbld', 16:44:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:26 INFO - 'LOGNAME': 'cltbld', 16:44:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:26 INFO - 'MOZ_NO_REMOTE': '1', 16:44:26 INFO - 'NO_EM_RESTART': '1', 16:44:26 INFO - 'PAGER': '/bin/cat', 16:44:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:26 INFO - 'PWD': '/builds/slave/test', 16:44:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:26 INFO - 'SHELL': '/bin/bash', 16:44:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 16:44:26 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 16:44:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:26 INFO - 'USER': 'cltbld', 16:44:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:26 INFO - 'XPC_FLAGS': '0x0', 16:44:26 INFO - 'XPC_SERVICE_NAME': '0', 16:44:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:26 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:44:26 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 16:44:26 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 16:44:26 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 16:44:26 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 16:44:26 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 16:44:26 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 16:44:28 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:44:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:44:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:44:28 INFO - Installing collected packages: mozsystemmonitor 16:44:28 INFO - Running setup.py install for mozsystemmonitor 16:44:28 INFO - Successfully installed mozsystemmonitor 16:44:28 INFO - Cleaning up... 16:44:29 INFO - Return code: 0 16:44:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:29 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 0x10636ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10637b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bf14a6470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620ae00>, '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 0x106073db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106205b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:29 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 16:44:29 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 16:44:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 16:44:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:29 INFO - 'HOME': '/Users/cltbld', 16:44:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:29 INFO - 'LOGNAME': 'cltbld', 16:44:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:29 INFO - 'MOZ_NO_REMOTE': '1', 16:44:29 INFO - 'NO_EM_RESTART': '1', 16:44:29 INFO - 'PAGER': '/bin/cat', 16:44:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:29 INFO - 'PWD': '/builds/slave/test', 16:44:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:29 INFO - 'SHELL': '/bin/bash', 16:44:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 16:44:29 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 16:44:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:29 INFO - 'USER': 'cltbld', 16:44:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:29 INFO - 'XPC_FLAGS': '0x0', 16:44:29 INFO - 'XPC_SERVICE_NAME': '0', 16:44:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:29 INFO - Downloading/unpacking blobuploader==1.2.4 16:44:29 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 16:44:29 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 16:44:29 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 16:44:29 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 16:44:29 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 16:44:29 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 16:44:31 INFO - Downloading blobuploader-1.2.4.tar.gz 16:44:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:44:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:44:31 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 16:44:31 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 16:44:31 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 16:44:31 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 16:44:31 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 16:44:31 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 16:44:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:44:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:44:32 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 16:44:32 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 16:44:32 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 16:44:32 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 16:44:32 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 16:44:32 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 16:44:32 INFO - Downloading docopt-0.6.1.tar.gz 16:44:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:44:32 INFO - Installing collected packages: blobuploader, requests, docopt 16:44:32 INFO - Running setup.py install for blobuploader 16:44:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:44:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:44:33 INFO - Running setup.py install for requests 16:44:33 INFO - Running setup.py install for docopt 16:44:33 INFO - Successfully installed blobuploader requests docopt 16:44:33 INFO - Cleaning up... 16:44:33 INFO - Return code: 0 16:44:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:44:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:33 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 0x10636ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10637b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bf14a6470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620ae00>, '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 0x106073db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106205b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:33 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 16:44:33 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 16:44:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 16:44:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:33 INFO - 'HOME': '/Users/cltbld', 16:44:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:33 INFO - 'LOGNAME': 'cltbld', 16:44:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:33 INFO - 'MOZ_NO_REMOTE': '1', 16:44:33 INFO - 'NO_EM_RESTART': '1', 16:44:33 INFO - 'PAGER': '/bin/cat', 16:44:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:33 INFO - 'PWD': '/builds/slave/test', 16:44:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:33 INFO - 'SHELL': '/bin/bash', 16:44:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 16:44:33 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 16:44:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:33 INFO - 'USER': 'cltbld', 16:44:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:33 INFO - 'XPC_FLAGS': '0x0', 16:44:33 INFO - 'XPC_SERVICE_NAME': '0', 16:44:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:44:34 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-94qeLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:44:34 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-6mb7QU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:44:34 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-y5m4xj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:44:34 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-fQj5Vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:44:34 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-bODy_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:44:34 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-0KbNgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:44:34 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-frnbzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:44:34 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-vw3TpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:44:34 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-rR_2fl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:44:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Vv0Lbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:44:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-zcNDtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:44:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-PjhrXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:44:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-LcyTex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:44:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-t64DK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-W12uEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:44:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-19hTSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:44:35 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-iHFJ5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:44:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:44:35 INFO - Running setup.py install for manifestparser 16:44:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:44:36 INFO - Running setup.py install for mozcrash 16:44:36 INFO - Running setup.py install for mozdebug 16:44:36 INFO - Running setup.py install for mozdevice 16:44:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:44:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:44:36 INFO - Running setup.py install for mozfile 16:44:36 INFO - Running setup.py install for mozhttpd 16:44:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:44:36 INFO - Running setup.py install for mozinfo 16:44:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:44:36 INFO - Running setup.py install for mozInstall 16:44:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:44:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:44:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:44:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:44:37 INFO - Running setup.py install for mozleak 16:44:37 INFO - Running setup.py install for mozlog 16:44:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:44:37 INFO - Running setup.py install for moznetwork 16:44:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:44:37 INFO - Running setup.py install for mozprocess 16:44:37 INFO - Running setup.py install for mozprofile 16:44:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:44:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:44:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:44:37 INFO - Running setup.py install for mozrunner 16:44:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:44:38 INFO - Running setup.py install for mozscreenshot 16:44:38 INFO - Running setup.py install for moztest 16:44:38 INFO - Running setup.py install for mozversion 16:44:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:44:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:44:38 INFO - Cleaning up... 16:44:38 INFO - Return code: 0 16:44:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:44:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:38 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 0x10636ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10637b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bf14a6470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620ae00>, '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 0x106073db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106205b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:38 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 16:44:38 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 16:44:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 16:44:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:38 INFO - 'HOME': '/Users/cltbld', 16:44:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:38 INFO - 'LOGNAME': 'cltbld', 16:44:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:38 INFO - 'MOZ_NO_REMOTE': '1', 16:44:38 INFO - 'NO_EM_RESTART': '1', 16:44:38 INFO - 'PAGER': '/bin/cat', 16:44:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:38 INFO - 'PWD': '/builds/slave/test', 16:44:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:38 INFO - 'SHELL': '/bin/bash', 16:44:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 16:44:38 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 16:44:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:38 INFO - 'USER': 'cltbld', 16:44:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:38 INFO - 'XPC_FLAGS': '0x0', 16:44:38 INFO - 'XPC_SERVICE_NAME': '0', 16:44:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:44:38 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-p34hmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:44:38 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)) 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:44:38 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-5HRVcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:44:38 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)) 16:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:44:38 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-xczdTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:44:39 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)) 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:44:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-BPrxwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:44:39 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)) 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:44:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-hmre3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:44:39 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)) 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:44:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-So7o3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:44:39 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)) 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:44:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-UiObKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:44:39 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)) 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:44:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Jca0TR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:44:39 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)) 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:44:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-aLXBeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:44:39 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)) 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:44:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-z1e_US-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:44:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-wlb4mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:44:39 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)) 16:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:44:39 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-nbQ7Cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:44:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 16:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:44:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-pM4cKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:44:40 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)) 16:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:44:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Ohk1ok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:44:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 16:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-lVQ5QC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:40 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)) 16:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:44:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-JfkPlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:44:40 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)) 16:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:44:40 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-OKPEmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:44:40 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)) 16:44:40 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)) 16:44:40 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)) 16:44:40 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)) 16:44:40 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)) 16:44:40 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)) 16:44:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:44:40 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 16:44:40 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 16:44:40 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 16:44:40 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 16:44:40 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 16:44:40 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 16:44:43 INFO - Downloading blessings-1.6.tar.gz 16:44:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:44:44 INFO - Installing collected packages: blessings 16:44:44 INFO - Running setup.py install for blessings 16:44:44 INFO - Successfully installed blessings 16:44:44 INFO - Cleaning up... 16:44:44 INFO - Return code: 0 16:44:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:44:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:44:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:44:44 INFO - Reading from file tmpfile_stdout 16:44:44 INFO - Current package versions: 16:44:44 INFO - blessings == 1.6 16:44:44 INFO - blobuploader == 1.2.4 16:44:44 INFO - docopt == 0.6.1 16:44:44 INFO - manifestparser == 1.1 16:44:44 INFO - mozInstall == 1.12 16:44:44 INFO - mozcrash == 0.17 16:44:44 INFO - mozdebug == 0.1 16:44:44 INFO - mozdevice == 0.48 16:44:44 INFO - mozfile == 1.2 16:44:44 INFO - mozhttpd == 0.7 16:44:44 INFO - mozinfo == 0.9 16:44:44 INFO - mozleak == 0.1 16:44:44 INFO - mozlog == 3.1 16:44:44 INFO - moznetwork == 0.27 16:44:44 INFO - mozprocess == 0.22 16:44:44 INFO - mozprofile == 0.28 16:44:44 INFO - mozrunner == 6.11 16:44:44 INFO - mozscreenshot == 0.1 16:44:44 INFO - mozsystemmonitor == 0.0 16:44:44 INFO - moztest == 0.7 16:44:44 INFO - mozversion == 1.4 16:44:44 INFO - psutil == 3.1.1 16:44:44 INFO - requests == 1.2.3 16:44:44 INFO - wsgiref == 0.1.2 16:44:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:44 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 0x10636ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10637b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bf14a6470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620ae00>, '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 0x106073db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106205b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:44 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 16:44:44 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 16:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 16:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:44 INFO - 'HOME': '/Users/cltbld', 16:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:44 INFO - 'LOGNAME': 'cltbld', 16:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:44 INFO - 'MOZ_NO_REMOTE': '1', 16:44:44 INFO - 'NO_EM_RESTART': '1', 16:44:44 INFO - 'PAGER': '/bin/cat', 16:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:44 INFO - 'PWD': '/builds/slave/test', 16:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:44 INFO - 'SHELL': '/bin/bash', 16:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 16:44:44 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 16:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:44 INFO - 'USER': 'cltbld', 16:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:44 INFO - 'XPC_FLAGS': '0x0', 16:44:44 INFO - 'XPC_SERVICE_NAME': '0', 16:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:44 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)) 16:44:44 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)) 16:44:44 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)) 16:44:44 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)) 16:44:44 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)) 16:44:44 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)) 16:44:44 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)) 16:44:44 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)) 16:44:44 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)) 16:44:44 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)) 16:44:44 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)) 16:44:44 INFO - Cleaning up... 16:44:44 INFO - Return code: 0 16:44:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:44 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 0x10636ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10637b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bf14a6470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620ae00>, '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 0x106073db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106205b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:44 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 16:44:44 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 16:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 16:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:44 INFO - 'HOME': '/Users/cltbld', 16:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:44 INFO - 'LOGNAME': 'cltbld', 16:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:44 INFO - 'MOZ_NO_REMOTE': '1', 16:44:44 INFO - 'NO_EM_RESTART': '1', 16:44:44 INFO - 'PAGER': '/bin/cat', 16:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:44 INFO - 'PWD': '/builds/slave/test', 16:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:44 INFO - 'SHELL': '/bin/bash', 16:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 16:44:44 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 16:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:44 INFO - 'USER': 'cltbld', 16:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:44 INFO - 'XPC_FLAGS': '0x0', 16:44:44 INFO - 'XPC_SERVICE_NAME': '0', 16:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:45 INFO - Downloading/unpacking jsonschema 16:44:45 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 16:44:45 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 16:44:45 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 16:44:45 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 16:44:45 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 16:44:45 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 16:44:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:44:47 INFO - Downloading/unpacking functools32 (from jsonschema) 16:44:47 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 16:44:47 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 16:44:47 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 16:44:47 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 16:44:47 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 16:44:47 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 16:44:47 INFO - Downloading functools32-3.2.3-2.tar.gz 16:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:44:48 INFO - warning: no files found matching '*.txt' 16:44:48 INFO - no previously-included directories found matching 'build' 16:44:48 INFO - no previously-included directories found matching 'dist' 16:44:48 INFO - no previously-included directories found matching '.git*' 16:44:48 INFO - Installing collected packages: jsonschema, functools32 16:44:48 INFO - Running setup.py install for functools32 16:44:48 INFO - warning: no files found matching '*.txt' 16:44:48 INFO - no previously-included directories found matching 'build' 16:44:48 INFO - no previously-included directories found matching 'dist' 16:44:48 INFO - no previously-included directories found matching '.git*' 16:44:48 INFO - Successfully installed jsonschema functools32 16:44:48 INFO - Cleaning up... 16:44:48 INFO - Return code: 0 16:44:48 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:48 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10636ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10637b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bf14a6470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620ae00>, '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 0x106073db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106205b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:44:48 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', 'flake8'] in /builds/slave/test/build 16:44:48 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 flake8 16:44:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 16:44:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:48 INFO - 'HOME': '/Users/cltbld', 16:44:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:48 INFO - 'LOGNAME': 'cltbld', 16:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:48 INFO - 'MOZ_NO_REMOTE': '1', 16:44:48 INFO - 'NO_EM_RESTART': '1', 16:44:48 INFO - 'PAGER': '/bin/cat', 16:44:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:48 INFO - 'PWD': '/builds/slave/test', 16:44:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:48 INFO - 'SHELL': '/bin/bash', 16:44:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 16:44:48 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 16:44:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:48 INFO - 'USER': 'cltbld', 16:44:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:48 INFO - 'XPC_FLAGS': '0x0', 16:44:48 INFO - 'XPC_SERVICE_NAME': '0', 16:44:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:48 INFO - Downloading/unpacking flake8 16:44:48 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 16:44:48 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 16:44:48 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 16:44:48 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 16:44:48 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 16:44:48 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 16:44:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:44:50 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:44:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:44:51 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:44:51 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:44:51 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 16:44:51 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 16:44:51 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 16:44:51 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 16:44:51 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 16:44:51 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 16:44:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:44:51 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 16:44:51 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 16:44:51 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 16:44:51 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 16:44:51 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 16:44:51 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 16:44:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:44:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:44:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:44:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:44:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:44:52 INFO - no previously-included directories found matching 'docs/_build' 16:44:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:44:52 INFO - Running setup.py install for pep8 16:44:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:44:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:44:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:44:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:44:52 INFO - no previously-included directories found matching 'docs/_build' 16:44:52 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:44:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:44:52 INFO - Cleaning up... 16:44:52 INFO - Return code: 0 16:44:52 INFO - Running post-action listener: _resource_record_post_action 16:44:52 INFO - Running post-action listener: _start_resource_monitoring 16:44:52 INFO - Starting resource monitoring. 16:44:52 INFO - ##### 16:44:52 INFO - ##### Running install step. 16:44:52 INFO - ##### 16:44:52 INFO - Running pre-action listener: _resource_record_pre_action 16:44:52 INFO - Running main action method: install 16:44:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:44:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:44:52 INFO - Reading from file tmpfile_stdout 16:44:52 INFO - Detecting whether we're running mozinstall >=1.0... 16:44:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:44:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:44:52 INFO - Reading from file tmpfile_stdout 16:44:52 INFO - Output received: 16:44:52 INFO - Usage: mozinstall [options] installer 16:44:52 INFO - Options: 16:44:52 INFO - -h, --help show this help message and exit 16:44:52 INFO - -d DEST, --destination=DEST 16:44:52 INFO - Directory to install application into. [default: 16:44:52 INFO - "/builds/slave/test"] 16:44:52 INFO - --app=APP Application being installed. [default: firefox] 16:44:52 INFO - mkdir: /builds/slave/test/build/application 16:44:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:44:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:45:26 INFO - Reading from file tmpfile_stdout 16:45:26 INFO - Output received: 16:45:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:45:26 INFO - Running post-action listener: _resource_record_post_action 16:45:26 INFO - ##### 16:45:26 INFO - ##### Running run-tests step. 16:45:26 INFO - ##### 16:45:26 INFO - Running pre-action listener: _resource_record_pre_action 16:45:26 INFO - Running main action method: run_tests 16:45:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:45:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:45:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:45:26 INFO - 2016-04-29 16:45:26.920 screenresolution[1929:12177] starting screenresolution argv=screenresolution get 16:45:26 INFO - 2016-04-29 16:45:26.923 screenresolution[1929:12177] Display 0: 1600x1200x32@60 16:45:26 INFO - 2016-04-29 16:45:26.928 screenresolution[1930:12180] starting screenresolution argv=screenresolution list 16:45:26 INFO - Available Modes on Display 0 16:45:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:45:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:45:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:45:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:45:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:45:27 INFO - Intel Iris: 16:45:27 INFO - Chipset Model: Intel Iris 16:45:27 INFO - Type: GPU 16:45:27 INFO - Bus: Built-In 16:45:27 INFO - VRAM (Dynamic, Max): 1536 MB 16:45:27 INFO - Vendor: Intel (0x8086) 16:45:27 INFO - Device ID: 0x0a2e 16:45:27 INFO - Revision ID: 0x0009 16:45:27 INFO - Displays: 16:45:27 INFO - Display: 16:45:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:45:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:45:27 INFO - Display Serial Number: 1600x1200 60 16:45:27 INFO - Main Display: Yes 16:45:27 INFO - Mirror: Off 16:45:27 INFO - Online: Yes 16:45:27 INFO - Rotation: Supported 16:45:27 INFO - Return code: 0 16:45:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:45:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:45:27 INFO - Python 2.7.3 16:45:27 INFO - Return code: 0 16:45:27 INFO - grabbing minidump binary from tooltool 16:45:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:27 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620ae00>, '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 0x106073db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106205b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:45:27 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:45:27 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:45:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:45:27 INFO - Return code: 0 16:45:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:45:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:45:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:45:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:45:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:45:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:45:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:45:28 INFO - Return code: 0 16:45:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:45:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0029 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:45:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQhkVvWkcS/Render', 16:45:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:28 INFO - 'HOME': '/Users/cltbld', 16:45:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:28 INFO - 'LOGNAME': 'cltbld', 16:45:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:45:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:45:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:28 INFO - 'MOZ_NO_REMOTE': '1', 16:45:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:45:28 INFO - 'NO_EM_RESTART': '1', 16:45:28 INFO - 'PAGER': '/bin/cat', 16:45:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:28 INFO - 'PWD': '/builds/slave/test', 16:45:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:45:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:28 INFO - 'SHELL': '/bin/bash', 16:45:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners', 16:45:28 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 16:45:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:28 INFO - 'USER': 'cltbld', 16:45:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:28 INFO - 'XPC_FLAGS': '0x0', 16:45:28 INFO - 'XPC_SERVICE_NAME': '0', 16:45:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:45:28 INFO - mozversion application_buildid: 20160429145857 16:45:28 INFO - mozversion application_changeset: 2b7c421063ad7e30b6491d62ed8480ca333b628a 16:45:28 INFO - mozversion application_display_name: Nightly 16:45:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:45:28 INFO - mozversion application_name: Firefox 16:45:28 INFO - mozversion application_remotingname: firefox 16:45:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:45:28 INFO - mozversion application_vendor: Mozilla 16:45:28 INFO - mozversion application_version: 49.0a1 16:45:28 INFO - mozversion platform_buildid: 20160429145857 16:45:28 INFO - mozversion platform_changeset: 2b7c421063ad7e30b6491d62ed8480ca333b628a 16:45:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:45:28 INFO - mozversion platform_version: 49.0a1 16:45:28 INFO - using testdate: 1461973528 16:45:28 INFO - actual date: 1461973528 16:45:28 INFO - starting webserver on 'localhost:51968' 16:45:28 INFO - SUITE-START | Running 2 tests 16:45:28 INFO - TEST-START | dromaeo_css 16:45:28 INFO - operating with platform_type : mac_ 16:45:28 INFO - Initialising browser for dromaeo_css test... 16:45:28 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpyYvTPK/profile http://localhost:51968/getInfo.html) 16:45:31 INFO - PROCESS | 1948 | __metrics Screen width/height:1600/1200 16:45:31 INFO - PROCESS | 1948 | colorDepth:24 16:45:31 INFO - PROCESS | 1948 | Browser inner width/height: 1024/689 16:45:31 INFO - PROCESS | 1948 | __metrics 16:45:31 ERROR - PROCESS | 1948 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 16:45:32 INFO - TEST-INFO | 1948: exit 0 16:45:32 INFO - Browser initialized. 16:45:32 INFO - Running cycle 1/1 for dromaeo_css test... 16:45:32 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpyYvTPK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:45:44 INFO - PROCESS | 1951 | RSS: Main: 250150912 16:45:44 INFO - PROCESS | 1951 | 16:46:34 INFO - PROCESS | 1951 | 1461973594258 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] 16:48:46 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51968/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51968/tests/dromaeo/cssquery-ext.html) 16:48:47 INFO - PROCESS | 1951 | RSS: Main: 262361088 16:48:47 INFO - PROCESS | 1951 | 16:51:49 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51968/tests/dromaeo/cssquery-ext.html (next: http://localhost:51968/tests/dromaeo/cssquery-jquery.html) 16:51:50 INFO - PROCESS | 1951 | RSS: Main: 274186240 16:51:50 INFO - PROCESS | 1951 | 16:54:52 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51968/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51968/tests/dromaeo/cssquery-mootools.html) 16:54:52 INFO - PROCESS | 1951 | RSS: Main: 276008960 16:54:52 INFO - PROCESS | 1951 | 16:57:54 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51968/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51968/tests/dromaeo/cssquery-prototype.html) 16:57:55 INFO - PROCESS | 1951 | RSS: Main: 277655552 16:57:55 INFO - PROCESS | 1951 | 17:00:57 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51968/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51968/tests/dromaeo/cssquery-yui.html) 17:00:58 INFO - PROCESS | 1951 | RSS: Main: 280432640 17:00:58 INFO - PROCESS | 1951 | 17:04:00 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:51968/tests/dromaeo/cssquery-yui.html (next: http://localhost:51968/tests/dromaeo/cssquery-dojo.html) 17:04:01 INFO - PROCESS | 1951 | RSS: Main: 281767936 17:04:01 INFO - PROCESS | 1951 | 17:04:01 INFO - PROCESS | 1951 | __start_tp_report 17:04:01 INFO - PROCESS | 1951 | _x_x_mozilla_page_load 17:04:01 INFO - PROCESS | 1951 | _x_x_mozilla_page_load_details 17:04:01 INFO - PROCESS | 1951 | |i|pagename|runs| 17:04:01 INFO - PROCESS | 1951 | |0;dojo.html;3217.227865312325;3461.1693193633528;3519.4720791881214;3527.329807336606;3529.5235143255654;5673.404292549285;5713.685747283908;5735.397783232763;5810.622309549876;5819.447152520509;5676.574256930737;5684.54523638109;5689.687067211302;5754.16564598134;5773.336066352371;5175.844724658247;5211.5049070338255;5242.554382877441;5262.289590905236;5300.681959082448;5196.454372290912;5350.491703288181;5385.461453854622;5393.298871146745;5428.864278393052;5156.74216289184;5194.68831870089;5299.8410047698635;5326.041312563737;5346.732663366835;5214.739263036833;5227.424983251839;5252.107141785906;5262.578993680497;5266.34170728659;5169.638125331228;5216.3218781558535;5222.477752224785;5277.656952298088;5308.814191503279;4982.402111746614;5314.893702125974;5687.573431992617;5782.132680097977;5811.389804070554;6172.84567885802;6203.379662033805;6211.534134939852;6226.128342032074;6268.905966410462;6267.247930248353;6270.216222972129;6270.905936410957;6278.340774218733;6283.277423096357;5701.743421546041;5741.741621627037;5775.249217601685;5791.102379131242;5813.215215945877;6256.919716328991;6326.43062124411;6327;6333.904991425133;6340.144080549159;5683.920055189501;6064.514838812885;6192;6207.6275423474735;6228.595141315891;6191.071339299141;6239.469645080128;6270.247570291503;6294.937050629435;6296.433321001132;5100.4899510049445;6100.145979562864;6188.659623720649;6231.781887634002;6241.906371404432;4527.366168736379;6139.4781443577795;6207.006878899445;6250.656213908188;6271.153394291712;4955.355803745562;4994;5001.19980803077;5012.072766538129;5032.974835125801;4413.095315460387;4471.575200355961;4472.351509031172;4509.864704058883;4520.9095818084115;4827.61379089672;5769.701817091121;5893.116032595145;5932.851678707981;6004.639721616717;6228;6249.96875015622;6255.562110652301;6258.123862659231;6313.05304204373;5886.055713200932;5894.583220879419;5894.941050589525;5898.439648233411;5934.584579079509;5366.597505187126;5480.945190548123;5481.862953426196;5492.368377636623;5528.861778455571;6468.870622587522;6490.3185165558825;6491.578047426902;6497.577657452251;6535;6262.405071518198;6281.685915704105;6283.654399008098;6283.874322513616;6288.622682639057;5404.621676482609;6299.4645455135;6368.267649220399;6471.741130354922;6487.610743355413;6214.161088253028;6227.470664993345;6255.655938923403;6257.843553911189;6259.467945224525;6178.5366097543765;6249.093881387264;6251.156093927261;6252.406021427957;6256.812295631138;4986.675866068839;5881.2942446906645;6127.20346354971;6158.815235542941;6195.783147589813;6141.293751218488;6175.722092505938;6195.752169913153;6220.284667263322;6240.188775459162;5854.443827836349;5934.465898069195;6044.063170208625;6051.281033165003;6072.210612620508;6089.269287685329;6124.014786914997;6167.414095660905;6181.165542651865;6239.594426362272;6055.7577696893395;6097.004109670779;6099.020917883141;6102.334823646299;6109.252844142398 17:04:01 INFO - PROCESS | 1951 | |1;ext.html;456.8675084225575;491.22877082979716;496.83356075714636;536.2867386376118;571.7484306904959;37749.81125094374;38538;38556.421653675185;38589.42115868264;38716.0320991975;36797.44803827938;36937;40165.39751903724;40204.39693404594;40331.798341008325;9163.541822908845;9296.7675808105;9379.577918993662;9397.483138427384;9397.62409503619;9346.579403926806;9481.762955926122;9583.329166958316;9608.2793790466;9634.759131021745;9549.236061115094;9615.759606009835;9620.374675646095;9644.662436814713;9691;9518.333716639838;9609.903900961006;9611.663591774324;9624.278179136523;9641.987591302917;8985.921689397313;9292.814143717087;9343.71968840936;9447.527623618791;9488.715338539854;30727;38965.22069558621;39045.02387440309;39216.01959951024;39402.80298598488;2599.8310109842987;3002.6697063323018;3016;3296.587926509186;3433.4678124890684;21988;26538.469230615472;27528.311792205353;27628.58557121645;27707.86146069277;2368.3723813189517;2415.927522174338;2503.8482298142876;2820.4218135282217;2821.84479853608;288429;312171;317116.41441792645;344060;345134;31564.526532102038;32705.50941735876;32758.18104547358;57266.7136664314;57547.849043018905;32963.67036329654;33355;33573.328533429194;33613.495797562566;33799.662003380145;14421.711565768626;14601.92699036498;14631.561053168421;14675.92662036683;14711.55865324042;12329.013678905667;12431.93784031074;12457.813132803016;12474;12482.251064936132;7639.312461878458;7649.273319034683;7704.345130663844;7770.494917830323;7840.09838868526;3246.951295730566;3260;3319.817410042471;3323.567936168283;3336.8164750938445;960.7502049467049;978.9559469823876;1006.7684432580547;1019.9592016319413;1052.0689190066846;27646.585301220497;29456.41087178287;29726.81092756309;29789.40421191564;30083;1093.7265683579105;1111.9166062545341;1118.4128332625437;1135.9318440893574;1141.8116010858282;13637.590872273848;13899.652508687364;14069.577912662431;14319.212443315717;14417.711645767236;435640;440500;442485;443162;656268;55994.720026399606;56154.59613509613;57218.42781572214;57423.42576574264;57545;1282.6665067082813;1451.0205611212389;1454.3891565542285;1467.8972471926863;1477.4533422633694;28927;28968.85515572493;29213;29824.552631710107;30138.84930575333;49398.753006234736;49460.50539494559;49894.75052624859;50056.74404187653;50238.74880625573;492001;494075;499910;499997.5000124976;502708;25844.35389115287;26226.86886565555;37618.623813762606;38285.808570956964;39043.41434878536;38856;39073.43706251718;41933.370999434424;42769.572304277805;42770.78614606907;31642.734290628116;35222.647773522964;35293.117672058404;35822;36208.09479763027;35195.82402087973;35541.82229088838;35910.8204458976;36073.91778246657;36230;35719;35875;36003.81998089993;36603.26793464169;36976.260474789815;31693;32511.837440812644;32710.509342359408;32845.17887052843;33069.66930330666;19517.414477565697;19787.40637780869;24304.513909721354;24532.877335613208;24579.89390477397 17:04:01 INFO - PROCESS | 1951 | |2;jquery.html;325.63203579954654;376.482336786918;385.07581803671195;390.2507186202491;398.6671129606778;19900.50248743782;20333.49166270844;20362.592748145027;21334.61325013875;21351.57296854062;11835.644930652074;12153.513859445611;12271.386430678453;12290.692732681688;15960.920195399036;4537.160625284317;5272.367315922094;5475.863103422426;5489;5490.423505531921;3894.9026274343087;4689.484156742756;6026.819195424145;6043.123747056674;6055.909161362583;5616.943830561703;5641.435856414346;6016.067509536007;6031.577789554733;6046.365131661179;6573.901391479131;6577.80266592003;6584.275729669733;6588.50586206034;6622.801315960529;5599.216109744639;5750.338711048241;5800.216970708942;5822.330432000332;5827.562932780059;12688.429020694091;13064.608061758163;13150.60548183556;13191.86808131916;13256.801147982787;1950.444123424817;2015.748031496063;2135.8077773000505;2167.035669127223;2246.8314876384175;9951.353162044445;10065.396076235376;10186.949065254626;10206.132478739317;10721.83917241242;1728.5332960100827;1742.259539695637;1764.2590112152927;1773.4590949760318;1808.1320965936425;69999;126453.56103706459;134360.65639343578;134755.32622336922;135840.999999999;10609.310394824312;10980.780384392427;11502.252353596989;11650.242734222418;11829.290242585472;14831.851681483264;14851.554453366416;14920.701585968436;14942.70114597702;14949;8441.366897482714;8549.829003419896;8628.050914399411;8736.213740763234;8741.388102832741;6901.965490172517;8485.109063548362;8543.871841922377;8694.782630434289;8767.824643507094;4471.888202794956;4568.771561421916;4579.6794224403975;4619.3071039343695;4630.490646028934;1761.2338632694818;1840.6103391939;1945.5136215946013;1949.83426408754;1969.1335812242567;842.5155535567073;844.1684940333766;886.3751055505853;949.5821838391224;960.7598100474881;19604.607907842048;20115.798842011685;20250.59498810045;20313.695294570593;20371.8981405092;921.8156368726281;951.3863558005048;957.4398976598628;979.407458487619;980.2354163752285;10609.73475663099;10763.192760542834;10839.67480975572;10844.945775271073;10845.566177352976;575631;605303;608048;626899;627230;108985;127937;130973;132389;134447.6555234435;979.4074584876047;987.8370068938689;1007.2345017786389;1008.1581879130861;1012.402682417392;18035.819641803413;18164.545886352946;18256;18304.90170589769;18493.445196644123;51271.48728512816;51884;52297;52801.735991319794;52806.679833004484;75556.62221689077;75675;75896;76651.01768385037;76743;7717.729879454192;7818.983532140785;7940.6029698513685;8204;8347.41568090252;11050.447477626247;11642.602887653537;11859.940700296444;12371.257724536557;12656.810147847973;10740.516676749408;10890.727731806768;10942.726431839268;11103.72240693989;11161.218714690218;10704.785904282027;10930.945345273223;10932.836007460053;11031.393273370042;11165.330080195214;10434.582616695247;10531.420771857314;11095.88904110981;11178.217524773188;11263.66209013731;7210.2789721027475;7260.927390726025;7591.861220816922;8002.03924547921;8030;7981.600919954095;9278.675246366345;10177.491125443847;11409.486573104396;11478.483468244116 17:04:01 INFO - PROCESS | 1951 | |3;mootools.html;1564.3977068828497;1574.850389213025;1814.1473507451494;1900.2019151956176;1902.7336172935782;6810.965945170274;7057.682404291806;7809.687612495508;8673.653053877846;8676.305895528358;8371.455855369397;8386.748397548068;8457.407981441302;8542;8553.871691924625;1983.841292696588;2029.7564292284947;2261.8642881427086;2329.6621989811465;2348.8708121053337;2320.1299512682745;2343.285297984114;2343.8359314847967;2344.120954642009;2349.0721165139757;2312.2022902098747;2319.2926157521947;2325.093213646679;2373.679553260305;2376.6435034744754;1911.5125642960957;2334.2647066174186;2338.649202619612;2347.788699017097;2351.200591798788;2297.9885100574556;2298.862068275892;2299.6665483504967;2312.4681323295567;2353.976460235393;8570.785730356729;8629.95685021577;8666.86999695005;8687.348448866298;8704.173103555215;4495.640348772123;5160.690358578459;5360.142377219665;5384.515393614554;5390.407055223922;3329.900102996914;3355.76509644325;3550.1657110578994;3561.626029266916;3571.339302229096;989.2432289298692;1024.0834453164375;1031.3605564550028;1032.633415137623;1035.1718625099966;11116.055135313509;11222.66332010041;11294.265872718248;11318.547258109666;11328.376939268337;4398.076403955141;4514.367988481616;4565.840195593139;4567.314902764612;4572.337011133367;9035.141661542142;9051.59267832949;9139.223166030828;9152.084791520794;9185.40294880844;5189.76646050921;5232.084385232569;5240.475952404805;5244.7902083916215;5287.920681189786;4377.146456440963;4796.232602783608;4870.683405578626;4873.853784386474;4937.481564435754;3145.2608636970294;3789.3368660484307;3837.5586807516934;3842.116313247913;3927.7054220933546;911.1890417528405;985.9507024648882;1012.4026824173773;1188.3879801902028;1196.1985469735298;825.2820046559411;900.4057322167338;917.0874979395544;937.7702462896634;956.7416797464646;5682.943170568242;5858.56060795442;5936.436038576415;5955.106733989849;5971.28344598651;431.56627589273205;436.5220084008006;445.0498186372119;446.45755407180434;452.94791099023377;3834.213986132836;3918.74528155669;3918.7844668542934;3988.6809055275507;4021.155557332993;10224.233182511342;10546.209034322457;10770.515326810313;10771.51528181218;10810.783784324427;10600.628977985734;10715.142788576895;10715.410652414192;10720.088792452572;10724.946375268073;7288.96355518219;7739.458237923292;8068.798280043045;8144.75565733029;8146.2261085196815;5953.613015154001;5993.032740174699;6009.549283803644;6114.327423983447;6117.418845209698;9859;10005.399676019462;10025.799484010426;10039.146672532917;10043.849342259726;8687.566551519181;9281.350305478552;9393.29550283745;9912;10141.847872281774;6871.175458944758;7150.606716630636;7286.7085316586745;7487.850242995;7529.736459223901;8189.590520474072;8220.712275070344;8271.379646526415;8306.418550701394;8312;7200.675969581277;7295.161056478573;7332.0834895638045;7441.479096463412;7510.47426680149;7160.928390716026;7188.964055179691;7212;7230.13238411394;7299.05112335393;6803.081583986099;6867;6896.655167241718;6908.2400935896;6929.341712537301;4597.494275629611;4757.286407038971;4783.115123702126;4810.975945120252;4966.975165124151;4954.455009948975;4981.153203955264;5008.649394542347;5050.242463630485;5198.16829307309 17:04:01 INFO - PROCESS | 1951 | |4;prototype.html;246.668231228997;301.5311191097842;305.8180382672309;306.37499501017925;307.9384123175366;4563.65772567058;4739.862433016068;5451.23682684424;5515.613907026504;5531.225628412026;4393.494748103969;5462.836114916549;5469.15228139639;5496.983058134248;5510.9448905510935;3215.485522316436;3598.748087633867;4029.81865816039;4111.9103437589065;4121.381792731099;3655.415133578628;3795.8612416275146;4142.5028996520305;4157.189348077126;4208.97895510522;4120.443740095094;4120.938185927213;4124.113315637137;4143.653312673378;4169.4162817205615;3731.645493678096;3736;4115.382692596105;4165.0216147330075;4167.166566686659;3346.364190803764;3438.644528497962;3467;3483.7735547189604;3526.1889765354113;5189.962007598496;5212.53087222148;5223.895522089575;5228.503292187274;5228.712420816853;4133.503979522477;4559.521250268707;4621.075784843045;4662.660910049536;4700.459447163552;4800.543948324904;5501.807436739735;5762.193292938992;5775.480206781368;5800.9129863052085;3458.9654103458893;3568.1793187566745;3798.506194194737;3870.9419358709642;3905.258000979833;5818.6217895836635;5960.433758792908;6055.364186760371;6082.300535438393;6145.513774944271;3493.63316851732;3903.1998440319667;3913.295606790805;3928.194720082376;3932.724709270381;3335.382954153489;3738.1962877981364;3967.4048892666333;3976.089529431208;3985.980070099689;4134.669226461876;4628.305754136906;4647.721136731807;4694.600958918462;4708.552687494683;4348.63036641889;4637.3626373626375;4926.605871530269;5700.657960522299;5762.88474230513;1276.304414094321;1371.8285214348207;1411.484808045056;1431.0340520149066;1436.5044059799352;837.9036410812835;878.2359347367831;925.134998776133;943.2783920300975;946.4605175050156;5782;5860.2381690380835;5879.500242479352;5881.5882888198275;5882.705864706748;5750.734838335559;5797.855053623609;6098.908516372258;6435.646039467781;6459.257185423737;5529.30883639545;5818.5636077294375;5848.02334412595;5850;5883;2600;3146.3235404388165;3329.983350083234;3388.186835159593;3422.6235114137426;5841.299044114733;6308.243010838729;6644.235912870082;6731.889238275629;6772.492063095287;5929.05135178378;6224.719887605069;6230;6232.01438848919;6280.748770049238;5403.729813509309;6243.188385509946;6263.68681565929;6278.937210628018;6333.556651034475;5200.271961925409;5603.439656034527;5653.802116925888;5962.628595422991;6062.151298818081;4758.595519380891;5102.132637451717;5383.946160538345;5856.267966504187;5890.3801454600325;5545.611807173459;5794.217780599565;5797.710114494343;6131.356207598316;6232.501399887908;3196.856141473686;3275.7051865332232;3642.4354225095153;3877.224555088937;3950.2889479893897;5288.709120998382;5571.804986825604;6069.969650151721;6086.269647642312;6094.451499365078;4820.999285296562;5674.204982277536;5825.76696932118;5832.24180856486;5852.970735146297;5518.19986102021;5690.487856092972;5788;5808.94191058084;5838.883222335445;5325.174597937326;5351.3075977575345;5686.203931449518;5732.713364331683;5763.452472015152;5087.227461033672;5627.352854421794;5878.29460464747;5890.144604449584;5906.70466476683;5531.861703457445;5751.194832723506;5768.134779783066;5815.738291776795;5825.271841019872 17:04:01 INFO - PROCESS | 1951 | |5;yui.html;578.8581797459623;630.7918386932313;651.9934800651993;685.4858855858106;688.2257460357097;14277.857221427796;14757.040792348489;14760.261986900665;14787.89090818189;16054.919725401356;15379.846201537981;15453.381864725425;15488;15519;15528.53414397567;722.0685315942428;893.2675206330812;911.9726408207764;922.8477301245287;926.4302453990781;916.1525588830339;918.2470374293049;920.093707697917;923.0492592629569;923.1507013547553;961.8797650293714;961.9278554108434;965.9806803863919;966.4153187321674;966.6568368229283;916.2303664921498;946.957386917587;953.7949340891707;956.4643799472318;958.4393130018947;689.5035574386434;702.1328659106;717.5587013986413;720.045939130654;721.4589058206345;15166.620834479116;15220.162891040989;15248.008879422801;15252.237388130548;15265.771013434807;12068.577599784054;12145.332006739625;12183.269003859708;12255.877441225563;12460;9640.614375424975;9723.222142228675;9847.803043939153;9980.251481138943;10490.737731556772;3915;3917.485715424346;3933.744306620089;3944.842206311744;4053.4730485037053;50579.49420505822;69998.65000674964;70705;70869.99999999949;71675.64162179208;8929.06244844282;8958.014618391971;9015.729528114167;9075.682351117679;9153.496557689257;20191.697124543145;20191.899040504704;20198.899005505173;20255.898720506302;20257.480688948388;7093.92906070943;7191.617717097977;7332.523385979895;7405.925940740523;7493.325600695964;4050.23045621331;4119.567445418248;4142.668586513072;4148.585141485827;4157.563455837094;3083;3095.7059079387423;3390.4066788311948;3419.9487007694906;3423.7432192585657;626.101544283954;630.2972186012563;631.1983780598706;635.7234602947733;638.4147518348315;491.6533843640225;492.1363007921147;516.7054778776137;550.4742970463197;558.5671104893659;7864.5281283123195;7897.921020789719;8015.398845086642;8033.799155021171;8110.553919534483;568.630390246335;579.4524174654995;586.3550094895666;591.6982339007137;603.966781826995;3757.5490941086696;3768.9246215075545;3823.4456003879836;3853.344931361675;3904.9804750976064;81747;83289;83370.16629833746;86528.51036172312;87874;71044.22389440305;73782;74936.25063749401;75183;76389;64937.67531162314;65097.34902651008;67761.32238677649;68137.31862681409;68831.65584172147;8125.349972002344;8133.552654604054;8134;8239.423240373357;8335;43760.562394376924;43841.34237986369;43969.0427861494;44241.33637995497;44295.77852110719;61814;63156.68421658046;63373.049404259895;63598.04602930867;64272.67863660839;10513.842292365467;10740;10844.349339039683;11027.393493357942;11080.445977701243;11884.346360949885;11890.76218475643;11898.583549575724;11901.880981190077;11947.103967202669;10992.505337260003;11251.324920504796;11261;11371.374574398156;11492.230724249343;11048.889511105108;11197.888021119685;11206.215564910379;11501.079913607058;11550.537978481103;10847.566097356017;10847.945760271148;10961.50673219723;11115;11983.880161198276;9287.86068208991;9700.029997000243;9715.9028409715;10143.391396515937;10266.538005789607;9254.35219534626;9361.297902657192;9457.33798634089;9540.093691099335;9576.952115239379 17:04:01 INFO - PROCESS | 1951 | __end_tp_report 17:04:01 INFO - PROCESS | 1951 | __start_cc_report 17:04:01 INFO - PROCESS | 1951 | _x_x_mozilla_cycle_collect,428 17:04:01 INFO - PROCESS | 1951 | __end_cc_report 17:04:01 INFO - PROCESS | 1951 | __startTimestamp1461974641159__endTimestamp 17:04:01 INFO - PROCESS | 1951 | 17:04:01 INFO - PROCESS | 1951 | ------- Summary: start ------- 17:04:01 INFO - PROCESS | 1951 | Number of tests: 6 17:04:01 INFO - PROCESS | 1951 | 17:04:01 INFO - PROCESS | 1951 | [#0] dojo.html Cycles:165 Average:5760.48 Median:6004.64 stddev:648.79 (10.8%) stddev-sans-first:619.34 17:04:01 INFO - PROCESS | 1951 | Values: 3217.2 3461.2 3519.5 3527.3 3529.5 5673.4 5713.7 5735.4 5810.6 5819.4 5676.6 5684.5 5689.7 5754.2 5773.3 5175.8 5211.5 5242.6 5262.3 5300.7 5196.5 5350.5 5385.5 5393.3 5428.9 5156.7 5194.7 5299.8 5326.0 5346.7 5214.7 5227.4 5252.1 5262.6 5266.3 5169.6 5216.3 5222.5 5277.7 5308.8 4982.4 5314.9 5687.6 5782.1 5811.4 6172.8 6203.4 6211.5 6226.1 6268.9 6267.2 6270.2 6270.9 6278.3 6283.3 5701.7 5741.7 5775.2 5791.1 5813.2 6256.9 6326.4 6327.0 6333.9 6340.1 5683.9 6064.5 6192.0 6207.6 6228.6 6191.1 6239.5 6270.2 6294.9 6296.4 5100.5 6100.1 6188.7 6231.8 6241.9 4527.4 6139.5 6207.0 6250.7 6271.2 4955.4 4994.0 5001.2 5012.1 5033.0 4413.1 4471.6 4472.4 4509.9 4520.9 4827.6 5769.7 5893.1 5932.9 6004.6 6228.0 6250.0 6255.6 6258.1 6313.1 5886.1 5894.6 5894.9 5898.4 5934.6 5366.6 5480.9 5481.9 5492.4 5528.9 6468.9 6490.3 6491.6 6497.6 6535.0 6262.4 6281.7 6283.7 6283.9 6288.6 5404.6 6299.5 6368.3 6471.7 6487.6 6214.2 6227.5 6255.7 6257.8 6259.5 6178.5 6249.1 6251.2 6252.4 6256.8 4986.7 5881.3 6127.2 6158.8 6195.8 6141.3 6175.7 6195.8 6220.3 6240.2 5854.4 5934.5 6044.1 6051.3 6072.2 6089.3 6124.0 6167.4 6181.2 6239.6 6055.8 6097.0 6099.0 6102.3 6109.3 17:04:01 INFO - PROCESS | 1951 | 17:04:01 INFO - PROCESS | 1951 | [#1] ext.html Cycles:180 Average:56410.02 Median:35848.50 stddev:118560.90 (330.7%) stddev-sans-first:118819.06 17:04:01 INFO - PROCESS | 1951 | Values: 456.9 491.2 496.8 536.3 571.7 37749.8 38538.0 38556.4 38589.4 38716.0 36797.4 36937.0 40165.4 40204.4 40331.8 9163.5 9296.8 9379.6 9397.5 9397.6 9346.6 9481.8 9583.3 9608.3 9634.8 9549.2 9615.8 9620.4 9644.7 9691.0 9518.3 9609.9 9611.7 9624.3 9642.0 8985.9 9292.8 9343.7 9447.5 9488.7 30727.0 38965.2 39045.0 39216.0 39402.8 2599.8 3002.7 3016.0 3296.6 3433.5 21988.0 26538.5 27528.3 27628.6 27707.9 2368.4 2415.9 2503.8 2820.4 2821.8 288429.0 312171.0 317116.4 344060.0 345134.0 31564.5 32705.5 32758.2 57266.7 57547.8 32963.7 33355.0 33573.3 33613.5 33799.7 14421.7 14601.9 14631.6 14675.9 14711.6 12329.0 12431.9 12457.8 12474.0 12482.3 7639.3 7649.3 7704.3 7770.5 7840.1 3247.0 3260.0 3319.8 3323.6 3336.8 960.8 979.0 1006.8 1020.0 1052.1 27646.6 29456.4 29726.8 29789.4 30083.0 1093.7 1111.9 1118.4 1135.9 1141.8 13637.6 13899.7 14069.6 14319.2 14417.7 435640.0 440500.0 442485.0 443162.0 656268.0 55994.7 56154.6 57218.4 57423.4 57545.0 1282.7 1451.0 1454.4 1467.9 1477.5 28927.0 28968.9 29213.0 29824.6 30138.8 49398.8 49460.5 49894.8 50056.7 50238.7 492001.0 494075.0 499910.0 499997.5 502708.0 25844.4 26226.9 37618.6 38285.8 39043.4 38856.0 39073.4 41933.4 42769.6 42770.8 31642.7 35222.6 35293.1 35822.0 36208.1 35195.8 35541.8 35910.8 36073.9 36230.0 35719.0 35875.0 36003.8 36603.3 36976.3 31693.0 32511.8 32710.5 32845.2 33069.7 19517.4 19787.4 24304.5 24532.9 24579.9 17:04:01 INFO - PROCESS | 1951 | 17:04:01 INFO - PROCESS | 1951 | [#2] jquery.html Cycles:180 Average:34613.96 Median:11743.69 stddev:101747.88 (866.4%) stddev-sans-first:102000.73 17:04:01 INFO - PROCESS | 1951 | Values: 325.6 376.5 385.1 390.3 398.7 19900.5 20333.5 20362.6 21334.6 21351.6 11835.6 12153.5 12271.4 12290.7 15960.9 4537.2 5272.4 5475.9 5489.0 5490.4 3894.9 4689.5 6026.8 6043.1 6055.9 5616.9 5641.4 6016.1 6031.6 6046.4 6573.9 6577.8 6584.3 6588.5 6622.8 5599.2 5750.3 5800.2 5822.3 5827.6 12688.4 13064.6 13150.6 13191.9 13256.8 1950.4 2015.7 2135.8 2167.0 2246.8 9951.4 10065.4 10186.9 10206.1 10721.8 1728.5 1742.3 1764.3 1773.5 1808.1 69999.0 126453.6 134360.7 134755.3 135841.0 10609.3 10980.8 11502.3 11650.2 11829.3 14831.9 14851.6 14920.7 14942.7 14949.0 8441.4 8549.8 8628.1 8736.2 8741.4 6902.0 8485.1 8543.9 8694.8 8767.8 4471.9 4568.8 4579.7 4619.3 4630.5 1761.2 1840.6 1945.5 1949.8 1969.1 842.5 844.2 886.4 949.6 960.8 19604.6 20115.8 20250.6 20313.7 20371.9 921.8 951.4 957.4 979.4 980.2 10609.7 10763.2 10839.7 10844.9 10845.6 575631.0 605303.0 608048.0 626899.0 627230.0 108985.0 127937.0 130973.0 132389.0 134447.7 979.4 987.8 1007.2 1008.2 1012.4 18035.8 18164.5 18256.0 18304.9 18493.4 51271.5 51884.0 52297.0 52801.7 52806.7 75556.6 75675.0 75896.0 76651.0 76743.0 7717.7 7819.0 7940.6 8204.0 8347.4 11050.4 11642.6 11859.9 12371.3 12656.8 10740.5 10890.7 10942.7 11103.7 11161.2 10704.8 10930.9 10932.8 11031.4 11165.3 10434.6 10531.4 11095.9 11178.2 11263.7 7210.3 7260.9 7591.9 8002.0 8030.0 7981.6 9278.7 10177.5 11409.5 11478.5 17:04:01 INFO - PROCESS | 1951 | 17:04:01 INFO - PROCESS | 1951 | [#3] mootools.html Cycles:180 Average:5558.86 Median:4905.67 stddev:3128.05 (63.8%) stddev-sans-first:3122.42 17:04:01 INFO - PROCESS | 1951 | Values: 1564.4 1574.9 1814.1 1900.2 1902.7 6811.0 7057.7 7809.7 8673.7 8676.3 8371.5 8386.7 8457.4 8542.0 8553.9 1983.8 2029.8 2261.9 2329.7 2348.9 2320.1 2343.3 2343.8 2344.1 2349.1 2312.2 2319.3 2325.1 2373.7 2376.6 1911.5 2334.3 2338.6 2347.8 2351.2 2298.0 2298.9 2299.7 2312.5 2354.0 8570.8 8630.0 8666.9 8687.3 8704.2 4495.6 5160.7 5360.1 5384.5 5390.4 3329.9 3355.8 3550.2 3561.6 3571.3 989.2 1024.1 1031.4 1032.6 1035.2 11116.1 11222.7 11294.3 11318.5 11328.4 4398.1 4514.4 4565.8 4567.3 4572.3 9035.1 9051.6 9139.2 9152.1 9185.4 5189.8 5232.1 5240.5 5244.8 5287.9 4377.1 4796.2 4870.7 4873.9 4937.5 3145.3 3789.3 3837.6 3842.1 3927.7 911.2 986.0 1012.4 1188.4 1196.2 825.3 900.4 917.1 937.8 956.7 5682.9 5858.6 5936.4 5955.1 5971.3 431.6 436.5 445.0 446.5 452.9 3834.2 3918.7 3918.8 3988.7 4021.2 10224.2 10546.2 10770.5 10771.5 10810.8 10600.6 10715.1 10715.4 10720.1 10724.9 7289.0 7739.5 8068.8 8144.8 8146.2 5953.6 5993.0 6009.5 6114.3 6117.4 9859.0 10005.4 10025.8 10039.1 10043.8 8687.6 9281.4 9393.3 9912.0 10141.8 6871.2 7150.6 7286.7 7487.9 7529.7 8189.6 8220.7 8271.4 8306.4 8312.0 7200.7 7295.2 7332.1 7441.5 7510.5 7160.9 7189.0 7212.0 7230.1 7299.1 6803.1 6867.0 6896.7 6908.2 6929.3 4597.5 4757.3 4783.1 4811.0 4967.0 4954.5 4981.2 5008.6 5050.2 5198.2 17:04:01 INFO - PROCESS | 1951 | 17:04:01 INFO - PROCESS | 1951 | [#4] prototype.html Cycles:180 Average:4696.48 Median:5393.84 stddev:1505.61 (27.9%) stddev-sans-first:1472.33 17:04:01 INFO - PROCESS | 1951 | Values: 246.7 301.5 305.8 306.4 307.9 4563.7 4739.9 5451.2 5515.6 5531.2 4393.5 5462.8 5469.2 5497.0 5510.9 3215.5 3598.7 4029.8 4111.9 4121.4 3655.4 3795.9 4142.5 4157.2 4209.0 4120.4 4120.9 4124.1 4143.7 4169.4 3731.6 3736.0 4115.4 4165.0 4167.2 3346.4 3438.6 3467.0 3483.8 3526.2 5190.0 5212.5 5223.9 5228.5 5228.7 4133.5 4559.5 4621.1 4662.7 4700.5 4800.5 5501.8 5762.2 5775.5 5800.9 3459.0 3568.2 3798.5 3870.9 3905.3 5818.6 5960.4 6055.4 6082.3 6145.5 3493.6 3903.2 3913.3 3928.2 3932.7 3335.4 3738.2 3967.4 3976.1 3986.0 4134.7 4628.3 4647.7 4694.6 4708.6 4348.6 4637.4 4926.6 5700.7 5762.9 1276.3 1371.8 1411.5 1431.0 1436.5 837.9 878.2 925.1 943.3 946.5 5782.0 5860.2 5879.5 5881.6 5882.7 5750.7 5797.9 6098.9 6435.6 6459.3 5529.3 5818.6 5848.0 5850.0 5883.0 2600.0 3146.3 3330.0 3388.2 3422.6 5841.3 6308.2 6644.2 6731.9 6772.5 5929.1 6224.7 6230.0 6232.0 6280.7 5403.7 6243.2 6263.7 6278.9 6333.6 5200.3 5603.4 5653.8 5962.6 6062.2 4758.6 5102.1 5383.9 5856.3 5890.4 5545.6 5794.2 5797.7 6131.4 6232.5 3196.9 3275.7 3642.4 3877.2 3950.3 5288.7 5571.8 6070.0 6086.3 6094.5 4821.0 5674.2 5825.8 5832.2 5853.0 5518.2 5690.5 5788.0 5808.9 5838.9 5325.2 5351.3 5686.2 5732.7 5763.5 5087.2 5627.4 5878.3 5890.1 5906.7 5531.9 5751.2 5768.1 5815.7 5825.3 17:04:01 INFO - PROCESS | 1951 | 17:04:01 INFO - PROCESS | 1951 | [#5] yui.html Cycles:180 Average:17167.97 Median:31220.05 stddev:23348.43 (74.8%) stddev-sans-first:23380.70 17:04:01 INFO - PROCESS | 1951 | Values: 578.9 630.8 652.0 685.5 688.2 14277.9 14757.0 14760.3 14787.9 16054.9 15379.8 15453.4 15488.0 15519.0 15528.5 722.1 893.3 912.0 922.8 926.4 916.2 918.2 920.1 923.0 923.2 961.9 961.9 966.0 966.4 966.7 916.2 947.0 953.8 956.5 958.4 689.5 702.1 717.6 720.0 721.5 15166.6 15220.2 15248.0 15252.2 15265.8 12068.6 12145.3 12183.3 12255.9 12460.0 9640.6 9723.2 9847.8 9980.3 10490.7 3915.0 3917.5 3933.7 3944.8 4053.5 50579.5 69998.7 70705.0 70870.0 71675.6 8929.1 8958.0 9015.7 9075.7 9153.5 20191.7 20191.9 20198.9 20255.9 20257.5 7093.9 7191.6 7332.5 7405.9 7493.3 4050.2 4119.6 4142.7 4148.6 4157.6 3083.0 3095.7 3390.4 3419.9 3423.7 626.1 630.3 631.2 635.7 638.4 491.7 492.1 516.7 550.5 558.6 7864.5 7897.9 8015.4 8033.8 8110.6 568.6 579.5 586.4 591.7 604.0 3757.5 3768.9 3823.4 3853.3 3905.0 81747.0 83289.0 83370.2 86528.5 87874.0 71044.2 73782.0 74936.3 75183.0 76389.0 64937.7 65097.3 67761.3 68137.3 68831.7 8125.3 8133.6 8134.0 8239.4 8335.0 43760.6 43841.3 43969.0 44241.3 44295.8 61814.0 63156.7 63373.0 63598.0 64272.7 10513.8 10740.0 10844.3 11027.4 11080.4 11884.3 11890.8 11898.6 11901.9 11947.1 10992.5 11251.3 11261.0 11371.4 11492.2 11048.9 11197.9 11206.2 11501.1 11550.5 10847.6 10847.9 10961.5 11115.0 11983.9 9287.9 9700.0 9715.9 10143.4 10266.5 9254.4 9361.3 9457.3 9540.1 9577.0 17:04:01 INFO - PROCESS | 1951 | -------- Summary: end -------- 17:04:01 INFO - PROCESS | 1951 | 17:04:01 INFO - TEST-INFO | 1951: exit 0 17:04:01 INFO - TEST-OK | dromaeo_css | took 1113185ms 17:04:01 INFO - TEST-START | kraken 17:04:01 INFO - operating with platform_type : mac_ 17:04:01 INFO - Initialising browser for kraken test... 17:04:01 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpTYxTm_/profile http://localhost:51968/getInfo.html) 17:04:04 INFO - PROCESS | 1957 | __metrics Screen width/height:1600/1200 17:04:04 INFO - PROCESS | 1957 | colorDepth:24 17:04:04 INFO - PROCESS | 1957 | Browser inner width/height: 1024/689 17:04:04 INFO - PROCESS | 1957 | __metrics 17:04:04 ERROR - PROCESS | 1957 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 17:04:05 INFO - TEST-INFO | 1957: exit 0 17:04:05 INFO - Browser initialized. 17:04:05 INFO - Running cycle 1/1 for kraken test... 17:04:05 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpTYxTm_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:04:17 INFO - PROCESS | 1960 | RSS: Main: 250499072 17:04:17 INFO - PROCESS | 1960 | 17:04:20 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51968/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51968/tests/kraken/driver.html?testName=audio-beat-detection) 17:04:21 INFO - PROCESS | 1960 | RSS: Main: 262418432 17:04:21 INFO - PROCESS | 1960 | 17:04:24 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51968/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51968/tests/kraken/driver.html?testName=audio-dft) 17:04:24 INFO - PROCESS | 1960 | RSS: Main: 267132928 17:04:24 INFO - PROCESS | 1960 | 17:04:28 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51968/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51968/tests/kraken/driver.html?testName=audio-fft) 17:04:28 INFO - PROCESS | 1960 | RSS: Main: 267464704 17:04:28 INFO - PROCESS | 1960 | 17:04:30 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51968/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51968/tests/kraken/driver.html?testName=audio-oscillator) 17:04:31 INFO - PROCESS | 1960 | RSS: Main: 268603392 17:04:31 INFO - PROCESS | 1960 | 17:04:33 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51968/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51968/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:04:34 INFO - PROCESS | 1960 | RSS: Main: 268554240 17:04:34 INFO - PROCESS | 1960 | 17:04:37 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51968/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51968/tests/kraken/driver.html?testName=imaging-darkroom) 17:04:37 INFO - PROCESS | 1960 | RSS: Main: 268972032 17:04:37 INFO - PROCESS | 1960 | 17:04:40 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51968/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51968/tests/kraken/driver.html?testName=imaging-desaturate) 17:04:41 INFO - PROCESS | 1960 | RSS: Main: 269082624 17:04:41 INFO - PROCESS | 1960 | 17:04:44 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51968/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51968/tests/kraken/driver.html?testName=json-parse-financial) 17:04:45 INFO - PROCESS | 1960 | RSS: Main: 269230080 17:04:45 INFO - PROCESS | 1960 | 17:04:46 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51968/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51968/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:04:47 INFO - PROCESS | 1960 | RSS: Main: 268746752 17:04:47 INFO - PROCESS | 1960 | 17:04:49 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51968/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51968/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:04:50 INFO - PROCESS | 1960 | RSS: Main: 268722176 17:04:50 INFO - PROCESS | 1960 | 17:04:52 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51968/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51968/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:04:53 INFO - PROCESS | 1960 | RSS: Main: 268845056 17:04:53 INFO - PROCESS | 1960 | 17:04:55 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51968/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51968/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:04:56 INFO - PROCESS | 1960 | RSS: Main: 268881920 17:04:56 INFO - PROCESS | 1960 | 17:04:59 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51968/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51968/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:05:00 INFO - PROCESS | 1960 | RSS: Main: 268677120 17:05:00 INFO - PROCESS | 1960 | 17:05:01 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51968/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51968/tests/kraken/driver.html?testName=ai-astar) 17:05:02 INFO - PROCESS | 1960 | RSS: Main: 270331904 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - PROCESS | 1960 | __start_tp_report 17:05:02 INFO - PROCESS | 1960 | _x_x_mozilla_page_load 17:05:02 INFO - PROCESS | 1960 | _x_x_mozilla_page_load_details 17:05:02 INFO - PROCESS | 1960 | |i|pagename|runs| 17:05:02 INFO - PROCESS | 1960 | |0;ai-astar;117;112;115;116;118;127;116;116;118;117 17:05:02 INFO - PROCESS | 1960 | |1;audio-beat-detection;159;137;191;182;159;179;185;154;157;117 17:05:02 INFO - PROCESS | 1960 | |2;audio-dft;197;207;223;205;195;201;222;196;200;198 17:05:02 INFO - PROCESS | 1960 | |3;audio-fft;69;72;101;143;72;91;90;96;136;61 17:05:02 INFO - PROCESS | 1960 | |4;audio-oscillator;78;83;131;79;82;80;116;84;81;122 17:05:02 INFO - PROCESS | 1960 | |5;imaging-gaussian-blur;88;87;89;85;92;115;84;132;132;124 17:05:02 INFO - PROCESS | 1960 | |6;imaging-darkroom;95;97;94;94;96;101;151;110;151;152 17:05:02 INFO - PROCESS | 1960 | |7;imaging-desaturate;116;117;89;90;92;113;128;88;147;147 17:05:02 INFO - PROCESS | 1960 | |8;json-parse-financial;59;60;61;62;62;61;69;101;100;92 17:05:02 INFO - PROCESS | 1960 | |9;json-stringify-tinderbox;64;68;72;70;101;119;73;79;71;70 17:05:02 INFO - PROCESS | 1960 | |10;stanford-crypto-aes;85;84;86;114;73;102;115;116;113;96 17:05:02 INFO - PROCESS | 1960 | |11;stanford-crypto-ccm;141;141;138;134;156;155;135;138;176;139 17:05:02 INFO - PROCESS | 1960 | |12;stanford-crypto-pbkdf2;176;176;176;174;172;173;174;185;232;216 17:05:02 INFO - PROCESS | 1960 | |13;stanford-crypto-sha256-iterative;70;71;73;73;72;80;68;72;72;73 17:05:02 INFO - PROCESS | 1960 | __end_tp_report 17:05:02 INFO - PROCESS | 1960 | __start_cc_report 17:05:02 INFO - PROCESS | 1960 | _x_x_mozilla_cycle_collect,1097 17:05:02 INFO - PROCESS | 1960 | __end_cc_report 17:05:02 INFO - PROCESS | 1960 | __startTimestamp1461974702116__endTimestamp 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - PROCESS | 1960 | ------- Summary: start ------- 17:05:02 INFO - PROCESS | 1960 | Number of tests: 14 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - PROCESS | 1960 | [#0] ai-astar Cycles:10 Average:117.20 Median:117.00 stddev:3.85 (3.3%) stddev-sans-first:4.09 17:05:02 INFO - PROCESS | 1960 | Values: 117.0 112.0 115.0 116.0 118.0 127.0 116.0 116.0 118.0 117.0 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - PROCESS | 1960 | [#1] audio-beat-detection Cycles:10 Average:162.00 Median:169.00 stddev:23.13 (13.7%) stddev-sans-first:24.51 17:05:02 INFO - PROCESS | 1960 | Values: 159.0 137.0 191.0 182.0 159.0 179.0 185.0 154.0 157.0 117.0 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - PROCESS | 1960 | [#2] audio-dft Cycles:10 Average:204.40 Median:203.00 stddev:10.27 (5.1%) stddev-sans-first:10.53 17:05:02 INFO - PROCESS | 1960 | Values: 197.0 207.0 223.0 205.0 195.0 201.0 222.0 196.0 200.0 198.0 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - PROCESS | 1960 | [#3] audio-fft Cycles:10 Average:93.10 Median:72.00 stddev:27.72 (38.5%) stddev-sans-first:28.00 17:05:02 INFO - PROCESS | 1960 | Values: 69.0 72.0 101.0 143.0 72.0 91.0 90.0 96.0 136.0 61.0 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - PROCESS | 1960 | [#4] audio-oscillator Cycles:10 Average:93.60 Median:80.50 stddev:20.67 (25.7%) stddev-sans-first:21.14 17:05:02 INFO - PROCESS | 1960 | Values: 78.0 83.0 131.0 79.0 82.0 80.0 116.0 84.0 81.0 122.0 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - PROCESS | 1960 | [#5] imaging-gaussian-blur Cycles:10 Average:102.80 Median:86.00 stddev:20.41 (23.7%) stddev-sans-first:20.94 17:05:02 INFO - PROCESS | 1960 | Values: 88.0 87.0 89.0 85.0 92.0 115.0 84.0 132.0 132.0 124.0 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - PROCESS | 1960 | [#6] imaging-darkroom Cycles:10 Average:114.10 Median:94.00 stddev:26.12 (27.8%) stddev-sans-first:26.78 17:05:02 INFO - PROCESS | 1960 | Values: 95.0 97.0 94.0 94.0 96.0 101.0 151.0 110.0 151.0 152.0 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - PROCESS | 1960 | [#7] imaging-desaturate Cycles:10 Average:112.70 Median:117.50 stddev:22.93 (19.5%) stddev-sans-first:24.29 17:05:02 INFO - PROCESS | 1960 | Values: 116.0 117.0 89.0 90.0 92.0 113.0 128.0 88.0 147.0 147.0 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - PROCESS | 1960 | [#8] json-parse-financial Cycles:10 Average:72.70 Median:61.50 stddev:17.59 (28.6%) stddev-sans-first:17.94 17:05:02 INFO - PROCESS | 1960 | Values: 59.0 60.0 61.0 62.0 62.0 61.0 69.0 101.0 100.0 92.0 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - PROCESS | 1960 | [#9] json-stringify-tinderbox Cycles:10 Average:78.70 Median:70.50 stddev:17.45 (24.7%) stddev-sans-first:17.68 17:05:02 INFO - PROCESS | 1960 | Values: 64.0 68.0 72.0 70.0 101.0 119.0 73.0 79.0 71.0 70.0 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - PROCESS | 1960 | [#10] stanford-crypto-aes Cycles:10 Average:98.40 Median:78.50 stddev:15.80 (20.1%) stddev-sans-first:16.00 17:05:02 INFO - PROCESS | 1960 | Values: 85.0 84.0 86.0 114.0 73.0 102.0 115.0 116.0 113.0 96.0 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - PROCESS | 1960 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:141.00 stddev:13.20 (9.4%) stddev-sans-first:13.91 17:05:02 INFO - PROCESS | 1960 | Values: 141.0 141.0 138.0 134.0 156.0 155.0 135.0 138.0 176.0 139.0 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - PROCESS | 1960 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.40 Median:176.00 stddev:20.99 (11.9%) stddev-sans-first:21.99 17:05:02 INFO - PROCESS | 1960 | Values: 176.0 176.0 176.0 174.0 172.0 173.0 174.0 185.0 232.0 216.0 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - PROCESS | 1960 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.40 Median:72.50 stddev:3.10 (4.3%) stddev-sans-first:3.16 17:05:02 INFO - PROCESS | 1960 | Values: 70.0 71.0 73.0 73.0 72.0 80.0 68.0 72.0 72.0 73.0 17:05:02 INFO - PROCESS | 1960 | -------- Summary: end -------- 17:05:02 INFO - PROCESS | 1960 | 17:05:02 INFO - TEST-INFO | 1960: exit 0 17:05:02 INFO - TEST-OK | kraken | took 61135ms 17:05:02 INFO - SUITE-END | took 1174s 17:05:03 INFO - Completed test suite (00:19:34) 17:05:03 INFO - javascript benchmark 17:05:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3217.227865312325, 3461.1693193633528, 3519.4720791881214, 3527.329807336606, 3529.5235143255654, 5673.404292549285, 5713.685747283908, 5735.397783232763, 5810.622309549876, 5819.447152520509, 5676.574256930737, 5684.54523638109, 5689.687067211302, 5754.16564598134, 5773.336066352371, 5175.844724658247, 5211.5049070338255, 5242.554382877441, 5262.289590905236, 5300.681959082448, 5196.454372290912, 5350.491703288181, 5385.461453854622, 5393.298871146745, 5428.864278393052, 5156.74216289184, 5194.68831870089, 5299.8410047698635, 5326.041312563737, 5346.732663366835, 5214.739263036833, 5227.424983251839, 5252.107141785906, 5262.578993680497, 5266.34170728659, 5169.638125331228, 5216.3218781558535, 5222.477752224785, 5277.656952298088, 5308.814191503279, 4982.402111746614, 5314.893702125974, 5687.573431992617, 5782.132680097977, 5811.389804070554, 6172.84567885802, 6203.379662033805, 6211.534134939852, 6226.128342032074, 6268.905966410462, 6267.247930248353, 6270.216222972129, 6270.905936410957, 6278.340774218733, 6283.277423096357, 5701.743421546041, 5741.741621627037, 5775.249217601685, 5791.102379131242, 5813.215215945877, 6256.919716328991, 6326.43062124411, 6327.0, 6333.904991425133, 6340.144080549159, 5683.920055189501, 6064.514838812885, 6192.0, 6207.6275423474735, 6228.595141315891, 6191.071339299141, 6239.469645080128, 6270.247570291503, 6294.937050629435, 6296.433321001132, 5100.4899510049445, 6100.145979562864, 6188.659623720649, 6231.781887634002, 6241.906371404432, 4527.366168736379, 6139.4781443577795, 6207.006878899445, 6250.656213908188, 6271.153394291712, 4955.355803745562, 4994.0, 5001.19980803077, 5012.072766538129, 5032.974835125801, 4413.095315460387, 4471.575200355961, 4472.351509031172, 4509.864704058883, 4520.9095818084115, 4827.61379089672, 5769.701817091121, 5893.116032595145, 5932.851678707981, 6004.639721616717, 6228.0, 6249.96875015622, 6255.562110652301, 6258.123862659231, 6313.05304204373, 5886.055713200932, 5894.583220879419, 5894.941050589525, 5898.439648233411, 5934.584579079509, 5366.597505187126, 5480.945190548123, 5481.862953426196, 5492.368377636623, 5528.861778455571, 6468.870622587522, 6490.3185165558825, 6491.578047426902, 6497.577657452251, 6535.0, 6262.405071518198, 6281.685915704105, 6283.654399008098, 6283.874322513616, 6288.622682639057, 5404.621676482609, 6299.4645455135, 6368.267649220399, 6471.741130354922, 6487.610743355413, 6214.161088253028, 6227.470664993345, 6255.655938923403, 6257.843553911189, 6259.467945224525, 6178.5366097543765, 6249.093881387264, 6251.156093927261, 6252.406021427957, 6256.812295631138, 4986.675866068839, 5881.2942446906645, 6127.20346354971, 6158.815235542941, 6195.783147589813, 6141.293751218488, 6175.722092505938, 6195.752169913153, 6220.284667263322, 6240.188775459162, 5854.443827836349, 5934.465898069195, 6044.063170208625, 6051.281033165003, 6072.210612620508, 6089.269287685329, 6124.014786914997, 6167.414095660905, 6181.165542651865, 6239.594426362272, 6055.7577696893395, 6097.004109670779, 6099.020917883141, 6102.334823646299, 6109.252844142398], "value": 5721.967252735935, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.8675084225575, 491.22877082979716, 496.83356075714636, 536.2867386376118, 571.7484306904959, 37749.81125094374, 38538.0, 38556.421653675185, 38589.42115868264, 38716.0320991975, 36797.44803827938, 36937.0, 40165.39751903724, 40204.39693404594, 40331.798341008325, 9163.541822908845, 9296.7675808105, 9379.577918993662, 9397.483138427384, 9397.62409503619, 9346.579403926806, 9481.762955926122, 9583.329166958316, 9608.2793790466, 9634.759131021745, 9549.236061115094, 9615.759606009835, 9620.374675646095, 9644.662436814713, 9691.0, 9518.333716639838, 9609.903900961006, 9611.663591774324, 9624.278179136523, 9641.987591302917, 8985.921689397313, 9292.814143717087, 9343.71968840936, 9447.527623618791, 9488.715338539854, 30727.0, 38965.22069558621, 39045.02387440309, 39216.01959951024, 39402.80298598488, 2599.8310109842987, 3002.6697063323018, 3016.0, 3296.587926509186, 3433.4678124890684, 21988.0, 26538.469230615472, 27528.311792205353, 27628.58557121645, 27707.86146069277, 2368.3723813189517, 2415.927522174338, 2503.8482298142876, 2820.4218135282217, 2821.84479853608, 288429.0, 312171.0, 317116.41441792645, 344060.0, 345134.0, 31564.526532102038, 32705.50941735876, 32758.18104547358, 57266.7136664314, 57547.849043018905, 32963.67036329654, 33355.0, 33573.328533429194, 33613.495797562566, 33799.662003380145, 14421.711565768626, 14601.92699036498, 14631.561053168421, 14675.92662036683, 14711.55865324042, 12329.013678905667, 12431.93784031074, 12457.813132803016, 12474.0, 12482.251064936132, 7639.312461878458, 7649.273319034683, 7704.345130663844, 7770.494917830323, 7840.09838868526, 3246.951295730566, 3260.0, 3319.817410042471, 3323.567936168283, 3336.8164750938445, 960.7502049467049, 978.9559469823876, 1006.7684432580547, 1019.9592016319413, 1052.0689190066846, 27646.585301220497, 29456.41087178287, 29726.81092756309, 29789.40421191564, 30083.0, 1093.7265683579105, 1111.9166062545341, 1118.4128332625437, 1135.9318440893574, 1141.8116010858282, 13637.590872273848, 13899.652508687364, 14069.577912662431, 14319.212443315717, 14417.711645767236, 435640.0, 440500.0, 442485.0, 443162.0, 656268.0, 55994.720026399606, 56154.59613509613, 57218.42781572214, 57423.42576574264, 57545.0, 1282.6665067082813, 1451.0205611212389, 1454.3891565542285, 1467.8972471926863, 1477.4533422633694, 28927.0, 28968.85515572493, 29213.0, 29824.552631710107, 30138.84930575333, 49398.753006234736, 49460.50539494559, 49894.75052624859, 50056.74404187653, 50238.74880625573, 492001.0, 494075.0, 499910.0, 499997.5000124976, 502708.0, 25844.35389115287, 26226.86886565555, 37618.623813762606, 38285.808570956964, 39043.41434878536, 38856.0, 39073.43706251718, 41933.370999434424, 42769.572304277805, 42770.78614606907, 31642.734290628116, 35222.647773522964, 35293.117672058404, 35822.0, 36208.09479763027, 35195.82402087973, 35541.82229088838, 35910.8204458976, 36073.91778246657, 36230.0, 35719.0, 35875.0, 36003.81998089993, 36603.26793464169, 36976.260474789815, 31693.0, 32511.837440812644, 32710.509342359408, 32845.17887052843, 33069.66930330666, 19517.414477565697, 19787.40637780869, 24304.513909721354, 24532.877335613208, 24579.89390477397], "value": 17519.107369972997, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.63203579954654, 376.482336786918, 385.07581803671195, 390.2507186202491, 398.6671129606778, 19900.50248743782, 20333.49166270844, 20362.592748145027, 21334.61325013875, 21351.57296854062, 11835.644930652074, 12153.513859445611, 12271.386430678453, 12290.692732681688, 15960.920195399036, 4537.160625284317, 5272.367315922094, 5475.863103422426, 5489.0, 5490.423505531921, 3894.9026274343087, 4689.484156742756, 6026.819195424145, 6043.123747056674, 6055.909161362583, 5616.943830561703, 5641.435856414346, 6016.067509536007, 6031.577789554733, 6046.365131661179, 6573.901391479131, 6577.80266592003, 6584.275729669733, 6588.50586206034, 6622.801315960529, 5599.216109744639, 5750.338711048241, 5800.216970708942, 5822.330432000332, 5827.562932780059, 12688.429020694091, 13064.608061758163, 13150.60548183556, 13191.86808131916, 13256.801147982787, 1950.444123424817, 2015.748031496063, 2135.8077773000505, 2167.035669127223, 2246.8314876384175, 9951.353162044445, 10065.396076235376, 10186.949065254626, 10206.132478739317, 10721.83917241242, 1728.5332960100827, 1742.259539695637, 1764.2590112152927, 1773.4590949760318, 1808.1320965936425, 69999.0, 126453.56103706459, 134360.65639343578, 134755.32622336922, 135840.999999999, 10609.310394824312, 10980.780384392427, 11502.252353596989, 11650.242734222418, 11829.290242585472, 14831.851681483264, 14851.554453366416, 14920.701585968436, 14942.70114597702, 14949.0, 8441.366897482714, 8549.829003419896, 8628.050914399411, 8736.213740763234, 8741.388102832741, 6901.965490172517, 8485.109063548362, 8543.871841922377, 8694.782630434289, 8767.824643507094, 4471.888202794956, 4568.771561421916, 4579.6794224403975, 4619.3071039343695, 4630.490646028934, 1761.2338632694818, 1840.6103391939, 1945.5136215946013, 1949.83426408754, 1969.1335812242567, 842.5155535567073, 844.1684940333766, 886.3751055505853, 949.5821838391224, 960.7598100474881, 19604.607907842048, 20115.798842011685, 20250.59498810045, 20313.695294570593, 20371.8981405092, 921.8156368726281, 951.3863558005048, 957.4398976598628, 979.407458487619, 980.2354163752285, 10609.73475663099, 10763.192760542834, 10839.67480975572, 10844.945775271073, 10845.566177352976, 575631.0, 605303.0, 608048.0, 626899.0, 627230.0, 108985.0, 127937.0, 130973.0, 132389.0, 134447.6555234435, 979.4074584876047, 987.8370068938689, 1007.2345017786389, 1008.1581879130861, 1012.402682417392, 18035.819641803413, 18164.545886352946, 18256.0, 18304.90170589769, 18493.445196644123, 51271.48728512816, 51884.0, 52297.0, 52801.735991319794, 52806.679833004484, 75556.62221689077, 75675.0, 75896.0, 76651.01768385037, 76743.0, 7717.729879454192, 7818.983532140785, 7940.6029698513685, 8204.0, 8347.41568090252, 11050.447477626247, 11642.602887653537, 11859.940700296444, 12371.257724536557, 12656.810147847973, 10740.516676749408, 10890.727731806768, 10942.726431839268, 11103.72240693989, 11161.218714690218, 10704.785904282027, 10930.945345273223, 10932.836007460053, 11031.393273370042, 11165.330080195214, 10434.582616695247, 10531.420771857314, 11095.88904110981, 11178.217524773188, 11263.66209013731, 7210.2789721027475, 7260.927390726025, 7591.861220816922, 8002.03924547921, 8030.0, 7981.600919954095, 9278.675246366345, 10177.491125443847, 11409.486573104396, 11478.483468244116], "value": 9138.700464568787, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1564.3977068828497, 1574.850389213025, 1814.1473507451494, 1900.2019151956176, 1902.7336172935782, 6810.965945170274, 7057.682404291806, 7809.687612495508, 8673.653053877846, 8676.305895528358, 8371.455855369397, 8386.748397548068, 8457.407981441302, 8542.0, 8553.871691924625, 1983.841292696588, 2029.7564292284947, 2261.8642881427086, 2329.6621989811465, 2348.8708121053337, 2320.1299512682745, 2343.285297984114, 2343.8359314847967, 2344.120954642009, 2349.0721165139757, 2312.2022902098747, 2319.2926157521947, 2325.093213646679, 2373.679553260305, 2376.6435034744754, 1911.5125642960957, 2334.2647066174186, 2338.649202619612, 2347.788699017097, 2351.200591798788, 2297.9885100574556, 2298.862068275892, 2299.6665483504967, 2312.4681323295567, 2353.976460235393, 8570.785730356729, 8629.95685021577, 8666.86999695005, 8687.348448866298, 8704.173103555215, 4495.640348772123, 5160.690358578459, 5360.142377219665, 5384.515393614554, 5390.407055223922, 3329.900102996914, 3355.76509644325, 3550.1657110578994, 3561.626029266916, 3571.339302229096, 989.2432289298692, 1024.0834453164375, 1031.3605564550028, 1032.633415137623, 1035.1718625099966, 11116.055135313509, 11222.66332010041, 11294.265872718248, 11318.547258109666, 11328.376939268337, 4398.076403955141, 4514.367988481616, 4565.840195593139, 4567.314902764612, 4572.337011133367, 9035.141661542142, 9051.59267832949, 9139.223166030828, 9152.084791520794, 9185.40294880844, 5189.76646050921, 5232.084385232569, 5240.475952404805, 5244.7902083916215, 5287.920681189786, 4377.146456440963, 4796.232602783608, 4870.683405578626, 4873.853784386474, 4937.481564435754, 3145.2608636970294, 3789.3368660484307, 3837.5586807516934, 3842.116313247913, 3927.7054220933546, 911.1890417528405, 985.9507024648882, 1012.4026824173773, 1188.3879801902028, 1196.1985469735298, 825.2820046559411, 900.4057322167338, 917.0874979395544, 937.7702462896634, 956.7416797464646, 5682.943170568242, 5858.56060795442, 5936.436038576415, 5955.106733989849, 5971.28344598651, 431.56627589273205, 436.5220084008006, 445.0498186372119, 446.45755407180434, 452.94791099023377, 3834.213986132836, 3918.74528155669, 3918.7844668542934, 3988.6809055275507, 4021.155557332993, 10224.233182511342, 10546.209034322457, 10770.515326810313, 10771.51528181218, 10810.783784324427, 10600.628977985734, 10715.142788576895, 10715.410652414192, 10720.088792452572, 10724.946375268073, 7288.96355518219, 7739.458237923292, 8068.798280043045, 8144.75565733029, 8146.2261085196815, 5953.613015154001, 5993.032740174699, 6009.549283803644, 6114.327423983447, 6117.418845209698, 9859.0, 10005.399676019462, 10025.799484010426, 10039.146672532917, 10043.849342259726, 8687.566551519181, 9281.350305478552, 9393.29550283745, 9912.0, 10141.847872281774, 6871.175458944758, 7150.606716630636, 7286.7085316586745, 7487.850242995, 7529.736459223901, 8189.590520474072, 8220.712275070344, 8271.379646526415, 8306.418550701394, 8312.0, 7200.675969581277, 7295.161056478573, 7332.0834895638045, 7441.479096463412, 7510.47426680149, 7160.928390716026, 7188.964055179691, 7212.0, 7230.13238411394, 7299.05112335393, 6803.081583986099, 6867.0, 6896.655167241718, 6908.2400935896, 6929.341712537301, 4597.494275629611, 4757.286407038971, 4783.115123702126, 4810.975945120252, 4966.975165124151, 4954.455009948975, 4981.153203955264, 5008.649394542347, 5050.242463630485, 5198.16829307309], "value": 4387.843689921878, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [246.668231228997, 301.5311191097842, 305.8180382672309, 306.37499501017925, 307.9384123175366, 4563.65772567058, 4739.862433016068, 5451.23682684424, 5515.613907026504, 5531.225628412026, 4393.494748103969, 5462.836114916549, 5469.15228139639, 5496.983058134248, 5510.9448905510935, 3215.485522316436, 3598.748087633867, 4029.81865816039, 4111.9103437589065, 4121.381792731099, 3655.415133578628, 3795.8612416275146, 4142.5028996520305, 4157.189348077126, 4208.97895510522, 4120.443740095094, 4120.938185927213, 4124.113315637137, 4143.653312673378, 4169.4162817205615, 3731.645493678096, 3736.0, 4115.382692596105, 4165.0216147330075, 4167.166566686659, 3346.364190803764, 3438.644528497962, 3467.0, 3483.7735547189604, 3526.1889765354113, 5189.962007598496, 5212.53087222148, 5223.895522089575, 5228.503292187274, 5228.712420816853, 4133.503979522477, 4559.521250268707, 4621.075784843045, 4662.660910049536, 4700.459447163552, 4800.543948324904, 5501.807436739735, 5762.193292938992, 5775.480206781368, 5800.9129863052085, 3458.9654103458893, 3568.1793187566745, 3798.506194194737, 3870.9419358709642, 3905.258000979833, 5818.6217895836635, 5960.433758792908, 6055.364186760371, 6082.300535438393, 6145.513774944271, 3493.63316851732, 3903.1998440319667, 3913.295606790805, 3928.194720082376, 3932.724709270381, 3335.382954153489, 3738.1962877981364, 3967.4048892666333, 3976.089529431208, 3985.980070099689, 4134.669226461876, 4628.305754136906, 4647.721136731807, 4694.600958918462, 4708.552687494683, 4348.63036641889, 4637.3626373626375, 4926.605871530269, 5700.657960522299, 5762.88474230513, 1276.304414094321, 1371.8285214348207, 1411.484808045056, 1431.0340520149066, 1436.5044059799352, 837.9036410812835, 878.2359347367831, 925.134998776133, 943.2783920300975, 946.4605175050156, 5782.0, 5860.2381690380835, 5879.500242479352, 5881.5882888198275, 5882.705864706748, 5750.734838335559, 5797.855053623609, 6098.908516372258, 6435.646039467781, 6459.257185423737, 5529.30883639545, 5818.5636077294375, 5848.02334412595, 5850.0, 5883.0, 2600.0, 3146.3235404388165, 3329.983350083234, 3388.186835159593, 3422.6235114137426, 5841.299044114733, 6308.243010838729, 6644.235912870082, 6731.889238275629, 6772.492063095287, 5929.05135178378, 6224.719887605069, 6230.0, 6232.01438848919, 6280.748770049238, 5403.729813509309, 6243.188385509946, 6263.68681565929, 6278.937210628018, 6333.556651034475, 5200.271961925409, 5603.439656034527, 5653.802116925888, 5962.628595422991, 6062.151298818081, 4758.595519380891, 5102.132637451717, 5383.946160538345, 5856.267966504187, 5890.3801454600325, 5545.611807173459, 5794.217780599565, 5797.710114494343, 6131.356207598316, 6232.501399887908, 3196.856141473686, 3275.7051865332232, 3642.4354225095153, 3877.224555088937, 3950.2889479893897, 5288.709120998382, 5571.804986825604, 6069.969650151721, 6086.269647642312, 6094.451499365078, 4820.999285296562, 5674.204982277536, 5825.76696932118, 5832.24180856486, 5852.970735146297, 5518.19986102021, 5690.487856092972, 5788.0, 5808.94191058084, 5838.883222335445, 5325.174597937326, 5351.3075977575345, 5686.203931449518, 5732.713364331683, 5763.452472015152, 5087.227461033672, 5627.352854421794, 5878.29460464747, 5890.144604449584, 5906.70466476683, 5531.861703457445, 5751.194832723506, 5768.134779783066, 5815.738291776795, 5825.271841019872], "value": 4212.491077462048, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.8581797459623, 630.7918386932313, 651.9934800651993, 685.4858855858106, 688.2257460357097, 14277.857221427796, 14757.040792348489, 14760.261986900665, 14787.89090818189, 16054.919725401356, 15379.846201537981, 15453.381864725425, 15488.0, 15519.0, 15528.53414397567, 722.0685315942428, 893.2675206330812, 911.9726408207764, 922.8477301245287, 926.4302453990781, 916.1525588830339, 918.2470374293049, 920.093707697917, 923.0492592629569, 923.1507013547553, 961.8797650293714, 961.9278554108434, 965.9806803863919, 966.4153187321674, 966.6568368229283, 916.2303664921498, 946.957386917587, 953.7949340891707, 956.4643799472318, 958.4393130018947, 689.5035574386434, 702.1328659106, 717.5587013986413, 720.045939130654, 721.4589058206345, 15166.620834479116, 15220.162891040989, 15248.008879422801, 15252.237388130548, 15265.771013434807, 12068.577599784054, 12145.332006739625, 12183.269003859708, 12255.877441225563, 12460.0, 9640.614375424975, 9723.222142228675, 9847.803043939153, 9980.251481138943, 10490.737731556772, 3915.0, 3917.485715424346, 3933.744306620089, 3944.842206311744, 4053.4730485037053, 50579.49420505822, 69998.65000674964, 70705.0, 70869.99999999949, 71675.64162179208, 8929.06244844282, 8958.014618391971, 9015.729528114167, 9075.682351117679, 9153.496557689257, 20191.697124543145, 20191.899040504704, 20198.899005505173, 20255.898720506302, 20257.480688948388, 7093.92906070943, 7191.617717097977, 7332.523385979895, 7405.925940740523, 7493.325600695964, 4050.23045621331, 4119.567445418248, 4142.668586513072, 4148.585141485827, 4157.563455837094, 3083.0, 3095.7059079387423, 3390.4066788311948, 3419.9487007694906, 3423.7432192585657, 626.101544283954, 630.2972186012563, 631.1983780598706, 635.7234602947733, 638.4147518348315, 491.6533843640225, 492.1363007921147, 516.7054778776137, 550.4742970463197, 558.5671104893659, 7864.5281283123195, 7897.921020789719, 8015.398845086642, 8033.799155021171, 8110.553919534483, 568.630390246335, 579.4524174654995, 586.3550094895666, 591.6982339007137, 603.966781826995, 3757.5490941086696, 3768.9246215075545, 3823.4456003879836, 3853.344931361675, 3904.9804750976064, 81747.0, 83289.0, 83370.16629833746, 86528.51036172312, 87874.0, 71044.22389440305, 73782.0, 74936.25063749401, 75183.0, 76389.0, 64937.67531162314, 65097.34902651008, 67761.32238677649, 68137.31862681409, 68831.65584172147, 8125.349972002344, 8133.552654604054, 8134.0, 8239.423240373357, 8335.0, 43760.562394376924, 43841.34237986369, 43969.0427861494, 44241.33637995497, 44295.77852110719, 61814.0, 63156.68421658046, 63373.049404259895, 63598.04602930867, 64272.67863660839, 10513.842292365467, 10740.0, 10844.349339039683, 11027.393493357942, 11080.445977701243, 11884.346360949885, 11890.76218475643, 11898.583549575724, 11901.880981190077, 11947.103967202669, 10992.505337260003, 11251.324920504796, 11261.0, 11371.374574398156, 11492.230724249343, 11048.889511105108, 11197.888021119685, 11206.215564910379, 11501.079913607058, 11550.537978481103, 10847.566097356017, 10847.945760271148, 10961.50673219723, 11115.0, 11983.880161198276, 9287.86068208991, 9700.029997000243, 9715.9028409715, 10143.391396515937, 10266.538005789607, 9254.35219534626, 9361.297902657192, 9457.33798634089, 9540.093691099335, 9576.952115239379], "value": 6736.967550940468, "unit": "score"}], "name": "dromaeo_css", "value": 6964.225517199292, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 112.0, 115.0, 116.0, 118.0, 127.0, 116.0, 116.0, 118.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 137.0, 191.0, 182.0, 159.0, 179.0, 185.0, 154.0, 157.0, 117.0], "value": 162.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 207.0, 223.0, 205.0, 195.0, 201.0, 222.0, 196.0, 200.0, 198.0], "value": 204.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 101.0, 143.0, 72.0, 91.0, 90.0, 96.0, 136.0, 61.0], "value": 93.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 83.0, 131.0, 79.0, 82.0, 80.0, 116.0, 84.0, 81.0, 122.0], "value": 93.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 85.0, 92.0, 115.0, 84.0, 132.0, 132.0, 124.0], "value": 102.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 94.0, 94.0, 96.0, 101.0, 151.0, 110.0, 151.0, 152.0], "value": 114.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 89.0, 90.0, 92.0, 113.0, 128.0, 88.0, 147.0, 147.0], "value": 112.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 61.0, 62.0, 62.0, 61.0, 69.0, 101.0, 100.0, 92.0], "value": 72.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 68.0, 72.0, 70.0, 101.0, 119.0, 73.0, 79.0, 71.0, 70.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 86.0, 114.0, 73.0, 102.0, 115.0, 116.0, 113.0, 96.0], "value": 98.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 141.0, 138.0, 134.0, 156.0, 155.0, 135.0, 138.0, 176.0, 139.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 176.0, 174.0, 172.0, 173.0, 174.0, 185.0, 232.0, 216.0], "value": 185.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 73.0, 73.0, 72.0, 80.0, 68.0, 72.0, 72.0, 73.0], "value": 72.4, "unit": "score"}], "name": "kraken", "value": 1652.8000000000004, "alertThreshold": 2.0}]} 17:05:03 INFO - Return code: 0 17:05:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:05:03 ERROR - # TBPL SUCCESS # 17:05:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:05:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:05:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:05:03 INFO - 2016-04-29 17:05:03.431 screenresolution[1965:14704] starting screenresolution argv=screenresolution get 17:05:03 INFO - 2016-04-29 17:05:03.433 screenresolution[1965:14704] Display 0: 1600x1200x32@60 17:05:03 INFO - 2016-04-29 17:05:03.439 screenresolution[1966:14707] starting screenresolution argv=screenresolution list 17:05:03 INFO - Available Modes on Display 0 17:05:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:05:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:05:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:05:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:05:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:05:03 INFO - Intel Iris: 17:05:03 INFO - Chipset Model: Intel Iris 17:05:03 INFO - Type: GPU 17:05:03 INFO - Bus: Built-In 17:05:03 INFO - VRAM (Dynamic, Max): 1536 MB 17:05:03 INFO - Vendor: Intel (0x8086) 17:05:03 INFO - Device ID: 0x0a2e 17:05:03 INFO - Revision ID: 0x0009 17:05:03 INFO - Displays: 17:05:03 INFO - Display: 17:05:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:05:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:05:03 INFO - Display Serial Number: 1600x1200 60 17:05:03 INFO - Main Display: Yes 17:05:03 INFO - Mirror: Off 17:05:03 INFO - Online: Yes 17:05:03 INFO - Rotation: Supported 17:05:03 INFO - Return code: 0 17:05:03 INFO - Running post-action listener: _resource_record_post_action 17:05:03 INFO - Running post-run listener: _resource_record_post_run 17:05:04 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 544768; Write bytes: 699239936; Read time: 10; Write time: 2273 17:05:04 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 393619968; Write bytes: 388052992; Read time: 29437; Write time: 958 17:05:04 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 520192; Write bytes: 297793024; Read time: 9; Write time: 1280 17:05:04 INFO - Running post-run listener: _upload_blobber_files 17:05:04 INFO - Blob upload gear active. 17:05:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:05:04 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 17:05:04 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:05:04 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:05:05 INFO - (blobuploader) - INFO - Open directory for files ... 17:05:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:05:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:05:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:05 INFO - (blobuploader) - INFO - Done attempting. 17:05:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:05:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:05:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:06 INFO - (blobuploader) - INFO - Done attempting. 17:05:06 INFO - (blobuploader) - INFO - Iteration through files over. 17:05:06 INFO - Return code: 0 17:05:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:05:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:05:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f3b555575f44bea65e14044ef1d95499eb19fd709afc339589a2705ca2f6ba238380f9489fa7e80e263c3cb0aa147d14e0d594e8cd93510ea1ce03a393539bcf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98d2d25cbde5fb0a5d0c523358af1d769f7e94ca210fa0aabd5f73f332aa3329bd21e53ad16265a0d7516138b6ca9bb64bdee9ee6b6f441a23653de2bc840966"} 17:05:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:05:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:05:06 INFO - Contents: 17:05:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f3b555575f44bea65e14044ef1d95499eb19fd709afc339589a2705ca2f6ba238380f9489fa7e80e263c3cb0aa147d14e0d594e8cd93510ea1ce03a393539bcf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98d2d25cbde5fb0a5d0c523358af1d769f7e94ca210fa0aabd5f73f332aa3329bd21e53ad16265a0d7516138b6ca9bb64bdee9ee6b6f441a23653de2bc840966"} 17:05:06 INFO - Running post-run listener: copy_logs_to_upload_dir 17:05:06 INFO - Copying logs to upload dir... 17:05:06 INFO - mkdir: /builds/slave/test/build/upload/logs 17:05:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.585069 ========= master_lag: 67.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 13 secs) (at 2016-04-29 17:06:14.078347) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-04-29 17:06:14.081545) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uQhkVvWkcS/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f3b555575f44bea65e14044ef1d95499eb19fd709afc339589a2705ca2f6ba238380f9489fa7e80e263c3cb0aa147d14e0d594e8cd93510ea1ce03a393539bcf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98d2d25cbde5fb0a5d0c523358af1d769f7e94ca210fa0aabd5f73f332aa3329bd21e53ad16265a0d7516138b6ca9bb64bdee9ee6b6f441a23653de2bc840966"} build_url:https://queue.taskcluster.net/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011777 build_url: 'https://queue.taskcluster.net/v1/task/R2T5pQ4fRVyX7Fx-XYNrzw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f3b555575f44bea65e14044ef1d95499eb19fd709afc339589a2705ca2f6ba238380f9489fa7e80e263c3cb0aa147d14e0d594e8cd93510ea1ce03a393539bcf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98d2d25cbde5fb0a5d0c523358af1d769f7e94ca210fa0aabd5f73f332aa3329bd21e53ad16265a0d7516138b6ca9bb64bdee9ee6b6f441a23653de2bc840966"}' ========= master_lag: 3.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-04-29 17:06:17.120239) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-29 17:06:17.120542) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uQhkVvWkcS/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zBwsfyWKlY/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.004973 ========= master_lag: 3.57 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-29 17:06:20.691408) ========= ========= Started reboot skipped (results: 3, elapsed: 6 secs) (at 2016-04-29 17:06:20.691685) ========= ========= Finished reboot skipped (results: 3, elapsed: 6 secs) (at 2016-04-29 17:06:27.558119) ========= ========= Total master_lag: 73.77 =========