builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0281 starttime: 1463735736.8 results: success (0) buildid: 20160520012301 builduid: ac6e7119d04d424a8507eefeee4b9448 revision: efef1c387fcc672f000e26aea5876f32191f6d90 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 02:15:36.803145) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 02:15:36.803590) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 02:15:36.803867) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 02:15:36.823556) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 02:15:36.823866) ========= 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.Fx9Hp0JB4n/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.S8kgpjbyeI/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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-05-20 02:15:36-- 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% 7.26M=0.002s 2016-05-20 02:15:36 (7.26 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123091 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 02:15:36.965277) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 02:15:36.965624) ========= 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.Fx9Hp0JB4n/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.S8kgpjbyeI/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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.020307 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 02:15:37.013934) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 02:15:37.014283) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev efef1c387fcc672f000e26aea5876f32191f6d90 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev efef1c387fcc672f000e26aea5876f32191f6d90 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Fx9Hp0JB4n/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.S8kgpjbyeI/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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-05-20 02:15:37,049 truncating revision to first 12 chars 2016-05-20 02:15:37,049 Setting DEBUG logging. 2016-05-20 02:15:37,049 attempt 1/10 2016-05-20 02:15:37,049 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/efef1c387fcc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 02:15:38,001 unpacking tar archive at: mozilla-aurora-efef1c387fcc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.209913 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 02:15:38.242650) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 02:15:38.242973) ========= script_repo_revision: efef1c387fcc672f000e26aea5876f32191f6d90 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 02:15:38.243463) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 02:15:38.243767) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 02:15:38.258383) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-05-20 02:15:38.258657) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Fx9Hp0JB4n/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.S8kgpjbyeI/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 02:15:38 INFO - MultiFileLogger online at 20160520 02:15:38 in /builds/slave/test 02:15:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:15:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:15:38 INFO - {'append_to_log': False, 02:15:38 INFO - 'base_work_dir': '/builds/slave/test', 02:15:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:15:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:15:38 INFO - 'branch': 'Mozilla-Aurora', 02:15:38 INFO - 'buildbot_json_path': 'buildprops.json', 02:15:38 INFO - 'config_files': ('talos/mac_config.py',), 02:15:38 INFO - 'default_actions': ('clobber', 02:15:38 INFO - 'read-buildbot-config', 02:15:38 INFO - 'download-and-extract', 02:15:38 INFO - 'populate-webroot', 02:15:38 INFO - 'create-virtualenv', 02:15:38 INFO - 'install', 02:15:38 INFO - 'run-tests'), 02:15:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:15:38 INFO - 'download_minidump_stackwalk': True, 02:15:38 INFO - 'download_symbols': 'ondemand', 02:15:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:15:38 INFO - 'tooltool.py': '/tools/tooltool.py', 02:15:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:15:38 INFO - '/tools/misc-python/virtualenv.py')}, 02:15:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:15:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:15:38 INFO - 'installer_path': 'installer.exe', 02:15:38 INFO - 'log_level': 'info', 02:15:38 INFO - 'log_name': 'talos', 02:15:38 INFO - 'log_to_console': True, 02:15:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:15:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:15:38 INFO - 'opt_config_files': (), 02:15:38 INFO - 'pip_index': False, 02:15:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:15:38 INFO - 'cmd': ('bash', 02:15:38 INFO - '-c', 02:15:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:15:38 INFO - 'enabled': True, 02:15:38 INFO - 'halt_on_failure': False, 02:15:38 INFO - 'name': 'check_screen_resolution'},), 02:15:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:15:38 INFO - 'cmd': ('bash', 02:15:38 INFO - '-c', 02:15:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:15:38 INFO - 'enabled': True, 02:15:38 INFO - 'halt_on_failure': False, 02:15:38 INFO - 'name': 'check_screen_resolution'},), 02:15:38 INFO - 'run_cmd_checks_enabled': True, 02:15:38 INFO - 'sps_profile': False, 02:15:38 INFO - 'sps_profile_interval': 0, 02:15:38 INFO - 'suite': 'dromaeojs-e10s', 02:15:38 INFO - 'system_bits': '32', 02:15:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:15:38 INFO - 'title': 't-yosemite-r7-0281', 02:15:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:15:38 INFO - 'use_talos_json': True, 02:15:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:15:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:15:38 INFO - 'work_dir': 'build'} 02:15:38 INFO - ##### 02:15:38 INFO - ##### Running clobber step. 02:15:38 INFO - ##### 02:15:38 INFO - Running pre-action listener: _resource_record_pre_action 02:15:38 INFO - Running main action method: clobber 02:15:38 INFO - rmtree: /builds/slave/test/build 02:15:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:15:40 INFO - Running post-action listener: _resource_record_post_action 02:15:40 INFO - ##### 02:15:40 INFO - ##### Running read-buildbot-config step. 02:15:40 INFO - ##### 02:15:40 INFO - Running pre-action listener: _resource_record_pre_action 02:15:40 INFO - Running main action method: read_buildbot_config 02:15:40 INFO - Using buildbot properties: 02:15:40 INFO - { 02:15:40 INFO - "project": "", 02:15:40 INFO - "product": "firefox", 02:15:40 INFO - "script_repo_revision": "production", 02:15:40 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:15:40 INFO - "repository": "", 02:15:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:15:40 INFO - "buildid": "20160520012301", 02:15:40 INFO - "pgo_build": "False", 02:15:40 INFO - "basedir": "/builds/slave/test", 02:15:40 INFO - "buildnumber": 295, 02:15:40 INFO - "slavename": "t-yosemite-r7-0281", 02:15:40 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:15:40 INFO - "platform": "yosemite_r7", 02:15:40 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:15:40 INFO - "branch": "mozilla-aurora", 02:15:40 INFO - "repo_path": "releases/mozilla-aurora", 02:15:40 INFO - "revision": "efef1c387fcc672f000e26aea5876f32191f6d90", 02:15:40 INFO - "stage_platform": "macosx64", 02:15:40 INFO - "builduid": "ac6e7119d04d424a8507eefeee4b9448", 02:15:40 INFO - "slavebuilddir": "test" 02:15:40 INFO - } 02:15:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:15:40 INFO - Running post-action listener: _resource_record_post_action 02:15:40 INFO - ##### 02:15:40 INFO - ##### Running download-and-extract step. 02:15:40 INFO - ##### 02:15:40 INFO - Running pre-action listener: _resource_record_pre_action 02:15:40 INFO - Running main action method: download_and_extract 02:15:40 INFO - mkdir: /builds/slave/test/build/tests 02:15:40 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')]} 02:15:40 INFO - https://queue.taskcluster.net/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:15:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:15:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:15:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:15:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:15:41 INFO - Downloaded 1199 bytes. 02:15:41 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:15:41 INFO - Using the following test package requirements: 02:15:41 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:15:41 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:15:41 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:15:41 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:15:41 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:15:41 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:15:41 INFO - u'jsshell-mac.zip'], 02:15:41 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:15:41 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:15:41 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:15:41 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:15:41 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:15:41 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:15:41 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:15:41 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:15:41 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:15:41 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:15:41 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:15:41 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:15:41 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')]} 02:15:41 INFO - https://queue.taskcluster.net/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:15:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:15:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:15:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:15:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:15:42 INFO - Downloaded 18102385 bytes. 02:15:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:15:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:15:45 INFO - Return code: 0 02:15:45 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:15:45 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')]} 02:15:45 INFO - https://queue.taskcluster.net/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:15:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:15:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:15:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:15:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:15:46 INFO - Downloaded 18102385 bytes. 02:15:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:15:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:15:50 INFO - Return code: 0 02:15:50 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')]} 02:15:50 INFO - https://queue.taskcluster.net/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:15:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:15:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:15:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:15:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:15:51 INFO - Downloaded 10840145 bytes. 02:15:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:15:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:15:51 INFO - Return code: 0 02:15: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')]} 02:15:51 INFO - https://queue.taskcluster.net/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:15:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:15:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:15:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:15:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:15:55 INFO - Downloaded 114098442 bytes. 02:15:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:15:55 INFO - mkdir: /builds/slave/test/properties 02:15:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:15:55 INFO - Writing to file /builds/slave/test/properties/build_url 02:15:55 INFO - Contents: 02:15:55 INFO - build_url:https://queue.taskcluster.net/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:15:56 INFO - Running post-action listener: _resource_record_post_action 02:15:56 INFO - Running post-action listener: set_extra_try_arguments 02:15:56 INFO - ##### 02:15:56 INFO - ##### Running populate-webroot step. 02:15:56 INFO - ##### 02:15:56 INFO - Running pre-action listener: _resource_record_pre_action 02:15:56 INFO - Running main action method: populate_webroot 02:15:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:15:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:15:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:15:56 INFO - '--tppagecycles', 02:15:56 INFO - '7'], 02:15:56 INFO - 'tests': ['tsvgm']}}, 02:15:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:15:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:15:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:15:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:15:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:15:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:15:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:56 INFO - 'talos_options': ['--disable-e10s'], 02:15:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:15:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:15:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:56 INFO - 'talos_options': ['--disable-e10s'], 02:15:56 INFO - 'tests': ['damp', 'tps']}, 02:15:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:56 INFO - 'tests': ['damp', 'tps']}, 02:15:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:15:56 INFO - 'tests': ['dromaeo_dom']}, 02:15:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:15:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:15:56 INFO - 'tests': ['a11yr', 02:15:56 INFO - 'ts_paint', 02:15:56 INFO - 'tpaint', 02:15:56 INFO - 'sessionrestore', 02:15:56 INFO - 'sessionrestore_no_auto_restore', 02:15:56 INFO - 'tabpaint']}, 02:15:56 INFO - 'other-e10s': {'tests': ['a11yr', 02:15:56 INFO - 'ts_paint', 02:15:56 INFO - 'tpaint', 02:15:56 INFO - 'sessionrestore', 02:15:56 INFO - 'sessionrestore_no_auto_restore', 02:15:56 INFO - 'tabpaint']}, 02:15:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:15:56 INFO - 'ts_paint', 02:15:56 INFO - 'tpaint', 02:15:56 INFO - 'sessionrestore', 02:15:56 INFO - 'sessionrestore_no_auto_restore', 02:15:56 INFO - 'tabpaint']}, 02:15:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:15:56 INFO - 'ts_paint', 02:15:56 INFO - 'tpaint', 02:15:56 INFO - 'sessionrestore', 02:15:56 INFO - 'sessionrestore_no_auto_restore', 02:15:56 INFO - 'tabpaint']}, 02:15:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:15:56 INFO - 'tests': ['a11yr', 02:15:56 INFO - 'ts_paint', 02:15:56 INFO - 'tpaint', 02:15:56 INFO - 'sessionrestore', 02:15:56 INFO - 'sessionrestore_no_auto_restore', 02:15:56 INFO - 'tabpaint']}, 02:15:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:15:56 INFO - 'tests': ['a11yr', 02:15:56 INFO - 'ts_paint', 02:15:56 INFO - 'tpaint', 02:15:56 INFO - 'sessionrestore', 02:15:56 INFO - 'sessionrestore_no_auto_restore', 02:15:56 INFO - 'tabpaint']}, 02:15:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:15:56 INFO - 'tests': ['tsvgx', 02:15:56 INFO - 'tsvgr_opacity', 02:15:56 INFO - 'tart', 02:15:56 INFO - 'tscrollx', 02:15:56 INFO - 'cart']}, 02:15:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:15:56 INFO - 'tsvgr_opacity', 02:15:56 INFO - 'tart', 02:15:56 INFO - 'tscrollx', 02:15:56 INFO - 'cart']}, 02:15:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:56 INFO - 'talos_options': ['--disable-e10s'], 02:15:56 INFO - 'tests': ['tp5o']}, 02:15:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:56 INFO - 'tests': ['tp5o']}, 02:15:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:56 INFO - 'talos_options': ['--disable-e10s', 02:15:56 INFO - '--xperf_path', 02:15:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:15:56 INFO - 'tests': ['tp5n']}, 02:15:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:56 INFO - 'talos_options': ['--xperf_path', 02:15:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:15:56 INFO - 'tests': ['tp5n']}}, 02:15:56 INFO - 'talos.zip': {'path': '', 02:15:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:15:56 INFO - Running post-action listener: _resource_record_post_action 02:15:56 INFO - ##### 02:15:56 INFO - ##### Running create-virtualenv step. 02:15:56 INFO - ##### 02:15:56 INFO - Running pre-action listener: _resource_record_pre_action 02:15:56 INFO - Running main action method: create_virtualenv 02:15:56 INFO - Creating virtualenv /builds/slave/test/build/venv 02:15:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:15:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:15:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:15:56 INFO - Using real prefix '/tools/python27' 02:15:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:15:57 INFO - Installing distribute.............................................................................................................................................................................................done. 02:16:00 INFO - Installing pip.................done. 02:16:00 INFO - Return code: 0 02:16:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:16:00 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')]} 02:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:00 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')]} 02:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:00 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 0x10cc1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc2f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffea34941c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cabee00>, '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 0x10c927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cab9b20>, '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.Fx9Hp0JB4n/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.S8kgpjbyeI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 02:16:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:16:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:16:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fx9Hp0JB4n/Render', 02:16:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:00 INFO - 'HOME': '/Users/cltbld', 02:16:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:00 INFO - 'LOGNAME': 'cltbld', 02:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:00 INFO - 'MOZ_NO_REMOTE': '1', 02:16:00 INFO - 'NO_EM_RESTART': '1', 02:16:00 INFO - 'PAGER': '/bin/cat', 02:16:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:00 INFO - 'PWD': '/builds/slave/test', 02:16:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:00 INFO - 'SHELL': '/bin/bash', 02:16:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S8kgpjbyeI/Listeners', 02:16:00 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 02:16:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:00 INFO - 'USER': 'cltbld', 02:16:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:00 INFO - 'XPC_FLAGS': '0x0', 02:16:00 INFO - 'XPC_SERVICE_NAME': '0', 02:16:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:16:00 INFO - Cleaning up... 02:16:00 INFO - Return code: 0 02:16:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:16:00 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')]} 02:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:00 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')]} 02:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:00 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 0x10cc1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc2f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffea34941c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cabee00>, '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 0x10c927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cab9b20>, '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.Fx9Hp0JB4n/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.S8kgpjbyeI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 02:16:00 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 02:16:00 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 02:16:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fx9Hp0JB4n/Render', 02:16:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:00 INFO - 'HOME': '/Users/cltbld', 02:16:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:00 INFO - 'LOGNAME': 'cltbld', 02:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:00 INFO - 'MOZ_NO_REMOTE': '1', 02:16:00 INFO - 'NO_EM_RESTART': '1', 02:16:00 INFO - 'PAGER': '/bin/cat', 02:16:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:00 INFO - 'PWD': '/builds/slave/test', 02:16:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:00 INFO - 'SHELL': '/bin/bash', 02:16:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S8kgpjbyeI/Listeners', 02:16:00 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 02:16:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:00 INFO - 'USER': 'cltbld', 02:16:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:00 INFO - 'XPC_FLAGS': '0x0', 02:16:00 INFO - 'XPC_SERVICE_NAME': '0', 02:16:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:00 INFO - Downloading/unpacking psutil>=0.7.1 02:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:16:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:16:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:16:03 INFO - Installing collected packages: psutil 02:16:03 INFO - Running setup.py install for psutil 02:16:03 INFO - building 'psutil._psutil_osx' extension 02:16:03 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 02:16:04 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 02:16:04 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 02:16:04 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 02:16:04 INFO - building 'psutil._psutil_posix' extension 02:16:04 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 02:16:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:16:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:16:04 INFO - ^ 02:16:04 INFO - 1 warning generated. 02:16:04 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 02:16:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:16:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:16:04 INFO - Successfully installed psutil 02:16:04 INFO - Cleaning up... 02:16:04 INFO - Return code: 0 02:16:04 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:16:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:04 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 0x10cc1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc2f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffea34941c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cabee00>, '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 0x10c927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cab9b20>, '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.Fx9Hp0JB4n/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.S8kgpjbyeI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 02:16:04 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 02:16:04 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 02:16:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fx9Hp0JB4n/Render', 02:16:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:04 INFO - 'HOME': '/Users/cltbld', 02:16:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:04 INFO - 'LOGNAME': 'cltbld', 02:16:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:04 INFO - 'MOZ_NO_REMOTE': '1', 02:16:04 INFO - 'NO_EM_RESTART': '1', 02:16:04 INFO - 'PAGER': '/bin/cat', 02:16:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:04 INFO - 'PWD': '/builds/slave/test', 02:16:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:04 INFO - 'SHELL': '/bin/bash', 02:16:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S8kgpjbyeI/Listeners', 02:16:04 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 02:16:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:04 INFO - 'USER': 'cltbld', 02:16:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:04 INFO - 'XPC_FLAGS': '0x0', 02:16:04 INFO - 'XPC_SERVICE_NAME': '0', 02:16:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:04 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:16:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:16:07 INFO - Installing collected packages: mozsystemmonitor 02:16:07 INFO - Running setup.py install for mozsystemmonitor 02:16:07 INFO - Successfully installed mozsystemmonitor 02:16:07 INFO - Cleaning up... 02:16:07 INFO - Return code: 0 02:16:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:07 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 0x10cc1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc2f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffea34941c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cabee00>, '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 0x10c927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cab9b20>, '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.Fx9Hp0JB4n/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.S8kgpjbyeI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 02:16:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:16:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fx9Hp0JB4n/Render', 02:16:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:07 INFO - 'HOME': '/Users/cltbld', 02:16:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:07 INFO - 'LOGNAME': 'cltbld', 02:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:07 INFO - 'MOZ_NO_REMOTE': '1', 02:16:07 INFO - 'NO_EM_RESTART': '1', 02:16:07 INFO - 'PAGER': '/bin/cat', 02:16:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:07 INFO - 'PWD': '/builds/slave/test', 02:16:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:07 INFO - 'SHELL': '/bin/bash', 02:16:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S8kgpjbyeI/Listeners', 02:16:07 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 02:16:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:07 INFO - 'USER': 'cltbld', 02:16:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:07 INFO - 'XPC_FLAGS': '0x0', 02:16:07 INFO - 'XPC_SERVICE_NAME': '0', 02:16:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:07 INFO - Downloading/unpacking blobuploader==1.2.4 02:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:10 INFO - Downloading blobuploader-1.2.4.tar.gz 02:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:16:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:16: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 02:16: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 02:16: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 02:16: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 02:16: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 02:16: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 02:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:16:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:11 INFO - Downloading docopt-0.6.1.tar.gz 02:16:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:16:11 INFO - Installing collected packages: blobuploader, requests, docopt 02:16:11 INFO - Running setup.py install for blobuploader 02:16:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:16:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:16:11 INFO - Running setup.py install for requests 02:16:12 INFO - Running setup.py install for docopt 02:16:12 INFO - Successfully installed blobuploader requests docopt 02:16:12 INFO - Cleaning up... 02:16:12 INFO - Return code: 0 02:16:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:12 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 0x10cc1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc2f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffea34941c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cabee00>, '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 0x10c927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cab9b20>, '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.Fx9Hp0JB4n/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.S8kgpjbyeI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 02:16:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:16:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:16:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fx9Hp0JB4n/Render', 02:16:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:12 INFO - 'HOME': '/Users/cltbld', 02:16:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:12 INFO - 'LOGNAME': 'cltbld', 02:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:12 INFO - 'MOZ_NO_REMOTE': '1', 02:16:12 INFO - 'NO_EM_RESTART': '1', 02:16:12 INFO - 'PAGER': '/bin/cat', 02:16:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:12 INFO - 'PWD': '/builds/slave/test', 02:16:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:12 INFO - 'SHELL': '/bin/bash', 02:16:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S8kgpjbyeI/Listeners', 02:16:12 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 02:16:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:12 INFO - 'USER': 'cltbld', 02:16:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:12 INFO - 'XPC_FLAGS': '0x0', 02:16:12 INFO - 'XPC_SERVICE_NAME': '0', 02:16:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:16:12 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-2QXocc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:16:12 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-je9PEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:16:12 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-jmj86X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:16:12 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-f590BY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:16:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-QofI7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:16:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-SG_81A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:16:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-_d6M8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:16:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-nGJATA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:16:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-fGYmoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:16:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-m5DQhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:16:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-ccsWtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:16:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-bV1v8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:16:13 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-lxnn0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:16:14 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-BgEWHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:14 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-9dEvfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:16:14 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-JgLAQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:16:14 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-zLt_vX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:16:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:16:14 INFO - Running setup.py install for manifestparser 02:16:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:16:14 INFO - Running setup.py install for mozcrash 02:16:14 INFO - Running setup.py install for mozdebug 02:16:14 INFO - Running setup.py install for mozdevice 02:16:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:16:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:16:15 INFO - Running setup.py install for mozfile 02:16:15 INFO - Running setup.py install for mozhttpd 02:16:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:16:15 INFO - Running setup.py install for mozinfo 02:16:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:16:15 INFO - Running setup.py install for mozInstall 02:16:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:16:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:16:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:16:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:16:15 INFO - Running setup.py install for mozleak 02:16:15 INFO - Running setup.py install for mozlog 02:16:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:16:15 INFO - Running setup.py install for moznetwork 02:16:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:16:16 INFO - Running setup.py install for mozprocess 02:16:16 INFO - Running setup.py install for mozprofile 02:16:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:16:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:16:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:16:16 INFO - Running setup.py install for mozrunner 02:16:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:16:16 INFO - Running setup.py install for mozscreenshot 02:16:16 INFO - Running setup.py install for moztest 02:16:16 INFO - Running setup.py install for mozversion 02:16:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:16:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:16:16 INFO - Cleaning up... 02:16:16 INFO - Return code: 0 02:16:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-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 0x10cc1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc2f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffea34941c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cabee00>, '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 0x10c927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cab9b20>, '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.Fx9Hp0JB4n/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.S8kgpjbyeI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 02:16:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:16:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:16:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fx9Hp0JB4n/Render', 02:16:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:16 INFO - 'HOME': '/Users/cltbld', 02:16:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:16 INFO - 'LOGNAME': 'cltbld', 02:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:16 INFO - 'MOZ_NO_REMOTE': '1', 02:16:16 INFO - 'NO_EM_RESTART': '1', 02:16:16 INFO - 'PAGER': '/bin/cat', 02:16:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:16 INFO - 'PWD': '/builds/slave/test', 02:16:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:16 INFO - 'SHELL': '/bin/bash', 02:16:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S8kgpjbyeI/Listeners', 02:16:16 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 02:16:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:16 INFO - 'USER': 'cltbld', 02:16:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:16 INFO - 'XPC_FLAGS': '0x0', 02:16:16 INFO - 'XPC_SERVICE_NAME': '0', 02:16:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:16:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Vzsqig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:16:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-qRxMsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:16:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-2RHyog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:16:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-gUw3w5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:16:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-yk1jXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:16:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-JdpmEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:16:17 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-CHPGtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:16:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-O__PXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:16:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-PsAcdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:16:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-Jo6tzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:16:18 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)) 02:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:16:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-BxM2DK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:16:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-VOx4Ad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:16:18 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)) 02:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:16:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-fdJXVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:16:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-J06E44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:16:18 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)) 02:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-iaVzE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:16:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-NR6DZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:16:18 INFO - Running setup.py (path:/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/pip-pOq2YN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:16:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:21 INFO - Downloading blessings-1.6.tar.gz 02:16:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:16:21 INFO - Installing collected packages: blessings 02:16:21 INFO - Running setup.py install for blessings 02:16:21 INFO - Successfully installed blessings 02:16:21 INFO - Cleaning up... 02:16:21 INFO - Return code: 0 02:16:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:16:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:16:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:16:22 INFO - Reading from file tmpfile_stdout 02:16:22 INFO - Current package versions: 02:16:22 INFO - blessings == 1.6 02:16:22 INFO - blobuploader == 1.2.4 02:16:22 INFO - docopt == 0.6.1 02:16:22 INFO - manifestparser == 1.1 02:16:22 INFO - mozInstall == 1.12 02:16:22 INFO - mozcrash == 0.17 02:16:22 INFO - mozdebug == 0.1 02:16:22 INFO - mozdevice == 0.48 02:16:22 INFO - mozfile == 1.2 02:16:22 INFO - mozhttpd == 0.7 02:16:22 INFO - mozinfo == 0.9 02:16:22 INFO - mozleak == 0.1 02:16:22 INFO - mozlog == 3.1 02:16:22 INFO - moznetwork == 0.27 02:16:22 INFO - mozprocess == 0.22 02:16:22 INFO - mozprofile == 0.28 02:16:22 INFO - mozrunner == 6.11 02:16:22 INFO - mozscreenshot == 0.1 02:16:22 INFO - mozsystemmonitor == 0.0 02:16:22 INFO - moztest == 0.7 02:16:22 INFO - mozversion == 1.4 02:16:22 INFO - psutil == 3.1.1 02:16:22 INFO - requests == 1.2.3 02:16:22 INFO - wsgiref == 0.1.2 02:16:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:22 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 0x10cc1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc2f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffea34941c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cabee00>, '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 0x10c927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cab9b20>, '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.Fx9Hp0JB4n/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.S8kgpjbyeI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 02:16:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:16:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fx9Hp0JB4n/Render', 02:16:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:22 INFO - 'HOME': '/Users/cltbld', 02:16:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:22 INFO - 'LOGNAME': 'cltbld', 02:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:22 INFO - 'MOZ_NO_REMOTE': '1', 02:16:22 INFO - 'NO_EM_RESTART': '1', 02:16:22 INFO - 'PAGER': '/bin/cat', 02:16:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:22 INFO - 'PWD': '/builds/slave/test', 02:16:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:22 INFO - 'SHELL': '/bin/bash', 02:16:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S8kgpjbyeI/Listeners', 02:16:22 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 02:16:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:22 INFO - 'USER': 'cltbld', 02:16:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:22 INFO - 'XPC_FLAGS': '0x0', 02:16:22 INFO - 'XPC_SERVICE_NAME': '0', 02:16:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:16:22 INFO - Cleaning up... 02:16:22 INFO - Return code: 0 02:16:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc2f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffea34941c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cabee00>, '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 0x10c927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cab9b20>, '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.Fx9Hp0JB4n/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.S8kgpjbyeI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 02:16:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:16:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fx9Hp0JB4n/Render', 02:16:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:22 INFO - 'HOME': '/Users/cltbld', 02:16:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:22 INFO - 'LOGNAME': 'cltbld', 02:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:22 INFO - 'MOZ_NO_REMOTE': '1', 02:16:22 INFO - 'NO_EM_RESTART': '1', 02:16:22 INFO - 'PAGER': '/bin/cat', 02:16:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:22 INFO - 'PWD': '/builds/slave/test', 02:16:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:22 INFO - 'SHELL': '/bin/bash', 02:16:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S8kgpjbyeI/Listeners', 02:16:22 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 02:16:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:22 INFO - 'USER': 'cltbld', 02:16:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:22 INFO - 'XPC_FLAGS': '0x0', 02:16:22 INFO - 'XPC_SERVICE_NAME': '0', 02:16:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:22 INFO - Downloading/unpacking jsonschema 02:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:16:25 INFO - Downloading/unpacking functools32 (from jsonschema) 02:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:25 INFO - Downloading functools32-3.2.3-2.tar.gz 02:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:16:25 INFO - warning: no files found matching '*.txt' 02:16:25 INFO - no previously-included directories found matching 'build' 02:16:25 INFO - no previously-included directories found matching 'dist' 02:16:25 INFO - no previously-included directories found matching '.git*' 02:16:25 INFO - Installing collected packages: jsonschema, functools32 02:16:25 INFO - Running setup.py install for functools32 02:16:25 INFO - warning: no files found matching '*.txt' 02:16:25 INFO - no previously-included directories found matching 'build' 02:16:25 INFO - no previously-included directories found matching 'dist' 02:16:25 INFO - no previously-included directories found matching '.git*' 02:16:25 INFO - Successfully installed jsonschema functools32 02:16:25 INFO - Cleaning up... 02:16:25 INFO - Return code: 0 02:16:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:16:25 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')]} 02:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:25 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')]} 02:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:25 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 0x10cc1fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc2f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffea34941c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cabee00>, '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 0x10c927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cab9b20>, '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.Fx9Hp0JB4n/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.S8kgpjbyeI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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 02:16:25 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 02:16:25 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 02:16:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fx9Hp0JB4n/Render', 02:16:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:25 INFO - 'HOME': '/Users/cltbld', 02:16:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:25 INFO - 'LOGNAME': 'cltbld', 02:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:25 INFO - 'MOZ_NO_REMOTE': '1', 02:16:25 INFO - 'NO_EM_RESTART': '1', 02:16:25 INFO - 'PAGER': '/bin/cat', 02:16:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:25 INFO - 'PWD': '/builds/slave/test', 02:16:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:25 INFO - 'SHELL': '/bin/bash', 02:16:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S8kgpjbyeI/Listeners', 02:16:25 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 02:16:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:25 INFO - 'USER': 'cltbld', 02:16:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:25 INFO - 'XPC_FLAGS': '0x0', 02:16:25 INFO - 'XPC_SERVICE_NAME': '0', 02:16:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:26 INFO - Downloading/unpacking flake8 02:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:16:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:16:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:16:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:16:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:16:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:16:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:16:29 INFO - no previously-included directories found matching 'docs/_build' 02:16:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:16:29 INFO - Running setup.py install for pep8 02:16:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:16:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:16:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:16:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:16:30 INFO - no previously-included directories found matching 'docs/_build' 02:16:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:16:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:16:30 INFO - Cleaning up... 02:16:30 INFO - Return code: 0 02:16:30 INFO - Running post-action listener: _resource_record_post_action 02:16:30 INFO - Running post-action listener: _start_resource_monitoring 02:16:30 INFO - Starting resource monitoring. 02:16:30 INFO - ##### 02:16:30 INFO - ##### Running install step. 02:16:30 INFO - ##### 02:16:30 INFO - Running pre-action listener: _resource_record_pre_action 02:16:30 INFO - Running main action method: install 02:16:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:16:30 INFO - Reading from file tmpfile_stdout 02:16:30 INFO - Detecting whether we're running mozinstall >=1.0... 02:16:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:16:30 INFO - Reading from file tmpfile_stdout 02:16:30 INFO - Output received: 02:16:30 INFO - Usage: mozinstall [options] installer 02:16:30 INFO - Options: 02:16:30 INFO - -h, --help show this help message and exit 02:16:30 INFO - -d DEST, --destination=DEST 02:16:30 INFO - Directory to install application into. [default: 02:16:30 INFO - "/builds/slave/test"] 02:16:30 INFO - --app=APP Application being installed. [default: firefox] 02:16:30 INFO - mkdir: /builds/slave/test/build/application 02:16:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:17:01 INFO - Reading from file tmpfile_stdout 02:17:01 INFO - Output received: 02:17:01 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:17:01 INFO - Running post-action listener: _resource_record_post_action 02:17:01 INFO - ##### 02:17:01 INFO - ##### Running run-tests step. 02:17:01 INFO - ##### 02:17:01 INFO - Running pre-action listener: _resource_record_pre_action 02:17:01 INFO - Running main action method: run_tests 02:17:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:17:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:17:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:17:01 INFO - 2016-05-20 02:17:01.935 screenresolution[1987:11990] starting screenresolution argv=screenresolution get 02:17:01 INFO - 2016-05-20 02:17:01.938 screenresolution[1987:11990] Display 0: 1600x1200x32@60 02:17:01 INFO - 2016-05-20 02:17:01.943 screenresolution[1988:11992] starting screenresolution argv=screenresolution list 02:17:01 INFO - Available Modes on Display 0 02:17:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:17:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:17:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:17:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:17:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:17:02 INFO - Intel Iris: 02:17:02 INFO - Chipset Model: Intel Iris 02:17:02 INFO - Type: GPU 02:17:02 INFO - Bus: Built-In 02:17:02 INFO - VRAM (Dynamic, Max): 1536 MB 02:17:02 INFO - Vendor: Intel (0x8086) 02:17:02 INFO - Device ID: 0x0a2e 02:17:02 INFO - Revision ID: 0x0009 02:17:02 INFO - Displays: 02:17:02 INFO - Display: 02:17:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:17:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:17:02 INFO - Display Serial Number: 1600x1200 60 02:17:02 INFO - Main Display: Yes 02:17:02 INFO - Mirror: Off 02:17:02 INFO - Online: Yes 02:17:02 INFO - Rotation: Supported 02:17:02 INFO - Return code: 0 02:17:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:17:02 INFO - Python 2.7.3 02:17:02 INFO - Return code: 0 02:17:02 INFO - grabbing minidump binary from tooltool 02:17:02 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')]} 02:17:02 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 0x10cabee00>, '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 0x10c927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cab9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:17:02 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 02:17:02 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 02:17:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:17:02 INFO - Return code: 0 02:17:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:17:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:17:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:17:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:17:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:17:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:17:03 INFO - Return code: 0 02:17:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0281', '--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 02:17:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0281 --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 02:17:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fx9Hp0JB4n/Render', 02:17:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:03 INFO - 'HOME': '/Users/cltbld', 02:17:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:03 INFO - 'LOGNAME': 'cltbld', 02:17:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:17:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:03 INFO - 'MOZ_NO_REMOTE': '1', 02:17:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:17:03 INFO - 'NO_EM_RESTART': '1', 02:17:03 INFO - 'PAGER': '/bin/cat', 02:17:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:03 INFO - 'PWD': '/builds/slave/test', 02:17:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:17:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:03 INFO - 'SHELL': '/bin/bash', 02:17:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S8kgpjbyeI/Listeners', 02:17:03 INFO - 'TMPDIR': '/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/', 02:17:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:03 INFO - 'USER': 'cltbld', 02:17:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:03 INFO - 'XPC_FLAGS': '0x0', 02:17:03 INFO - 'XPC_SERVICE_NAME': '0', 02:17:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0281', '--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 02:17:03 INFO - mozversion application_buildid: 20160520012301 02:17:03 INFO - mozversion application_changeset: efef1c387fcc672f000e26aea5876f32191f6d90 02:17:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:17:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:17:03 INFO - mozversion application_name: Firefox 02:17:03 INFO - mozversion application_remotingname: firefox-dev 02:17:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:17:03 INFO - mozversion application_vendor: Mozilla 02:17:03 INFO - mozversion application_version: 48.0a2 02:17:03 INFO - mozversion platform_buildid: 20160520012301 02:17:03 INFO - mozversion platform_changeset: efef1c387fcc672f000e26aea5876f32191f6d90 02:17:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:17:03 INFO - mozversion platform_version: 48.0a2 02:17:03 INFO - using testdate: 1463735823 02:17:03 INFO - actual date: 1463735823 02:17:03 INFO - starting webserver on 'localhost:49409' 02:17:03 INFO - SUITE-START | Running 2 tests 02:17:03 INFO - TEST-START | dromaeo_css 02:17:03 INFO - operating with platform_type : mac_ 02:17:03 INFO - Initialising browser for dromaeo_css test... 02:17:03 INFO - TEST-INFO | started process 2006 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmp3zCJIM/profile http://localhost:49409/getInfo.html) 02:17:06 INFO - PROCESS | 2006 | __metrics Screen width/height:1600/1200 02:17:06 INFO - PROCESS | 2006 | colorDepth:24 02:17:06 INFO - PROCESS | 2006 | Browser inner width/height: 1024/689 02:17:06 INFO - PROCESS | 2006 | __metrics 02:17:06 ERROR - PROCESS | 2006 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 02:17:06 INFO - TEST-INFO | 2006: exit 0 02:17:06 INFO - Browser initialized. 02:17:06 INFO - Running cycle 1/1 for dromaeo_css test... 02:17:06 INFO - TEST-INFO | started process 2009 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmp3zCJIM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:17:18 INFO - PROCESS | 2009 | RSS: Main: 220483584 02:17:18 INFO - PROCESS | 2009 | 02:18:08 INFO - PROCESS | 2009 | 1463735888532 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:20:19 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49409/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49409/tests/dromaeo/cssquery-ext.html) 02:20:20 INFO - PROCESS | 2009 | RSS: Main: 232615936 02:20:20 INFO - PROCESS | 2009 | 02:23:22 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49409/tests/dromaeo/cssquery-ext.html (next: http://localhost:49409/tests/dromaeo/cssquery-jquery.html) 02:23:23 INFO - PROCESS | 2009 | RSS: Main: 243425280 02:23:23 INFO - PROCESS | 2009 | 02:26:25 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49409/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49409/tests/dromaeo/cssquery-mootools.html) 02:26:25 INFO - PROCESS | 2009 | RSS: Main: 245231616 02:26:25 INFO - PROCESS | 2009 | 02:29:27 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49409/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49409/tests/dromaeo/cssquery-prototype.html) 02:29:28 INFO - PROCESS | 2009 | RSS: Main: 247205888 02:29:28 INFO - PROCESS | 2009 | 02:32:30 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49409/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49409/tests/dromaeo/cssquery-yui.html) 02:32:31 INFO - PROCESS | 2009 | RSS: Main: 250052608 02:32:31 INFO - PROCESS | 2009 | 02:35:33 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49409/tests/dromaeo/cssquery-yui.html (next: http://localhost:49409/tests/dromaeo/cssquery-dojo.html) 02:35:33 INFO - PROCESS | 2009 | RSS: Main: 250560512 02:35:33 INFO - PROCESS | 2009 | 02:35:33 INFO - PROCESS | 2009 | __start_tp_report 02:35:33 INFO - PROCESS | 2009 | _x_x_mozilla_page_load 02:35:33 INFO - PROCESS | 2009 | _x_x_mozilla_page_load_details 02:35:33 INFO - PROCESS | 2009 | |i|pagename|runs| 02:35:33 INFO - PROCESS | 2009 | |0;dojo.html;3107.9533806992895;3424.520567120602;3427.7600567960253;3462.913427164322;3503.5620547431563;5684.971575142118;5690.118031705082;5734.225879506265;5735.225744524488;5784.68184249866;4707.929381059278;5725.456081672245;5750.683712395817;5769.682667453289;5775.999999999989;5192.766325515351;5252.868678283055;5253.238280449333;5290.523852853243;5300.337457817773;5098.515641014098;5283.973580132094;5325.520703136717;5383.838484845461;5388.191771234307;5277.261183434322;5323.307969963899;5323.760430780624;5331.360236771573;5332.200169974496;5274.868128296804;5315.01672190646;5323.840284791462;5330.333708286465;5364.758585863646;4815.5906747926465;5105.8767071244265;5115.565176959963;5257.158854583248;5259.973700131475;4601.872541227421;4859;5490.505854473076;5641;5647.181158731961;5449.591280653927;6274.623522588659;6278.121063051177;6310.116583678288;6332.430081292659;6114.694265286718;6195.2255968004;6205;6247.2815626202655;6277.52918531112;5931.436513531207;5938.643681379088;5939.851503712399;5942.554308426886;5951.672658003851;6323.320165968531;6371.108044873721;6386.393292637192;6390.904136437957;6400.935990640034;5138.332016837787;6125.41859352575;6190.133381326618;6241.594296370812;6246.312905580383;6264.624122552661;6285.5914365567005;6295.401936815995;6314.147590075281;6321.841953951188;6120.264288666148;6182.69086545671;6269.153664255267;6287.717052732638;6317.652529110851;6221.9377806221355;6247;6266.436020758154;6286.339934306832;6305.085762564402;4963.726995015309;5010.624203184776;5016.648834581544;5016.724080175552;5027.84916452507;4398.604125628709;4428.2914733642465;4446.666500012512;4541.3869127668;4543.54564543543;4644.814207431665;5724.713764311768;5781.942180578225;5883.205767221456;5927.9407205928255;6223.626582405071;6226.906596401058;6246.500279977591;6289;6301.8109456716375;5513.641613295123;5760.884782304414;5844.298684157929;5947.821565353046;5950.821475355746;5464.371597266286;5464.453554644504;5474.288342515448;5486.094794358966;5527.170924361378;5277.050130976308;6441.259255185713;6449.484041276686;6450.903236451457;6462.1276127723095;6186.721597528032;6229.657368844757;6266.717997690023;6274.6548939808745;6302.65335406557;6441.967790161019;6455.419012288917;6473.417392434703;6477.287498375269;6482.222133344029;6212.285587157354;6219.906701399392;6220.440160385406;6265.248170219603;6265.874682506415;5432;6134.846628834315;6185.876282474415;6200.720967556381;6298.370162983665;5027.824026159214;5520.585956053393;5787.131930210502;6159.445649891352;6181.721822517908;6084.513238940785;6127.448529632355;6148.200733904564;6155.815325540241;6168.722407491584;4949.802007919643;5805.76776928935;5823.039198532364;5963.403659634002;6051.697415129314;6077.696115194134;6151.169592105009;6182.805104188128;6184.907226391518;6197.225346831646;5965.8806823862415;6032.396760324108;6106.816795496142;6111.297200821962;6117.480014198843 02:35:33 INFO - PROCESS | 2009 | |1;ext.html;501.74661795793116;537.3605409562621;564.3312674480738;594.5243804956034;597.7268418269089;31714.365712685732;39929.401058984105;39968.80093597189;40024;40092.79721608349;40913.59086409135;41555.96110097249;41703.95740106499;42418.787906060505;42426.151476970444;9510.619575217;9610.942796292402;9631.855522167172;9654.13112819846;9660.90339096607;9603.711888643353;9737.805243895118;9786.755331116743;9806;9872.802543949118;9829.50852457378;9842.212622990144;9855.852162217572;9902;9915.355501892354;9710.417374957526;9782.315237933348;9800.166985806214;9816.509174541245;9861.359011664219;9702.61189552417;9789.853152202722;9822.65620703272;9826.410415375029;9835.409875407498;40047.7997610013;40604.79697601523;40711;40993.590064099575;41145;2590.7668309852015;2806.5930440086277;3160.8103513789247;3281.770276080675;3304.6695330467;24597.262082137564;26374.340641483923;26720;26900.923963041456;27267.181984540297;2466.7903228225623;2673.385121422084;2687.9865600672065;2691.923230707713;2703.2430919342614;286530;306904;311978;312260;312516;34928.82535587306;36243.825370477854;36795;37147.442788358196;37265.25469490595;35956.92129236127;36364.81817590895;36372.27255454929;36406.27187456289;36534.08664783349;15899.443519476994;16041.518754437386;16049.197540122947;16066.75899861503;16070.35718571244;13311.933440332736;13337.66655833612;13399.263040532669;13449.529266475627;13462.461501540025;7275.308845659654;8191.79520511992;8301.169883011651;8462.069172391031;8510;3420.555327807419;3464.34177506273;3464.5842498899788;3464.5842498900292;3474.4614584739406;980.4999450280266;987.8370068938689;1005.070309963281;1028.4086650176177;1141.5262665993687;25642.974281028924;30720;30908;31122.066338009896;31305;1131.2420678145663;1148.7645032768262;1157.2130950953265;1160.187868492042;1173.4484792147678;15606.531804045897;15706.057636541844;15895.841041589667;16019;16147.919260403623;618662;618922;621640.8917955381;626625;627696;61980.69009654923;62618;62660;62809.685951570864;62996.370036299966;1502.4365862801449;1523.2079318753965;1538.8615024647836;1539.4073281786368;1606.5421354913794;29775.851120744257;30015.849920750257;30346.937857174893;30795.538066928566;30876.691233087382;53892.46107538875;54620.45379546154;54981;55007.89984200374;55536.444635553125;557855;560176;560774;562192;564254;27705.861470693322;27723.861380692968;28250.85874570614;43874.341884871115;44773.776131119135;44506;44663.77668111639;45146;45262.321065184704;45579.31631025471;30169.547456788605;38355.232895342495;38688.80655596704;39916.60083399129;40806;37789.244215114995;38288.23423531569;38326.2334753309;38370;38671.41992870053;33306.83346583252;38017.61982380252;38038.049048774;38265;39619.405708913815;31256.9060082896;32676.673233268313;33574.992750217534;34941.47587786236;35061.47407788834;23223.95492202821;23918.402039949142;24720.876395617906;25137.87431062833;25374.1119060832 02:35:33 INFO - PROCESS | 2009 | |2;jquery.html;363.16653280720743;399.9840006399742;405.3683922213093;406.3234714200856;412.2065024827207;15150.696986060273;20136.8993155034;20232.89883550582;20554.075066622;20912.37262882114;20098.794072355668;20316.390508284738;20466.89766551169;20611.69082463764;20673.379798606027;5829.795957141501;5844.473997340238;5951.702414879239;5989.011813050842;5990.221271234734;4869.172240719086;5865.090910908816;5886.264216972879;5983.311919129291;5991.850203744898;5745.770169193248;5852.917210316084;5930.140129681194;5977.98374276374;6036.88317661234;5895.88208235835;5927.37762534932;5974.581779275452;5981.730822113015;6018.6689732064715;5028.547430731252;5153.304303918961;5253.55527230011;5272.6836389816735;5283.788648454057;21948.122075116975;22781.54436911269;26271.73728262712;26621.334466638295;26755.46489070227;2186.8797216153107;2378.8929498172624;2474.7277799442045;2515.019142534413;2671.2520494261444;13796.931015344959;14392.208428536427;14739.115653060851;15029.549113526611;15147.924260378628;1366.2690460603628;1427.9928600357036;1843.9262429502803;1853.8702290839647;1867.3930972434014;72812;97934.59294331772;103829.48085259525;108806;109303;11476.88523114775;11696.824547631792;11745.29528228309;11835.408229588485;11911.70220744471;13794.586162415144;14251.216183109822;14732.779008314883;14774.926125369304;14941.40234390634;7815.179406162385;8154.776783482525;8657;8695.739127826175;8869;8691.565421729014;8781.429207101646;8851.734447966572;8942.195202431813;8975.50634715097;4042.0097076215925;4422.29243321067;4570.9314360284625;4660.720356778605;4706.529347065266;1722.46603552899;2014.1540552967497;2017.1527958257273;2028.4624574487773;2084.0830034784644;967.7677357434166;998.0269237493386;1001.3140998416109;1053.1364281289423;1058.4284486377423;16939.661206775796;19521.511962201057;20057.796532208115;20059.197632094554;20192.69710954337;843.4348986179275;981.7005813227034;1022.7903279827617;1057.7567159553346;1069.3476979042778;10254.6410875619;10690.358578485315;10720.731981700364;10748.677539673823;10849.403282819363;593989;596947;602154;608079;612604;113859.43070284596;122906;125158.12262816065;125313.37343313225;125749.74250257564;974.28390133252;1010.9696709098739;1012.3419777144768;1016.5679586175846;1035.8860525342207;17781.822181778534;17817.910910445884;18011.639767204844;18128.546786330448;18325.633487330444;36229.09427264234;41372.793136034124;42074.7896260529;49987.50012499828;51329.23006154836;64869;65072.674636628406;65523.01715474176;67276.6636166816;67427;6557.475401967736;7805.843883122419;7881.330086942674;7984.96007519982;8215.096339402542;10122.949385253321;10529.578816847546;12066.215695979734;12226.449809758404;12386;8303.709370172015;8589.312854971462;9261.259099271907;10683.145348372267;10987.560497580007;10350.689479315633;10551.472426378803;10643.361398316125;10661.946690266499;10738.14094872424;10796;10845.295055821347;11077.723056923642;11099.72250693739;11254.268472549258;7866.213378662317;8092.87860682102;8101.959490202511;8175.018997720312;8399;9466.384684995728;10772.569097236023;10805;10813.297135686154;10928.617498387519 02:35:33 INFO - PROCESS | 2009 | |3;mootools.html;1574.071297934219;1592.617771734784;1801.8468430183434;1868.9158987845547;1936.9709454358178;7800.609969501531;8474.618642161102;8579.871301930469;8791.252743516801;8792.780180495483;8535.78660533487;8575.442596231256;8625.956870215656;8650.610722517486;8662.263707584847;2445.4131008557997;2449.8530088194675;2451.10534654851;2453.668754718117;2469.617209332547;2358.469344397514;2358.905643774247;2379.4051487128218;2395.197608801045;2405.6872606561124;1879.2858713688847;2334.171369163948;2422.188566830114;2449.963250551234;2470.765277298654;2321.3384185507134;2340.6137987232173;2344.4373350395954;2353.9646905296436;2471.938201544958;2387.9761202387926;2417.758224177586;2418.1899063813644;2421.769931856471;2439.6462512935705;8734.2139207471;8756.211940925283;8783.78040548985;8795.956020219857;8800.427972181787;4467.352233926095;5399.730013499309;5423.7830486780485;5429.728513574345;5435.510804027656;3478.513008178857;3497.1257185703575;3511.1046683095647;3567.2152126532123;3614.5843228028853;1049.1344640671477;1062.7661914378825;1064.297563608015;1066.2003497376968;1066.9626563070333;11593.76812463746;11610.94194529022;11831.99428048617;11833.053355731434;11842.763144737142;4013.9397909031386;4656.743879086683;4738.64460165489;4762.428508578923;4810.350602668595;9973.700788976343;10032.69901902944;10049.497525123714;10057;10057.899421005843;5316.521513063765;5340.465953404706;5361.571074314045;5410.540104091117;5417.674939503642;3694.8706795262037;4802.159622066124;4822.71063736177;4856.077345304381;4888.388951381077;3989.3816458448987;3993.281209382281;4008.198360327946;4076.796160192038;4156.625903668684;1352.3103217359217;1364.938577764003;1369.9315034248448;1370.1573532277778;1376.078027721429;908.6002159049997;920.6317473010849;921.2123634292717;925.7500474871747;933.7945651956559;5595.832125036255;5732.3407808101765;5756.079027355603;5763.394843541368;5816.156657284755;462.55132521454135;466.9439667239885;470.5200695290784;997.5535473563363;1033.9793204136026;3044.9847750761105;3380.543626610376;3419.785975978492;3765.171662234304;3786.753860999026;10025.348352357074;10658.946705266424;10734.302270352402;10749.89250107489;10772.676819695422;8562.871556926651;8568.186022327869;8768.78078048054;10466.63366782159;10526.631567895087;8158.714444994397;8389.286910612665;8422.747317580483;8426.831463370698;8462.111478294813;4889.486603906681;5223.4776522347465;5410.567154627542;5527.678375082555;5614.522765564972;9569.90430095718;9615.9038409615;9657.372270802376;9701.70894873155;9702.514874256118;9440.527973601154;9830;9892.406455612687;9897.158741507052;10001.099901008944;6356.745730170742;6745.966270168814;6961.965190174017;7030.367266945999;7126.429885609035;8156.877646835421;8179.386546009192;8228.917710823054;8242.29940455068;8259.710910118118;7139.82150446243;7262.636868156465;7279.490435669452;7341.706331746884;7385;7078.964605176941;7136.964315178391;7207;7213.458990575623;7268.527545709512;6814.284500127415;6816.727330906907;6826.488013398915;6842.726290948306;6896.206936202401;4265.637420819266;4618.769061546976;4755.548222918817;4807.375041244616;4810.086083644096;4808.9519104808505;4825.3485779419325;4835.29888166221;4871.3423687803115;5084.5932325414615 02:35:33 INFO - PROCESS | 2009 | |4;prototype.html;295.1205407884504;326.626013214869;328.435091642375;330.61813605285886;333.22691356053963;6792.796216113512;6810.284920083394;6860.348266914648;6906.896396554056;6913.481488888328;5506.476884695948;6777.695003724832;6794.4564434845215;6844.760433384832;6855.622940738257;4700.952990470103;4818.975905120469;4916.631252656047;4983.6760610560195;4991.975040124794;4943.703377797344;4993.525615066563;4995.925061124086;5004.62465315101;5026.723530205837;4907.82822601209;4927.704337739729;4941;4941.160002799515;4958.553730164284;4664.346991421204;4892.77982490787;4961.652684312099;4977.104121258172;4978.9751051244875;4260.1905637929;4263.616274535276;4274.807633656493;4277.871663850089;4278.572142785728;6362.968185159045;6542.214934207878;6621;6635.535512514126;6661;4787.784549695272;5889.204957330749;5929.851753706192;5939.643621382731;5958.910616340759;5809.738561764689;7219.1697954735355;7230.710771569131;7269.781906542759;7285.089363829521;4760;4784.330193772875;4786.688865223749;4797.352357431737;4797.424309082898;6895.241523432469;7659.463837531375;7691.1924247954275;7698.345640620554;7724.420668449889;4770.570648641569;4816.975915120472;4833.782479788418;4845.733484658378;4856.077345304381;4926.950730492649;4928.137575924199;4930.728809915489;4933.728644924492;4944.703317800943;5786.218860453869;5868.589198756046;5897.52819774417;5900.734466948998;5919.520119970008;7292.671829767673;7308.926910730931;7314.975903373532;7325.120985481776;7367.963160184165;1677.3877534699748;1714.202895653527;1736.270766278166;1737.044625455994;1739.034835666192;955.1165172215811;1107.5348353691309;1110.2561283939779;1127.8308253762002;1138.3795831271977;6735.797926062226;6737.562058466184;6759.425448836806;6781.932180678229;6799.388055075067;6132.540059495466;6159.6612186329285;7496.100467943882;7502.924970750332;7504.737334193277;5585.58108141891;6630.668466576652;6704.396604305538;6710.228323742735;6781.355771201728;3281.5405843181975;3935.9606403935595;3949.2496425679606;3979.641832235113;3988.561258261589;7540.208278130827;7843.254890785366;7845.176256492986;7850.175731548105;7851.842963140705;7685.654145563463;7750.5737184454265;7762.844743105219;7765.72819951299;7768.223177682186;7818.179091195457;7853.842923141505;7862.72480463181;7866.9213307866185;7874.960625196952;7322.0847394075745;7490.02629658141;7492.850142997;7499.137599176164;7532.43506736986;7406.8148296290865;7447.218042105501;7455.478116532007;7458.701651933862;7476.140243872024;6486.772962946463;7910.4179164168545;7944.44388892772;7983.5209887406945;8000.359971202173;4028.610129505316;4732.337472753886;4740.198724484674;4764.142454358249;4802.27965805132;7259.745908893164;7493.325600695964;7633.084029916446;7663.540187588763;7676.731314404193;7324.560526368435;7345.3021962913435;7379.151397589346;7380.741674041383;7407.481476296607;7334.266573342623;7346.816329591803;7351.779446616396;7359.558426494427;7366.631668416665;6954.478414119062;7131.465140114616;7141.714331426685;7175.2465991070185;7175.928240717735;7312.049433573601;7340.082489688789;7353.77938661841;7364.153122390993;7402.851942961218;7185.640717964186;7205.387542058984;7212.4230061596;7212.495125341386;7221.988921550882 02:35:33 INFO - PROCESS | 2009 | |5;yui.html;641.2433328672167;670.5976414151509;702.931544053039;710.6269208665447;742.2503271695588;15001.474948376808;15170;15185.240737963099;15238.923805380971;17119.486415407526;13298.734025319487;16075.276612552434;16190.271437785328;16247.025178489299;16267.430639927605;728.1007955175354;895.552223888056;899.1503029636996;925.0610630210342;925.3799954030816;858.682287553606;864.8367945113812;887.152769105506;916.1708653668408;916.2029034739786;943.0663642993455;944.2257348973844;976.6093562574956;981.8527220916884;982.5774916785779;969.4522594734024;975.3075316525241;975.8243516167087;979.0747743382507;979.8628192053118;714.3749219432996;722.2488611843677;723.2116992478224;725.5501589014792;726.9491135620509;15278.847211527853;15438.768418473732;15487.767683484757;15546.067235965878;15563.688726225411;12617.306048167347;12625.179363341353;12836.614901552968;12852.742945141139;12909.354532273348;9983.500824958723;10092.293539452241;10262.692119236435;10314.587416503331;10361.119304859096;3405.7615966882327;3431.7769344992494;3433.1588760753525;3456.9629111266518;3462.8961131166106;85358.57320713419;86436;87348;87912;88192.79518012037;9131.863022054542;9203.95398023019;9312.301577381724;9477.620895164117;9577.760555986157;21536.676949845765;21538.676919846213;21549.46126346854;21569.56860862774;21623.67564486534;7235.167955685059;7290.744823931243;7315.30504602062;7393.667284972189;7448.85102297951;4031.1534577738416;4043.844622894684;4060.7360521566;4062.0457577507486;4080.265552200573;3135.498320268746;3696.131409118855;3751.0997360633796;3755.3240416724707;3758.2483503298904;660.6234446365526;661.8510835062169;671.2984930747381;672.3175976383974;675.8546912413756;529.0529951387051;534.1263999681688;564.9039663257256;599.3167788720862;601.3986013986014;7948.920510794934;8215.055268644182;8284.420090593721;8310.168983101763;8360.99668039827;485.907677541266;524.1770420439949;564.6950646650763;570.74887049698;587.6474115530648;3733.981330093332;3908.6677632401565;3912.4131380292615;3936.4488971544006;4148.751074935513;95079.52460237655;96783.51608241914;99097;99273.50363248137;100546;84533;85248.57375713081;85397.29205415856;85422.57288713516;86358.56820715981;71070.64464677643;72303.2769672307;72726.63636681782;72976;75688.86466703004;5816.447437493432;7574.621268936641;7789.805254868674;7935.047794264725;8025.5987200638565;47046.52953470421;47428;49245.75377123091;49585.76035599139;49830.75084624554;55296.89406211934;60327;63525.72948540911;68747;70045.59908801694;8951.836261285991;9100.772480688036;11024.228304018641;11264.324140551593;11474.483648236017;11859.110566707359;12027.699307517381;12112.75774484523;12174.39128043612;12528.123031387715;9728.464934428674;11505.194636375374;11546.538138474703;11810.527578897092;11817.82273265919;11389.202755807013;11466.598669046543;11581.305121692725;11787.351695656478;12023.4589443477;10658.573657053941;11306.152038597302;11320.6037788677;11468.738438771896;11554.942225289156;8945.46327220369;9180.862287065833;9702.126808587262;9903.653372131941;10121.443320617436;9682.225421966366;9726.902730972883;9802.558884850056;9806.21550275962;9810.018998100133 02:35:33 INFO - PROCESS | 2009 | __end_tp_report 02:35:33 INFO - PROCESS | 2009 | __start_cc_report 02:35:33 INFO - PROCESS | 2009 | _x_x_mozilla_cycle_collect,423 02:35:33 INFO - PROCESS | 2009 | __end_cc_report 02:35:33 INFO - PROCESS | 2009 | __startTimestamp1463736933779__endTimestamp 02:35:33 INFO - PROCESS | 2009 | 02:35:33 INFO - PROCESS | 2009 | ------- Summary: start ------- 02:35:33 INFO - PROCESS | 2009 | Number of tests: 6 02:35:33 INFO - PROCESS | 2009 | 02:35:33 INFO - PROCESS | 2009 | [#0] dojo.html Cycles:165 Average:5736.38 Median:5947.82 stddev:668.97 (11.2%) stddev-sans-first:638.45 02:35:33 INFO - PROCESS | 2009 | Values: 3108.0 3424.5 3427.8 3462.9 3503.6 5685.0 5690.1 5734.2 5735.2 5784.7 4707.9 5725.5 5750.7 5769.7 5776.0 5192.8 5252.9 5253.2 5290.5 5300.3 5098.5 5284.0 5325.5 5383.8 5388.2 5277.3 5323.3 5323.8 5331.4 5332.2 5274.9 5315.0 5323.8 5330.3 5364.8 4815.6 5105.9 5115.6 5257.2 5260.0 4601.9 4859.0 5490.5 5641.0 5647.2 5449.6 6274.6 6278.1 6310.1 6332.4 6114.7 6195.2 6205.0 6247.3 6277.5 5931.4 5938.6 5939.9 5942.6 5951.7 6323.3 6371.1 6386.4 6390.9 6400.9 5138.3 6125.4 6190.1 6241.6 6246.3 6264.6 6285.6 6295.4 6314.1 6321.8 6120.3 6182.7 6269.2 6287.7 6317.7 6221.9 6247.0 6266.4 6286.3 6305.1 4963.7 5010.6 5016.6 5016.7 5027.8 4398.6 4428.3 4446.7 4541.4 4543.5 4644.8 5724.7 5781.9 5883.2 5927.9 6223.6 6226.9 6246.5 6289.0 6301.8 5513.6 5760.9 5844.3 5947.8 5950.8 5464.4 5464.5 5474.3 5486.1 5527.2 5277.1 6441.3 6449.5 6450.9 6462.1 6186.7 6229.7 6266.7 6274.7 6302.7 6442.0 6455.4 6473.4 6477.3 6482.2 6212.3 6219.9 6220.4 6265.2 6265.9 5432.0 6134.8 6185.9 6200.7 6298.4 5027.8 5520.6 5787.1 6159.4 6181.7 6084.5 6127.4 6148.2 6155.8 6168.7 4949.8 5805.8 5823.0 5963.4 6051.7 6077.7 6151.2 6182.8 6184.9 6197.2 5965.9 6032.4 6106.8 6111.3 6117.5 02:35:33 INFO - PROCESS | 2009 | 02:35:33 INFO - PROCESS | 2009 | [#1] ext.html Cycles:180 Average:62612.06 Median:38027.83 stddev:138097.94 (363.1%) stddev-sans-first:138406.60 02:35:33 INFO - PROCESS | 2009 | Values: 501.7 537.4 564.3 594.5 597.7 31714.4 39929.4 39968.8 40024.0 40092.8 40913.6 41556.0 41704.0 42418.8 42426.2 9510.6 9610.9 9631.9 9654.1 9660.9 9603.7 9737.8 9786.8 9806.0 9872.8 9829.5 9842.2 9855.9 9902.0 9915.4 9710.4 9782.3 9800.2 9816.5 9861.4 9702.6 9789.9 9822.7 9826.4 9835.4 40047.8 40604.8 40711.0 40993.6 41145.0 2590.8 2806.6 3160.8 3281.8 3304.7 24597.3 26374.3 26720.0 26900.9 27267.2 2466.8 2673.4 2688.0 2691.9 2703.2 286530.0 306904.0 311978.0 312260.0 312516.0 34928.8 36243.8 36795.0 37147.4 37265.3 35956.9 36364.8 36372.3 36406.3 36534.1 15899.4 16041.5 16049.2 16066.8 16070.4 13311.9 13337.7 13399.3 13449.5 13462.5 7275.3 8191.8 8301.2 8462.1 8510.0 3420.6 3464.3 3464.6 3464.6 3474.5 980.5 987.8 1005.1 1028.4 1141.5 25643.0 30720.0 30908.0 31122.1 31305.0 1131.2 1148.8 1157.2 1160.2 1173.4 15606.5 15706.1 15895.8 16019.0 16147.9 618662.0 618922.0 621640.9 626625.0 627696.0 61980.7 62618.0 62660.0 62809.7 62996.4 1502.4 1523.2 1538.9 1539.4 1606.5 29775.9 30015.8 30346.9 30795.5 30876.7 53892.5 54620.5 54981.0 55007.9 55536.4 557855.0 560176.0 560774.0 562192.0 564254.0 27705.9 27723.9 28250.9 43874.3 44773.8 44506.0 44663.8 45146.0 45262.3 45579.3 30169.5 38355.2 38688.8 39916.6 40806.0 37789.2 38288.2 38326.2 38370.0 38671.4 33306.8 38017.6 38038.0 38265.0 39619.4 31256.9 32676.7 33575.0 34941.5 35061.5 23224.0 23918.4 24720.9 25137.9 25374.1 02:35:33 INFO - PROCESS | 2009 | 02:35:33 INFO - PROCESS | 2009 | [#2] jquery.html Cycles:180 Average:33797.00 Median:22364.83 stddev:99912.51 (446.7%) stddev-sans-first:100161.25 02:35:33 INFO - PROCESS | 2009 | Values: 363.2 400.0 405.4 406.3 412.2 15150.7 20136.9 20232.9 20554.1 20912.4 20098.8 20316.4 20466.9 20611.7 20673.4 5829.8 5844.5 5951.7 5989.0 5990.2 4869.2 5865.1 5886.3 5983.3 5991.9 5745.8 5852.9 5930.1 5978.0 6036.9 5895.9 5927.4 5974.6 5981.7 6018.7 5028.5 5153.3 5253.6 5272.7 5283.8 21948.1 22781.5 26271.7 26621.3 26755.5 2186.9 2378.9 2474.7 2515.0 2671.3 13796.9 14392.2 14739.1 15029.5 15147.9 1366.3 1428.0 1843.9 1853.9 1867.4 72812.0 97934.6 103829.5 108806.0 109303.0 11476.9 11696.8 11745.3 11835.4 11911.7 13794.6 14251.2 14732.8 14774.9 14941.4 7815.2 8154.8 8657.0 8695.7 8869.0 8691.6 8781.4 8851.7 8942.2 8975.5 4042.0 4422.3 4570.9 4660.7 4706.5 1722.5 2014.2 2017.2 2028.5 2084.1 967.8 998.0 1001.3 1053.1 1058.4 16939.7 19521.5 20057.8 20059.2 20192.7 843.4 981.7 1022.8 1057.8 1069.3 10254.6 10690.4 10720.7 10748.7 10849.4 593989.0 596947.0 602154.0 608079.0 612604.0 113859.4 122906.0 125158.1 125313.4 125749.7 974.3 1011.0 1012.3 1016.6 1035.9 17781.8 17817.9 18011.6 18128.5 18325.6 36229.1 41372.8 42074.8 49987.5 51329.2 64869.0 65072.7 65523.0 67276.7 67427.0 6557.5 7805.8 7881.3 7985.0 8215.1 10122.9 10529.6 12066.2 12226.4 12386.0 8303.7 8589.3 9261.3 10683.1 10987.6 10350.7 10551.5 10643.4 10661.9 10738.1 10796.0 10845.3 11077.7 11099.7 11254.3 7866.2 8092.9 8102.0 8175.0 8399.0 9466.4 10772.6 10805.0 10813.3 10928.6 02:35:33 INFO - PROCESS | 2009 | 02:35:33 INFO - PROCESS | 2009 | [#3] mootools.html Cycles:180 Average:5583.31 Median:4824.03 stddev:3122.05 (64.7%) stddev-sans-first:3116.27 02:35:33 INFO - PROCESS | 2009 | Values: 1574.1 1592.6 1801.8 1868.9 1937.0 7800.6 8474.6 8579.9 8791.3 8792.8 8535.8 8575.4 8626.0 8650.6 8662.3 2445.4 2449.9 2451.1 2453.7 2469.6 2358.5 2358.9 2379.4 2395.2 2405.7 1879.3 2334.2 2422.2 2450.0 2470.8 2321.3 2340.6 2344.4 2354.0 2471.9 2388.0 2417.8 2418.2 2421.8 2439.6 8734.2 8756.2 8783.8 8796.0 8800.4 4467.4 5399.7 5423.8 5429.7 5435.5 3478.5 3497.1 3511.1 3567.2 3614.6 1049.1 1062.8 1064.3 1066.2 1067.0 11593.8 11610.9 11832.0 11833.1 11842.8 4013.9 4656.7 4738.6 4762.4 4810.4 9973.7 10032.7 10049.5 10057.0 10057.9 5316.5 5340.5 5361.6 5410.5 5417.7 3694.9 4802.2 4822.7 4856.1 4888.4 3989.4 3993.3 4008.2 4076.8 4156.6 1352.3 1364.9 1369.9 1370.2 1376.1 908.6 920.6 921.2 925.8 933.8 5595.8 5732.3 5756.1 5763.4 5816.2 462.6 466.9 470.5 997.6 1034.0 3045.0 3380.5 3419.8 3765.2 3786.8 10025.3 10658.9 10734.3 10749.9 10772.7 8562.9 8568.2 8768.8 10466.6 10526.6 8158.7 8389.3 8422.7 8426.8 8462.1 4889.5 5223.5 5410.6 5527.7 5614.5 9569.9 9615.9 9657.4 9701.7 9702.5 9440.5 9830.0 9892.4 9897.2 10001.1 6356.7 6746.0 6962.0 7030.4 7126.4 8156.9 8179.4 8228.9 8242.3 8259.7 7139.8 7262.6 7279.5 7341.7 7385.0 7079.0 7137.0 7207.0 7213.5 7268.5 6814.3 6816.7 6826.5 6842.7 6896.2 4265.6 4618.8 4755.5 4807.4 4810.1 4809.0 4825.3 4835.3 4871.3 5084.6 02:35:33 INFO - PROCESS | 2009 | 02:35:33 INFO - PROCESS | 2009 | [#4] prototype.html Cycles:180 Average:5932.74 Median:6768.56 stddev:1909.24 (28.2%) stddev-sans-first:1867.11 02:35:33 INFO - PROCESS | 2009 | Values: 295.1 326.6 328.4 330.6 333.2 6792.8 6810.3 6860.3 6906.9 6913.5 5506.5 6777.7 6794.5 6844.8 6855.6 4701.0 4819.0 4916.6 4983.7 4992.0 4943.7 4993.5 4995.9 5004.6 5026.7 4907.8 4927.7 4941.0 4941.2 4958.6 4664.3 4892.8 4961.7 4977.1 4979.0 4260.2 4263.6 4274.8 4277.9 4278.6 6363.0 6542.2 6621.0 6635.5 6661.0 4787.8 5889.2 5929.9 5939.6 5958.9 5809.7 7219.2 7230.7 7269.8 7285.1 4760.0 4784.3 4786.7 4797.4 4797.4 6895.2 7659.5 7691.2 7698.3 7724.4 4770.6 4817.0 4833.8 4845.7 4856.1 4927.0 4928.1 4930.7 4933.7 4944.7 5786.2 5868.6 5897.5 5900.7 5919.5 7292.7 7308.9 7315.0 7325.1 7368.0 1677.4 1714.2 1736.3 1737.0 1739.0 955.1 1107.5 1110.3 1127.8 1138.4 6735.8 6737.6 6759.4 6781.9 6799.4 6132.5 6159.7 7496.1 7502.9 7504.7 5585.6 6630.7 6704.4 6710.2 6781.4 3281.5 3936.0 3949.2 3979.6 3988.6 7540.2 7843.3 7845.2 7850.2 7851.8 7685.7 7750.6 7762.8 7765.7 7768.2 7818.2 7853.8 7862.7 7866.9 7875.0 7322.1 7490.0 7492.9 7499.1 7532.4 7406.8 7447.2 7455.5 7458.7 7476.1 6486.8 7910.4 7944.4 7983.5 8000.4 4028.6 4732.3 4740.2 4764.1 4802.3 7259.7 7493.3 7633.1 7663.5 7676.7 7324.6 7345.3 7379.2 7380.7 7407.5 7334.3 7346.8 7351.8 7359.6 7366.6 6954.5 7131.5 7141.7 7175.2 7175.9 7312.0 7340.1 7353.8 7364.2 7402.9 7185.6 7205.4 7212.4 7212.5 7222.0 02:35:33 INFO - PROCESS | 2009 | 02:35:33 INFO - PROCESS | 2009 | [#5] yui.html Cycles:180 Average:18811.89 Median:61926.36 stddev:27027.93 (43.6%) stddev-sans-first:27069.32 02:35:33 INFO - PROCESS | 2009 | Values: 641.2 670.6 702.9 710.6 742.3 15001.5 15170.0 15185.2 15238.9 17119.5 13298.7 16075.3 16190.3 16247.0 16267.4 728.1 895.6 899.2 925.1 925.4 858.7 864.8 887.2 916.2 916.2 943.1 944.2 976.6 981.9 982.6 969.5 975.3 975.8 979.1 979.9 714.4 722.2 723.2 725.6 726.9 15278.8 15438.8 15487.8 15546.1 15563.7 12617.3 12625.2 12836.6 12852.7 12909.4 9983.5 10092.3 10262.7 10314.6 10361.1 3405.8 3431.8 3433.2 3457.0 3462.9 85358.6 86436.0 87348.0 87912.0 88192.8 9131.9 9204.0 9312.3 9477.6 9577.8 21536.7 21538.7 21549.5 21569.6 21623.7 7235.2 7290.7 7315.3 7393.7 7448.9 4031.2 4043.8 4060.7 4062.0 4080.3 3135.5 3696.1 3751.1 3755.3 3758.2 660.6 661.9 671.3 672.3 675.9 529.1 534.1 564.9 599.3 601.4 7948.9 8215.1 8284.4 8310.2 8361.0 485.9 524.2 564.7 570.7 587.6 3734.0 3908.7 3912.4 3936.4 4148.8 95079.5 96783.5 99097.0 99273.5 100546.0 84533.0 85248.6 85397.3 85422.6 86358.6 71070.6 72303.3 72726.6 72976.0 75688.9 5816.4 7574.6 7789.8 7935.0 8025.6 47046.5 47428.0 49245.8 49585.8 49830.8 55296.9 60327.0 63525.7 68747.0 70045.6 8951.8 9100.8 11024.2 11264.3 11474.5 11859.1 12027.7 12112.8 12174.4 12528.1 9728.5 11505.2 11546.5 11810.5 11817.8 11389.2 11466.6 11581.3 11787.4 12023.5 10658.6 11306.2 11320.6 11468.7 11554.9 8945.5 9180.9 9702.1 9903.7 10121.4 9682.2 9726.9 9802.6 9806.2 9810.0 02:35:33 INFO - PROCESS | 2009 | -------- Summary: end -------- 02:35:33 INFO - PROCESS | 2009 | 02:35:34 INFO - TEST-INFO | 2009: exit 0 02:35:34 INFO - TEST-OK | dromaeo_css | took 1110776ms 02:35:34 INFO - TEST-START | kraken 02:35:34 INFO - operating with platform_type : mac_ 02:35:34 INFO - Initialising browser for kraken test... 02:35:34 INFO - TEST-INFO | started process 2014 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmp8JdWQt/profile http://localhost:49409/getInfo.html) 02:35:37 INFO - PROCESS | 2014 | __metrics Screen width/height:1600/1200 02:35:37 INFO - PROCESS | 2014 | colorDepth:24 02:35:37 INFO - PROCESS | 2014 | Browser inner width/height: 1024/689 02:35:37 INFO - PROCESS | 2014 | __metrics 02:35:37 ERROR - PROCESS | 2014 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 02:35:38 INFO - TEST-INFO | 2014: exit 0 02:35:38 INFO - Browser initialized. 02:35:38 INFO - Running cycle 1/1 for kraken test... 02:35:38 INFO - TEST-INFO | started process 2017 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/T/tmp8JdWQt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:35:49 INFO - PROCESS | 2017 | RSS: Main: 220594176 02:35:49 INFO - PROCESS | 2017 | 02:35:52 INFO - PROCESS | 2017 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49409/tests/kraken/driver.html?testName=audio-beat-detection) 02:35:53 INFO - PROCESS | 2017 | RSS: Main: 232595456 02:35:53 INFO - PROCESS | 2017 | 02:35:56 INFO - PROCESS | 2017 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49409/tests/kraken/driver.html?testName=audio-dft) 02:35:56 INFO - PROCESS | 2017 | RSS: Main: 241786880 02:35:56 INFO - PROCESS | 2017 | 02:36:00 INFO - PROCESS | 2017 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49409/tests/kraken/driver.html?testName=audio-fft) 02:36:00 INFO - PROCESS | 2017 | RSS: Main: 245387264 02:36:00 INFO - PROCESS | 2017 | 02:36:02 INFO - PROCESS | 2017 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49409/tests/kraken/driver.html?testName=audio-oscillator) 02:36:03 INFO - PROCESS | 2017 | RSS: Main: 245907456 02:36:03 INFO - PROCESS | 2017 | 02:36:05 INFO - PROCESS | 2017 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49409/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:36:06 INFO - PROCESS | 2017 | RSS: Main: 246222848 02:36:06 INFO - PROCESS | 2017 | 02:36:09 INFO - PROCESS | 2017 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49409/tests/kraken/driver.html?testName=imaging-darkroom) 02:36:09 INFO - PROCESS | 2017 | RSS: Main: 246890496 02:36:09 INFO - PROCESS | 2017 | 02:36:12 INFO - PROCESS | 2017 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49409/tests/kraken/driver.html?testName=imaging-desaturate) 02:36:13 INFO - PROCESS | 2017 | RSS: Main: 248250368 02:36:13 INFO - PROCESS | 2017 | 02:36:16 INFO - PROCESS | 2017 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49409/tests/kraken/driver.html?testName=json-parse-financial) 02:36:17 INFO - PROCESS | 2017 | RSS: Main: 248741888 02:36:17 INFO - PROCESS | 2017 | 02:36:18 INFO - PROCESS | 2017 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49409/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:36:19 INFO - PROCESS | 2017 | RSS: Main: 247549952 02:36:19 INFO - PROCESS | 2017 | 02:36:21 INFO - PROCESS | 2017 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49409/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:36:22 INFO - PROCESS | 2017 | RSS: Main: 247468032 02:36:22 INFO - PROCESS | 2017 | 02:36:24 INFO - PROCESS | 2017 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49409/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:36:24 INFO - PROCESS | 2017 | RSS: Main: 247672832 02:36:24 INFO - PROCESS | 2017 | 02:36:27 INFO - PROCESS | 2017 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49409/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:36:28 INFO - PROCESS | 2017 | RSS: Main: 247721984 02:36:28 INFO - PROCESS | 2017 | 02:36:31 INFO - PROCESS | 2017 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49409/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:36:32 INFO - PROCESS | 2017 | RSS: Main: 247955456 02:36:32 INFO - PROCESS | 2017 | 02:36:33 INFO - PROCESS | 2017 | Cycle 1(1): loaded http://localhost:49409/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49409/tests/kraken/driver.html?testName=ai-astar) 02:36:34 INFO - PROCESS | 2017 | RSS: Main: 249405440 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - PROCESS | 2017 | __start_tp_report 02:36:34 INFO - PROCESS | 2017 | _x_x_mozilla_page_load 02:36:34 INFO - PROCESS | 2017 | _x_x_mozilla_page_load_details 02:36:34 INFO - PROCESS | 2017 | |i|pagename|runs| 02:36:34 INFO - PROCESS | 2017 | |0;ai-astar;112;119;108;117;119;119;108;114;115;115 02:36:34 INFO - PROCESS | 2017 | |1;audio-beat-detection;156;154;163;147;137;173;175;158;166;126 02:36:34 INFO - PROCESS | 2017 | |2;audio-dft;200;196;196;217;240;195;201;199;198;194 02:36:34 INFO - PROCESS | 2017 | |3;audio-fft;69;90;74;149;55;58;57;64;133;57 02:36:34 INFO - PROCESS | 2017 | |4;audio-oscillator;81;84;127;76;79;83;125;83;82;125 02:36:34 INFO - PROCESS | 2017 | |5;imaging-gaussian-blur;87;86;105;130;88;111;134;81;138;75 02:36:34 INFO - PROCESS | 2017 | |6;imaging-darkroom;95;96;121;146;98;142;104;151;95;152 02:36:34 INFO - PROCESS | 2017 | |7;imaging-desaturate;90;102;112;91;93;89;92;95;89;90 02:36:34 INFO - PROCESS | 2017 | |8;json-parse-financial;61;63;67;66;65;78;86;109;79;62 02:36:34 INFO - PROCESS | 2017 | |9;json-stringify-tinderbox;67;57;81;98;117;110;93;68;69;68 02:36:34 INFO - PROCESS | 2017 | |10;stanford-crypto-aes;83;74;83;107;108;107;82;81;84;84 02:36:34 INFO - PROCESS | 2017 | |11;stanford-crypto-ccm;132;134;128;129;162;151;168;158;176;171 02:36:34 INFO - PROCESS | 2017 | |12;stanford-crypto-pbkdf2;166;170;196;235;190;191;234;199;169;177 02:36:34 INFO - PROCESS | 2017 | |13;stanford-crypto-sha256-iterative;70;72;65;73;67;66;74;97;62;93 02:36:34 INFO - PROCESS | 2017 | __end_tp_report 02:36:34 INFO - PROCESS | 2017 | __start_cc_report 02:36:34 INFO - PROCESS | 2017 | _x_x_mozilla_cycle_collect,1097 02:36:34 INFO - PROCESS | 2017 | __end_cc_report 02:36:34 INFO - PROCESS | 2017 | __startTimestamp1463736994132__endTimestamp 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - PROCESS | 2017 | ------- Summary: start ------- 02:36:34 INFO - PROCESS | 2017 | Number of tests: 14 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - PROCESS | 2017 | [#0] ai-astar Cycles:10 Average:114.60 Median:116.00 stddev:4.20 (3.6%) stddev-sans-first:4.34 02:36:34 INFO - PROCESS | 2017 | Values: 112.0 119.0 108.0 117.0 119.0 119.0 108.0 114.0 115.0 115.0 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - PROCESS | 2017 | [#1] audio-beat-detection Cycles:10 Average:155.50 Median:160.50 stddev:15.44 (9.6%) stddev-sans-first:16.38 02:36:34 INFO - PROCESS | 2017 | Values: 156.0 154.0 163.0 147.0 137.0 173.0 175.0 158.0 166.0 126.0 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - PROCESS | 2017 | [#2] audio-dft Cycles:10 Average:203.60 Median:199.50 stddev:14.37 (7.2%) stddev-sans-first:15.18 02:36:34 INFO - PROCESS | 2017 | Values: 200.0 196.0 196.0 217.0 240.0 195.0 201.0 199.0 198.0 194.0 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - PROCESS | 2017 | [#3] audio-fft Cycles:10 Average:80.60 Median:61.00 stddev:33.74 (55.3%) stddev-sans-first:35.53 02:36:34 INFO - PROCESS | 2017 | Values: 69.0 90.0 74.0 149.0 55.0 58.0 57.0 64.0 133.0 57.0 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - PROCESS | 2017 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:81.50 stddev:21.63 (26.5%) stddev-sans-first:22.39 02:36:34 INFO - PROCESS | 2017 | Values: 81.0 84.0 127.0 76.0 79.0 83.0 125.0 83.0 82.0 125.0 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - PROCESS | 2017 | [#5] imaging-gaussian-blur Cycles:10 Average:103.50 Median:78.00 stddev:23.61 (30.3%) stddev-sans-first:24.28 02:36:34 INFO - PROCESS | 2017 | Values: 87.0 86.0 105.0 130.0 88.0 111.0 134.0 81.0 138.0 75.0 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - PROCESS | 2017 | [#6] imaging-darkroom Cycles:10 Average:120.00 Median:123.50 stddev:25.19 (20.4%) stddev-sans-first:25.04 02:36:34 INFO - PROCESS | 2017 | Values: 95.0 96.0 121.0 146.0 98.0 142.0 104.0 151.0 95.0 152.0 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - PROCESS | 2017 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:90.50 stddev:7.33 (8.1%) stddev-sans-first:7.61 02:36:34 INFO - PROCESS | 2017 | Values: 90.0 102.0 112.0 91.0 93.0 89.0 92.0 95.0 89.0 90.0 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - PROCESS | 2017 | [#8] json-parse-financial Cycles:10 Average:73.60 Median:66.50 stddev:15.04 (22.6%) stddev-sans-first:15.25 02:36:34 INFO - PROCESS | 2017 | Values: 61.0 63.0 67.0 66.0 65.0 78.0 86.0 109.0 79.0 62.0 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - PROCESS | 2017 | [#9] json-stringify-tinderbox Cycles:10 Average:82.80 Median:68.50 stddev:20.53 (30.0%) stddev-sans-first:20.96 02:36:34 INFO - PROCESS | 2017 | Values: 67.0 57.0 81.0 98.0 117.0 110.0 93.0 68.0 69.0 68.0 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - PROCESS | 2017 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:82.50 stddev:12.77 (15.5%) stddev-sans-first:13.34 02:36:34 INFO - PROCESS | 2017 | Values: 83.0 74.0 83.0 107.0 108.0 107.0 82.0 81.0 84.0 84.0 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - PROCESS | 2017 | [#11] stanford-crypto-ccm Cycles:10 Average:150.90 Median:160.00 stddev:18.70 (11.7%) stddev-sans-first:18.54 02:36:34 INFO - PROCESS | 2017 | Values: 132.0 134.0 128.0 129.0 162.0 151.0 168.0 158.0 176.0 171.0 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - PROCESS | 2017 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.70 Median:193.50 stddev:24.93 (12.9%) stddev-sans-first:24.49 02:36:34 INFO - PROCESS | 2017 | Values: 166.0 170.0 196.0 235.0 190.0 191.0 234.0 199.0 169.0 177.0 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - PROCESS | 2017 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.90 Median:72.50 stddev:11.78 (16.2%) stddev-sans-first:12.41 02:36:34 INFO - PROCESS | 2017 | Values: 70.0 72.0 65.0 73.0 67.0 66.0 74.0 97.0 62.0 93.0 02:36:34 INFO - PROCESS | 2017 | -------- Summary: end -------- 02:36:34 INFO - PROCESS | 2017 | 02:36:34 INFO - TEST-INFO | 2017: exit 0 02:36:34 INFO - TEST-OK | kraken | took 60619ms 02:36:34 INFO - SUITE-END | took 1171s 02:36:35 INFO - Completed test suite (00:19:31) 02:36:35 INFO - javascript benchmark 02:36:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3107.9533806992895, 3424.520567120602, 3427.7600567960253, 3462.913427164322, 3503.5620547431563, 5684.971575142118, 5690.118031705082, 5734.225879506265, 5735.225744524488, 5784.68184249866, 4707.929381059278, 5725.456081672245, 5750.683712395817, 5769.682667453289, 5775.999999999989, 5192.766325515351, 5252.868678283055, 5253.238280449333, 5290.523852853243, 5300.337457817773, 5098.515641014098, 5283.973580132094, 5325.520703136717, 5383.838484845461, 5388.191771234307, 5277.261183434322, 5323.307969963899, 5323.760430780624, 5331.360236771573, 5332.200169974496, 5274.868128296804, 5315.01672190646, 5323.840284791462, 5330.333708286465, 5364.758585863646, 4815.5906747926465, 5105.8767071244265, 5115.565176959963, 5257.158854583248, 5259.973700131475, 4601.872541227421, 4859.0, 5490.505854473076, 5641.0, 5647.181158731961, 5449.591280653927, 6274.623522588659, 6278.121063051177, 6310.116583678288, 6332.430081292659, 6114.694265286718, 6195.2255968004, 6205.0, 6247.2815626202655, 6277.52918531112, 5931.436513531207, 5938.643681379088, 5939.851503712399, 5942.554308426886, 5951.672658003851, 6323.320165968531, 6371.108044873721, 6386.393292637192, 6390.904136437957, 6400.935990640034, 5138.332016837787, 6125.41859352575, 6190.133381326618, 6241.594296370812, 6246.312905580383, 6264.624122552661, 6285.5914365567005, 6295.401936815995, 6314.147590075281, 6321.841953951188, 6120.264288666148, 6182.69086545671, 6269.153664255267, 6287.717052732638, 6317.652529110851, 6221.9377806221355, 6247.0, 6266.436020758154, 6286.339934306832, 6305.085762564402, 4963.726995015309, 5010.624203184776, 5016.648834581544, 5016.724080175552, 5027.84916452507, 4398.604125628709, 4428.2914733642465, 4446.666500012512, 4541.3869127668, 4543.54564543543, 4644.814207431665, 5724.713764311768, 5781.942180578225, 5883.205767221456, 5927.9407205928255, 6223.626582405071, 6226.906596401058, 6246.500279977591, 6289.0, 6301.8109456716375, 5513.641613295123, 5760.884782304414, 5844.298684157929, 5947.821565353046, 5950.821475355746, 5464.371597266286, 5464.453554644504, 5474.288342515448, 5486.094794358966, 5527.170924361378, 5277.050130976308, 6441.259255185713, 6449.484041276686, 6450.903236451457, 6462.1276127723095, 6186.721597528032, 6229.657368844757, 6266.717997690023, 6274.6548939808745, 6302.65335406557, 6441.967790161019, 6455.419012288917, 6473.417392434703, 6477.287498375269, 6482.222133344029, 6212.285587157354, 6219.906701399392, 6220.440160385406, 6265.248170219603, 6265.874682506415, 5432.0, 6134.846628834315, 6185.876282474415, 6200.720967556381, 6298.370162983665, 5027.824026159214, 5520.585956053393, 5787.131930210502, 6159.445649891352, 6181.721822517908, 6084.513238940785, 6127.448529632355, 6148.200733904564, 6155.815325540241, 6168.722407491584, 4949.802007919643, 5805.76776928935, 5823.039198532364, 5963.403659634002, 6051.697415129314, 6077.696115194134, 6151.169592105009, 6182.805104188128, 6184.907226391518, 6197.225346831646, 5965.8806823862415, 6032.396760324108, 6106.816795496142, 6111.297200821962, 6117.480014198843], "value": 5695.792932314689, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.74661795793116, 537.3605409562621, 564.3312674480738, 594.5243804956034, 597.7268418269089, 31714.365712685732, 39929.401058984105, 39968.80093597189, 40024.0, 40092.79721608349, 40913.59086409135, 41555.96110097249, 41703.95740106499, 42418.787906060505, 42426.151476970444, 9510.619575217, 9610.942796292402, 9631.855522167172, 9654.13112819846, 9660.90339096607, 9603.711888643353, 9737.805243895118, 9786.755331116743, 9806.0, 9872.802543949118, 9829.50852457378, 9842.212622990144, 9855.852162217572, 9902.0, 9915.355501892354, 9710.417374957526, 9782.315237933348, 9800.166985806214, 9816.509174541245, 9861.359011664219, 9702.61189552417, 9789.853152202722, 9822.65620703272, 9826.410415375029, 9835.409875407498, 40047.7997610013, 40604.79697601523, 40711.0, 40993.590064099575, 41145.0, 2590.7668309852015, 2806.5930440086277, 3160.8103513789247, 3281.770276080675, 3304.6695330467, 24597.262082137564, 26374.340641483923, 26720.0, 26900.923963041456, 27267.181984540297, 2466.7903228225623, 2673.385121422084, 2687.9865600672065, 2691.923230707713, 2703.2430919342614, 286530.0, 306904.0, 311978.0, 312260.0, 312516.0, 34928.82535587306, 36243.825370477854, 36795.0, 37147.442788358196, 37265.25469490595, 35956.92129236127, 36364.81817590895, 36372.27255454929, 36406.27187456289, 36534.08664783349, 15899.443519476994, 16041.518754437386, 16049.197540122947, 16066.75899861503, 16070.35718571244, 13311.933440332736, 13337.66655833612, 13399.263040532669, 13449.529266475627, 13462.461501540025, 7275.308845659654, 8191.79520511992, 8301.169883011651, 8462.069172391031, 8510.0, 3420.555327807419, 3464.34177506273, 3464.5842498899788, 3464.5842498900292, 3474.4614584739406, 980.4999450280266, 987.8370068938689, 1005.070309963281, 1028.4086650176177, 1141.5262665993687, 25642.974281028924, 30720.0, 30908.0, 31122.066338009896, 31305.0, 1131.2420678145663, 1148.7645032768262, 1157.2130950953265, 1160.187868492042, 1173.4484792147678, 15606.531804045897, 15706.057636541844, 15895.841041589667, 16019.0, 16147.919260403623, 618662.0, 618922.0, 621640.8917955381, 626625.0, 627696.0, 61980.69009654923, 62618.0, 62660.0, 62809.685951570864, 62996.370036299966, 1502.4365862801449, 1523.2079318753965, 1538.8615024647836, 1539.4073281786368, 1606.5421354913794, 29775.851120744257, 30015.849920750257, 30346.937857174893, 30795.538066928566, 30876.691233087382, 53892.46107538875, 54620.45379546154, 54981.0, 55007.89984200374, 55536.444635553125, 557855.0, 560176.0, 560774.0, 562192.0, 564254.0, 27705.861470693322, 27723.861380692968, 28250.85874570614, 43874.341884871115, 44773.776131119135, 44506.0, 44663.77668111639, 45146.0, 45262.321065184704, 45579.31631025471, 30169.547456788605, 38355.232895342495, 38688.80655596704, 39916.60083399129, 40806.0, 37789.244215114995, 38288.23423531569, 38326.2334753309, 38370.0, 38671.41992870053, 33306.83346583252, 38017.61982380252, 38038.049048774, 38265.0, 39619.405708913815, 31256.9060082896, 32676.673233268313, 33574.992750217534, 34941.47587786236, 35061.47407788834, 23223.95492202821, 23918.402039949142, 24720.876395617906, 25137.87431062833, 25374.1119060832], "value": 18436.051878302158, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.16653280720743, 399.9840006399742, 405.3683922213093, 406.3234714200856, 412.2065024827207, 15150.696986060273, 20136.8993155034, 20232.89883550582, 20554.075066622, 20912.37262882114, 20098.794072355668, 20316.390508284738, 20466.89766551169, 20611.69082463764, 20673.379798606027, 5829.795957141501, 5844.473997340238, 5951.702414879239, 5989.011813050842, 5990.221271234734, 4869.172240719086, 5865.090910908816, 5886.264216972879, 5983.311919129291, 5991.850203744898, 5745.770169193248, 5852.917210316084, 5930.140129681194, 5977.98374276374, 6036.88317661234, 5895.88208235835, 5927.37762534932, 5974.581779275452, 5981.730822113015, 6018.6689732064715, 5028.547430731252, 5153.304303918961, 5253.55527230011, 5272.6836389816735, 5283.788648454057, 21948.122075116975, 22781.54436911269, 26271.73728262712, 26621.334466638295, 26755.46489070227, 2186.8797216153107, 2378.8929498172624, 2474.7277799442045, 2515.019142534413, 2671.2520494261444, 13796.931015344959, 14392.208428536427, 14739.115653060851, 15029.549113526611, 15147.924260378628, 1366.2690460603628, 1427.9928600357036, 1843.9262429502803, 1853.8702290839647, 1867.3930972434014, 72812.0, 97934.59294331772, 103829.48085259525, 108806.0, 109303.0, 11476.88523114775, 11696.824547631792, 11745.29528228309, 11835.408229588485, 11911.70220744471, 13794.586162415144, 14251.216183109822, 14732.779008314883, 14774.926125369304, 14941.40234390634, 7815.179406162385, 8154.776783482525, 8657.0, 8695.739127826175, 8869.0, 8691.565421729014, 8781.429207101646, 8851.734447966572, 8942.195202431813, 8975.50634715097, 4042.0097076215925, 4422.29243321067, 4570.9314360284625, 4660.720356778605, 4706.529347065266, 1722.46603552899, 2014.1540552967497, 2017.1527958257273, 2028.4624574487773, 2084.0830034784644, 967.7677357434166, 998.0269237493386, 1001.3140998416109, 1053.1364281289423, 1058.4284486377423, 16939.661206775796, 19521.511962201057, 20057.796532208115, 20059.197632094554, 20192.69710954337, 843.4348986179275, 981.7005813227034, 1022.7903279827617, 1057.7567159553346, 1069.3476979042778, 10254.6410875619, 10690.358578485315, 10720.731981700364, 10748.677539673823, 10849.403282819363, 593989.0, 596947.0, 602154.0, 608079.0, 612604.0, 113859.43070284596, 122906.0, 125158.12262816065, 125313.37343313225, 125749.74250257564, 974.28390133252, 1010.9696709098739, 1012.3419777144768, 1016.5679586175846, 1035.8860525342207, 17781.822181778534, 17817.910910445884, 18011.639767204844, 18128.546786330448, 18325.633487330444, 36229.09427264234, 41372.793136034124, 42074.7896260529, 49987.50012499828, 51329.23006154836, 64869.0, 65072.674636628406, 65523.01715474176, 67276.6636166816, 67427.0, 6557.475401967736, 7805.843883122419, 7881.330086942674, 7984.96007519982, 8215.096339402542, 10122.949385253321, 10529.578816847546, 12066.215695979734, 12226.449809758404, 12386.0, 8303.709370172015, 8589.312854971462, 9261.259099271907, 10683.145348372267, 10987.560497580007, 10350.689479315633, 10551.472426378803, 10643.361398316125, 10661.946690266499, 10738.14094872424, 10796.0, 10845.295055821347, 11077.723056923642, 11099.72250693739, 11254.268472549258, 7866.213378662317, 8092.87860682102, 8101.959490202511, 8175.018997720312, 8399.0, 9466.384684995728, 10772.569097236023, 10805.0, 10813.297135686154, 10928.617498387519], "value": 9408.844806680963, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1574.071297934219, 1592.617771734784, 1801.8468430183434, 1868.9158987845547, 1936.9709454358178, 7800.609969501531, 8474.618642161102, 8579.871301930469, 8791.252743516801, 8792.780180495483, 8535.78660533487, 8575.442596231256, 8625.956870215656, 8650.610722517486, 8662.263707584847, 2445.4131008557997, 2449.8530088194675, 2451.10534654851, 2453.668754718117, 2469.617209332547, 2358.469344397514, 2358.905643774247, 2379.4051487128218, 2395.197608801045, 2405.6872606561124, 1879.2858713688847, 2334.171369163948, 2422.188566830114, 2449.963250551234, 2470.765277298654, 2321.3384185507134, 2340.6137987232173, 2344.4373350395954, 2353.9646905296436, 2471.938201544958, 2387.9761202387926, 2417.758224177586, 2418.1899063813644, 2421.769931856471, 2439.6462512935705, 8734.2139207471, 8756.211940925283, 8783.78040548985, 8795.956020219857, 8800.427972181787, 4467.352233926095, 5399.730013499309, 5423.7830486780485, 5429.728513574345, 5435.510804027656, 3478.513008178857, 3497.1257185703575, 3511.1046683095647, 3567.2152126532123, 3614.5843228028853, 1049.1344640671477, 1062.7661914378825, 1064.297563608015, 1066.2003497376968, 1066.9626563070333, 11593.76812463746, 11610.94194529022, 11831.99428048617, 11833.053355731434, 11842.763144737142, 4013.9397909031386, 4656.743879086683, 4738.64460165489, 4762.428508578923, 4810.350602668595, 9973.700788976343, 10032.69901902944, 10049.497525123714, 10057.0, 10057.899421005843, 5316.521513063765, 5340.465953404706, 5361.571074314045, 5410.540104091117, 5417.674939503642, 3694.8706795262037, 4802.159622066124, 4822.71063736177, 4856.077345304381, 4888.388951381077, 3989.3816458448987, 3993.281209382281, 4008.198360327946, 4076.796160192038, 4156.625903668684, 1352.3103217359217, 1364.938577764003, 1369.9315034248448, 1370.1573532277778, 1376.078027721429, 908.6002159049997, 920.6317473010849, 921.2123634292717, 925.7500474871747, 933.7945651956559, 5595.832125036255, 5732.3407808101765, 5756.079027355603, 5763.394843541368, 5816.156657284755, 462.55132521454135, 466.9439667239885, 470.5200695290784, 997.5535473563363, 1033.9793204136026, 3044.9847750761105, 3380.543626610376, 3419.785975978492, 3765.171662234304, 3786.753860999026, 10025.348352357074, 10658.946705266424, 10734.302270352402, 10749.89250107489, 10772.676819695422, 8562.871556926651, 8568.186022327869, 8768.78078048054, 10466.63366782159, 10526.631567895087, 8158.714444994397, 8389.286910612665, 8422.747317580483, 8426.831463370698, 8462.111478294813, 4889.486603906681, 5223.4776522347465, 5410.567154627542, 5527.678375082555, 5614.522765564972, 9569.90430095718, 9615.9038409615, 9657.372270802376, 9701.70894873155, 9702.514874256118, 9440.527973601154, 9830.0, 9892.406455612687, 9897.158741507052, 10001.099901008944, 6356.745730170742, 6745.966270168814, 6961.965190174017, 7030.367266945999, 7126.429885609035, 8156.877646835421, 8179.386546009192, 8228.917710823054, 8242.29940455068, 8259.710910118118, 7139.82150446243, 7262.636868156465, 7279.490435669452, 7341.706331746884, 7385.0, 7078.964605176941, 7136.964315178391, 7207.0, 7213.458990575623, 7268.527545709512, 6814.284500127415, 6816.727330906907, 6826.488013398915, 6842.726290948306, 6896.206936202401, 4265.637420819266, 4618.769061546976, 4755.548222918817, 4807.375041244616, 4810.086083644096, 4808.9519104808505, 4825.3485779419325, 4835.29888166221, 4871.3423687803115, 5084.5932325414615], "value": 4491.791616876392, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.1205407884504, 326.626013214869, 328.435091642375, 330.61813605285886, 333.22691356053963, 6792.796216113512, 6810.284920083394, 6860.348266914648, 6906.896396554056, 6913.481488888328, 5506.476884695948, 6777.695003724832, 6794.4564434845215, 6844.760433384832, 6855.622940738257, 4700.952990470103, 4818.975905120469, 4916.631252656047, 4983.6760610560195, 4991.975040124794, 4943.703377797344, 4993.525615066563, 4995.925061124086, 5004.62465315101, 5026.723530205837, 4907.82822601209, 4927.704337739729, 4941.0, 4941.160002799515, 4958.553730164284, 4664.346991421204, 4892.77982490787, 4961.652684312099, 4977.104121258172, 4978.9751051244875, 4260.1905637929, 4263.616274535276, 4274.807633656493, 4277.871663850089, 4278.572142785728, 6362.968185159045, 6542.214934207878, 6621.0, 6635.535512514126, 6661.0, 4787.784549695272, 5889.204957330749, 5929.851753706192, 5939.643621382731, 5958.910616340759, 5809.738561764689, 7219.1697954735355, 7230.710771569131, 7269.781906542759, 7285.089363829521, 4760.0, 4784.330193772875, 4786.688865223749, 4797.352357431737, 4797.424309082898, 6895.241523432469, 7659.463837531375, 7691.1924247954275, 7698.345640620554, 7724.420668449889, 4770.570648641569, 4816.975915120472, 4833.782479788418, 4845.733484658378, 4856.077345304381, 4926.950730492649, 4928.137575924199, 4930.728809915489, 4933.728644924492, 4944.703317800943, 5786.218860453869, 5868.589198756046, 5897.52819774417, 5900.734466948998, 5919.520119970008, 7292.671829767673, 7308.926910730931, 7314.975903373532, 7325.120985481776, 7367.963160184165, 1677.3877534699748, 1714.202895653527, 1736.270766278166, 1737.044625455994, 1739.034835666192, 955.1165172215811, 1107.5348353691309, 1110.2561283939779, 1127.8308253762002, 1138.3795831271977, 6735.797926062226, 6737.562058466184, 6759.425448836806, 6781.932180678229, 6799.388055075067, 6132.540059495466, 6159.6612186329285, 7496.100467943882, 7502.924970750332, 7504.737334193277, 5585.58108141891, 6630.668466576652, 6704.396604305538, 6710.228323742735, 6781.355771201728, 3281.5405843181975, 3935.9606403935595, 3949.2496425679606, 3979.641832235113, 3988.561258261589, 7540.208278130827, 7843.254890785366, 7845.176256492986, 7850.175731548105, 7851.842963140705, 7685.654145563463, 7750.5737184454265, 7762.844743105219, 7765.72819951299, 7768.223177682186, 7818.179091195457, 7853.842923141505, 7862.72480463181, 7866.9213307866185, 7874.960625196952, 7322.0847394075745, 7490.02629658141, 7492.850142997, 7499.137599176164, 7532.43506736986, 7406.8148296290865, 7447.218042105501, 7455.478116532007, 7458.701651933862, 7476.140243872024, 6486.772962946463, 7910.4179164168545, 7944.44388892772, 7983.5209887406945, 8000.359971202173, 4028.610129505316, 4732.337472753886, 4740.198724484674, 4764.142454358249, 4802.27965805132, 7259.745908893164, 7493.325600695964, 7633.084029916446, 7663.540187588763, 7676.731314404193, 7324.560526368435, 7345.3021962913435, 7379.151397589346, 7380.741674041383, 7407.481476296607, 7334.266573342623, 7346.816329591803, 7351.779446616396, 7359.558426494427, 7366.631668416665, 6954.478414119062, 7131.465140114616, 7141.714331426685, 7175.2465991070185, 7175.928240717735, 7312.049433573601, 7340.082489688789, 7353.77938661841, 7364.153122390993, 7402.851942961218, 7185.640717964186, 7205.387542058984, 7212.4230061596, 7212.495125341386, 7221.988921550882], "value": 5289.726424222142, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.2433328672167, 670.5976414151509, 702.931544053039, 710.6269208665447, 742.2503271695588, 15001.474948376808, 15170.0, 15185.240737963099, 15238.923805380971, 17119.486415407526, 13298.734025319487, 16075.276612552434, 16190.271437785328, 16247.025178489299, 16267.430639927605, 728.1007955175354, 895.552223888056, 899.1503029636996, 925.0610630210342, 925.3799954030816, 858.682287553606, 864.8367945113812, 887.152769105506, 916.1708653668408, 916.2029034739786, 943.0663642993455, 944.2257348973844, 976.6093562574956, 981.8527220916884, 982.5774916785779, 969.4522594734024, 975.3075316525241, 975.8243516167087, 979.0747743382507, 979.8628192053118, 714.3749219432996, 722.2488611843677, 723.2116992478224, 725.5501589014792, 726.9491135620509, 15278.847211527853, 15438.768418473732, 15487.767683484757, 15546.067235965878, 15563.688726225411, 12617.306048167347, 12625.179363341353, 12836.614901552968, 12852.742945141139, 12909.354532273348, 9983.500824958723, 10092.293539452241, 10262.692119236435, 10314.587416503331, 10361.119304859096, 3405.7615966882327, 3431.7769344992494, 3433.1588760753525, 3456.9629111266518, 3462.8961131166106, 85358.57320713419, 86436.0, 87348.0, 87912.0, 88192.79518012037, 9131.863022054542, 9203.95398023019, 9312.301577381724, 9477.620895164117, 9577.760555986157, 21536.676949845765, 21538.676919846213, 21549.46126346854, 21569.56860862774, 21623.67564486534, 7235.167955685059, 7290.744823931243, 7315.30504602062, 7393.667284972189, 7448.85102297951, 4031.1534577738416, 4043.844622894684, 4060.7360521566, 4062.0457577507486, 4080.265552200573, 3135.498320268746, 3696.131409118855, 3751.0997360633796, 3755.3240416724707, 3758.2483503298904, 660.6234446365526, 661.8510835062169, 671.2984930747381, 672.3175976383974, 675.8546912413756, 529.0529951387051, 534.1263999681688, 564.9039663257256, 599.3167788720862, 601.3986013986014, 7948.920510794934, 8215.055268644182, 8284.420090593721, 8310.168983101763, 8360.99668039827, 485.907677541266, 524.1770420439949, 564.6950646650763, 570.74887049698, 587.6474115530648, 3733.981330093332, 3908.6677632401565, 3912.4131380292615, 3936.4488971544006, 4148.751074935513, 95079.52460237655, 96783.51608241914, 99097.0, 99273.50363248137, 100546.0, 84533.0, 85248.57375713081, 85397.29205415856, 85422.57288713516, 86358.56820715981, 71070.64464677643, 72303.2769672307, 72726.63636681782, 72976.0, 75688.86466703004, 5816.447437493432, 7574.621268936641, 7789.805254868674, 7935.047794264725, 8025.5987200638565, 47046.52953470421, 47428.0, 49245.75377123091, 49585.76035599139, 49830.75084624554, 55296.89406211934, 60327.0, 63525.72948540911, 68747.0, 70045.59908801694, 8951.836261285991, 9100.772480688036, 11024.228304018641, 11264.324140551593, 11474.483648236017, 11859.110566707359, 12027.699307517381, 12112.75774484523, 12174.39128043612, 12528.123031387715, 9728.464934428674, 11505.194636375374, 11546.538138474703, 11810.527578897092, 11817.82273265919, 11389.202755807013, 11466.598669046543, 11581.305121692725, 11787.351695656478, 12023.4589443477, 10658.573657053941, 11306.152038597302, 11320.6037788677, 11468.738438771896, 11554.942225289156, 8945.46327220369, 9180.862287065833, 9702.126808587262, 9903.653372131941, 10121.443320617436, 9682.225421966366, 9726.902730972883, 9802.558884850056, 9806.21550275962, 9810.018998100133], "value": 6937.503422536456, "unit": "score"}], "name": "dromaeo_css", "value": 7389.95049588661, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 119.0, 108.0, 117.0, 119.0, 119.0, 108.0, 114.0, 115.0, 115.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 154.0, 163.0, 147.0, 137.0, 173.0, 175.0, 158.0, 166.0, 126.0], "value": 155.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 196.0, 196.0, 217.0, 240.0, 195.0, 201.0, 199.0, 198.0, 194.0], "value": 203.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 90.0, 74.0, 149.0, 55.0, 58.0, 57.0, 64.0, 133.0, 57.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 127.0, 76.0, 79.0, 83.0, 125.0, 83.0, 82.0, 125.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 105.0, 130.0, 88.0, 111.0, 134.0, 81.0, 138.0, 75.0], "value": 103.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 121.0, 146.0, 98.0, 142.0, 104.0, 151.0, 95.0, 152.0], "value": 120.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 102.0, 112.0, 91.0, 93.0, 89.0, 92.0, 95.0, 89.0, 90.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 67.0, 66.0, 65.0, 78.0, 86.0, 109.0, 79.0, 62.0], "value": 73.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 57.0, 81.0, 98.0, 117.0, 110.0, 93.0, 68.0, 69.0, 68.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 74.0, 83.0, 107.0, 108.0, 107.0, 82.0, 81.0, 84.0, 84.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 134.0, 128.0, 129.0, 162.0, 151.0, 168.0, 158.0, 176.0, 171.0], "value": 150.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 170.0, 196.0, 235.0, 190.0, 191.0, 234.0, 199.0, 169.0, 177.0], "value": 192.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 65.0, 73.0, 67.0, 66.0, 74.0, 97.0, 62.0, 93.0], "value": 73.9, "unit": "score"}], "name": "kraken", "value": 1629.8000000000002, "alertThreshold": 2.0}]} 02:36:35 INFO - Return code: 0 02:36:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:36:35 ERROR - # TBPL SUCCESS # 02:36:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:36:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:36:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:36:35 INFO - 2016-05-20 02:36:35.425 screenresolution[2022:14601] starting screenresolution argv=screenresolution get 02:36:35 INFO - 2016-05-20 02:36:35.428 screenresolution[2022:14601] Display 0: 1600x1200x32@60 02:36:35 INFO - 2016-05-20 02:36:35.434 screenresolution[2023:14604] starting screenresolution argv=screenresolution list 02:36:35 INFO - Available Modes on Display 0 02:36:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:36:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:36:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:36:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:36:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:36:35 INFO - Intel Iris: 02:36:35 INFO - Chipset Model: Intel Iris 02:36:35 INFO - Type: GPU 02:36:35 INFO - Bus: Built-In 02:36:35 INFO - VRAM (Dynamic, Max): 1536 MB 02:36:35 INFO - Vendor: Intel (0x8086) 02:36:35 INFO - Device ID: 0x0a2e 02:36:35 INFO - Revision ID: 0x0009 02:36:35 INFO - Displays: 02:36:35 INFO - Display: 02:36:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:36:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:36:35 INFO - Display Serial Number: 1600x1200 60 02:36:35 INFO - Main Display: Yes 02:36:35 INFO - Mirror: Off 02:36:35 INFO - Online: Yes 02:36:35 INFO - Rotation: Supported 02:36:35 INFO - Return code: 0 02:36:35 INFO - Running post-action listener: _resource_record_post_action 02:36:35 INFO - Running post-run listener: _resource_record_post_run 02:36:36 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 331776; Write bytes: 657777664; Read time: 5; Write time: 1933 02:36:36 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345547264; Write bytes: 347305984; Read time: 26522; Write time: 750 02:36:36 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 331776; Write bytes: 300514304; Read time: 5; Write time: 1148 02:36:36 INFO - Running post-run listener: _upload_blobber_files 02:36:36 INFO - Blob upload gear active. 02:36:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:36:36 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:36:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:36:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:36:37 INFO - (blobuploader) - INFO - Open directory for files ... 02:36:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:36:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:36:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:36:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:36:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:36:37 INFO - (blobuploader) - INFO - Done attempting. 02:36:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:36:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:36:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:36:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:36:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:36:38 INFO - (blobuploader) - INFO - Done attempting. 02:36:38 INFO - (blobuploader) - INFO - Iteration through files over. 02:36:38 INFO - Return code: 0 02:36:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:36:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:36:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c259f4cef495667b7d6fc2f6b63ee59ae4622611d3698a90c7d338bcf1190350fe39f07f3fe12a4966091d0d94204562f0fd670751043fdd565887ce3f57bf96", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4224ae236a9d1e762474f8ba6b42a750b1e5fd364444ee20997d18dcf778c1b800b909fd46fd84d41f0cb9796e3a5aadc0a8396fe2c52cb4b480e5ba8f376d61"} 02:36:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:36:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:36:38 INFO - Contents: 02:36:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c259f4cef495667b7d6fc2f6b63ee59ae4622611d3698a90c7d338bcf1190350fe39f07f3fe12a4966091d0d94204562f0fd670751043fdd565887ce3f57bf96", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4224ae236a9d1e762474f8ba6b42a750b1e5fd364444ee20997d18dcf778c1b800b909fd46fd84d41f0cb9796e3a5aadc0a8396fe2c52cb4b480e5ba8f376d61"} 02:36:38 INFO - Running post-run listener: copy_logs_to_upload_dir 02:36:38 INFO - Copying logs to upload dir... 02:36:38 INFO - mkdir: /builds/slave/test/build/upload/logs 02:36:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.591976 ========= master_lag: 1.36 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-05-20 02:36:40.210836) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 02:36:40.215203) ========= 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.Fx9Hp0JB4n/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.S8kgpjbyeI/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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/Mozilla-Aurora/sha512/c259f4cef495667b7d6fc2f6b63ee59ae4622611d3698a90c7d338bcf1190350fe39f07f3fe12a4966091d0d94204562f0fd670751043fdd565887ce3f57bf96", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4224ae236a9d1e762474f8ba6b42a750b1e5fd364444ee20997d18dcf778c1b800b909fd46fd84d41f0cb9796e3a5aadc0a8396fe2c52cb4b480e5ba8f376d61"} build_url:https://queue.taskcluster.net/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011277 build_url: 'https://queue.taskcluster.net/v1/task/GfDAG_CzTDKrTMhuH1AA4w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c259f4cef495667b7d6fc2f6b63ee59ae4622611d3698a90c7d338bcf1190350fe39f07f3fe12a4966091d0d94204562f0fd670751043fdd565887ce3f57bf96", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4224ae236a9d1e762474f8ba6b42a750b1e5fd364444ee20997d18dcf778c1b800b909fd46fd84d41f0cb9796e3a5aadc0a8396fe2c52cb4b480e5ba8f376d61"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 02:36:40.253081) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-05-20 02:36:40.253790) ========= 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.Fx9Hp0JB4n/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.S8kgpjbyeI/Listeners TMPDIR=/var/folders/xm/n27ws1sd1t3406fsw7fnw23r00000w/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.005106 ========= master_lag: 5.42 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-05-20 02:36:45.675903) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 02:36:45.676231) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 02:36:45.735524) ========= ========= Total master_lag: 6.87 =========