builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0122 starttime: 1479562161.84 results: success (0) buildid: 20161119030204 builduid: 02292f9e3509430fa804597553cb229d revision: f09e137ead39230eaa94f47988ccce2cfcda4195 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:21.836819) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:21.837325) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:21.837641) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:21.893558) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:21.893870) ========= 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.nzSYYzlRUq/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.YY8NucGLkK/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-19 05:29:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.0M=0.001s 2016-11-19 05:29:22 (15.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.583560 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:22.501171) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:22.501556) ========= 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.nzSYYzlRUq/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.YY8NucGLkK/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.027908 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:22.549962) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-19 05:29:22.550335) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f09e137ead39230eaa94f47988ccce2cfcda4195 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f09e137ead39230eaa94f47988ccce2cfcda4195 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nzSYYzlRUq/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.YY8NucGLkK/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-19 05:29:22,640 truncating revision to first 12 chars 2016-11-19 05:29:22,640 Setting DEBUG logging. 2016-11-19 05:29:22,640 attempt 1/10 2016-11-19 05:29:22,640 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f09e137ead39?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-19 05:29:23,437 unpacking tar archive at: mozilla-central-f09e137ead39/testing/mozharness/ program finished with exit code 0 elapsedTime=1.051410 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-19 05:29:23.624000) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:23.624337) ========= script_repo_revision: f09e137ead39230eaa94f47988ccce2cfcda4195 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:23.624753) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:23.625072) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:23.644051) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-19 05:29:23.644381) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nzSYYzlRUq/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.YY8NucGLkK/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:29:23 INFO - MultiFileLogger online at 20161119 05:29:23 in /builds/slave/test 05:29:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:29:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:29:23 INFO - {'append_to_log': False, 05:29:23 INFO - 'base_work_dir': '/builds/slave/test', 05:29:23 INFO - 'blob_upload_branch': 'Firefox', 05:29:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:29:23 INFO - 'branch': 'Firefox', 05:29:23 INFO - 'buildbot_json_path': 'buildprops.json', 05:29:23 INFO - 'config_files': ('talos/mac_config.py',), 05:29:23 INFO - 'default_actions': ('clobber', 05:29:23 INFO - 'read-buildbot-config', 05:29:23 INFO - 'download-and-extract', 05:29:23 INFO - 'populate-webroot', 05:29:23 INFO - 'create-virtualenv', 05:29:23 INFO - 'install', 05:29:23 INFO - 'run-tests'), 05:29:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:29:23 INFO - 'download_minidump_stackwalk': True, 05:29:23 INFO - 'download_symbols': 'ondemand', 05:29:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:29:23 INFO - 'tooltool.py': '/tools/tooltool.py', 05:29:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:29:23 INFO - '/tools/misc-python/virtualenv.py')}, 05:29:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:29:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:29:23 INFO - 'installer_path': 'installer.exe', 05:29:23 INFO - 'log_level': 'info', 05:29:23 INFO - 'log_name': 'talos', 05:29:23 INFO - 'log_to_console': True, 05:29:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:29:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:29:23 INFO - 'opt_config_files': (), 05:29:23 INFO - 'pip_index': False, 05:29:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:29:23 INFO - 'cmd': ('bash', 05:29:23 INFO - '-c', 05:29:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:29:23 INFO - 'enabled': True, 05:29:23 INFO - 'halt_on_failure': False, 05:29:23 INFO - 'name': 'check_screen_resolution'},), 05:29:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:29:23 INFO - 'cmd': ('bash', 05:29:23 INFO - '-c', 05:29:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:29:23 INFO - 'enabled': True, 05:29:23 INFO - 'halt_on_failure': False, 05:29:23 INFO - 'name': 'check_screen_resolution'},), 05:29:23 INFO - 'run_cmd_checks_enabled': True, 05:29:23 INFO - 'sps_profile': False, 05:29:23 INFO - 'sps_profile_interval': 0, 05:29:23 INFO - 'suite': 'dromaeojs-e10s', 05:29:23 INFO - 'system_bits': '32', 05:29:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:29:23 INFO - 'title': 't-yosemite-r7-0122', 05:29:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:29:23 INFO - 'use_talos_json': True, 05:29:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:29:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:29:23 INFO - 'work_dir': 'build'} 05:29:23 INFO - [mozharness: 2016-11-19 13:29:23.852069Z] Running clobber step. 05:29:23 INFO - Running pre-action listener: _resource_record_pre_action 05:29:23 INFO - Running main action method: clobber 05:29:23 INFO - rmtree: /builds/slave/test/build 05:29:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:29:25 INFO - Running post-action listener: _resource_record_post_action 05:29:25 INFO - [mozharness: 2016-11-19 13:29:25.986487Z] Finished clobber step (success) 05:29:25 INFO - [mozharness: 2016-11-19 13:29:25.986603Z] Running read-buildbot-config step. 05:29:25 INFO - Running pre-action listener: _resource_record_pre_action 05:29:25 INFO - Running main action method: read_buildbot_config 05:29:25 INFO - Using buildbot properties: 05:29:25 INFO - { 05:29:25 INFO - "project": "", 05:29:25 INFO - "product": "firefox", 05:29:25 INFO - "script_repo_revision": "production", 05:29:25 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:29:25 INFO - "repository": "", 05:29:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:29:25 INFO - "buildid": "20161119030204", 05:29:25 INFO - "stage_platform": "macosx64", 05:29:25 INFO - "basedir": "/builds/slave/test", 05:29:25 INFO - "nightly_build": "True", 05:29:25 INFO - "buildnumber": 21, 05:29:25 INFO - "slavename": "t-yosemite-r7-0122", 05:29:25 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:29:25 INFO - "platform": "yosemite_r7", 05:29:25 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:29:25 INFO - "branch": "mozilla-central", 05:29:25 INFO - "repo_path": "mozilla-central", 05:29:25 INFO - "revision": "f09e137ead39230eaa94f47988ccce2cfcda4195", 05:29:25 INFO - "pgo_build": "False", 05:29:25 INFO - "builduid": "02292f9e3509430fa804597553cb229d", 05:29:25 INFO - "slavebuilddir": "test" 05:29:25 INFO - } 05:29:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 05:29:25 INFO - Running post-action listener: _resource_record_post_action 05:29:25 INFO - [mozharness: 2016-11-19 13:29:25.989984Z] Finished read-buildbot-config step (success) 05:29:25 INFO - [mozharness: 2016-11-19 13:29:25.990096Z] Running download-and-extract step. 05:29:25 INFO - Running pre-action listener: _resource_record_pre_action 05:29:25 INFO - Running main action method: download_and_extract 05:29:25 INFO - mkdir: /builds/slave/test/build/tests 05:29: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')]} 05:29:25 INFO - trying https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 05:29:25 INFO - Downloading https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:29:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 05:29:27 INFO - Downloaded 1199 bytes. 05:29:27 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:29:27 INFO - Using the following test package requirements: 05:29:27 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:29:27 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:29:27 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 05:29:27 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:29:27 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 05:29:27 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:29:27 INFO - u'jsshell-mac.zip'], 05:29:27 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:29:27 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 05:29:27 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:29:27 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:29:27 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 05:29:27 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:29:27 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 05:29:27 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:29:27 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 05:29:27 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:29:27 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 05:29:27 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:29:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:29:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:29:27 INFO - Fetch https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:29:27 INFO - Http code: 200 05:29:27 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 05:29:27 INFO - x-amz-cf-id: 7W4TQAGxqWdLOl_yOSqoxdAXYSES2aF6X-BeaBTt5a3gzs2wT7zU7Q== 05:29:27 INFO - x-amz-version-id: 5MwntJMIA1vPEUZ2guR4TMaw71acYLNT 05:29:28 INFO - Expected file size: 16265647 05:29:28 INFO - Obtained file size: 16265647 05:29:34 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:29:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:29:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:29:34 INFO - Fetch https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:29:35 INFO - Http code: 200 05:29:35 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 05:29:35 INFO - x-amz-cf-id: Cuxt5qLp4JxlLERqOqSIfM8ogYpiktympQRWZuE1vUdhg3uipuIjSQ== 05:29:35 INFO - x-amz-version-id: 5MwntJMIA1vPEUZ2guR4TMaw71acYLNT 05:29:35 INFO - Expected file size: 16265647 05:29:35 INFO - Obtained file size: 16265647 05:29:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 05:29:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:29:41 INFO - Fetch https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 05:29:43 INFO - Http code: 200 05:29:43 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 05:29:43 INFO - x-amz-cf-id: t7i1w95USvl5PpytmO9471wuIAZEtz2DwHcmbIFxw5uyE7NHPR634g== 05:29:43 INFO - x-amz-version-id: WxLWUm3uSjoCRdjzuZkydHNE3WkP.EPW 05:29:43 INFO - Expected file size: 14409392 05:29:43 INFO - Obtained file size: 14409392 05:29:43 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')]} 05:29:43 INFO - trying https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:29:43 INFO - Downloading https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 05:29:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 05:29:46 INFO - Downloaded 107683205 bytes. 05:29:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:29:46 INFO - mkdir: /builds/slave/test/properties 05:29:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:29:46 INFO - Writing to file /builds/slave/test/properties/build_url 05:29:46 INFO - Contents: 05:29:46 INFO - build_url:https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:29:47 INFO - Running post-action listener: _resource_record_post_action 05:29:47 INFO - Running post-action listener: set_extra_try_arguments 05:29:47 INFO - [mozharness: 2016-11-19 13:29:47.627240Z] Finished download-and-extract step (success) 05:29:47 INFO - [mozharness: 2016-11-19 13:29:47.627361Z] Running populate-webroot step. 05:29:47 INFO - Running pre-action listener: _resource_record_pre_action 05:29:47 INFO - Running main action method: populate_webroot 05:29:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:29:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:29:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:29:47 INFO - '--tppagecycles', 05:29:47 INFO - '7'], 05:29:47 INFO - 'tests': ['tsvgm']}}, 05:29:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:29:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:29:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:29:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:29:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:29:47 INFO - 'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:29:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:29:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:29:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:29:47 INFO - 'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['damp', 'tps']}, 05:29:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:29:47 INFO - 'tests': ['damp', 'tps']}, 05:29:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['dromaeo_dom']}, 05:29:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:29:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['basic_compositor_video']}, 05:29:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:29:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['a11yr', 05:29:47 INFO - 'ts_paint', 05:29:47 INFO - 'tpaint', 05:29:47 INFO - 'sessionrestore', 05:29:47 INFO - 'sessionrestore_no_auto_restore', 05:29:47 INFO - 'tabpaint']}, 05:29:47 INFO - 'other-e10s': {'tests': ['a11yr', 05:29:47 INFO - 'ts_paint', 05:29:47 INFO - 'tpaint', 05:29:47 INFO - 'sessionrestore', 05:29:47 INFO - 'sessionrestore_no_auto_restore', 05:29:47 INFO - 'tabpaint']}, 05:29:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:29:47 INFO - 'ts_paint', 05:29:47 INFO - 'tpaint', 05:29:47 INFO - 'sessionrestore', 05:29:47 INFO - 'sessionrestore_no_auto_restore', 05:29:47 INFO - 'tabpaint']}, 05:29:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:29:47 INFO - 'ts_paint', 05:29:47 INFO - 'tpaint', 05:29:47 INFO - 'sessionrestore', 05:29:47 INFO - 'sessionrestore_no_auto_restore', 05:29:47 INFO - 'tabpaint']}, 05:29:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['a11yr', 05:29:47 INFO - 'ts_paint', 05:29:47 INFO - 'tpaint', 05:29:47 INFO - 'sessionrestore', 05:29:47 INFO - 'sessionrestore_no_auto_restore', 05:29:47 INFO - 'tabpaint']}, 05:29:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['a11yr', 05:29:47 INFO - 'ts_paint', 05:29:47 INFO - 'tpaint', 05:29:47 INFO - 'sessionrestore', 05:29:47 INFO - 'sessionrestore_no_auto_restore', 05:29:47 INFO - 'tabpaint']}, 05:29:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['tsvgx', 05:29:47 INFO - 'tsvgr_opacity', 05:29:47 INFO - 'tart', 05:29:47 INFO - 'tscrollx', 05:29:47 INFO - 'cart']}, 05:29:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:29:47 INFO - 'tsvgr_opacity', 05:29:47 INFO - 'tart', 05:29:47 INFO - 'tscrollx', 05:29:47 INFO - 'cart']}, 05:29:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:29:47 INFO - 'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['tp5o']}, 05:29:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:29:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:29:47 INFO - 'talos_options': ['--disable-e10s', 05:29:47 INFO - '--xperf_path', 05:29:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:29:47 INFO - 'tests': ['tp5n']}, 05:29:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:29:47 INFO - 'talos_options': ['--xperf_path', 05:29:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:29:47 INFO - 'tests': ['tp5n']}}, 05:29:47 INFO - 'talos.zip': {'path': '', 05:29:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:29:47 INFO - Running post-action listener: _resource_record_post_action 05:29:47 INFO - [mozharness: 2016-11-19 13:29:47.645570Z] Finished populate-webroot step (success) 05:29:47 INFO - [mozharness: 2016-11-19 13:29:47.645701Z] Running create-virtualenv step. 05:29:47 INFO - Running pre-action listener: _resource_record_pre_action 05:29:47 INFO - Running main action method: create_virtualenv 05:29:47 INFO - Creating virtualenv /builds/slave/test/build/venv 05:29:47 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:29:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:29:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:29:47 INFO - Using real prefix '/tools/python27' 05:29:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:29:48 INFO - Installing distribute.............................................................................................................................................................................................done. 05:29:51 INFO - Installing pip.................done. 05:29:51 INFO - Return code: 0 05:29:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:29:51 INFO - Reading from file tmpfile_stdout 05:29:51 INFO - Output received: 05:29:51 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) 05:29:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:51 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 0x10f7ec3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f762570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe779cd2a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602d30>, '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 0x10f469db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5fab20>, '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.nzSYYzlRUq/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.YY8NucGLkK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:29:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:29:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzSYYzlRUq/Render', 05:29:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:51 INFO - 'HOME': '/Users/cltbld', 05:29:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:51 INFO - 'LOGNAME': 'cltbld', 05:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:51 INFO - 'MOZ_NO_REMOTE': '1', 05:29:51 INFO - 'NO_EM_RESTART': '1', 05:29:51 INFO - 'PAGER': '/bin/cat', 05:29:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:51 INFO - 'PWD': '/builds/slave/test', 05:29:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:51 INFO - 'SHELL': '/bin/bash', 05:29:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YY8NucGLkK/Listeners', 05:29:51 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:29:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:51 INFO - 'USER': 'cltbld', 05:29:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:51 INFO - 'XPC_FLAGS': '0x0', 05:29:51 INFO - 'XPC_SERVICE_NAME': '0', 05:29:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:29:51 INFO - Cleaning up... 05:29:51 INFO - Return code: 0 05:29:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:51 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 0x10f7ec3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f762570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe779cd2a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602d30>, '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 0x10f469db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5fab20>, '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.nzSYYzlRUq/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.YY8NucGLkK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:29:51 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 05:29:51 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 05:29:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzSYYzlRUq/Render', 05:29:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:51 INFO - 'HOME': '/Users/cltbld', 05:29:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:51 INFO - 'LOGNAME': 'cltbld', 05:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:51 INFO - 'MOZ_NO_REMOTE': '1', 05:29:51 INFO - 'NO_EM_RESTART': '1', 05:29:51 INFO - 'PAGER': '/bin/cat', 05:29:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:51 INFO - 'PWD': '/builds/slave/test', 05:29:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:51 INFO - 'SHELL': '/bin/bash', 05:29:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YY8NucGLkK/Listeners', 05:29:51 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:29:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:51 INFO - 'USER': 'cltbld', 05:29:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:51 INFO - 'XPC_FLAGS': '0x0', 05:29:51 INFO - 'XPC_SERVICE_NAME': '0', 05:29:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:52 INFO - Downloading/unpacking psutil>=3.1.1 05:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:29:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:56 INFO - Installing collected packages: psutil 05:29:56 INFO - Running setup.py install for psutil 05:29:56 INFO - building 'psutil._psutil_osx' extension 05:29:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:29:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:29:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:29:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:29:56 INFO - building 'psutil._psutil_posix' extension 05:29:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:29:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:29:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:29:56 INFO - ^ 05:29:57 INFO - 1 warning generated. 05:29:57 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:29:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:57 INFO - Successfully installed psutil 05:29:57 INFO - Cleaning up... 05:29:57 INFO - Return code: 0 05:29:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:29:57 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')]} 05:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:57 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')]} 05:29:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:57 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 0x10f7ec3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f762570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe779cd2a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602d30>, '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 0x10f469db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5fab20>, '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.nzSYYzlRUq/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.YY8NucGLkK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:29:57 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 05:29:57 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 05:29:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzSYYzlRUq/Render', 05:29:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:57 INFO - 'HOME': '/Users/cltbld', 05:29:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:57 INFO - 'LOGNAME': 'cltbld', 05:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:57 INFO - 'MOZ_NO_REMOTE': '1', 05:29:57 INFO - 'NO_EM_RESTART': '1', 05:29:57 INFO - 'PAGER': '/bin/cat', 05:29:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:57 INFO - 'PWD': '/builds/slave/test', 05:29:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:57 INFO - 'SHELL': '/bin/bash', 05:29:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YY8NucGLkK/Listeners', 05:29:57 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:29:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:57 INFO - 'USER': 'cltbld', 05:29:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:57 INFO - 'XPC_FLAGS': '0x0', 05:29:57 INFO - 'XPC_SERVICE_NAME': '0', 05:29:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:30:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:30:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:30:00 INFO - Installing collected packages: mozsystemmonitor 05:30:00 INFO - Running setup.py install for mozsystemmonitor 05:30:00 INFO - Successfully installed mozsystemmonitor 05:30:00 INFO - Cleaning up... 05:30:00 INFO - Return code: 0 05:30:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f7ec3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f762570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe779cd2a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602d30>, '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 0x10f469db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5fab20>, '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.nzSYYzlRUq/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.YY8NucGLkK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:30:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:30:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzSYYzlRUq/Render', 05:30:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:00 INFO - 'HOME': '/Users/cltbld', 05:30:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:00 INFO - 'LOGNAME': 'cltbld', 05:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:00 INFO - 'MOZ_NO_REMOTE': '1', 05:30:00 INFO - 'NO_EM_RESTART': '1', 05:30:00 INFO - 'PAGER': '/bin/cat', 05:30:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:00 INFO - 'PWD': '/builds/slave/test', 05:30:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:00 INFO - 'SHELL': '/bin/bash', 05:30:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YY8NucGLkK/Listeners', 05:30:00 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:30:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:00 INFO - 'USER': 'cltbld', 05:30:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:00 INFO - 'XPC_FLAGS': '0x0', 05:30:00 INFO - 'XPC_SERVICE_NAME': '0', 05:30:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:00 INFO - Downloading/unpacking jsonschema==2.5.1 05:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:30:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:30: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 05:30: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 05:30: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 05:30: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 05:30: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 05:30: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 05:30:03 INFO - Downloading functools32-3.2.3-2.tar.gz 05:30:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:30:03 INFO - warning: no files found matching '*.txt' 05:30:03 INFO - no previously-included directories found matching 'build' 05:30:03 INFO - no previously-included directories found matching 'dist' 05:30:03 INFO - no previously-included directories found matching '.git*' 05:30:03 INFO - Installing collected packages: jsonschema, functools32 05:30:03 INFO - Running setup.py install for functools32 05:30:03 INFO - warning: no files found matching '*.txt' 05:30:03 INFO - no previously-included directories found matching 'build' 05:30:03 INFO - no previously-included directories found matching 'dist' 05:30:03 INFO - no previously-included directories found matching '.git*' 05:30:03 INFO - Successfully installed jsonschema functools32 05:30:03 INFO - Cleaning up... 05:30:03 INFO - Return code: 0 05:30:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30: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')]} 05:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30: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', '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 0x10f7ec3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f762570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe779cd2a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602d30>, '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 0x10f469db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5fab20>, '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.nzSYYzlRUq/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.YY8NucGLkK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:30: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:30: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 functools32==3.2.3-2 05:30:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzSYYzlRUq/Render', 05:30:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:03 INFO - 'HOME': '/Users/cltbld', 05:30:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:03 INFO - 'LOGNAME': 'cltbld', 05:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:03 INFO - 'MOZ_NO_REMOTE': '1', 05:30:03 INFO - 'NO_EM_RESTART': '1', 05:30:03 INFO - 'PAGER': '/bin/cat', 05:30:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:03 INFO - 'PWD': '/builds/slave/test', 05:30:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:03 INFO - 'SHELL': '/bin/bash', 05:30:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YY8NucGLkK/Listeners', 05:30:03 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:30:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:03 INFO - 'USER': 'cltbld', 05:30:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:03 INFO - 'XPC_FLAGS': '0x0', 05:30:03 INFO - 'XPC_SERVICE_NAME': '0', 05:30:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:30:04 INFO - Cleaning up... 05:30:04 INFO - Return code: 0 05:30:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f7ec3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f762570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe779cd2a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602d30>, '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 0x10f469db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5fab20>, '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.nzSYYzlRUq/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.YY8NucGLkK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:30:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzSYYzlRUq/Render', 05:30:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:04 INFO - 'HOME': '/Users/cltbld', 05:30:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:04 INFO - 'LOGNAME': 'cltbld', 05:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:04 INFO - 'MOZ_NO_REMOTE': '1', 05:30:04 INFO - 'NO_EM_RESTART': '1', 05:30:04 INFO - 'PAGER': '/bin/cat', 05:30:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:04 INFO - 'PWD': '/builds/slave/test', 05:30:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:04 INFO - 'SHELL': '/bin/bash', 05:30:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YY8NucGLkK/Listeners', 05:30:04 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:30:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:04 INFO - 'USER': 'cltbld', 05:30:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:04 INFO - 'XPC_FLAGS': '0x0', 05:30:04 INFO - 'XPC_SERVICE_NAME': '0', 05:30:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:04 INFO - Downloading/unpacking blobuploader==1.2.4 05:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:07 INFO - Downloading blobuploader-1.2.4.tar.gz 05:30:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:30:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:30:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:08 INFO - Downloading docopt-0.6.1.tar.gz 05:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:30:08 INFO - Installing collected packages: blobuploader, requests, docopt 05:30:08 INFO - Running setup.py install for blobuploader 05:30:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:30:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:30:08 INFO - Running setup.py install for requests 05:30:08 INFO - Running setup.py install for docopt 05:30:09 INFO - Successfully installed blobuploader requests docopt 05:30:09 INFO - Cleaning up... 05:30:09 INFO - Return code: 0 05:30:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30: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')]} 05:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:09 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 0x10f7ec3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f762570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe779cd2a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602d30>, '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 0x10f469db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5fab20>, '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.nzSYYzlRUq/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.YY8NucGLkK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:30:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzSYYzlRUq/Render', 05:30:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:09 INFO - 'HOME': '/Users/cltbld', 05:30:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:09 INFO - 'LOGNAME': 'cltbld', 05:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:09 INFO - 'MOZ_NO_REMOTE': '1', 05:30:09 INFO - 'NO_EM_RESTART': '1', 05:30:09 INFO - 'PAGER': '/bin/cat', 05:30:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:09 INFO - 'PWD': '/builds/slave/test', 05:30:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:09 INFO - 'SHELL': '/bin/bash', 05:30:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YY8NucGLkK/Listeners', 05:30:09 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:30:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:09 INFO - 'USER': 'cltbld', 05:30:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:09 INFO - 'XPC_FLAGS': '0x0', 05:30:09 INFO - 'XPC_SERVICE_NAME': '0', 05:30:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:30:09 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-CtRNSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:30:09 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-PeuJtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:30:09 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-j6816k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:30:09 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-pS2GVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:30:09 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-AVEUbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:30:10 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-bl83ew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:30:10 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-5B76ap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:30:10 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-xrF_20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:30:10 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-FoTq5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:30:10 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-SWpaWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:30:10 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-8d3PPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:30:10 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-fPl_u9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:10 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-57y27v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:10 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-QsBkf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:10 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-Se_WHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:11 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-VXnUu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:11 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-SbqNKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:30:11 INFO - Running setup.py install for manifestparser 05:30:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:30:11 INFO - Running setup.py install for mozcrash 05:30:11 INFO - Running setup.py install for mozdebug 05:30:11 INFO - Running setup.py install for mozdevice 05:30:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:30:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:30:11 INFO - Running setup.py install for mozfile 05:30:11 INFO - Running setup.py install for mozhttpd 05:30:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:30:12 INFO - Running setup.py install for mozinfo 05:30:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:30:12 INFO - Running setup.py install for mozInstall 05:30:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:30:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:30:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:30:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:30:12 INFO - Running setup.py install for mozleak 05:30:12 INFO - Running setup.py install for mozlog 05:30:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:30:12 INFO - Running setup.py install for moznetwork 05:30:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:30:12 INFO - Running setup.py install for mozprocess 05:30:13 INFO - Running setup.py install for mozprofile 05:30:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:30:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:30:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:30:13 INFO - Running setup.py install for mozrunner 05:30:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:30:13 INFO - Running setup.py install for mozscreenshot 05:30:13 INFO - Running setup.py install for moztest 05:30:13 INFO - Running setup.py install for mozversion 05:30:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:30:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:30:13 INFO - Cleaning up... 05:30:13 INFO - Return code: 0 05:30:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:13 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 0x10f7ec3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f762570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe779cd2a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602d30>, '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 0x10f469db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5fab20>, '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.nzSYYzlRUq/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.YY8NucGLkK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:30:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:30:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:30:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzSYYzlRUq/Render', 05:30:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:13 INFO - 'HOME': '/Users/cltbld', 05:30:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:13 INFO - 'LOGNAME': 'cltbld', 05:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:13 INFO - 'MOZ_NO_REMOTE': '1', 05:30:13 INFO - 'NO_EM_RESTART': '1', 05:30:13 INFO - 'PAGER': '/bin/cat', 05:30:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:13 INFO - 'PWD': '/builds/slave/test', 05:30:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:13 INFO - 'SHELL': '/bin/bash', 05:30:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YY8NucGLkK/Listeners', 05:30:13 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:30:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:13 INFO - 'USER': 'cltbld', 05:30:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:13 INFO - 'XPC_FLAGS': '0x0', 05:30:13 INFO - 'XPC_SERVICE_NAME': '0', 05:30:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:30:14 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-s8JIKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:30:14 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:30:14 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-zdectO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:30:14 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)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:30:14 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-Iya69M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:30:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:30:14 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-XZkdIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:30:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:30:14 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-553hkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:30:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:30:14 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-PVyIhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:30:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:30:14 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-5VjspQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:30:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:30:14 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-UT8y3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:30:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:30:14 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-VmYGds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:30:15 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-zw3cJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:30:15 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)) 05:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:30:15 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-1dtUlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:30:15 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-cjPrar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:30:15 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)) 05:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:15 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-6gq7XM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:15 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-v7qabo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:15 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)) 05:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:15 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-n0pXKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:15 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-ZbDlaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:15 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-WgRv1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:30:15 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)) 05:30:15 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)) 05:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:30:15 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:30:15 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:30:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:18 INFO - Downloading blessings-1.6.tar.gz 05:30:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:30:18 INFO - Installing collected packages: blessings 05:30:18 INFO - Running setup.py install for blessings 05:30:18 INFO - Successfully installed blessings 05:30:18 INFO - Cleaning up... 05:30:18 INFO - Return code: 0 05:30:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:30:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:30:18 INFO - Reading from file tmpfile_stdout 05:30:19 INFO - Current package versions: 05:30:19 INFO - blessings == 1.6 05:30:19 INFO - blobuploader == 1.2.4 05:30:19 INFO - docopt == 0.6.1 05:30:19 INFO - functools32 == 3.2.3-2 05:30:19 INFO - jsonschema == 2.5.1 05:30:19 INFO - manifestparser == 1.1 05:30:19 INFO - mozInstall == 1.12 05:30:19 INFO - mozcrash == 1.0 05:30:19 INFO - mozdebug == 0.1 05:30:19 INFO - mozdevice == 0.49 05:30:19 INFO - mozfile == 1.2 05:30:19 INFO - mozhttpd == 0.7 05:30:19 INFO - mozinfo == 0.9 05:30:19 INFO - mozleak == 0.1 05:30:19 INFO - mozlog == 3.4 05:30:19 INFO - moznetwork == 0.27 05:30:19 INFO - mozprocess == 0.23 05:30:19 INFO - mozprofile == 0.28 05:30:19 INFO - mozrunner == 6.13 05:30:19 INFO - mozscreenshot == 0.1 05:30:19 INFO - mozsystemmonitor == 0.3 05:30:19 INFO - moztest == 0.7 05:30:19 INFO - mozversion == 1.4 05:30:19 INFO - psutil == 3.1.1 05:30:19 INFO - requests == 1.2.3 05:30:19 INFO - wsgiref == 0.1.2 05:30:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30: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')]} 05:30:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:19 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 0x10f7ec3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f762570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe779cd2a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602d30>, '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 0x10f469db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5fab20>, '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.nzSYYzlRUq/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.YY8NucGLkK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:30:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:30:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzSYYzlRUq/Render', 05:30:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:19 INFO - 'HOME': '/Users/cltbld', 05:30:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:19 INFO - 'LOGNAME': 'cltbld', 05:30:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:19 INFO - 'MOZ_NO_REMOTE': '1', 05:30:19 INFO - 'NO_EM_RESTART': '1', 05:30:19 INFO - 'PAGER': '/bin/cat', 05:30:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:19 INFO - 'PWD': '/builds/slave/test', 05:30:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:19 INFO - 'SHELL': '/bin/bash', 05:30:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YY8NucGLkK/Listeners', 05:30:19 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:30:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:19 INFO - 'USER': 'cltbld', 05:30:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:19 INFO - 'XPC_FLAGS': '0x0', 05:30:19 INFO - 'XPC_SERVICE_NAME': '0', 05:30:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:30:19 INFO - Cleaning up... 05:30:19 INFO - Return code: 0 05:30:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30: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')]} 05:30:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f7ec3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f762570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe779cd2a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602d30>, '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 0x10f469db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5fab20>, '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.nzSYYzlRUq/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.YY8NucGLkK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:30:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:30:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzSYYzlRUq/Render', 05:30:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:19 INFO - 'HOME': '/Users/cltbld', 05:30:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:19 INFO - 'LOGNAME': 'cltbld', 05:30:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:19 INFO - 'MOZ_NO_REMOTE': '1', 05:30:19 INFO - 'NO_EM_RESTART': '1', 05:30:19 INFO - 'PAGER': '/bin/cat', 05:30:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:19 INFO - 'PWD': '/builds/slave/test', 05:30:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:19 INFO - 'SHELL': '/bin/bash', 05:30:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YY8NucGLkK/Listeners', 05:30:19 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:30:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:19 INFO - 'USER': 'cltbld', 05:30:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:19 INFO - 'XPC_FLAGS': '0x0', 05:30:19 INFO - 'XPC_SERVICE_NAME': '0', 05:30:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:30:19 INFO - Cleaning up... 05:30:19 INFO - Return code: 0 05:30:19 INFO - Running post-action listener: _resource_record_post_action 05:30:19 INFO - Running post-action listener: _start_resource_monitoring 05:30:19 INFO - Starting resource monitoring. 05:30:19 INFO - [mozharness: 2016-11-19 13:30:19.564334Z] Finished create-virtualenv step (success) 05:30:19 INFO - [mozharness: 2016-11-19 13:30:19.565303Z] Running install step. 05:30:19 INFO - Running pre-action listener: _resource_record_pre_action 05:30:19 INFO - Running main action method: install 05:30:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:30:19 INFO - Reading from file tmpfile_stdout 05:30:19 INFO - Detecting whether we're running mozinstall >=1.0... 05:30:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:30:19 INFO - Reading from file tmpfile_stdout 05:30:19 INFO - Output received: 05:30:19 INFO - Usage: mozinstall [options] installer 05:30:19 INFO - Options: 05:30:19 INFO - -h, --help show this help message and exit 05:30:19 INFO - -d DEST, --destination=DEST 05:30:19 INFO - Directory to install application into. [default: 05:30:19 INFO - "/builds/slave/test"] 05:30:19 INFO - --app=APP Application being installed. [default: firefox] 05:30:19 INFO - mkdir: /builds/slave/test/build/application 05:30:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:30:52 INFO - Reading from file tmpfile_stdout 05:30:52 INFO - Output received: 05:30:52 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:30:52 INFO - Running post-action listener: _resource_record_post_action 05:30:52 INFO - [mozharness: 2016-11-19 13:30:52.667327Z] Finished install step (success) 05:30:52 INFO - [mozharness: 2016-11-19 13:30:52.667440Z] Running run-tests step. 05:30:52 INFO - Running pre-action listener: _resource_record_pre_action 05:30:52 INFO - Running main action method: run_tests 05:30:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:30:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:30:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:30:52 INFO - 2016-11-19 05:30:52.679 screenresolution[1638:9078] starting screenresolution argv=screenresolution get 05:30:52 INFO - 2016-11-19 05:30:52.682 screenresolution[1638:9078] Display 0: 1600x1200x32@60 05:30:52 INFO - 2016-11-19 05:30:52.687 screenresolution[1639:9080] starting screenresolution argv=screenresolution list 05:30:52 INFO - Available Modes on Display 0 05:30:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:30:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:30:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:30:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:30:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:30:52 INFO - Intel Iris: 05:30:52 INFO - Chipset Model: Intel Iris 05:30:52 INFO - Type: GPU 05:30:52 INFO - Bus: Built-In 05:30:52 INFO - VRAM (Dynamic, Max): 1536 MB 05:30:52 INFO - Vendor: Intel (0x8086) 05:30:52 INFO - Device ID: 0x0a2e 05:30:52 INFO - Revision ID: 0x0009 05:30:52 INFO - Displays: 05:30:52 INFO - Display: 05:30:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:30:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:30:52 INFO - Display Serial Number: 1600x1200 60 05:30:52 INFO - Main Display: Yes 05:30:52 INFO - Mirror: Off 05:30:52 INFO - Online: Yes 05:30:52 INFO - Rotation: Supported 05:30:52 INFO - Return code: 0 05:30:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:30:52 INFO - Python 2.7.3 05:30:52 INFO - Return code: 0 05:30:52 INFO - grabbing minidump binary from tooltool 05:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:52 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 0x10f602d30>, '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 0x10f469db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f5fab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:30:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:30:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:30:52 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 05:30:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:30:53 INFO - Return code: 0 05:30:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:30:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:30:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:30:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:30:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:30:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0122', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0122 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:30:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzSYYzlRUq/Render', 05:30:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:53 INFO - 'HOME': '/Users/cltbld', 05:30:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:53 INFO - 'LOGNAME': 'cltbld', 05:30:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:30:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:53 INFO - 'MOZ_NO_REMOTE': '1', 05:30:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:30:53 INFO - 'NO_EM_RESTART': '1', 05:30:53 INFO - 'PAGER': '/bin/cat', 05:30:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:53 INFO - 'PWD': '/builds/slave/test', 05:30:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:30:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:53 INFO - 'SHELL': '/bin/bash', 05:30:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YY8NucGLkK/Listeners', 05:30:53 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 05:30:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:53 INFO - 'USER': 'cltbld', 05:30:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:53 INFO - 'XPC_FLAGS': '0x0', 05:30:53 INFO - 'XPC_SERVICE_NAME': '0', 05:30:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0122', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:30:53 INFO - mozversion application_buildid: 20161119030204 05:30:53 INFO - mozversion application_changeset: f09e137ead39230eaa94f47988ccce2cfcda4195 05:30:53 INFO - mozversion application_display_name: Nightly 05:30:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:30:53 INFO - mozversion application_name: Firefox 05:30:53 INFO - mozversion application_remotingname: firefox 05:30:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:30:53 INFO - mozversion application_vendor: Mozilla 05:30:53 INFO - mozversion application_version: 53.0a1 05:30:53 INFO - mozversion platform_buildid: 20161119030204 05:30:53 INFO - mozversion platform_changeset: f09e137ead39230eaa94f47988ccce2cfcda4195 05:30:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:30:53 INFO - mozversion platform_version: 53.0a1 05:30:53 INFO - using testdate: 1479562253 05:30:53 INFO - actual date: 1479562253 05:30:53 INFO - starting webserver on 'localhost:49242' 05:30:53 INFO - SUITE-START | Running 2 tests 05:30:53 INFO - TEST-START | dromaeo_css 05:30:53 INFO - operating with platform_type : mac_ 05:30:53 INFO - Initialising browser for dromaeo_css test... 05:30:53 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpMeGTAN/profile http://localhost:49242/getInfo.html) 05:30:56 INFO - PROCESS | 1649 | 2016-11-19 05:30:56.709 plugin-container[1653:9275] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x924f, name = 'com.apple.tsm.portname' 05:30:56 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:30:56 INFO - PROCESS | 1649 | 2016-11-19 05:30:56.710 plugin-container[1653:9275] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 05:30:56 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:30:56 INFO - PROCESS | 1649 | 2016-11-19 05:30:56.710 plugin-container[1653:9275] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:30:56 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 05:30:56 INFO - PROCESS | 1649 | colorDepth:24 05:30:56 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 05:30:56 INFO - PROCESS | 1649 | __metrics 05:30:56 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 05:30:57 INFO - TEST-INFO | 1649: exit 0 05:30:57 INFO - Browser initialized. 05:30:57 INFO - Running cycle 1/1 for dromaeo_css test... 05:30:57 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpMeGTAN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:31:00 INFO - PROCESS | 1655 | 2016-11-19 05:31:00.654 plugin-container[1656:9402] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 05:31:00 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:31:00 INFO - PROCESS | 1655 | 2016-11-19 05:31:00.655 plugin-container[1656:9402] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 05:31:00 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:31:00 INFO - PROCESS | 1655 | 2016-11-19 05:31:00.655 plugin-container[1656:9402] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:31:10 INFO - PROCESS | 1655 | RSS: Main: 243212288 05:31:10 INFO - PROCESS | 1655 | 05:31:59 INFO - PROCESS | 1655 | 1479562319964 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:34:14 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 05:34:15 INFO - PROCESS | 1655 | RSS: Main: 248598528 05:34:15 INFO - PROCESS | 1655 | 05:37:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 05:37:18 INFO - PROCESS | 1655 | RSS: Main: 259854336 05:37:18 INFO - PROCESS | 1655 | 05:40:20 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 05:40:21 INFO - PROCESS | 1655 | RSS: Main: 264249344 05:40:21 INFO - PROCESS | 1655 | 05:43:23 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 05:43:23 INFO - PROCESS | 1655 | RSS: Main: 262959104 05:43:23 INFO - PROCESS | 1655 | 05:46:26 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 05:46:27 INFO - PROCESS | 1655 | RSS: Main: 267472896 05:46:27 INFO - PROCESS | 1655 | 05:49:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 05:49:30 INFO - PROCESS | 1655 | RSS: Main: 269479936 05:49:30 INFO - PROCESS | 1655 | 05:49:30 INFO - PROCESS | 1655 | __start_tp_report 05:49:30 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 05:49:30 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 05:49:30 INFO - PROCESS | 1655 | |i|pagename|runs| 05:49:30 INFO - PROCESS | 1655 | |0;dojo.html;2844.1325395754293;3379.969109421627;3386.949195762064;3389.9491507627376;3456.222349971256;5676.375598684152;5722.027255366587;5774.191613174159;5778.653280803154;5844.064949608063;5692.0608099663505;5750.412314458489;5788.652680839153;5865.589408741379;5902.409759024106;5195.740212989345;5215.478452154774;5215.530602245797;5217.34783152106;5365.03429382711;5344.973275133619;5366.168243922199;5367.329083864517;5376.247325374451;5396.082665946778;5141.126008578551;5295.4969277918535;5307.203919412081;5321.547668448186;5351.09031464652;5064.265681476165;5152.969406118791;5187.48125187482;5217.217417387422;5242.973785131088;5127.743612819344;5128;5133.486651334837;5193.091209038441;5272.05103081449;5149.871253218699;5387.757550910179;5555.777768889239;5810.941890581083;5850.882982340372;4638.9536104638855;5096.898062038776;5419.620626556142;6283.968580157116;6306;4843.66094373394;5239.79040838366;5707.486326230659;6214.192155019864;6280.026595877646;5730.054541000688;5935.673537955454;5956.612820166675;5985.371535988746;5992.161097446361;5363.927214557104;6027.758889644453;6303.873922521524;6363.045543168561;6406.391392817675;6131.651934473265;6151.6001459904965;6174.351693072163;6186;6193.102000210034;6158.599691020069;6307.621542707452;6316.526260530479;6327.588706734047;6371.39471750183;6072.726727297282;6146;6159.722812473449;6211.751529938751;6248.187735594343;6200.7519699211525;6215.192025036807;6327.810165695037;6329.525285603599;6371.872562548723;4996.075725990703;5005.34930459038;5021.698698078127;5030.572401345853;5067.037262920034;4467.723162324327;4497.977510112429;4502.212112880233;4512.571305725951;4569.1547063793305;5783.971080144573;5839.970800146057;5869.73586188614;5879.559033072451;6019.849503762441;6222.751089956441;6273.905891411632;6286.968565157145;6303.716332765037;6324.430801227912;5992.43071908168;6016.217891674054;6021.638701677914;6039.973204555236;6074.787382441682;4393.868183954486;5241.0042092002395;5509.531689806411;5531.419200983919;5558.610897237155;6218.378162183745;6232.345603711727;6295.401936815995;6313.084602732486;6362.618242905441;5830.795922142875;5840.853978650568;6254.781082662267;6294.559380843297;6327.651979141191;6355;6359.618422894641;6444.516661250331;6451.2903580607035;6460.935390646034;6142.232220972379;6164.260288765377;6173.6913154343;6193.102000209854;6275.560710750204;6183.103449999815;6279.1209230708655;6293.559450838397;6310.242770867525;6310.810675679737;6043.063325184604;6078.483328917092;6122.062734707273;6234.781782637586;6260.499160067275;6137.775380989496;6200.7519699211525;6248.875022499434;6270.623762574261;6288.811335659938;5838.211841401357;5885.852853678693;6058.0610005449225;6145.033589251397;6147.9855823788575;5697.829065128053;5739.966805974965;6194.659293738888;6221.751129954932;6245.531585131042;6052.788152414644;6108.633481991094;6110.450059494667;6116.204893363834;6131.233595800525 05:49:30 INFO - PROCESS | 1655 | |1;ext.html;454.00799253630817;483.6662702735115;488.71654440424544;495.4525249599193;502.87930896584805;34930.65069349306;39151.41272880905;39255.82232533025;41125.560605378814;41142.58857411432;34342.31315373691;40655;41070;41094.17811643765;41130.177396452054;8051.677932882707;9274.118958698913;9392.87285525738;9490.288228382864;9514.286428517855;7937.801554961115;9285.25717942563;9330.300227482932;9366.063393660648;9447.149756521912;9302.441853488779;9342.439453632769;9412.2470202384;9454.43273403598;9482.43105413674;9335.486548239878;9357.719268421924;9379.671711490066;9445.338826282163;9461.716148515556;9216.354855160142;9236.261099112055;9314.161725444676;9318;9338.859917101248;38231;38318;38601.03497412559;38765.80617096925;38968.220635587415;3336.199312165087;3346.6987971082476;3506.8597256109724;3529.205928666055;3651.1602331463646;29279.975200867866;29539.556906646416;29680.8515957421;29850.701492985227;30272.697273027206;3043.08707387785;3155.936881262362;3198.424283628946;3213.5340375645633;3273.950890736617;447917;449918;453235;455306;455400.9999999967;56939;57332;57511;57548.424515754305;57638.7118064407;33511.83244083813;34451.31097378038;34980.125496862274;35070.82464587661;61612.69193654093;15580.065196088271;15638;15776.211189440482;15784.842151578567;15802.683946321009;11945.402729863472;12068.818967715491;12171.878281217252;12180.57367992116;12189.78102189774;16048.438304659281;17731.29074836992;18533.443996680122;18655.440336789918;18694.439166825017;6822.522423430411;7081.787546373617;7477.439192060618;7615.504992175491;7642.847143057108;862.3316929379655;867.219502447802;903.3134817538587;951.6383774165911;1051.1905832508926;23213.76786232116;28246.85876570604;30029.849850750605;30427;31129.37741245208;940.7036783413203;1033.0495943731635;1050.7583255851198;1056.5773690523852;1093.6773651772746;14888.553343399715;14923;15013;15056;15296.541103766906;649657;657312;657959.7102014555;658020.5494862571;658495;53428.19857702137;53511.732441337546;53538.464615354125;53918;53929.73035134799;1295.7602843473987;1320.3002408723391;1343.2544937559553;1344.4890941442188;1353.2421843767522;24822.751772482043;27544.58683119795;28443.004494842582;28797.856010719814;28969;47391.52608473871;48267.75866120647;48385.75807120942;48445.27332089951;48649;587823;589522;591638;592577;593792;20786.688199676715;23692.52614947726;25106;25491.872540637178;26400.207993760218;40127.595534156164;45396;45615.77192114151;46010.76994615005;46564.53435465602;33393.33213335768;34935;37705;37985.620143799315;38863.41704874486;37471.812640937715;38060.619393805704;38126.237475249785;38302.808485957394;38603;36307.63692363042;36847.63152368442;37333;37662.05844853791;37804.05489862776;32631.836840815646;33286.66713332836;34159.804406845644;34188.31623367569;34250.14374640554;23639.409014774767;24800;25098.62352064684;25190.4200235905;25666.743332566435 05:49:30 INFO - PROCESS | 1655 | |2;jquery.html;314.85706884011034;360.9133807886107;378.2756022217454;387.6956589077575;400.43138742984956;18674.813251867476;19039.714404283946;19090.713639295423;19539.023048847554;19754.802451975476;18985.24059037637;19096.713549296735;19559.608807823835;19605.607887842234;19672.311469098586;5490.176473528983;5501.202325662786;5760.0783874580075;5769.105788602752;5791.739371728261;4881.511848815126;5561.63849349793;5738.626989245707;5748.655080695151;5756.165356023395;5450.945490545103;5710.172025056364;5713.17159011943;5715.685637289947;5723.370429252779;5623.662580245198;5786.594938354317;5799.159121927339;5805.767769289223;5808.303003639569;5422.43064478228;5611.270534830487;5611.551075913917;5617.578681598897;5656.2929633795775;12673.302968336739;12837.486500539968;12903.80644290327;12979.28613926243;21707;2066.4627196928905;2089.300084471704;2360.492494113774;2533.911313104051;2534.2903986883703;9587.472689002172;9634.855477167848;10226.386416815016;10698.51856666452;15185.240737963057;1712.1610410898695;1808.1501694203703;1829.4511646505996;1847.1503108570073;1896.7249748786348;109093;113885.72228555383;118368.40815795866;119615.40192298983;121893.99999999911;10398.168146548258;11034.779304413867;11132.332060076422;11528.308301501937;11609.187356884937;12347.938260308641;13972.371243294076;14099;14121.78817317741;14157.07978981363;8043.155468675822;8215.383846211558;8220.465669731448;8252.917470825336;8343.123971982975;6633.767818126343;7462.626868656545;7471.514351567131;8184.508929464251;8312.916870831335;3591.586967498719;4366.825326986885;4482.327650852399;4564.3838081859185;4566.292224705176;1860.5255659806662;1917.4343568647282;1920.5006698258553;1924.009135295324;1927.7012063130237;780.8321210939675;871.847426700325;872.4601079095402;964.1997142371814;1022.6778564752079;15370.077795332316;16486.83513164853;19257.903710481358;19551.924644144423;19736.70394944077;765.9540427574364;865.9004214515286;877.1491653096487;879.336101243557;934.1125930366179;9413.717588472357;10655.25413221067;10749.30129541593;10755.731106722395;10760.56957721698;558190;564544;581053;593224.0338798278;598938.0053099707;115734;120664;127882;129469.35265323613;132462;930.2790337488366;948.5257371314343;984.5175863826673;1002.4586723169552;1006.6023920551341;17811;18624.161912714233;18624.44126676202;18633.72049419287;18650.533736656693;50686.746566266935;51824.48175518347;52536.68658283573;55787.72106139443;56587;62909.68545157245;69825.30174698391;77056;77209;77444.83832742626;6438.871222575616;7591.4685971981435;7641.503302285556;7843.490173138956;8146.389020823581;8812.95593522054;11940.462679179283;12135.08986825974;12305.507779689071;12348.444320005441;9966.850497242693;10530.104941079788;10650.946745266225;10769.946150269512;10772.622958196738;8258.380621453534;9596.32825702222;9636.518174091127;9913.65302214419;9973.700788976343;8528.872066918877;10912;11100.111991040536;11299.209055366046;11431.885681143081;7705.6147192641265;7717.382609391348;7803.336716378944;7893.210678932061;7915;8991.820163596822;10242.539085741011;10582.259241852997;10974;11033.062189713964 05:49:30 INFO - PROCESS | 1655 | |3;mootools.html;1455.5706066710322;1497.3187199824079;1537.769334599809;1667.241405160652;1717.4418314047937;7502.662380192891;7756.92243077569;7983.760487185379;8441.78895527612;8467.449615774978;7959.044914610255;8234.258916697496;8268.297194738454;8395.622197001134;8415.95792021039;1782.4118041046424;2269.977300226993;2296.7358753743283;2297.6668383084443;2349.530093981202;1781.5100847266976;2316.814654827618;2375.0381095656285;2381.023780250098;2383.0586918167314;2310.7689231076924;2317.165820304689;2325.802306803924;2328.1036800831635;2353.95292094158;2070.3478404302596;2233.8324625652976;2296.0471404367167;2326.9767302326927;2327.650852372149;2106.557622899193;2207.172310383606;2331.6735657008035;2343.109618345035;2352.364861487406;7582.279683430065;7885.895974563566;8404.11756765543;8405.663773449054;8481.491110533327;4631.258998560214;4785.856424307276;4845.806167753286;5212.1921102228835;5247.422783493813;3008.262975570999;3309.851056702454;3585.94621080684;3595.7662751921043;3605.06268370223;1038;1058.730023843915;1082.53992053377;1098.1105304703135;1106.8339749037627;10675.252732308742;10898.182636302308;10958.123350131971;10962.506687199095;10995.725106872313;4010.037590978144;4338.262495375794;4581.289900065495;4673.6494762892235;4683.297505374153;9433.528323583794;9485.668001620048;9492.003339649376;9498.905010949802;9516.476593787269;5220.556252718486;5225.425203227642;5227.529522342931;5232.843014709565;5234.581233501311;4289.806958686867;4637.791299391535;4754.572088511982;4814.759262036884;4830.830920917751;3767.830447629863;3804.0489877530617;3847.9038024049623;3864.3623802072907;3935.2326296372507;1288.6585054960442;1290.8967282617368;1297.331874084847;1308.1039487950784;1310.5085592902662;741.4624397311906;845.8858054162732;882.75282920781;903.8689390038408;922.1976880114344;4713.882152946204;5121.590272778169;5844.853878653068;5931.9406805931385;6174.197354343906;742.224375527575;882.7793051737066;895.6775560798108;937.5124935033745;957.262907561174;3877.941830872539;3910.589388114207;3925.4111883217174;4016.3373043447505;4050.3924411337944;9912.702618921443;10133.695989120339;10228.488575571191;10273.332233404804;10277.229207809294;9567.425954442755;10002.399856008662;10011.949940250252;10103.646372376932;10286.897131028743;7512.511686740344;7703.229677032252;7711.768646940601;7721.806954826175;7728.381729461629;4638.8144474222;5294.020606187868;5606.607537472338;5622.353429355677;5917.467427931507;9246.214071803703;9478.52607369615;9487.905120948979;9512.09635084666;9515.286353523652;9478;9503.572339244889;9528.333016688764;9643.855342170013;9696.903030969883;6557.344265573404;6672.733090676513;6923.9653801730665;7053.506254562131;7174.569525828467;7882.763517094496;7920.247576480225;8003.559804210824;8015.679372825021;8017.679292828221;7045.365917067488;7172.0676312079095;7205.639718013974;7252.166000909963;7262.455315851226;5344.278522399426;5580.637258578179;6452.061461447557;7002.124734408199;7147.3210045045635;5477.643953143033;5979.535013921617;6466.547341686037;6651.800445986628;6693.397594216543;4187.6440502557625;4333.133373325284;4865.318855360323;4951.702897826142;5021.623378246573;4801.759912004456;4860.149473841992;4918.508149185196;4969;5122.206058061007 05:49:30 INFO - PROCESS | 1655 | |4;prototype.html;299.12058547869265;301.683232605764;306.106169983648;312.67325644701276;316.6437757522787;4303.634191093757;5194.896102077956;5324.92012619811;5451.236826844245;5517.751701173447;4311.655067594593;4410.73535587865;4532.682712210147;4566.063956888838;5319.707416092114;3543.875964341255;3888.3972984187353;3890.2025084857537;4092.4475195848477;4105.815238314268;3614.8793873899044;3780.8298626561873;3849.826757795906;4113.856015039474;4128.339465685491;3446.2952326249256;3996.740211886233;4026.1142548639255;4037.1925614876995;4056.1684854604737;3874.2638898609175;3996.7202295839447;4012.057166565855;4041.8787436376956;4049.777262250575;3493.5633045869267;3495.07380544156;3538.1331573764337;3545.592256890439;3548.946765798515;4528;4969.478204788481;4969.875753106165;5200.843974680765;5270.39390470097;3970.920581588381;4609.925533509934;4623.190941585242;4639.095376401603;4650.720956742606;5656.377798442168;5658.236138121383;5663.4336566343445;5668.914966275509;5685.772569097231;3576.910577235564;3793.905152371213;3809.2191100824266;3856.859443009129;3861.8455261789495;5036.471170527078;5392.083345831218;5685.015366932353;5928.110783382484;5967.254093238345;3314.834258287076;3674.06311390599;3868.4777555030273;3879.573246942834;3887.7278590498395;3443.3974054540854;3706.8121415582577;3868.1490072184074;3945.2109578084496;3967.4725230786357;4564.995700945787;4597.655175861823;4609.631229501631;4635.258358662598;4635.8841028973875;5217.921731174036;5704.34400043992;5720.370759216483;5720.513756330675;5727.856803579861;1235.8208059831275;1238.7522495500755;1251.468126046445;1264.5890085722176;1270.6315168601002;724.7499612633633;811.8471770086492;865.9220670139717;879.8812160358398;888.1695614600369;4663.976680116578;5072.28632681521;5164.992826398816;5180.274761533388;5211.5830733541225;4991.675541089818;5367.651102678314;5388.440490766854;5416.83749487516;5615.1750681028825;4369.885679151796;4863.884210368501;5048.747562621854;5053.7220452875445;5076;2692.8017032420776;2785.804993650425;2904.7676185905075;2922.8684709188137;2923.8391888445913;4504.977475112603;4972.676776009548;5391.406945236021;5664.26364572603;5767.145263094795;4809.509052193831;5693.943060569424;5723.627964182315;5726.427357264323;5759.185856455203;4750.168720473903;4821.734804585782;5750.396208398141;5795.507381872504;5807;4560.879191176119;4631.397918270603;5266.657667251617;5362.919556206582;5416.806955695037;4861.927071093865;5428.972855135699;5460.32444102379;5482.5888058394985;5488.4785945335125;4659.464161621457;5236.659617124875;5253.842384728464;5649.54803615718;5699.375677931936;3572.8034958077556;3679.595244523047;3683.834227459717;3709.258148370283;3713.851445942132;4524.932126018047;5172.879119560962;5242.397124330751;5344.793124125649;5620.662760234399;5176.896462070813;5393.919091213556;5431.076716958048;5432.864178395572;5448.019356515707;4186.225548273579;5110.415771110967;5183.740812959261;5262.89474210506;5358.079846483598;4203.957960420479;4828.686135401188;5407.242985981887;5442.3469183696625;5625.69058701759;5033.325672259198;5104.698301932984;5215.009148261818;5658.519025882846;5736.6558006519745;4983.177775667119;5175.068487672255;5301.655392399482;5521.420250873761;5613.968457033319 05:49:30 INFO - PROCESS | 1655 | |5;yui.html;637.4995628431682;691.8028361916855;706.6396137969634;756.2664215710762;786.5123623353521;11696.473658685358;13776.173429594231;14705.33825977831;14741.484048058306;14743.115413075224;12776.488940442394;13092.803607945889;13241.205527668322;13328.333583320844;13355.13191642545;791.7901756034655;803.8512875118092;804.9356051515865;807.7092246791151;808.6361137488154;647.9676016199195;666.6633350158163;796.4424902568196;807.7859367267679;812;853.6926706385697;866.8309679612478;867.5445391169624;867.6225841758824;867.8307729992654;852.1223140165578;859.140859140856;861.5347712235385;862.0948004595213;862.5816479007676;631.1983780598614;639.66097968077;639.9264084630281;639.9648019358933;640.2541039688757;14352.513700262109;14379.137251764823;14460.120184375965;14463;14517.346719397652;13762.65593360164;13816.723665526633;14032.719345613134;14132.929335353258;14177.787333189906;9357.906420935771;10826.945865270702;11629.767404651859;11636.069114470822;11770.705732356673;3625.184333524936;3731;3810.7141964352786;3851.595582463829;3865.768053916774;99261.0073899259;105530;105905.8818823627;106034;107618.99999999921;8120.715774947848;8748.650053997897;9113.270938324918;9763.316567840184;10457.529411176516;15659.451919182773;21399.358019259445;21446.24938127158;21608.35174944754;21634;7514.098308202942;7580.658870350848;7619.085709714759;7735.535867847947;7786.987691600056;3546.592141903714;4307.461567304087;4310.418093557392;4326.58897404746;4326.762028088486;2855.6430446194227;3259.674032596721;3403.9149021274666;3543.9291214175573;3728.5712143103347;650.4926157597082;663.3366633366634;668.7458765669014;669.3473862983552;697.0763738047108;607.6840043177523;619.170311782214;637.2926052082278;664.9667516624149;673.8281738156738;8477.23704866565;8819.691310804219;8895.777605559913;8961.103889610988;9037;528.5507318779084;553.6021545597318;614.4316507230793;668.8595394967111;682.4131247127466;3393.0838673558496;4061.4517040199785;4146.025683964266;4395.670324725658;4399.318105693623;67983.32016679869;91268.43657817083;94173.5873961891;98047.50976245073;104243;68581;91940;95073;96255;100272;59811.10283345753;66346.66826665835;89289;89591;90779.54610226907;8464.703735369232;8468.407211495136;8528.317734581095;8613.397062205586;8654.82690346202;41873;49842.75078624584;50698.98602028013;51147;52307.47692523026;54999.72500137474;56993.43006569881;60217;65015.67492162509;74834;11994.820077699016;12154.574589889311;12273.140880138304;12526.749465010467;12552;13982.300884955508;14051.648708781953;14074.718505630035;14456.494022709154;14537.538862801144;12650.240985540897;13005;13303.230288294239;13402.93298533501;13542.796858047333;12952.158109722837;13176.011799114913;13497.196205631673;13683.452661893412;13786.586402407542;12589.631891737972;12886.806697899352;13059.085863989809;13188.538401155913;13432.261225632685;9095.818083638422;10666.306690065121;10739.624113156002;11062.612808551663;11318.09455243595;8649.091845356399;9396.436213827192;10292.073713365533;11264.155188360743;11417.429128543374 05:49:30 INFO - PROCESS | 1655 | __end_tp_report 05:49:30 INFO - PROCESS | 1655 | __start_cc_report 05:49:30 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,376 05:49:30 INFO - PROCESS | 1655 | __end_cc_report 05:49:30 INFO - PROCESS | 1655 | __startTimestamp1479563370463__endTimestamp 05:49:30 INFO - PROCESS | 1655 | 05:49:30 INFO - PROCESS | 1655 | ------- Summary: start ------- 05:49:30 INFO - PROCESS | 1655 | Number of tests: 6 05:49:30 INFO - PROCESS | 1655 | 05:49:30 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5743.65 Median:5992.43 stddev:667.11 (11.1%) stddev-sans-first:629.18 05:49:30 INFO - PROCESS | 1655 | Values: 2844.1 3380.0 3386.9 3389.9 3456.2 5676.4 5722.0 5774.2 5778.7 5844.1 5692.1 5750.4 5788.7 5865.6 5902.4 5195.7 5215.5 5215.5 5217.3 5365.0 5345.0 5366.2 5367.3 5376.2 5396.1 5141.1 5295.5 5307.2 5321.5 5351.1 5064.3 5153.0 5187.5 5217.2 5243.0 5127.7 5128.0 5133.5 5193.1 5272.1 5149.9 5387.8 5555.8 5810.9 5850.9 4639.0 5096.9 5419.6 6284.0 6306.0 4843.7 5239.8 5707.5 6214.2 6280.0 5730.1 5935.7 5956.6 5985.4 5992.2 5363.9 6027.8 6303.9 6363.0 6406.4 6131.7 6151.6 6174.4 6186.0 6193.1 6158.6 6307.6 6316.5 6327.6 6371.4 6072.7 6146.0 6159.7 6211.8 6248.2 6200.8 6215.2 6327.8 6329.5 6371.9 4996.1 5005.3 5021.7 5030.6 5067.0 4467.7 4498.0 4502.2 4512.6 4569.2 5784.0 5840.0 5869.7 5879.6 6019.8 6222.8 6273.9 6287.0 6303.7 6324.4 5992.4 6016.2 6021.6 6040.0 6074.8 4393.9 5241.0 5509.5 5531.4 5558.6 6218.4 6232.3 6295.4 6313.1 6362.6 5830.8 5840.9 6254.8 6294.6 6327.7 6355.0 6359.6 6444.5 6451.3 6460.9 6142.2 6164.3 6173.7 6193.1 6275.6 6183.1 6279.1 6293.6 6310.2 6310.8 6043.1 6078.5 6122.1 6234.8 6260.5 6137.8 6200.8 6248.9 6270.6 6288.8 5838.2 5885.9 6058.1 6145.0 6148.0 5697.8 5740.0 6194.7 6221.8 6245.5 6052.8 6108.6 6110.5 6116.2 6131.2 05:49:30 INFO - PROCESS | 1655 | 05:49:30 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:68519.01 Median:38459.52 stddev:153451.77 (399.0%) stddev-sans-first:153797.14 05:49:30 INFO - PROCESS | 1655 | Values: 454.0 483.7 488.7 495.5 502.9 34930.7 39151.4 39255.8 41125.6 41142.6 34342.3 40655.0 41070.0 41094.2 41130.2 8051.7 9274.1 9392.9 9490.3 9514.3 7937.8 9285.3 9330.3 9366.1 9447.1 9302.4 9342.4 9412.2 9454.4 9482.4 9335.5 9357.7 9379.7 9445.3 9461.7 9216.4 9236.3 9314.2 9318.0 9338.9 38231.0 38318.0 38601.0 38765.8 38968.2 3336.2 3346.7 3506.9 3529.2 3651.2 29280.0 29539.6 29680.9 29850.7 30272.7 3043.1 3155.9 3198.4 3213.5 3274.0 447917.0 449918.0 453235.0 455306.0 455401.0 56939.0 57332.0 57511.0 57548.4 57638.7 33511.8 34451.3 34980.1 35070.8 61612.7 15580.1 15638.0 15776.2 15784.8 15802.7 11945.4 12068.8 12171.9 12180.6 12189.8 16048.4 17731.3 18533.4 18655.4 18694.4 6822.5 7081.8 7477.4 7615.5 7642.8 862.3 867.2 903.3 951.6 1051.2 23213.8 28246.9 30029.8 30427.0 31129.4 940.7 1033.0 1050.8 1056.6 1093.7 14888.6 14923.0 15013.0 15056.0 15296.5 649657.0 657312.0 657959.7 658020.5 658495.0 53428.2 53511.7 53538.5 53918.0 53929.7 1295.8 1320.3 1343.3 1344.5 1353.2 24822.8 27544.6 28443.0 28797.9 28969.0 47391.5 48267.8 48385.8 48445.3 48649.0 587823.0 589522.0 591638.0 592577.0 593792.0 20786.7 23692.5 25106.0 25491.9 26400.2 40127.6 45396.0 45615.8 46010.8 46564.5 33393.3 34935.0 37705.0 37985.6 38863.4 37471.8 38060.6 38126.2 38302.8 38603.0 36307.6 36847.6 37333.0 37662.1 37804.1 32631.8 33286.7 34159.8 34188.3 34250.1 23639.4 24800.0 25098.6 25190.4 25666.7 05:49:30 INFO - PROCESS | 1655 | 05:49:30 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:33582.04 Median:394.06 stddev:96856.20 (24578.8%) stddev-sans-first:97095.70 05:49:30 INFO - PROCESS | 1655 | Values: 314.9 360.9 378.3 387.7 400.4 18674.8 19039.7 19090.7 19539.0 19754.8 18985.2 19096.7 19559.6 19605.6 19672.3 5490.2 5501.2 5760.1 5769.1 5791.7 4881.5 5561.6 5738.6 5748.7 5756.2 5450.9 5710.2 5713.2 5715.7 5723.4 5623.7 5786.6 5799.2 5805.8 5808.3 5422.4 5611.3 5611.6 5617.6 5656.3 12673.3 12837.5 12903.8 12979.3 21707.0 2066.5 2089.3 2360.5 2533.9 2534.3 9587.5 9634.9 10226.4 10698.5 15185.2 1712.2 1808.2 1829.5 1847.2 1896.7 109093.0 113885.7 118368.4 119615.4 121894.0 10398.2 11034.8 11132.3 11528.3 11609.2 12347.9 13972.4 14099.0 14121.8 14157.1 8043.2 8215.4 8220.5 8252.9 8343.1 6633.8 7462.6 7471.5 8184.5 8312.9 3591.6 4366.8 4482.3 4564.4 4566.3 1860.5 1917.4 1920.5 1924.0 1927.7 780.8 871.8 872.5 964.2 1022.7 15370.1 16486.8 19257.9 19551.9 19736.7 766.0 865.9 877.1 879.3 934.1 9413.7 10655.3 10749.3 10755.7 10760.6 558190.0 564544.0 581053.0 593224.0 598938.0 115734.0 120664.0 127882.0 129469.4 132462.0 930.3 948.5 984.5 1002.5 1006.6 17811.0 18624.2 18624.4 18633.7 18650.5 50686.7 51824.5 52536.7 55787.7 56587.0 62909.7 69825.3 77056.0 77209.0 77444.8 6438.9 7591.5 7641.5 7843.5 8146.4 8813.0 11940.5 12135.1 12305.5 12348.4 9966.9 10530.1 10650.9 10769.9 10772.6 8258.4 9596.3 9636.5 9913.7 9973.7 8528.9 10912.0 11100.1 11299.2 11431.9 7705.6 7717.4 7803.3 7893.2 7915.0 8991.8 10242.5 10582.3 10974.0 11033.1 05:49:30 INFO - PROCESS | 1655 | 05:49:30 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5398.52 Median:4995.31 stddev:2987.03 (59.8%) stddev-sans-first:2980.71 05:49:30 INFO - PROCESS | 1655 | Values: 1455.6 1497.3 1537.8 1667.2 1717.4 7502.7 7756.9 7983.8 8441.8 8467.4 7959.0 8234.3 8268.3 8395.6 8416.0 1782.4 2270.0 2296.7 2297.7 2349.5 1781.5 2316.8 2375.0 2381.0 2383.1 2310.8 2317.2 2325.8 2328.1 2354.0 2070.3 2233.8 2296.0 2327.0 2327.7 2106.6 2207.2 2331.7 2343.1 2352.4 7582.3 7885.9 8404.1 8405.7 8481.5 4631.3 4785.9 4845.8 5212.2 5247.4 3008.3 3309.9 3585.9 3595.8 3605.1 1038.0 1058.7 1082.5 1098.1 1106.8 10675.3 10898.2 10958.1 10962.5 10995.7 4010.0 4338.3 4581.3 4673.6 4683.3 9433.5 9485.7 9492.0 9498.9 9516.5 5220.6 5225.4 5227.5 5232.8 5234.6 4289.8 4637.8 4754.6 4814.8 4830.8 3767.8 3804.0 3847.9 3864.4 3935.2 1288.7 1290.9 1297.3 1308.1 1310.5 741.5 845.9 882.8 903.9 922.2 4713.9 5121.6 5844.9 5931.9 6174.2 742.2 882.8 895.7 937.5 957.3 3877.9 3910.6 3925.4 4016.3 4050.4 9912.7 10133.7 10228.5 10273.3 10277.2 9567.4 10002.4 10011.9 10103.6 10286.9 7512.5 7703.2 7711.8 7721.8 7728.4 4638.8 5294.0 5606.6 5622.4 5917.5 9246.2 9478.5 9487.9 9512.1 9515.3 9478.0 9503.6 9528.3 9643.9 9696.9 6557.3 6672.7 6924.0 7053.5 7174.6 7882.8 7920.2 8003.6 8015.7 8017.7 7045.4 7172.1 7205.6 7252.2 7262.5 5344.3 5580.6 6452.1 7002.1 7147.3 5477.6 5979.5 6466.5 6651.8 6693.4 4187.6 4333.1 4865.3 4951.7 5021.6 4801.8 4860.1 4918.5 4969.0 5122.2 05:49:30 INFO - PROCESS | 1655 | 05:49:30 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4409.14 Median:4916.68 stddev:1340.74 (27.3%) stddev-sans-first:1308.53 05:49:30 INFO - PROCESS | 1655 | Values: 299.1 301.7 306.1 312.7 316.6 4303.6 5194.9 5324.9 5451.2 5517.8 4311.7 4410.7 4532.7 4566.1 5319.7 3543.9 3888.4 3890.2 4092.4 4105.8 3614.9 3780.8 3849.8 4113.9 4128.3 3446.3 3996.7 4026.1 4037.2 4056.2 3874.3 3996.7 4012.1 4041.9 4049.8 3493.6 3495.1 3538.1 3545.6 3548.9 4528.0 4969.5 4969.9 5200.8 5270.4 3970.9 4609.9 4623.2 4639.1 4650.7 5656.4 5658.2 5663.4 5668.9 5685.8 3576.9 3793.9 3809.2 3856.9 3861.8 5036.5 5392.1 5685.0 5928.1 5967.3 3314.8 3674.1 3868.5 3879.6 3887.7 3443.4 3706.8 3868.1 3945.2 3967.5 4565.0 4597.7 4609.6 4635.3 4635.9 5217.9 5704.3 5720.4 5720.5 5727.9 1235.8 1238.8 1251.5 1264.6 1270.6 724.7 811.8 865.9 879.9 888.2 4664.0 5072.3 5165.0 5180.3 5211.6 4991.7 5367.7 5388.4 5416.8 5615.2 4369.9 4863.9 5048.7 5053.7 5076.0 2692.8 2785.8 2904.8 2922.9 2923.8 4505.0 4972.7 5391.4 5664.3 5767.1 4809.5 5693.9 5723.6 5726.4 5759.2 4750.2 4821.7 5750.4 5795.5 5807.0 4560.9 4631.4 5266.7 5362.9 5416.8 4861.9 5429.0 5460.3 5482.6 5488.5 4659.5 5236.7 5253.8 5649.5 5699.4 3572.8 3679.6 3683.8 3709.3 3713.9 4524.9 5172.9 5242.4 5344.8 5620.7 5176.9 5393.9 5431.1 5432.9 5448.0 4186.2 5110.4 5183.7 5262.9 5358.1 4204.0 4828.7 5407.2 5442.3 5625.7 5033.3 5104.7 5215.0 5658.5 5736.7 4983.2 5175.1 5301.7 5521.4 5614.0 05:49:30 INFO - PROCESS | 1655 | 05:49:30 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:19645.02 Median:50922.99 stddev:28690.02 (56.3%) stddev-sans-first:28735.00 05:49:30 INFO - PROCESS | 1655 | Values: 637.5 691.8 706.6 756.3 786.5 11696.5 13776.2 14705.3 14741.5 14743.1 12776.5 13092.8 13241.2 13328.3 13355.1 791.8 803.9 804.9 807.7 808.6 648.0 666.7 796.4 807.8 812.0 853.7 866.8 867.5 867.6 867.8 852.1 859.1 861.5 862.1 862.6 631.2 639.7 639.9 640.0 640.3 14352.5 14379.1 14460.1 14463.0 14517.3 13762.7 13816.7 14032.7 14132.9 14177.8 9357.9 10826.9 11629.8 11636.1 11770.7 3625.2 3731.0 3810.7 3851.6 3865.8 99261.0 105530.0 105905.9 106034.0 107619.0 8120.7 8748.7 9113.3 9763.3 10457.5 15659.5 21399.4 21446.2 21608.4 21634.0 7514.1 7580.7 7619.1 7735.5 7787.0 3546.6 4307.5 4310.4 4326.6 4326.8 2855.6 3259.7 3403.9 3543.9 3728.6 650.5 663.3 668.7 669.3 697.1 607.7 619.2 637.3 665.0 673.8 8477.2 8819.7 8895.8 8961.1 9037.0 528.6 553.6 614.4 668.9 682.4 3393.1 4061.5 4146.0 4395.7 4399.3 67983.3 91268.4 94173.6 98047.5 104243.0 68581.0 91940.0 95073.0 96255.0 100272.0 59811.1 66346.7 89289.0 89591.0 90779.5 8464.7 8468.4 8528.3 8613.4 8654.8 41873.0 49842.8 50699.0 51147.0 52307.5 54999.7 56993.4 60217.0 65015.7 74834.0 11994.8 12154.6 12273.1 12526.7 12552.0 13982.3 14051.6 14074.7 14456.5 14537.5 12650.2 13005.0 13303.2 13402.9 13542.8 12952.2 13176.0 13497.2 13683.5 13786.6 12589.6 12886.8 13059.1 13188.5 13432.3 9095.8 10666.3 10739.6 11062.6 11318.1 8649.1 9396.4 10292.1 11264.2 11417.4 05:49:30 INFO - PROCESS | 1655 | -------- Summary: end -------- 05:49:30 INFO - PROCESS | 1655 | 05:49:31 INFO - TEST-INFO | 1655: exit 0 05:49:31 INFO - TEST-OK | dromaeo_css | took 1117860ms 05:49:31 INFO - TEST-START | kraken 05:49:31 INFO - operating with platform_type : mac_ 05:49:31 INFO - Initialising browser for kraken test... 05:49:31 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmplycAx5/profile http://localhost:49242/getInfo.html) 05:49:34 INFO - PROCESS | 1662 | 2016-11-19 05:49:34.325 plugin-container[1664:11540] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 05:49:34 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:49:34 INFO - PROCESS | 1662 | 2016-11-19 05:49:34.327 plugin-container[1664:11540] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 05:49:34 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:49:34 INFO - PROCESS | 1662 | 2016-11-19 05:49:34.327 plugin-container[1664:11540] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:49:34 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 05:49:34 INFO - PROCESS | 1662 | colorDepth:24 05:49:34 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 05:49:34 INFO - PROCESS | 1662 | __metrics 05:49:34 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 05:49:35 INFO - TEST-INFO | 1662: exit 0 05:49:35 INFO - Browser initialized. 05:49:35 INFO - Running cycle 1/1 for kraken test... 05:49:35 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmplycAx5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:49:38 INFO - PROCESS | 1665 | 2016-11-19 05:49:38.124 plugin-container[1666:11659] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x894b, name = 'com.apple.tsm.portname' 05:49:38 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:49:38 INFO - PROCESS | 1665 | 2016-11-19 05:49:38.125 plugin-container[1666:11659] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f17, name = 'com.apple.CFPasteboardClient' 05:49:38 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:49:38 INFO - PROCESS | 1665 | 2016-11-19 05:49:38.125 plugin-container[1666:11659] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:49:47 INFO - PROCESS | 1665 | RSS: Main: 243068928 05:49:47 INFO - PROCESS | 1665 | 05:49:50 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 05:49:51 INFO - PROCESS | 1665 | RSS: Main: 255053824 05:49:51 INFO - PROCESS | 1665 | 05:49:54 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 05:49:55 INFO - PROCESS | 1665 | RSS: Main: 262598656 05:49:55 INFO - PROCESS | 1665 | 05:49:58 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 05:49:59 INFO - PROCESS | 1665 | RSS: Main: 265453568 05:49:59 INFO - PROCESS | 1665 | 05:50:01 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 05:50:01 INFO - PROCESS | 1665 | RSS: Main: 267575296 05:50:01 INFO - PROCESS | 1665 | 05:50:03 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:50:04 INFO - PROCESS | 1665 | RSS: Main: 267915264 05:50:04 INFO - PROCESS | 1665 | 05:50:07 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 05:50:08 INFO - PROCESS | 1665 | RSS: Main: 273424384 05:50:08 INFO - PROCESS | 1665 | 05:50:11 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 05:50:11 INFO - PROCESS | 1665 | RSS: Main: 273395712 05:50:11 INFO - PROCESS | 1665 | 05:50:15 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 05:50:15 INFO - PROCESS | 1665 | RSS: Main: 273604608 05:50:15 INFO - PROCESS | 1665 | 05:50:17 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:50:17 INFO - PROCESS | 1665 | RSS: Main: 272793600 05:50:17 INFO - PROCESS | 1665 | 05:50:19 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:50:20 INFO - PROCESS | 1665 | RSS: Main: 272871424 05:50:20 INFO - PROCESS | 1665 | 05:50:22 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:50:23 INFO - PROCESS | 1665 | RSS: Main: 273408000 05:50:23 INFO - PROCESS | 1665 | 05:50:26 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:50:27 INFO - PROCESS | 1665 | RSS: Main: 273203200 05:50:27 INFO - PROCESS | 1665 | 05:50:30 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:50:30 INFO - PROCESS | 1665 | RSS: Main: 273031168 05:50:30 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 05:50:32 INFO - PROCESS | 1665 | RSS: Main: 274997248 05:50:32 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | __start_tp_report 05:50:32 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 05:50:32 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 05:50:32 INFO - PROCESS | 1665 | |i|pagename|runs| 05:50:32 INFO - PROCESS | 1665 | |0;ai-astar;106;117;117;118;116;118;117;121;116;118 05:50:32 INFO - PROCESS | 1665 | |1;audio-beat-detection;152;116;116;135;108;139;142;145;140;113 05:50:32 INFO - PROCESS | 1665 | |2;audio-dft;268;275;194;199;200;245;257;199;196;193 05:50:32 INFO - PROCESS | 1665 | |3;audio-fft;82;61;85;110;87;69;62;62;80;63 05:50:32 INFO - PROCESS | 1665 | |4;audio-oscillator;88;70;91;97;98;81;85;72;71;88 05:50:32 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;91;89;91;103;98;133;137;89;91;87 05:50:32 INFO - PROCESS | 1665 | |6;imaging-darkroom;96;126;153;97;96;153;123;96;97;97 05:50:32 INFO - PROCESS | 1665 | |7;imaging-desaturate;92;98;78;146;145;90;95;91;125;144 05:50:32 INFO - PROCESS | 1665 | |8;json-parse-financial;57;57;56;58;56;60;59;57;58;59 05:50:32 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;69;79;74;86;78;73;73;106;64;101 05:50:32 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;87;86;91;85;90;91;88;90;92;91 05:50:32 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;191;163;168;161;162;163;157;153;221;168 05:50:32 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;177;180;180;180;176;178;176;177;181;184 05:50:32 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;71;69;70;72;70;67;70;68;68;71 05:50:32 INFO - PROCESS | 1665 | __end_tp_report 05:50:32 INFO - PROCESS | 1665 | __start_cc_report 05:50:32 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,952 05:50:32 INFO - PROCESS | 1665 | __end_cc_report 05:50:32 INFO - PROCESS | 1665 | __startTimestamp1479563432887__endTimestamp 05:50:32 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | ------- Summary: start ------- 05:50:32 INFO - PROCESS | 1665 | Number of tests: 14 05:50:32 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:116.40 Median:117.50 stddev:3.92 (3.3%) stddev-sans-first:1.51 05:50:32 INFO - PROCESS | 1665 | Values: 106.0 117.0 117.0 118.0 116.0 118.0 117.0 121.0 116.0 118.0 05:50:32 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:130.60 Median:139.50 stddev:15.71 (11.3%) stddev-sans-first:14.63 05:50:32 INFO - PROCESS | 1665 | Values: 152.0 116.0 116.0 135.0 108.0 139.0 142.0 145.0 140.0 113.0 05:50:32 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:222.60 Median:222.50 stddev:34.19 (15.4%) stddev-sans-first:32.07 05:50:32 INFO - PROCESS | 1665 | Values: 268.0 275.0 194.0 199.0 200.0 245.0 257.0 199.0 196.0 193.0 05:50:32 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:76.10 Median:74.50 stddev:15.79 (21.2%) stddev-sans-first:16.61 05:50:32 INFO - PROCESS | 1665 | Values: 82.0 61.0 85.0 110.0 87.0 69.0 62.0 62.0 80.0 63.0 05:50:32 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:84.10 Median:88.00 stddev:10.35 (11.8%) stddev-sans-first:10.89 05:50:32 INFO - PROCESS | 1665 | Values: 88.0 70.0 91.0 97.0 98.0 81.0 85.0 72.0 71.0 88.0 05:50:32 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:100.90 Median:90.00 stddev:18.61 (20.7%) stddev-sans-first:19.39 05:50:32 INFO - PROCESS | 1665 | Values: 91.0 89.0 91.0 103.0 98.0 133.0 137.0 89.0 91.0 87.0 05:50:32 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:113.40 Median:96.00 stddev:23.81 (24.8%) stddev-sans-first:24.41 05:50:32 INFO - PROCESS | 1665 | Values: 96.0 126.0 153.0 97.0 96.0 153.0 123.0 96.0 97.0 97.0 05:50:32 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:110.40 Median:90.50 stddev:26.62 (29.4%) stddev-sans-first:27.39 05:50:32 INFO - PROCESS | 1665 | Values: 92.0 98.0 78.0 146.0 145.0 90.0 95.0 91.0 125.0 144.0 05:50:32 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:58.00 stddev:1.34 (2.3%) stddev-sans-first:1.39 05:50:32 INFO - PROCESS | 1665 | Values: 57.0 57.0 56.0 58.0 56.0 60.0 59.0 57.0 58.0 59.0 05:50:32 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:80.30 Median:73.50 stddev:13.61 (18.5%) stddev-sans-first:13.81 05:50:32 INFO - PROCESS | 1665 | Values: 69.0 79.0 74.0 86.0 78.0 73.0 73.0 106.0 64.0 101.0 05:50:32 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:89.10 Median:90.50 stddev:2.42 (2.7%) stddev-sans-first:2.45 05:50:32 INFO - PROCESS | 1665 | Values: 87.0 86.0 91.0 85.0 90.0 91.0 88.0 90.0 92.0 91.0 05:50:32 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:170.70 Median:165.50 stddev:20.40 (12.3%) stddev-sans-first:20.27 05:50:32 INFO - PROCESS | 1665 | Values: 191.0 163.0 168.0 161.0 162.0 163.0 157.0 153.0 221.0 168.0 05:50:32 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.90 Median:180.00 stddev:2.56 (1.4%) stddev-sans-first:2.62 05:50:32 INFO - PROCESS | 1665 | Values: 177.0 180.0 180.0 180.0 176.0 178.0 176.0 177.0 181.0 184.0 05:50:32 INFO - PROCESS | 1665 | 05:50:32 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:70.00 stddev:1.58 (2.3%) stddev-sans-first:1.59 05:50:32 INFO - PROCESS | 1665 | Values: 71.0 69.0 70.0 72.0 70.0 67.0 70.0 68.0 68.0 71.0 05:50:32 INFO - PROCESS | 1665 | -------- Summary: end -------- 05:50:32 INFO - PROCESS | 1665 | 05:50:33 INFO - TEST-INFO | 1665: exit 0 05:50:33 INFO - TEST-OK | kraken | took 62833ms 05:50:33 INFO - SUITE-END | took 1180s 05:50:34 INFO - Completed test suite (00:19:40) 05:50:34 INFO - javascript benchmark 05:50:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7040.931680893055, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2844.1325395754293, 3379.969109421627, 3386.949195762064, 3389.9491507627376, 3456.222349971256, 5676.375598684152, 5722.027255366587, 5774.191613174159, 5778.653280803154, 5844.064949608063, 5692.0608099663505, 5750.412314458489, 5788.652680839153, 5865.589408741379, 5902.409759024106, 5195.740212989345, 5215.478452154774, 5215.530602245797, 5217.34783152106, 5365.03429382711, 5344.973275133619, 5366.168243922199, 5367.329083864517, 5376.247325374451, 5396.082665946778, 5141.126008578551, 5295.4969277918535, 5307.203919412081, 5321.547668448186, 5351.09031464652, 5064.265681476165, 5152.969406118791, 5187.48125187482, 5217.217417387422, 5242.973785131088, 5127.743612819344, 5128.0, 5133.486651334837, 5193.091209038441, 5272.05103081449, 5149.871253218699, 5387.757550910179, 5555.777768889239, 5810.941890581083, 5850.882982340372, 4638.9536104638855, 5096.898062038776, 5419.620626556142, 6283.968580157116, 6306.0, 4843.66094373394, 5239.79040838366, 5707.486326230659, 6214.192155019864, 6280.026595877646, 5730.054541000688, 5935.673537955454, 5956.612820166675, 5985.371535988746, 5992.161097446361, 5363.927214557104, 6027.758889644453, 6303.873922521524, 6363.045543168561, 6406.391392817675, 6131.651934473265, 6151.6001459904965, 6174.351693072163, 6186.0, 6193.102000210034, 6158.599691020069, 6307.621542707452, 6316.526260530479, 6327.588706734047, 6371.39471750183, 6072.726727297282, 6146.0, 6159.722812473449, 6211.751529938751, 6248.187735594343, 6200.7519699211525, 6215.192025036807, 6327.810165695037, 6329.525285603599, 6371.872562548723, 4996.075725990703, 5005.34930459038, 5021.698698078127, 5030.572401345853, 5067.037262920034, 4467.723162324327, 4497.977510112429, 4502.212112880233, 4512.571305725951, 4569.1547063793305, 5783.971080144573, 5839.970800146057, 5869.73586188614, 5879.559033072451, 6019.849503762441, 6222.751089956441, 6273.905891411632, 6286.968565157145, 6303.716332765037, 6324.430801227912, 5992.43071908168, 6016.217891674054, 6021.638701677914, 6039.973204555236, 6074.787382441682, 4393.868183954486, 5241.0042092002395, 5509.531689806411, 5531.419200983919, 5558.610897237155, 6218.378162183745, 6232.345603711727, 6295.401936815995, 6313.084602732486, 6362.618242905441, 5830.795922142875, 5840.853978650568, 6254.781082662267, 6294.559380843297, 6327.651979141191, 6355.0, 6359.618422894641, 6444.516661250331, 6451.2903580607035, 6460.935390646034, 6142.232220972379, 6164.260288765377, 6173.6913154343, 6193.102000209854, 6275.560710750204, 6183.103449999815, 6279.1209230708655, 6293.559450838397, 6310.242770867525, 6310.810675679737, 6043.063325184604, 6078.483328917092, 6122.062734707273, 6234.781782637586, 6260.499160067275, 6137.775380989496, 6200.7519699211525, 6248.875022499434, 6270.623762574261, 6288.811335659938, 5838.211841401357, 5885.852853678693, 6058.0610005449225, 6145.033589251397, 6147.9855823788575, 5697.829065128053, 5739.966805974965, 6194.659293738888, 6221.751129954932, 6245.531585131042, 6052.788152414644, 6108.633481991094, 6110.450059494667, 6116.204893363834, 6131.233595800525], "value": 5701.723275567547, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [454.00799253630817, 483.6662702735115, 488.71654440424544, 495.4525249599193, 502.87930896584805, 34930.65069349306, 39151.41272880905, 39255.82232533025, 41125.560605378814, 41142.58857411432, 34342.31315373691, 40655.0, 41070.0, 41094.17811643765, 41130.177396452054, 8051.677932882707, 9274.118958698913, 9392.87285525738, 9490.288228382864, 9514.286428517855, 7937.801554961115, 9285.25717942563, 9330.300227482932, 9366.063393660648, 9447.149756521912, 9302.441853488779, 9342.439453632769, 9412.2470202384, 9454.43273403598, 9482.43105413674, 9335.486548239878, 9357.719268421924, 9379.671711490066, 9445.338826282163, 9461.716148515556, 9216.354855160142, 9236.261099112055, 9314.161725444676, 9318.0, 9338.859917101248, 38231.0, 38318.0, 38601.03497412559, 38765.80617096925, 38968.220635587415, 3336.199312165087, 3346.6987971082476, 3506.8597256109724, 3529.205928666055, 3651.1602331463646, 29279.975200867866, 29539.556906646416, 29680.8515957421, 29850.701492985227, 30272.697273027206, 3043.08707387785, 3155.936881262362, 3198.424283628946, 3213.5340375645633, 3273.950890736617, 447917.0, 449918.0, 453235.0, 455306.0, 455400.9999999967, 56939.0, 57332.0, 57511.0, 57548.424515754305, 57638.7118064407, 33511.83244083813, 34451.31097378038, 34980.125496862274, 35070.82464587661, 61612.69193654093, 15580.065196088271, 15638.0, 15776.211189440482, 15784.842151578567, 15802.683946321009, 11945.402729863472, 12068.818967715491, 12171.878281217252, 12180.57367992116, 12189.78102189774, 16048.438304659281, 17731.29074836992, 18533.443996680122, 18655.440336789918, 18694.439166825017, 6822.522423430411, 7081.787546373617, 7477.439192060618, 7615.504992175491, 7642.847143057108, 862.3316929379655, 867.219502447802, 903.3134817538587, 951.6383774165911, 1051.1905832508926, 23213.76786232116, 28246.85876570604, 30029.849850750605, 30427.0, 31129.37741245208, 940.7036783413203, 1033.0495943731635, 1050.7583255851198, 1056.5773690523852, 1093.6773651772746, 14888.553343399715, 14923.0, 15013.0, 15056.0, 15296.541103766906, 649657.0, 657312.0, 657959.7102014555, 658020.5494862571, 658495.0, 53428.19857702137, 53511.732441337546, 53538.464615354125, 53918.0, 53929.73035134799, 1295.7602843473987, 1320.3002408723391, 1343.2544937559553, 1344.4890941442188, 1353.2421843767522, 24822.751772482043, 27544.58683119795, 28443.004494842582, 28797.856010719814, 28969.0, 47391.52608473871, 48267.75866120647, 48385.75807120942, 48445.27332089951, 48649.0, 587823.0, 589522.0, 591638.0, 592577.0, 593792.0, 20786.688199676715, 23692.52614947726, 25106.0, 25491.872540637178, 26400.207993760218, 40127.595534156164, 45396.0, 45615.77192114151, 46010.76994615005, 46564.53435465602, 33393.33213335768, 34935.0, 37705.0, 37985.620143799315, 38863.41704874486, 37471.812640937715, 38060.619393805704, 38126.237475249785, 38302.808485957394, 38603.0, 36307.63692363042, 36847.63152368442, 37333.0, 37662.05844853791, 37804.05489862776, 32631.836840815646, 33286.66713332836, 34159.804406845644, 34188.31623367569, 34250.14374640554, 23639.409014774767, 24800.0, 25098.62352064684, 25190.4200235905, 25666.743332566435], "value": 19062.285678635228, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.85706884011034, 360.9133807886107, 378.2756022217454, 387.6956589077575, 400.43138742984956, 18674.813251867476, 19039.714404283946, 19090.713639295423, 19539.023048847554, 19754.802451975476, 18985.24059037637, 19096.713549296735, 19559.608807823835, 19605.607887842234, 19672.311469098586, 5490.176473528983, 5501.202325662786, 5760.0783874580075, 5769.105788602752, 5791.739371728261, 4881.511848815126, 5561.63849349793, 5738.626989245707, 5748.655080695151, 5756.165356023395, 5450.945490545103, 5710.172025056364, 5713.17159011943, 5715.685637289947, 5723.370429252779, 5623.662580245198, 5786.594938354317, 5799.159121927339, 5805.767769289223, 5808.303003639569, 5422.43064478228, 5611.270534830487, 5611.551075913917, 5617.578681598897, 5656.2929633795775, 12673.302968336739, 12837.486500539968, 12903.80644290327, 12979.28613926243, 21707.0, 2066.4627196928905, 2089.300084471704, 2360.492494113774, 2533.911313104051, 2534.2903986883703, 9587.472689002172, 9634.855477167848, 10226.386416815016, 10698.51856666452, 15185.240737963057, 1712.1610410898695, 1808.1501694203703, 1829.4511646505996, 1847.1503108570073, 1896.7249748786348, 109093.0, 113885.72228555383, 118368.40815795866, 119615.40192298983, 121893.99999999911, 10398.168146548258, 11034.779304413867, 11132.332060076422, 11528.308301501937, 11609.187356884937, 12347.938260308641, 13972.371243294076, 14099.0, 14121.78817317741, 14157.07978981363, 8043.155468675822, 8215.383846211558, 8220.465669731448, 8252.917470825336, 8343.123971982975, 6633.767818126343, 7462.626868656545, 7471.514351567131, 8184.508929464251, 8312.916870831335, 3591.586967498719, 4366.825326986885, 4482.327650852399, 4564.3838081859185, 4566.292224705176, 1860.5255659806662, 1917.4343568647282, 1920.5006698258553, 1924.009135295324, 1927.7012063130237, 780.8321210939675, 871.847426700325, 872.4601079095402, 964.1997142371814, 1022.6778564752079, 15370.077795332316, 16486.83513164853, 19257.903710481358, 19551.924644144423, 19736.70394944077, 765.9540427574364, 865.9004214515286, 877.1491653096487, 879.336101243557, 934.1125930366179, 9413.717588472357, 10655.25413221067, 10749.30129541593, 10755.731106722395, 10760.56957721698, 558190.0, 564544.0, 581053.0, 593224.0338798278, 598938.0053099707, 115734.0, 120664.0, 127882.0, 129469.35265323613, 132462.0, 930.2790337488366, 948.5257371314343, 984.5175863826673, 1002.4586723169552, 1006.6023920551341, 17811.0, 18624.161912714233, 18624.44126676202, 18633.72049419287, 18650.533736656693, 50686.746566266935, 51824.48175518347, 52536.68658283573, 55787.72106139443, 56587.0, 62909.68545157245, 69825.30174698391, 77056.0, 77209.0, 77444.83832742626, 6438.871222575616, 7591.4685971981435, 7641.503302285556, 7843.490173138956, 8146.389020823581, 8812.95593522054, 11940.462679179283, 12135.08986825974, 12305.507779689071, 12348.444320005441, 9966.850497242693, 10530.104941079788, 10650.946745266225, 10769.946150269512, 10772.622958196738, 8258.380621453534, 9596.32825702222, 9636.518174091127, 9913.65302214419, 9973.700788976343, 8528.872066918877, 10912.0, 11100.111991040536, 11299.209055366046, 11431.885681143081, 7705.6147192641265, 7717.382609391348, 7803.336716378944, 7893.210678932061, 7915.0, 8991.820163596822, 10242.539085741011, 10582.259241852997, 10974.0, 11033.062189713964], "value": 9052.29408727001, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1455.5706066710322, 1497.3187199824079, 1537.769334599809, 1667.241405160652, 1717.4418314047937, 7502.662380192891, 7756.92243077569, 7983.760487185379, 8441.78895527612, 8467.449615774978, 7959.044914610255, 8234.258916697496, 8268.297194738454, 8395.622197001134, 8415.95792021039, 1782.4118041046424, 2269.977300226993, 2296.7358753743283, 2297.6668383084443, 2349.530093981202, 1781.5100847266976, 2316.814654827618, 2375.0381095656285, 2381.023780250098, 2383.0586918167314, 2310.7689231076924, 2317.165820304689, 2325.802306803924, 2328.1036800831635, 2353.95292094158, 2070.3478404302596, 2233.8324625652976, 2296.0471404367167, 2326.9767302326927, 2327.650852372149, 2106.557622899193, 2207.172310383606, 2331.6735657008035, 2343.109618345035, 2352.364861487406, 7582.279683430065, 7885.895974563566, 8404.11756765543, 8405.663773449054, 8481.491110533327, 4631.258998560214, 4785.856424307276, 4845.806167753286, 5212.1921102228835, 5247.422783493813, 3008.262975570999, 3309.851056702454, 3585.94621080684, 3595.7662751921043, 3605.06268370223, 1038.0, 1058.730023843915, 1082.53992053377, 1098.1105304703135, 1106.8339749037627, 10675.252732308742, 10898.182636302308, 10958.123350131971, 10962.506687199095, 10995.725106872313, 4010.037590978144, 4338.262495375794, 4581.289900065495, 4673.6494762892235, 4683.297505374153, 9433.528323583794, 9485.668001620048, 9492.003339649376, 9498.905010949802, 9516.476593787269, 5220.556252718486, 5225.425203227642, 5227.529522342931, 5232.843014709565, 5234.581233501311, 4289.806958686867, 4637.791299391535, 4754.572088511982, 4814.759262036884, 4830.830920917751, 3767.830447629863, 3804.0489877530617, 3847.9038024049623, 3864.3623802072907, 3935.2326296372507, 1288.6585054960442, 1290.8967282617368, 1297.331874084847, 1308.1039487950784, 1310.5085592902662, 741.4624397311906, 845.8858054162732, 882.75282920781, 903.8689390038408, 922.1976880114344, 4713.882152946204, 5121.590272778169, 5844.853878653068, 5931.9406805931385, 6174.197354343906, 742.224375527575, 882.7793051737066, 895.6775560798108, 937.5124935033745, 957.262907561174, 3877.941830872539, 3910.589388114207, 3925.4111883217174, 4016.3373043447505, 4050.3924411337944, 9912.702618921443, 10133.695989120339, 10228.488575571191, 10273.332233404804, 10277.229207809294, 9567.425954442755, 10002.399856008662, 10011.949940250252, 10103.646372376932, 10286.897131028743, 7512.511686740344, 7703.229677032252, 7711.768646940601, 7721.806954826175, 7728.381729461629, 4638.8144474222, 5294.020606187868, 5606.607537472338, 5622.353429355677, 5917.467427931507, 9246.214071803703, 9478.52607369615, 9487.905120948979, 9512.09635084666, 9515.286353523652, 9478.0, 9503.572339244889, 9528.333016688764, 9643.855342170013, 9696.903030969883, 6557.344265573404, 6672.733090676513, 6923.9653801730665, 7053.506254562131, 7174.569525828467, 7882.763517094496, 7920.247576480225, 8003.559804210824, 8015.679372825021, 8017.679292828221, 7045.365917067488, 7172.0676312079095, 7205.639718013974, 7252.166000909963, 7262.455315851226, 5344.278522399426, 5580.637258578179, 6452.061461447557, 7002.124734408199, 7147.3210045045635, 5477.643953143033, 5979.535013921617, 6466.547341686037, 6651.800445986628, 6693.397594216543, 4187.6440502557625, 4333.133373325284, 4865.318855360323, 4951.702897826142, 5021.623378246573, 4801.759912004456, 4860.149473841992, 4918.508149185196, 4969.0, 5122.206058061007], "value": 4372.889227610168, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.12058547869265, 301.683232605764, 306.106169983648, 312.67325644701276, 316.6437757522787, 4303.634191093757, 5194.896102077956, 5324.92012619811, 5451.236826844245, 5517.751701173447, 4311.655067594593, 4410.73535587865, 4532.682712210147, 4566.063956888838, 5319.707416092114, 3543.875964341255, 3888.3972984187353, 3890.2025084857537, 4092.4475195848477, 4105.815238314268, 3614.8793873899044, 3780.8298626561873, 3849.826757795906, 4113.856015039474, 4128.339465685491, 3446.2952326249256, 3996.740211886233, 4026.1142548639255, 4037.1925614876995, 4056.1684854604737, 3874.2638898609175, 3996.7202295839447, 4012.057166565855, 4041.8787436376956, 4049.777262250575, 3493.5633045869267, 3495.07380544156, 3538.1331573764337, 3545.592256890439, 3548.946765798515, 4528.0, 4969.478204788481, 4969.875753106165, 5200.843974680765, 5270.39390470097, 3970.920581588381, 4609.925533509934, 4623.190941585242, 4639.095376401603, 4650.720956742606, 5656.377798442168, 5658.236138121383, 5663.4336566343445, 5668.914966275509, 5685.772569097231, 3576.910577235564, 3793.905152371213, 3809.2191100824266, 3856.859443009129, 3861.8455261789495, 5036.471170527078, 5392.083345831218, 5685.015366932353, 5928.110783382484, 5967.254093238345, 3314.834258287076, 3674.06311390599, 3868.4777555030273, 3879.573246942834, 3887.7278590498395, 3443.3974054540854, 3706.8121415582577, 3868.1490072184074, 3945.2109578084496, 3967.4725230786357, 4564.995700945787, 4597.655175861823, 4609.631229501631, 4635.258358662598, 4635.8841028973875, 5217.921731174036, 5704.34400043992, 5720.370759216483, 5720.513756330675, 5727.856803579861, 1235.8208059831275, 1238.7522495500755, 1251.468126046445, 1264.5890085722176, 1270.6315168601002, 724.7499612633633, 811.8471770086492, 865.9220670139717, 879.8812160358398, 888.1695614600369, 4663.976680116578, 5072.28632681521, 5164.992826398816, 5180.274761533388, 5211.5830733541225, 4991.675541089818, 5367.651102678314, 5388.440490766854, 5416.83749487516, 5615.1750681028825, 4369.885679151796, 4863.884210368501, 5048.747562621854, 5053.7220452875445, 5076.0, 2692.8017032420776, 2785.804993650425, 2904.7676185905075, 2922.8684709188137, 2923.8391888445913, 4504.977475112603, 4972.676776009548, 5391.406945236021, 5664.26364572603, 5767.145263094795, 4809.509052193831, 5693.943060569424, 5723.627964182315, 5726.427357264323, 5759.185856455203, 4750.168720473903, 4821.734804585782, 5750.396208398141, 5795.507381872504, 5807.0, 4560.879191176119, 4631.397918270603, 5266.657667251617, 5362.919556206582, 5416.806955695037, 4861.927071093865, 5428.972855135699, 5460.32444102379, 5482.5888058394985, 5488.4785945335125, 4659.464161621457, 5236.659617124875, 5253.842384728464, 5649.54803615718, 5699.375677931936, 3572.8034958077556, 3679.595244523047, 3683.834227459717, 3709.258148370283, 3713.851445942132, 4524.932126018047, 5172.879119560962, 5242.397124330751, 5344.793124125649, 5620.662760234399, 5176.896462070813, 5393.919091213556, 5431.076716958048, 5432.864178395572, 5448.019356515707, 4186.225548273579, 5110.415771110967, 5183.740812959261, 5262.89474210506, 5358.079846483598, 4203.957960420479, 4828.686135401188, 5407.242985981887, 5442.3469183696625, 5625.69058701759, 5033.325672259198, 5104.698301932984, 5215.009148261818, 5658.519025882846, 5736.6558006519745, 4983.177775667119, 5175.068487672255, 5301.655392399482, 5521.420250873761, 5613.968457033319], "value": 3981.338498696831, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.4995628431682, 691.8028361916855, 706.6396137969634, 756.2664215710762, 786.5123623353521, 11696.473658685358, 13776.173429594231, 14705.33825977831, 14741.484048058306, 14743.115413075224, 12776.488940442394, 13092.803607945889, 13241.205527668322, 13328.333583320844, 13355.13191642545, 791.7901756034655, 803.8512875118092, 804.9356051515865, 807.7092246791151, 808.6361137488154, 647.9676016199195, 666.6633350158163, 796.4424902568196, 807.7859367267679, 812.0, 853.6926706385697, 866.8309679612478, 867.5445391169624, 867.6225841758824, 867.8307729992654, 852.1223140165578, 859.140859140856, 861.5347712235385, 862.0948004595213, 862.5816479007676, 631.1983780598614, 639.66097968077, 639.9264084630281, 639.9648019358933, 640.2541039688757, 14352.513700262109, 14379.137251764823, 14460.120184375965, 14463.0, 14517.346719397652, 13762.65593360164, 13816.723665526633, 14032.719345613134, 14132.929335353258, 14177.787333189906, 9357.906420935771, 10826.945865270702, 11629.767404651859, 11636.069114470822, 11770.705732356673, 3625.184333524936, 3731.0, 3810.7141964352786, 3851.595582463829, 3865.768053916774, 99261.0073899259, 105530.0, 105905.8818823627, 106034.0, 107618.99999999921, 8120.715774947848, 8748.650053997897, 9113.270938324918, 9763.316567840184, 10457.529411176516, 15659.451919182773, 21399.358019259445, 21446.24938127158, 21608.35174944754, 21634.0, 7514.098308202942, 7580.658870350848, 7619.085709714759, 7735.535867847947, 7786.987691600056, 3546.592141903714, 4307.461567304087, 4310.418093557392, 4326.58897404746, 4326.762028088486, 2855.6430446194227, 3259.674032596721, 3403.9149021274666, 3543.9291214175573, 3728.5712143103347, 650.4926157597082, 663.3366633366634, 668.7458765669014, 669.3473862983552, 697.0763738047108, 607.6840043177523, 619.170311782214, 637.2926052082278, 664.9667516624149, 673.8281738156738, 8477.23704866565, 8819.691310804219, 8895.777605559913, 8961.103889610988, 9037.0, 528.5507318779084, 553.6021545597318, 614.4316507230793, 668.8595394967111, 682.4131247127466, 3393.0838673558496, 4061.4517040199785, 4146.025683964266, 4395.670324725658, 4399.318105693623, 67983.32016679869, 91268.43657817083, 94173.5873961891, 98047.50976245073, 104243.0, 68581.0, 91940.0, 95073.0, 96255.0, 100272.0, 59811.10283345753, 66346.66826665835, 89289.0, 89591.0, 90779.54610226907, 8464.703735369232, 8468.407211495136, 8528.317734581095, 8613.397062205586, 8654.82690346202, 41873.0, 49842.75078624584, 50698.98602028013, 51147.0, 52307.47692523026, 54999.72500137474, 56993.43006569881, 60217.0, 65015.67492162509, 74834.0, 11994.820077699016, 12154.574589889311, 12273.140880138304, 12526.749465010467, 12552.0, 13982.300884955508, 14051.648708781953, 14074.718505630035, 14456.494022709154, 14537.538862801144, 12650.240985540897, 13005.0, 13303.230288294239, 13402.93298533501, 13542.796858047333, 12952.158109722837, 13176.011799114913, 13497.196205631673, 13683.452661893412, 13786.586402407542, 12589.631891737972, 12886.806697899352, 13059.085863989809, 13188.538401155913, 13432.261225632685, 9095.818083638422, 10666.306690065121, 10739.624113156002, 11062.612808551663, 11318.09455243595, 8649.091845356399, 9396.436213827192, 10292.073713365533, 11264.155188360743, 11417.429128543374], "value": 7112.08845459616, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1600.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 117.0, 118.0, 116.0, 118.0, 117.0, 121.0, 116.0, 118.0], "value": 116.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 116.0, 116.0, 135.0, 108.0, 139.0, 142.0, 145.0, 140.0, 113.0], "value": 130.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [268.0, 275.0, 194.0, 199.0, 200.0, 245.0, 257.0, 199.0, 196.0, 193.0], "value": 222.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 61.0, 85.0, 110.0, 87.0, 69.0, 62.0, 62.0, 80.0, 63.0], "value": 76.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 91.0, 97.0, 98.0, 81.0, 85.0, 72.0, 71.0, 88.0], "value": 84.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 91.0, 103.0, 98.0, 133.0, 137.0, 89.0, 91.0, 87.0], "value": 100.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 126.0, 153.0, 97.0, 96.0, 153.0, 123.0, 96.0, 97.0, 97.0], "value": 113.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 98.0, 78.0, 146.0, 145.0, 90.0, 95.0, 91.0, 125.0, 144.0], "value": 110.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 56.0, 58.0, 56.0, 60.0, 59.0, 57.0, 58.0, 59.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 74.0, 86.0, 78.0, 73.0, 73.0, 106.0, 64.0, 101.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 91.0, 85.0, 90.0, 91.0, 88.0, 90.0, 92.0, 91.0], "value": 89.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 163.0, 168.0, 161.0, 162.0, 163.0, 157.0, 153.0, 221.0, 168.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 180.0, 180.0, 180.0, 176.0, 178.0, 176.0, 177.0, 181.0, 184.0], "value": 178.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 70.0, 72.0, 70.0, 67.0, 70.0, 68.0, 68.0, 71.0], "value": 69.6, "unit": "score"}]}]} 05:50:34 INFO - Return code: 0 05:50:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:50:34 ERROR - # TBPL SUCCESS # 05:50:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:50:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:50:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:50:34 INFO - 2016-11-19 05:50:34.209 screenresolution[1670:12044] starting screenresolution argv=screenresolution get 05:50:34 INFO - 2016-11-19 05:50:34.212 screenresolution[1670:12044] Display 0: 1600x1200x32@60 05:50:34 INFO - 2016-11-19 05:50:34.219 screenresolution[1671:12047] starting screenresolution argv=screenresolution list 05:50:34 INFO - Available Modes on Display 0 05:50:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:50:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:50:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:50:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:50:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:50:34 INFO - Intel Iris: 05:50:34 INFO - Chipset Model: Intel Iris 05:50:34 INFO - Type: GPU 05:50:34 INFO - Bus: Built-In 05:50:34 INFO - VRAM (Dynamic, Max): 1536 MB 05:50:34 INFO - Vendor: Intel (0x8086) 05:50:34 INFO - Device ID: 0x0a2e 05:50:34 INFO - Revision ID: 0x0009 05:50:34 INFO - Displays: 05:50:34 INFO - Display: 05:50:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:50:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:50:34 INFO - Display Serial Number: 1600x1200 60 05:50:34 INFO - Main Display: Yes 05:50:34 INFO - Mirror: Off 05:50:34 INFO - Online: Yes 05:50:34 INFO - Rotation: Supported 05:50:34 INFO - Return code: 0 05:50:34 INFO - Running post-action listener: _resource_record_post_action 05:50:34 INFO - [mozharness: 2016-11-19 13:50:34.465356Z] Finished run-tests step (success) 05:50:34 INFO - Running post-run listener: _resource_record_post_run 05:50:34 INFO - Total resource usage - Wall time: 1214s; CPU: 55.0%; Read bytes: 32959488; Write bytes: 697995264; Read time: 306; Write time: 2001 05:50:34 INFO - TinderboxPrint: CPU usage
54.5% 05:50:34 INFO - TinderboxPrint: I/O read bytes / time
32,959,488 / 306 05:50:34 INFO - TinderboxPrint: I/O write bytes / time
697,995,264 / 2,001 05:50:34 INFO - TinderboxPrint: CPU idle
2,207.9 (45.5%) 05:50:34 INFO - TinderboxPrint: CPU system
982.4 (20.2%) 05:50:34 INFO - TinderboxPrint: CPU user
1,667.5 (34.3%) 05:50:34 INFO - TinderboxPrint: Swap in / out
414,093,312 / 0 05:50:34 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 384672256; Write bytes: 387293696; Read time: 28912; Write time: 732 05:50:34 INFO - run-tests - Wall time: 1182s; CPU: 55.0%; Read bytes: 30841856; Write bytes: 309517824; Read time: 282; Write time: 1265 05:50:34 INFO - Running post-run listener: _upload_blobber_files 05:50:34 INFO - Blob upload gear active. 05:50:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:50:34 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:50:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:50:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:50:35 INFO - (blobuploader) - INFO - Open directory for files ... 05:50:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:50:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:50:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:36 INFO - (blobuploader) - INFO - Done attempting. 05:50:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:50:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:50:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:37 INFO - (blobuploader) - INFO - Done attempting. 05:50:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:50:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:50:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:40 INFO - (blobuploader) - INFO - Done attempting. 05:50:40 INFO - (blobuploader) - INFO - Iteration through files over. 05:50:40 INFO - Return code: 0 05:50:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:50:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:50:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12fab7fd05c39dbd2e7ff842c9a311116797f07a15d510f25373e7554921233d7fba24e9cbfac9717657344b160062c52ca28ae837a9d92fe6940df7d33797bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7aea40f5165f6daa34c2b5da838263ac12993b5a5aecda6acfa2d38603b61acbc672c1e7f8d8e8415d0c12ff650395567d25a5f84bf743d3c67663024f1b0b3e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d58633aa02fea2568b2d19ae405e196d0b4e75ab8836173f51b1c6dc4d1a7958773f47ea3e3248a88ebe4ec1bfbde6952ab643870f97c2ed8d6990971a7e51d2"} 05:50:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:50:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:50:40 INFO - Contents: 05:50:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12fab7fd05c39dbd2e7ff842c9a311116797f07a15d510f25373e7554921233d7fba24e9cbfac9717657344b160062c52ca28ae837a9d92fe6940df7d33797bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7aea40f5165f6daa34c2b5da838263ac12993b5a5aecda6acfa2d38603b61acbc672c1e7f8d8e8415d0c12ff650395567d25a5f84bf743d3c67663024f1b0b3e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d58633aa02fea2568b2d19ae405e196d0b4e75ab8836173f51b1c6dc4d1a7958773f47ea3e3248a88ebe4ec1bfbde6952ab643870f97c2ed8d6990971a7e51d2"} 05:50:40 INFO - Running post-run listener: copy_logs_to_upload_dir 05:50:40 INFO - Copying logs to upload dir... 05:50:40 INFO - mkdir: /builds/slave/test/build/upload/logs 05:50:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.395687 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-19 05:50:40.081048) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-19 05:50:40.084713) ========= 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.nzSYYzlRUq/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.YY8NucGLkK/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12fab7fd05c39dbd2e7ff842c9a311116797f07a15d510f25373e7554921233d7fba24e9cbfac9717657344b160062c52ca28ae837a9d92fe6940df7d33797bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7aea40f5165f6daa34c2b5da838263ac12993b5a5aecda6acfa2d38603b61acbc672c1e7f8d8e8415d0c12ff650395567d25a5f84bf743d3c67663024f1b0b3e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d58633aa02fea2568b2d19ae405e196d0b4e75ab8836173f51b1c6dc4d1a7958773f47ea3e3248a88ebe4ec1bfbde6952ab643870f97c2ed8d6990971a7e51d2"} build_url:https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011306 build_url: 'https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12fab7fd05c39dbd2e7ff842c9a311116797f07a15d510f25373e7554921233d7fba24e9cbfac9717657344b160062c52ca28ae837a9d92fe6940df7d33797bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7aea40f5165f6daa34c2b5da838263ac12993b5a5aecda6acfa2d38603b61acbc672c1e7f8d8e8415d0c12ff650395567d25a5f84bf743d3c67663024f1b0b3e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d58633aa02fea2568b2d19ae405e196d0b4e75ab8836173f51b1c6dc4d1a7958773f47ea3e3248a88ebe4ec1bfbde6952ab643870f97c2ed8d6990971a7e51d2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-19 05:50:40.118065) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:50:40.118447) ========= 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.nzSYYzlRUq/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.YY8NucGLkK/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.005118 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:50:40.168591) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-19 05:50:40.168972) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-19 05:50:40.169393) ========= ========= Total master_lag: 0.17 =========