builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0018 starttime: 1461068392.94 results: success (0) buildid: 20160419025944 builduid: 33b87f2012724d1ead24a708768fb835 revision: ae7413abfa4d3954a6a4ce7c1613a7100f367f9a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 05:19:52.937735) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 05:19:52.938162) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 05:19:52.938433) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 05:19:52.971274) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-19 05:19:52.971548) ========= 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.Vf19TKiwPM/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.ezaRFj1Lkf/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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-19 05:19:52-- 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% 38.2M=0s 2016-04-19 05:19:53 (38.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087947 ========= master_lag: 6.54 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-19 05:19:59.602894) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 05:19:59.603189) ========= 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.Vf19TKiwPM/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.ezaRFj1Lkf/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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.019665 ========= master_lag: 0.35 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 05:19:59.973921) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-19 05:19:59.977350) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ae7413abfa4d3954a6a4ce7c1613a7100f367f9a --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 ae7413abfa4d3954a6a4ce7c1613a7100f367f9a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Vf19TKiwPM/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.ezaRFj1Lkf/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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-19 05:20:00,039 truncating revision to first 12 chars 2016-04-19 05:20:00,039 Setting DEBUG logging. 2016-04-19 05:20:00,039 attempt 1/10 2016-04-19 05:20:00,039 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ae7413abfa4d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-19 05:20:01,156 unpacking tar archive at: mozilla-central-ae7413abfa4d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.362405 ========= master_lag: 5.45 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-19 05:20:06.791359) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 05:20:06.791632) ========= script_repo_revision: ae7413abfa4d3954a6a4ce7c1613a7100f367f9a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 05:20:06.795052) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 22 secs) (at 2016-04-19 05:20:06.795379) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 22 secs) (at 2016-04-19 05:20:29.343290) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-04-19 05:20:29.343588) ========= /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.Vf19TKiwPM/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.ezaRFj1Lkf/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 05:20:29 INFO - MultiFileLogger online at 20160419 05:20:29 in /builds/slave/test 05:20:29 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 05:20:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:20:29 INFO - {'append_to_log': False, 05:20:29 INFO - 'base_work_dir': '/builds/slave/test', 05:20:29 INFO - 'blob_upload_branch': 'Firefox', 05:20:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:20:29 INFO - 'branch': 'Firefox', 05:20:29 INFO - 'buildbot_json_path': 'buildprops.json', 05:20:29 INFO - 'config_files': ('talos/mac_config.py',), 05:20:29 INFO - 'default_actions': ('clobber', 05:20:29 INFO - 'read-buildbot-config', 05:20:29 INFO - 'download-and-extract', 05:20:29 INFO - 'populate-webroot', 05:20:29 INFO - 'create-virtualenv', 05:20:29 INFO - 'install', 05:20:29 INFO - 'run-tests'), 05:20:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:20:29 INFO - 'download_minidump_stackwalk': True, 05:20:29 INFO - 'download_symbols': 'ondemand', 05:20:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:20:29 INFO - 'tooltool.py': '/tools/tooltool.py', 05:20:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:20:29 INFO - '/tools/misc-python/virtualenv.py')}, 05:20:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:20:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:20:29 INFO - 'installer_path': 'installer.exe', 05:20:29 INFO - 'log_level': 'info', 05:20:29 INFO - 'log_name': 'talos', 05:20:29 INFO - 'log_to_console': True, 05:20:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:20:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:20:29 INFO - 'opt_config_files': (), 05:20:29 INFO - 'pip_index': False, 05:20:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:20:29 INFO - 'cmd': ('bash', 05:20:29 INFO - '-c', 05:20:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:20:29 INFO - 'enabled': True, 05:20:29 INFO - 'halt_on_failure': False, 05:20:29 INFO - 'name': 'check_screen_resolution'},), 05:20:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:20:29 INFO - 'cmd': ('bash', 05:20:29 INFO - '-c', 05:20:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:20:29 INFO - 'enabled': True, 05:20:29 INFO - 'halt_on_failure': False, 05:20:29 INFO - 'name': 'check_screen_resolution'},), 05:20:29 INFO - 'run_cmd_checks_enabled': True, 05:20:29 INFO - 'sps_profile': False, 05:20:29 INFO - 'sps_profile_interval': 0, 05:20:29 INFO - 'suite': 'dromaeojs-e10s', 05:20:29 INFO - 'system_bits': '32', 05:20:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:20:29 INFO - 'title': 't-yosemite-r7-0018', 05:20:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:20:29 INFO - 'use_talos_json': True, 05:20:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:20:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:20:29 INFO - 'work_dir': 'build'} 05:20:29 INFO - ##### 05:20:29 INFO - ##### Running clobber step. 05:20:29 INFO - ##### 05:20:29 INFO - Running pre-action listener: _resource_record_pre_action 05:20:29 INFO - Running main action method: clobber 05:20:29 INFO - rmtree: /builds/slave/test/build 05:20:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:20:31 INFO - Running post-action listener: _resource_record_post_action 05:20:31 INFO - ##### 05:20:31 INFO - ##### Running read-buildbot-config step. 05:20:31 INFO - ##### 05:20:31 INFO - Running pre-action listener: _resource_record_pre_action 05:20:31 INFO - Running main action method: read_buildbot_config 05:20:31 INFO - Using buildbot properties: 05:20:31 INFO - { 05:20:31 INFO - "project": "", 05:20:31 INFO - "product": "firefox", 05:20:31 INFO - "script_repo_revision": "production", 05:20:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:20:31 INFO - "repository": "", 05:20:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:20:31 INFO - "buildid": "20160419025944", 05:20:31 INFO - "pgo_build": "False", 05:20:31 INFO - "basedir": "/builds/slave/test", 05:20:31 INFO - "buildnumber": 31, 05:20:31 INFO - "slavename": "t-yosemite-r7-0018", 05:20:31 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:20:31 INFO - "platform": "yosemite_r7", 05:20:31 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:20:31 INFO - "branch": "mozilla-central", 05:20:31 INFO - "repo_path": "mozilla-central", 05:20:31 INFO - "revision": "ae7413abfa4d3954a6a4ce7c1613a7100f367f9a", 05:20:31 INFO - "stage_platform": "macosx64", 05:20:31 INFO - "builduid": "33b87f2012724d1ead24a708768fb835", 05:20:31 INFO - "slavebuilddir": "test" 05:20:31 INFO - } 05:20:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:20:31 INFO - Running post-action listener: _resource_record_post_action 05:20:31 INFO - ##### 05:20:31 INFO - ##### Running download-and-extract step. 05:20:31 INFO - ##### 05:20:31 INFO - Running pre-action listener: _resource_record_pre_action 05:20:31 INFO - Running main action method: download_and_extract 05:20:31 INFO - mkdir: /builds/slave/test/build/tests 05:20:31 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')]} 05:20:31 INFO - https://queue.taskcluster.net/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:20:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:20:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:20:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:20:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 05:20:32 INFO - Downloaded 1199 bytes. 05:20:32 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:20:32 INFO - Using the following test package requirements: 05:20:32 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:20:32 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:20:32 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:20:32 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:20:32 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:20:32 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:20:32 INFO - u'jsshell-mac.zip'], 05:20:32 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:20:32 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:20:32 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:20:32 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:20:32 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:20:32 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:20:32 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:20:32 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:20:32 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:20:32 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:20:32 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:20:32 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:20:32 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')]} 05:20:32 INFO - https://queue.taskcluster.net/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:20:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:20:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:20:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:20:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:20:34 INFO - Downloaded 18038817 bytes. 05:20:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:20:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:20:37 INFO - Return code: 0 05:20:37 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:37 INFO - https://queue.taskcluster.net/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:20:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:20:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:20:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:20:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:20:39 INFO - Downloaded 18038817 bytes. 05:20:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:20:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:20:42 INFO - Return code: 0 05:20:42 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')]} 05:20:42 INFO - https://queue.taskcluster.net/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:20:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:20:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:20:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:20:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:20:43 INFO - Downloaded 10845952 bytes. 05:20:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:20:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:20:44 INFO - Return code: 0 05:20: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')]} 05:20:44 INFO - https://queue.taskcluster.net/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:20:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:20:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:20:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:20:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 05:20:47 INFO - Downloaded 109965596 bytes. 05:20:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:20:47 INFO - mkdir: /builds/slave/test/properties 05:20:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:20:47 INFO - Writing to file /builds/slave/test/properties/build_url 05:20:47 INFO - Contents: 05:20:47 INFO - build_url:https://queue.taskcluster.net/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:20:48 INFO - Running post-action listener: _resource_record_post_action 05:20:48 INFO - Running post-action listener: set_extra_try_arguments 05:20:48 INFO - ##### 05:20:48 INFO - ##### Running populate-webroot step. 05:20:48 INFO - ##### 05:20:48 INFO - Running pre-action listener: _resource_record_pre_action 05:20:48 INFO - Running main action method: populate_webroot 05:20:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:20:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:20:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:20:48 INFO - '--tppagecycles', 05:20:48 INFO - '7'], 05:20:48 INFO - 'tests': ['tsvgm']}}, 05:20:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:20:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:20:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:20:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:20:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:20:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:20:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:48 INFO - 'talos_options': ['--disable-e10s'], 05:20:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:48 INFO - 'talos_options': ['--disable-e10s'], 05:20:48 INFO - 'tests': ['damp', 'tps']}, 05:20:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:48 INFO - 'tests': ['damp', 'tps']}, 05:20:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:20:48 INFO - 'tests': ['dromaeo_dom']}, 05:20:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:20:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:20:48 INFO - 'tests': ['a11yr', 05:20:48 INFO - 'ts_paint', 05:20:48 INFO - 'tpaint', 05:20:48 INFO - 'sessionrestore', 05:20:48 INFO - 'sessionrestore_no_auto_restore', 05:20:48 INFO - 'tabpaint']}, 05:20:48 INFO - 'other-e10s': {'tests': ['a11yr', 05:20:48 INFO - 'ts_paint', 05:20:48 INFO - 'tpaint', 05:20:48 INFO - 'sessionrestore', 05:20:48 INFO - 'sessionrestore_no_auto_restore', 05:20:48 INFO - 'tabpaint']}, 05:20:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:20:48 INFO - 'ts_paint', 05:20:48 INFO - 'tpaint', 05:20:48 INFO - 'sessionrestore', 05:20:48 INFO - 'sessionrestore_no_auto_restore', 05:20:48 INFO - 'tabpaint']}, 05:20:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:20:48 INFO - 'ts_paint', 05:20:48 INFO - 'tpaint', 05:20:48 INFO - 'sessionrestore', 05:20:48 INFO - 'sessionrestore_no_auto_restore', 05:20:48 INFO - 'tabpaint']}, 05:20:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:20:48 INFO - 'tests': ['a11yr', 05:20:48 INFO - 'ts_paint', 05:20:48 INFO - 'tpaint', 05:20:48 INFO - 'sessionrestore', 05:20:48 INFO - 'sessionrestore_no_auto_restore', 05:20:48 INFO - 'tabpaint']}, 05:20:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:20:48 INFO - 'tests': ['a11yr', 05:20:48 INFO - 'ts_paint', 05:20:48 INFO - 'tpaint', 05:20:48 INFO - 'sessionrestore', 05:20:48 INFO - 'sessionrestore_no_auto_restore', 05:20:48 INFO - 'tabpaint']}, 05:20:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:20:48 INFO - 'tests': ['tsvgx', 05:20:48 INFO - 'tsvgr_opacity', 05:20:48 INFO - 'tart', 05:20:48 INFO - 'tscrollx', 05:20:48 INFO - 'cart']}, 05:20:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:20:48 INFO - 'tsvgr_opacity', 05:20:48 INFO - 'tart', 05:20:48 INFO - 'tscrollx', 05:20:48 INFO - 'cart']}, 05:20:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:48 INFO - 'talos_options': ['--disable-e10s'], 05:20:48 INFO - 'tests': ['tp5o']}, 05:20:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:48 INFO - 'tests': ['tp5o']}, 05:20:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:48 INFO - 'talos_options': ['--disable-e10s', 05:20:48 INFO - '--xperf_path', 05:20:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:48 INFO - 'tests': ['tp5n']}, 05:20:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:48 INFO - 'talos_options': ['--xperf_path', 05:20:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:48 INFO - 'tests': ['tp5n']}}, 05:20:48 INFO - 'talos.zip': {'path': '', 05:20:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:20:48 INFO - Running post-action listener: _resource_record_post_action 05:20:48 INFO - ##### 05:20:48 INFO - ##### Running create-virtualenv step. 05:20:48 INFO - ##### 05:20:48 INFO - Running pre-action listener: _resource_record_pre_action 05:20:48 INFO - Running main action method: create_virtualenv 05:20:48 INFO - Creating virtualenv /builds/slave/test/build/venv 05:20:48 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 05:20:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:20:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:20:48 INFO - Using real prefix '/tools/python27' 05:20:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:20:49 INFO - Installing distribute.............................................................................................................................................................................................done. 05:20:51 INFO - Installing pip.................done. 05:20:51 INFO - Return code: 0 05:20:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:20:51 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')]} 05:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d71ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd641caddc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5bde00>, '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 0x10d426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b8b20>, '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.Vf19TKiwPM/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.ezaRFj1Lkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 05:20:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:20:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:20:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vf19TKiwPM/Render', 05:20:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:52 INFO - 'HOME': '/Users/cltbld', 05:20:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:52 INFO - 'LOGNAME': 'cltbld', 05:20:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:52 INFO - 'MOZ_NO_REMOTE': '1', 05:20:52 INFO - 'NO_EM_RESTART': '1', 05:20:52 INFO - 'PAGER': '/bin/cat', 05:20:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:52 INFO - 'PWD': '/builds/slave/test', 05:20:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:52 INFO - 'SHELL': '/bin/bash', 05:20:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ezaRFj1Lkf/Listeners', 05:20:52 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:20:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:52 INFO - 'USER': 'cltbld', 05:20:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:52 INFO - 'XPC_FLAGS': '0x0', 05:20:52 INFO - 'XPC_SERVICE_NAME': '0', 05:20:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:52 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 05:20:52 INFO - Cleaning up... 05:20:52 INFO - Return code: 0 05:20:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:20:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d71ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd641caddc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5bde00>, '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 0x10d426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b8b20>, '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.Vf19TKiwPM/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.ezaRFj1Lkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 05:20:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:20:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:20:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vf19TKiwPM/Render', 05:20:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:52 INFO - 'HOME': '/Users/cltbld', 05:20:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:52 INFO - 'LOGNAME': 'cltbld', 05:20:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:52 INFO - 'MOZ_NO_REMOTE': '1', 05:20:52 INFO - 'NO_EM_RESTART': '1', 05:20:52 INFO - 'PAGER': '/bin/cat', 05:20:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:52 INFO - 'PWD': '/builds/slave/test', 05:20:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:52 INFO - 'SHELL': '/bin/bash', 05:20:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ezaRFj1Lkf/Listeners', 05:20:52 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:20:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:52 INFO - 'USER': 'cltbld', 05:20:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:52 INFO - 'XPC_FLAGS': '0x0', 05:20:52 INFO - 'XPC_SERVICE_NAME': '0', 05:20:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:52 INFO - Downloading/unpacking psutil>=0.7.1 05:20:52 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 05:20:52 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 05:20:52 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 05:20:52 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 05:20:52 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 05:20:52 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 05:20:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:20:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:55 INFO - Installing collected packages: psutil 05:20:55 INFO - Running setup.py install for psutil 05:20:55 INFO - building 'psutil._psutil_osx' extension 05:20:55 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 05:20:56 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 05:20:56 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 05:20:56 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 05:20:56 INFO - building 'psutil._psutil_posix' extension 05:20:56 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 05:20:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:20:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:20:56 INFO - ^ 05:20:56 INFO - 1 warning generated. 05:20:56 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 05:20:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:56 INFO - Successfully installed psutil 05:20:56 INFO - Cleaning up... 05:20:56 INFO - Return code: 0 05:20:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:20:56 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')]} 05:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:56 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')]} 05:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:56 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 0x10d71ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd641caddc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5bde00>, '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 0x10d426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b8b20>, '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.Vf19TKiwPM/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.ezaRFj1Lkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 05:20:56 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 05:20:56 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 05:20:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vf19TKiwPM/Render', 05:20:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:56 INFO - 'HOME': '/Users/cltbld', 05:20:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:56 INFO - 'LOGNAME': 'cltbld', 05:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:56 INFO - 'MOZ_NO_REMOTE': '1', 05:20:56 INFO - 'NO_EM_RESTART': '1', 05:20:56 INFO - 'PAGER': '/bin/cat', 05:20:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:56 INFO - 'PWD': '/builds/slave/test', 05:20:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:56 INFO - 'SHELL': '/bin/bash', 05:20:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ezaRFj1Lkf/Listeners', 05:20:56 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:20:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:56 INFO - 'USER': 'cltbld', 05:20:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:56 INFO - 'XPC_FLAGS': '0x0', 05:20:56 INFO - 'XPC_SERVICE_NAME': '0', 05:20:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:20:56 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 05:20:56 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 05:20:56 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 05:20:56 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 05:20:56 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 05:20:56 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 05:20:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:20:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:20:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:20:59 INFO - Installing collected packages: mozsystemmonitor 05:20:59 INFO - Running setup.py install for mozsystemmonitor 05:20:59 INFO - Successfully installed mozsystemmonitor 05:20:59 INFO - Cleaning up... 05:20:59 INFO - Return code: 0 05:20:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:59 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 0x10d71ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd641caddc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5bde00>, '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 0x10d426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b8b20>, '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.Vf19TKiwPM/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.ezaRFj1Lkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 05:20:59 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 05:20:59 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 05:20:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vf19TKiwPM/Render', 05:20:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:59 INFO - 'HOME': '/Users/cltbld', 05:20:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:59 INFO - 'LOGNAME': 'cltbld', 05:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:59 INFO - 'MOZ_NO_REMOTE': '1', 05:20:59 INFO - 'NO_EM_RESTART': '1', 05:20:59 INFO - 'PAGER': '/bin/cat', 05:20:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:59 INFO - 'PWD': '/builds/slave/test', 05:20:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:59 INFO - 'SHELL': '/bin/bash', 05:20:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ezaRFj1Lkf/Listeners', 05:20:59 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:20:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:59 INFO - 'USER': 'cltbld', 05:20:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:59 INFO - 'XPC_FLAGS': '0x0', 05:20:59 INFO - 'XPC_SERVICE_NAME': '0', 05:20:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:59 INFO - Downloading/unpacking blobuploader==1.2.4 05:20:59 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 05:20:59 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 05:20:59 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 05:20:59 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 05:20:59 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 05:20:59 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 05:21:02 INFO - Downloading blobuploader-1.2.4.tar.gz 05:21:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:21:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:21:02 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 05:21:02 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 05:21:02 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 05:21:02 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 05:21:02 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 05:21:02 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 05:21:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:21:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:21:02 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 05:21:02 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 05:21:02 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 05:21:02 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 05:21:02 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 05:21:02 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 05:21:03 INFO - Downloading docopt-0.6.1.tar.gz 05:21:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:21:03 INFO - Installing collected packages: blobuploader, requests, docopt 05:21:03 INFO - Running setup.py install for blobuploader 05:21:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:21:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:21:03 INFO - Running setup.py install for requests 05:21:04 INFO - Running setup.py install for docopt 05:21:04 INFO - Successfully installed blobuploader requests docopt 05:21:04 INFO - Cleaning up... 05:21:04 INFO - Return code: 0 05:21:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:21:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:04 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 0x10d71ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd641caddc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5bde00>, '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 0x10d426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b8b20>, '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.Vf19TKiwPM/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.ezaRFj1Lkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 05:21:04 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 05:21:04 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 05:21:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vf19TKiwPM/Render', 05:21:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:04 INFO - 'HOME': '/Users/cltbld', 05:21:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:04 INFO - 'LOGNAME': 'cltbld', 05:21:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:04 INFO - 'MOZ_NO_REMOTE': '1', 05:21:04 INFO - 'NO_EM_RESTART': '1', 05:21:04 INFO - 'PAGER': '/bin/cat', 05:21:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:04 INFO - 'PWD': '/builds/slave/test', 05:21:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:04 INFO - 'SHELL': '/bin/bash', 05:21:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ezaRFj1Lkf/Listeners', 05:21:04 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:21:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:04 INFO - 'USER': 'cltbld', 05:21:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:04 INFO - 'XPC_FLAGS': '0x0', 05:21:04 INFO - 'XPC_SERVICE_NAME': '0', 05:21:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:21:04 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-ZBeLMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:21:04 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-dTGPyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:21:04 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-CSrnvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:21:04 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-eB7OTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:21:04 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-II5luV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:21:05 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-wxzb02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:21:05 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-aOTvwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:21:05 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-chE78l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:21:05 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-SCfpVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:21:05 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-9V4jby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:21:05 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-ZRQo9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:21:05 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-vWdbtR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:21:05 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-jaUQVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:21:05 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-nFFyrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:21:06 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-trbJF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:21:06 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-NZKin_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:21:06 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-p92r9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:21:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:21:06 INFO - Running setup.py install for manifestparser 05:21:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:21:06 INFO - Running setup.py install for mozcrash 05:21:06 INFO - Running setup.py install for mozdebug 05:21:06 INFO - Running setup.py install for mozdevice 05:21:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:21:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:21:06 INFO - Running setup.py install for mozfile 05:21:07 INFO - Running setup.py install for mozhttpd 05:21:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:21:07 INFO - Running setup.py install for mozinfo 05:21:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:21:07 INFO - Running setup.py install for mozInstall 05:21:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:21:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:21:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:21:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:21:07 INFO - Running setup.py install for mozleak 05:21:07 INFO - Running setup.py install for mozlog 05:21:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:21:07 INFO - Running setup.py install for moznetwork 05:21:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:21:07 INFO - Running setup.py install for mozprocess 05:21:08 INFO - Running setup.py install for mozprofile 05:21:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:21:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:21:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:21:08 INFO - Running setup.py install for mozrunner 05:21:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:21:08 INFO - Running setup.py install for mozscreenshot 05:21:08 INFO - Running setup.py install for moztest 05:21:08 INFO - Running setup.py install for mozversion 05:21:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:21:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:21:08 INFO - Cleaning up... 05:21:08 INFO - Return code: 0 05:21:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:21:08 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')]} 05:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:08 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')]} 05:21:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:08 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 0x10d71ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd641caddc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5bde00>, '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 0x10d426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b8b20>, '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.Vf19TKiwPM/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.ezaRFj1Lkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 05:21:08 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 05:21:08 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 05:21:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vf19TKiwPM/Render', 05:21:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:08 INFO - 'HOME': '/Users/cltbld', 05:21:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:08 INFO - 'LOGNAME': 'cltbld', 05:21:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:08 INFO - 'MOZ_NO_REMOTE': '1', 05:21:08 INFO - 'NO_EM_RESTART': '1', 05:21:08 INFO - 'PAGER': '/bin/cat', 05:21:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:08 INFO - 'PWD': '/builds/slave/test', 05:21:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:08 INFO - 'SHELL': '/bin/bash', 05:21:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ezaRFj1Lkf/Listeners', 05:21:08 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:21:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:08 INFO - 'USER': 'cltbld', 05:21:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:08 INFO - 'XPC_FLAGS': '0x0', 05:21:08 INFO - 'XPC_SERVICE_NAME': '0', 05:21:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:21:09 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-IaORXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:21:09 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)) 05:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:21:09 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-e0xKzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:21:09 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)) 05:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:21:09 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-417CBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:21:09 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)) 05:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:21:09 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-O_2Gn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:21:09 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)) 05:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:21:09 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-7UU3Bj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:21:09 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)) 05:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:21:09 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-EAdhCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:21:09 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)) 05:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:21:09 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-2JFuL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:21:09 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)) 05:21:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:21:09 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-5Jrvif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:21:09 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)) 05:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:21:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-E8eIpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:21:10 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)) 05:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:21:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-XmbhhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:21:10 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)) 05:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:21:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-ycJFEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:21:10 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)) 05:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:21:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-uCb7MS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:21:10 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)) 05:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:21:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-TOGuF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:21:10 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)) 05:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:21:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-oM8kb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:21:10 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)) 05:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:21:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-jby4tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:21:10 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)) 05:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:21:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-VH3Ir8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:21:10 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)) 05:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:21:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-Zi4XCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:21:10 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)) 05:21:10 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)) 05:21:10 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)) 05:21:10 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)) 05:21:10 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)) 05:21:10 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)) 05:21:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:21:10 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 05:21:10 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 05:21:10 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 05:21:10 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 05:21:10 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 05:21:10 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 05:21:13 INFO - Downloading blessings-1.6.tar.gz 05:21:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:21:13 INFO - Installing collected packages: blessings 05:21:13 INFO - Running setup.py install for blessings 05:21:13 INFO - Successfully installed blessings 05:21:13 INFO - Cleaning up... 05:21:13 INFO - Return code: 0 05:21:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:21:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:21:13 INFO - Reading from file tmpfile_stdout 05:21:13 INFO - Current package versions: 05:21:13 INFO - blessings == 1.6 05:21:13 INFO - blobuploader == 1.2.4 05:21:13 INFO - docopt == 0.6.1 05:21:13 INFO - manifestparser == 1.1 05:21:13 INFO - mozInstall == 1.12 05:21:13 INFO - mozcrash == 0.17 05:21:13 INFO - mozdebug == 0.1 05:21:13 INFO - mozdevice == 0.48 05:21:13 INFO - mozfile == 1.2 05:21:13 INFO - mozhttpd == 0.7 05:21:13 INFO - mozinfo == 0.9 05:21:13 INFO - mozleak == 0.1 05:21:13 INFO - mozlog == 3.1 05:21:13 INFO - moznetwork == 0.27 05:21:13 INFO - mozprocess == 0.22 05:21:13 INFO - mozprofile == 0.28 05:21:13 INFO - mozrunner == 6.11 05:21:13 INFO - mozscreenshot == 0.1 05:21:13 INFO - mozsystemmonitor == 0.0 05:21:13 INFO - moztest == 0.7 05:21:13 INFO - mozversion == 1.4 05:21:13 INFO - psutil == 3.1.1 05:21:13 INFO - requests == 1.2.3 05:21:13 INFO - wsgiref == 0.1.2 05:21:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:21:13 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')]} 05:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:13 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')]} 05:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:13 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 0x10d71ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd641caddc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5bde00>, '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 0x10d426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b8b20>, '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.Vf19TKiwPM/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.ezaRFj1Lkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 05:21:13 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 05:21:13 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 05:21:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vf19TKiwPM/Render', 05:21:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:13 INFO - 'HOME': '/Users/cltbld', 05:21:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:13 INFO - 'LOGNAME': 'cltbld', 05:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:13 INFO - 'MOZ_NO_REMOTE': '1', 05:21:13 INFO - 'NO_EM_RESTART': '1', 05:21:13 INFO - 'PAGER': '/bin/cat', 05:21:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:13 INFO - 'PWD': '/builds/slave/test', 05:21:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:13 INFO - 'SHELL': '/bin/bash', 05:21:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ezaRFj1Lkf/Listeners', 05:21:13 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:21:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:13 INFO - 'USER': 'cltbld', 05:21:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:13 INFO - 'XPC_FLAGS': '0x0', 05:21:13 INFO - 'XPC_SERVICE_NAME': '0', 05:21:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:14 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)) 05:21:14 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)) 05:21:14 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)) 05:21:14 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)) 05:21:14 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)) 05:21:14 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)) 05:21:14 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)) 05:21:14 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)) 05:21:14 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)) 05:21:14 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)) 05:21:14 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)) 05:21:14 INFO - Cleaning up... 05:21:14 INFO - Return code: 0 05:21:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:21: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')]} 05:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21: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')]} 05:21:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d71ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd641caddc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5bde00>, '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 0x10d426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b8b20>, '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.Vf19TKiwPM/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.ezaRFj1Lkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 05:21:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:21:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:21:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vf19TKiwPM/Render', 05:21:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:14 INFO - 'HOME': '/Users/cltbld', 05:21:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:14 INFO - 'LOGNAME': 'cltbld', 05:21:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:14 INFO - 'MOZ_NO_REMOTE': '1', 05:21:14 INFO - 'NO_EM_RESTART': '1', 05:21:14 INFO - 'PAGER': '/bin/cat', 05:21:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:14 INFO - 'PWD': '/builds/slave/test', 05:21:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:14 INFO - 'SHELL': '/bin/bash', 05:21:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ezaRFj1Lkf/Listeners', 05:21:14 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:21:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:14 INFO - 'USER': 'cltbld', 05:21:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:14 INFO - 'XPC_FLAGS': '0x0', 05:21:14 INFO - 'XPC_SERVICE_NAME': '0', 05:21:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:14 INFO - Downloading/unpacking jsonschema 05:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:21:16 INFO - Downloading/unpacking functools32 (from jsonschema) 05:21:16 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 05:21:16 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 05:21:16 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 05:21:16 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 05:21:16 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 05:21:16 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 05:21:17 INFO - Downloading functools32-3.2.3-2.tar.gz 05:21:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:21:17 INFO - warning: no files found matching '*.txt' 05:21:17 INFO - no previously-included directories found matching 'build' 05:21:17 INFO - no previously-included directories found matching 'dist' 05:21:17 INFO - no previously-included directories found matching '.git*' 05:21:17 INFO - Installing collected packages: jsonschema, functools32 05:21:17 INFO - Running setup.py install for functools32 05:21:17 INFO - warning: no files found matching '*.txt' 05:21:17 INFO - no previously-included directories found matching 'build' 05:21:17 INFO - no previously-included directories found matching 'dist' 05:21:17 INFO - no previously-included directories found matching '.git*' 05:21:17 INFO - Successfully installed jsonschema functools32 05:21:17 INFO - Cleaning up... 05:21:17 INFO - Return code: 0 05:21:17 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:21:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d71ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd641caddc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5bde00>, '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 0x10d426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b8b20>, '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.Vf19TKiwPM/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.ezaRFj1Lkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 05:21:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:21:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vf19TKiwPM/Render', 05:21:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:17 INFO - 'HOME': '/Users/cltbld', 05:21:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:17 INFO - 'LOGNAME': 'cltbld', 05:21:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:17 INFO - 'MOZ_NO_REMOTE': '1', 05:21:17 INFO - 'NO_EM_RESTART': '1', 05:21:17 INFO - 'PAGER': '/bin/cat', 05:21:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:17 INFO - 'PWD': '/builds/slave/test', 05:21:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:17 INFO - 'SHELL': '/bin/bash', 05:21:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ezaRFj1Lkf/Listeners', 05:21:17 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:21:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:17 INFO - 'USER': 'cltbld', 05:21:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:17 INFO - 'XPC_FLAGS': '0x0', 05:21:17 INFO - 'XPC_SERVICE_NAME': '0', 05:21:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:17 INFO - Downloading/unpacking flake8 05:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:21:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:21:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:21:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:21:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:21:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:21:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:21:21 INFO - no previously-included directories found matching 'docs/_build' 05:21:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:21:21 INFO - Running setup.py install for pep8 05:21:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:21:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:21:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:21:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:21:21 INFO - no previously-included directories found matching 'docs/_build' 05:21:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:21:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:21:21 INFO - Cleaning up... 05:21:21 INFO - Return code: 0 05:21:21 INFO - Running post-action listener: _resource_record_post_action 05:21:21 INFO - Running post-action listener: _start_resource_monitoring 05:21:21 INFO - Starting resource monitoring. 05:21:21 INFO - ##### 05:21:21 INFO - ##### Running install step. 05:21:21 INFO - ##### 05:21:21 INFO - Running pre-action listener: _resource_record_pre_action 05:21:21 INFO - Running main action method: install 05:21:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:21:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:21:22 INFO - Reading from file tmpfile_stdout 05:21:22 INFO - Detecting whether we're running mozinstall >=1.0... 05:21:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:21:22 INFO - Reading from file tmpfile_stdout 05:21:22 INFO - Output received: 05:21:22 INFO - Usage: mozinstall [options] installer 05:21:22 INFO - Options: 05:21:22 INFO - -h, --help show this help message and exit 05:21:22 INFO - -d DEST, --destination=DEST 05:21:22 INFO - Directory to install application into. [default: 05:21:22 INFO - "/builds/slave/test"] 05:21:22 INFO - --app=APP Application being installed. [default: firefox] 05:21:22 INFO - mkdir: /builds/slave/test/build/application 05:21:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:21:55 INFO - Reading from file tmpfile_stdout 05:21:55 INFO - Output received: 05:21:55 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:21:55 INFO - Running post-action listener: _resource_record_post_action 05:21:55 INFO - ##### 05:21:55 INFO - ##### Running run-tests step. 05:21:55 INFO - ##### 05:21:55 INFO - Running pre-action listener: _resource_record_pre_action 05:21:55 INFO - Running main action method: run_tests 05:21:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:21:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:21:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:21:55 INFO - 2016-04-19 05:21:55.718 screenresolution[1919:18677] starting screenresolution argv=screenresolution get 05:21:55 INFO - 2016-04-19 05:21:55.720 screenresolution[1919:18677] Display 0: 1600x1200x32@60 05:21:55 INFO - 2016-04-19 05:21:55.726 screenresolution[1920:18679] starting screenresolution argv=screenresolution list 05:21:55 INFO - Available Modes on Display 0 05:21:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:21:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:21:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:21:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:21:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:21:55 INFO - Intel Iris: 05:21:55 INFO - Chipset Model: Intel Iris 05:21:55 INFO - Type: GPU 05:21:55 INFO - Bus: Built-In 05:21:55 INFO - VRAM (Dynamic, Max): 1536 MB 05:21:55 INFO - Vendor: Intel (0x8086) 05:21:55 INFO - Device ID: 0x0a2e 05:21:55 INFO - Revision ID: 0x0009 05:21:55 INFO - Displays: 05:21:55 INFO - Display: 05:21:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:21:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:21:55 INFO - Display Serial Number: 1600x1200 60 05:21:55 INFO - Main Display: Yes 05:21:55 INFO - Mirror: Off 05:21:55 INFO - Online: Yes 05:21:55 INFO - Rotation: Supported 05:21:55 INFO - Return code: 0 05:21:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:21:55 INFO - Python 2.7.3 05:21:55 INFO - Return code: 0 05:21:55 INFO - grabbing minidump binary from tooltool 05:21:55 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')]} 05:21:55 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 0x10d5bde00>, '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 0x10d426db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5b8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:21:55 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 05:21:55 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 05:21:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:21:56 INFO - Return code: 0 05:21:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:21:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:21:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:21:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:21:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:21:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:21:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:21:57 INFO - Return code: 0 05:21:57 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/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--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 05:21:57 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/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0018 --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 05:21:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vf19TKiwPM/Render', 05:21:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:57 INFO - 'HOME': '/Users/cltbld', 05:21:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:57 INFO - 'LOGNAME': 'cltbld', 05:21:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:21:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:21:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:57 INFO - 'MOZ_NO_REMOTE': '1', 05:21:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:21:57 INFO - 'NO_EM_RESTART': '1', 05:21:57 INFO - 'PAGER': '/bin/cat', 05:21:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:57 INFO - 'PWD': '/builds/slave/test', 05:21:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:21:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:57 INFO - 'SHELL': '/bin/bash', 05:21:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ezaRFj1Lkf/Listeners', 05:21:57 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 05:21:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:57 INFO - 'USER': 'cltbld', 05:21:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:57 INFO - 'XPC_FLAGS': '0x0', 05:21:57 INFO - 'XPC_SERVICE_NAME': '0', 05:21:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:57 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/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--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 05:21:57 INFO - mozversion application_buildid: 20160419025944 05:21:57 INFO - mozversion application_changeset: ae7413abfa4d3954a6a4ce7c1613a7100f367f9a 05:21:57 INFO - mozversion application_display_name: Nightly 05:21:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:21:57 INFO - mozversion application_name: Firefox 05:21:57 INFO - mozversion application_remotingname: firefox 05:21:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:21:57 INFO - mozversion application_vendor: Mozilla 05:21:57 INFO - mozversion application_version: 48.0a1 05:21:57 INFO - mozversion platform_buildid: 20160419025944 05:21:57 INFO - mozversion platform_changeset: ae7413abfa4d3954a6a4ce7c1613a7100f367f9a 05:21:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:21:57 INFO - mozversion platform_version: 48.0a1 05:21:57 INFO - using testdate: 1461068517 05:21:57 INFO - actual date: 1461068517 05:21:57 INFO - starting webserver on 'localhost:55224' 05:21:57 INFO - SUITE-START | Running 2 tests 05:21:57 INFO - TEST-START | dromaeo_css 05:21:57 INFO - operating with platform_type : mac_ 05:21:57 INFO - Initialising browser for dromaeo_css test... 05:21:57 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpedNKzs/profile http://localhost:55224/getInfo.html) 05:22:00 INFO - PROCESS | 1938 | __metrics Screen width/height:1600/1200 05:22:00 INFO - PROCESS | 1938 | colorDepth:24 05:22:00 INFO - PROCESS | 1938 | Browser inner width/height: 1024/689 05:22:00 INFO - PROCESS | 1938 | __metrics 05:22:00 ERROR - PROCESS | 1938 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:22:00 INFO - TEST-INFO | 1938: exit 0 05:22:00 INFO - Browser initialized. 05:22:00 INFO - Running cycle 1/1 for dromaeo_css test... 05:22:00 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpedNKzs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:22:12 INFO - PROCESS | 1941 | RSS: Main: 247554048 05:22:12 INFO - PROCESS | 1941 | 05:23:02 INFO - PROCESS | 1941 | 1461068582404 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] 05:25:14 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55224/tests/dromaeo/cssquery-ext.html) 05:25:15 INFO - PROCESS | 1941 | RSS: Main: 259903488 05:25:15 INFO - PROCESS | 1941 | 05:28:18 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55224/tests/dromaeo/cssquery-ext.html (next: http://localhost:55224/tests/dromaeo/cssquery-jquery.html) 05:28:18 INFO - PROCESS | 1941 | RSS: Main: 271712256 05:28:18 INFO - PROCESS | 1941 | 05:31:20 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55224/tests/dromaeo/cssquery-mootools.html) 05:31:21 INFO - PROCESS | 1941 | RSS: Main: 274055168 05:31:21 INFO - PROCESS | 1941 | 05:34:22 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55224/tests/dromaeo/cssquery-prototype.html) 05:34:23 INFO - PROCESS | 1941 | RSS: Main: 275279872 05:34:23 INFO - PROCESS | 1941 | 05:37:26 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55224/tests/dromaeo/cssquery-yui.html) 05:37:26 INFO - PROCESS | 1941 | RSS: Main: 278085632 05:37:26 INFO - PROCESS | 1941 | 05:40:29 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55224/tests/dromaeo/cssquery-yui.html (next: http://localhost:55224/tests/dromaeo/cssquery-dojo.html) 05:40:29 INFO - PROCESS | 1941 | RSS: Main: 279662592 05:40:29 INFO - PROCESS | 1941 | 05:40:29 INFO - PROCESS | 1941 | __start_tp_report 05:40:29 INFO - PROCESS | 1941 | _x_x_mozilla_page_load 05:40:29 INFO - PROCESS | 1941 | _x_x_mozilla_page_load_details 05:40:29 INFO - PROCESS | 1941 | |i|pagename|runs| 05:40:29 INFO - PROCESS | 1941 | |0;dojo.html;3497.510348551202;3503.7197024238058;3504.351694936437;3566.928661426773;3578.499010138581;5861.618994765337;5897.20387747655;5950.970245148768;5951.970240148798;5985.491233245174;5799.971000145004;5911.763529458816;5938.64368137911;6001.099835024748;6037.969810150954;4261.3607958806115;4828.927566086511;5304.787808487666;5317.973410132944;5354.732263386834;612.1712026759918;5246.816361427351;5340.359156901177;5346.572274218053;5360.061989151883;5281.815136470224;5319.388270348921;5333.35999680037;5344.465553444663;5378.946210537883;5350.30446042016;5369.087255166631;5377.4084850666395;5398.78404863805;5413.702246376448;4868.85581888256;5029.622778291643;5085.847424577269;5328;5364.570834333283;5764.827055188351;5840.883182336372;5846.327672317653;5907.4683278505145;5965.55258355625;6131.0708424985305;6233;6244.843878903019;6259.561830671855;6268.937310626927;6237.594556353868;6295.5907865988565;6304.991201407753;6310.305866354697;6313.779017734358;6014.8195554133445;6038;6046.576739628228;6053.303870054956;6071.119687645355;6299.212598425197;6345.365463453618;6480.125183100221;6482.222133344029;6499.837504062436;6167.5682702210415;6242.282137554149;6246.312905580383;6248.062790581449;6250.1874756281395;6219.689015549205;6333.556651034475;6335.986242201226;6340.619562826245;6347.650879201596;6138.32709113608;6168.383161683885;6196.132541444202;6256.812295631138;6296.087067375205;5797.7824656821695;6207.751689932352;6231.906521402183;6234.37656234382;6239.344868788712;4241.703080784378;5025.723585202776;5042.268871013683;5052;5081.796728130834;3301.7853839500835;4470.955290447119;4525.389072475239;4574.839880604163;4765.761711914391;4958.553730164303;5926.970365148233;5967.671778052249;6026.578139530278;6101.60339577926;6312.24253089623;6351.809445716636;6394.968025159845;6402.679866006681;6407.135036769976;5468.687514996373;5753.539716822728;6027.336992930774;6033.698315084228;6046.939530604725;5508.834734957958;5517.172424136332;5525.944740552623;5535.640183388067;5595.328560572676;6481.449076828522;6500.252470965805;6507.739690412425;6522.412982831568;6544.345565443417;5085.64400491962;5617.017022021048;6176.876462470816;6310.337414571588;6312.053192021233;5282.577393808409;5516.034693928626;5530.170474428868;5615.652243461622;6494.512911531559;5168.198929165986;6189.442950134509;6240.970239910545;6241.157443745036;6250.968745156245;6149.907751383822;6223.3465486123305;6243.282022567463;6263.498920086473;6325.7153428094925;5206.5314121729225;6144.600600960924;6147.631142131487;6163.7842675506145;6177.949748542848;5186;6097.725602347817;6193.256809182927;6210.5652604317265;6215.129881816459;6064.878702426015;6087.969560152171;6124.777645458507;6164.815055548341;6194.969025154845;5184.533391994739;6139.546016675353;6140.539459540463;6211.254649442096;6218.5647004709235;4703.811847526061;5900.468957793819;6167.876642467215;6169.6915154241215;6197.659128747962 05:40:29 INFO - PROCESS | 1941 | |1;ext.html;510.0971280832926;519.3585921387087;539.4551502981986;541.5045233611246;578.9681567513785;37529;38312.85061448161;38349.0412739681;38356.424653630216;38508.42237366435;39071.21857562847;39637.8018109909;39938.40092398609;39944;40230.19539609213;9246.630134794601;9415.340926135174;9499.572519236617;9523.95238023809;9562.61749530018;9372.203362714177;9588.185004274645;9612.471314077722;9631.181349585293;9641.614335426575;9568.712938611818;9651.758706032335;9653;9653.131218190361;9654.5172741363;9512.47681377524;9599.136077752966;9607.615695372177;9613.086756758097;9624.989376115513;9441.527923603793;9532.856057273102;9570.85643715338;9593.04069593042;9605.951970240174;31953.840230798698;38965.415518767244;39083.609163908564;39229.41155882664;39319.80340098281;2681.7184195659565;2773.195773225762;2955.9852200739074;3167.255694911694;3382.0530251529613;26472.205833825017;26511.73488265112;26991.86504067467;27039.188824335302;27462.90148394062;2623.3048242215827;2643.7488438598302;2705.2154875086203;2705.256054584985;2751.119641714652;317470;317869;318999.4050029735;320078.3996080028;321531;35011;35179.824100879334;35183.4722479163;35240.295194096485;35264.29471410558;67551.66224168945;68792;69307.65346173236;69472;69527;17160.656786864194;17247.74128388075;17431.651366972845;17461.214245358988;17504.38734644307;13112.803307950388;13169.275689836959;13171.07802453819;13196.934015329862;13204.735905281841;16831.57921051959;20032.69950950737;20073.895935723674;20371.77769333845;20377.796222037887;7951.562664053533;7999.480033797785;8027.638756255982;8029.397795165386;8099.473534220257;918.9586468608928;941.1623654947102;964.4599024546136;979.740368802268;1095.3209010413595;24000.63999040016;28361;29870.40259194804;30408;30923.536146957813;966.2077096780713;1168.3982748884332;1171.3381939204323;1238.962831115068;1241.2242348532168;15319.617009574627;15505.844941550666;15550.455734049254;15862.68274634524;15930.840691593168;575066;577914.331285031;584560;586091;586967;56813.715931420906;60308;60960;61825.690871545354;61828;1425.2446203512154;1455.8762505187178;1465.48707952218;1466.5893549805858;1469.0304399096544;29383.853080734458;29505;29972.550411744276;30053.248668783457;30075.69924300729;53470.46529534655;54022.459775401745;54081.72959135179;54133;54183;484854;486417;486471;487353;487561;27358.863205683843;41511.58488415077;43603.12793744044;43715.56284437115;44305;36079.27841443104;36308;42822.57177428186;43777.68666939997;43930.780346098065;28956.13131605971;32534.349313013136;33397.332053359285;33489.66041358318;35372.292554148255;32163.51754723728;34601.78893738707;36615;37280.88157355283;37743.05642358963;37290.6270937298;37913.620863792115;37994.43008354932;38823;39361.80319098386;26483.867580661972;32507.837460812545;33281.83359083189;33558.16104597405;33591.83204083964;19500.902495487433;23478.53042939166;24868.378290542158;25025.249242522754;25588 05:40:29 INFO - PROCESS | 1941 | |2;jquery.html;308.9860956256968;373.79441307280973;389.27206124547104;393.3784620299926;403.8647053237165;17302.91348543257;19785.307514237003;20002.599948001032;20847.166113355466;20877.895610521966;20217.595648087066;20964.475888102806;21042.789572104313;21139.2601258956;21276.787232127674;5625.353084395286;5850.88298234035;6104.053871649901;6127.35662755411;6137.110119032737;6053.697315134225;6096.69516524172;6102.48128909041;6121.479674227696;6138.815835524942;5510.448955104497;5895.263092113486;5939;5951.821445356624;6072.574919755619;5631.436856314356;5741.167530708045;5774.480296773289;6086.969565152191;6093.756249750005;5761.279840019997;5856.590038697293;5879.529637629023;5891.734871930774;5905.084711869667;13727.656808579766;15613;19975.40073797774;24940;25012.749872501223;1960.7941166177488;1999.5800881814837;2004.5790384019374;2036.694495825623;2246.213825161197;9690.75773105671;10105;10137.391756494633;10235.130013948823;10271.383716977005;1704.21606061212;1774.547490389955;1781.3320004998125;1781.4388467632655;1805.1696219738935;120203;121203.3939830304;123650.9999999991;123775.3811230947;124668;9665.758356041155;11571.247868888495;11583.420828958519;11722.765544689059;11730.002949749367;11835.171537992257;13104.148230364995;14869.851301487062;14914.4779932704;14938.178400187877;8429.28351090152;8609.65561377538;8617.526036068077;8724.040355560883;8731.301495880314;8535.317174626016;8550.78623034429;8554.315654747605;8556.101609331055;8562.957185214034;4508.940399006231;4585.72485650862;4590.288505281686;4655.348251244828;4674.2988551716835;1818.7726534183228;1868.4394681595468;1884.5194475408957;1890.914908829106;1893.2427029188434;837.5644664774405;867.6746220167437;870.4472659861034;921.8939821920565;1005.4118340770585;19234.615307693766;20207.898960505103;20348.372130229545;20375.18499260013;20429.387118386472;937.723371605378;942.8397172480695;945.3146468810057;976.8046390721884;1100.4332768624165;8677.305815534743;10283.53724082418;10447.477626118663;10447.791044179226;10499.527521261562;505597;513016;565677;567092;570383;97670.51164744131;127805.72194277938;133937;134949.32525337508;138714;1003.40798928631;1006.2754816532085;1019.6482213636284;1026.2662196529518;1026.461107918349;17389;17728.645427091127;17850.196741146938;17912.552186195448;18122.546936326697;41054.38418423666;48014.759926200146;49658.75170624124;49722.00555988932;51145.46563603098;62791;63138.36861631325;64236.71526569536;64767;65251;8033.076196237507;8079.394045446497;8156.673733050849;8187.222213889671;8555.700550480704;10158.39049657023;12466.563670271496;12501.874981250436;12871.935640321739;12921;8656.870146947927;9830.705078847646;10251.436171010666;11391.316521008766;11672.358020308624;9665.9033409665;10802.243842930919;11128.833067503832;11286.830697539379;11391.54433822671;11596.130290228435;11599.5360185595;11631.59289424866;11722.70693232676;11751.588694395656;7994.5602991836;8199.385046121446;8256.83486330282;8260.132686067876;8579.013413457533;8324.875126873223;10835.133189344991;10841.403722795007;11142.832857507306;11196.133309514027 05:40:29 INFO - PROCESS | 1941 | |3;mootools.html;1519.962000949976;1605.181357507671;1634.6730653869229;1856.8143185681424;1966.9704954425695;8095.554744489051;8632.56837158142;8688.913110868889;8902.465852048881;8914.64341426343;8682.609282582283;8726.432781869175;8794.076621954699;8801.251893589053;8822.029576746569;2079.1787244038596;2414.8188885833547;2447.510497900418;2451.3871532116973;2462.8645424501733;2404.5070760494145;2419.2863105383867;2442.792362649177;2446.6330050492393;2457.5453541094867;2423.7333893271725;2438.426969662128;2452.4849781545895;2460.065175233418;2460.950780984388;2048.283100914683;2370.6444033394955;2398.412388964715;2400.6519054737055;2428.6842710447704;2050.759290629163;2128.70198172256;2187.687387567463;2396.604560247557;2396.940076498084;8741.825163496758;8864.689735859214;8935;8952.373333866632;9004.279657627374;4907.773056735816;5006.974965125188;5485.561155107582;5499.285092937933;5510.834674959758;3253.5933008373954;3406.744494162923;3564.9483402396354;3596.514470546469;3652.1600031992525;981.8330883749708;995.4723996281982;1009.333839665817;1010.6614284214797;1011.4942528735633;11740.295582265006;11850.762984740257;11858.762824743542;11864.347460889647;11864.822027669505;3697;4119.546849846514;4695.22528782747;4873.561379475864;4888.462269150391;9955.651552195639;10020.749481262881;10037.146842518468;10063.245256605784;10063.396196228103;5434.157705555645;5434.456554344534;5451.291332126877;5507.421720719347;5518.061929471999;4021.4571032910185;4457.264551349056;4858.58702010333;4928.186849169919;4983.9252411213865;4028.858989935397;4040.797960102042;4087.3255912774653;4157;4157.771322577288;1333.3599872061466;1348.7504662439387;1350.9121907076008;1354.8441929178073;1356.4641966423208;828.0146625515617;872.4242000279785;894.7628878347243;902.9322800789967;944.6693657220055;5676.347220069745;5785.566082543826;5885.205497257815;5964.1650168976375;5985.132155837379;974.6198982396871;990.5443495991781;997.6757553795046;1012.9949350253202;1027.4605831938143;3902.1220225449047;3958.5447673517915;4001.4397984282223;4027.275090483741;4039.2931237033404;10608;10882.347059176474;10957.726056848642;10982;11062.949019843101;10828.891711082946;10911.399873006902;10962.780744385227;11007.504662290215;11045.613403530839;6484.773032943825;8232;8274.089850116363;8359.456635318806;8409.579521023925;4859.586935110414;5918.585699001201;6022.939770602413;6029.78895738647;6068.787592434243;9559.904400955902;9778.706638800848;9940.10539051489;9962.501874906371;9991.450470224207;10206.438645874548;10224.53989570486;10278.691639250834;10310.278280520592;10363.170946324126;6758.357955994173;7392.080128349379;7461;7545;7605.733799316998;8298.668053278043;8349.99800024001;8359.37304702162;8368.581570921551;8411.3270938326;7289.489735718449;7523.849523009619;7532.661030253542;7572.242775722384;7576.962115189389;7310.59791711461;7355.926440735739;7363.558186508827;7433.331000209791;7481.925180748341;7064.646767661699;7085.043519125058;7125.821854453473;7161.391281740902;7169.247229040876;4786.6410019248;4823.807047718052;4942.135126352946;4946.975265123795;5104.795808167781;4857.489963553776;4983.177775666974;5039.924401133912;5044.621653376085;5183.481651834786 05:40:29 INFO - PROCESS | 1941 | |4;prototype.html;318.60333884314025;319.29595242490313;325.2632786738039;327.6379600541401;331.35551352619166;5422.728863556826;6177;6230.657313847742;6257.71840267188;6333.8099857004245;5408.997341966946;5852.385499522553;6193.7832175873855;6218.284897236807;6239.313675495703;3953.8023098845088;4435.290353543425;4485.215087359716;4515.977420112895;4651.627869770427;4197.7271477354025;4450.154470650582;4617.591634551461;4618.284165954283;4673.299005149221;4080.204360149772;4568.703034302776;4598.172328980782;4602.447706275235;4606.769661516928;4380.2115619188535;4622.352870598119;4623.32961720552;4623.815047398083;4633.351330813689;3969.5236571611304;3974.3243648579523;3978.3435733104;3980.144268982154;4017.6183262590007;5176.922346164811;5918;5955.702214889281;5969.761209551612;5976.910346344808;4463.888402789924;5100.107481190814;5267.288915996368;5399.865003374908;5418.783248670048;5676.716164191774;6519.739210431577;6519.8696026079215;6537.738490460376;6539;4026.3828511467486;4176.582341765829;4412.514623391425;4416.8516185791605;4435.578620031092;6105.321036714895;6817.659117044128;6904.516683832134;6918.93081069193;6919.308069193041;3790.981045094757;4458.910821783546;4470.597646211857;4494.640428765691;4547.727136371828;3959.48526691534;4556.384888040138;4565.748883811356;4573.748443835621;4575.199340115467;4652.325412815123;5134.614903882149;5270.97216042868;5341.050516561427;5404.270423492857;5543.8891222176135;5763.596548241583;6579.638119903451;6588.637624930579;6621.9337806621315;1419.6592817723672;1531.9080855148727;1533.854283843033;1547.3578464936925;1591.5861875912115;921.3504479342048;974.5565767575736;984.2027957354566;991.980160396788;993.5777294649745;5376.4085950545405;6043.425874541911;6100;6117.816465506042;6149.938500615026;5345.625806193607;5603.915941260884;5738.483536481736;6770.052192693027;6785.287544807724;4566.474855391606;5267;5738.397468265855;6113.510919126459;6119.938800611937;3446.9310613788084;3525.453554699026;3564.8752293669595;3578.1911199708106;3600.8739694111105;5163.967206558703;5735.770569177186;6422.197225346832;6800.557963732342;7098.29017098286;6408.487321014308;6753.29090445506;6845.520813543104;6847.246802851682;6960.895586566206;6705.6647167642395;6777.999999999902;6927.483458412924;7073.408483091263;7074.823129421806;5358.303420555303;6682.665866706548;6735.865282694221;6741.59550426976;6773.762918297836;6295.401936815995;6639.468842492685;6659.267480577036;6677.599344039373;6692;6253.374662533893;6670.9332906670315;6734.225564060196;7037.824054398681;7241.710331586888;4350.847720329773;4384.693071484965;4404.603585677304;4404.7357158570585;4406.383106365176;5729.427057294237;6556.803295901131;6675.566088204251;6790.351762812408;6809.693563789542;5363.383210930793;5692.31692196939;6257.9687101564205;6593.505487088391;6620.966895165686;4915.188993816123;5293.894122117613;5719.542436605145;6589.308122647053;6593.538392344058;5250.422453530032;5993.940060599512;6224.190855188796;6479.1901012373455;6487.4430136765895;6257.530685198537;6614.272430032596;6615.239247486601;6625.039369291329;6629.723828130732;5670.546356291569;6214.782482613087;6459.838504037249;6504.1869766279215;6510 05:40:29 INFO - PROCESS | 1941 | |5;yui.html;651.5178767711894;651.8305240637433;661.2957200581382;720.171802427209;720.5568575326173;13242.404091815868;14814.70370592589;14945;15135.243237838105;16489.422870199545;15530.767038494432;15553.844461555382;15611.531654050368;15620.687586248298;15670.608234794106;906.4380084347714;915.276931224332;917.4862077236735;918.1736437206534;920.1028996728203;876.4872549558515;894.1773568317163;897.6544030548221;898.8516894712365;899.1053901368148;943.2831048403194;960.3037797596721;965.227817745804;966.3090890013639;969.9127078562927;925.3476299209075;930.4370855632346;948.0472125513849;955.6129767444196;956.3353469338813;696.08464868698;707.6249587718518;711.4486273138308;714.8141483214401;719.5215181904027;14459.78310325346;14773.778393324108;14780.260986950609;14784;14796.260186990607;12269.815952760715;12312.26126432408;12384.938075309567;12399.566015189424;12649.620511384674;9831;9869.950650246774;10038.196944244515;10184;10605;3180.840957952093;3380.154961259685;3462.87879924204;3503.8598456061727;3584.551931008624;77343.8398424024;77378;77469.61265193638;77817.44365112722;78675.60662196767;9434.90565094354;9481.261106563072;9521.095495927875;9732.95133524342;9771.21830253578;20758.377248682562;20788.27241046556;20872.26947056876;20908.268210612554;21243.893780530998;7442.627868606656;7468.477206595595;7547.0943486781935;7581.052368453943;7799.80500487481;4034.3141665916864;4041.474608300961;4049.190161967618;4073.429719839225;4085.3667681509414;3597.4963505109304;3665.578458477309;3680.9263814723554;3691.280200360904;3702.9629703702617;572.6964708704394;665.5607299182516;688.8243497908002;695.2109355881112;700.8423104801481;502.45734606624757;555.9888802224013;561.2535328013732;568.8122919436659;578.0317967404665;6882.7591034313555;7499.887501687479;8059.032916050111;8077.111517733045;8105.797355066171;521.426430925986;589.0516268807216;598.6617561077977;616.9383061693794;630.9400606942334;3896.162325100117;3896.4544963705102;3961.9405708914387;3975.2248311578965;4036.0442978040232;99069.50465247627;105749;106963;107966.44234903638;108491;87832;89147.55426222828;89906.65140022905;90496;90625.54687226521;73848.26151738521;77531.61234193793;77993.44013119706;78677.4264514718;80206.13843279053;7584.12782529994;7637.541747495168;7647.732329368668;7668.6165691716305;7676.692932282646;41930.790346048074;43696.56303437052;43795.34306985334;46500.13697606601;47876.5212347886;64685.67657161684;68211.65894170497;68290;68357;68440;9848.310618256939;9851.605935762489;9898.604055837686;10082.546285417027;10460.48778550934;10921.563137474412;11261.099112071179;11332.376719280519;11409.714757131138;11427.371494567878;10064.696625284316;10223.368126031877;10270.178385729274;10280.897191028293;10413.739656508345;10457.529411176363;10490.632827850988;10763.623273185402;10781.299215550964;10788.244822862323;10143.442110683687;10337.483125843828;10351.223658225512;10384.532696028546;10451.895481045092;7570.205128461433;8574.31405487547;8672.349573781865;8738.257248133725;8762;8843.734687959372;8980.281577473917;9267.320814862725;9333.240326454059;9578.664746733832 05:40:29 INFO - PROCESS | 1941 | __end_tp_report 05:40:29 INFO - PROCESS | 1941 | __start_cc_report 05:40:29 INFO - PROCESS | 1941 | _x_x_mozilla_cycle_collect,395 05:40:29 INFO - PROCESS | 1941 | __end_cc_report 05:40:29 INFO - PROCESS | 1941 | __startTimestamp1461069629909__endTimestamp 05:40:29 INFO - PROCESS | 1941 | 05:40:29 INFO - PROCESS | 1941 | ------- Summary: start ------- 05:40:29 INFO - PROCESS | 1941 | Number of tests: 6 05:40:29 INFO - PROCESS | 1941 | 05:40:29 INFO - PROCESS | 1941 | [#0] dojo.html Cycles:165 Average:5740.08 Median:6046.58 stddev:787.62 (13.0%) stddev-sans-first:770.14 05:40:29 INFO - PROCESS | 1941 | Values: 3497.5 3503.7 3504.4 3566.9 3578.5 5861.6 5897.2 5951.0 5952.0 5985.5 5800.0 5911.8 5938.6 6001.1 6038.0 4261.4 4828.9 5304.8 5318.0 5354.7 612.2 5246.8 5340.4 5346.6 5360.1 5281.8 5319.4 5333.4 5344.5 5378.9 5350.3 5369.1 5377.4 5398.8 5413.7 4868.9 5029.6 5085.8 5328.0 5364.6 5764.8 5840.9 5846.3 5907.5 5965.6 6131.1 6233.0 6244.8 6259.6 6268.9 6237.6 6295.6 6305.0 6310.3 6313.8 6014.8 6038.0 6046.6 6053.3 6071.1 6299.2 6345.4 6480.1 6482.2 6499.8 6167.6 6242.3 6246.3 6248.1 6250.2 6219.7 6333.6 6336.0 6340.6 6347.7 6138.3 6168.4 6196.1 6256.8 6296.1 5797.8 6207.8 6231.9 6234.4 6239.3 4241.7 5025.7 5042.3 5052.0 5081.8 3301.8 4471.0 4525.4 4574.8 4765.8 4958.6 5927.0 5967.7 6026.6 6101.6 6312.2 6351.8 6395.0 6402.7 6407.1 5468.7 5753.5 6027.3 6033.7 6046.9 5508.8 5517.2 5525.9 5535.6 5595.3 6481.4 6500.3 6507.7 6522.4 6544.3 5085.6 5617.0 6176.9 6310.3 6312.1 5282.6 5516.0 5530.2 5615.7 6494.5 5168.2 6189.4 6241.0 6241.2 6251.0 6149.9 6223.3 6243.3 6263.5 6325.7 5206.5 6144.6 6147.6 6163.8 6177.9 5186.0 6097.7 6193.3 6210.6 6215.1 6064.9 6088.0 6124.8 6164.8 6195.0 5184.5 6139.5 6140.5 6211.3 6218.6 4703.8 5900.5 6167.9 6169.7 6197.7 05:40:29 INFO - PROCESS | 1941 | 05:40:29 INFO - PROCESS | 1941 | [#1] ext.html Cycles:180 Average:60601.67 Median:37828.34 stddev:126744.64 (335.1%) stddev-sans-first:127019.89 05:40:29 INFO - PROCESS | 1941 | Values: 510.1 519.4 539.5 541.5 579.0 37529.0 38312.9 38349.0 38356.4 38508.4 39071.2 39637.8 39938.4 39944.0 40230.2 9246.6 9415.3 9499.6 9524.0 9562.6 9372.2 9588.2 9612.5 9631.2 9641.6 9568.7 9651.8 9653.0 9653.1 9654.5 9512.5 9599.1 9607.6 9613.1 9625.0 9441.5 9532.9 9570.9 9593.0 9606.0 31953.8 38965.4 39083.6 39229.4 39319.8 2681.7 2773.2 2956.0 3167.3 3382.1 26472.2 26511.7 26991.9 27039.2 27462.9 2623.3 2643.7 2705.2 2705.3 2751.1 317470.0 317869.0 318999.4 320078.4 321531.0 35011.0 35179.8 35183.5 35240.3 35264.3 67551.7 68792.0 69307.7 69472.0 69527.0 17160.7 17247.7 17431.7 17461.2 17504.4 13112.8 13169.3 13171.1 13196.9 13204.7 16831.6 20032.7 20073.9 20371.8 20377.8 7951.6 7999.5 8027.6 8029.4 8099.5 919.0 941.2 964.5 979.7 1095.3 24000.6 28361.0 29870.4 30408.0 30923.5 966.2 1168.4 1171.3 1239.0 1241.2 15319.6 15505.8 15550.5 15862.7 15930.8 575066.0 577914.3 584560.0 586091.0 586967.0 56813.7 60308.0 60960.0 61825.7 61828.0 1425.2 1455.9 1465.5 1466.6 1469.0 29383.9 29505.0 29972.6 30053.2 30075.7 53470.5 54022.5 54081.7 54133.0 54183.0 484854.0 486417.0 486471.0 487353.0 487561.0 27358.9 41511.6 43603.1 43715.6 44305.0 36079.3 36308.0 42822.6 43777.7 43930.8 28956.1 32534.3 33397.3 33489.7 35372.3 32163.5 34601.8 36615.0 37280.9 37743.1 37290.6 37913.6 37994.4 38823.0 39361.8 26483.9 32507.8 33281.8 33558.2 33591.8 19500.9 23478.5 24868.4 25025.2 25588.0 05:40:29 INFO - PROCESS | 1941 | 05:40:29 INFO - PROCESS | 1941 | [#2] jquery.html Cycles:180 Average:32828.67 Median:11761.50 stddev:91493.34 (777.9%) stddev-sans-first:91717.42 05:40:29 INFO - PROCESS | 1941 | Values: 309.0 373.8 389.3 393.4 403.9 17302.9 19785.3 20002.6 20847.2 20877.9 20217.6 20964.5 21042.8 21139.3 21276.8 5625.4 5850.9 6104.1 6127.4 6137.1 6053.7 6096.7 6102.5 6121.5 6138.8 5510.4 5895.3 5939.0 5951.8 6072.6 5631.4 5741.2 5774.5 6087.0 6093.8 5761.3 5856.6 5879.5 5891.7 5905.1 13727.7 15613.0 19975.4 24940.0 25012.7 1960.8 1999.6 2004.6 2036.7 2246.2 9690.8 10105.0 10137.4 10235.1 10271.4 1704.2 1774.5 1781.3 1781.4 1805.2 120203.0 121203.4 123651.0 123775.4 124668.0 9665.8 11571.2 11583.4 11722.8 11730.0 11835.2 13104.1 14869.9 14914.5 14938.2 8429.3 8609.7 8617.5 8724.0 8731.3 8535.3 8550.8 8554.3 8556.1 8563.0 4508.9 4585.7 4590.3 4655.3 4674.3 1818.8 1868.4 1884.5 1890.9 1893.2 837.6 867.7 870.4 921.9 1005.4 19234.6 20207.9 20348.4 20375.2 20429.4 937.7 942.8 945.3 976.8 1100.4 8677.3 10283.5 10447.5 10447.8 10499.5 505597.0 513016.0 565677.0 567092.0 570383.0 97670.5 127805.7 133937.0 134949.3 138714.0 1003.4 1006.3 1019.6 1026.3 1026.5 17389.0 17728.6 17850.2 17912.6 18122.5 41054.4 48014.8 49658.8 49722.0 51145.5 62791.0 63138.4 64236.7 64767.0 65251.0 8033.1 8079.4 8156.7 8187.2 8555.7 10158.4 12466.6 12501.9 12871.9 12921.0 8656.9 9830.7 10251.4 11391.3 11672.4 9665.9 10802.2 11128.8 11286.8 11391.5 11596.1 11599.5 11631.6 11722.7 11751.6 7994.6 8199.4 8256.8 8260.1 8579.0 8324.9 10835.1 10841.4 11142.8 11196.1 05:40:29 INFO - PROCESS | 1941 | 05:40:29 INFO - PROCESS | 1941 | [#3] mootools.html Cycles:180 Average:5738.97 Median:4898.12 stddev:3232.83 (66.0%) stddev-sans-first:3226.35 05:40:29 INFO - PROCESS | 1941 | Values: 1520.0 1605.2 1634.7 1856.8 1967.0 8095.6 8632.6 8688.9 8902.5 8914.6 8682.6 8726.4 8794.1 8801.3 8822.0 2079.2 2414.8 2447.5 2451.4 2462.9 2404.5 2419.3 2442.8 2446.6 2457.5 2423.7 2438.4 2452.5 2460.1 2461.0 2048.3 2370.6 2398.4 2400.7 2428.7 2050.8 2128.7 2187.7 2396.6 2396.9 8741.8 8864.7 8935.0 8952.4 9004.3 4907.8 5007.0 5485.6 5499.3 5510.8 3253.6 3406.7 3564.9 3596.5 3652.2 981.8 995.5 1009.3 1010.7 1011.5 11740.3 11850.8 11858.8 11864.3 11864.8 3697.0 4119.5 4695.2 4873.6 4888.5 9955.7 10020.7 10037.1 10063.2 10063.4 5434.2 5434.5 5451.3 5507.4 5518.1 4021.5 4457.3 4858.6 4928.2 4983.9 4028.9 4040.8 4087.3 4157.0 4157.8 1333.4 1348.8 1350.9 1354.8 1356.5 828.0 872.4 894.8 902.9 944.7 5676.3 5785.6 5885.2 5964.2 5985.1 974.6 990.5 997.7 1013.0 1027.5 3902.1 3958.5 4001.4 4027.3 4039.3 10608.0 10882.3 10957.7 10982.0 11062.9 10828.9 10911.4 10962.8 11007.5 11045.6 6484.8 8232.0 8274.1 8359.5 8409.6 4859.6 5918.6 6022.9 6029.8 6068.8 9559.9 9778.7 9940.1 9962.5 9991.5 10206.4 10224.5 10278.7 10310.3 10363.2 6758.4 7392.1 7461.0 7545.0 7605.7 8298.7 8350.0 8359.4 8368.6 8411.3 7289.5 7523.8 7532.7 7572.2 7577.0 7310.6 7355.9 7363.6 7433.3 7481.9 7064.6 7085.0 7125.8 7161.4 7169.2 4786.6 4823.8 4942.1 4947.0 5104.8 4857.5 4983.2 5039.9 5044.6 5183.5 05:40:29 INFO - PROCESS | 1941 | 05:40:29 INFO - PROCESS | 1941 | [#4] prototype.html Cycles:180 Average:5221.14 Median:5737.08 stddev:1633.04 (28.5%) stddev-sans-first:1595.62 05:40:29 INFO - PROCESS | 1941 | Values: 318.6 319.3 325.3 327.6 331.4 5422.7 6177.0 6230.7 6257.7 6333.8 5409.0 5852.4 6193.8 6218.3 6239.3 3953.8 4435.3 4485.2 4516.0 4651.6 4197.7 4450.2 4617.6 4618.3 4673.3 4080.2 4568.7 4598.2 4602.4 4606.8 4380.2 4622.4 4623.3 4623.8 4633.4 3969.5 3974.3 3978.3 3980.1 4017.6 5176.9 5918.0 5955.7 5969.8 5976.9 4463.9 5100.1 5267.3 5399.9 5418.8 5676.7 6519.7 6519.9 6537.7 6539.0 4026.4 4176.6 4412.5 4416.9 4435.6 6105.3 6817.7 6904.5 6918.9 6919.3 3791.0 4458.9 4470.6 4494.6 4547.7 3959.5 4556.4 4565.7 4573.7 4575.2 4652.3 5134.6 5271.0 5341.1 5404.3 5543.9 5763.6 6579.6 6588.6 6621.9 1419.7 1531.9 1533.9 1547.4 1591.6 921.4 974.6 984.2 992.0 993.6 5376.4 6043.4 6100.0 6117.8 6149.9 5345.6 5603.9 5738.5 6770.1 6785.3 4566.5 5267.0 5738.4 6113.5 6119.9 3446.9 3525.5 3564.9 3578.2 3600.9 5164.0 5735.8 6422.2 6800.6 7098.3 6408.5 6753.3 6845.5 6847.2 6960.9 6705.7 6778.0 6927.5 7073.4 7074.8 5358.3 6682.7 6735.9 6741.6 6773.8 6295.4 6639.5 6659.3 6677.6 6692.0 6253.4 6670.9 6734.2 7037.8 7241.7 4350.8 4384.7 4404.6 4404.7 4406.4 5729.4 6556.8 6675.6 6790.4 6809.7 5363.4 5692.3 6258.0 6593.5 6621.0 4915.2 5293.9 5719.5 6589.3 6593.5 5250.4 5993.9 6224.2 6479.2 6487.4 6257.5 6614.3 6615.2 6625.0 6629.7 5670.5 6214.8 6459.8 6504.2 6510.0 05:40:29 INFO - PROCESS | 1941 | 05:40:29 INFO - PROCESS | 1941 | [#5] yui.html Cycles:180 Average:18788.88 Median:32668.60 stddev:27749.92 (84.9%) stddev-sans-first:27794.35 05:40:29 INFO - PROCESS | 1941 | Values: 651.5 651.8 661.3 720.2 720.6 13242.4 14814.7 14945.0 15135.2 16489.4 15530.8 15553.8 15611.5 15620.7 15670.6 906.4 915.3 917.5 918.2 920.1 876.5 894.2 897.7 898.9 899.1 943.3 960.3 965.2 966.3 969.9 925.3 930.4 948.0 955.6 956.3 696.1 707.6 711.4 714.8 719.5 14459.8 14773.8 14780.3 14784.0 14796.3 12269.8 12312.3 12384.9 12399.6 12649.6 9831.0 9870.0 10038.2 10184.0 10605.0 3180.8 3380.2 3462.9 3503.9 3584.6 77343.8 77378.0 77469.6 77817.4 78675.6 9434.9 9481.3 9521.1 9733.0 9771.2 20758.4 20788.3 20872.3 20908.3 21243.9 7442.6 7468.5 7547.1 7581.1 7799.8 4034.3 4041.5 4049.2 4073.4 4085.4 3597.5 3665.6 3680.9 3691.3 3703.0 572.7 665.6 688.8 695.2 700.8 502.5 556.0 561.3 568.8 578.0 6882.8 7499.9 8059.0 8077.1 8105.8 521.4 589.1 598.7 616.9 630.9 3896.2 3896.5 3961.9 3975.2 4036.0 99069.5 105749.0 106963.0 107966.4 108491.0 87832.0 89147.6 89906.7 90496.0 90625.5 73848.3 77531.6 77993.4 78677.4 80206.1 7584.1 7637.5 7647.7 7668.6 7676.7 41930.8 43696.6 43795.3 46500.1 47876.5 64685.7 68211.7 68290.0 68357.0 68440.0 9848.3 9851.6 9898.6 10082.5 10460.5 10921.6 11261.1 11332.4 11409.7 11427.4 10064.7 10223.4 10270.2 10280.9 10413.7 10457.5 10490.6 10763.6 10781.3 10788.2 10143.4 10337.5 10351.2 10384.5 10451.9 7570.2 8574.3 8672.3 8738.3 8762.0 8843.7 8980.3 9267.3 9333.2 9578.7 05:40:29 INFO - PROCESS | 1941 | -------- Summary: end -------- 05:40:29 INFO - PROCESS | 1941 | 05:40:30 INFO - TEST-INFO | 1941: exit 0 05:40:30 INFO - TEST-OK | dromaeo_css | took 1112995ms 05:40:30 INFO - TEST-START | kraken 05:40:30 INFO - operating with platform_type : mac_ 05:40:30 INFO - Initialising browser for kraken test... 05:40:30 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpnZ5_WZ/profile http://localhost:55224/getInfo.html) 05:40:33 INFO - PROCESS | 1947 | __metrics Screen width/height:1600/1200 05:40:33 INFO - PROCESS | 1947 | colorDepth:24 05:40:33 INFO - PROCESS | 1947 | Browser inner width/height: 1024/689 05:40:33 INFO - PROCESS | 1947 | __metrics 05:40:33 ERROR - PROCESS | 1947 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:40:34 INFO - TEST-INFO | 1947: exit 0 05:40:34 INFO - Browser initialized. 05:40:34 INFO - Running cycle 1/1 for kraken test... 05:40:34 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpnZ5_WZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:40:46 INFO - PROCESS | 1950 | RSS: Main: 247726080 05:40:46 INFO - PROCESS | 1950 | 05:40:49 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55224/tests/kraken/driver.html?testName=audio-beat-detection) 05:40:50 INFO - PROCESS | 1950 | RSS: Main: 259821568 05:40:50 INFO - PROCESS | 1950 | 05:40:53 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55224/tests/kraken/driver.html?testName=audio-dft) 05:40:53 INFO - PROCESS | 1950 | RSS: Main: 269185024 05:40:53 INFO - PROCESS | 1950 | 05:40:57 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55224/tests/kraken/driver.html?testName=audio-fft) 05:40:57 INFO - PROCESS | 1950 | RSS: Main: 272044032 05:40:57 INFO - PROCESS | 1950 | 05:40:59 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55224/tests/kraken/driver.html?testName=audio-oscillator) 05:41:00 INFO - PROCESS | 1950 | RSS: Main: 272994304 05:41:00 INFO - PROCESS | 1950 | 05:41:02 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:41:03 INFO - PROCESS | 1950 | RSS: Main: 274546688 05:41:03 INFO - PROCESS | 1950 | 05:41:06 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55224/tests/kraken/driver.html?testName=imaging-darkroom) 05:41:06 INFO - PROCESS | 1950 | RSS: Main: 275308544 05:41:06 INFO - PROCESS | 1950 | 05:41:09 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55224/tests/kraken/driver.html?testName=imaging-desaturate) 05:41:10 INFO - PROCESS | 1950 | RSS: Main: 275517440 05:41:10 INFO - PROCESS | 1950 | 05:41:13 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55224/tests/kraken/driver.html?testName=json-parse-financial) 05:41:13 INFO - PROCESS | 1950 | RSS: Main: 275525632 05:41:13 INFO - PROCESS | 1950 | 05:41:15 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:41:15 INFO - PROCESS | 1950 | RSS: Main: 274759680 05:41:15 INFO - PROCESS | 1950 | 05:41:17 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55224/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:41:18 INFO - PROCESS | 1950 | RSS: Main: 274825216 05:41:18 INFO - PROCESS | 1950 | 05:41:20 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:41:21 INFO - PROCESS | 1950 | RSS: Main: 275091456 05:41:21 INFO - PROCESS | 1950 | 05:41:24 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:41:24 INFO - PROCESS | 1950 | RSS: Main: 275185664 05:41:24 INFO - PROCESS | 1950 | 05:41:27 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:41:28 INFO - PROCESS | 1950 | RSS: Main: 275202048 05:41:28 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:55224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55224/tests/kraken/driver.html?testName=ai-astar) 05:41:30 INFO - PROCESS | 1950 | RSS: Main: 276815872 05:41:30 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | __start_tp_report 05:41:30 INFO - PROCESS | 1950 | _x_x_mozilla_page_load 05:41:30 INFO - PROCESS | 1950 | _x_x_mozilla_page_load_details 05:41:30 INFO - PROCESS | 1950 | |i|pagename|runs| 05:41:30 INFO - PROCESS | 1950 | |0;ai-astar;128;154;165;115;117;123;111;118;115;117 05:41:30 INFO - PROCESS | 1950 | |1;audio-beat-detection;196;160;176;157;153;173;174;125;174;160 05:41:30 INFO - PROCESS | 1950 | |2;audio-dft;233;271;264;217;197;204;201;199;199;202 05:41:30 INFO - PROCESS | 1950 | |3;audio-fft;66;77;76;143;55;59;93;83;78;103 05:41:30 INFO - PROCESS | 1950 | |4;audio-oscillator;91;71;164;111;117;124;125;79;83;120 05:41:30 INFO - PROCESS | 1950 | |5;imaging-gaussian-blur;90;87;88;87;89;87;92;87;90;103 05:41:30 INFO - PROCESS | 1950 | |6;imaging-darkroom;95;95;95;97;148;125;96;95;96;94 05:41:30 INFO - PROCESS | 1950 | |7;imaging-desaturate;90;93;90;90;90;88;90;117;117;90 05:41:30 INFO - PROCESS | 1950 | |8;json-parse-financial;63;63;62;60;62;61;62;81;64;60 05:41:30 INFO - PROCESS | 1950 | |9;json-stringify-tinderbox;64;66;67;99;76;73;65;66;67;68 05:41:30 INFO - PROCESS | 1950 | |10;stanford-crypto-aes;86;82;86;86;83;84;83;86;85;87 05:41:30 INFO - PROCESS | 1950 | |11;stanford-crypto-ccm;138;146;153;153;165;157;157;140;173;143 05:41:30 INFO - PROCESS | 1950 | |12;stanford-crypto-pbkdf2;173;171;171;171;218;164;167;173;166;172 05:41:30 INFO - PROCESS | 1950 | |13;stanford-crypto-sha256-iterative;69;72;70;70;70;70;86;98;99;98 05:41:30 INFO - PROCESS | 1950 | __end_tp_report 05:41:30 INFO - PROCESS | 1950 | __start_cc_report 05:41:30 INFO - PROCESS | 1950 | _x_x_mozilla_cycle_collect,1045 05:41:30 INFO - PROCESS | 1950 | __end_cc_report 05:41:30 INFO - PROCESS | 1950 | __startTimestamp1461069690378__endTimestamp 05:41:30 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | ------- Summary: start ------- 05:41:30 INFO - PROCESS | 1950 | Number of tests: 14 05:41:30 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | [#0] ai-astar Cycles:10 Average:126.30 Median:120.50 stddev:18.29 (15.2%) stddev-sans-first:19.39 05:41:30 INFO - PROCESS | 1950 | Values: 128.0 154.0 165.0 115.0 117.0 123.0 111.0 118.0 115.0 117.0 05:41:30 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | [#1] audio-beat-detection Cycles:10 Average:164.80 Median:173.50 stddev:18.75 (10.8%) stddev-sans-first:16.14 05:41:30 INFO - PROCESS | 1950 | Values: 196.0 160.0 176.0 157.0 153.0 173.0 174.0 125.0 174.0 160.0 05:41:30 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | [#2] audio-dft Cycles:10 Average:218.70 Median:210.50 stddev:27.99 (13.3%) stddev-sans-first:29.20 05:41:30 INFO - PROCESS | 1950 | Values: 233.0 271.0 264.0 217.0 197.0 204.0 201.0 199.0 199.0 202.0 05:41:30 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | [#3] audio-fft Cycles:10 Average:83.30 Median:76.50 stddev:25.51 (33.3%) stddev-sans-first:26.28 05:41:30 INFO - PROCESS | 1950 | Values: 66.0 77.0 76.0 143.0 55.0 59.0 93.0 83.0 78.0 103.0 05:41:30 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | [#4] audio-oscillator Cycles:10 Average:108.50 Median:117.50 stddev:27.96 (23.8%) stddev-sans-first:28.93 05:41:30 INFO - PROCESS | 1950 | Values: 91.0 71.0 164.0 111.0 117.0 124.0 125.0 79.0 83.0 120.0 05:41:30 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | [#5] imaging-gaussian-blur Cycles:10 Average:90.00 Median:88.50 stddev:4.88 (5.5%) stddev-sans-first:5.17 05:41:30 INFO - PROCESS | 1950 | Values: 90.0 87.0 88.0 87.0 89.0 87.0 92.0 87.0 90.0 103.0 05:41:30 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | [#6] imaging-darkroom Cycles:10 Average:103.60 Median:95.00 stddev:18.19 (19.1%) stddev-sans-first:19.02 05:41:30 INFO - PROCESS | 1950 | Values: 95.0 95.0 95.0 97.0 148.0 125.0 96.0 95.0 96.0 94.0 05:41:30 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:90.00 stddev:11.39 (12.7%) stddev-sans-first:11.91 05:41:30 INFO - PROCESS | 1950 | Values: 90.0 93.0 90.0 90.0 90.0 88.0 90.0 117.0 117.0 90.0 05:41:30 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:62.50 stddev:6.18 (9.9%) stddev-sans-first:6.55 05:41:30 INFO - PROCESS | 1950 | Values: 63.0 63.0 62.0 60.0 62.0 61.0 62.0 81.0 64.0 60.0 05:41:30 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:67.50 stddev:10.48 (15.5%) stddev-sans-first:10.80 05:41:30 INFO - PROCESS | 1950 | Values: 64.0 66.0 67.0 99.0 76.0 73.0 65.0 66.0 67.0 68.0 05:41:30 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:86.00 stddev:1.69 (2.0%) stddev-sans-first:1.73 05:41:30 INFO - PROCESS | 1950 | Values: 86.0 82.0 86.0 86.0 83.0 84.0 83.0 86.0 85.0 87.0 05:41:30 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | [#11] stanford-crypto-ccm Cycles:10 Average:152.50 Median:155.00 stddev:11.14 (7.2%) stddev-sans-first:10.51 05:41:30 INFO - PROCESS | 1950 | Values: 138.0 146.0 153.0 153.0 165.0 157.0 157.0 140.0 173.0 143.0 05:41:30 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.60 Median:171.50 stddev:15.56 (9.1%) stddev-sans-first:16.49 05:41:30 INFO - PROCESS | 1950 | Values: 173.0 171.0 171.0 171.0 218.0 164.0 167.0 173.0 166.0 172.0 05:41:30 INFO - PROCESS | 1950 | 05:41:30 INFO - PROCESS | 1950 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.20 Median:79.00 stddev:13.46 (17.0%) stddev-sans-first:13.65 05:41:30 INFO - PROCESS | 1950 | Values: 69.0 72.0 70.0 70.0 70.0 70.0 86.0 98.0 99.0 98.0 05:41:30 INFO - PROCESS | 1950 | -------- Summary: end -------- 05:41:30 INFO - PROCESS | 1950 | 05:41:31 INFO - TEST-INFO | 1950: exit 0 05:41:31 INFO - TEST-OK | kraken | took 60733ms 05:41:31 INFO - SUITE-END | took 1173s 05:41:31 INFO - Completed test suite (00:19:34) 05:41:31 INFO - javascript benchmark 05:41:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3497.510348551202, 3503.7197024238058, 3504.351694936437, 3566.928661426773, 3578.499010138581, 5861.618994765337, 5897.20387747655, 5950.970245148768, 5951.970240148798, 5985.491233245174, 5799.971000145004, 5911.763529458816, 5938.64368137911, 6001.099835024748, 6037.969810150954, 4261.3607958806115, 4828.927566086511, 5304.787808487666, 5317.973410132944, 5354.732263386834, 612.1712026759918, 5246.816361427351, 5340.359156901177, 5346.572274218053, 5360.061989151883, 5281.815136470224, 5319.388270348921, 5333.35999680037, 5344.465553444663, 5378.946210537883, 5350.30446042016, 5369.087255166631, 5377.4084850666395, 5398.78404863805, 5413.702246376448, 4868.85581888256, 5029.622778291643, 5085.847424577269, 5328.0, 5364.570834333283, 5764.827055188351, 5840.883182336372, 5846.327672317653, 5907.4683278505145, 5965.55258355625, 6131.0708424985305, 6233.0, 6244.843878903019, 6259.561830671855, 6268.937310626927, 6237.594556353868, 6295.5907865988565, 6304.991201407753, 6310.305866354697, 6313.779017734358, 6014.8195554133445, 6038.0, 6046.576739628228, 6053.303870054956, 6071.119687645355, 6299.212598425197, 6345.365463453618, 6480.125183100221, 6482.222133344029, 6499.837504062436, 6167.5682702210415, 6242.282137554149, 6246.312905580383, 6248.062790581449, 6250.1874756281395, 6219.689015549205, 6333.556651034475, 6335.986242201226, 6340.619562826245, 6347.650879201596, 6138.32709113608, 6168.383161683885, 6196.132541444202, 6256.812295631138, 6296.087067375205, 5797.7824656821695, 6207.751689932352, 6231.906521402183, 6234.37656234382, 6239.344868788712, 4241.703080784378, 5025.723585202776, 5042.268871013683, 5052.0, 5081.796728130834, 3301.7853839500835, 4470.955290447119, 4525.389072475239, 4574.839880604163, 4765.761711914391, 4958.553730164303, 5926.970365148233, 5967.671778052249, 6026.578139530278, 6101.60339577926, 6312.24253089623, 6351.809445716636, 6394.968025159845, 6402.679866006681, 6407.135036769976, 5468.687514996373, 5753.539716822728, 6027.336992930774, 6033.698315084228, 6046.939530604725, 5508.834734957958, 5517.172424136332, 5525.944740552623, 5535.640183388067, 5595.328560572676, 6481.449076828522, 6500.252470965805, 6507.739690412425, 6522.412982831568, 6544.345565443417, 5085.64400491962, 5617.017022021048, 6176.876462470816, 6310.337414571588, 6312.053192021233, 5282.577393808409, 5516.034693928626, 5530.170474428868, 5615.652243461622, 6494.512911531559, 5168.198929165986, 6189.442950134509, 6240.970239910545, 6241.157443745036, 6250.968745156245, 6149.907751383822, 6223.3465486123305, 6243.282022567463, 6263.498920086473, 6325.7153428094925, 5206.5314121729225, 6144.600600960924, 6147.631142131487, 6163.7842675506145, 6177.949748542848, 5186.0, 6097.725602347817, 6193.256809182927, 6210.5652604317265, 6215.129881816459, 6064.878702426015, 6087.969560152171, 6124.777645458507, 6164.815055548341, 6194.969025154845, 5184.533391994739, 6139.546016675353, 6140.539459540463, 6211.254649442096, 6218.5647004709235, 4703.811847526061, 5900.468957793819, 6167.876642467215, 6169.6915154241215, 6197.659128747962], "value": 5695.3706654043235, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.0971280832926, 519.3585921387087, 539.4551502981986, 541.5045233611246, 578.9681567513785, 37529.0, 38312.85061448161, 38349.0412739681, 38356.424653630216, 38508.42237366435, 39071.21857562847, 39637.8018109909, 39938.40092398609, 39944.0, 40230.19539609213, 9246.630134794601, 9415.340926135174, 9499.572519236617, 9523.95238023809, 9562.61749530018, 9372.203362714177, 9588.185004274645, 9612.471314077722, 9631.181349585293, 9641.614335426575, 9568.712938611818, 9651.758706032335, 9653.0, 9653.131218190361, 9654.5172741363, 9512.47681377524, 9599.136077752966, 9607.615695372177, 9613.086756758097, 9624.989376115513, 9441.527923603793, 9532.856057273102, 9570.85643715338, 9593.04069593042, 9605.951970240174, 31953.840230798698, 38965.415518767244, 39083.609163908564, 39229.41155882664, 39319.80340098281, 2681.7184195659565, 2773.195773225762, 2955.9852200739074, 3167.255694911694, 3382.0530251529613, 26472.205833825017, 26511.73488265112, 26991.86504067467, 27039.188824335302, 27462.90148394062, 2623.3048242215827, 2643.7488438598302, 2705.2154875086203, 2705.256054584985, 2751.119641714652, 317470.0, 317869.0, 318999.4050029735, 320078.3996080028, 321531.0, 35011.0, 35179.824100879334, 35183.4722479163, 35240.295194096485, 35264.29471410558, 67551.66224168945, 68792.0, 69307.65346173236, 69472.0, 69527.0, 17160.656786864194, 17247.74128388075, 17431.651366972845, 17461.214245358988, 17504.38734644307, 13112.803307950388, 13169.275689836959, 13171.07802453819, 13196.934015329862, 13204.735905281841, 16831.57921051959, 20032.69950950737, 20073.895935723674, 20371.77769333845, 20377.796222037887, 7951.562664053533, 7999.480033797785, 8027.638756255982, 8029.397795165386, 8099.473534220257, 918.9586468608928, 941.1623654947102, 964.4599024546136, 979.740368802268, 1095.3209010413595, 24000.63999040016, 28361.0, 29870.40259194804, 30408.0, 30923.536146957813, 966.2077096780713, 1168.3982748884332, 1171.3381939204323, 1238.962831115068, 1241.2242348532168, 15319.617009574627, 15505.844941550666, 15550.455734049254, 15862.68274634524, 15930.840691593168, 575066.0, 577914.331285031, 584560.0, 586091.0, 586967.0, 56813.715931420906, 60308.0, 60960.0, 61825.690871545354, 61828.0, 1425.2446203512154, 1455.8762505187178, 1465.48707952218, 1466.5893549805858, 1469.0304399096544, 29383.853080734458, 29505.0, 29972.550411744276, 30053.248668783457, 30075.69924300729, 53470.46529534655, 54022.459775401745, 54081.72959135179, 54133.0, 54183.0, 484854.0, 486417.0, 486471.0, 487353.0, 487561.0, 27358.863205683843, 41511.58488415077, 43603.12793744044, 43715.56284437115, 44305.0, 36079.27841443104, 36308.0, 42822.57177428186, 43777.68666939997, 43930.780346098065, 28956.13131605971, 32534.349313013136, 33397.332053359285, 33489.66041358318, 35372.292554148255, 32163.51754723728, 34601.78893738707, 36615.0, 37280.88157355283, 37743.05642358963, 37290.6270937298, 37913.620863792115, 37994.43008354932, 38823.0, 39361.80319098386, 26483.867580661972, 32507.837460812545, 33281.83359083189, 33558.16104597405, 33591.83204083964, 19500.902495487433, 23478.53042939166, 24868.378290542158, 25025.249242522754, 25588.0], "value": 19226.106203540352, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.9860956256968, 373.79441307280973, 389.27206124547104, 393.3784620299926, 403.8647053237165, 17302.91348543257, 19785.307514237003, 20002.599948001032, 20847.166113355466, 20877.895610521966, 20217.595648087066, 20964.475888102806, 21042.789572104313, 21139.2601258956, 21276.787232127674, 5625.353084395286, 5850.88298234035, 6104.053871649901, 6127.35662755411, 6137.110119032737, 6053.697315134225, 6096.69516524172, 6102.48128909041, 6121.479674227696, 6138.815835524942, 5510.448955104497, 5895.263092113486, 5939.0, 5951.821445356624, 6072.574919755619, 5631.436856314356, 5741.167530708045, 5774.480296773289, 6086.969565152191, 6093.756249750005, 5761.279840019997, 5856.590038697293, 5879.529637629023, 5891.734871930774, 5905.084711869667, 13727.656808579766, 15613.0, 19975.40073797774, 24940.0, 25012.749872501223, 1960.7941166177488, 1999.5800881814837, 2004.5790384019374, 2036.694495825623, 2246.213825161197, 9690.75773105671, 10105.0, 10137.391756494633, 10235.130013948823, 10271.383716977005, 1704.21606061212, 1774.547490389955, 1781.3320004998125, 1781.4388467632655, 1805.1696219738935, 120203.0, 121203.3939830304, 123650.9999999991, 123775.3811230947, 124668.0, 9665.758356041155, 11571.247868888495, 11583.420828958519, 11722.765544689059, 11730.002949749367, 11835.171537992257, 13104.148230364995, 14869.851301487062, 14914.4779932704, 14938.178400187877, 8429.28351090152, 8609.65561377538, 8617.526036068077, 8724.040355560883, 8731.301495880314, 8535.317174626016, 8550.78623034429, 8554.315654747605, 8556.101609331055, 8562.957185214034, 4508.940399006231, 4585.72485650862, 4590.288505281686, 4655.348251244828, 4674.2988551716835, 1818.7726534183228, 1868.4394681595468, 1884.5194475408957, 1890.914908829106, 1893.2427029188434, 837.5644664774405, 867.6746220167437, 870.4472659861034, 921.8939821920565, 1005.4118340770585, 19234.615307693766, 20207.898960505103, 20348.372130229545, 20375.18499260013, 20429.387118386472, 937.723371605378, 942.8397172480695, 945.3146468810057, 976.8046390721884, 1100.4332768624165, 8677.305815534743, 10283.53724082418, 10447.477626118663, 10447.791044179226, 10499.527521261562, 505597.0, 513016.0, 565677.0, 567092.0, 570383.0, 97670.51164744131, 127805.72194277938, 133937.0, 134949.32525337508, 138714.0, 1003.40798928631, 1006.2754816532085, 1019.6482213636284, 1026.2662196529518, 1026.461107918349, 17389.0, 17728.645427091127, 17850.196741146938, 17912.552186195448, 18122.546936326697, 41054.38418423666, 48014.759926200146, 49658.75170624124, 49722.00555988932, 51145.46563603098, 62791.0, 63138.36861631325, 64236.71526569536, 64767.0, 65251.0, 8033.076196237507, 8079.394045446497, 8156.673733050849, 8187.222213889671, 8555.700550480704, 10158.39049657023, 12466.563670271496, 12501.874981250436, 12871.935640321739, 12921.0, 8656.870146947927, 9830.705078847646, 10251.436171010666, 11391.316521008766, 11672.358020308624, 9665.9033409665, 10802.243842930919, 11128.833067503832, 11286.830697539379, 11391.54433822671, 11596.130290228435, 11599.5360185595, 11631.59289424866, 11722.70693232676, 11751.588694395656, 7994.5602991836, 8199.385046121446, 8256.83486330282, 8260.132686067876, 8579.013413457533, 8324.875126873223, 10835.133189344991, 10841.403722795007, 11142.832857507306, 11196.133309514027], "value": 9318.415045952124, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1519.962000949976, 1605.181357507671, 1634.6730653869229, 1856.8143185681424, 1966.9704954425695, 8095.554744489051, 8632.56837158142, 8688.913110868889, 8902.465852048881, 8914.64341426343, 8682.609282582283, 8726.432781869175, 8794.076621954699, 8801.251893589053, 8822.029576746569, 2079.1787244038596, 2414.8188885833547, 2447.510497900418, 2451.3871532116973, 2462.8645424501733, 2404.5070760494145, 2419.2863105383867, 2442.792362649177, 2446.6330050492393, 2457.5453541094867, 2423.7333893271725, 2438.426969662128, 2452.4849781545895, 2460.065175233418, 2460.950780984388, 2048.283100914683, 2370.6444033394955, 2398.412388964715, 2400.6519054737055, 2428.6842710447704, 2050.759290629163, 2128.70198172256, 2187.687387567463, 2396.604560247557, 2396.940076498084, 8741.825163496758, 8864.689735859214, 8935.0, 8952.373333866632, 9004.279657627374, 4907.773056735816, 5006.974965125188, 5485.561155107582, 5499.285092937933, 5510.834674959758, 3253.5933008373954, 3406.744494162923, 3564.9483402396354, 3596.514470546469, 3652.1600031992525, 981.8330883749708, 995.4723996281982, 1009.333839665817, 1010.6614284214797, 1011.4942528735633, 11740.295582265006, 11850.762984740257, 11858.762824743542, 11864.347460889647, 11864.822027669505, 3697.0, 4119.546849846514, 4695.22528782747, 4873.561379475864, 4888.462269150391, 9955.651552195639, 10020.749481262881, 10037.146842518468, 10063.245256605784, 10063.396196228103, 5434.157705555645, 5434.456554344534, 5451.291332126877, 5507.421720719347, 5518.061929471999, 4021.4571032910185, 4457.264551349056, 4858.58702010333, 4928.186849169919, 4983.9252411213865, 4028.858989935397, 4040.797960102042, 4087.3255912774653, 4157.0, 4157.771322577288, 1333.3599872061466, 1348.7504662439387, 1350.9121907076008, 1354.8441929178073, 1356.4641966423208, 828.0146625515617, 872.4242000279785, 894.7628878347243, 902.9322800789967, 944.6693657220055, 5676.347220069745, 5785.566082543826, 5885.205497257815, 5964.1650168976375, 5985.132155837379, 974.6198982396871, 990.5443495991781, 997.6757553795046, 1012.9949350253202, 1027.4605831938143, 3902.1220225449047, 3958.5447673517915, 4001.4397984282223, 4027.275090483741, 4039.2931237033404, 10608.0, 10882.347059176474, 10957.726056848642, 10982.0, 11062.949019843101, 10828.891711082946, 10911.399873006902, 10962.780744385227, 11007.504662290215, 11045.613403530839, 6484.773032943825, 8232.0, 8274.089850116363, 8359.456635318806, 8409.579521023925, 4859.586935110414, 5918.585699001201, 6022.939770602413, 6029.78895738647, 6068.787592434243, 9559.904400955902, 9778.706638800848, 9940.10539051489, 9962.501874906371, 9991.450470224207, 10206.438645874548, 10224.53989570486, 10278.691639250834, 10310.278280520592, 10363.170946324126, 6758.357955994173, 7392.080128349379, 7461.0, 7545.0, 7605.733799316998, 8298.668053278043, 8349.99800024001, 8359.37304702162, 8368.581570921551, 8411.3270938326, 7289.489735718449, 7523.849523009619, 7532.661030253542, 7572.242775722384, 7576.962115189389, 7310.59791711461, 7355.926440735739, 7363.558186508827, 7433.331000209791, 7481.925180748341, 7064.646767661699, 7085.043519125058, 7125.821854453473, 7161.391281740902, 7169.247229040876, 4786.6410019248, 4823.807047718052, 4942.135126352946, 4946.975265123795, 5104.795808167781, 4857.489963553776, 4983.177775666974, 5039.924401133912, 5044.621653376085, 5183.481651834786], "value": 4614.302154272121, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.60333884314025, 319.29595242490313, 325.2632786738039, 327.6379600541401, 331.35551352619166, 5422.728863556826, 6177.0, 6230.657313847742, 6257.71840267188, 6333.8099857004245, 5408.997341966946, 5852.385499522553, 6193.7832175873855, 6218.284897236807, 6239.313675495703, 3953.8023098845088, 4435.290353543425, 4485.215087359716, 4515.977420112895, 4651.627869770427, 4197.7271477354025, 4450.154470650582, 4617.591634551461, 4618.284165954283, 4673.299005149221, 4080.204360149772, 4568.703034302776, 4598.172328980782, 4602.447706275235, 4606.769661516928, 4380.2115619188535, 4622.352870598119, 4623.32961720552, 4623.815047398083, 4633.351330813689, 3969.5236571611304, 3974.3243648579523, 3978.3435733104, 3980.144268982154, 4017.6183262590007, 5176.922346164811, 5918.0, 5955.702214889281, 5969.761209551612, 5976.910346344808, 4463.888402789924, 5100.107481190814, 5267.288915996368, 5399.865003374908, 5418.783248670048, 5676.716164191774, 6519.739210431577, 6519.8696026079215, 6537.738490460376, 6539.0, 4026.3828511467486, 4176.582341765829, 4412.514623391425, 4416.8516185791605, 4435.578620031092, 6105.321036714895, 6817.659117044128, 6904.516683832134, 6918.93081069193, 6919.308069193041, 3790.981045094757, 4458.910821783546, 4470.597646211857, 4494.640428765691, 4547.727136371828, 3959.48526691534, 4556.384888040138, 4565.748883811356, 4573.748443835621, 4575.199340115467, 4652.325412815123, 5134.614903882149, 5270.97216042868, 5341.050516561427, 5404.270423492857, 5543.8891222176135, 5763.596548241583, 6579.638119903451, 6588.637624930579, 6621.9337806621315, 1419.6592817723672, 1531.9080855148727, 1533.854283843033, 1547.3578464936925, 1591.5861875912115, 921.3504479342048, 974.5565767575736, 984.2027957354566, 991.980160396788, 993.5777294649745, 5376.4085950545405, 6043.425874541911, 6100.0, 6117.816465506042, 6149.938500615026, 5345.625806193607, 5603.915941260884, 5738.483536481736, 6770.052192693027, 6785.287544807724, 4566.474855391606, 5267.0, 5738.397468265855, 6113.510919126459, 6119.938800611937, 3446.9310613788084, 3525.453554699026, 3564.8752293669595, 3578.1911199708106, 3600.8739694111105, 5163.967206558703, 5735.770569177186, 6422.197225346832, 6800.557963732342, 7098.29017098286, 6408.487321014308, 6753.29090445506, 6845.520813543104, 6847.246802851682, 6960.895586566206, 6705.6647167642395, 6777.999999999902, 6927.483458412924, 7073.408483091263, 7074.823129421806, 5358.303420555303, 6682.665866706548, 6735.865282694221, 6741.59550426976, 6773.762918297836, 6295.401936815995, 6639.468842492685, 6659.267480577036, 6677.599344039373, 6692.0, 6253.374662533893, 6670.9332906670315, 6734.225564060196, 7037.824054398681, 7241.710331586888, 4350.847720329773, 4384.693071484965, 4404.603585677304, 4404.7357158570585, 4406.383106365176, 5729.427057294237, 6556.803295901131, 6675.566088204251, 6790.351762812408, 6809.693563789542, 5363.383210930793, 5692.31692196939, 6257.9687101564205, 6593.505487088391, 6620.966895165686, 4915.188993816123, 5293.894122117613, 5719.542436605145, 6589.308122647053, 6593.538392344058, 5250.422453530032, 5993.940060599512, 6224.190855188796, 6479.1901012373455, 6487.4430136765895, 6257.530685198537, 6614.272430032596, 6615.239247486601, 6625.039369291329, 6629.723828130732, 5670.546356291569, 6214.782482613087, 6459.838504037249, 6504.1869766279215, 6510.0], "value": 4692.431417943119, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [651.5178767711894, 651.8305240637433, 661.2957200581382, 720.171802427209, 720.5568575326173, 13242.404091815868, 14814.70370592589, 14945.0, 15135.243237838105, 16489.422870199545, 15530.767038494432, 15553.844461555382, 15611.531654050368, 15620.687586248298, 15670.608234794106, 906.4380084347714, 915.276931224332, 917.4862077236735, 918.1736437206534, 920.1028996728203, 876.4872549558515, 894.1773568317163, 897.6544030548221, 898.8516894712365, 899.1053901368148, 943.2831048403194, 960.3037797596721, 965.227817745804, 966.3090890013639, 969.9127078562927, 925.3476299209075, 930.4370855632346, 948.0472125513849, 955.6129767444196, 956.3353469338813, 696.08464868698, 707.6249587718518, 711.4486273138308, 714.8141483214401, 719.5215181904027, 14459.78310325346, 14773.778393324108, 14780.260986950609, 14784.0, 14796.260186990607, 12269.815952760715, 12312.26126432408, 12384.938075309567, 12399.566015189424, 12649.620511384674, 9831.0, 9869.950650246774, 10038.196944244515, 10184.0, 10605.0, 3180.840957952093, 3380.154961259685, 3462.87879924204, 3503.8598456061727, 3584.551931008624, 77343.8398424024, 77378.0, 77469.61265193638, 77817.44365112722, 78675.60662196767, 9434.90565094354, 9481.261106563072, 9521.095495927875, 9732.95133524342, 9771.21830253578, 20758.377248682562, 20788.27241046556, 20872.26947056876, 20908.268210612554, 21243.893780530998, 7442.627868606656, 7468.477206595595, 7547.0943486781935, 7581.052368453943, 7799.80500487481, 4034.3141665916864, 4041.474608300961, 4049.190161967618, 4073.429719839225, 4085.3667681509414, 3597.4963505109304, 3665.578458477309, 3680.9263814723554, 3691.280200360904, 3702.9629703702617, 572.6964708704394, 665.5607299182516, 688.8243497908002, 695.2109355881112, 700.8423104801481, 502.45734606624757, 555.9888802224013, 561.2535328013732, 568.8122919436659, 578.0317967404665, 6882.7591034313555, 7499.887501687479, 8059.032916050111, 8077.111517733045, 8105.797355066171, 521.426430925986, 589.0516268807216, 598.6617561077977, 616.9383061693794, 630.9400606942334, 3896.162325100117, 3896.4544963705102, 3961.9405708914387, 3975.2248311578965, 4036.0442978040232, 99069.50465247627, 105749.0, 106963.0, 107966.44234903638, 108491.0, 87832.0, 89147.55426222828, 89906.65140022905, 90496.0, 90625.54687226521, 73848.26151738521, 77531.61234193793, 77993.44013119706, 78677.4264514718, 80206.13843279053, 7584.12782529994, 7637.541747495168, 7647.732329368668, 7668.6165691716305, 7676.692932282646, 41930.790346048074, 43696.56303437052, 43795.34306985334, 46500.13697606601, 47876.5212347886, 64685.67657161684, 68211.65894170497, 68290.0, 68357.0, 68440.0, 9848.310618256939, 9851.605935762489, 9898.604055837686, 10082.546285417027, 10460.48778550934, 10921.563137474412, 11261.099112071179, 11332.376719280519, 11409.714757131138, 11427.371494567878, 10064.696625284316, 10223.368126031877, 10270.178385729274, 10280.897191028293, 10413.739656508345, 10457.529411176363, 10490.632827850988, 10763.623273185402, 10781.299215550964, 10788.244822862323, 10143.442110683687, 10337.483125843828, 10351.223658225512, 10384.532696028546, 10451.895481045092, 7570.205128461433, 8574.31405487547, 8672.349573781865, 8738.257248133725, 8762.0, 8843.734687959372, 8980.281577473917, 9267.320814862725, 9333.240326454059, 9578.664746733832], "value": 6821.6348543754675, "unit": "score"}], "name": "dromaeo_css", "value": 7295.096911480936, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 154.0, 165.0, 115.0, 117.0, 123.0, 111.0, 118.0, 115.0, 117.0], "value": 126.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 160.0, 176.0, 157.0, 153.0, 173.0, 174.0, 125.0, 174.0, 160.0], "value": 164.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [233.0, 271.0, 264.0, 217.0, 197.0, 204.0, 201.0, 199.0, 199.0, 202.0], "value": 218.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 77.0, 76.0, 143.0, 55.0, 59.0, 93.0, 83.0, 78.0, 103.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 164.0, 111.0, 117.0, 124.0, 125.0, 79.0, 83.0, 120.0], "value": 108.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 88.0, 87.0, 89.0, 87.0, 92.0, 87.0, 90.0, 103.0], "value": 90.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 97.0, 148.0, 125.0, 96.0, 95.0, 96.0, 94.0], "value": 103.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 90.0, 90.0, 90.0, 88.0, 90.0, 117.0, 117.0, 90.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 60.0, 62.0, 61.0, 62.0, 81.0, 64.0, 60.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 67.0, 99.0, 76.0, 73.0, 65.0, 66.0, 67.0, 68.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 86.0, 86.0, 83.0, 84.0, 83.0, 86.0, 85.0, 87.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 146.0, 153.0, 153.0, 165.0, 157.0, 157.0, 140.0, 173.0, 143.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 171.0, 171.0, 171.0, 218.0, 164.0, 167.0, 173.0, 166.0, 172.0], "value": 174.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 70.0, 70.0, 70.0, 70.0, 86.0, 98.0, 99.0, 98.0], "value": 80.2, "unit": "score"}], "name": "kraken", "value": 1617.6999999999998, "alertThreshold": 2.0}]} 05:41:31 INFO - Return code: 0 05:41:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:41:31 ERROR - # TBPL SUCCESS # 05:41:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:41:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:41:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:41:31 INFO - 2016-04-19 05:41:31.698 screenresolution[1955:21197] starting screenresolution argv=screenresolution get 05:41:31 INFO - 2016-04-19 05:41:31.700 screenresolution[1955:21197] Display 0: 1600x1200x32@60 05:41:31 INFO - 2016-04-19 05:41:31.706 screenresolution[1956:21200] starting screenresolution argv=screenresolution list 05:41:31 INFO - Available Modes on Display 0 05:41:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:41:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:41:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:41:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:41:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:41:31 INFO - Intel Iris: 05:41:31 INFO - Chipset Model: Intel Iris 05:41:31 INFO - Type: GPU 05:41:31 INFO - Bus: Built-In 05:41:31 INFO - VRAM (Dynamic, Max): 1536 MB 05:41:31 INFO - Vendor: Intel (0x8086) 05:41:31 INFO - Device ID: 0x0a2e 05:41:31 INFO - Revision ID: 0x0009 05:41:31 INFO - Displays: 05:41:31 INFO - Display: 05:41:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:41:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:41:31 INFO - Display Serial Number: 1600x1200 60 05:41:31 INFO - Main Display: Yes 05:41:31 INFO - Mirror: Off 05:41:31 INFO - Online: Yes 05:41:31 INFO - Rotation: Supported 05:41:31 INFO - Return code: 0 05:41:31 INFO - Running post-action listener: _resource_record_post_action 05:41:31 INFO - Running post-run listener: _resource_record_post_run 05:41:33 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 696320; Write bytes: 695328256; Read time: 9; Write time: 1859 05:41:33 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 379529728; Write bytes: 374448640; Read time: 28098; Write time: 703 05:41:33 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 696320; Write bytes: 297806848; Read time: 9; Write time: 1108 05:41:33 INFO - Running post-run listener: _upload_blobber_files 05:41:33 INFO - Blob upload gear active. 05:41:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:41:33 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 05:41:33 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'] 05:41:33 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 05:41:33 INFO - (blobuploader) - INFO - Open directory for files ... 05:41:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:41:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:41:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:41:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:41:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:41:34 INFO - (blobuploader) - INFO - Done attempting. 05:41:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:41:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:41:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:41:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:41:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:41:35 INFO - (blobuploader) - INFO - Done attempting. 05:41:35 INFO - (blobuploader) - INFO - Iteration through files over. 05:41:35 INFO - Return code: 0 05:41:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:41:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:41:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5796065b20890d23876e51f08b7509abb9bdcdcc5d18a8e52e4c1db7a33d4de884b1e4257ec42270d63d5b8047b8340e58d6111b62a113967d6566517c92d06b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c02c4df7227b7cdb8bf9f7f517c72c63d7b41b08c679e9f7423f3123f57922c644068030dea9506cb1bd2711229115f5353c5e5143739a8db5367466e97b3092"} 05:41:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:41:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:41:35 INFO - Contents: 05:41:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5796065b20890d23876e51f08b7509abb9bdcdcc5d18a8e52e4c1db7a33d4de884b1e4257ec42270d63d5b8047b8340e58d6111b62a113967d6566517c92d06b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c02c4df7227b7cdb8bf9f7f517c72c63d7b41b08c679e9f7423f3123f57922c644068030dea9506cb1bd2711229115f5353c5e5143739a8db5367466e97b3092"} 05:41:35 INFO - Running post-run listener: copy_logs_to_upload_dir 05:41:35 INFO - Copying logs to upload dir... 05:41:35 INFO - mkdir: /builds/slave/test/build/upload/logs 05:41:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.694689 ========= master_lag: 0.32 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-04-19 05:41:35.357621) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-19 05:41:35.361518) ========= 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.Vf19TKiwPM/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.ezaRFj1Lkf/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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/5796065b20890d23876e51f08b7509abb9bdcdcc5d18a8e52e4c1db7a33d4de884b1e4257ec42270d63d5b8047b8340e58d6111b62a113967d6566517c92d06b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c02c4df7227b7cdb8bf9f7f517c72c63d7b41b08c679e9f7423f3123f57922c644068030dea9506cb1bd2711229115f5353c5e5143739a8db5367466e97b3092"} build_url:https://queue.taskcluster.net/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011035 build_url: 'https://queue.taskcluster.net/v1/task/NY2IWMjEQtufIOExGdGdlw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5796065b20890d23876e51f08b7509abb9bdcdcc5d18a8e52e4c1db7a33d4de884b1e4257ec42270d63d5b8047b8340e58d6111b62a113967d6566517c92d06b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c02c4df7227b7cdb8bf9f7f517c72c63d7b41b08c679e9f7423f3123f57922c644068030dea9506cb1bd2711229115f5353c5e5143739a8db5367466e97b3092"}' ========= master_lag: 1.22 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-19 05:41:36.593505) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 05:41:36.593863) ========= 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.Vf19TKiwPM/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.ezaRFj1Lkf/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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.005293 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 05:41:36.636465) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 05:41:36.636777) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 05:41:36.642218) ========= ========= Total master_lag: 13.92 =========