builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0031 starttime: 1484821590.84 results: success (0) buildid: 20170119004006 builduid: d75212b9bf4f4cf6aa3646482ce5737c revision: ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-19 02:26:30.844185) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-19 02:26:30.844720) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-19 02:26:30.845043) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-19 02:26:30.870580) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 02:26:30.870897) ========= 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.alyxNwdjcN/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.nIwG8BRhmX/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 --2017-01-19 02:26:30-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 166M=0s 2017-01-19 02:26:30 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115120 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 02:26:31.009933) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 02:26:31.010311) ========= 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.alyxNwdjcN/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.nIwG8BRhmX/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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.019935 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 02:26:31.059337) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-19 02:26:31.059732) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.alyxNwdjcN/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.nIwG8BRhmX/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 2017-01-19 02:26:31,139 truncating revision to first 12 chars 2017-01-19 02:26:31,139 Setting DEBUG logging. 2017-01-19 02:26:31,139 attempt 1/10 2017-01-19 02:26:31,139 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ed6e5d97950a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-19 02:26:32,409 unpacking tar archive at: mozilla-aurora-ed6e5d97950a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.514322 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-19 02:26:32.602017) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-19 02:26:32.602437) ========= script_repo_revision: ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-19 02:26:32.602954) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-19 02:26:32.603267) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-19 02:26:32.623649) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-01-19 02:26:32.623977) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.alyxNwdjcN/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.nIwG8BRhmX/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:26:32 INFO - MultiFileLogger online at 20170119 02:26:32 in /builds/slave/test 02:26:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:26:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:26:32 INFO - {'append_to_log': False, 02:26:32 INFO - 'base_work_dir': '/builds/slave/test', 02:26:32 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:26:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:26:32 INFO - 'branch': 'Mozilla-Aurora', 02:26:32 INFO - 'buildbot_json_path': 'buildprops.json', 02:26:32 INFO - 'config_files': ('talos/mac_config.py',), 02:26:32 INFO - 'default_actions': ('clobber', 02:26:32 INFO - 'read-buildbot-config', 02:26:32 INFO - 'download-and-extract', 02:26:32 INFO - 'populate-webroot', 02:26:32 INFO - 'create-virtualenv', 02:26:32 INFO - 'install', 02:26:32 INFO - 'run-tests'), 02:26:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:26:32 INFO - 'download_minidump_stackwalk': True, 02:26:32 INFO - 'download_symbols': 'ondemand', 02:26:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:26:32 INFO - 'tooltool.py': '/tools/tooltool.py', 02:26:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:26:32 INFO - '/tools/misc-python/virtualenv.py')}, 02:26:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:26:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:26:32 INFO - 'installer_path': 'installer.exe', 02:26:32 INFO - 'log_level': 'info', 02:26:32 INFO - 'log_name': 'talos', 02:26:32 INFO - 'log_to_console': True, 02:26:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:26:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:26:32 INFO - 'opt_config_files': (), 02:26:32 INFO - 'pip_index': False, 02:26:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:26:32 INFO - 'cmd': ('bash', 02:26:32 INFO - '-c', 02:26:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:26:32 INFO - 'enabled': True, 02:26:32 INFO - 'halt_on_failure': False, 02:26:32 INFO - 'name': 'check_screen_resolution'},), 02:26:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:26:32 INFO - 'cmd': ('bash', 02:26:32 INFO - '-c', 02:26:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:26:32 INFO - 'enabled': True, 02:26:32 INFO - 'halt_on_failure': False, 02:26:32 INFO - 'name': 'check_screen_resolution'},), 02:26:32 INFO - 'run_cmd_checks_enabled': True, 02:26:32 INFO - 'sps_profile': False, 02:26:32 INFO - 'sps_profile_interval': 0, 02:26:32 INFO - 'suite': 'dromaeojs-e10s', 02:26:32 INFO - 'system_bits': '32', 02:26:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:26:32 INFO - 'title': 't-yosemite-r7-0031', 02:26:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:26:32 INFO - 'use_talos_json': True, 02:26:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:26:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:26:32 INFO - 'work_dir': 'build'} 02:26:32 INFO - [mozharness: 2017-01-19 10:26:32.828298Z] Running clobber step. 02:26:32 INFO - Running pre-action listener: _resource_record_pre_action 02:26:32 INFO - Running main action method: clobber 02:26:32 INFO - rmtree: /builds/slave/test/build 02:26:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:26:33 INFO - Running post-action listener: _resource_record_post_action 02:26:33 INFO - [mozharness: 2017-01-19 10:26:33.497953Z] Finished clobber step (success) 02:26:33 INFO - [mozharness: 2017-01-19 10:26:33.498198Z] Running read-buildbot-config step. 02:26:33 INFO - Running pre-action listener: _resource_record_pre_action 02:26:33 INFO - Running main action method: read_buildbot_config 02:26:33 INFO - Using buildbot properties: 02:26:33 INFO - { 02:26:33 INFO - "project": "", 02:26:33 INFO - "product": "firefox", 02:26:33 INFO - "script_repo_revision": "production", 02:26:33 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:26:33 INFO - "repository": "", 02:26:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:26:33 INFO - "buildid": "20170119004006", 02:26:33 INFO - "stage_platform": "macosx64", 02:26:33 INFO - "basedir": "/builds/slave/test", 02:26:33 INFO - "nightly_build": "True", 02:26:33 INFO - "buildnumber": 78, 02:26:33 INFO - "slavename": "t-yosemite-r7-0031", 02:26:33 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 02:26:33 INFO - "platform": "yosemite_r7", 02:26:33 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:26:33 INFO - "branch": "mozilla-aurora", 02:26:33 INFO - "repo_path": "releases/mozilla-aurora", 02:26:33 INFO - "revision": "ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b", 02:26:33 INFO - "pgo_build": "False", 02:26:33 INFO - "builduid": "d75212b9bf4f4cf6aa3646482ce5737c", 02:26:33 INFO - "slavebuilddir": "test" 02:26:33 INFO - } 02:26:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:26:33 INFO - Running post-action listener: _resource_record_post_action 02:26:33 INFO - [mozharness: 2017-01-19 10:26:33.504260Z] Finished read-buildbot-config step (success) 02:26:33 INFO - [mozharness: 2017-01-19 10:26:33.504485Z] Running download-and-extract step. 02:26:33 INFO - Running pre-action listener: _resource_record_pre_action 02:26:33 INFO - Running main action method: download_and_extract 02:26:33 INFO - mkdir: /builds/slave/test/build/tests 02:26:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:26:33 INFO - trying https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:26:33 INFO - Downloading https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:26:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 02:26:34 INFO - Downloaded 1199 bytes. 02:26:34 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:26:34 INFO - Using the following test package requirements: 02:26:34 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:26:34 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:26:34 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:26:34 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:26:34 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:26:34 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:26:34 INFO - u'jsshell-mac.zip'], 02:26:34 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:26:34 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:26:34 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:26:34 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:26:34 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:26:34 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:26:34 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:26:34 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:26:34 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:26:34 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:26:34 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:26:34 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:26:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:26:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:26:34 INFO - Fetch https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:26:34 INFO - Http code: 200 02:26:34 INFO - via: 1.1 ff09df097f823b2834408d17e9779d62.cloudfront.net (CloudFront) 02:26:34 INFO - x-amz-cf-id: evsUgWGl8ZqfURPARN2Ckq6Uj7SEAqv6ypmqxXkVX1hgewHNaZqJ4Q== 02:26:34 INFO - x-amz-version-id: wxqlFfp.fVR_t4VQM_yrDygbX1SPivwB 02:26:35 INFO - Expected file size: 16275910 02:26:35 INFO - Obtained file size: 16275910 02:26:41 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:26:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:26:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:26:41 INFO - Fetch https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:26:42 INFO - Http code: 200 02:26:42 INFO - via: 1.1 0576b942ae9f4fc9c0b62b0736e9bfd6.cloudfront.net (CloudFront) 02:26:42 INFO - x-amz-cf-id: mreUo27Y7QAGNB3AVeflYXJ_2HQpHPU-D3gdVEP49PwRiWlXuMY-Pg== 02:26:42 INFO - x-amz-version-id: wxqlFfp.fVR_t4VQM_yrDygbX1SPivwB 02:26:42 INFO - Expected file size: 16275910 02:26:42 INFO - Obtained file size: 16275910 02:26:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:26:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:26:48 INFO - Fetch https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:26:49 INFO - Http code: 200 02:26:49 INFO - via: 1.1 30bb04916f91d64c600e15c15000042d.cloudfront.net (CloudFront) 02:26:49 INFO - x-amz-cf-id: tldQl4eW7ENk0-XkYxLFLMGAGxT1a6E0rxlH7jgHu-OeWCIWlVyj1A== 02:26:49 INFO - x-amz-version-id: PdAtJUutIspdYAHZvNE1GizlcLUX_WnR 02:26:50 INFO - Expected file size: 14409038 02:26:50 INFO - Obtained file size: 14409038 02:26: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:26:50 INFO - trying https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:26:50 INFO - Downloading https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:26:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 02:26:53 INFO - Downloaded 110059874 bytes. 02:26:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:26:53 INFO - mkdir: /builds/slave/test/properties 02:26:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:26:53 INFO - Writing to file /builds/slave/test/properties/build_url 02:26:53 INFO - Contents: 02:26:53 INFO - build_url:https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:26:54 INFO - Running post-action listener: _resource_record_post_action 02:26:54 INFO - Running post-action listener: set_extra_try_arguments 02:26:54 INFO - [mozharness: 2017-01-19 10:26:54.123819Z] Finished download-and-extract step (success) 02:26:54 INFO - [mozharness: 2017-01-19 10:26:54.123975Z] Running populate-webroot step. 02:26:54 INFO - Running pre-action listener: _resource_record_pre_action 02:26:54 INFO - Running main action method: populate_webroot 02:26:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:26:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:26:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:26:54 INFO - '--tppagecycles', 02:26:54 INFO - '7'], 02:26:54 INFO - 'tests': ['tsvgm']}}, 02:26:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:26:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:26:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:26:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:26:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:26:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:26:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:26:54 INFO - 'talos_options': ['--disable-e10s'], 02:26:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:26:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:26:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:26:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:26:54 INFO - 'talos_options': ['--disable-e10s'], 02:26:54 INFO - 'tests': ['damp', 'tps']}, 02:26:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:26:54 INFO - 'tests': ['damp', 'tps']}, 02:26:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:26:54 INFO - 'tests': ['dromaeo_dom']}, 02:26:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:26:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:26:54 INFO - 'tests': ['basic_compositor_video']}, 02:26:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:26:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:26:54 INFO - 'tests': ['a11yr', 02:26:54 INFO - 'ts_paint', 02:26:54 INFO - 'tpaint', 02:26:54 INFO - 'sessionrestore', 02:26:54 INFO - 'sessionrestore_no_auto_restore', 02:26:54 INFO - 'tabpaint']}, 02:26:54 INFO - 'other-e10s': {'tests': ['a11yr', 02:26:54 INFO - 'ts_paint', 02:26:54 INFO - 'tpaint', 02:26:54 INFO - 'sessionrestore', 02:26:54 INFO - 'sessionrestore_no_auto_restore', 02:26:54 INFO - 'tabpaint']}, 02:26:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:26:54 INFO - 'ts_paint', 02:26:54 INFO - 'tpaint', 02:26:54 INFO - 'sessionrestore', 02:26:54 INFO - 'sessionrestore_no_auto_restore', 02:26:54 INFO - 'tabpaint']}, 02:26:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:26:54 INFO - 'ts_paint', 02:26:54 INFO - 'tpaint', 02:26:54 INFO - 'sessionrestore', 02:26:54 INFO - 'sessionrestore_no_auto_restore', 02:26:54 INFO - 'tabpaint']}, 02:26:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:26:54 INFO - 'tests': ['a11yr', 02:26:54 INFO - 'ts_paint', 02:26:54 INFO - 'tpaint', 02:26:54 INFO - 'sessionrestore', 02:26:54 INFO - 'sessionrestore_no_auto_restore', 02:26:54 INFO - 'tabpaint']}, 02:26:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:26:54 INFO - 'tests': ['a11yr', 02:26:54 INFO - 'ts_paint', 02:26:54 INFO - 'tpaint', 02:26:54 INFO - 'sessionrestore', 02:26:54 INFO - 'sessionrestore_no_auto_restore', 02:26:54 INFO - 'tabpaint']}, 02:26:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:26:54 INFO - 'tests': ['tsvgx', 02:26:54 INFO - 'tsvgr_opacity', 02:26:54 INFO - 'tart', 02:26:54 INFO - 'tscrollx', 02:26:54 INFO - 'cart']}, 02:26:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:26:54 INFO - 'tsvgr_opacity', 02:26:54 INFO - 'tart', 02:26:54 INFO - 'tscrollx', 02:26:54 INFO - 'cart']}, 02:26:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:26:54 INFO - 'talos_options': ['--disable-e10s'], 02:26:54 INFO - 'tests': ['tp5o']}, 02:26:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:26:54 INFO - 'tests': ['tp5o']}, 02:26:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:26:54 INFO - 'talos_options': ['--disable-e10s', 02:26:54 INFO - '--xperf_path', 02:26:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:26:54 INFO - 'tests': ['tp5n']}, 02:26:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:26:54 INFO - 'talos_options': ['--xperf_path', 02:26:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:26:54 INFO - 'tests': ['tp5n']}}, 02:26:54 INFO - 'talos.zip': {'path': '', 02:26:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:26:54 INFO - Running post-action listener: _resource_record_post_action 02:26:54 INFO - [mozharness: 2017-01-19 10:26:54.142689Z] Finished populate-webroot step (success) 02:26:54 INFO - [mozharness: 2017-01-19 10:26:54.142769Z] Running create-virtualenv step. 02:26:54 INFO - Running pre-action listener: _resource_record_pre_action 02:26:54 INFO - Running main action method: create_virtualenv 02:26:54 INFO - Creating virtualenv /builds/slave/test/build/venv 02:26:54 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:26:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:26:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:26:54 INFO - Using real prefix '/tools/python27' 02:26:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:26:54 INFO - Installing distribute.............................................................................................................................................................................................done. 02:26:57 INFO - Installing pip.................done. 02:26:57 INFO - Return code: 0 02:26:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:26:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:26:58 INFO - Reading from file tmpfile_stdout 02:26:58 INFO - Output received: 02:26:58 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:26:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:26:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:26:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:26:58 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 0x104c1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e1da5ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a2ab20>, '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.alyxNwdjcN/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.nIwG8BRhmX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:26:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:26:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.alyxNwdjcN/Render', 02:26:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:26:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:26:58 INFO - 'HOME': '/Users/cltbld', 02:26:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:26:58 INFO - 'LOGNAME': 'cltbld', 02:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:58 INFO - 'MOZ_NO_REMOTE': '1', 02:26:58 INFO - 'NO_EM_RESTART': '1', 02:26:58 INFO - 'PAGER': '/bin/cat', 02:26:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:58 INFO - 'PWD': '/builds/slave/test', 02:26:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:26:58 INFO - 'SHELL': '/bin/bash', 02:26:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIwG8BRhmX/Listeners', 02:26:58 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 02:26:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:26:58 INFO - 'USER': 'cltbld', 02:26:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:26:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:58 INFO - 'XPC_FLAGS': '0x0', 02:26:58 INFO - 'XPC_SERVICE_NAME': '0', 02:26:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:26:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:26:58 INFO - Cleaning up... 02:26:58 INFO - Return code: 0 02:26:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:26:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:26:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:26:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:26:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e1da5ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a2ab20>, '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.alyxNwdjcN/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.nIwG8BRhmX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:26:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:26:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.alyxNwdjcN/Render', 02:26:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:26:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:26:58 INFO - 'HOME': '/Users/cltbld', 02:26:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:26:58 INFO - 'LOGNAME': 'cltbld', 02:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:58 INFO - 'MOZ_NO_REMOTE': '1', 02:26:58 INFO - 'NO_EM_RESTART': '1', 02:26:58 INFO - 'PAGER': '/bin/cat', 02:26:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:58 INFO - 'PWD': '/builds/slave/test', 02:26:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:26:58 INFO - 'SHELL': '/bin/bash', 02:26:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIwG8BRhmX/Listeners', 02:26:58 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 02:26:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:26:58 INFO - 'USER': 'cltbld', 02:26:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:26:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:58 INFO - 'XPC_FLAGS': '0x0', 02:26:58 INFO - 'XPC_SERVICE_NAME': '0', 02:26:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:26:58 INFO - Downloading/unpacking psutil>=3.1.1 02:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:26:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:26:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:26:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:26:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:27:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:27:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:27:02 INFO - Installing collected packages: psutil 02:27:02 INFO - Running setup.py install for psutil 02:27:02 INFO - building 'psutil._psutil_osx' extension 02:27:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:27:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:27:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:27:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:27:02 INFO - building 'psutil._psutil_posix' extension 02:27:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:27:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:27:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:27:02 INFO - ^ 02:27:02 INFO - 1 warning generated. 02:27:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:27:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:27:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:27:03 INFO - Successfully installed psutil 02:27:03 INFO - Cleaning up... 02:27:03 INFO - Return code: 0 02:27:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:27:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e1da5ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a2ab20>, '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.alyxNwdjcN/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.nIwG8BRhmX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:27:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:27:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:27:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.alyxNwdjcN/Render', 02:27:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:03 INFO - 'HOME': '/Users/cltbld', 02:27:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:03 INFO - 'LOGNAME': 'cltbld', 02:27:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:03 INFO - 'MOZ_NO_REMOTE': '1', 02:27:03 INFO - 'NO_EM_RESTART': '1', 02:27:03 INFO - 'PAGER': '/bin/cat', 02:27:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:03 INFO - 'PWD': '/builds/slave/test', 02:27:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:03 INFO - 'SHELL': '/bin/bash', 02:27:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIwG8BRhmX/Listeners', 02:27:03 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 02:27:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:03 INFO - 'USER': 'cltbld', 02:27:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:03 INFO - 'XPC_FLAGS': '0x0', 02:27:03 INFO - 'XPC_SERVICE_NAME': '0', 02:27:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:27:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:27:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:27:06 INFO - Installing collected packages: mozsystemmonitor 02:27:06 INFO - Running setup.py install for mozsystemmonitor 02:27:06 INFO - Successfully installed mozsystemmonitor 02:27:06 INFO - Cleaning up... 02:27:06 INFO - Return code: 0 02:27:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:27:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e1da5ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a2ab20>, '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.alyxNwdjcN/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.nIwG8BRhmX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:27:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:27:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.alyxNwdjcN/Render', 02:27:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:06 INFO - 'HOME': '/Users/cltbld', 02:27:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:06 INFO - 'LOGNAME': 'cltbld', 02:27:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:06 INFO - 'MOZ_NO_REMOTE': '1', 02:27:06 INFO - 'NO_EM_RESTART': '1', 02:27:06 INFO - 'PAGER': '/bin/cat', 02:27:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:06 INFO - 'PWD': '/builds/slave/test', 02:27:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:06 INFO - 'SHELL': '/bin/bash', 02:27:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIwG8BRhmX/Listeners', 02:27:06 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 02:27:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:06 INFO - 'USER': 'cltbld', 02:27:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:06 INFO - 'XPC_FLAGS': '0x0', 02:27:06 INFO - 'XPC_SERVICE_NAME': '0', 02:27:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:06 INFO - Downloading/unpacking jsonschema==2.5.1 02:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:27:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:09 INFO - Downloading functools32-3.2.3-2.tar.gz 02:27:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:27:09 INFO - warning: no files found matching '*.txt' 02:27:09 INFO - no previously-included directories found matching 'build' 02:27:09 INFO - no previously-included directories found matching 'dist' 02:27:09 INFO - no previously-included directories found matching '.git*' 02:27:09 INFO - Installing collected packages: jsonschema, functools32 02:27:09 INFO - Running setup.py install for functools32 02:27:09 INFO - warning: no files found matching '*.txt' 02:27:09 INFO - no previously-included directories found matching 'build' 02:27:09 INFO - no previously-included directories found matching 'dist' 02:27:09 INFO - no previously-included directories found matching '.git*' 02:27:09 INFO - Successfully installed jsonschema functools32 02:27:09 INFO - Cleaning up... 02:27:09 INFO - Return code: 0 02:27:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e1da5ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a2ab20>, '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.alyxNwdjcN/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.nIwG8BRhmX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:27:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:27:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:27:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.alyxNwdjcN/Render', 02:27:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:09 INFO - 'HOME': '/Users/cltbld', 02:27:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:09 INFO - 'LOGNAME': 'cltbld', 02:27:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:09 INFO - 'MOZ_NO_REMOTE': '1', 02:27:09 INFO - 'NO_EM_RESTART': '1', 02:27:09 INFO - 'PAGER': '/bin/cat', 02:27:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:09 INFO - 'PWD': '/builds/slave/test', 02:27:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:09 INFO - 'SHELL': '/bin/bash', 02:27:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIwG8BRhmX/Listeners', 02:27:09 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 02:27:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:09 INFO - 'USER': 'cltbld', 02:27:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:09 INFO - 'XPC_FLAGS': '0x0', 02:27:09 INFO - 'XPC_SERVICE_NAME': '0', 02:27:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:27:10 INFO - Cleaning up... 02:27:10 INFO - Return code: 0 02:27:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:10 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 0x104c1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e1da5ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a2ab20>, '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.alyxNwdjcN/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.nIwG8BRhmX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:27:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:27:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.alyxNwdjcN/Render', 02:27:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:10 INFO - 'HOME': '/Users/cltbld', 02:27:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:10 INFO - 'LOGNAME': 'cltbld', 02:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:10 INFO - 'MOZ_NO_REMOTE': '1', 02:27:10 INFO - 'NO_EM_RESTART': '1', 02:27:10 INFO - 'PAGER': '/bin/cat', 02:27:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:10 INFO - 'PWD': '/builds/slave/test', 02:27:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:10 INFO - 'SHELL': '/bin/bash', 02:27:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIwG8BRhmX/Listeners', 02:27:10 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 02:27:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:10 INFO - 'USER': 'cltbld', 02:27:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:10 INFO - 'XPC_FLAGS': '0x0', 02:27:10 INFO - 'XPC_SERVICE_NAME': '0', 02:27:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:10 INFO - Downloading/unpacking blobuploader==1.2.4 02:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:13 INFO - Downloading blobuploader-1.2.4.tar.gz 02:27:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:27:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:27:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:14 INFO - Downloading docopt-0.6.1.tar.gz 02:27:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:27:14 INFO - Installing collected packages: blobuploader, requests, docopt 02:27:14 INFO - Running setup.py install for blobuploader 02:27:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:27:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:27:14 INFO - Running setup.py install for requests 02:27:15 INFO - Running setup.py install for docopt 02:27:15 INFO - Successfully installed blobuploader requests docopt 02:27:15 INFO - Cleaning up... 02:27:15 INFO - Return code: 0 02:27:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:15 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 0x104c1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e1da5ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a2ab20>, '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.alyxNwdjcN/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.nIwG8BRhmX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:27:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:27:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:27:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.alyxNwdjcN/Render', 02:27:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:15 INFO - 'HOME': '/Users/cltbld', 02:27:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:15 INFO - 'LOGNAME': 'cltbld', 02:27:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:15 INFO - 'MOZ_NO_REMOTE': '1', 02:27:15 INFO - 'NO_EM_RESTART': '1', 02:27:15 INFO - 'PAGER': '/bin/cat', 02:27:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:15 INFO - 'PWD': '/builds/slave/test', 02:27:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:15 INFO - 'SHELL': '/bin/bash', 02:27:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIwG8BRhmX/Listeners', 02:27:15 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 02:27:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:15 INFO - 'USER': 'cltbld', 02:27:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:15 INFO - 'XPC_FLAGS': '0x0', 02:27:15 INFO - 'XPC_SERVICE_NAME': '0', 02:27:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:27:15 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-5bajBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:27:15 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-w8cNDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:27:15 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-GocTNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:27:15 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-MzzJ47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:27:15 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-QfDJAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:27:16 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-i0I4WK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:27:16 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-enY72F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:27:16 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-fe1dCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:27:16 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-du3asT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:27:16 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-sW2TCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:27:16 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-0AY04V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:27:16 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-wic70x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:27:16 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-RHARuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:27:16 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-q_R7go-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:27:17 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-Q1zvFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:27:17 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-Q1tI2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:27:17 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-RXvbOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:27:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:27:17 INFO - Running setup.py install for manifestparser 02:27:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:27:17 INFO - Running setup.py install for mozcrash 02:27:17 INFO - Running setup.py install for mozdebug 02:27:17 INFO - Running setup.py install for mozdevice 02:27:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:27:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:27:17 INFO - Running setup.py install for mozfile 02:27:18 INFO - Running setup.py install for mozhttpd 02:27:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:27:18 INFO - Running setup.py install for mozinfo 02:27:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:27:18 INFO - Running setup.py install for mozInstall 02:27:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:27:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:27:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:27:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:27:18 INFO - Running setup.py install for mozleak 02:27:18 INFO - Running setup.py install for mozlog 02:27:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:27:18 INFO - Running setup.py install for moznetwork 02:27:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:27:18 INFO - Running setup.py install for mozprocess 02:27:19 INFO - Running setup.py install for mozprofile 02:27:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:27:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:27:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:27:19 INFO - Running setup.py install for mozrunner 02:27:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:27:19 INFO - Running setup.py install for mozscreenshot 02:27:19 INFO - Running setup.py install for moztest 02:27:19 INFO - Running setup.py install for mozversion 02:27:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:27:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:27:19 INFO - Cleaning up... 02:27:19 INFO - Return code: 0 02:27:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:27:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:19 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 0x104c1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e1da5ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a2ab20>, '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.alyxNwdjcN/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.nIwG8BRhmX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:27:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:27:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:27:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.alyxNwdjcN/Render', 02:27:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:19 INFO - 'HOME': '/Users/cltbld', 02:27:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:19 INFO - 'LOGNAME': 'cltbld', 02:27:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:19 INFO - 'MOZ_NO_REMOTE': '1', 02:27:19 INFO - 'NO_EM_RESTART': '1', 02:27:19 INFO - 'PAGER': '/bin/cat', 02:27:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:19 INFO - 'PWD': '/builds/slave/test', 02:27:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:19 INFO - 'SHELL': '/bin/bash', 02:27:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIwG8BRhmX/Listeners', 02:27:19 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 02:27:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:19 INFO - 'USER': 'cltbld', 02:27:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:19 INFO - 'XPC_FLAGS': '0x0', 02:27:19 INFO - 'XPC_SERVICE_NAME': '0', 02:27:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:27:20 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-F233FX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:27:20 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-YrHrE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:27:20 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-UI2B96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:27:20 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-jaLl6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:27:20 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-fAtwOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:27:20 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-0iSAy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:27:20 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-CNnn1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:27:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:27:20 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-ICmTAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:27:21 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-FoAXSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:27:21 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-kg_d3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:27:21 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-7D7Pli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:27:21 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-Feit2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:27:21 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-MQvLWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:27:21 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-0qfLPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:27:21 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-hPmgLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:27:21 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-I4g6xi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:27:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:27:21 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-ZdPJPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:27:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:27:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:27:24 INFO - Downloading blessings-1.6.tar.gz 02:27:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:27:24 INFO - Installing collected packages: blessings 02:27:24 INFO - Running setup.py install for blessings 02:27:24 INFO - Successfully installed blessings 02:27:24 INFO - Cleaning up... 02:27:25 INFO - Return code: 0 02:27:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:27:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:27:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:27:25 INFO - Reading from file tmpfile_stdout 02:27:25 INFO - Current package versions: 02:27:25 INFO - blessings == 1.6 02:27:25 INFO - blobuploader == 1.2.4 02:27:25 INFO - docopt == 0.6.1 02:27:25 INFO - functools32 == 3.2.3-2 02:27:25 INFO - jsonschema == 2.5.1 02:27:25 INFO - manifestparser == 1.1 02:27:25 INFO - mozInstall == 1.12 02:27:25 INFO - mozcrash == 1.0 02:27:25 INFO - mozdebug == 0.1 02:27:25 INFO - mozdevice == 0.48 02:27:25 INFO - mozfile == 1.2 02:27:25 INFO - mozhttpd == 0.7 02:27:25 INFO - mozinfo == 0.9 02:27:25 INFO - mozleak == 0.1 02:27:25 INFO - mozlog == 3.4 02:27:25 INFO - moznetwork == 0.27 02:27:25 INFO - mozprocess == 0.23 02:27:25 INFO - mozprofile == 0.28 02:27:25 INFO - mozrunner == 6.13 02:27:25 INFO - mozscreenshot == 0.1 02:27:25 INFO - mozsystemmonitor == 0.3 02:27:25 INFO - moztest == 0.8 02:27:25 INFO - mozversion == 1.4 02:27:25 INFO - psutil == 3.1.1 02:27:25 INFO - requests == 1.2.3 02:27:25 INFO - wsgiref == 0.1.2 02:27:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:27:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:26 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 0x104c1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e1da5ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a2ab20>, '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.alyxNwdjcN/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.nIwG8BRhmX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:27:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:27:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:27:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.alyxNwdjcN/Render', 02:27:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:26 INFO - 'HOME': '/Users/cltbld', 02:27:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:26 INFO - 'LOGNAME': 'cltbld', 02:27:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:26 INFO - 'MOZ_NO_REMOTE': '1', 02:27:26 INFO - 'NO_EM_RESTART': '1', 02:27:26 INFO - 'PAGER': '/bin/cat', 02:27:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:26 INFO - 'PWD': '/builds/slave/test', 02:27:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:26 INFO - 'SHELL': '/bin/bash', 02:27:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIwG8BRhmX/Listeners', 02:27:26 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 02:27:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:26 INFO - 'USER': 'cltbld', 02:27:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:26 INFO - 'XPC_FLAGS': '0x0', 02:27:26 INFO - 'XPC_SERVICE_NAME': '0', 02:27:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:27:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:27:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:27:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:27:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:27:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:27:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:27:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:27:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:27:26 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:27:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:27:26 INFO - Cleaning up... 02:27:26 INFO - Return code: 0 02:27:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:27:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c1e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e1da5ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a2ab20>, '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.alyxNwdjcN/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.nIwG8BRhmX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:27:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:27:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:27:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.alyxNwdjcN/Render', 02:27:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:26 INFO - 'HOME': '/Users/cltbld', 02:27:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:26 INFO - 'LOGNAME': 'cltbld', 02:27:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:26 INFO - 'MOZ_NO_REMOTE': '1', 02:27:26 INFO - 'NO_EM_RESTART': '1', 02:27:26 INFO - 'PAGER': '/bin/cat', 02:27:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:26 INFO - 'PWD': '/builds/slave/test', 02:27:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:26 INFO - 'SHELL': '/bin/bash', 02:27:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIwG8BRhmX/Listeners', 02:27:26 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 02:27:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:26 INFO - 'USER': 'cltbld', 02:27:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:26 INFO - 'XPC_FLAGS': '0x0', 02:27:26 INFO - 'XPC_SERVICE_NAME': '0', 02:27:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:27:27 INFO - Cleaning up... 02:27:27 INFO - Return code: 0 02:27:27 INFO - Running post-action listener: _resource_record_post_action 02:27:27 INFO - Running post-action listener: _start_resource_monitoring 02:27:27 INFO - Starting resource monitoring. 02:27:27 INFO - [mozharness: 2017-01-19 10:27:27.188200Z] Finished create-virtualenv step (success) 02:27:27 INFO - [mozharness: 2017-01-19 10:27:27.189257Z] Running install step. 02:27:27 INFO - Running pre-action listener: _resource_record_pre_action 02:27:27 INFO - Running main action method: install 02:27:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:27:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:27:27 INFO - Reading from file tmpfile_stdout 02:27:27 INFO - Detecting whether we're running mozinstall >=1.0... 02:27:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:27:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:27:27 INFO - Reading from file tmpfile_stdout 02:27:27 INFO - Output received: 02:27:27 INFO - Usage: mozinstall [options] installer 02:27:27 INFO - Options: 02:27:27 INFO - -h, --help show this help message and exit 02:27:27 INFO - -d DEST, --destination=DEST 02:27:27 INFO - Directory to install application into. [default: 02:27:27 INFO - "/builds/slave/test"] 02:27:27 INFO - --app=APP Application being installed. [default: firefox] 02:27:27 INFO - mkdir: /builds/slave/test/build/application 02:27:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:27:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:27:58 INFO - Reading from file tmpfile_stdout 02:27:58 INFO - Output received: 02:27:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:27:58 INFO - Running post-action listener: _resource_record_post_action 02:27:58 INFO - [mozharness: 2017-01-19 10:27:58.862050Z] Finished install step (success) 02:27:58 INFO - [mozharness: 2017-01-19 10:27:58.862231Z] Running run-tests step. 02:27:58 INFO - Running pre-action listener: _resource_record_pre_action 02:27:58 INFO - Running main action method: run_tests 02:27:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:27:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:27:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:27:58 INFO - 2017-01-19 02:27:58.877 screenresolution[29532:270026] starting screenresolution argv=screenresolution get 02:27:58 INFO - 2017-01-19 02:27:58.879 screenresolution[29532:270026] Display 0: 1600x1200x32@60 02:27:58 INFO - 2017-01-19 02:27:58.885 screenresolution[29533:270028] starting screenresolution argv=screenresolution list 02:27:58 INFO - Available Modes on Display 0 02:27:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:27:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:27:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:27:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:27:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:27:59 INFO - Intel Iris: 02:27:59 INFO - Chipset Model: Intel Iris 02:27:59 INFO - Type: GPU 02:27:59 INFO - Bus: Built-In 02:27:59 INFO - VRAM (Dynamic, Max): 1536 MB 02:27:59 INFO - Vendor: Intel (0x8086) 02:27:59 INFO - Device ID: 0x0a2e 02:27:59 INFO - Revision ID: 0x0009 02:27:59 INFO - Displays: 02:27:59 INFO - Display: 02:27:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:27:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:27:59 INFO - Display Serial Number: 1600x1200 60 02:27:59 INFO - Main Display: Yes 02:27:59 INFO - Mirror: Off 02:27:59 INFO - Online: Yes 02:27:59 INFO - Rotation: Supported 02:27:59 INFO - Return code: 0 02:27:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:27:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:27:59 INFO - Python 2.7.3 02:27:59 INFO - Return code: 0 02:27:59 INFO - grabbing minidump binary from tooltool 02:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:59 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a2ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:27:59 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:27:59 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:27:59 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:27:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:27:59 INFO - Return code: 0 02:27:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:27:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:27:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:27:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:27:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:27:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:27:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0031 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:27:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.alyxNwdjcN/Render', 02:27:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:59 INFO - 'HOME': '/Users/cltbld', 02:27:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:59 INFO - 'LOGNAME': 'cltbld', 02:27:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:27:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:59 INFO - 'MOZ_NO_REMOTE': '1', 02:27:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:27:59 INFO - 'NO_EM_RESTART': '1', 02:27:59 INFO - 'PAGER': '/bin/cat', 02:27:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:59 INFO - 'PWD': '/builds/slave/test', 02:27:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:27:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:59 INFO - 'SHELL': '/bin/bash', 02:27:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIwG8BRhmX/Listeners', 02:27:59 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 02:27:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:59 INFO - 'USER': 'cltbld', 02:27:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:59 INFO - 'XPC_FLAGS': '0x0', 02:27:59 INFO - 'XPC_SERVICE_NAME': '0', 02:27:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:27:59 INFO - mozversion application_buildid: 20170119004006 02:27:59 INFO - mozversion application_changeset: ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b 02:27:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:27:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:27:59 INFO - mozversion application_name: Firefox 02:27:59 INFO - mozversion application_remotingname: firefox-dev 02:27:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:27:59 INFO - mozversion application_vendor: Mozilla 02:27:59 INFO - mozversion application_version: 52.0a2 02:27:59 INFO - mozversion platform_buildid: 20170119004006 02:27:59 INFO - mozversion platform_changeset: ed6e5d97950a01e80679a17e7abd4a3ed28e8f6b 02:27:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:27:59 INFO - mozversion platform_version: 52.0a2 02:27:59 INFO - using testdate: 1484821679 02:27:59 INFO - actual date: 1484821679 02:27:59 INFO - starting webserver on 'localhost:49299' 02:27:59 INFO - SUITE-START | Running 2 tests 02:27:59 INFO - TEST-START | dromaeo_css 02:27:59 INFO - operating with platform_type : mac_ 02:27:59 INFO - Initialising browser for dromaeo_css test... 02:27:59 INFO - TEST-INFO | started process 29543 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/tmpoYbI4V/profile http://localhost:49299/getInfo.html) 02:28:03 INFO - PROCESS | 29543 | 2017-01-19 02:28:03.069 plugin-container[29546:270208] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d47, name = 'com.apple.tsm.portname' 02:28:03 INFO - PROCESS | 29543 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:03 INFO - PROCESS | 29543 | 2017-01-19 02:28:03.069 plugin-container[29546:270208] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9403, name = 'com.apple.CFPasteboardClient' 02:28:03 INFO - PROCESS | 29543 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:03 INFO - PROCESS | 29543 | 2017-01-19 02:28:03.069 plugin-container[29546:270208] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:28:03 INFO - PROCESS | 29543 | __metrics Screen width/height:1600/1200 02:28:03 INFO - PROCESS | 29543 | colorDepth:24 02:28:03 INFO - PROCESS | 29543 | Browser inner width/height: 1024/689 02:28:03 INFO - PROCESS | 29543 | __metrics 02:28:03 ERROR - PROCESS | 29543 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 02:28:03 INFO - TEST-INFO | 29543: exit 0 02:28:03 INFO - Browser initialized. 02:28:03 INFO - Running cycle 1/1 for dromaeo_css test... 02:28:03 INFO - TEST-INFO | started process 29548 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/tmpoYbI4V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:28:06 INFO - PROCESS | 29548 | 2017-01-19 02:28:06.803 plugin-container[29549:270341] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 02:28:06 INFO - PROCESS | 29548 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:06 INFO - PROCESS | 29548 | 2017-01-19 02:28:06.803 plugin-container[29549:270341] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 02:28:06 INFO - PROCESS | 29548 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:06 INFO - PROCESS | 29548 | 2017-01-19 02:28:06.803 plugin-container[29549:270341] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:28:16 INFO - PROCESS | 29548 | RSS: Main: 229101568 02:28:16 INFO - PROCESS | 29548 | 02:31:18 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:49299/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49299/tests/dromaeo/cssquery-ext.html) 02:31:20 INFO - PROCESS | 29548 | RSS: Main: 235397120 02:31:20 INFO - PROCESS | 29548 | 02:34:22 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:49299/tests/dromaeo/cssquery-ext.html (next: http://localhost:49299/tests/dromaeo/cssquery-jquery.html) 02:34:23 INFO - PROCESS | 29548 | RSS: Main: 243535872 02:34:23 INFO - PROCESS | 29548 | 02:37:25 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:49299/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49299/tests/dromaeo/cssquery-mootools.html) 02:37:25 INFO - PROCESS | 29548 | RSS: Main: 247283712 02:37:25 INFO - PROCESS | 29548 | 02:40:27 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:49299/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49299/tests/dromaeo/cssquery-prototype.html) 02:40:28 INFO - PROCESS | 29548 | RSS: Main: 247619584 02:40:28 INFO - PROCESS | 29548 | 02:43:31 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:49299/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49299/tests/dromaeo/cssquery-yui.html) 02:43:31 INFO - PROCESS | 29548 | RSS: Main: 251142144 02:43:31 INFO - PROCESS | 29548 | 02:46:34 INFO - PROCESS | 29548 | Cycle 1(1): loaded http://localhost:49299/tests/dromaeo/cssquery-yui.html (next: http://localhost:49299/tests/dromaeo/cssquery-dojo.html) 02:46:34 INFO - PROCESS | 29548 | RSS: Main: 252473344 02:46:34 INFO - PROCESS | 29548 | 02:46:34 INFO - PROCESS | 29548 | __start_tp_report 02:46:34 INFO - PROCESS | 29548 | _x_x_mozilla_page_load 02:46:34 INFO - PROCESS | 29548 | _x_x_mozilla_page_load_details 02:46:34 INFO - PROCESS | 29548 | |i|pagename|runs| 02:46:34 INFO - PROCESS | 29548 | |0;dojo.html;2630.3950091478973;2777.916662500125;3332.250243695169;3376.290978894433;3382.0699307690384;5677.602567820254;5711.657300561969;5739.483446489815;5746.540276777858;5763.279590051244;5253.684778913268;5616.680620024469;5700.942990570104;5807.854803629901;5861.472467477926;4554.3168524721395;5240.423553409122;5256.290400795901;5297.947020529803;5302.522772950434;4695.389599352079;5082.932584157332;5209.531142197202;5314.070037743399;5366.007288651593;5203.427622961472;5220.634555581111;5323.281357016811;5329.387120481136;5373.247745315659;4501.797419116164;4827.179379505493;5264.947350526503;5350.866228344323;5368.597355198376;5185.896282074337;5240.056789777838;5267.130923397634;5268.341457317835;5281.287026251445;4849.393825771778;4991.675541089818;5587.8882422351735;5776.682282474462;5779.595428320019;6096.451319381233;6131.4072507432475;6132.447291485822;6143.170671959318;6149.385061493859;6133.957227271375;6140.078988151813;6167.444929956326;6216.595921265149;6226.564140510166;5886.852828679274;5895.616784908967;5898.498627616701;5930.762769489215;5984.790532331391;6149.569530132937;6197.349278325801;6214.564980451325;6221.751129954841;6241.687915604201;6139.416755408229;6140.263168419729;6153.938460615426;6155.599886007485;6169.198004259418;6163;6228.719707613168;6233.657148856766;6251.374862513712;6260.718267677966;6053.182820319201;6116.396640261158;6123.08153776937;6184.330230837649;6187.16473276102;6159.876802463926;6213.316535181127;6221.129041934133;6236.688165591702;6237;4902.779374928136;4961.454240033593;4966.751662416865;4970.254461830755;4998.925016124761;4238.703290769617;4290.12052529237;4363.061941682554;4431.290993441098;4463.263561512379;5754.0505816540635;5782.10377391505;5868.67722275279;5895.793647222326;5904.970475147683;6209.441150296405;6226.377362263737;6233.937660623426;6237;6242.375762423721;5608.1587761835235;5681.659100453986;5770.076787713946;5836.124581312837;5861.824145275648;5423.457654234624;5439.891202176013;5449;5452.700101494456;5481;6335.176427064544;6391.680415979183;6411.6153030818305;6426.710798014007;6433.999999999906;6113.84715382119;6123.387661233841;6157.599756015845;6172.382761723792;6231.127642130196;6417.48660107181;6424;6430.453411460078;6433.871322573429;6448.903266450913;5349.742810439621;6223.0354295084335;6228.968855155696;6229.2524897010835;6232.407921247474;6020.8193754185695;6142.069075791586;6167.5682702210415;6263.060540918898;6266.749330026748;6055.909161362495;6068.302145253176;6111.083337499411;6135.815925522242;6145.446909778141;4612.377329060668;5619.775208991595;6059.575829691879;6114.48026917717;6147.600405973598;4855.927161092657;5596.768710883599;5864.67744274069;5978.163057172086;6000.639961602318;5559.471850174227;5986.4013598639785;6025.548083893638;6089.908651370321;6122.916604401471;5937.643741375531;5992.221011268507;5992.9811931972545;5998.970005149947;6010.609310394811 02:46:34 INFO - PROCESS | 29548 | |1;ext.html;483.34265399057284;487.4126677353785;489.3100727973558;501.60122702451525;503.48895870691246;28753.84984600616;31070.2232444189;39009.80495097524;39753.80123099388;39849.80075099628;40485.59514404855;40813.387799182965;40884.38673419894;41020.38469422961;41836.58163418373;9166.175044246016;9326.300527460433;9328.533573321341;9350.953245233764;9362.391444556082;9242.02958689338;9353.392029518094;9370;9398.201152902011;9398.436093834358;9332.48671323077;9340.766480837965;9401.62393504259;9402.200812930909;9415.246780257597;9289.349745517782;9305.720828375159;9361.625534978593;9373.578188981444;9381.671641492549;9289.163975242192;9320.16118549327;9384.155426011623;9390.436573805526;9392.530373481299;38961.80519097415;38963;39214.60785392167;39334;39411.21177576461;3415.1803567143957;3440.7419443541585;3463.290025544757;3907.711700802475;4144.689148313889;24591.508169836503;28216.435671286665;29089.96370254083;29217.70782292171;30048;3109.673484284163;3147;3202.8078315300922;3230.709236168733;3245.496947973068;491460.62809057895;493877;496524;496877;497789;46855.531444685796;56138.15792763112;56243.875122497324;56520;56866;57668.134977975365;58210.417895821345;58362.41637583655;58498;58640.7067964666;15807.841921580866;15911.80661450396;16073.839261607234;16076.678466430605;16123.435679751152;12389.566365177176;12501.3749312534;12515.249085054926;12546.247225166519;12548.933340666146;14456.927715361566;15571.299291531908;15698.372065117495;15731.44939927097;15813.762793558106;4950.890760668636;5623.718814059281;5631.746571404296;5716.456936591017;5912.497437717756;776.9689212431553;812.7155495576501;937.437537477508;1003.8556046107443;1007.6171054999201;28759.424811504072;29304.56043159354;30120;30466.77682341678;31298.68701313003;885.7696998780364;889.4440974391006;928.9688445825128;995.8257304971601;1003.5684655598017;13943.790843137362;14850.851491485162;14958.476453324081;15236.314365853563;15264;677021;919563;959223;962598;963644;46100.76949615298;55698.443015569326;56139.43860561424;56583.43416565864;56702.71648641813;1323.589687196972;1336.211635135275;1353.749556332062;1362.4822567424515;1367.5555444480385;25644.743552564236;29205.415891681623;29628;29659.110226693232;30583.847080764455;48626.51373486361;48774.756126219145;49275.26087108625;49346;49462.75268623778;627715;631102;634159;640566;642786;21785.23751668684;25851.0952116675;26300.736992629827;26601.467970640864;26609.467810644066;43255;43597.910052248946;44078.779606101765;44360.556394436935;44618;34384.82807585946;35945.1013724659;36451;37525.62474375221;37819.243615126994;36483.27033459369;36538;36779.89660310195;36975;37090.81454592818;31305.530417044218;35597.46603800893;35922.64077359193;36222;36310.63689363178;30925.69074309228;33089;33153;33165.17087072746;33195.50206746949;24778.752212478365;25084.49831003406;25160.874195628905;25682.743172568036;25685.229443116736 02:46:34 INFO - PROCESS | 29548 | |2;jquery.html;341.1607445683618;341.5082281514619;368.033910983668;372.0753926492665;384.6538115695875;15583.610409739762;16857.747133793004;18316.80940738851;19182.328618498337;20450.386488405355;19287;19311.324103656374;19426.1258243379;19597.804021959775;19842.503937401572;5546.223528705974;5769.798057068004;5771.50942169916;5805.5065319447685;5853.180554722342;5411.945880541183;5690.687012214326;5737.3688894221605;5739.856503587423;5745.2818397700285;4843;5645.604807663465;5669.6314739541795;5678.5741069419755;5691.260136182291;4550.931736023962;5880.353161152312;5895.764169433218;5956.404359564052;6046.788362407295;5615.775368985236;5782.7397767100165;5805.912911306334;5813.447722466359;5817.243758311435;12912.741745165138;12972.286524241163;13188.406521706545;13193.802092968614;13199.802002969964;2240.271911628727;2326.5695846268495;2358.4339758457845;2395.8802059896934;2742.369255071325;9863.80272394548;10163.847542286872;10701.090407315387;11023.724406889813;15766.369345226178;1748.7901451825735;1831.3773317072125;1842.7788665360242;1885.7831349394721;1909.2458478900896;120417;122525.77474225232;128253.35873320667;128524;128617;9532.332736708364;9773.778277715286;11055.0603198729;11597.246178998337;11664.825027624423;12087.697807554705;14532;14681.339339729739;14730.852691473163;14751.188684622233;6951.7914462566205;8208.794780130425;8428.536430496382;8585.227329540372;8593.226609605166;6756.966215168991;8341.874871876928;8364.53995030267;8477.915220847835;8487.745367638981;4459.308807134899;4505.617022553054;4506.909861802811;4507.842225522091;4585.060062687141;1856.9350072747388;1910.6656335141295;1911.1590900003953;1919.625672993753;1919.7888232294436;821.572782153277;845.556083056388;891.4695756025154;894.789724414762;929.9163075323254;19727.901360493106;20002.299919502748;20456.386308410772;20592;20749.688754668692;793.7856778669727;888.6623083228461;905.1265528764774;905.9275257979345;951.9714408567754;10473.68578942633;10910.672679819618;10922.29005114665;10933.890661093446;10954.068904143078;574380;576937;580004.0999794974;594228;594586;130036.82324840727;131604.3419782914;133934;136569;136775;920.061537232033;924.58393722825;937.4047479850208;939.6570251858022;964.599691128174;17202;17965.191566379282;18307.084645767925;18359.265629374677;18407.723884142015;47225;55703.88592228214;55757;56290.49676025826;56963.14555281757;61279.387206127365;78220.60889695743;80862.59568702118;81684.9578760512;83567.16432835594;7820.960895195488;8065.919340806517;8133.959330203311;8242.917570824215;8338.916610833814;9088.954555227181;10723.624673136403;11989.160758746804;12851.357432128169;12980.480780768663;5405.162199859028;5501.504864562208;11031.896810318904;11261.211715180187;11351.886481135414;10801.891981080089;11121.777564448828;11205.66383008511;11282.548698051985;11293.43532823372;11423.600173993871;11584.884151158381;11585.536578536765;11586.246893952204;11604.825927611262;8213.917860821315;8273.917260827315;8384.035835878953;8405.95797021011;8450.704225352083;11164.832527511931;11255.04332131778;11333.150013748836;11353.489092991;11528.365939873387 02:46:34 INFO - PROCESS | 29548 | |3;mootools.html;1430.2777097565727;1473.1087691946373;1621.9918900405496;1711.3753479979796;1771.3180425536173;7471.551706897576;7719.382449404048;8035.156308587594;8478.91521084789;8511.531865747382;7988.7203947861835;8315.542645154514;8316;8399.580020998956;8401.201885820852;2314.930552083436;2338.555674421863;2345.6833327500826;2351.329870986769;2354.846934949223;2124.8618839775363;2146.592147491979;2161.27597254919;2164.2208804830248;2167.2089687264115;2200.966985495219;2228.0864845413385;2298.804601608857;2345.3901985483812;2353.8587684738886;2314.5023819878807;2318.038014224095;2327.138958585317;2337.8129749620075;2340.4031971847157;2345.1205797825814;2351.741308456086;2357.905683772647;2359.9528009439887;2370.4073981504625;6490.578112422678;6941.583504989717;7192.496525243235;8179.631916563769;8224.794380140485;5007;5161.122609156415;5164.122099243138;5176.0683077046115;5196.142636464979;3219.0181994491672;3245.172481017349;3540.8760693375866;3548.290341931598;3551.5915669698056;940.3840484482674;976.584951395661;1052.9052385285283;1087.6247694545368;1088.58633719187;10406.635767748168;10727.302725322828;10821.242513024092;10824.51289691958;11030.724231894186;4338.305871060616;4382.496012958487;4457.086297309044;4494.460664720189;4503.864884053483;8613.741587752253;8681.739547813575;8684.60919258635;8691.043985161627;8694.695685650971;5063.265826455144;5083.517065878736;5110.770015349318;5119.59043276537;5120.129577971754;4241.512226094039;4261.829526818955;4714.787834547385;4738.360321356642;4809.302651115639;3599.784012959231;3663.6153203913736;3673.430618254175;3696.131409118855;3701.0747313171705;1112.5382966069153;1150.8331291962618;1166.848309719731;1171.9569583071072;1177.717347836513;760.422079219797;840;889.9332550058642;906.795970906554;918.1231923513029;5447.155690867922;5528.710417301699;5555.472230138131;5655.462731040545;5904.025835737141;818.5007145641155;1019.2152042926905;1019.3883669798062;1021.4228960637216;1054.7468607534136;3738.476613274144;3796.867109651149;3826.445165450994;3857.18999010211;3865.033741564609;9865.852012219822;10132.797344053077;10219.284650074424;10235;10275.972402759664;10223.437710925822;10231.693049208536;10260.692179234635;10261.640842570474;10272.845907311395;7082.504224704324;7335.633218339061;7808.882866757003;7961.72133975308;7967.482113662593;5530.115181570929;5577.2191893135805;5778.913316300175;5848.2689663792025;5923.318818335946;7484.887726683995;9274;9394.107559781809;9464.668736594185;9469.905300947177;9377.484238366955;9471.242300616072;9555.23558115363;9623.374480658735;9642;6571.605703657796;6767.25560188369;7031.859362812817;7138.571685698667;7255.310745479171;7781.4163937706035;7939.483933544301;7972;7988.440809143304;7988.720394786154;6899.724011039502;7133.5719856808755;7172.462065345016;7211.134663840373;7214.74748383804;6871.244163141951;6993.335633114846;7020.403265722266;7068.434525237866;7098.716051358095;6235.532335074797;6677.09859169006;6771.830704232433;6784.59292442455;6801.5238933274195;4872.220444728827;4874.756262186947;4888.144574699484;4904;4948.455669876239;4841.75791210431;4880.731559764105;4898.363212782316;4948.950510494849;4986.351774269322 02:46:34 INFO - PROCESS | 29548 | |4;prototype.html;284.92734352740047;300.15506349625815;300.9286938498943;309.5078824668776;316.3593722711509;4285.507166675833;4649.69776964497;4987.426445958716;5355.732213389329;5384.757685904134;4557.316402539613;4989.106318335366;5347.759350829212;5353.812616558421;5367.785288588461;3316.502524621302;3760.304343696425;3903.746256493333;4168.458100446937;4223.64099051581;4027.3556231003045;4193.811278492475;4206.49522057352;4224.091820258644;4246.490421149451;3247.7564182686274;4156.480439945007;4162.666986641061;4167.874963751093;4205.894852628693;3384.627690953993;3696.5194524711883;3792.886213413602;4148.937765933513;4155.563665815076;3405.4210784166753;3639.344917914774;3648.5074514940407;3671.6328367163337;3683.7475258412;4135.627793498599;4814.518548145193;4890.462049174588;5167.560757335631;5312.7061222042585;4739.786709598042;4755.548222918852;4772.331873537708;4788.353572267734;4796.904061918777;4322.433991956648;4432.911341773147;5417.539509141728;5702.714864256771;5709.571782116358;3616.2405894762132;3730.8694195703292;3757.9060523487133;3874.713918787929;4023.718339716221;5148.794048238028;5290.788368465257;5790.913136302959;5997.610155339889;5998.220231369937;3995.2808494471274;4055.4727885374714;4065.654419374386;4066.430699702044;4099.508059032935;3736.645018723217;4023.3562629979065;4115.32097203964;4136.338185890303;4144.896377590584;3579.744760800731;4298.969226695235;4680.542715074545;4724.149653062413;4756.500567440438;4866.31871537985;5204.76578553966;5801.564882633819;5805.506531944748;5825.475707186289;1129.3619105205532;1140.9087273018138;1215.3437143942172;1230.839990801208;1313.6847156682518;735.9668814903342;776.8096816280048;866.9089745576624;867.2108381372921;887.4852585500523;4232.513260974965;4279;4678.5496496086325;5028.924566131511;5057.519535644108;4400.69195156336;4859.611231101503;4997.800527873357;5110.310108135429;5496.657640463989;4504.887377815581;4994.225894986283;5031.6729412588065;5070.974645126751;5086.516780905808;2228.9331320060423;2919.6496420429685;2924.956125658117;2946.543285790706;2956.807807492506;5138.87152821175;5340.332458442695;5751.827445176651;5751.913721294184;5814.505767009767;4759.666823322404;4846.418429788378;5685.346185188673;5784.871949969715;5785.363610002981;5289.444608316071;6173.783917562953;6220.813375598739;6232.937670623236;6252.562320637512;6013.939860601338;6032.883916475466;6034.465524479641;6037.909431358621;6059.575829692056;4906;5624.943750562442;5766.106253530709;5869.589128760946;5941.821745347646;5467.371252306036;5660.217031635022;6114.104627565469;6253.405926436982;6289.9056514153235;3728.478013078117;3744.569374521965;3786.67813235878;4004.539477960072;4020.537638171648;5834.766609335748;5863.677497737494;5869.7945571904775;5907.261592300963;5933.940660593339;5095.847124586268;5552.861178470571;5651.660900345993;5668.291463567054;5879.5002424792665;4732.739699316433;5806.65160090396;5852.326982397079;5865.60648892709;5866.677332746569;4913.43495498008;5354.919676204938;5428.782848686008;5450.509454149145;5636.971815140898;5769.7595017071535;5808.556454312539;5834.7082645866685;5840.890230856124;5874;5470.972645136749;5689.487946084872;5700.144978253295;5720.3707592164;5756.884862302814 02:46:34 INFO - PROCESS | 29548 | |5;yui.html;644.2237104289331;768.4160038370838;774.3688893551752;774.8915151878738;777.4363586399861;12916.160449570783;13536.999999999987;13778.724425511497;14025.859741402583;14121.788173177385;12584.622461326175;12667.619971400849;12672.68318292041;12698.619041428748;12808.67978300543;800.7757827808223;812.8821320908464;826.5949684654506;827.1645637905698;827.7971896885241;647.4464332995292;666.7099811581953;801.6432687454085;818.8321394114192;827.4662842466605;847.089378917666;864.3733293362294;865.9523726195057;866.0906048648925;867.7917299848023;850.6172222499838;856.2422256303186;859.2266959736224;861.51324501657;861.8448679237734;625.6465097220056;634.0995785983914;637.6365471681144;639.2999665366416;641.5188608543592;10453.006964338376;11420.086393088533;13034.543790967271;13047;13155.934220328932;11685.357305348203;12221.877781222163;12258.264504129691;12264;12360.25838449696;9908.306418550705;10441.373517588969;10568.309070548676;10591.84112238317;10656.786864262749;2755.8070935034557;3106.5961425014602;3401.8639254429795;3406.5741782277214;3461.1174150591437;55172.17241741377;80401;84695.57652211761;85331;85790.57104714499;9398.436093834393;9404.482753448488;9429.434233945985;9565.713028609152;9767.413955162712;18742;18769.53076173079;18918;18952.52618684544;18991.240350386106;6942.375186233263;7017.122859642544;7108.680109394986;7301.342879140797;7303.45224108194;3635.9818200909353;3945.5265368156006;3988.66096381805;4003.259397011562;4008.5590585035625;2650.814442989011;3074.308280636839;3080.6919308069014;3315.7678962472396;3342.8662853486076;540.1816248383702;603.9486643635253;610.4414460768436;616.202018386191;620.0358442621766;583.7402355951646;587.952963762898;653.3401264722664;653.8888388973886;656.1535619051466;8249.340052795762;8731.345149113842;8883.244924181517;8908.465492070496;8950;636.9521394360576;658.9143411356494;670.33971538035;675.8952362383839;680.9829754256183;4176.519700234451;4262.573742625775;4564.1556312082375;4628.652851036186;4812.5668689818085;76932;84853.57573212094;114821;114879;117432;97202.51398743103;98251;98353;100213.4967975481;103717;85032.14967850242;86276.56861715652;86646.56676716702;87773;88086;7797.298243158143;9388.624455021723;9400.576974036321;9402.43585384879;9411.670591529264;43194.35208471938;54385.45614543804;54506.72746636241;56032.43967560272;56916.43083569277;75525.86711199446;77631;80249.19750802417;80445.391092179;83357.16642833494;10608.628697995533;11401.714957126138;11793.174477786473;12011.81982270284;12099.697507562028;12652.367381630698;13497.662558435724;13604.931975340456;14183.503577374742;14205.28973551339;12282.447289871798;12333.93833030829;12394.442250098587;12650.114491985472;12771.93614031924;12488.43802028929;12591.685207869876;12624.053196010153;12749.6175114743;12844.422001010164;8648.394612377073;9207.86188207164;9745.707628771148;11539.826902596637;11789.99785018283;7946;10108.949455252676;10488.423136727248;11107;11166.609168679057;10094.99050095014;10414.764477839715;10460.790784184426;10465.529051192867;10559.208059395729 02:46:34 INFO - PROCESS | 29548 | __end_tp_report 02:46:34 INFO - PROCESS | 29548 | __start_cc_report 02:46:34 INFO - PROCESS | 29548 | _x_x_mozilla_cycle_collect,375 02:46:34 INFO - PROCESS | 29548 | __end_cc_report 02:46:34 INFO - PROCESS | 29548 | __startTimestamp1484822794663__endTimestamp 02:46:34 INFO - PROCESS | 29548 | 02:46:34 INFO - PROCESS | 29548 | ------- Summary: start ------- 02:46:34 INFO - PROCESS | 29548 | Number of tests: 6 02:46:34 INFO - PROCESS | 29548 | 02:46:34 INFO - PROCESS | 29548 | [#0] dojo.html Cycles:165 Average:5700.49 Median:5978.16 stddev:695.05 (11.6%) stddev-sans-first:654.12 02:46:34 INFO - PROCESS | 29548 | Values: 2630.4 2777.9 3332.3 3376.3 3382.1 5677.6 5711.7 5739.5 5746.5 5763.3 5253.7 5616.7 5700.9 5807.9 5861.5 4554.3 5240.4 5256.3 5297.9 5302.5 4695.4 5082.9 5209.5 5314.1 5366.0 5203.4 5220.6 5323.3 5329.4 5373.2 4501.8 4827.2 5264.9 5350.9 5368.6 5185.9 5240.1 5267.1 5268.3 5281.3 4849.4 4991.7 5587.9 5776.7 5779.6 6096.5 6131.4 6132.4 6143.2 6149.4 6134.0 6140.1 6167.4 6216.6 6226.6 5886.9 5895.6 5898.5 5930.8 5984.8 6149.6 6197.3 6214.6 6221.8 6241.7 6139.4 6140.3 6153.9 6155.6 6169.2 6163.0 6228.7 6233.7 6251.4 6260.7 6053.2 6116.4 6123.1 6184.3 6187.2 6159.9 6213.3 6221.1 6236.7 6237.0 4902.8 4961.5 4966.8 4970.3 4998.9 4238.7 4290.1 4363.1 4431.3 4463.3 5754.1 5782.1 5868.7 5895.8 5905.0 6209.4 6226.4 6233.9 6237.0 6242.4 5608.2 5681.7 5770.1 5836.1 5861.8 5423.5 5439.9 5449.0 5452.7 5481.0 6335.2 6391.7 6411.6 6426.7 6434.0 6113.8 6123.4 6157.6 6172.4 6231.1 6417.5 6424.0 6430.5 6433.9 6448.9 5349.7 6223.0 6229.0 6229.3 6232.4 6020.8 6142.1 6167.6 6263.1 6266.7 6055.9 6068.3 6111.1 6135.8 6145.4 4612.4 5619.8 6059.6 6114.5 6147.6 4855.9 5596.8 5864.7 5978.2 6000.6 5559.5 5986.4 6025.5 6089.9 6122.9 5937.6 5992.2 5993.0 5999.0 6010.6 02:46:34 INFO - PROCESS | 29548 | 02:46:34 INFO - PROCESS | 29548 | [#1] ext.html Cycles:180 Average:77874.64 Median:39801.80 stddev:188706.64 (474.1%) stddev-sans-first:189146.54 02:46:34 INFO - PROCESS | 29548 | Values: 483.3 487.4 489.3 501.6 503.5 28753.8 31070.2 39009.8 39753.8 39849.8 40485.6 40813.4 40884.4 41020.4 41836.6 9166.2 9326.3 9328.5 9351.0 9362.4 9242.0 9353.4 9370.0 9398.2 9398.4 9332.5 9340.8 9401.6 9402.2 9415.2 9289.3 9305.7 9361.6 9373.6 9381.7 9289.2 9320.2 9384.2 9390.4 9392.5 38961.8 38963.0 39214.6 39334.0 39411.2 3415.2 3440.7 3463.3 3907.7 4144.7 24591.5 28216.4 29090.0 29217.7 30048.0 3109.7 3147.0 3202.8 3230.7 3245.5 491460.6 493877.0 496524.0 496877.0 497789.0 46855.5 56138.2 56243.9 56520.0 56866.0 57668.1 58210.4 58362.4 58498.0 58640.7 15807.8 15911.8 16073.8 16076.7 16123.4 12389.6 12501.4 12515.2 12546.2 12548.9 14456.9 15571.3 15698.4 15731.4 15813.8 4950.9 5623.7 5631.7 5716.5 5912.5 777.0 812.7 937.4 1003.9 1007.6 28759.4 29304.6 30120.0 30466.8 31298.7 885.8 889.4 929.0 995.8 1003.6 13943.8 14850.9 14958.5 15236.3 15264.0 677021.0 919563.0 959223.0 962598.0 963644.0 46100.8 55698.4 56139.4 56583.4 56702.7 1323.6 1336.2 1353.7 1362.5 1367.6 25644.7 29205.4 29628.0 29659.1 30583.8 48626.5 48774.8 49275.3 49346.0 49462.8 627715.0 631102.0 634159.0 640566.0 642786.0 21785.2 25851.1 26300.7 26601.5 26609.5 43255.0 43597.9 44078.8 44360.6 44618.0 34384.8 35945.1 36451.0 37525.6 37819.2 36483.3 36538.0 36779.9 36975.0 37090.8 31305.5 35597.5 35922.6 36222.0 36310.6 30925.7 33089.0 33153.0 33165.2 33195.5 24778.8 25084.5 25160.9 25682.7 25685.2 02:46:34 INFO - PROCESS | 29548 | 02:46:34 INFO - PROCESS | 29548 | [#2] jquery.html Cycles:180 Average:34458.04 Median:354.77 stddev:98078.97 (27645.7%) stddev-sans-first:98320.65 02:46:34 INFO - PROCESS | 29548 | Values: 341.2 341.5 368.0 372.1 384.7 15583.6 16857.7 18316.8 19182.3 20450.4 19287.0 19311.3 19426.1 19597.8 19842.5 5546.2 5769.8 5771.5 5805.5 5853.2 5411.9 5690.7 5737.4 5739.9 5745.3 4843.0 5645.6 5669.6 5678.6 5691.3 4550.9 5880.4 5895.8 5956.4 6046.8 5615.8 5782.7 5805.9 5813.4 5817.2 12912.7 12972.3 13188.4 13193.8 13199.8 2240.3 2326.6 2358.4 2395.9 2742.4 9863.8 10163.8 10701.1 11023.7 15766.4 1748.8 1831.4 1842.8 1885.8 1909.2 120417.0 122525.8 128253.4 128524.0 128617.0 9532.3 9773.8 11055.1 11597.2 11664.8 12087.7 14532.0 14681.3 14730.9 14751.2 6951.8 8208.8 8428.5 8585.2 8593.2 6757.0 8341.9 8364.5 8477.9 8487.7 4459.3 4505.6 4506.9 4507.8 4585.1 1856.9 1910.7 1911.2 1919.6 1919.8 821.6 845.6 891.5 894.8 929.9 19727.9 20002.3 20456.4 20592.0 20749.7 793.8 888.7 905.1 905.9 952.0 10473.7 10910.7 10922.3 10933.9 10954.1 574380.0 576937.0 580004.1 594228.0 594586.0 130036.8 131604.3 133934.0 136569.0 136775.0 920.1 924.6 937.4 939.7 964.6 17202.0 17965.2 18307.1 18359.3 18407.7 47225.0 55703.9 55757.0 56290.5 56963.1 61279.4 78220.6 80862.6 81685.0 83567.2 7821.0 8065.9 8134.0 8242.9 8338.9 9089.0 10723.6 11989.2 12851.4 12980.5 5405.2 5501.5 11031.9 11261.2 11351.9 10801.9 11121.8 11205.7 11282.5 11293.4 11423.6 11584.9 11585.5 11586.2 11604.8 8213.9 8273.9 8384.0 8406.0 8450.7 11164.8 11255.0 11333.2 11353.5 11528.4 02:46:34 INFO - PROCESS | 29548 | 02:46:34 INFO - PROCESS | 29548 | [#3] mootools.html Cycles:180 Average:5383.81 Median:4996.68 stddev:2940.43 (58.8%) stddev-sans-first:2933.67 02:46:34 INFO - PROCESS | 29548 | Values: 1430.3 1473.1 1622.0 1711.4 1771.3 7471.6 7719.4 8035.2 8478.9 8511.5 7988.7 8315.5 8316.0 8399.6 8401.2 2314.9 2338.6 2345.7 2351.3 2354.8 2124.9 2146.6 2161.3 2164.2 2167.2 2201.0 2228.1 2298.8 2345.4 2353.9 2314.5 2318.0 2327.1 2337.8 2340.4 2345.1 2351.7 2357.9 2360.0 2370.4 6490.6 6941.6 7192.5 8179.6 8224.8 5007.0 5161.1 5164.1 5176.1 5196.1 3219.0 3245.2 3540.9 3548.3 3551.6 940.4 976.6 1052.9 1087.6 1088.6 10406.6 10727.3 10821.2 10824.5 11030.7 4338.3 4382.5 4457.1 4494.5 4503.9 8613.7 8681.7 8684.6 8691.0 8694.7 5063.3 5083.5 5110.8 5119.6 5120.1 4241.5 4261.8 4714.8 4738.4 4809.3 3599.8 3663.6 3673.4 3696.1 3701.1 1112.5 1150.8 1166.8 1172.0 1177.7 760.4 840.0 889.9 906.8 918.1 5447.2 5528.7 5555.5 5655.5 5904.0 818.5 1019.2 1019.4 1021.4 1054.7 3738.5 3796.9 3826.4 3857.2 3865.0 9865.9 10132.8 10219.3 10235.0 10276.0 10223.4 10231.7 10260.7 10261.6 10272.8 7082.5 7335.6 7808.9 7961.7 7967.5 5530.1 5577.2 5778.9 5848.3 5923.3 7484.9 9274.0 9394.1 9464.7 9469.9 9377.5 9471.2 9555.2 9623.4 9642.0 6571.6 6767.3 7031.9 7138.6 7255.3 7781.4 7939.5 7972.0 7988.4 7988.7 6899.7 7133.6 7172.5 7211.1 7214.7 6871.2 6993.3 7020.4 7068.4 7098.7 6235.5 6677.1 6771.8 6784.6 6801.5 4872.2 4874.8 4888.1 4904.0 4948.5 4841.8 4880.7 4898.4 4949.0 4986.4 02:46:34 INFO - PROCESS | 29548 | 02:46:34 INFO - PROCESS | 29548 | [#4] prototype.html Cycles:180 Average:4549.94 Median:4988.27 stddev:1430.65 (28.7%) stddev-sans-first:1398.38 02:46:34 INFO - PROCESS | 29548 | Values: 284.9 300.2 300.9 309.5 316.4 4285.5 4649.7 4987.4 5355.7 5384.8 4557.3 4989.1 5347.8 5353.8 5367.8 3316.5 3760.3 3903.7 4168.5 4223.6 4027.4 4193.8 4206.5 4224.1 4246.5 3247.8 4156.5 4162.7 4167.9 4205.9 3384.6 3696.5 3792.9 4148.9 4155.6 3405.4 3639.3 3648.5 3671.6 3683.7 4135.6 4814.5 4890.5 5167.6 5312.7 4739.8 4755.5 4772.3 4788.4 4796.9 4322.4 4432.9 5417.5 5702.7 5709.6 3616.2 3730.9 3757.9 3874.7 4023.7 5148.8 5290.8 5790.9 5997.6 5998.2 3995.3 4055.5 4065.7 4066.4 4099.5 3736.6 4023.4 4115.3 4136.3 4144.9 3579.7 4299.0 4680.5 4724.1 4756.5 4866.3 5204.8 5801.6 5805.5 5825.5 1129.4 1140.9 1215.3 1230.8 1313.7 736.0 776.8 866.9 867.2 887.5 4232.5 4279.0 4678.5 5028.9 5057.5 4400.7 4859.6 4997.8 5110.3 5496.7 4504.9 4994.2 5031.7 5071.0 5086.5 2228.9 2919.6 2925.0 2946.5 2956.8 5138.9 5340.3 5751.8 5751.9 5814.5 4759.7 4846.4 5685.3 5784.9 5785.4 5289.4 6173.8 6220.8 6232.9 6252.6 6013.9 6032.9 6034.5 6037.9 6059.6 4906.0 5624.9 5766.1 5869.6 5941.8 5467.4 5660.2 6114.1 6253.4 6289.9 3728.5 3744.6 3786.7 4004.5 4020.5 5834.8 5863.7 5869.8 5907.3 5933.9 5095.8 5552.9 5651.7 5668.3 5879.5 4732.7 5806.7 5852.3 5865.6 5866.7 4913.4 5354.9 5428.8 5450.5 5637.0 5769.8 5808.6 5834.7 5840.9 5874.0 5471.0 5689.5 5700.1 5720.4 5756.9 02:46:34 INFO - PROCESS | 29548 | 02:46:34 INFO - PROCESS | 29548 | [#5] yui.html Cycles:180 Average:19881.66 Median:56474.44 stddev:29765.13 (52.7%) stddev-sans-first:29813.58 02:46:34 INFO - PROCESS | 29548 | Values: 644.2 768.4 774.4 774.9 777.4 12916.2 13537.0 13778.7 14025.9 14121.8 12584.6 12667.6 12672.7 12698.6 12808.7 800.8 812.9 826.6 827.2 827.8 647.4 666.7 801.6 818.8 827.5 847.1 864.4 866.0 866.1 867.8 850.6 856.2 859.2 861.5 861.8 625.6 634.1 637.6 639.3 641.5 10453.0 11420.1 13034.5 13047.0 13155.9 11685.4 12221.9 12258.3 12264.0 12360.3 9908.3 10441.4 10568.3 10591.8 10656.8 2755.8 3106.6 3401.9 3406.6 3461.1 55172.2 80401.0 84695.6 85331.0 85790.6 9398.4 9404.5 9429.4 9565.7 9767.4 18742.0 18769.5 18918.0 18952.5 18991.2 6942.4 7017.1 7108.7 7301.3 7303.5 3636.0 3945.5 3988.7 4003.3 4008.6 2650.8 3074.3 3080.7 3315.8 3342.9 540.2 603.9 610.4 616.2 620.0 583.7 588.0 653.3 653.9 656.2 8249.3 8731.3 8883.2 8908.5 8950.0 637.0 658.9 670.3 675.9 681.0 4176.5 4262.6 4564.2 4628.7 4812.6 76932.0 84853.6 114821.0 114879.0 117432.0 97202.5 98251.0 98353.0 100213.5 103717.0 85032.1 86276.6 86646.6 87773.0 88086.0 7797.3 9388.6 9400.6 9402.4 9411.7 43194.4 54385.5 54506.7 56032.4 56916.4 75525.9 77631.0 80249.2 80445.4 83357.2 10608.6 11401.7 11793.2 12011.8 12099.7 12652.4 13497.7 13604.9 14183.5 14205.3 12282.4 12333.9 12394.4 12650.1 12771.9 12488.4 12591.7 12624.1 12749.6 12844.4 8648.4 9207.9 9745.7 11539.8 11790.0 7946.0 10108.9 10488.4 11107.0 11166.6 10095.0 10414.8 10460.8 10465.5 10559.2 02:46:34 INFO - PROCESS | 29548 | -------- Summary: end -------- 02:46:34 INFO - PROCESS | 29548 | 02:46:35 INFO - TEST-INFO | 29548: exit 0 02:46:35 INFO - TEST-OK | dromaeo_css | took 1115865ms 02:46:35 INFO - TEST-START | kraken 02:46:35 INFO - operating with platform_type : mac_ 02:46:35 INFO - Initialising browser for kraken test... 02:46:35 INFO - TEST-INFO | started process 29557 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/tmpxvYSeC/profile http://localhost:49299/getInfo.html) 02:46:38 INFO - PROCESS | 29557 | 2017-01-19 02:46:38.463 plugin-container[29559:272456] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8643, name = 'com.apple.tsm.portname' 02:46:38 INFO - PROCESS | 29557 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:46:38 INFO - PROCESS | 29557 | 2017-01-19 02:46:38.464 plugin-container[29559:272456] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4507, name = 'com.apple.CFPasteboardClient' 02:46:38 INFO - PROCESS | 29557 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:46:38 INFO - PROCESS | 29557 | 2017-01-19 02:46:38.464 plugin-container[29559:272456] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:46:38 INFO - PROCESS | 29557 | __metrics Screen width/height:1600/1200 02:46:38 INFO - PROCESS | 29557 | colorDepth:24 02:46:38 INFO - PROCESS | 29557 | Browser inner width/height: 1024/689 02:46:38 INFO - PROCESS | 29557 | __metrics 02:46:38 ERROR - PROCESS | 29557 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 02:46:39 INFO - TEST-INFO | 29557: exit 0 02:46:39 INFO - Browser initialized. 02:46:39 INFO - Running cycle 1/1 for kraken test... 02:46:39 INFO - TEST-INFO | started process 29560 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/tmpxvYSeC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:46:42 INFO - PROCESS | 29560 | 2017-01-19 02:46:42.106 plugin-container[29561:272580] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8547, name = 'com.apple.tsm.portname' 02:46:42 INFO - PROCESS | 29560 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:46:42 INFO - PROCESS | 29560 | 2017-01-19 02:46:42.107 plugin-container[29561:272580] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c03, name = 'com.apple.CFPasteboardClient' 02:46:42 INFO - PROCESS | 29560 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:46:42 INFO - PROCESS | 29560 | 2017-01-19 02:46:42.107 plugin-container[29561:272580] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:46:51 INFO - PROCESS | 29560 | RSS: Main: 229019648 02:46:51 INFO - PROCESS | 29560 | 02:46:54 INFO - PROCESS | 29560 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49299/tests/kraken/driver.html?testName=audio-beat-detection) 02:46:55 INFO - PROCESS | 29560 | RSS: Main: 241086464 02:46:55 INFO - PROCESS | 29560 | 02:46:58 INFO - PROCESS | 29560 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49299/tests/kraken/driver.html?testName=audio-dft) 02:46:59 INFO - PROCESS | 29560 | RSS: Main: 249192448 02:46:59 INFO - PROCESS | 29560 | 02:47:02 INFO - PROCESS | 29560 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49299/tests/kraken/driver.html?testName=audio-fft) 02:47:02 INFO - PROCESS | 29560 | RSS: Main: 252928000 02:47:02 INFO - PROCESS | 29560 | 02:47:04 INFO - PROCESS | 29560 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49299/tests/kraken/driver.html?testName=audio-oscillator) 02:47:05 INFO - PROCESS | 29560 | RSS: Main: 254324736 02:47:05 INFO - PROCESS | 29560 | 02:47:07 INFO - PROCESS | 29560 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49299/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:47:08 INFO - PROCESS | 29560 | RSS: Main: 254369792 02:47:08 INFO - PROCESS | 29560 | 02:47:11 INFO - PROCESS | 29560 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49299/tests/kraken/driver.html?testName=imaging-darkroom) 02:47:11 INFO - PROCESS | 29560 | RSS: Main: 258367488 02:47:11 INFO - PROCESS | 29560 | 02:47:14 INFO - PROCESS | 29560 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49299/tests/kraken/driver.html?testName=imaging-desaturate) 02:47:15 INFO - PROCESS | 29560 | RSS: Main: 257744896 02:47:15 INFO - PROCESS | 29560 | 02:47:18 INFO - PROCESS | 29560 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49299/tests/kraken/driver.html?testName=json-parse-financial) 02:47:18 INFO - PROCESS | 29560 | RSS: Main: 258068480 02:47:18 INFO - PROCESS | 29560 | 02:47:20 INFO - PROCESS | 29560 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49299/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:47:21 INFO - PROCESS | 29560 | RSS: Main: 257007616 02:47:21 INFO - PROCESS | 29560 | 02:47:23 INFO - PROCESS | 29560 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49299/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:47:23 INFO - PROCESS | 29560 | RSS: Main: 257069056 02:47:23 INFO - PROCESS | 29560 | 02:47:25 INFO - PROCESS | 29560 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49299/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:47:26 INFO - PROCESS | 29560 | RSS: Main: 257101824 02:47:26 INFO - PROCESS | 29560 | 02:47:29 INFO - PROCESS | 29560 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49299/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:47:30 INFO - PROCESS | 29560 | RSS: Main: 257302528 02:47:30 INFO - PROCESS | 29560 | 02:47:33 INFO - PROCESS | 29560 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49299/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:47:33 INFO - PROCESS | 29560 | RSS: Main: 257503232 02:47:33 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49299/tests/kraken/driver.html?testName=ai-astar) 02:47:35 INFO - PROCESS | 29560 | RSS: Main: 259293184 02:47:35 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | __start_tp_report 02:47:35 INFO - PROCESS | 29560 | _x_x_mozilla_page_load 02:47:35 INFO - PROCESS | 29560 | _x_x_mozilla_page_load_details 02:47:35 INFO - PROCESS | 29560 | |i|pagename|runs| 02:47:35 INFO - PROCESS | 29560 | |0;ai-astar;85;177;177;177;120;123;120;119;120;121 02:47:35 INFO - PROCESS | 29560 | |1;audio-beat-detection;140;110;114;144;114;112;116;123;144;118 02:47:35 INFO - PROCESS | 29560 | |2;audio-dft;199;199;197;198;197;197;201;198;199;198 02:47:35 INFO - PROCESS | 29560 | |3;audio-fft;73;60;61;85;58;62;60;59;77;60 02:47:35 INFO - PROCESS | 29560 | |4;audio-oscillator;87;72;89;74;73;101;122;102;73;88 02:47:35 INFO - PROCESS | 29560 | |5;imaging-gaussian-blur;124;88;91;89;115;132;81;130;134;88 02:47:35 INFO - PROCESS | 29560 | |6;imaging-darkroom;96;97;103;111;134;150;105;152;152;97 02:47:35 INFO - PROCESS | 29560 | |7;imaging-desaturate;88;94;87;85;88;88;90;89;89;89 02:47:35 INFO - PROCESS | 29560 | |8;json-parse-financial;58;57;56;59;62;59;62;64;61;60 02:47:35 INFO - PROCESS | 29560 | |9;json-stringify-tinderbox;69;72;77;82;71;71;70;71;72;73 02:47:35 INFO - PROCESS | 29560 | |10;stanford-crypto-aes;85;84;84;82;89;84;86;84;83;85 02:47:35 INFO - PROCESS | 29560 | |11;stanford-crypto-ccm;150;137;139;139;151;161;158;153;206;166 02:47:35 INFO - PROCESS | 29560 | |12;stanford-crypto-pbkdf2;170;173;169;169;177;199;158;228;150;165 02:47:35 INFO - PROCESS | 29560 | |13;stanford-crypto-sha256-iterative;69;68;71;68;69;68;68;70;70;67 02:47:35 INFO - PROCESS | 29560 | __end_tp_report 02:47:35 INFO - PROCESS | 29560 | __start_cc_report 02:47:35 INFO - PROCESS | 29560 | _x_x_mozilla_cycle_collect,964 02:47:35 INFO - PROCESS | 29560 | __end_cc_report 02:47:35 INFO - PROCESS | 29560 | __startTimestamp1484822855687__endTimestamp 02:47:35 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | ------- Summary: start ------- 02:47:35 INFO - PROCESS | 29560 | Number of tests: 14 02:47:35 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | [#0] ai-astar Cycles:10 Average:133.90 Median:150.00 stddev:31.71 (21.1%) stddev-sans-first:28.27 02:47:35 INFO - PROCESS | 29560 | Values: 85.0 177.0 177.0 177.0 120.0 123.0 120.0 119.0 120.0 121.0 02:47:35 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | [#1] audio-beat-detection Cycles:10 Average:123.50 Median:120.50 stddev:13.72 (11.4%) stddev-sans-first:13.19 02:47:35 INFO - PROCESS | 29560 | Values: 140.0 110.0 114.0 144.0 114.0 112.0 116.0 123.0 144.0 118.0 02:47:35 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | [#2] audio-dft Cycles:10 Average:198.30 Median:198.50 stddev:1.25 (0.6%) stddev-sans-first:1.30 02:47:35 INFO - PROCESS | 29560 | Values: 199.0 199.0 197.0 198.0 197.0 197.0 201.0 198.0 199.0 198.0 02:47:35 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | [#3] audio-fft Cycles:10 Average:65.50 Median:61.50 stddev:9.37 (15.2%) stddev-sans-first:9.54 02:47:35 INFO - PROCESS | 29560 | Values: 73.0 60.0 61.0 85.0 58.0 62.0 60.0 59.0 77.0 60.0 02:47:35 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | [#4] audio-oscillator Cycles:10 Average:88.10 Median:73.50 stddev:16.41 (22.3%) stddev-sans-first:17.41 02:47:35 INFO - PROCESS | 29560 | Values: 87.0 72.0 89.0 74.0 73.0 101.0 122.0 102.0 73.0 88.0 02:47:35 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | [#5] imaging-gaussian-blur Cycles:10 Average:107.20 Median:84.50 stddev:21.64 (25.6%) stddev-sans-first:22.08 02:47:35 INFO - PROCESS | 29560 | Values: 124.0 88.0 91.0 89.0 115.0 132.0 81.0 130.0 134.0 88.0 02:47:35 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | [#6] imaging-darkroom Cycles:10 Average:119.70 Median:152.00 stddev:24.43 (16.1%) stddev-sans-first:24.36 02:47:35 INFO - PROCESS | 29560 | Values: 96.0 97.0 103.0 111.0 134.0 150.0 105.0 152.0 152.0 97.0 02:47:35 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | [#7] imaging-desaturate Cycles:10 Average:88.70 Median:89.00 stddev:2.31 (2.6%) stddev-sans-first:2.44 02:47:35 INFO - PROCESS | 29560 | Values: 88.0 94.0 87.0 85.0 88.0 88.0 90.0 89.0 89.0 89.0 02:47:35 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | [#8] json-parse-financial Cycles:10 Average:59.80 Median:60.50 stddev:2.49 (4.1%) stddev-sans-first:2.55 02:47:35 INFO - PROCESS | 29560 | Values: 58.0 57.0 56.0 59.0 62.0 59.0 62.0 64.0 61.0 60.0 02:47:35 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | [#9] json-stringify-tinderbox Cycles:10 Average:72.80 Median:72.00 stddev:3.88 (5.4%) stddev-sans-first:3.87 02:47:35 INFO - PROCESS | 29560 | Values: 69.0 72.0 77.0 82.0 71.0 71.0 70.0 71.0 72.0 73.0 02:47:35 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:84.50 stddev:1.90 (2.2%) stddev-sans-first:2.01 02:47:35 INFO - PROCESS | 29560 | Values: 85.0 84.0 84.0 82.0 89.0 84.0 86.0 84.0 83.0 85.0 02:47:35 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | [#11] stanford-crypto-ccm Cycles:10 Average:156.00 Median:155.50 stddev:20.11 (12.9%) stddev-sans-first:21.21 02:47:35 INFO - PROCESS | 29560 | Values: 150.0 137.0 139.0 139.0 151.0 161.0 158.0 153.0 206.0 166.0 02:47:35 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.80 Median:171.50 stddev:22.35 (13.0%) stddev-sans-first:23.61 02:47:35 INFO - PROCESS | 29560 | Values: 170.0 173.0 169.0 169.0 177.0 199.0 158.0 228.0 150.0 165.0 02:47:35 INFO - PROCESS | 29560 | 02:47:35 INFO - PROCESS | 29560 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.00 stddev:1.23 (1.8%) stddev-sans-first:1.30 02:47:35 INFO - PROCESS | 29560 | Values: 69.0 68.0 71.0 68.0 69.0 68.0 68.0 70.0 70.0 67.0 02:47:35 INFO - PROCESS | 29560 | -------- Summary: end -------- 02:47:35 INFO - PROCESS | 29560 | 02:47:36 INFO - TEST-INFO | 29560: exit 0 02:47:36 INFO - TEST-OK | kraken | took 61380ms 02:47:36 INFO - SUITE-END | took 1177s 02:47:36 INFO - Completed test suite (00:19:37) 02:47:36 INFO - javascript benchmark 02:47:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7066.828081845111, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2630.3950091478973, 2777.916662500125, 3332.250243695169, 3376.290978894433, 3382.0699307690384, 5677.602567820254, 5711.657300561969, 5739.483446489815, 5746.540276777858, 5763.279590051244, 5253.684778913268, 5616.680620024469, 5700.942990570104, 5807.854803629901, 5861.472467477926, 4554.3168524721395, 5240.423553409122, 5256.290400795901, 5297.947020529803, 5302.522772950434, 4695.389599352079, 5082.932584157332, 5209.531142197202, 5314.070037743399, 5366.007288651593, 5203.427622961472, 5220.634555581111, 5323.281357016811, 5329.387120481136, 5373.247745315659, 4501.797419116164, 4827.179379505493, 5264.947350526503, 5350.866228344323, 5368.597355198376, 5185.896282074337, 5240.056789777838, 5267.130923397634, 5268.341457317835, 5281.287026251445, 4849.393825771778, 4991.675541089818, 5587.8882422351735, 5776.682282474462, 5779.595428320019, 6096.451319381233, 6131.4072507432475, 6132.447291485822, 6143.170671959318, 6149.385061493859, 6133.957227271375, 6140.078988151813, 6167.444929956326, 6216.595921265149, 6226.564140510166, 5886.852828679274, 5895.616784908967, 5898.498627616701, 5930.762769489215, 5984.790532331391, 6149.569530132937, 6197.349278325801, 6214.564980451325, 6221.751129954841, 6241.687915604201, 6139.416755408229, 6140.263168419729, 6153.938460615426, 6155.599886007485, 6169.198004259418, 6163.0, 6228.719707613168, 6233.657148856766, 6251.374862513712, 6260.718267677966, 6053.182820319201, 6116.396640261158, 6123.08153776937, 6184.330230837649, 6187.16473276102, 6159.876802463926, 6213.316535181127, 6221.129041934133, 6236.688165591702, 6237.0, 4902.779374928136, 4961.454240033593, 4966.751662416865, 4970.254461830755, 4998.925016124761, 4238.703290769617, 4290.12052529237, 4363.061941682554, 4431.290993441098, 4463.263561512379, 5754.0505816540635, 5782.10377391505, 5868.67722275279, 5895.793647222326, 5904.970475147683, 6209.441150296405, 6226.377362263737, 6233.937660623426, 6237.0, 6242.375762423721, 5608.1587761835235, 5681.659100453986, 5770.076787713946, 5836.124581312837, 5861.824145275648, 5423.457654234624, 5439.891202176013, 5449.0, 5452.700101494456, 5481.0, 6335.176427064544, 6391.680415979183, 6411.6153030818305, 6426.710798014007, 6433.999999999906, 6113.84715382119, 6123.387661233841, 6157.599756015845, 6172.382761723792, 6231.127642130196, 6417.48660107181, 6424.0, 6430.453411460078, 6433.871322573429, 6448.903266450913, 5349.742810439621, 6223.0354295084335, 6228.968855155696, 6229.2524897010835, 6232.407921247474, 6020.8193754185695, 6142.069075791586, 6167.5682702210415, 6263.060540918898, 6266.749330026748, 6055.909161362495, 6068.302145253176, 6111.083337499411, 6135.815925522242, 6145.446909778141, 4612.377329060668, 5619.775208991595, 6059.575829691879, 6114.48026917717, 6147.600405973598, 4855.927161092657, 5596.768710883599, 5864.67744274069, 5978.163057172086, 6000.639961602318, 5559.471850174227, 5986.4013598639785, 6025.548083893638, 6089.908651370321, 6122.916604401471, 5937.643741375531, 5992.221011268507, 5992.9811931972545, 5998.970005149947, 6010.609310394811], "value": 5652.844490068821, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.34265399057284, 487.4126677353785, 489.3100727973558, 501.60122702451525, 503.48895870691246, 28753.84984600616, 31070.2232444189, 39009.80495097524, 39753.80123099388, 39849.80075099628, 40485.59514404855, 40813.387799182965, 40884.38673419894, 41020.38469422961, 41836.58163418373, 9166.175044246016, 9326.300527460433, 9328.533573321341, 9350.953245233764, 9362.391444556082, 9242.02958689338, 9353.392029518094, 9370.0, 9398.201152902011, 9398.436093834358, 9332.48671323077, 9340.766480837965, 9401.62393504259, 9402.200812930909, 9415.246780257597, 9289.349745517782, 9305.720828375159, 9361.625534978593, 9373.578188981444, 9381.671641492549, 9289.163975242192, 9320.16118549327, 9384.155426011623, 9390.436573805526, 9392.530373481299, 38961.80519097415, 38963.0, 39214.60785392167, 39334.0, 39411.21177576461, 3415.1803567143957, 3440.7419443541585, 3463.290025544757, 3907.711700802475, 4144.689148313889, 24591.508169836503, 28216.435671286665, 29089.96370254083, 29217.70782292171, 30048.0, 3109.673484284163, 3147.0, 3202.8078315300922, 3230.709236168733, 3245.496947973068, 491460.62809057895, 493877.0, 496524.0, 496877.0, 497789.0, 46855.531444685796, 56138.15792763112, 56243.875122497324, 56520.0, 56866.0, 57668.134977975365, 58210.417895821345, 58362.41637583655, 58498.0, 58640.7067964666, 15807.841921580866, 15911.80661450396, 16073.839261607234, 16076.678466430605, 16123.435679751152, 12389.566365177176, 12501.3749312534, 12515.249085054926, 12546.247225166519, 12548.933340666146, 14456.927715361566, 15571.299291531908, 15698.372065117495, 15731.44939927097, 15813.762793558106, 4950.890760668636, 5623.718814059281, 5631.746571404296, 5716.456936591017, 5912.497437717756, 776.9689212431553, 812.7155495576501, 937.437537477508, 1003.8556046107443, 1007.6171054999201, 28759.424811504072, 29304.56043159354, 30120.0, 30466.77682341678, 31298.68701313003, 885.7696998780364, 889.4440974391006, 928.9688445825128, 995.8257304971601, 1003.5684655598017, 13943.790843137362, 14850.851491485162, 14958.476453324081, 15236.314365853563, 15264.0, 677021.0, 919563.0, 959223.0, 962598.0, 963644.0, 46100.76949615298, 55698.443015569326, 56139.43860561424, 56583.43416565864, 56702.71648641813, 1323.589687196972, 1336.211635135275, 1353.749556332062, 1362.4822567424515, 1367.5555444480385, 25644.743552564236, 29205.415891681623, 29628.0, 29659.110226693232, 30583.847080764455, 48626.51373486361, 48774.756126219145, 49275.26087108625, 49346.0, 49462.75268623778, 627715.0, 631102.0, 634159.0, 640566.0, 642786.0, 21785.23751668684, 25851.0952116675, 26300.736992629827, 26601.467970640864, 26609.467810644066, 43255.0, 43597.910052248946, 44078.779606101765, 44360.556394436935, 44618.0, 34384.82807585946, 35945.1013724659, 36451.0, 37525.62474375221, 37819.243615126994, 36483.27033459369, 36538.0, 36779.89660310195, 36975.0, 37090.81454592818, 31305.530417044218, 35597.46603800893, 35922.64077359193, 36222.0, 36310.63689363178, 30925.69074309228, 33089.0, 33153.0, 33165.17087072746, 33195.50206746949, 24778.752212478365, 25084.49831003406, 25160.874195628905, 25682.743172568036, 25685.229443116736], "value": 19261.96781385242, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.1607445683618, 341.5082281514619, 368.033910983668, 372.0753926492665, 384.6538115695875, 15583.610409739762, 16857.747133793004, 18316.80940738851, 19182.328618498337, 20450.386488405355, 19287.0, 19311.324103656374, 19426.1258243379, 19597.804021959775, 19842.503937401572, 5546.223528705974, 5769.798057068004, 5771.50942169916, 5805.5065319447685, 5853.180554722342, 5411.945880541183, 5690.687012214326, 5737.3688894221605, 5739.856503587423, 5745.2818397700285, 4843.0, 5645.604807663465, 5669.6314739541795, 5678.5741069419755, 5691.260136182291, 4550.931736023962, 5880.353161152312, 5895.764169433218, 5956.404359564052, 6046.788362407295, 5615.775368985236, 5782.7397767100165, 5805.912911306334, 5813.447722466359, 5817.243758311435, 12912.741745165138, 12972.286524241163, 13188.406521706545, 13193.802092968614, 13199.802002969964, 2240.271911628727, 2326.5695846268495, 2358.4339758457845, 2395.8802059896934, 2742.369255071325, 9863.80272394548, 10163.847542286872, 10701.090407315387, 11023.724406889813, 15766.369345226178, 1748.7901451825735, 1831.3773317072125, 1842.7788665360242, 1885.7831349394721, 1909.2458478900896, 120417.0, 122525.77474225232, 128253.35873320667, 128524.0, 128617.0, 9532.332736708364, 9773.778277715286, 11055.0603198729, 11597.246178998337, 11664.825027624423, 12087.697807554705, 14532.0, 14681.339339729739, 14730.852691473163, 14751.188684622233, 6951.7914462566205, 8208.794780130425, 8428.536430496382, 8585.227329540372, 8593.226609605166, 6756.966215168991, 8341.874871876928, 8364.53995030267, 8477.915220847835, 8487.745367638981, 4459.308807134899, 4505.617022553054, 4506.909861802811, 4507.842225522091, 4585.060062687141, 1856.9350072747388, 1910.6656335141295, 1911.1590900003953, 1919.625672993753, 1919.7888232294436, 821.572782153277, 845.556083056388, 891.4695756025154, 894.789724414762, 929.9163075323254, 19727.901360493106, 20002.299919502748, 20456.386308410772, 20592.0, 20749.688754668692, 793.7856778669727, 888.6623083228461, 905.1265528764774, 905.9275257979345, 951.9714408567754, 10473.68578942633, 10910.672679819618, 10922.29005114665, 10933.890661093446, 10954.068904143078, 574380.0, 576937.0, 580004.0999794974, 594228.0, 594586.0, 130036.82324840727, 131604.3419782914, 133934.0, 136569.0, 136775.0, 920.061537232033, 924.58393722825, 937.4047479850208, 939.6570251858022, 964.599691128174, 17202.0, 17965.191566379282, 18307.084645767925, 18359.265629374677, 18407.723884142015, 47225.0, 55703.88592228214, 55757.0, 56290.49676025826, 56963.14555281757, 61279.387206127365, 78220.60889695743, 80862.59568702118, 81684.9578760512, 83567.16432835594, 7820.960895195488, 8065.919340806517, 8133.959330203311, 8242.917570824215, 8338.916610833814, 9088.954555227181, 10723.624673136403, 11989.160758746804, 12851.357432128169, 12980.480780768663, 5405.162199859028, 5501.504864562208, 11031.896810318904, 11261.211715180187, 11351.886481135414, 10801.891981080089, 11121.777564448828, 11205.66383008511, 11282.548698051985, 11293.43532823372, 11423.600173993871, 11584.884151158381, 11585.536578536765, 11586.246893952204, 11604.825927611262, 8213.917860821315, 8273.917260827315, 8384.035835878953, 8405.95797021011, 8450.704225352083, 11164.832527511931, 11255.04332131778, 11333.150013748836, 11353.489092991, 11528.365939873387], "value": 9214.056067242514, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1430.2777097565727, 1473.1087691946373, 1621.9918900405496, 1711.3753479979796, 1771.3180425536173, 7471.551706897576, 7719.382449404048, 8035.156308587594, 8478.91521084789, 8511.531865747382, 7988.7203947861835, 8315.542645154514, 8316.0, 8399.580020998956, 8401.201885820852, 2314.930552083436, 2338.555674421863, 2345.6833327500826, 2351.329870986769, 2354.846934949223, 2124.8618839775363, 2146.592147491979, 2161.27597254919, 2164.2208804830248, 2167.2089687264115, 2200.966985495219, 2228.0864845413385, 2298.804601608857, 2345.3901985483812, 2353.8587684738886, 2314.5023819878807, 2318.038014224095, 2327.138958585317, 2337.8129749620075, 2340.4031971847157, 2345.1205797825814, 2351.741308456086, 2357.905683772647, 2359.9528009439887, 2370.4073981504625, 6490.578112422678, 6941.583504989717, 7192.496525243235, 8179.631916563769, 8224.794380140485, 5007.0, 5161.122609156415, 5164.122099243138, 5176.0683077046115, 5196.142636464979, 3219.0181994491672, 3245.172481017349, 3540.8760693375866, 3548.290341931598, 3551.5915669698056, 940.3840484482674, 976.584951395661, 1052.9052385285283, 1087.6247694545368, 1088.58633719187, 10406.635767748168, 10727.302725322828, 10821.242513024092, 10824.51289691958, 11030.724231894186, 4338.305871060616, 4382.496012958487, 4457.086297309044, 4494.460664720189, 4503.864884053483, 8613.741587752253, 8681.739547813575, 8684.60919258635, 8691.043985161627, 8694.695685650971, 5063.265826455144, 5083.517065878736, 5110.770015349318, 5119.59043276537, 5120.129577971754, 4241.512226094039, 4261.829526818955, 4714.787834547385, 4738.360321356642, 4809.302651115639, 3599.784012959231, 3663.6153203913736, 3673.430618254175, 3696.131409118855, 3701.0747313171705, 1112.5382966069153, 1150.8331291962618, 1166.848309719731, 1171.9569583071072, 1177.717347836513, 760.422079219797, 840.0, 889.9332550058642, 906.795970906554, 918.1231923513029, 5447.155690867922, 5528.710417301699, 5555.472230138131, 5655.462731040545, 5904.025835737141, 818.5007145641155, 1019.2152042926905, 1019.3883669798062, 1021.4228960637216, 1054.7468607534136, 3738.476613274144, 3796.867109651149, 3826.445165450994, 3857.18999010211, 3865.033741564609, 9865.852012219822, 10132.797344053077, 10219.284650074424, 10235.0, 10275.972402759664, 10223.437710925822, 10231.693049208536, 10260.692179234635, 10261.640842570474, 10272.845907311395, 7082.504224704324, 7335.633218339061, 7808.882866757003, 7961.72133975308, 7967.482113662593, 5530.115181570929, 5577.2191893135805, 5778.913316300175, 5848.2689663792025, 5923.318818335946, 7484.887726683995, 9274.0, 9394.107559781809, 9464.668736594185, 9469.905300947177, 9377.484238366955, 9471.242300616072, 9555.23558115363, 9623.374480658735, 9642.0, 6571.605703657796, 6767.25560188369, 7031.859362812817, 7138.571685698667, 7255.310745479171, 7781.4163937706035, 7939.483933544301, 7972.0, 7988.440809143304, 7988.720394786154, 6899.724011039502, 7133.5719856808755, 7172.462065345016, 7211.134663840373, 7214.74748383804, 6871.244163141951, 6993.335633114846, 7020.403265722266, 7068.434525237866, 7098.716051358095, 6235.532335074797, 6677.09859169006, 6771.830704232433, 6784.59292442455, 6801.5238933274195, 4872.220444728827, 4874.756262186947, 4888.144574699484, 4904.0, 4948.455669876239, 4841.75791210431, 4880.731559764105, 4898.363212782316, 4948.950510494849, 4986.351774269322], "value": 4372.348879799708, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.92734352740047, 300.15506349625815, 300.9286938498943, 309.5078824668776, 316.3593722711509, 4285.507166675833, 4649.69776964497, 4987.426445958716, 5355.732213389329, 5384.757685904134, 4557.316402539613, 4989.106318335366, 5347.759350829212, 5353.812616558421, 5367.785288588461, 3316.502524621302, 3760.304343696425, 3903.746256493333, 4168.458100446937, 4223.64099051581, 4027.3556231003045, 4193.811278492475, 4206.49522057352, 4224.091820258644, 4246.490421149451, 3247.7564182686274, 4156.480439945007, 4162.666986641061, 4167.874963751093, 4205.894852628693, 3384.627690953993, 3696.5194524711883, 3792.886213413602, 4148.937765933513, 4155.563665815076, 3405.4210784166753, 3639.344917914774, 3648.5074514940407, 3671.6328367163337, 3683.7475258412, 4135.627793498599, 4814.518548145193, 4890.462049174588, 5167.560757335631, 5312.7061222042585, 4739.786709598042, 4755.548222918852, 4772.331873537708, 4788.353572267734, 4796.904061918777, 4322.433991956648, 4432.911341773147, 5417.539509141728, 5702.714864256771, 5709.571782116358, 3616.2405894762132, 3730.8694195703292, 3757.9060523487133, 3874.713918787929, 4023.718339716221, 5148.794048238028, 5290.788368465257, 5790.913136302959, 5997.610155339889, 5998.220231369937, 3995.2808494471274, 4055.4727885374714, 4065.654419374386, 4066.430699702044, 4099.508059032935, 3736.645018723217, 4023.3562629979065, 4115.32097203964, 4136.338185890303, 4144.896377590584, 3579.744760800731, 4298.969226695235, 4680.542715074545, 4724.149653062413, 4756.500567440438, 4866.31871537985, 5204.76578553966, 5801.564882633819, 5805.506531944748, 5825.475707186289, 1129.3619105205532, 1140.9087273018138, 1215.3437143942172, 1230.839990801208, 1313.6847156682518, 735.9668814903342, 776.8096816280048, 866.9089745576624, 867.2108381372921, 887.4852585500523, 4232.513260974965, 4279.0, 4678.5496496086325, 5028.924566131511, 5057.519535644108, 4400.69195156336, 4859.611231101503, 4997.800527873357, 5110.310108135429, 5496.657640463989, 4504.887377815581, 4994.225894986283, 5031.6729412588065, 5070.974645126751, 5086.516780905808, 2228.9331320060423, 2919.6496420429685, 2924.956125658117, 2946.543285790706, 2956.807807492506, 5138.87152821175, 5340.332458442695, 5751.827445176651, 5751.913721294184, 5814.505767009767, 4759.666823322404, 4846.418429788378, 5685.346185188673, 5784.871949969715, 5785.363610002981, 5289.444608316071, 6173.783917562953, 6220.813375598739, 6232.937670623236, 6252.562320637512, 6013.939860601338, 6032.883916475466, 6034.465524479641, 6037.909431358621, 6059.575829692056, 4906.0, 5624.943750562442, 5766.106253530709, 5869.589128760946, 5941.821745347646, 5467.371252306036, 5660.217031635022, 6114.104627565469, 6253.405926436982, 6289.9056514153235, 3728.478013078117, 3744.569374521965, 3786.67813235878, 4004.539477960072, 4020.537638171648, 5834.766609335748, 5863.677497737494, 5869.7945571904775, 5907.261592300963, 5933.940660593339, 5095.847124586268, 5552.861178470571, 5651.660900345993, 5668.291463567054, 5879.5002424792665, 4732.739699316433, 5806.65160090396, 5852.326982397079, 5865.60648892709, 5866.677332746569, 4913.43495498008, 5354.919676204938, 5428.782848686008, 5450.509454149145, 5636.971815140898, 5769.7595017071535, 5808.556454312539, 5834.7082645866685, 5840.890230856124, 5874.0, 5470.972645136749, 5689.487946084872, 5700.144978253295, 5720.3707592164, 5756.884862302814], "value": 4085.44874291177, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.2237104289331, 768.4160038370838, 774.3688893551752, 774.8915151878738, 777.4363586399861, 12916.160449570783, 13536.999999999987, 13778.724425511497, 14025.859741402583, 14121.788173177385, 12584.622461326175, 12667.619971400849, 12672.68318292041, 12698.619041428748, 12808.67978300543, 800.7757827808223, 812.8821320908464, 826.5949684654506, 827.1645637905698, 827.7971896885241, 647.4464332995292, 666.7099811581953, 801.6432687454085, 818.8321394114192, 827.4662842466605, 847.089378917666, 864.3733293362294, 865.9523726195057, 866.0906048648925, 867.7917299848023, 850.6172222499838, 856.2422256303186, 859.2266959736224, 861.51324501657, 861.8448679237734, 625.6465097220056, 634.0995785983914, 637.6365471681144, 639.2999665366416, 641.5188608543592, 10453.006964338376, 11420.086393088533, 13034.543790967271, 13047.0, 13155.934220328932, 11685.357305348203, 12221.877781222163, 12258.264504129691, 12264.0, 12360.25838449696, 9908.306418550705, 10441.373517588969, 10568.309070548676, 10591.84112238317, 10656.786864262749, 2755.8070935034557, 3106.5961425014602, 3401.8639254429795, 3406.5741782277214, 3461.1174150591437, 55172.17241741377, 80401.0, 84695.57652211761, 85331.0, 85790.57104714499, 9398.436093834393, 9404.482753448488, 9429.434233945985, 9565.713028609152, 9767.413955162712, 18742.0, 18769.53076173079, 18918.0, 18952.52618684544, 18991.240350386106, 6942.375186233263, 7017.122859642544, 7108.680109394986, 7301.342879140797, 7303.45224108194, 3635.9818200909353, 3945.5265368156006, 3988.66096381805, 4003.259397011562, 4008.5590585035625, 2650.814442989011, 3074.308280636839, 3080.6919308069014, 3315.7678962472396, 3342.8662853486076, 540.1816248383702, 603.9486643635253, 610.4414460768436, 616.202018386191, 620.0358442621766, 583.7402355951646, 587.952963762898, 653.3401264722664, 653.8888388973886, 656.1535619051466, 8249.340052795762, 8731.345149113842, 8883.244924181517, 8908.465492070496, 8950.0, 636.9521394360576, 658.9143411356494, 670.33971538035, 675.8952362383839, 680.9829754256183, 4176.519700234451, 4262.573742625775, 4564.1556312082375, 4628.652851036186, 4812.5668689818085, 76932.0, 84853.57573212094, 114821.0, 114879.0, 117432.0, 97202.51398743103, 98251.0, 98353.0, 100213.4967975481, 103717.0, 85032.14967850242, 86276.56861715652, 86646.56676716702, 87773.0, 88086.0, 7797.298243158143, 9388.624455021723, 9400.576974036321, 9402.43585384879, 9411.670591529264, 43194.35208471938, 54385.45614543804, 54506.72746636241, 56032.43967560272, 56916.43083569277, 75525.86711199446, 77631.0, 80249.19750802417, 80445.391092179, 83357.16642833494, 10608.628697995533, 11401.714957126138, 11793.174477786473, 12011.81982270284, 12099.697507562028, 12652.367381630698, 13497.662558435724, 13604.931975340456, 14183.503577374742, 14205.28973551339, 12282.447289871798, 12333.93833030829, 12394.442250098587, 12650.114491985472, 12771.93614031924, 12488.43802028929, 12591.685207869876, 12624.053196010153, 12749.6175114743, 12844.422001010164, 8648.394612377073, 9207.86188207164, 9745.707628771148, 11539.826902596637, 11789.99785018283, 7946.0, 10108.949455252676, 10488.423136727248, 11107.0, 11166.609168679057, 10094.99050095014, 10414.764477839715, 10460.790784184426, 10465.529051192867, 10559.208059395729], "value": 6949.0934337356375, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1542.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 177.0, 177.0, 177.0, 120.0, 123.0, 120.0, 119.0, 120.0, 121.0], "value": 133.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 110.0, 114.0, 144.0, 114.0, 112.0, 116.0, 123.0, 144.0, 118.0], "value": 123.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 199.0, 197.0, 198.0, 197.0, 197.0, 201.0, 198.0, 199.0, 198.0], "value": 198.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 60.0, 61.0, 85.0, 58.0, 62.0, 60.0, 59.0, 77.0, 60.0], "value": 65.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 72.0, 89.0, 74.0, 73.0, 101.0, 122.0, 102.0, 73.0, 88.0], "value": 88.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 88.0, 91.0, 89.0, 115.0, 132.0, 81.0, 130.0, 134.0, 88.0], "value": 107.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 103.0, 111.0, 134.0, 150.0, 105.0, 152.0, 152.0, 97.0], "value": 119.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 94.0, 87.0, 85.0, 88.0, 88.0, 90.0, 89.0, 89.0, 89.0], "value": 88.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 56.0, 59.0, 62.0, 59.0, 62.0, 64.0, 61.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 77.0, 82.0, 71.0, 71.0, 70.0, 71.0, 72.0, 73.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 82.0, 89.0, 84.0, 86.0, 84.0, 83.0, 85.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 137.0, 139.0, 139.0, 151.0, 161.0, 158.0, 153.0, 206.0, 166.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 173.0, 169.0, 169.0, 177.0, 199.0, 158.0, 228.0, 150.0, 165.0], "value": 175.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 71.0, 68.0, 69.0, 68.0, 68.0, 70.0, 70.0, 67.0], "value": 68.8, "unit": "score"}]}]} 02:47:36 INFO - Return code: 0 02:47:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:47:36 ERROR - # TBPL SUCCESS # 02:47:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:47:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:47:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:47:37 INFO - 2017-01-19 02:47:37.004 screenresolution[29565:272964] starting screenresolution argv=screenresolution get 02:47:37 INFO - 2017-01-19 02:47:37.007 screenresolution[29565:272964] Display 0: 1600x1200x32@60 02:47:37 INFO - 2017-01-19 02:47:37.013 screenresolution[29566:272967] starting screenresolution argv=screenresolution list 02:47:37 INFO - Available Modes on Display 0 02:47:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:47:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:47:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:47:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:47:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:47:37 INFO - Intel Iris: 02:47:37 INFO - Chipset Model: Intel Iris 02:47:37 INFO - Type: GPU 02:47:37 INFO - Bus: Built-In 02:47:37 INFO - VRAM (Dynamic, Max): 1536 MB 02:47:37 INFO - Vendor: Intel (0x8086) 02:47:37 INFO - Device ID: 0x0a2e 02:47:37 INFO - Revision ID: 0x0009 02:47:37 INFO - Displays: 02:47:37 INFO - Display: 02:47:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:47:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:47:37 INFO - Display Serial Number: 1600x1200 60 02:47:37 INFO - Main Display: Yes 02:47:37 INFO - Mirror: Off 02:47:37 INFO - Online: Yes 02:47:37 INFO - Rotation: Supported 02:47:37 INFO - Return code: 0 02:47:37 INFO - Running post-action listener: _resource_record_post_action 02:47:37 INFO - [mozharness: 2017-01-19 10:47:37.236399Z] Finished run-tests step (success) 02:47:37 INFO - Running post-run listener: _resource_record_post_run 02:47:37 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 32423936; Write bytes: 660868608; Read time: 301; Write time: 1831 02:47:37 INFO - TinderboxPrint: CPU usage
54.3% 02:47:37 INFO - TinderboxPrint: I/O read bytes / time
32,423,936 / 301 02:47:37 INFO - TinderboxPrint: I/O write bytes / time
660,868,608 / 1,831 02:47:37 INFO - TinderboxPrint: CPU idle
2,209.8 (45.7%) 02:47:37 INFO - TinderboxPrint: CPU system
982.5 (20.3%) 02:47:37 INFO - TinderboxPrint: CPU user
1,645.4 (34.0%) 02:47:37 INFO - TinderboxPrint: Swap in / out
376,705,024 / 0 02:47:37 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 342833664; Write bytes: 336816128; Read time: 26883; Write time: 625 02:47:37 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 32423936; Write bytes: 308610560; Read time: 301; Write time: 1157 02:47:37 INFO - Running post-run listener: _upload_blobber_files 02:47:37 INFO - Blob upload gear active. 02:47:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:47:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:47:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:47:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:47:38 INFO - (blobuploader) - INFO - Open directory for files ... 02:47:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:47:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:47:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:47:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:47:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:47:39 INFO - (blobuploader) - INFO - Done attempting. 02:47:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:47:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:47:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:47:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:47:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:47:40 INFO - (blobuploader) - INFO - Done attempting. 02:47:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:47:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:47:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:47:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:47:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:47:42 INFO - (blobuploader) - INFO - Done attempting. 02:47:42 INFO - (blobuploader) - INFO - Iteration through files over. 02:47:42 INFO - Return code: 0 02:47:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:47:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:47:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5863c0d93e7301c2127f38ebe77dacd5dcdaf5e60aa18778ebba3fe7da715f49b1739215114e3a88d1a2e0a1bc9c159fb0c4c96d6dd86950e047e353ae47683b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ec0a401f7e55fbc3a020d870c75de2d71911cafe0b565074d2301c8412394eef81de64f39af2242ac98fa2c0628d004f9dd25f349b1bbc9adb1e86afaa298b83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9a6b7bb9b26cb56085b9bbced8dd6b43a6afe4bd7a2a32a1fb28f305bf33a6b558ba0e50bb27504158d1296798a44cbcbea5e0d12e6e6bbdf730f6c664bb3bb"} 02:47:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:47:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:47:42 INFO - Contents: 02:47:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5863c0d93e7301c2127f38ebe77dacd5dcdaf5e60aa18778ebba3fe7da715f49b1739215114e3a88d1a2e0a1bc9c159fb0c4c96d6dd86950e047e353ae47683b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ec0a401f7e55fbc3a020d870c75de2d71911cafe0b565074d2301c8412394eef81de64f39af2242ac98fa2c0628d004f9dd25f349b1bbc9adb1e86afaa298b83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9a6b7bb9b26cb56085b9bbced8dd6b43a6afe4bd7a2a32a1fb28f305bf33a6b558ba0e50bb27504158d1296798a44cbcbea5e0d12e6e6bbdf730f6c664bb3bb"} 02:47:42 INFO - Running post-run listener: copy_logs_to_upload_dir 02:47:42 INFO - Copying logs to upload dir... 02:47:42 INFO - mkdir: /builds/slave/test/build/upload/logs 02:47:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.058590 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-01-19 02:47:42.758504) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-19 02:47:42.761789) ========= 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.alyxNwdjcN/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.nIwG8BRhmX/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5863c0d93e7301c2127f38ebe77dacd5dcdaf5e60aa18778ebba3fe7da715f49b1739215114e3a88d1a2e0a1bc9c159fb0c4c96d6dd86950e047e353ae47683b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ec0a401f7e55fbc3a020d870c75de2d71911cafe0b565074d2301c8412394eef81de64f39af2242ac98fa2c0628d004f9dd25f349b1bbc9adb1e86afaa298b83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9a6b7bb9b26cb56085b9bbced8dd6b43a6afe4bd7a2a32a1fb28f305bf33a6b558ba0e50bb27504158d1296798a44cbcbea5e0d12e6e6bbdf730f6c664bb3bb"} build_url:https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011179 build_url: 'https://queue.taskcluster.net/v1/task/D2x_S4mFSKOMgxMxdoIJAA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5863c0d93e7301c2127f38ebe77dacd5dcdaf5e60aa18778ebba3fe7da715f49b1739215114e3a88d1a2e0a1bc9c159fb0c4c96d6dd86950e047e353ae47683b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ec0a401f7e55fbc3a020d870c75de2d71911cafe0b565074d2301c8412394eef81de64f39af2242ac98fa2c0628d004f9dd25f349b1bbc9adb1e86afaa298b83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9a6b7bb9b26cb56085b9bbced8dd6b43a6afe4bd7a2a32a1fb28f305bf33a6b558ba0e50bb27504158d1296798a44cbcbea5e0d12e6e6bbdf730f6c664bb3bb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-19 02:47:42.795924) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 02:47:42.796250) ========= 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.alyxNwdjcN/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.nIwG8BRhmX/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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.005302 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 02:47:42.845152) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-19 02:47:42.845480) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-19 02:47:42.845804) ========= ========= Total master_lag: 0.22 =========