builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0142 starttime: 1461326855.09 results: success (0) buildid: 20160422030223 builduid: 4499c409e49941e4810cca90538d9a79 revision: 0891f0fa044cba28024849803e170ed7700e01e0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 05:07:35.092932) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 05:07:35.093349) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-04-22 05:07:35.093629) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-04-22 05:07:38.502268) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:07:38.502561) ========= 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.G7Fmz1erkl/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.gyBDv8BDwb/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-22 05:07:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 196M=0s 2016-04-22 05:07:39 (196 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.507045 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:07:39.047541) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:07:39.047863) ========= 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.G7Fmz1erkl/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.gyBDv8BDwb/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.030260 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:07:39.104366) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:07:39.104713) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0891f0fa044cba28024849803e170ed7700e01e0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0891f0fa044cba28024849803e170ed7700e01e0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.G7Fmz1erkl/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.gyBDv8BDwb/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-22 05:07:39,180 truncating revision to first 12 chars 2016-04-22 05:07:39,180 Setting DEBUG logging. 2016-04-22 05:07:39,180 attempt 1/10 2016-04-22 05:07:39,180 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0891f0fa044c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-22 05:07:39,819 unpacking tar archive at: mozilla-central-0891f0fa044c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.881889 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:07:40.005829) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 05:07:40.006158) ========= script_repo_revision: 0891f0fa044cba28024849803e170ed7700e01e0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 05:07:40.006601) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 05:07:40.006872) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 05:07:40.023281) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-04-22 05:07:40.023603) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.G7Fmz1erkl/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.gyBDv8BDwb/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:07:40 INFO - MultiFileLogger online at 20160422 05:07:40 in /builds/slave/test 05:07:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:07:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:07:40 INFO - {'append_to_log': False, 05:07:40 INFO - 'base_work_dir': '/builds/slave/test', 05:07:40 INFO - 'blob_upload_branch': 'Firefox', 05:07:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:07:40 INFO - 'branch': 'Firefox', 05:07:40 INFO - 'buildbot_json_path': 'buildprops.json', 05:07:40 INFO - 'config_files': ('talos/mac_config.py',), 05:07:40 INFO - 'default_actions': ('clobber', 05:07:40 INFO - 'read-buildbot-config', 05:07:40 INFO - 'download-and-extract', 05:07:40 INFO - 'populate-webroot', 05:07:40 INFO - 'create-virtualenv', 05:07:40 INFO - 'install', 05:07:40 INFO - 'run-tests'), 05:07:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:07:40 INFO - 'download_minidump_stackwalk': True, 05:07:40 INFO - 'download_symbols': 'ondemand', 05:07:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:07:40 INFO - 'tooltool.py': '/tools/tooltool.py', 05:07:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:07:40 INFO - '/tools/misc-python/virtualenv.py')}, 05:07:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:07:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:07:40 INFO - 'installer_path': 'installer.exe', 05:07:40 INFO - 'log_level': 'info', 05:07:40 INFO - 'log_name': 'talos', 05:07:40 INFO - 'log_to_console': True, 05:07:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:07:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:07:40 INFO - 'opt_config_files': (), 05:07:40 INFO - 'pip_index': False, 05:07:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:07:40 INFO - 'cmd': ('bash', 05:07:40 INFO - '-c', 05:07:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:07:40 INFO - 'enabled': True, 05:07:40 INFO - 'halt_on_failure': False, 05:07:40 INFO - 'name': 'check_screen_resolution'},), 05:07:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:07:40 INFO - 'cmd': ('bash', 05:07:40 INFO - '-c', 05:07:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:07:40 INFO - 'enabled': True, 05:07:40 INFO - 'halt_on_failure': False, 05:07:40 INFO - 'name': 'check_screen_resolution'},), 05:07:40 INFO - 'run_cmd_checks_enabled': True, 05:07:40 INFO - 'sps_profile': False, 05:07:40 INFO - 'sps_profile_interval': 0, 05:07:40 INFO - 'suite': 'dromaeojs-e10s', 05:07:40 INFO - 'system_bits': '32', 05:07:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:07:40 INFO - 'title': 't-yosemite-r7-0142', 05:07:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:07:40 INFO - 'use_talos_json': True, 05:07:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:07:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:07:40 INFO - 'work_dir': 'build'} 05:07:40 INFO - ##### 05:07:40 INFO - ##### Running clobber step. 05:07:40 INFO - ##### 05:07:40 INFO - Running pre-action listener: _resource_record_pre_action 05:07:40 INFO - Running main action method: clobber 05:07:40 INFO - rmtree: /builds/slave/test/build 05:07:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:07:41 INFO - Running post-action listener: _resource_record_post_action 05:07:41 INFO - ##### 05:07:41 INFO - ##### Running read-buildbot-config step. 05:07:41 INFO - ##### 05:07:41 INFO - Running pre-action listener: _resource_record_pre_action 05:07:41 INFO - Running main action method: read_buildbot_config 05:07:41 INFO - Using buildbot properties: 05:07:41 INFO - { 05:07:41 INFO - "project": "", 05:07:41 INFO - "product": "firefox", 05:07:41 INFO - "script_repo_revision": "production", 05:07:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:07:41 INFO - "repository": "", 05:07:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:07:41 INFO - "buildid": "20160422030223", 05:07:41 INFO - "stage_platform": "macosx64", 05:07:41 INFO - "basedir": "/builds/slave/test", 05:07:41 INFO - "nightly_build": "True", 05:07:41 INFO - "buildnumber": 252, 05:07:41 INFO - "slavename": "t-yosemite-r7-0142", 05:07:41 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:07:41 INFO - "platform": "yosemite_r7", 05:07:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:07:41 INFO - "branch": "mozilla-central", 05:07:41 INFO - "repo_path": "mozilla-central", 05:07:41 INFO - "revision": "0891f0fa044cba28024849803e170ed7700e01e0", 05:07:41 INFO - "pgo_build": "False", 05:07:41 INFO - "builduid": "4499c409e49941e4810cca90538d9a79", 05:07:41 INFO - "slavebuilddir": "test" 05:07:41 INFO - } 05:07:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:07:41 INFO - Running post-action listener: _resource_record_post_action 05:07:41 INFO - ##### 05:07:41 INFO - ##### Running download-and-extract step. 05:07:41 INFO - ##### 05:07:41 INFO - Running pre-action listener: _resource_record_pre_action 05:07:41 INFO - Running main action method: download_and_extract 05:07:41 INFO - mkdir: /builds/slave/test/build/tests 05:07: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')]} 05:07:41 INFO - https://queue.taskcluster.net/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:07:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:07:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:07:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:07:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 05:07:45 INFO - Downloaded 1199 bytes. 05:07:45 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:07:45 INFO - Using the following test package requirements: 05:07:45 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:07:45 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:07:45 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:07:45 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:07:45 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:07:45 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:07:45 INFO - u'jsshell-mac.zip'], 05:07:45 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:07:45 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:07:45 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:07:45 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:07:45 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:07:45 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:07:45 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:07:45 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:07:45 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:07:45 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:07:45 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:07:45 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:07: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')]} 05:07:45 INFO - https://queue.taskcluster.net/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:07:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:07:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:07:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:07:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:07:46 INFO - Downloaded 18105858 bytes. 05:07:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:07:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:07:50 INFO - Return code: 0 05:07:50 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:07: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')]} 05:07:50 INFO - https://queue.taskcluster.net/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:07:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:07:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:07:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:07:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:07:57 INFO - Downloaded 18105858 bytes. 05:07:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:07:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:08:00 INFO - Return code: 0 05:08: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')]} 05:08:00 INFO - https://queue.taskcluster.net/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:08:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:08:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:08:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:08:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:08:01 INFO - Downloaded 10845952 bytes. 05:08:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:08:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:08:01 INFO - Return code: 0 05:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:01 INFO - https://queue.taskcluster.net/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:08:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:08:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:08:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:08:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 05:08:06 INFO - Downloaded 110169040 bytes. 05:08:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:08:06 INFO - mkdir: /builds/slave/test/properties 05:08:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:08:06 INFO - Writing to file /builds/slave/test/properties/build_url 05:08:06 INFO - Contents: 05:08:06 INFO - build_url:https://queue.taskcluster.net/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:08:08 INFO - Running post-action listener: _resource_record_post_action 05:08:08 INFO - Running post-action listener: set_extra_try_arguments 05:08:08 INFO - ##### 05:08:08 INFO - ##### Running populate-webroot step. 05:08:08 INFO - ##### 05:08:08 INFO - Running pre-action listener: _resource_record_pre_action 05:08:08 INFO - Running main action method: populate_webroot 05:08:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:08:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:08:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:08:08 INFO - '--tppagecycles', 05:08:08 INFO - '7'], 05:08:08 INFO - 'tests': ['tsvgm']}}, 05:08:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:08:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:08:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:08:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:08:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:08:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:08:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:08 INFO - 'talos_options': ['--disable-e10s'], 05:08:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:08 INFO - 'talos_options': ['--disable-e10s'], 05:08:08 INFO - 'tests': ['damp', 'tps']}, 05:08:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:08 INFO - 'tests': ['damp', 'tps']}, 05:08:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:08:08 INFO - 'tests': ['dromaeo_dom']}, 05:08:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:08:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:08:08 INFO - 'tests': ['a11yr', 05:08:08 INFO - 'ts_paint', 05:08:08 INFO - 'tpaint', 05:08:08 INFO - 'sessionrestore', 05:08:08 INFO - 'sessionrestore_no_auto_restore', 05:08:08 INFO - 'tabpaint']}, 05:08:08 INFO - 'other-e10s': {'tests': ['a11yr', 05:08:08 INFO - 'ts_paint', 05:08:08 INFO - 'tpaint', 05:08:08 INFO - 'sessionrestore', 05:08:08 INFO - 'sessionrestore_no_auto_restore', 05:08:08 INFO - 'tabpaint']}, 05:08:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:08:08 INFO - 'ts_paint', 05:08:08 INFO - 'tpaint', 05:08:08 INFO - 'sessionrestore', 05:08:08 INFO - 'sessionrestore_no_auto_restore', 05:08:08 INFO - 'tabpaint']}, 05:08:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:08:08 INFO - 'ts_paint', 05:08:08 INFO - 'tpaint', 05:08:08 INFO - 'sessionrestore', 05:08:08 INFO - 'sessionrestore_no_auto_restore', 05:08:08 INFO - 'tabpaint']}, 05:08:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:08:08 INFO - 'tests': ['a11yr', 05:08:08 INFO - 'ts_paint', 05:08:08 INFO - 'tpaint', 05:08:08 INFO - 'sessionrestore', 05:08:08 INFO - 'sessionrestore_no_auto_restore', 05:08:08 INFO - 'tabpaint']}, 05:08:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:08:08 INFO - 'tests': ['a11yr', 05:08:08 INFO - 'ts_paint', 05:08:08 INFO - 'tpaint', 05:08:08 INFO - 'sessionrestore', 05:08:08 INFO - 'sessionrestore_no_auto_restore', 05:08:08 INFO - 'tabpaint']}, 05:08:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:08:08 INFO - 'tests': ['tsvgx', 05:08:08 INFO - 'tsvgr_opacity', 05:08:08 INFO - 'tart', 05:08:08 INFO - 'tscrollx', 05:08:08 INFO - 'cart']}, 05:08:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:08:08 INFO - 'tsvgr_opacity', 05:08:08 INFO - 'tart', 05:08:08 INFO - 'tscrollx', 05:08:08 INFO - 'cart']}, 05:08:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:08 INFO - 'talos_options': ['--disable-e10s'], 05:08:08 INFO - 'tests': ['tp5o']}, 05:08:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:08 INFO - 'tests': ['tp5o']}, 05:08:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:08 INFO - 'talos_options': ['--disable-e10s', 05:08:08 INFO - '--xperf_path', 05:08:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:08 INFO - 'tests': ['tp5n']}, 05:08:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:08 INFO - 'talos_options': ['--xperf_path', 05:08:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:08 INFO - 'tests': ['tp5n']}}, 05:08:08 INFO - 'talos.zip': {'path': '', 05:08:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:08:08 INFO - Running post-action listener: _resource_record_post_action 05:08:08 INFO - ##### 05:08:08 INFO - ##### Running create-virtualenv step. 05:08:08 INFO - ##### 05:08:08 INFO - Running pre-action listener: _resource_record_pre_action 05:08:08 INFO - Running main action method: create_virtualenv 05:08:08 INFO - Creating virtualenv /builds/slave/test/build/venv 05:08:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:08:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:08:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:08:08 INFO - Using real prefix '/tools/python27' 05:08:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:08:09 INFO - Installing distribute.............................................................................................................................................................................................done. 05:08:11 INFO - Installing pip.................done. 05:08:11 INFO - Return code: 0 05:08:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:08:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105a6ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a7b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce0c99f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10590ae00>, '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 0x105773db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105905b20>, '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.G7Fmz1erkl/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.gyBDv8BDwb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:08:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G7Fmz1erkl/Render', 05:08:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:11 INFO - 'HOME': '/Users/cltbld', 05:08:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:11 INFO - 'LOGNAME': 'cltbld', 05:08:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:11 INFO - 'MOZ_NO_REMOTE': '1', 05:08:11 INFO - 'NO_EM_RESTART': '1', 05:08:11 INFO - 'PAGER': '/bin/cat', 05:08:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:11 INFO - 'PWD': '/builds/slave/test', 05:08:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:11 INFO - 'SHELL': '/bin/bash', 05:08:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gyBDv8BDwb/Listeners', 05:08:11 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:08:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:11 INFO - 'USER': 'cltbld', 05:08:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:11 INFO - 'XPC_FLAGS': '0x0', 05:08:11 INFO - 'XPC_SERVICE_NAME': '0', 05:08:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:08:12 INFO - Cleaning up... 05:08:12 INFO - Return code: 0 05:08:12 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:08: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')]} 05:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08: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')]} 05:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105a6ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a7b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce0c99f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10590ae00>, '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 0x105773db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105905b20>, '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.G7Fmz1erkl/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.gyBDv8BDwb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:08:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:08:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G7Fmz1erkl/Render', 05:08:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:12 INFO - 'HOME': '/Users/cltbld', 05:08:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:12 INFO - 'LOGNAME': 'cltbld', 05:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:12 INFO - 'MOZ_NO_REMOTE': '1', 05:08:12 INFO - 'NO_EM_RESTART': '1', 05:08:12 INFO - 'PAGER': '/bin/cat', 05:08:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:12 INFO - 'PWD': '/builds/slave/test', 05:08:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:12 INFO - 'SHELL': '/bin/bash', 05:08:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gyBDv8BDwb/Listeners', 05:08:12 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:08:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:12 INFO - 'USER': 'cltbld', 05:08:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:12 INFO - 'XPC_FLAGS': '0x0', 05:08:12 INFO - 'XPC_SERVICE_NAME': '0', 05:08:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:12 INFO - Downloading/unpacking psutil>=0.7.1 05:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:08:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:08:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:08:15 INFO - Installing collected packages: psutil 05:08:15 INFO - Running setup.py install for psutil 05:08:15 INFO - building 'psutil._psutil_osx' extension 05:08:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:08:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:08:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:08:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:08:16 INFO - building 'psutil._psutil_posix' extension 05:08:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:08:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:08:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:08:16 INFO - ^ 05:08:16 INFO - 1 warning generated. 05:08:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:08:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:08:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:08:16 INFO - Successfully installed psutil 05:08:16 INFO - Cleaning up... 05:08:17 INFO - Return code: 0 05:08:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105a6ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a7b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce0c99f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10590ae00>, '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 0x105773db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105905b20>, '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.G7Fmz1erkl/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.gyBDv8BDwb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:08:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:08:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G7Fmz1erkl/Render', 05:08:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:17 INFO - 'HOME': '/Users/cltbld', 05:08:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:17 INFO - 'LOGNAME': 'cltbld', 05:08:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:17 INFO - 'MOZ_NO_REMOTE': '1', 05:08:17 INFO - 'NO_EM_RESTART': '1', 05:08:17 INFO - 'PAGER': '/bin/cat', 05:08:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:17 INFO - 'PWD': '/builds/slave/test', 05:08:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:17 INFO - 'SHELL': '/bin/bash', 05:08:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gyBDv8BDwb/Listeners', 05:08:17 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:08:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:17 INFO - 'USER': 'cltbld', 05:08:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:17 INFO - 'XPC_FLAGS': '0x0', 05:08:17 INFO - 'XPC_SERVICE_NAME': '0', 05:08:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:19 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:08:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:08:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:08:19 INFO - Installing collected packages: mozsystemmonitor 05:08:19 INFO - Running setup.py install for mozsystemmonitor 05:08:19 INFO - Successfully installed mozsystemmonitor 05:08:19 INFO - Cleaning up... 05:08:19 INFO - Return code: 0 05:08:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:08:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105a6ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a7b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce0c99f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10590ae00>, '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 0x105773db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105905b20>, '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.G7Fmz1erkl/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.gyBDv8BDwb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:08:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:08:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G7Fmz1erkl/Render', 05:08:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:19 INFO - 'HOME': '/Users/cltbld', 05:08:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:19 INFO - 'LOGNAME': 'cltbld', 05:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:19 INFO - 'MOZ_NO_REMOTE': '1', 05:08:19 INFO - 'NO_EM_RESTART': '1', 05:08:19 INFO - 'PAGER': '/bin/cat', 05:08:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:19 INFO - 'PWD': '/builds/slave/test', 05:08:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:19 INFO - 'SHELL': '/bin/bash', 05:08:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gyBDv8BDwb/Listeners', 05:08:19 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:08:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:19 INFO - 'USER': 'cltbld', 05:08:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:19 INFO - 'XPC_FLAGS': '0x0', 05:08:19 INFO - 'XPC_SERVICE_NAME': '0', 05:08:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:20 INFO - Downloading/unpacking blobuploader==1.2.4 05:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:22 INFO - Downloading blobuploader-1.2.4.tar.gz 05:08:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:08:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:08: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 05:08: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 05:08: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 05:08: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 05:08: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 05:08: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 05:08:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:08:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:23 INFO - Downloading docopt-0.6.1.tar.gz 05:08:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:08:23 INFO - Installing collected packages: blobuploader, requests, docopt 05:08:23 INFO - Running setup.py install for blobuploader 05:08:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:08:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:08:24 INFO - Running setup.py install for requests 05:08:24 INFO - Running setup.py install for docopt 05:08:24 INFO - Successfully installed blobuploader requests docopt 05:08:24 INFO - Cleaning up... 05:08:24 INFO - Return code: 0 05:08:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:24 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 0x105a6ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a7b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce0c99f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10590ae00>, '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 0x105773db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105905b20>, '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.G7Fmz1erkl/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.gyBDv8BDwb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:08:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G7Fmz1erkl/Render', 05:08:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:24 INFO - 'HOME': '/Users/cltbld', 05:08:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:24 INFO - 'LOGNAME': 'cltbld', 05:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:24 INFO - 'MOZ_NO_REMOTE': '1', 05:08:24 INFO - 'NO_EM_RESTART': '1', 05:08:24 INFO - 'PAGER': '/bin/cat', 05:08:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:24 INFO - 'PWD': '/builds/slave/test', 05:08:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:24 INFO - 'SHELL': '/bin/bash', 05:08:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gyBDv8BDwb/Listeners', 05:08:24 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:08:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:24 INFO - 'USER': 'cltbld', 05:08:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:24 INFO - 'XPC_FLAGS': '0x0', 05:08:24 INFO - 'XPC_SERVICE_NAME': '0', 05:08:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:08:25 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-OQuDIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:08:25 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-bJVWBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:08:25 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-0cLfap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:08:25 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-We49AH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:08:25 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-dAm_Cb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:08:25 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-2jJyyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:08:25 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-rYJIlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:08:25 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-3Hrmwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:08:25 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-6tMMgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:08:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:08:25 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-nmE_I_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:08:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-t5eLJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:08:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-jfj0N0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:08:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-BQkMXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:08:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-cVptTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:08:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Ih2k_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:08:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-WQahcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:08:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:08:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-n2im1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:08:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:08:26 INFO - Running setup.py install for manifestparser 05:08:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:08:26 INFO - Running setup.py install for mozcrash 05:08:27 INFO - Running setup.py install for mozdebug 05:08:27 INFO - Running setup.py install for mozdevice 05:08:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:08:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:08:27 INFO - Running setup.py install for mozfile 05:08:27 INFO - Running setup.py install for mozhttpd 05:08:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:08:27 INFO - Running setup.py install for mozinfo 05:08:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:08:27 INFO - Running setup.py install for mozInstall 05:08:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:08:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:08:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:08:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:08:27 INFO - Running setup.py install for mozleak 05:08:28 INFO - Running setup.py install for mozlog 05:08:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:08:28 INFO - Running setup.py install for moznetwork 05:08:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:08:28 INFO - Running setup.py install for mozprocess 05:08:28 INFO - Running setup.py install for mozprofile 05:08:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:08:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:08:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:08:28 INFO - Running setup.py install for mozrunner 05:08:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:08:28 INFO - Running setup.py install for mozscreenshot 05:08:29 INFO - Running setup.py install for moztest 05:08:29 INFO - Running setup.py install for mozversion 05:08:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:08:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:08:29 INFO - Cleaning up... 05:08:29 INFO - Return code: 0 05:08:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:29 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 0x105a6ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a7b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce0c99f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10590ae00>, '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 0x105773db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105905b20>, '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.G7Fmz1erkl/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.gyBDv8BDwb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:08:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G7Fmz1erkl/Render', 05:08:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:29 INFO - 'HOME': '/Users/cltbld', 05:08:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:29 INFO - 'LOGNAME': 'cltbld', 05:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:29 INFO - 'MOZ_NO_REMOTE': '1', 05:08:29 INFO - 'NO_EM_RESTART': '1', 05:08:29 INFO - 'PAGER': '/bin/cat', 05:08:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:29 INFO - 'PWD': '/builds/slave/test', 05:08:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:29 INFO - 'SHELL': '/bin/bash', 05:08:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gyBDv8BDwb/Listeners', 05:08:29 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:08:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:29 INFO - 'USER': 'cltbld', 05:08:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:29 INFO - 'XPC_FLAGS': '0x0', 05:08:29 INFO - 'XPC_SERVICE_NAME': '0', 05:08:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:08:29 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-_TwuEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:08:29 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:08:29 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-knVvqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:08:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:08:29 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-My_Bya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:08:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:08:29 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-GUufPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:08:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-QhyruJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:08:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-MlYnla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:08:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-QSo1RF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:08:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-RbMmqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:08:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-1wh8hQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:08:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Q_MDzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:08:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-eBfm5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:08:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-RVqBZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:08:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:08:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-V34Amj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:08:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:08:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-tOW9qh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:08:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:08:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-G9V4eZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:08:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:08:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-wN_CB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:08:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:08:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-6WPgwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:08:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:08:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:08:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:08:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:08:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:08:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:08:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:33 INFO - Downloading blessings-1.6.tar.gz 05:08:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:08:33 INFO - Installing collected packages: blessings 05:08:33 INFO - Running setup.py install for blessings 05:08:34 INFO - Successfully installed blessings 05:08:34 INFO - Cleaning up... 05:08:34 INFO - Return code: 0 05:08:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:08:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:08:34 INFO - Reading from file tmpfile_stdout 05:08:34 INFO - Current package versions: 05:08:34 INFO - blessings == 1.6 05:08:34 INFO - blobuploader == 1.2.4 05:08:34 INFO - docopt == 0.6.1 05:08:34 INFO - manifestparser == 1.1 05:08:34 INFO - mozInstall == 1.12 05:08:34 INFO - mozcrash == 0.17 05:08:34 INFO - mozdebug == 0.1 05:08:34 INFO - mozdevice == 0.48 05:08:34 INFO - mozfile == 1.2 05:08:34 INFO - mozhttpd == 0.7 05:08:34 INFO - mozinfo == 0.9 05:08:34 INFO - mozleak == 0.1 05:08:34 INFO - mozlog == 3.1 05:08:34 INFO - moznetwork == 0.27 05:08:34 INFO - mozprocess == 0.22 05:08:34 INFO - mozprofile == 0.28 05:08:34 INFO - mozrunner == 6.11 05:08:34 INFO - mozscreenshot == 0.1 05:08:34 INFO - mozsystemmonitor == 0.0 05:08:34 INFO - moztest == 0.7 05:08:34 INFO - mozversion == 1.4 05:08:34 INFO - psutil == 3.1.1 05:08:34 INFO - requests == 1.2.3 05:08:34 INFO - wsgiref == 0.1.2 05:08:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:34 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 0x105a6ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a7b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce0c99f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10590ae00>, '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 0x105773db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105905b20>, '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.G7Fmz1erkl/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.gyBDv8BDwb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:08:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G7Fmz1erkl/Render', 05:08:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:34 INFO - 'HOME': '/Users/cltbld', 05:08:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:34 INFO - 'LOGNAME': 'cltbld', 05:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:34 INFO - 'MOZ_NO_REMOTE': '1', 05:08:34 INFO - 'NO_EM_RESTART': '1', 05:08:34 INFO - 'PAGER': '/bin/cat', 05:08:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:34 INFO - 'PWD': '/builds/slave/test', 05:08:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:34 INFO - 'SHELL': '/bin/bash', 05:08:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gyBDv8BDwb/Listeners', 05:08:34 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:08:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:34 INFO - 'USER': 'cltbld', 05:08:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:34 INFO - 'XPC_FLAGS': '0x0', 05:08:34 INFO - 'XPC_SERVICE_NAME': '0', 05:08:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:08:34 INFO - Cleaning up... 05:08:34 INFO - Return code: 0 05:08:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:34 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 0x105a6ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a7b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce0c99f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10590ae00>, '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 0x105773db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105905b20>, '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.G7Fmz1erkl/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.gyBDv8BDwb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:08:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G7Fmz1erkl/Render', 05:08:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:34 INFO - 'HOME': '/Users/cltbld', 05:08:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:34 INFO - 'LOGNAME': 'cltbld', 05:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:34 INFO - 'MOZ_NO_REMOTE': '1', 05:08:34 INFO - 'NO_EM_RESTART': '1', 05:08:34 INFO - 'PAGER': '/bin/cat', 05:08:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:34 INFO - 'PWD': '/builds/slave/test', 05:08:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:34 INFO - 'SHELL': '/bin/bash', 05:08:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gyBDv8BDwb/Listeners', 05:08:34 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:08:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:34 INFO - 'USER': 'cltbld', 05:08:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:34 INFO - 'XPC_FLAGS': '0x0', 05:08:34 INFO - 'XPC_SERVICE_NAME': '0', 05:08:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:34 INFO - Downloading/unpacking jsonschema 05:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:08:37 INFO - Downloading/unpacking functools32 (from jsonschema) 05:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:37 INFO - Downloading functools32-3.2.3-2.tar.gz 05:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:08:38 INFO - warning: no files found matching '*.txt' 05:08:38 INFO - no previously-included directories found matching 'build' 05:08:38 INFO - no previously-included directories found matching 'dist' 05:08:38 INFO - no previously-included directories found matching '.git*' 05:08:38 INFO - Installing collected packages: jsonschema, functools32 05:08:38 INFO - Running setup.py install for functools32 05:08:38 INFO - warning: no files found matching '*.txt' 05:08:38 INFO - no previously-included directories found matching 'build' 05:08:38 INFO - no previously-included directories found matching 'dist' 05:08:38 INFO - no previously-included directories found matching '.git*' 05:08:38 INFO - Successfully installed jsonschema functools32 05:08:38 INFO - Cleaning up... 05:08:38 INFO - Return code: 0 05:08:38 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:38 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 0x105a6ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a7b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcce0c99f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10590ae00>, '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 0x105773db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105905b20>, '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.G7Fmz1erkl/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.gyBDv8BDwb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:08:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:08:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G7Fmz1erkl/Render', 05:08:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:38 INFO - 'HOME': '/Users/cltbld', 05:08:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:38 INFO - 'LOGNAME': 'cltbld', 05:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:38 INFO - 'MOZ_NO_REMOTE': '1', 05:08:38 INFO - 'NO_EM_RESTART': '1', 05:08:38 INFO - 'PAGER': '/bin/cat', 05:08:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:38 INFO - 'PWD': '/builds/slave/test', 05:08:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:38 INFO - 'SHELL': '/bin/bash', 05:08:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gyBDv8BDwb/Listeners', 05:08:38 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:08:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:38 INFO - 'USER': 'cltbld', 05:08:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:38 INFO - 'XPC_FLAGS': '0x0', 05:08:38 INFO - 'XPC_SERVICE_NAME': '0', 05:08:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:38 INFO - Downloading/unpacking flake8 05:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:08:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:08:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:41 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:08:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:08:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:08:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:08:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:08:42 INFO - no previously-included directories found matching 'docs/_build' 05:08:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:08:42 INFO - Running setup.py install for pep8 05:08:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:08:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:08:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:08:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:08:42 INFO - no previously-included directories found matching 'docs/_build' 05:08:42 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:08:42 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:08:42 INFO - Cleaning up... 05:08:42 INFO - Return code: 0 05:08:42 INFO - Running post-action listener: _resource_record_post_action 05:08:42 INFO - Running post-action listener: _start_resource_monitoring 05:08:42 INFO - Starting resource monitoring. 05:08:42 INFO - ##### 05:08:42 INFO - ##### Running install step. 05:08:42 INFO - ##### 05:08:42 INFO - Running pre-action listener: _resource_record_pre_action 05:08:42 INFO - Running main action method: install 05:08:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:08:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:08:42 INFO - Reading from file tmpfile_stdout 05:08:42 INFO - Detecting whether we're running mozinstall >=1.0... 05:08:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:08:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:08:43 INFO - Reading from file tmpfile_stdout 05:08:43 INFO - Output received: 05:08:43 INFO - Usage: mozinstall [options] installer 05:08:43 INFO - Options: 05:08:43 INFO - -h, --help show this help message and exit 05:08:43 INFO - -d DEST, --destination=DEST 05:08:43 INFO - Directory to install application into. [default: 05:08:43 INFO - "/builds/slave/test"] 05:08:43 INFO - --app=APP Application being installed. [default: firefox] 05:08:43 INFO - mkdir: /builds/slave/test/build/application 05:08:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:08:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:09:16 INFO - Reading from file tmpfile_stdout 05:09:16 INFO - Output received: 05:09:16 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:09:16 INFO - Running post-action listener: _resource_record_post_action 05:09:16 INFO - ##### 05:09:16 INFO - ##### Running run-tests step. 05:09:16 INFO - ##### 05:09:16 INFO - Running pre-action listener: _resource_record_pre_action 05:09:16 INFO - Running main action method: run_tests 05:09:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:09:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:09:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:09:17 INFO - 2016-04-22 05:09:17.001 screenresolution[1644:7762] starting screenresolution argv=screenresolution get 05:09:17 INFO - 2016-04-22 05:09:17.003 screenresolution[1644:7762] Display 0: 1600x1200x32@60 05:09:17 INFO - 2016-04-22 05:09:17.009 screenresolution[1645:7764] starting screenresolution argv=screenresolution list 05:09:17 INFO - Available Modes on Display 0 05:09:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:09:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:09:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:09:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:09:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:09:17 INFO - Intel Iris: 05:09:17 INFO - Chipset Model: Intel Iris 05:09:17 INFO - Type: GPU 05:09:17 INFO - Bus: Built-In 05:09:17 INFO - VRAM (Dynamic, Max): 1536 MB 05:09:17 INFO - Vendor: Intel (0x8086) 05:09:17 INFO - Device ID: 0x0a2e 05:09:17 INFO - Revision ID: 0x0009 05:09:17 INFO - Displays: 05:09:17 INFO - Display: 05:09:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:09:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:09:17 INFO - Display Serial Number: 1600x1200 60 05:09:17 INFO - Main Display: Yes 05:09:17 INFO - Mirror: Off 05:09:17 INFO - Online: Yes 05:09:17 INFO - Rotation: Supported 05:09:17 INFO - Return code: 0 05:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:09:17 INFO - Python 2.7.3 05:09:17 INFO - Return code: 0 05:09:17 INFO - grabbing minidump binary from tooltool 05:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:17 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 0x10590ae00>, '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 0x105773db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105905b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:09:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:09:17 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:09:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:09:17 INFO - Return code: 0 05:09:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:09:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:09:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:09:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:09:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:09:18 INFO - Return code: 0 05:09:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0142 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:09:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G7Fmz1erkl/Render', 05:09:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:18 INFO - 'HOME': '/Users/cltbld', 05:09:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:18 INFO - 'LOGNAME': 'cltbld', 05:09:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:09:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:18 INFO - 'MOZ_NO_REMOTE': '1', 05:09:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:09:18 INFO - 'NO_EM_RESTART': '1', 05:09:18 INFO - 'PAGER': '/bin/cat', 05:09:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:18 INFO - 'PWD': '/builds/slave/test', 05:09:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:09:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:18 INFO - 'SHELL': '/bin/bash', 05:09:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gyBDv8BDwb/Listeners', 05:09:18 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:09:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:18 INFO - 'USER': 'cltbld', 05:09:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:18 INFO - 'XPC_FLAGS': '0x0', 05:09:18 INFO - 'XPC_SERVICE_NAME': '0', 05:09:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:09:18 INFO - mozversion application_buildid: 20160422030223 05:09:18 INFO - mozversion application_changeset: 0891f0fa044cba28024849803e170ed7700e01e0 05:09:18 INFO - mozversion application_display_name: Nightly 05:09:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:09:18 INFO - mozversion application_name: Firefox 05:09:18 INFO - mozversion application_remotingname: firefox 05:09:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:09:18 INFO - mozversion application_vendor: Mozilla 05:09:18 INFO - mozversion application_version: 48.0a1 05:09:18 INFO - mozversion platform_buildid: 20160422030223 05:09:18 INFO - mozversion platform_changeset: 0891f0fa044cba28024849803e170ed7700e01e0 05:09:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:09:18 INFO - mozversion platform_version: 48.0a1 05:09:18 INFO - using testdate: 1461326958 05:09:18 INFO - actual date: 1461326958 05:09:18 INFO - starting webserver on 'localhost:49277' 05:09:18 INFO - SUITE-START | Running 2 tests 05:09:18 INFO - TEST-START | dromaeo_css 05:09:18 INFO - operating with platform_type : mac_ 05:09:18 INFO - Initialising browser for dromaeo_css test... 05:09:18 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpeRrOxZ/profile http://localhost:49277/getInfo.html) 05:09:21 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 05:09:21 INFO - PROCESS | 1663 | colorDepth:24 05:09:21 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 05:09:21 INFO - PROCESS | 1663 | __metrics 05:09:22 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:09:22 INFO - TEST-INFO | 1663: exit 0 05:09:22 INFO - Browser initialized. 05:09:22 INFO - Running cycle 1/1 for dromaeo_css test... 05:09:22 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpeRrOxZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:09:34 INFO - PROCESS | 1668 | RSS: Main: 247963648 05:09:34 INFO - PROCESS | 1668 | 05:10:24 INFO - PROCESS | 1668 | 1461327024356 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:12:36 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 05:12:37 INFO - PROCESS | 1668 | RSS: Main: 260210688 05:12:37 INFO - PROCESS | 1668 | 05:15:39 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 05:15:40 INFO - PROCESS | 1668 | RSS: Main: 272039936 05:15:40 INFO - PROCESS | 1668 | 05:18:41 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 05:18:42 INFO - PROCESS | 1668 | RSS: Main: 273932288 05:18:42 INFO - PROCESS | 1668 | 05:21:44 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 05:21:44 INFO - PROCESS | 1668 | RSS: Main: 275537920 05:21:44 INFO - PROCESS | 1668 | 05:24:47 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 05:24:48 INFO - PROCESS | 1668 | RSS: Main: 278360064 05:24:48 INFO - PROCESS | 1668 | 05:27:50 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 05:27:51 INFO - PROCESS | 1668 | RSS: Main: 280252416 05:27:51 INFO - PROCESS | 1668 | 05:27:51 INFO - PROCESS | 1668 | __start_tp_report 05:27:51 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 05:27:51 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 05:27:51 INFO - PROCESS | 1668 | |i|pagename|runs| 05:27:51 INFO - PROCESS | 1668 | |0;dojo.html;3142.3872344892748;3478.0435380270414;3513.367593833109;3537.2041290709594;3548.8225588720575;4824.011077729066;5788.536917046642;5824.475797178253;5867.823965281038;5868.061110222366;5809.535237181026;5828;5865.442782935615;5919.585629005971;5986.790462333819;5215.734607395917;5237.45006774289;5263.657862238962;5293.179557168645;5341.893162136755;4602.171609110359;5329.973350133263;5350.224217488462;5363.651362661434;5413.052715774744;5075.796968121308;5208.5572726318305;5261.894762104775;5295.735213239361;5302.178162384856;5238.083335416325;5250.291210686547;5255.159174532095;5257.263983042377;5258.1849813279005;4812.446568644616;4897.314375987365;5244.79020839166;5265.6577322474295;5299.33758280215;5441.8572099859075;5706.172604972297;5749.913751293734;5758.856028599319;5818.8254352369495;6160.630362178284;6181.536384771115;6218.098375735493;6218.347073557256;6252.937470625281;5460;6090.8781824363705;6261.154744109578;6261.624302541816;6267.71795269214;5869.735861886183;5943.375945525744;5950.7917222896995;5957.1957785698805;5995.250593675791;6307.590006649553;6315.695129784488;6324.683765811783;6344;6362.554621176473;6105.823680184376;6163.753449861955;6236.6258024518675;6244.344343843831;6267.059941008794;6174.629522228681;6226.439620434183;6256.937430625727;6272.372762723691;6275.1842260506755;6095.740618599586;6143.603994442171;6180.133589767719;6246.968765156145;6256.468200202943;6146.0473626587955;6181.629102253879;6214.316425193225;6224.502039836802;6230.470410015108;4993.550580447777;5021.347224860745;5028.245763135559;5044.596432285409;5075.543201111844;4492.550744925481;4507.346434767006;4534.750588717587;4542.886427839265;4571.405717256735;5884.911726324109;5948.82153535386;5953.3451320354725;5992.790252341147;6043.456088952015;5357.4106848246665;6269.404406581368;6293.874122517524;6341.4609758170145;6366.84082897922;5433.402325744165;6005.6396616203165;6005.6997150142315;6021.969890150609;6029.854327677727;4281.935770963438;4667.533246675305;5049.267856160836;5477.890442191134;5527.695976721238;6373.235211774617;6398.872022559616;6490.675466226669;6504.1869766279215;6524.412802847766;6264.091706702411;6303.590266632654;6305.779297724557;6312.873742525216;6319;6003.6397816131175;6212.099245609452;6268.498520118471;6500.479961602966;6505.642189679613;5452.509274165344;6107.572469927062;6146.862830376394;6224.751009959551;6253.718582663882;6206.658633775186;6247.406496382836;6256.155419018374;6265.592736472115;6273.529485288713;4772.642051846195;6112.113743507078;6121.117656725397;6186.288576813724;6211.223597050369;6186.876262474635;6210;6227.564070515021;6233.844153896189;6235.532335074979;5293.470652934676;6033.336333003454;6117.938820611737;6140.846478838065;6174.320824709368;6097.373446762037;6137.7447333336595;6164.4143806338325;6189.978653522298;6250.124982502362;6154.599951003171;6170.352113028068;6174.104754810617;6178.042403427476;6178.351273116258 05:27:51 INFO - PROCESS | 1668 | |1;ext.html;517.1183132758645;520.21187900331;533.2214966149421;594.9796099689033;611.9877602447951;30368.08895733129;38074;38093.04767380817;38190.80904595477;38242.80878595607;38807.61192388075;39320.60679393205;39556.80221598895;39575.20849583007;39661.20677586446;9411.952940235306;9481.573329200202;9529.285303602222;9541.904580954206;9569.952150239274;9346.906530934672;9509.096635819586;9537.28470364722;9588.95205523975;9617.75955601112;9528.38065525742;9542.713718588453;9624.278179136592;9668.516574171263;9670.613175472972;9519.571619277114;9548.331616786792;9557;9611.471369074698;9612.279079069063;9511.952440237754;9578.425294482284;9640.228781697448;9642.662506812228;9686.273529485246;37690.62309376898;37827.810860945516;37837.621623783685;38130.42804357937;38479.422808657895;3200.8719651213923;3343.047231539024;3474.322507111114;3548.183917698918;3565.0552603560077;28122.296942576395;28150.155495335173;28181.872725090972;28835.134945951653;29228.415431691246;2764.3918337965615;2774.1400165948417;2780.9721902780916;2877.84171870547;2884.2933481297005;436843;437863;439210;440612;441751;34655;58390.41609583935;58476.707616462496;58828.70585647044;59035.704821475614;34108.976730698116;34768;34908.65091349054;35036.824815876265;35208.823955880056;14830.480933167288;14985.550433487013;15034;15046.473373431878;15078.623034424227;12433.564825231073;12643.304618246084;12739.42672579736;12777.105602607728;12824.29466379358;7836.138024817265;7934.127246002935;8017;8051.074126475413;8097.595120243965;3339.749518786101;3382.678645528671;3392.1858753898887;3393.3043726036103;3415.504751811023;886.9622541626313;970.2141265574907;1011.691434112588;1037.255932489109;1076.3757020927842;29366.853165734035;29561.26096847596;29578.556321655193;29673;30058.699413006027;1035.3270374256792;1106.882397481384;1123.4045955643521;1142.086330935265;1203.3622180244483;14527.201003944885;14754.40982360715;14848.777268340984;14878.80105777263;14920.40318387274;630449;638427;648291;654149;658892;57730;58322.833543328896;58812.117818232764;59011.704941475014;59024.1146382796;1457.4753088887992;1469.4048910191389;1491.828439729445;1492.149474799354;1498.7227362937892;28144.71855281421;28326.858365708864;28604.71395286104;28705.712942870305;28813.855930721053;47664.761676191396;48240;48513.27230091622;49739.75130124471;49804.25293620526;577675.1116244391;579483;580309;580518;580758;26159.869200653873;41111;43147.56852431435;43362.34956475718;43479.13041739211;36929.44605830968;45480;46907.76546117248;47789.761051194524;48115.73691082528;29309.681064352582;34230.657693423746;35437;35467.645323546436;37456;35793.82103089468;35795.64204357923;35980.820095899355;36702.44946325754;36709.26581468302;36342;36639.08402289964;36988;37842.43236351512;38426.807865960494;29278.56082158812;30430.84784576063;32129.196770080936;32131.51802723008;32172;24237.151699690425;24409.38976525601;24471.63292550649;24728;24892.75107248904 05:27:51 INFO - PROCESS | 1668 | |2;jquery.html;382.89470395641195;387.39566276608485;392.52112422844135;397.12695805652936;398.2950178184613;14506.854931450682;20318.59362812743;20603.48491287715;20629.793702062976;20717.792822071773;19944.800551994475;19982.800171998275;20330.898345508253;20716.171353145855;20772.584548309023;5667.3482549506925;5830.242068531106;5968.850778730544;6004.369541198177;6014.969925150368;5840.06558950568;5928.258967629046;5969.761209551612;5979.133025711291;6003.879922401549;5642.238297829782;5929.258842644669;6000.729967151467;6007.399260074001;6017.8194654160225;5711.885762284731;5853.443922827325;5933.703314834284;5984.0126379147605;5990.921634105859;5164.431912489623;5205.349331333583;5213.791448342062;5213.843584692465;5215.348081489814;20086.79913200864;22929;24914.750852491605;25627.359316017064;25999.870000650066;2102.190656597206;2109.3566462229014;2119.406566161462;2176.749673787519;2270.8410411271216;8207.507549546986;9628.988956159572;9653.517324133765;9880.258980576413;9903.80192396148;1688.2571668465957;1707.5731067233191;1719.389616686071;1750.798658154303;1810.583565779878;123755.76244237533;127899;128473.99999999907;130636;131581;9469.905300946903;11572.72700002999;11689.123315751352;11801.704957375963;11879.168458208016;14518.419263229353;14729.631759206106;14975;14987.775183372258;14991.625209369635;7658.96170519155;8246.845441638176;8548.273396761346;8676.305895528343;8742.38803283764;8639;8684.43551169172;8695.695650652196;8704.347173961978;8808.91191088081;4529.524399937959;4587.472440669299;4595.540445955445;4707.7881495332795;4709.3877795887;1943.8250557449896;1946.951326216856;1948.1817636592382;1950.9804901951084;1954.3843689237845;898.1781669772216;944.6268723854039;978.6574698855486;1016.2530540053056;1066.8879767624444;17363.739543906853;19464.1241144146;19894.403167904988;20422.661870503536;20634.793652063287;935.8382137262944;937.920276776468;940.2055263302499;963.1524258652481;1003.934744241622;8863.955680221687;8943.731688049369;10478.161747060218;10736.087432568163;10750.08624266946;596224;601524;612227;612397;613997;85960.71058934121;113810.43094784638;114347;131732;137836;950.0689324462066;963.1476143612953;980.6077568972468;985.0248254228405;987.5210522896533;17634.82365176332;17822.10889455496;17827.732584011508;17899.19453624616;18109;51112;51851;52650.94698106093;52715;53094;68818;76970;77582;77951.2204877944;81406;7620.123685775985;7777.844443110993;7804.141544430222;7924.524528528307;8252.21603947624;10680.359178449318;10737.838932415863;11508.424578771195;12355.270262163483;12459.190152640049;8737.257333126756;9108.908910911072;9987.001299870246;10429.374237545771;10778.13774898022;8717.215450609476;10303.690889273334;10380.948095259475;10423.478826058516;10691.786164276826;10370.688879333633;10584.788304233718;10669.57321707123;10790.73023174427;11197.160212984221;7709.845803083795;7888.526688398714;7916.485428447132;7920.247576480225;7933.286004259414;10290.536925838205;10650.946745266225;10841.67474975752;10937.179711521509;11051.557937682725 05:27:51 INFO - PROCESS | 1668 | |3;mootools.html;1562.9843701562984;1586.7857839191709;1650.488348611931;1805.2327760701698;1813.2746901239502;7933.730603103505;8017.238362355582;8614.267787238085;8883.777905552364;8893.555322233886;7401.555906645592;8060.717874874381;8639.308855291578;8762.167594078552;8777;2419.443527988564;2436.4396188876567;2472.8763561821925;2477.8389404688637;2481.3796550862285;2069.699893515447;2398.20859116492;2445.4375493636476;2446.645236440715;2460.5324988252264;2414.8309618326725;2446.107170882633;2457.127719659522;2458.2748089313695;2481.8510889346608;2402.987985060081;2412.19191570824;2425.842320249178;2435.916017372261;2445.987770061156;2415.5410472010262;2426.381272775431;2433.172721274775;2442.7312995570474;2444.1323330217874;8791.065965487582;8805.25155361795;8956.417832840909;8961.731148065568;8963.731088067369;5172.612054095958;5362.892742145174;5365.141577347645;5377.704226267554;5414.485623865727;3463.2727127303297;3515.2617950230483;3551.7158627309755;3562.91092722684;3581.803000834953;1020.9846852297221;1030.469308306223;1032.927695061346;1050.38027563737;1054.6888667843004;10236.692899213036;11223.71940701489;11228.214025018253;11452;11560.710982225512;4598.26427771555;4632.305154226826;4832.685875418087;4835.153848076572;4883.487233840396;9691.563879625433;9834.967328430555;9855.162311203485;9862.605495780232;9868.703938881845;5268.947310526923;5274;5334.359876814807;5338.386085600128;5373.2746079278795;4443.733375997386;4755.476897541268;4838.7338696372035;4927.334809800631;4936.555709986119;4022.597740225954;4043.454133691973;4166.479190101238;4173.83304667816;4187.309093999456;1320.0165876422127;1327.8141060251573;1335.5592654424017;1336.1315145155531;1352.0468070010618;870.1124852650262;892.9419587726777;922.4603606890045;932.3939439364332;959.3524371049514;5046.6719663221775;5178.119719647669;5906.616069955439;5984;6239.1577137085915;1002.4636819301638;1038.6832016234969;1067.530286673861;1070.2882583082164;1073.6778966310076;3919.92160156801;3936.15372694872;3981.7212795104065;4038.495188101487;4049.838006479708;10391.688249352532;10582.894171058344;10629.202809789296;10679.733006674895;10829.241953063367;10647.94676026615;10674.199435042403;10691.465426728633;10756.247062705692;10943.616973405893;7754.573498457752;8206.22040906113;8390.454620449651;8400.117987611335;8429.536375499283;5992.340842507408;5996.670183139795;6107.144999700135;6135.6932153393045;6229.478747717031;10013.299069065388;10041.246906482189;10147;10219.131373833308;10274.691759247235;9759.316847820584;9944.850827237453;10297.897021029694;10343.896561034293;10359.87622537363;7145.285471452813;7504.3246107850555;7590.962045189739;7701.537907725555;7727.42044346665;8269.875951860848;8443.408961372645;8490.872636910328;8550.957245213734;8564.657413703631;7422.072240969879;7546.924530754842;7553.660085296065;7578.355839753683;7600.391968642606;6403.231612206565;6982.825429364103;7336.119665640157;7444.925550744423;7508.699652013858;6095.35998720128;7149.213586505584;7220.205777364591;7233.782986510413;7240.203577606564;4863.513648635108;4875.366202393666;4984.302197692254;5032.597392208541;5158.0199762045095;4405.185040767468;4856.63575231852;4927.531884470969;4963.4540200577185;5113.51421614946 05:27:51 INFO - PROCESS | 1668 | |4;prototype.html;268.888147510046;295.57437290301976;304.5553491901823;314.03590975704594;315.76791058571933;5349.689326115103;5721.284839395081;6001.789937352193;6036.336003039673;6359.55483116182;5020.924686129711;6087.25633735253;6125.63246205228;6157.445829875311;6249.2500899892075;3990.6408423241974;4033.3143365627766;4304.837693822666;4553.977230113845;4582.258741678158;4444.799984000711;4574.336721175428;4589.449266088058;4597.793059331523;4598.425196850394;3891.7081218908697;4477.947682294658;4513.526079761627;4534.9546504534865;4535.439679851317;4144.254034273829;4421.734695918255;4551.749653769041;4561.29299958507;4598.908021839561;3772.5850156482766;3877.6316249956208;3886.5336159660737;3893.201893611831;3893.6301051400073;5899.970500147515;5905.852353691193;5914.4381283777975;5936.853467662146;5958.731857066442;4054.5945405459515;4690.874190194329;4915.5084491550915;5300.469953004668;5359.115745901921;4879.756012199376;5423.535645375727;5704.258446401983;6277.403646653561;6481.4166724994975;3592.3713350163616;4172.561881002482;4204.684648651363;4222.746635201897;4243.511996120455;5942.5840191186635;6756.493263005245;6826.897596536056;6866.969954506864;6881.724731010753;3400.3539327528183;3468.271662950758;4111.465509483748;4244.172386384687;4261.2542805009;3976.8409263629133;4290.764007979591;4319.179355922364;4323.286657701507;4362.498312694017;4450.777461126931;4634.702283360664;5049.434675250684;5056.64603477753;5160.638755287169;5445.346558413015;6141.324454310023;6336.732653469325;6352.968235158794;6456.580322279036;1432.8065711129072;1493.6333255071488;1535.877129829611;1554.0753251815152;1555.984440155584;997.9002099790108;1007.9899201008043;1021.4943602916604;1030.2427715629008;1034.462079718542;5696.174054762037;6051.243594550681;6052.636841789506;6080.908786368208;6083.904897118561;5420.322459692538;5532.889342213133;5838.737256823453;6812.8296792579595;6829.351211634887;5742.942570574324;6051.4553690168095;6073.18012068374;6188.1027251048345;6200.534959878028;3183.936321273608;3582.9283414331567;3590.983573798022;3610.3140403323287;3620.25784714133;6499.707513161825;6962.797113327926;7064.92935070653;7099.822504437327;7146.176330976674;6099.359567245464;6126.264848218153;6157.261128664589;6582.341765823475;7034.6834392452465;6642.302558231316;7063.234191452137;7129;7148.177959534616;7162.247963963709;6318.715657795502;6812.863742725019;6859.657017149023;6880.139982502187;6909.2745261748805;6073.267029836503;6109.8391305651785;6744.426723728342;6776.932230677828;6777.661116944231;6890.414314783299;6904.896426553505;7104.183018952886;7124.5725256484775;7135.892961605477;3650.671439570451;3881.525020492195;3996.6602838757867;4150.750954942713;4240.4063431120285;5703.317521331134;6464.806055818333;6700.564463309869;6884.759033433806;6892.241853396022;6580.440662543738;6652.501062420241;6662.333766623299;6663.233728121134;6693.966530167513;6292.514456789634;6636.966815166086;6665.966670166618;6679.966600166968;6700.363465470772;5709.51469125129;6505.674716264262;6515.576487528296;6575.145231119924;6584.835379115369;6074.605150665193;6488.94440974391;6658.3674550917585;6692.531522793358;6692.899606506004;5374.13062594931;5693.259876216065;6043.576949613661;6584.8024559263295;6584.814733348044 05:27:51 INFO - PROCESS | 1668 | |5;yui.html;615.7660089166118;718.4647437658941;733.1385621894275;737.8081698758322;749.1571981520789;14766;14892.776608350883;15178.39286428543;15235.390584376612;15239.923800380997;14099.78850317246;14153.363098660559;14226.71546569068;14260.429582816674;14270.928645356758;735.5108852613033;787.5865170785327;888.506878682331;907.809360034392;929.930255230857;880.3221519430035;882.355880207449;907.8229745199689;920.1718453391967;922.3174850610567;960.538941308169;968.0803236924914;983.4590974963777;987.012987012987;987.7234374375187;950.6340059077271;965.7054598347502;971.2666936462961;973.2797729680057;974.7758015656368;717.0713925466533;723.7756295548344;726.0597526203579;726.2773540327355;730.2697302697303;14509.564713058626;14586.562403127924;14646.853531464656;14670.413183472649;14892.702145957128;10025.979216626669;10932.945335273273;11822.758610345962;12329.260244385276;12891.097623166383;9946.35348702332;10124.139448146916;10187.185025197967;10200.285979981405;10816.567337306577;3491.930161396783;3507.368673638744;3516.771409858351;3553.6091029986683;3636.3999940009867;58860.234192974276;70855.99999999949;71681.92477112847;73360.99999999946;73422.63288683523;9412.764680883032;9484.336096473318;9589.568469418893;9701.272404569685;9850.261230407605;20874.06066727001;20951.266705665228;20993.265235716677;21068.051937662844;21374.572508549743;7631.580263085472;7795.883061754078;7833.216678332121;7889;8085.3531717462465;4139.399787030864;4145.79271036453;4195.16096780639;4219.97890010548;4335.501417337046;3111.4866047101973;3330.816805075744;3475.94786078209;3536.45184996326;3566.411542095525;637.0317117980643;642.881067002606;643.0322364840978;650.8405440667067;656.8423578341163;533.5118366694509;539.6842846934587;579.3395529096833;592.570386469806;609.1380696314665;6501.130912435085;7749.612519374122;7939.682412703427;8070.959645201737;8181.468204566684;557.8326502049368;560.9890028346289;585.7364186116265;595.5830918357167;597.3936454498687;3833.7316387852584;3844.961550384516;3847.6921846252235;3876.1084950460986;3921.7254792164276;82707;85498.57250713707;85919.57040214883;86528;86540;59636;75743.62128189324;76764;76936;77794;65056.67471662611;65687;66212;66723.66638166778;66936.66531667407;5460.563154947516;6139.3246742857355;7524.736634217776;7879.881801772864;7906.9209307906185;37253.441198382585;38083;38127.37789909954;40370.587029453825;40513.392299114945;55957.720211398686;60644;61697;64482;65573.68852623017;10846.674599761704;10913.890861091606;10955.671329860117;10974.957379048978;11178.496967636313;11774.411279435823;11848.28910265387;11855.703607409883;11979.28124312544;12001.099917506397;11402.20184587071;11468.770624587294;11521.020713239468;11914.285142891107;11950.092605500804;11410.657680269605;11543.942280288546;11624.128190385585;11667.00830429423;11864;9561.952190239004;11241.55033798639;11274.323540587591;11644.825327619923;11654.825177622173;9526.761211115521;9671.758206044906;9678.75803104928;10027.548760305657;10208.082476688849;9615.615375384907;9679.709608711468;9726.805463890823;9743.853842192515;9876 05:27:51 INFO - PROCESS | 1668 | __end_tp_report 05:27:51 INFO - PROCESS | 1668 | __start_cc_report 05:27:51 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,410 05:27:51 INFO - PROCESS | 1668 | __end_cc_report 05:27:51 INFO - PROCESS | 1668 | __startTimestamp1461328071106__endTimestamp 05:27:51 INFO - PROCESS | 1668 | 05:27:51 INFO - PROCESS | 1668 | ------- Summary: start ------- 05:27:51 INFO - PROCESS | 1668 | Number of tests: 6 05:27:51 INFO - PROCESS | 1668 | 05:27:51 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5769.32 Median:6090.88 stddev:667.40 (11.0%) stddev-sans-first:636.83 05:27:51 INFO - PROCESS | 1668 | Values: 3142.4 3478.0 3513.4 3537.2 3548.8 4824.0 5788.5 5824.5 5867.8 5868.1 5809.5 5828.0 5865.4 5919.6 5986.8 5215.7 5237.5 5263.7 5293.2 5341.9 4602.2 5330.0 5350.2 5363.7 5413.1 5075.8 5208.6 5261.9 5295.7 5302.2 5238.1 5250.3 5255.2 5257.3 5258.2 4812.4 4897.3 5244.8 5265.7 5299.3 5441.9 5706.2 5749.9 5758.9 5818.8 6160.6 6181.5 6218.1 6218.3 6252.9 5460.0 6090.9 6261.2 6261.6 6267.7 5869.7 5943.4 5950.8 5957.2 5995.3 6307.6 6315.7 6324.7 6344.0 6362.6 6105.8 6163.8 6236.6 6244.3 6267.1 6174.6 6226.4 6256.9 6272.4 6275.2 6095.7 6143.6 6180.1 6247.0 6256.5 6146.0 6181.6 6214.3 6224.5 6230.5 4993.6 5021.3 5028.2 5044.6 5075.5 4492.6 4507.3 4534.8 4542.9 4571.4 5884.9 5948.8 5953.3 5992.8 6043.5 5357.4 6269.4 6293.9 6341.5 6366.8 5433.4 6005.6 6005.7 6022.0 6029.9 4281.9 4667.5 5049.3 5477.9 5527.7 6373.2 6398.9 6490.7 6504.2 6524.4 6264.1 6303.6 6305.8 6312.9 6319.0 6003.6 6212.1 6268.5 6500.5 6505.6 5452.5 6107.6 6146.9 6224.8 6253.7 6206.7 6247.4 6256.2 6265.6 6273.5 4772.6 6112.1 6121.1 6186.3 6211.2 6186.9 6210.0 6227.6 6233.8 6235.5 5293.5 6033.3 6117.9 6140.8 6174.3 6097.4 6137.7 6164.4 6190.0 6250.1 6154.6 6170.4 6174.1 6178.0 6178.4 05:27:51 INFO - PROCESS | 1668 | 05:27:51 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:67274.76 Median:37759.22 stddev:150487.89 (398.5%) stddev-sans-first:150826.58 05:27:51 INFO - PROCESS | 1668 | Values: 517.1 520.2 533.2 595.0 612.0 30368.1 38074.0 38093.0 38190.8 38242.8 38807.6 39320.6 39556.8 39575.2 39661.2 9412.0 9481.6 9529.3 9541.9 9570.0 9346.9 9509.1 9537.3 9589.0 9617.8 9528.4 9542.7 9624.3 9668.5 9670.6 9519.6 9548.3 9557.0 9611.5 9612.3 9512.0 9578.4 9640.2 9642.7 9686.3 37690.6 37827.8 37837.6 38130.4 38479.4 3200.9 3343.0 3474.3 3548.2 3565.1 28122.3 28150.2 28181.9 28835.1 29228.4 2764.4 2774.1 2781.0 2877.8 2884.3 436843.0 437863.0 439210.0 440612.0 441751.0 34655.0 58390.4 58476.7 58828.7 59035.7 34109.0 34768.0 34908.7 35036.8 35208.8 14830.5 14985.6 15034.0 15046.5 15078.6 12433.6 12643.3 12739.4 12777.1 12824.3 7836.1 7934.1 8017.0 8051.1 8097.6 3339.7 3382.7 3392.2 3393.3 3415.5 887.0 970.2 1011.7 1037.3 1076.4 29366.9 29561.3 29578.6 29673.0 30058.7 1035.3 1106.9 1123.4 1142.1 1203.4 14527.2 14754.4 14848.8 14878.8 14920.4 630449.0 638427.0 648291.0 654149.0 658892.0 57730.0 58322.8 58812.1 59011.7 59024.1 1457.5 1469.4 1491.8 1492.1 1498.7 28144.7 28326.9 28604.7 28705.7 28813.9 47664.8 48240.0 48513.3 49739.8 49804.3 577675.1 579483.0 580309.0 580518.0 580758.0 26159.9 41111.0 43147.6 43362.3 43479.1 36929.4 45480.0 46907.8 47789.8 48115.7 29309.7 34230.7 35437.0 35467.6 37456.0 35793.8 35795.6 35980.8 36702.4 36709.3 36342.0 36639.1 36988.0 37842.4 38426.8 29278.6 30430.8 32129.2 32131.5 32172.0 24237.2 24409.4 24471.6 24728.0 24892.8 05:27:51 INFO - PROCESS | 1668 | 05:27:51 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:34896.29 Median:4558.50 stddev:101348.19 (2223.3%) stddev-sans-first:101599.37 05:27:51 INFO - PROCESS | 1668 | Values: 382.9 387.4 392.5 397.1 398.3 14506.9 20318.6 20603.5 20629.8 20717.8 19944.8 19982.8 20330.9 20716.2 20772.6 5667.3 5830.2 5968.9 6004.4 6015.0 5840.1 5928.3 5969.8 5979.1 6003.9 5642.2 5929.3 6000.7 6007.4 6017.8 5711.9 5853.4 5933.7 5984.0 5990.9 5164.4 5205.3 5213.8 5213.8 5215.3 20086.8 22929.0 24914.8 25627.4 25999.9 2102.2 2109.4 2119.4 2176.7 2270.8 8207.5 9629.0 9653.5 9880.3 9903.8 1688.3 1707.6 1719.4 1750.8 1810.6 123755.8 127899.0 128474.0 130636.0 131581.0 9469.9 11572.7 11689.1 11801.7 11879.2 14518.4 14729.6 14975.0 14987.8 14991.6 7659.0 8246.8 8548.3 8676.3 8742.4 8639.0 8684.4 8695.7 8704.3 8808.9 4529.5 4587.5 4595.5 4707.8 4709.4 1943.8 1947.0 1948.2 1951.0 1954.4 898.2 944.6 978.7 1016.3 1066.9 17363.7 19464.1 19894.4 20422.7 20634.8 935.8 937.9 940.2 963.2 1003.9 8864.0 8943.7 10478.2 10736.1 10750.1 596224.0 601524.0 612227.0 612397.0 613997.0 85960.7 113810.4 114347.0 131732.0 137836.0 950.1 963.1 980.6 985.0 987.5 17634.8 17822.1 17827.7 17899.2 18109.0 51112.0 51851.0 52650.9 52715.0 53094.0 68818.0 76970.0 77582.0 77951.2 81406.0 7620.1 7777.8 7804.1 7924.5 8252.2 10680.4 10737.8 11508.4 12355.3 12459.2 8737.3 9108.9 9987.0 10429.4 10778.1 8717.2 10303.7 10380.9 10423.5 10691.8 10370.7 10584.8 10669.6 10790.7 11197.2 7709.8 7888.5 7916.5 7920.2 7933.3 10290.5 10650.9 10841.7 10937.2 11051.6 05:27:51 INFO - PROCESS | 1668 | 05:27:51 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5716.15 Median:4847.68 stddev:3150.05 (65.0%) stddev-sans-first:3143.42 05:27:51 INFO - PROCESS | 1668 | Values: 1563.0 1586.8 1650.5 1805.2 1813.3 7933.7 8017.2 8614.3 8883.8 8893.6 7401.6 8060.7 8639.3 8762.2 8777.0 2419.4 2436.4 2472.9 2477.8 2481.4 2069.7 2398.2 2445.4 2446.6 2460.5 2414.8 2446.1 2457.1 2458.3 2481.9 2403.0 2412.2 2425.8 2435.9 2446.0 2415.5 2426.4 2433.2 2442.7 2444.1 8791.1 8805.3 8956.4 8961.7 8963.7 5172.6 5362.9 5365.1 5377.7 5414.5 3463.3 3515.3 3551.7 3562.9 3581.8 1021.0 1030.5 1032.9 1050.4 1054.7 10236.7 11223.7 11228.2 11452.0 11560.7 4598.3 4632.3 4832.7 4835.2 4883.5 9691.6 9835.0 9855.2 9862.6 9868.7 5268.9 5274.0 5334.4 5338.4 5373.3 4443.7 4755.5 4838.7 4927.3 4936.6 4022.6 4043.5 4166.5 4173.8 4187.3 1320.0 1327.8 1335.6 1336.1 1352.0 870.1 892.9 922.5 932.4 959.4 5046.7 5178.1 5906.6 5984.0 6239.2 1002.5 1038.7 1067.5 1070.3 1073.7 3919.9 3936.2 3981.7 4038.5 4049.8 10391.7 10582.9 10629.2 10679.7 10829.2 10647.9 10674.2 10691.5 10756.2 10943.6 7754.6 8206.2 8390.5 8400.1 8429.5 5992.3 5996.7 6107.1 6135.7 6229.5 10013.3 10041.2 10147.0 10219.1 10274.7 9759.3 9944.9 10297.9 10343.9 10359.9 7145.3 7504.3 7591.0 7701.5 7727.4 8269.9 8443.4 8490.9 8551.0 8564.7 7422.1 7546.9 7553.7 7578.4 7600.4 6403.2 6982.8 7336.1 7444.9 7508.7 6095.4 7149.2 7220.2 7233.8 7240.2 4863.5 4875.4 4984.3 5032.6 5158.0 4405.2 4856.6 4927.5 4963.5 5113.5 05:27:51 INFO - PROCESS | 1668 | 05:27:51 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:5213.95 Median:5902.91 stddev:1694.41 (28.7%) stddev-sans-first:1658.02 05:27:51 INFO - PROCESS | 1668 | Values: 268.9 295.6 304.6 314.0 315.8 5349.7 5721.3 6001.8 6036.3 6359.6 5020.9 6087.3 6125.6 6157.4 6249.3 3990.6 4033.3 4304.8 4554.0 4582.3 4444.8 4574.3 4589.4 4597.8 4598.4 3891.7 4477.9 4513.5 4535.0 4535.4 4144.3 4421.7 4551.7 4561.3 4598.9 3772.6 3877.6 3886.5 3893.2 3893.6 5900.0 5905.9 5914.4 5936.9 5958.7 4054.6 4690.9 4915.5 5300.5 5359.1 4879.8 5423.5 5704.3 6277.4 6481.4 3592.4 4172.6 4204.7 4222.7 4243.5 5942.6 6756.5 6826.9 6867.0 6881.7 3400.4 3468.3 4111.5 4244.2 4261.3 3976.8 4290.8 4319.2 4323.3 4362.5 4450.8 4634.7 5049.4 5056.6 5160.6 5445.3 6141.3 6336.7 6353.0 6456.6 1432.8 1493.6 1535.9 1554.1 1556.0 997.9 1008.0 1021.5 1030.2 1034.5 5696.2 6051.2 6052.6 6080.9 6083.9 5420.3 5532.9 5838.7 6812.8 6829.4 5742.9 6051.5 6073.2 6188.1 6200.5 3183.9 3582.9 3591.0 3610.3 3620.3 6499.7 6962.8 7064.9 7099.8 7146.2 6099.4 6126.3 6157.3 6582.3 7034.7 6642.3 7063.2 7129.0 7148.2 7162.2 6318.7 6812.9 6859.7 6880.1 6909.3 6073.3 6109.8 6744.4 6776.9 6777.7 6890.4 6904.9 7104.2 7124.6 7135.9 3650.7 3881.5 3996.7 4150.8 4240.4 5703.3 6464.8 6700.6 6884.8 6892.2 6580.4 6652.5 6662.3 6663.2 6694.0 6292.5 6637.0 6666.0 6680.0 6700.4 5709.5 6505.7 6515.6 6575.1 6584.8 6074.6 6488.9 6658.4 6692.5 6692.9 5374.1 5693.3 6043.6 6584.8 6584.8 05:27:51 INFO - PROCESS | 1668 | 05:27:51 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:17021.78 Median:30115.79 stddev:23268.05 (77.3%) stddev-sans-first:23300.71 05:27:51 INFO - PROCESS | 1668 | Values: 615.8 718.5 733.1 737.8 749.2 14766.0 14892.8 15178.4 15235.4 15239.9 14099.8 14153.4 14226.7 14260.4 14270.9 735.5 787.6 888.5 907.8 929.9 880.3 882.4 907.8 920.2 922.3 960.5 968.1 983.5 987.0 987.7 950.6 965.7 971.3 973.3 974.8 717.1 723.8 726.1 726.3 730.3 14509.6 14586.6 14646.9 14670.4 14892.7 10026.0 10932.9 11822.8 12329.3 12891.1 9946.4 10124.1 10187.2 10200.3 10816.6 3491.9 3507.4 3516.8 3553.6 3636.4 58860.2 70856.0 71681.9 73361.0 73422.6 9412.8 9484.3 9589.6 9701.3 9850.3 20874.1 20951.3 20993.3 21068.1 21374.6 7631.6 7795.9 7833.2 7889.0 8085.4 4139.4 4145.8 4195.2 4220.0 4335.5 3111.5 3330.8 3475.9 3536.5 3566.4 637.0 642.9 643.0 650.8 656.8 533.5 539.7 579.3 592.6 609.1 6501.1 7749.6 7939.7 8071.0 8181.5 557.8 561.0 585.7 595.6 597.4 3833.7 3845.0 3847.7 3876.1 3921.7 82707.0 85498.6 85919.6 86528.0 86540.0 59636.0 75743.6 76764.0 76936.0 77794.0 65056.7 65687.0 66212.0 66723.7 66936.7 5460.6 6139.3 7524.7 7879.9 7906.9 37253.4 38083.0 38127.4 40370.6 40513.4 55957.7 60644.0 61697.0 64482.0 65573.7 10846.7 10913.9 10955.7 10975.0 11178.5 11774.4 11848.3 11855.7 11979.3 12001.1 11402.2 11468.8 11521.0 11914.3 11950.1 11410.7 11543.9 11624.1 11667.0 11864.0 9562.0 11241.6 11274.3 11644.8 11654.8 9526.8 9671.8 9678.8 10027.5 10208.1 9615.6 9679.7 9726.8 9743.9 9876.0 05:27:51 INFO - PROCESS | 1668 | -------- Summary: end -------- 05:27:51 INFO - PROCESS | 1668 | 05:27:51 INFO - TEST-INFO | 1668: exit 0 05:27:51 INFO - TEST-OK | dromaeo_css | took 1112888ms 05:27:51 INFO - TEST-START | kraken 05:27:51 INFO - operating with platform_type : mac_ 05:27:51 INFO - Initialising browser for kraken test... 05:27:51 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpFzTTHW/profile http://localhost:49277/getInfo.html) 05:27:54 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 05:27:54 INFO - PROCESS | 1675 | colorDepth:24 05:27:54 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 05:27:54 INFO - PROCESS | 1675 | __metrics 05:27:54 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:27:55 INFO - TEST-INFO | 1675: exit 0 05:27:55 INFO - Browser initialized. 05:27:55 INFO - Running cycle 1/1 for kraken test... 05:27:55 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpFzTTHW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:28:07 INFO - PROCESS | 1679 | RSS: Main: 248217600 05:28:07 INFO - PROCESS | 1679 | 05:28:10 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 05:28:10 INFO - PROCESS | 1679 | RSS: Main: 260173824 05:28:10 INFO - PROCESS | 1679 | 05:28:13 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 05:28:14 INFO - PROCESS | 1679 | RSS: Main: 268980224 05:28:14 INFO - PROCESS | 1679 | 05:28:17 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 05:28:18 INFO - PROCESS | 1679 | RSS: Main: 272257024 05:28:18 INFO - PROCESS | 1679 | 05:28:20 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 05:28:20 INFO - PROCESS | 1679 | RSS: Main: 273039360 05:28:20 INFO - PROCESS | 1679 | 05:28:23 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:28:23 INFO - PROCESS | 1679 | RSS: Main: 274804736 05:28:23 INFO - PROCESS | 1679 | 05:28:26 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 05:28:27 INFO - PROCESS | 1679 | RSS: Main: 274735104 05:28:27 INFO - PROCESS | 1679 | 05:28:30 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 05:28:30 INFO - PROCESS | 1679 | RSS: Main: 274911232 05:28:30 INFO - PROCESS | 1679 | 05:28:33 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 05:28:34 INFO - PROCESS | 1679 | RSS: Main: 275714048 05:28:34 INFO - PROCESS | 1679 | 05:28:36 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:28:36 INFO - PROCESS | 1679 | RSS: Main: 274825216 05:28:36 INFO - PROCESS | 1679 | 05:28:38 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:28:39 INFO - PROCESS | 1679 | RSS: Main: 275083264 05:28:39 INFO - PROCESS | 1679 | 05:28:41 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:28:42 INFO - PROCESS | 1679 | RSS: Main: 275419136 05:28:42 INFO - PROCESS | 1679 | 05:28:45 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:28:45 INFO - PROCESS | 1679 | RSS: Main: 275320832 05:28:45 INFO - PROCESS | 1679 | 05:28:48 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:28:49 INFO - PROCESS | 1679 | RSS: Main: 275578880 05:28:49 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 05:28:51 INFO - PROCESS | 1679 | RSS: Main: 277024768 05:28:51 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | __start_tp_report 05:28:51 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 05:28:51 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 05:28:51 INFO - PROCESS | 1679 | |i|pagename|runs| 05:28:51 INFO - PROCESS | 1679 | |0;ai-astar;117;113;121;117;117;128;117;117;117;114 05:28:51 INFO - PROCESS | 1679 | |1;audio-beat-detection;152;121;151;196;156;143;138;123;188;152 05:28:51 INFO - PROCESS | 1679 | |2;audio-dft;191;197;197;243;270;192;274;254;200;194 05:28:51 INFO - PROCESS | 1679 | |3;audio-fft;69;77;75;143;57;57;56;63;128;45 05:28:51 INFO - PROCESS | 1679 | |4;audio-oscillator;61;91;166;115;87;81;117;80;82;115 05:28:51 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;91;89;93;92;90;87;91;89;78;129 05:28:51 INFO - PROCESS | 1679 | |6;imaging-darkroom;96;105;138;96;103;96;97;96;94;96 05:28:51 INFO - PROCESS | 1679 | |7;imaging-desaturate;135;148;92;121;143;91;93;91;91;127 05:28:51 INFO - PROCESS | 1679 | |8;json-parse-financial;61;63;61;62;62;61;61;75;63;61 05:28:51 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;62;66;69;69;74;79;82;65;72;69 05:28:51 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;95;108;75;87;107;109;89;81;85;82 05:28:51 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;138;137;164;162;166;141;141;150;197;224 05:28:51 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;169;216;170;231;164;169;181;164;226;226 05:28:51 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;70;68;70;69;64;73;59;60;101;98 05:28:51 INFO - PROCESS | 1679 | __end_tp_report 05:28:51 INFO - PROCESS | 1679 | __start_cc_report 05:28:51 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,1023 05:28:51 INFO - PROCESS | 1679 | __end_cc_report 05:28:51 INFO - PROCESS | 1679 | __startTimestamp1461328131441__endTimestamp 05:28:51 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | ------- Summary: start ------- 05:28:51 INFO - PROCESS | 1679 | Number of tests: 14 05:28:51 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:117.80 Median:117.00 stddev:4.16 (3.6%) stddev-sans-first:4.40 05:28:51 INFO - PROCESS | 1679 | Values: 117.0 113.0 121.0 117.0 117.0 128.0 117.0 117.0 117.0 114.0 05:28:51 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:152.00 Median:152.00 stddev:24.33 (16.0%) stddev-sans-first:25.81 05:28:51 INFO - PROCESS | 1679 | Values: 152.0 121.0 151.0 196.0 156.0 143.0 138.0 123.0 188.0 152.0 05:28:51 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:221.20 Median:221.50 stddev:34.71 (15.7%) stddev-sans-first:35.06 05:28:51 INFO - PROCESS | 1679 | Values: 191.0 197.0 197.0 243.0 270.0 192.0 274.0 254.0 200.0 194.0 05:28:51 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:77.00 Median:60.00 stddev:32.47 (54.1%) stddev-sans-first:34.31 05:28:51 INFO - PROCESS | 1679 | Values: 69.0 77.0 75.0 143.0 57.0 57.0 56.0 63.0 128.0 45.0 05:28:51 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:99.50 Median:80.50 stddev:29.72 (36.9%) stddev-sans-first:28.07 05:28:51 INFO - PROCESS | 1679 | Values: 61.0 91.0 166.0 115.0 87.0 81.0 117.0 80.0 82.0 115.0 05:28:51 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:92.90 Median:90.50 stddev:13.36 (14.8%) stddev-sans-first:14.15 05:28:51 INFO - PROCESS | 1679 | Values: 91.0 89.0 93.0 92.0 90.0 87.0 91.0 89.0 78.0 129.0 05:28:51 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:101.70 Median:96.00 stddev:13.22 (13.8%) stddev-sans-first:13.87 05:28:51 INFO - PROCESS | 1679 | Values: 96.0 105.0 138.0 96.0 103.0 96.0 97.0 96.0 94.0 96.0 05:28:51 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:113.20 Median:91.00 stddev:23.95 (26.3%) stddev-sans-first:24.07 05:28:51 INFO - PROCESS | 1679 | Values: 135.0 148.0 92.0 121.0 143.0 91.0 93.0 91.0 91.0 127.0 05:28:51 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:63.00 Median:62.00 stddev:4.29 (6.9%) stddev-sans-first:4.49 05:28:51 INFO - PROCESS | 1679 | Values: 61.0 63.0 61.0 62.0 62.0 61.0 61.0 75.0 63.0 61.0 05:28:51 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:70.50 stddev:6.22 (8.8%) stddev-sans-first:5.74 05:28:51 INFO - PROCESS | 1679 | Values: 62.0 66.0 69.0 69.0 74.0 79.0 82.0 65.0 72.0 69.0 05:28:51 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:91.80 Median:83.50 stddev:12.35 (14.8%) stddev-sans-first:13.04 05:28:51 INFO - PROCESS | 1679 | Values: 95.0 108.0 75.0 87.0 107.0 109.0 89.0 81.0 85.0 82.0 05:28:51 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:162.00 Median:163.00 stddev:28.51 (17.5%) stddev-sans-first:28.89 05:28:51 INFO - PROCESS | 1679 | Values: 138.0 137.0 164.0 162.0 166.0 141.0 141.0 150.0 197.0 224.0 05:28:51 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.60 Median:198.50 stddev:29.13 (14.7%) stddev-sans-first:29.73 05:28:51 INFO - PROCESS | 1679 | Values: 169.0 216.0 170.0 231.0 164.0 169.0 181.0 164.0 226.0 226.0 05:28:51 INFO - PROCESS | 1679 | 05:28:51 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.20 Median:69.50 stddev:14.58 (21.0%) stddev-sans-first:15.42 05:28:51 INFO - PROCESS | 1679 | Values: 70.0 68.0 70.0 69.0 64.0 73.0 59.0 60.0 101.0 98.0 05:28:51 INFO - PROCESS | 1679 | -------- Summary: end -------- 05:28:51 INFO - PROCESS | 1679 | 05:28:52 INFO - TEST-INFO | 1679: exit 0 05:28:52 INFO - TEST-OK | kraken | took 60550ms 05:28:52 INFO - SUITE-END | took 1173s 05:28:52 INFO - Completed test suite (00:19:33) 05:28:52 INFO - javascript benchmark 05:28:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3142.3872344892748, 3478.0435380270414, 3513.367593833109, 3537.2041290709594, 3548.8225588720575, 4824.011077729066, 5788.536917046642, 5824.475797178253, 5867.823965281038, 5868.061110222366, 5809.535237181026, 5828.0, 5865.442782935615, 5919.585629005971, 5986.790462333819, 5215.734607395917, 5237.45006774289, 5263.657862238962, 5293.179557168645, 5341.893162136755, 4602.171609110359, 5329.973350133263, 5350.224217488462, 5363.651362661434, 5413.052715774744, 5075.796968121308, 5208.5572726318305, 5261.894762104775, 5295.735213239361, 5302.178162384856, 5238.083335416325, 5250.291210686547, 5255.159174532095, 5257.263983042377, 5258.1849813279005, 4812.446568644616, 4897.314375987365, 5244.79020839166, 5265.6577322474295, 5299.33758280215, 5441.8572099859075, 5706.172604972297, 5749.913751293734, 5758.856028599319, 5818.8254352369495, 6160.630362178284, 6181.536384771115, 6218.098375735493, 6218.347073557256, 6252.937470625281, 5460.0, 6090.8781824363705, 6261.154744109578, 6261.624302541816, 6267.71795269214, 5869.735861886183, 5943.375945525744, 5950.7917222896995, 5957.1957785698805, 5995.250593675791, 6307.590006649553, 6315.695129784488, 6324.683765811783, 6344.0, 6362.554621176473, 6105.823680184376, 6163.753449861955, 6236.6258024518675, 6244.344343843831, 6267.059941008794, 6174.629522228681, 6226.439620434183, 6256.937430625727, 6272.372762723691, 6275.1842260506755, 6095.740618599586, 6143.603994442171, 6180.133589767719, 6246.968765156145, 6256.468200202943, 6146.0473626587955, 6181.629102253879, 6214.316425193225, 6224.502039836802, 6230.470410015108, 4993.550580447777, 5021.347224860745, 5028.245763135559, 5044.596432285409, 5075.543201111844, 4492.550744925481, 4507.346434767006, 4534.750588717587, 4542.886427839265, 4571.405717256735, 5884.911726324109, 5948.82153535386, 5953.3451320354725, 5992.790252341147, 6043.456088952015, 5357.4106848246665, 6269.404406581368, 6293.874122517524, 6341.4609758170145, 6366.84082897922, 5433.402325744165, 6005.6396616203165, 6005.6997150142315, 6021.969890150609, 6029.854327677727, 4281.935770963438, 4667.533246675305, 5049.267856160836, 5477.890442191134, 5527.695976721238, 6373.235211774617, 6398.872022559616, 6490.675466226669, 6504.1869766279215, 6524.412802847766, 6264.091706702411, 6303.590266632654, 6305.779297724557, 6312.873742525216, 6319.0, 6003.6397816131175, 6212.099245609452, 6268.498520118471, 6500.479961602966, 6505.642189679613, 5452.509274165344, 6107.572469927062, 6146.862830376394, 6224.751009959551, 6253.718582663882, 6206.658633775186, 6247.406496382836, 6256.155419018374, 6265.592736472115, 6273.529485288713, 4772.642051846195, 6112.113743507078, 6121.117656725397, 6186.288576813724, 6211.223597050369, 6186.876262474635, 6210.0, 6227.564070515021, 6233.844153896189, 6235.532335074979, 5293.470652934676, 6033.336333003454, 6117.938820611737, 6140.846478838065, 6174.320824709368, 6097.373446762037, 6137.7447333336595, 6164.4143806338325, 6189.978653522298, 6250.124982502362, 6154.599951003171, 6170.352113028068, 6174.104754810617, 6178.042403427476, 6178.351273116258], "value": 5728.583105931119, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [517.1183132758645, 520.21187900331, 533.2214966149421, 594.9796099689033, 611.9877602447951, 30368.08895733129, 38074.0, 38093.04767380817, 38190.80904595477, 38242.80878595607, 38807.61192388075, 39320.60679393205, 39556.80221598895, 39575.20849583007, 39661.20677586446, 9411.952940235306, 9481.573329200202, 9529.285303602222, 9541.904580954206, 9569.952150239274, 9346.906530934672, 9509.096635819586, 9537.28470364722, 9588.95205523975, 9617.75955601112, 9528.38065525742, 9542.713718588453, 9624.278179136592, 9668.516574171263, 9670.613175472972, 9519.571619277114, 9548.331616786792, 9557.0, 9611.471369074698, 9612.279079069063, 9511.952440237754, 9578.425294482284, 9640.228781697448, 9642.662506812228, 9686.273529485246, 37690.62309376898, 37827.810860945516, 37837.621623783685, 38130.42804357937, 38479.422808657895, 3200.8719651213923, 3343.047231539024, 3474.322507111114, 3548.183917698918, 3565.0552603560077, 28122.296942576395, 28150.155495335173, 28181.872725090972, 28835.134945951653, 29228.415431691246, 2764.3918337965615, 2774.1400165948417, 2780.9721902780916, 2877.84171870547, 2884.2933481297005, 436843.0, 437863.0, 439210.0, 440612.0, 441751.0, 34655.0, 58390.41609583935, 58476.707616462496, 58828.70585647044, 59035.704821475614, 34108.976730698116, 34768.0, 34908.65091349054, 35036.824815876265, 35208.823955880056, 14830.480933167288, 14985.550433487013, 15034.0, 15046.473373431878, 15078.623034424227, 12433.564825231073, 12643.304618246084, 12739.42672579736, 12777.105602607728, 12824.29466379358, 7836.138024817265, 7934.127246002935, 8017.0, 8051.074126475413, 8097.595120243965, 3339.749518786101, 3382.678645528671, 3392.1858753898887, 3393.3043726036103, 3415.504751811023, 886.9622541626313, 970.2141265574907, 1011.691434112588, 1037.255932489109, 1076.3757020927842, 29366.853165734035, 29561.26096847596, 29578.556321655193, 29673.0, 30058.699413006027, 1035.3270374256792, 1106.882397481384, 1123.4045955643521, 1142.086330935265, 1203.3622180244483, 14527.201003944885, 14754.40982360715, 14848.777268340984, 14878.80105777263, 14920.40318387274, 630449.0, 638427.0, 648291.0, 654149.0, 658892.0, 57730.0, 58322.833543328896, 58812.117818232764, 59011.704941475014, 59024.1146382796, 1457.4753088887992, 1469.4048910191389, 1491.828439729445, 1492.149474799354, 1498.7227362937892, 28144.71855281421, 28326.858365708864, 28604.71395286104, 28705.712942870305, 28813.855930721053, 47664.761676191396, 48240.0, 48513.27230091622, 49739.75130124471, 49804.25293620526, 577675.1116244391, 579483.0, 580309.0, 580518.0, 580758.0, 26159.869200653873, 41111.0, 43147.56852431435, 43362.34956475718, 43479.13041739211, 36929.44605830968, 45480.0, 46907.76546117248, 47789.761051194524, 48115.73691082528, 29309.681064352582, 34230.657693423746, 35437.0, 35467.645323546436, 37456.0, 35793.82103089468, 35795.64204357923, 35980.820095899355, 36702.44946325754, 36709.26581468302, 36342.0, 36639.08402289964, 36988.0, 37842.43236351512, 38426.807865960494, 29278.56082158812, 30430.84784576063, 32129.196770080936, 32131.51802723008, 32172.0, 24237.151699690425, 24409.38976525601, 24471.63292550649, 24728.0, 24892.75107248904], "value": 18479.35182885394, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.89470395641195, 387.39566276608485, 392.52112422844135, 397.12695805652936, 398.2950178184613, 14506.854931450682, 20318.59362812743, 20603.48491287715, 20629.793702062976, 20717.792822071773, 19944.800551994475, 19982.800171998275, 20330.898345508253, 20716.171353145855, 20772.584548309023, 5667.3482549506925, 5830.242068531106, 5968.850778730544, 6004.369541198177, 6014.969925150368, 5840.06558950568, 5928.258967629046, 5969.761209551612, 5979.133025711291, 6003.879922401549, 5642.238297829782, 5929.258842644669, 6000.729967151467, 6007.399260074001, 6017.8194654160225, 5711.885762284731, 5853.443922827325, 5933.703314834284, 5984.0126379147605, 5990.921634105859, 5164.431912489623, 5205.349331333583, 5213.791448342062, 5213.843584692465, 5215.348081489814, 20086.79913200864, 22929.0, 24914.750852491605, 25627.359316017064, 25999.870000650066, 2102.190656597206, 2109.3566462229014, 2119.406566161462, 2176.749673787519, 2270.8410411271216, 8207.507549546986, 9628.988956159572, 9653.517324133765, 9880.258980576413, 9903.80192396148, 1688.2571668465957, 1707.5731067233191, 1719.389616686071, 1750.798658154303, 1810.583565779878, 123755.76244237533, 127899.0, 128473.99999999907, 130636.0, 131581.0, 9469.905300946903, 11572.72700002999, 11689.123315751352, 11801.704957375963, 11879.168458208016, 14518.419263229353, 14729.631759206106, 14975.0, 14987.775183372258, 14991.625209369635, 7658.96170519155, 8246.845441638176, 8548.273396761346, 8676.305895528343, 8742.38803283764, 8639.0, 8684.43551169172, 8695.695650652196, 8704.347173961978, 8808.91191088081, 4529.524399937959, 4587.472440669299, 4595.540445955445, 4707.7881495332795, 4709.3877795887, 1943.8250557449896, 1946.951326216856, 1948.1817636592382, 1950.9804901951084, 1954.3843689237845, 898.1781669772216, 944.6268723854039, 978.6574698855486, 1016.2530540053056, 1066.8879767624444, 17363.739543906853, 19464.1241144146, 19894.403167904988, 20422.661870503536, 20634.793652063287, 935.8382137262944, 937.920276776468, 940.2055263302499, 963.1524258652481, 1003.934744241622, 8863.955680221687, 8943.731688049369, 10478.161747060218, 10736.087432568163, 10750.08624266946, 596224.0, 601524.0, 612227.0, 612397.0, 613997.0, 85960.71058934121, 113810.43094784638, 114347.0, 131732.0, 137836.0, 950.0689324462066, 963.1476143612953, 980.6077568972468, 985.0248254228405, 987.5210522896533, 17634.82365176332, 17822.10889455496, 17827.732584011508, 17899.19453624616, 18109.0, 51112.0, 51851.0, 52650.94698106093, 52715.0, 53094.0, 68818.0, 76970.0, 77582.0, 77951.2204877944, 81406.0, 7620.123685775985, 7777.844443110993, 7804.141544430222, 7924.524528528307, 8252.21603947624, 10680.359178449318, 10737.838932415863, 11508.424578771195, 12355.270262163483, 12459.190152640049, 8737.257333126756, 9108.908910911072, 9987.001299870246, 10429.374237545771, 10778.13774898022, 8717.215450609476, 10303.690889273334, 10380.948095259475, 10423.478826058516, 10691.786164276826, 10370.688879333633, 10584.788304233718, 10669.57321707123, 10790.73023174427, 11197.160212984221, 7709.845803083795, 7888.526688398714, 7916.485428447132, 7920.247576480225, 7933.286004259414, 10290.536925838205, 10650.946745266225, 10841.67474975752, 10937.179711521509, 11051.557937682725], "value": 9344.266471935096, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1562.9843701562984, 1586.7857839191709, 1650.488348611931, 1805.2327760701698, 1813.2746901239502, 7933.730603103505, 8017.238362355582, 8614.267787238085, 8883.777905552364, 8893.555322233886, 7401.555906645592, 8060.717874874381, 8639.308855291578, 8762.167594078552, 8777.0, 2419.443527988564, 2436.4396188876567, 2472.8763561821925, 2477.8389404688637, 2481.3796550862285, 2069.699893515447, 2398.20859116492, 2445.4375493636476, 2446.645236440715, 2460.5324988252264, 2414.8309618326725, 2446.107170882633, 2457.127719659522, 2458.2748089313695, 2481.8510889346608, 2402.987985060081, 2412.19191570824, 2425.842320249178, 2435.916017372261, 2445.987770061156, 2415.5410472010262, 2426.381272775431, 2433.172721274775, 2442.7312995570474, 2444.1323330217874, 8791.065965487582, 8805.25155361795, 8956.417832840909, 8961.731148065568, 8963.731088067369, 5172.612054095958, 5362.892742145174, 5365.141577347645, 5377.704226267554, 5414.485623865727, 3463.2727127303297, 3515.2617950230483, 3551.7158627309755, 3562.91092722684, 3581.803000834953, 1020.9846852297221, 1030.469308306223, 1032.927695061346, 1050.38027563737, 1054.6888667843004, 10236.692899213036, 11223.71940701489, 11228.214025018253, 11452.0, 11560.710982225512, 4598.26427771555, 4632.305154226826, 4832.685875418087, 4835.153848076572, 4883.487233840396, 9691.563879625433, 9834.967328430555, 9855.162311203485, 9862.605495780232, 9868.703938881845, 5268.947310526923, 5274.0, 5334.359876814807, 5338.386085600128, 5373.2746079278795, 4443.733375997386, 4755.476897541268, 4838.7338696372035, 4927.334809800631, 4936.555709986119, 4022.597740225954, 4043.454133691973, 4166.479190101238, 4173.83304667816, 4187.309093999456, 1320.0165876422127, 1327.8141060251573, 1335.5592654424017, 1336.1315145155531, 1352.0468070010618, 870.1124852650262, 892.9419587726777, 922.4603606890045, 932.3939439364332, 959.3524371049514, 5046.6719663221775, 5178.119719647669, 5906.616069955439, 5984.0, 6239.1577137085915, 1002.4636819301638, 1038.6832016234969, 1067.530286673861, 1070.2882583082164, 1073.6778966310076, 3919.92160156801, 3936.15372694872, 3981.7212795104065, 4038.495188101487, 4049.838006479708, 10391.688249352532, 10582.894171058344, 10629.202809789296, 10679.733006674895, 10829.241953063367, 10647.94676026615, 10674.199435042403, 10691.465426728633, 10756.247062705692, 10943.616973405893, 7754.573498457752, 8206.22040906113, 8390.454620449651, 8400.117987611335, 8429.536375499283, 5992.340842507408, 5996.670183139795, 6107.144999700135, 6135.6932153393045, 6229.478747717031, 10013.299069065388, 10041.246906482189, 10147.0, 10219.131373833308, 10274.691759247235, 9759.316847820584, 9944.850827237453, 10297.897021029694, 10343.896561034293, 10359.87622537363, 7145.285471452813, 7504.3246107850555, 7590.962045189739, 7701.537907725555, 7727.42044346665, 8269.875951860848, 8443.408961372645, 8490.872636910328, 8550.957245213734, 8564.657413703631, 7422.072240969879, 7546.924530754842, 7553.660085296065, 7578.355839753683, 7600.391968642606, 6403.231612206565, 6982.825429364103, 7336.119665640157, 7444.925550744423, 7508.699652013858, 6095.35998720128, 7149.213586505584, 7220.205777364591, 7233.782986510413, 7240.203577606564, 4863.513648635108, 4875.366202393666, 4984.302197692254, 5032.597392208541, 5158.0199762045095, 4405.185040767468, 4856.63575231852, 4927.531884470969, 4963.4540200577185, 5113.51421614946], "value": 4633.628609032172, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.888147510046, 295.57437290301976, 304.5553491901823, 314.03590975704594, 315.76791058571933, 5349.689326115103, 5721.284839395081, 6001.789937352193, 6036.336003039673, 6359.55483116182, 5020.924686129711, 6087.25633735253, 6125.63246205228, 6157.445829875311, 6249.2500899892075, 3990.6408423241974, 4033.3143365627766, 4304.837693822666, 4553.977230113845, 4582.258741678158, 4444.799984000711, 4574.336721175428, 4589.449266088058, 4597.793059331523, 4598.425196850394, 3891.7081218908697, 4477.947682294658, 4513.526079761627, 4534.9546504534865, 4535.439679851317, 4144.254034273829, 4421.734695918255, 4551.749653769041, 4561.29299958507, 4598.908021839561, 3772.5850156482766, 3877.6316249956208, 3886.5336159660737, 3893.201893611831, 3893.6301051400073, 5899.970500147515, 5905.852353691193, 5914.4381283777975, 5936.853467662146, 5958.731857066442, 4054.5945405459515, 4690.874190194329, 4915.5084491550915, 5300.469953004668, 5359.115745901921, 4879.756012199376, 5423.535645375727, 5704.258446401983, 6277.403646653561, 6481.4166724994975, 3592.3713350163616, 4172.561881002482, 4204.684648651363, 4222.746635201897, 4243.511996120455, 5942.5840191186635, 6756.493263005245, 6826.897596536056, 6866.969954506864, 6881.724731010753, 3400.3539327528183, 3468.271662950758, 4111.465509483748, 4244.172386384687, 4261.2542805009, 3976.8409263629133, 4290.764007979591, 4319.179355922364, 4323.286657701507, 4362.498312694017, 4450.777461126931, 4634.702283360664, 5049.434675250684, 5056.64603477753, 5160.638755287169, 5445.346558413015, 6141.324454310023, 6336.732653469325, 6352.968235158794, 6456.580322279036, 1432.8065711129072, 1493.6333255071488, 1535.877129829611, 1554.0753251815152, 1555.984440155584, 997.9002099790108, 1007.9899201008043, 1021.4943602916604, 1030.2427715629008, 1034.462079718542, 5696.174054762037, 6051.243594550681, 6052.636841789506, 6080.908786368208, 6083.904897118561, 5420.322459692538, 5532.889342213133, 5838.737256823453, 6812.8296792579595, 6829.351211634887, 5742.942570574324, 6051.4553690168095, 6073.18012068374, 6188.1027251048345, 6200.534959878028, 3183.936321273608, 3582.9283414331567, 3590.983573798022, 3610.3140403323287, 3620.25784714133, 6499.707513161825, 6962.797113327926, 7064.92935070653, 7099.822504437327, 7146.176330976674, 6099.359567245464, 6126.264848218153, 6157.261128664589, 6582.341765823475, 7034.6834392452465, 6642.302558231316, 7063.234191452137, 7129.0, 7148.177959534616, 7162.247963963709, 6318.715657795502, 6812.863742725019, 6859.657017149023, 6880.139982502187, 6909.2745261748805, 6073.267029836503, 6109.8391305651785, 6744.426723728342, 6776.932230677828, 6777.661116944231, 6890.414314783299, 6904.896426553505, 7104.183018952886, 7124.5725256484775, 7135.892961605477, 3650.671439570451, 3881.525020492195, 3996.6602838757867, 4150.750954942713, 4240.4063431120285, 5703.317521331134, 6464.806055818333, 6700.564463309869, 6884.759033433806, 6892.241853396022, 6580.440662543738, 6652.501062420241, 6662.333766623299, 6663.233728121134, 6693.966530167513, 6292.514456789634, 6636.966815166086, 6665.966670166618, 6679.966600166968, 6700.363465470772, 5709.51469125129, 6505.674716264262, 6515.576487528296, 6575.145231119924, 6584.835379115369, 6074.605150665193, 6488.94440974391, 6658.3674550917585, 6692.531522793358, 6692.899606506004, 5374.13062594931, 5693.259876216065, 6043.576949613661, 6584.8024559263295, 6584.814733348044], "value": 4660.444622524676, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [615.7660089166118, 718.4647437658941, 733.1385621894275, 737.8081698758322, 749.1571981520789, 14766.0, 14892.776608350883, 15178.39286428543, 15235.390584376612, 15239.923800380997, 14099.78850317246, 14153.363098660559, 14226.71546569068, 14260.429582816674, 14270.928645356758, 735.5108852613033, 787.5865170785327, 888.506878682331, 907.809360034392, 929.930255230857, 880.3221519430035, 882.355880207449, 907.8229745199689, 920.1718453391967, 922.3174850610567, 960.538941308169, 968.0803236924914, 983.4590974963777, 987.012987012987, 987.7234374375187, 950.6340059077271, 965.7054598347502, 971.2666936462961, 973.2797729680057, 974.7758015656368, 717.0713925466533, 723.7756295548344, 726.0597526203579, 726.2773540327355, 730.2697302697303, 14509.564713058626, 14586.562403127924, 14646.853531464656, 14670.413183472649, 14892.702145957128, 10025.979216626669, 10932.945335273273, 11822.758610345962, 12329.260244385276, 12891.097623166383, 9946.35348702332, 10124.139448146916, 10187.185025197967, 10200.285979981405, 10816.567337306577, 3491.930161396783, 3507.368673638744, 3516.771409858351, 3553.6091029986683, 3636.3999940009867, 58860.234192974276, 70855.99999999949, 71681.92477112847, 73360.99999999946, 73422.63288683523, 9412.764680883032, 9484.336096473318, 9589.568469418893, 9701.272404569685, 9850.261230407605, 20874.06066727001, 20951.266705665228, 20993.265235716677, 21068.051937662844, 21374.572508549743, 7631.580263085472, 7795.883061754078, 7833.216678332121, 7889.0, 8085.3531717462465, 4139.399787030864, 4145.79271036453, 4195.16096780639, 4219.97890010548, 4335.501417337046, 3111.4866047101973, 3330.816805075744, 3475.94786078209, 3536.45184996326, 3566.411542095525, 637.0317117980643, 642.881067002606, 643.0322364840978, 650.8405440667067, 656.8423578341163, 533.5118366694509, 539.6842846934587, 579.3395529096833, 592.570386469806, 609.1380696314665, 6501.130912435085, 7749.612519374122, 7939.682412703427, 8070.959645201737, 8181.468204566684, 557.8326502049368, 560.9890028346289, 585.7364186116265, 595.5830918357167, 597.3936454498687, 3833.7316387852584, 3844.961550384516, 3847.6921846252235, 3876.1084950460986, 3921.7254792164276, 82707.0, 85498.57250713707, 85919.57040214883, 86528.0, 86540.0, 59636.0, 75743.62128189324, 76764.0, 76936.0, 77794.0, 65056.67471662611, 65687.0, 66212.0, 66723.66638166778, 66936.66531667407, 5460.563154947516, 6139.3246742857355, 7524.736634217776, 7879.881801772864, 7906.9209307906185, 37253.441198382585, 38083.0, 38127.37789909954, 40370.587029453825, 40513.392299114945, 55957.720211398686, 60644.0, 61697.0, 64482.0, 65573.68852623017, 10846.674599761704, 10913.890861091606, 10955.671329860117, 10974.957379048978, 11178.496967636313, 11774.411279435823, 11848.28910265387, 11855.703607409883, 11979.28124312544, 12001.099917506397, 11402.20184587071, 11468.770624587294, 11521.020713239468, 11914.285142891107, 11950.092605500804, 11410.657680269605, 11543.942280288546, 11624.128190385585, 11667.00830429423, 11864.0, 9561.952190239004, 11241.55033798639, 11274.323540587591, 11644.825327619923, 11654.825177622173, 9526.761211115521, 9671.758206044906, 9678.75803104928, 10027.548760305657, 10208.082476688849, 9615.615375384907, 9679.709608711468, 9726.805463890823, 9743.853842192515, 9876.0], "value": 6731.888137756883, "unit": "score"}], "name": "dromaeo_css", "value": 7238.251014599174, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 113.0, 121.0, 117.0, 117.0, 128.0, 117.0, 117.0, 117.0, 114.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 121.0, 151.0, 196.0, 156.0, 143.0, 138.0, 123.0, 188.0, 152.0], "value": 152.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 197.0, 197.0, 243.0, 270.0, 192.0, 274.0, 254.0, 200.0, 194.0], "value": 221.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 77.0, 75.0, 143.0, 57.0, 57.0, 56.0, 63.0, 128.0, 45.0], "value": 77.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 91.0, 166.0, 115.0, 87.0, 81.0, 117.0, 80.0, 82.0, 115.0], "value": 99.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 93.0, 92.0, 90.0, 87.0, 91.0, 89.0, 78.0, 129.0], "value": 92.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 105.0, 138.0, 96.0, 103.0, 96.0, 97.0, 96.0, 94.0, 96.0], "value": 101.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 148.0, 92.0, 121.0, 143.0, 91.0, 93.0, 91.0, 91.0, 127.0], "value": 113.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 61.0, 62.0, 62.0, 61.0, 61.0, 75.0, 63.0, 61.0], "value": 63.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 69.0, 69.0, 74.0, 79.0, 82.0, 65.0, 72.0, 69.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 108.0, 75.0, 87.0, 107.0, 109.0, 89.0, 81.0, 85.0, 82.0], "value": 91.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 164.0, 162.0, 166.0, 141.0, 141.0, 150.0, 197.0, 224.0], "value": 162.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 216.0, 170.0, 231.0, 164.0, 169.0, 181.0, 164.0, 226.0, 226.0], "value": 191.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 70.0, 69.0, 64.0, 73.0, 59.0, 60.0, 101.0, 98.0], "value": 73.2, "unit": "score"}], "name": "kraken", "value": 1627.6000000000001, "alertThreshold": 2.0}]} 05:28:52 INFO - Return code: 0 05:28:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:28:52 ERROR - # TBPL SUCCESS # 05:28:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:28:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:28:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:28:52 INFO - 2016-04-22 05:28:52.762 screenresolution[1684:10430] starting screenresolution argv=screenresolution get 05:28:52 INFO - 2016-04-22 05:28:52.764 screenresolution[1684:10430] Display 0: 1600x1200x32@60 05:28:52 INFO - 2016-04-22 05:28:52.770 screenresolution[1685:10433] starting screenresolution argv=screenresolution list 05:28:52 INFO - Available Modes on Display 0 05:28:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:28:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:28:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:28:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:28:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:28:52 INFO - Intel Iris: 05:28:52 INFO - Chipset Model: Intel Iris 05:28:52 INFO - Type: GPU 05:28:52 INFO - Bus: Built-In 05:28:52 INFO - VRAM (Dynamic, Max): 1536 MB 05:28:52 INFO - Vendor: Intel (0x8086) 05:28:52 INFO - Device ID: 0x0a2e 05:28:52 INFO - Revision ID: 0x0009 05:28:52 INFO - Displays: 05:28:52 INFO - Display: 05:28:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:28:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:28:52 INFO - Display Serial Number: 1600x1200 60 05:28:52 INFO - Main Display: Yes 05:28:52 INFO - Mirror: Off 05:28:52 INFO - Online: Yes 05:28:52 INFO - Rotation: Supported 05:28:53 INFO - Return code: 0 05:28:53 INFO - Running post-action listener: _resource_record_post_action 05:28:53 INFO - Running post-run listener: _resource_record_post_run 05:28:54 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 33238528; Write bytes: 697984512; Read time: 328; Write time: 2062 05:28:54 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 392604160; Write bytes: 384350208; Read time: 29539; Write time: 740 05:28:54 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 31456768; Write bytes: 300146176; Read time: 310; Write time: 1291 05:28:54 INFO - Running post-run listener: _upload_blobber_files 05:28:54 INFO - Blob upload gear active. 05:28:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:28:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:28:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:28:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:28:54 INFO - (blobuploader) - INFO - Open directory for files ... 05:28:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:28:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:28:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:28:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:28:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:28:55 INFO - (blobuploader) - INFO - Done attempting. 05:28:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:28:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:28:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:28:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:28:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:28:56 INFO - (blobuploader) - INFO - Done attempting. 05:28:56 INFO - (blobuploader) - INFO - Iteration through files over. 05:28:56 INFO - Return code: 0 05:28:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:28:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:28:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6325fa4aea0aeb3dbb5bab9bc6ca7acf3cf57ceb315e9dc8f14aa82e52d0f0bb9bab8317f4463627aec630bed1224570a3be5464eec1d46f682ef72a9278b646", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f66c974cebbc801abc1a7ea1d20f9bfab2807c48e46fa6ec7ccee82f14ad1160a7fead021d489bdc87ef6e09d378a102f1cfe101e92d6b75fd10a61be669b5b8"} 05:28:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:28:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:28:56 INFO - Contents: 05:28:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6325fa4aea0aeb3dbb5bab9bc6ca7acf3cf57ceb315e9dc8f14aa82e52d0f0bb9bab8317f4463627aec630bed1224570a3be5464eec1d46f682ef72a9278b646", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f66c974cebbc801abc1a7ea1d20f9bfab2807c48e46fa6ec7ccee82f14ad1160a7fead021d489bdc87ef6e09d378a102f1cfe101e92d6b75fd10a61be669b5b8"} 05:28:56 INFO - Running post-run listener: copy_logs_to_upload_dir 05:28:56 INFO - Copying logs to upload dir... 05:28:56 INFO - mkdir: /builds/slave/test/build/upload/logs 05:28:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.377092 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-04-22 05:28:56.432551) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 05:28:56.435710) ========= 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.G7Fmz1erkl/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.gyBDv8BDwb/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6325fa4aea0aeb3dbb5bab9bc6ca7acf3cf57ceb315e9dc8f14aa82e52d0f0bb9bab8317f4463627aec630bed1224570a3be5464eec1d46f682ef72a9278b646", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f66c974cebbc801abc1a7ea1d20f9bfab2807c48e46fa6ec7ccee82f14ad1160a7fead021d489bdc87ef6e09d378a102f1cfe101e92d6b75fd10a61be669b5b8"} build_url:https://queue.taskcluster.net/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011276 build_url: 'https://queue.taskcluster.net/v1/task/D6UIDxaWRJuAj-ACbwMC6w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6325fa4aea0aeb3dbb5bab9bc6ca7acf3cf57ceb315e9dc8f14aa82e52d0f0bb9bab8317f4463627aec630bed1224570a3be5464eec1d46f682ef72a9278b646", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f66c974cebbc801abc1a7ea1d20f9bfab2807c48e46fa6ec7ccee82f14ad1160a7fead021d489bdc87ef6e09d378a102f1cfe101e92d6b75fd10a61be669b5b8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 05:28:56.463985) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:28:56.464301) ========= 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.G7Fmz1erkl/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.gyBDv8BDwb/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.005250 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:28:56.512930) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 05:28:56.513220) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 05:28:56.513584) ========= ========= Total master_lag: 0.18 =========