builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0114 starttime: 1485986121.54 results: success (0) buildid: 20170201114508 builduid: b2c64dbead90424085f4591e704b1aa6 revision: a15e503db4d1d7261680fb497af39b65babff013 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-01 13:55:21.538627) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-01 13:55:21.546394) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-01 13:55:21.546712) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-01 13:55:21.586882) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 13:55:21.587211) ========= 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.01AjV2DU28/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.BiWfWMpNue/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-01 13:55:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 79.0M=0s 2017-02-01 13:55:21 (79.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.088912 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 13:55:21.700972) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 13:55:21.701308) ========= 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.01AjV2DU28/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.BiWfWMpNue/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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.026376 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 13:55:21.749937) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-01 13:55:21.750259) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a15e503db4d1d7261680fb497af39b65babff013 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a15e503db4d1d7261680fb497af39b65babff013 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.01AjV2DU28/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.BiWfWMpNue/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-01 13:55:21,853 truncating revision to first 12 chars 2017-02-01 13:55:21,853 Setting DEBUG logging. 2017-02-01 13:55:21,853 attempt 1/10 2017-02-01 13:55:21,853 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a15e503db4d1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-01 13:55:23,121 unpacking tar archive at: mozilla-beta-a15e503db4d1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.544769 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-01 13:55:23.319161) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-01 13:55:23.319497) ========= script_repo_revision: a15e503db4d1d7261680fb497af39b65babff013 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-01 13:55:23.319901) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-01 13:55:23.320175) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-01 13:55:23.340116) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-02-01 13:55:23.340599) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.01AjV2DU28/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.BiWfWMpNue/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 13:55:23 INFO - MultiFileLogger online at 20170201 13:55:23 in /builds/slave/test 13:55:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 13:55:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:55:23 INFO - {'append_to_log': False, 13:55:23 INFO - 'base_work_dir': '/builds/slave/test', 13:55:23 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:55:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:55:23 INFO - 'branch': 'Mozilla-Beta', 13:55:23 INFO - 'buildbot_json_path': 'buildprops.json', 13:55:23 INFO - 'config_files': ('talos/mac_config.py',), 13:55:23 INFO - 'default_actions': ('clobber', 13:55:23 INFO - 'read-buildbot-config', 13:55:23 INFO - 'download-and-extract', 13:55:23 INFO - 'populate-webroot', 13:55:23 INFO - 'create-virtualenv', 13:55:23 INFO - 'install', 13:55:23 INFO - 'run-tests'), 13:55:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:55:23 INFO - 'download_minidump_stackwalk': True, 13:55:23 INFO - 'download_symbols': 'ondemand', 13:55:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:55:23 INFO - 'tooltool.py': '/tools/tooltool.py', 13:55:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:55:23 INFO - '/tools/misc-python/virtualenv.py')}, 13:55:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:55:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:55:23 INFO - 'installer_path': 'installer.exe', 13:55:23 INFO - 'log_level': 'info', 13:55:23 INFO - 'log_name': 'talos', 13:55:23 INFO - 'log_to_console': True, 13:55:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:55:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:55:23 INFO - 'opt_config_files': (), 13:55:23 INFO - 'pip_index': False, 13:55:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:55:23 INFO - 'cmd': ('bash', 13:55:23 INFO - '-c', 13:55:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:55:23 INFO - 'enabled': True, 13:55:23 INFO - 'halt_on_failure': False, 13:55:23 INFO - 'name': 'check_screen_resolution'},), 13:55:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:55:23 INFO - 'cmd': ('bash', 13:55:23 INFO - '-c', 13:55:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:55:23 INFO - 'enabled': True, 13:55:23 INFO - 'halt_on_failure': False, 13:55:23 INFO - 'name': 'check_screen_resolution'},), 13:55:23 INFO - 'run_cmd_checks_enabled': True, 13:55:23 INFO - 'sps_profile': False, 13:55:23 INFO - 'sps_profile_interval': 0, 13:55:23 INFO - 'suite': 'dromaeojs-e10s', 13:55:23 INFO - 'system_bits': '32', 13:55:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:55:23 INFO - 'title': 't-yosemite-r7-0114', 13:55:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:55:23 INFO - 'use_talos_json': True, 13:55:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:55:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:55:23 INFO - 'work_dir': 'build'} 13:55:23 INFO - [mozharness: 2017-02-01 21:55:23.559232Z] Running clobber step. 13:55:23 INFO - Running pre-action listener: _resource_record_pre_action 13:55:23 INFO - Running main action method: clobber 13:55:23 INFO - rmtree: /builds/slave/test/build 13:55:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:55:25 INFO - Running post-action listener: _resource_record_post_action 13:55:25 INFO - [mozharness: 2017-02-01 21:55:25.913698Z] Finished clobber step (success) 13:55:25 INFO - [mozharness: 2017-02-01 21:55:25.913852Z] Running read-buildbot-config step. 13:55:25 INFO - Running pre-action listener: _resource_record_pre_action 13:55:25 INFO - Running main action method: read_buildbot_config 13:55:25 INFO - Using buildbot properties: 13:55:25 INFO - { 13:55:25 INFO - "project": "", 13:55:25 INFO - "product": "firefox", 13:55:25 INFO - "script_repo_revision": "production", 13:55:25 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 13:55:25 INFO - "repository": "", 13:55:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 13:55:25 INFO - "buildid": "20170201114508", 13:55:25 INFO - "pgo_build": "False", 13:55:25 INFO - "basedir": "/builds/slave/test", 13:55:25 INFO - "buildnumber": 55, 13:55:25 INFO - "slavename": "t-yosemite-r7-0114", 13:55:25 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 13:55:25 INFO - "platform": "yosemite_r7", 13:55:25 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 13:55:25 INFO - "branch": "mozilla-beta", 13:55:25 INFO - "repo_path": "releases/mozilla-beta", 13:55:25 INFO - "revision": "a15e503db4d1d7261680fb497af39b65babff013", 13:55:25 INFO - "stage_platform": "macosx64", 13:55:25 INFO - "builduid": "b2c64dbead90424085f4591e704b1aa6", 13:55:25 INFO - "slavebuilddir": "test" 13:55:25 INFO - } 13:55:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 13:55:25 INFO - Running post-action listener: _resource_record_post_action 13:55:25 INFO - [mozharness: 2017-02-01 21:55:25.917311Z] Finished read-buildbot-config step (success) 13:55:25 INFO - [mozharness: 2017-02-01 21:55:25.917436Z] Running download-and-extract step. 13:55:25 INFO - Running pre-action listener: _resource_record_pre_action 13:55:25 INFO - Running main action method: download_and_extract 13:55:25 INFO - mkdir: /builds/slave/test/build/tests 13:55: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')]} 13:55:25 INFO - trying https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 13:55:25 INFO - Downloading https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 13:55:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 13:55:27 INFO - Downloaded 1165 bytes. 13:55:27 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 13:55:27 INFO - Using the following test package requirements: 13:55:27 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 13:55:27 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 13:55:27 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 13:55:27 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 13:55:27 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 13:55:27 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 13:55:27 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 13:55:27 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 13:55:27 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 13:55:27 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 13:55:27 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 13:55:27 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 13:55:27 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 13:55:27 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 13:55:27 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 13:55:27 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 13:55:27 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 13:55:27 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 13:55:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 13:55:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 13:55:27 INFO - Fetch https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 13:55:27 INFO - Http code: 200 13:55:27 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 13:55:27 INFO - x-amz-cf-id: OrfNXc0rbntPjU7TVZBk3WLYjNi6peQZbq6JOoqh4jCqw_KTzGAj8A== 13:55:27 INFO - x-amz-version-id: xVHJCSyQ58.x14NTlCjZY5SebPYycstW 13:55:28 INFO - Expected file size: 16090523 13:55:28 INFO - Obtained file size: 16090523 13:55:34 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 13:55:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 13:55:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 13:55:34 INFO - Fetch https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 13:55:35 INFO - Http code: 200 13:55:35 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 13:55:35 INFO - x-amz-cf-id: ZqmJqdZ8mhM84PX1UVSXVHw-lBjaFgSqIUKZWWUwI19GvdAopXmCFA== 13:55:35 INFO - x-amz-version-id: xVHJCSyQ58.x14NTlCjZY5SebPYycstW 13:55:35 INFO - Expected file size: 16090523 13:55:35 INFO - Obtained file size: 16090523 13:55:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 13:55:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 13:55:41 INFO - Fetch https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 13:55:43 INFO - Http code: 200 13:55:43 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 13:55:43 INFO - x-amz-cf-id: aEM1jTWxZZuBtF4kC5eRZACCCdwkXNBxZFYoFRijN3CYMkrskhK63w== 13:55:43 INFO - x-amz-version-id: 0YSC8KdHdcWNntplmmuK4VwovrsL1TWu 13:55:43 INFO - Expected file size: 14403005 13:55:43 INFO - Obtained file size: 14403005 13:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:44 INFO - trying https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 13:55:44 INFO - Downloading https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 13:55:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 13:55:46 INFO - Downloaded 82137790 bytes. 13:55:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 13:55:46 INFO - mkdir: /builds/slave/test/properties 13:55:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:55:46 INFO - Writing to file /builds/slave/test/properties/build_url 13:55:46 INFO - Contents: 13:55:46 INFO - build_url:https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 13:55:47 INFO - Running post-action listener: _resource_record_post_action 13:55:47 INFO - Running post-action listener: set_extra_try_arguments 13:55:47 INFO - [mozharness: 2017-02-01 21:55:47.637351Z] Finished download-and-extract step (success) 13:55:47 INFO - [mozharness: 2017-02-01 21:55:47.637467Z] Running populate-webroot step. 13:55:47 INFO - Running pre-action listener: _resource_record_pre_action 13:55:47 INFO - Running main action method: populate_webroot 13:55:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:55:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:55:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:55:47 INFO - '--tppagecycles', 13:55:47 INFO - '7'], 13:55:47 INFO - 'tests': ['tsvgm']}}, 13:55:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:55:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:55:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:55:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:55:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:55:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:55:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:55:47 INFO - 'talos_options': ['--disable-e10s'], 13:55:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:55:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:55:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:55:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:55:47 INFO - 'talos_options': ['--disable-e10s'], 13:55:47 INFO - 'tests': ['damp', 'tps']}, 13:55:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:55:47 INFO - 'tests': ['damp', 'tps']}, 13:55:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:55:47 INFO - 'tests': ['dromaeo_dom']}, 13:55:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:55:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:55:47 INFO - 'tests': ['basic_compositor_video']}, 13:55:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:55:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:55:47 INFO - 'tests': ['a11yr', 13:55:47 INFO - 'ts_paint', 13:55:47 INFO - 'tpaint', 13:55:47 INFO - 'sessionrestore', 13:55:47 INFO - 'sessionrestore_no_auto_restore', 13:55:47 INFO - 'tabpaint']}, 13:55:47 INFO - 'other-e10s': {'tests': ['a11yr', 13:55:47 INFO - 'ts_paint', 13:55:47 INFO - 'tpaint', 13:55:47 INFO - 'sessionrestore', 13:55:47 INFO - 'sessionrestore_no_auto_restore', 13:55:47 INFO - 'tabpaint']}, 13:55:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:55:47 INFO - 'ts_paint', 13:55:47 INFO - 'tpaint', 13:55:47 INFO - 'sessionrestore', 13:55:47 INFO - 'sessionrestore_no_auto_restore', 13:55:47 INFO - 'tabpaint']}, 13:55:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:55:47 INFO - 'ts_paint', 13:55:47 INFO - 'tpaint', 13:55:47 INFO - 'sessionrestore', 13:55:47 INFO - 'sessionrestore_no_auto_restore', 13:55:47 INFO - 'tabpaint']}, 13:55:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:55:47 INFO - 'tests': ['a11yr', 13:55:47 INFO - 'ts_paint', 13:55:47 INFO - 'tpaint', 13:55:47 INFO - 'sessionrestore', 13:55:47 INFO - 'sessionrestore_no_auto_restore', 13:55:47 INFO - 'tabpaint']}, 13:55:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:55:47 INFO - 'tests': ['a11yr', 13:55:47 INFO - 'ts_paint', 13:55:47 INFO - 'tpaint', 13:55:47 INFO - 'sessionrestore', 13:55:47 INFO - 'sessionrestore_no_auto_restore', 13:55:47 INFO - 'tabpaint']}, 13:55:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:55:47 INFO - 'tests': ['tsvgx', 13:55:47 INFO - 'tsvgr_opacity', 13:55:47 INFO - 'tart', 13:55:47 INFO - 'tscrollx', 13:55:47 INFO - 'cart']}, 13:55:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:55:47 INFO - 'tsvgr_opacity', 13:55:47 INFO - 'tart', 13:55:47 INFO - 'tscrollx', 13:55:47 INFO - 'cart']}, 13:55:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:55:47 INFO - 'talos_options': ['--disable-e10s'], 13:55:47 INFO - 'tests': ['tp5o']}, 13:55:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:55:47 INFO - 'tests': ['tp5o']}, 13:55:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:55:47 INFO - 'talos_options': ['--disable-e10s', 13:55:47 INFO - '--xperf_path', 13:55:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:55:47 INFO - 'tests': ['tp5n']}, 13:55:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:55:47 INFO - 'talos_options': ['--xperf_path', 13:55:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:55:47 INFO - 'tests': ['tp5n']}}, 13:55:47 INFO - 'talos.zip': {'path': '', 13:55:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:55:47 INFO - Running post-action listener: _resource_record_post_action 13:55:47 INFO - [mozharness: 2017-02-01 21:55:47.656295Z] Finished populate-webroot step (success) 13:55:47 INFO - [mozharness: 2017-02-01 21:55:47.656400Z] Running create-virtualenv step. 13:55:47 INFO - Running pre-action listener: _resource_record_pre_action 13:55:47 INFO - Running main action method: create_virtualenv 13:55:47 INFO - Creating virtualenv /builds/slave/test/build/venv 13:55: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 13:55:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:55:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:55:47 INFO - Using real prefix '/tools/python27' 13:55:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:55:48 INFO - Installing distribute.............................................................................................................................................................................................done. 13:55:51 INFO - Installing pip.................done. 13:55:51 INFO - Return code: 0 13:55:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:55:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:55:51 INFO - Reading from file tmpfile_stdout 13:55:51 INFO - Output received: 13:55: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) 13:55:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:55: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')]} 13:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55: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')]} 13:55:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55: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 0x106270490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa33246b360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106084d30>, '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 0x105eeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607cb20>, '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.01AjV2DU28/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.BiWfWMpNue/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 13:55: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 13:55: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 13:55:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01AjV2DU28/Render', 13:55:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:55:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:55:51 INFO - 'HOME': '/Users/cltbld', 13:55:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:55:51 INFO - 'LOGNAME': 'cltbld', 13:55:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:51 INFO - 'MOZ_NO_REMOTE': '1', 13:55:51 INFO - 'NO_EM_RESTART': '1', 13:55:51 INFO - 'PAGER': '/bin/cat', 13:55:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:55:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:51 INFO - 'PWD': '/builds/slave/test', 13:55:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:55:51 INFO - 'SHELL': '/bin/bash', 13:55:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiWfWMpNue/Listeners', 13:55:51 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 13:55:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:55:51 INFO - 'USER': 'cltbld', 13:55:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:55:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:55:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:51 INFO - 'XPC_FLAGS': '0x0', 13:55:51 INFO - 'XPC_SERVICE_NAME': '0', 13:55:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:55:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55: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 13:55:51 INFO - Cleaning up... 13:55:51 INFO - Return code: 0 13:55:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:55: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')]} 13:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55: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')]} 13:55:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55: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 0x106270490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa33246b360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106084d30>, '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 0x105eeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607cb20>, '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.01AjV2DU28/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.BiWfWMpNue/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 13:55: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 13:55: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 13:55:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01AjV2DU28/Render', 13:55:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:55:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:55:51 INFO - 'HOME': '/Users/cltbld', 13:55:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:55:51 INFO - 'LOGNAME': 'cltbld', 13:55:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:51 INFO - 'MOZ_NO_REMOTE': '1', 13:55:51 INFO - 'NO_EM_RESTART': '1', 13:55:51 INFO - 'PAGER': '/bin/cat', 13:55:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:55:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:51 INFO - 'PWD': '/builds/slave/test', 13:55:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:55:51 INFO - 'SHELL': '/bin/bash', 13:55:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiWfWMpNue/Listeners', 13:55:51 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 13:55:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:55:51 INFO - 'USER': 'cltbld', 13:55:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:55:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:55:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:51 INFO - 'XPC_FLAGS': '0x0', 13:55:51 INFO - 'XPC_SERVICE_NAME': '0', 13:55:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55:52 INFO - Downloading/unpacking psutil>=3.1.1 13:55: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 13:55: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 13:55: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 13:55: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 13:55: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 13:55: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 13:55:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:55:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:55:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:55:55 INFO - Installing collected packages: psutil 13:55:55 INFO - Running setup.py install for psutil 13:55:55 INFO - building 'psutil._psutil_osx' extension 13:55:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:55: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 13:55: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 13:55: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 13:55:56 INFO - building 'psutil._psutil_posix' extension 13:55: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 13:55:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:55:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:55:56 INFO - ^ 13:55:56 INFO - 1 warning generated. 13:55:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 13:55:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:55:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:55:56 INFO - Successfully installed psutil 13:55:56 INFO - Cleaning up... 13:55:56 INFO - Return code: 0 13:55:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:55:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106270490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa33246b360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106084d30>, '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 0x105eeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607cb20>, '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.01AjV2DU28/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.BiWfWMpNue/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 13:55:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:55:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:55:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01AjV2DU28/Render', 13:55:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:55:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:55:56 INFO - 'HOME': '/Users/cltbld', 13:55:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:55:56 INFO - 'LOGNAME': 'cltbld', 13:55:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:56 INFO - 'MOZ_NO_REMOTE': '1', 13:55:56 INFO - 'NO_EM_RESTART': '1', 13:55:56 INFO - 'PAGER': '/bin/cat', 13:55:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:55:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:56 INFO - 'PWD': '/builds/slave/test', 13:55:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:55:56 INFO - 'SHELL': '/bin/bash', 13:55:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiWfWMpNue/Listeners', 13:55:56 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 13:55:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:55:56 INFO - 'USER': 'cltbld', 13:55:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:55:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:55:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:56 INFO - 'XPC_FLAGS': '0x0', 13:55:56 INFO - 'XPC_SERVICE_NAME': '0', 13:55:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:55: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 13:55: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 13:55: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 13:55: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 13:55: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 13:55: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 13:55:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:55:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:55:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:55:59 INFO - Installing collected packages: mozsystemmonitor 13:55:59 INFO - Running setup.py install for mozsystemmonitor 13:56:00 INFO - Successfully installed mozsystemmonitor 13:56:00 INFO - Cleaning up... 13:56:00 INFO - Return code: 0 13:56:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:56: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')]} 13:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56: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')]} 13:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56: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 0x106270490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa33246b360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106084d30>, '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 0x105eeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607cb20>, '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.01AjV2DU28/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.BiWfWMpNue/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 13:56: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 13:56: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 13:56:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01AjV2DU28/Render', 13:56:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:56:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:56:00 INFO - 'HOME': '/Users/cltbld', 13:56:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:56:00 INFO - 'LOGNAME': 'cltbld', 13:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:00 INFO - 'MOZ_NO_REMOTE': '1', 13:56:00 INFO - 'NO_EM_RESTART': '1', 13:56:00 INFO - 'PAGER': '/bin/cat', 13:56:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:00 INFO - 'PWD': '/builds/slave/test', 13:56:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:56:00 INFO - 'SHELL': '/bin/bash', 13:56:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiWfWMpNue/Listeners', 13:56:00 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 13:56:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:56:00 INFO - 'USER': 'cltbld', 13:56:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:56:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:00 INFO - 'XPC_FLAGS': '0x0', 13:56:00 INFO - 'XPC_SERVICE_NAME': '0', 13:56:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:00 INFO - Downloading/unpacking jsonschema==2.5.1 13:56: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 13:56: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 13:56: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 13:56: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 13:56: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 13:56: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 13:56:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:56:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:56: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 13:56: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 13:56: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 13:56: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 13:56: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 13:56: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 13:56:03 INFO - Downloading functools32-3.2.3-2.tar.gz 13:56:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:56:03 INFO - warning: no files found matching '*.txt' 13:56:03 INFO - no previously-included directories found matching 'build' 13:56:03 INFO - no previously-included directories found matching 'dist' 13:56:03 INFO - no previously-included directories found matching '.git*' 13:56:03 INFO - Installing collected packages: jsonschema, functools32 13:56:03 INFO - Running setup.py install for functools32 13:56:03 INFO - warning: no files found matching '*.txt' 13:56:03 INFO - no previously-included directories found matching 'build' 13:56:03 INFO - no previously-included directories found matching 'dist' 13:56:03 INFO - no previously-included directories found matching '.git*' 13:56:03 INFO - Successfully installed jsonschema functools32 13:56:03 INFO - Cleaning up... 13:56:03 INFO - Return code: 0 13:56:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:56: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')]} 13:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56: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')]} 13:56:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56: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 0x106270490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa33246b360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106084d30>, '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 0x105eeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607cb20>, '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.01AjV2DU28/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.BiWfWMpNue/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 13:56: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 13:56: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 13:56:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01AjV2DU28/Render', 13:56:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:56:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:56:03 INFO - 'HOME': '/Users/cltbld', 13:56:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:56:03 INFO - 'LOGNAME': 'cltbld', 13:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:03 INFO - 'MOZ_NO_REMOTE': '1', 13:56:03 INFO - 'NO_EM_RESTART': '1', 13:56:03 INFO - 'PAGER': '/bin/cat', 13:56:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:03 INFO - 'PWD': '/builds/slave/test', 13:56:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:56:03 INFO - 'SHELL': '/bin/bash', 13:56:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiWfWMpNue/Listeners', 13:56:03 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 13:56:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:56:03 INFO - 'USER': 'cltbld', 13:56:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:56:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:03 INFO - 'XPC_FLAGS': '0x0', 13:56:03 INFO - 'XPC_SERVICE_NAME': '0', 13:56:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:56:04 INFO - Cleaning up... 13:56:04 INFO - Return code: 0 13:56:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:56: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')]} 13:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56: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')]} 13:56:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56: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 0x106270490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa33246b360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106084d30>, '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 0x105eeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607cb20>, '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.01AjV2DU28/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.BiWfWMpNue/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 13:56: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 13:56: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 13:56:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01AjV2DU28/Render', 13:56:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:56:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:56:04 INFO - 'HOME': '/Users/cltbld', 13:56:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:56:04 INFO - 'LOGNAME': 'cltbld', 13:56:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:04 INFO - 'MOZ_NO_REMOTE': '1', 13:56:04 INFO - 'NO_EM_RESTART': '1', 13:56:04 INFO - 'PAGER': '/bin/cat', 13:56:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:56:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:04 INFO - 'PWD': '/builds/slave/test', 13:56:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:56:04 INFO - 'SHELL': '/bin/bash', 13:56:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiWfWMpNue/Listeners', 13:56:04 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 13:56:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:56:04 INFO - 'USER': 'cltbld', 13:56:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:56:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:56:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:04 INFO - 'XPC_FLAGS': '0x0', 13:56:04 INFO - 'XPC_SERVICE_NAME': '0', 13:56:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:04 INFO - Downloading/unpacking blobuploader==1.2.4 13:56: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 13:56: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 13:56: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 13:56: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 13:56: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 13:56: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 13:56:07 INFO - Downloading blobuploader-1.2.4.tar.gz 13:56:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:56:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:56: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 13:56: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 13:56: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 13:56: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 13:56: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 13:56: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 13:56:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:56:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:56: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 13:56: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 13:56: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 13:56: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 13:56: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 13:56: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 13:56:08 INFO - Downloading docopt-0.6.1.tar.gz 13:56:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:56:08 INFO - Installing collected packages: blobuploader, requests, docopt 13:56:08 INFO - Running setup.py install for blobuploader 13:56:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:56:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:56:08 INFO - Running setup.py install for requests 13:56:09 INFO - Running setup.py install for docopt 13:56:09 INFO - Successfully installed blobuploader requests docopt 13:56:09 INFO - Cleaning up... 13:56:09 INFO - Return code: 0 13:56:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:56: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')]} 13:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56: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')]} 13:56:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56: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 0x106270490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa33246b360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106084d30>, '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 0x105eeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607cb20>, '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.01AjV2DU28/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.BiWfWMpNue/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 13:56: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 13:56: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 13:56:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01AjV2DU28/Render', 13:56:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:56:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:56:09 INFO - 'HOME': '/Users/cltbld', 13:56:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:56:09 INFO - 'LOGNAME': 'cltbld', 13:56:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:09 INFO - 'MOZ_NO_REMOTE': '1', 13:56:09 INFO - 'NO_EM_RESTART': '1', 13:56:09 INFO - 'PAGER': '/bin/cat', 13:56:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:56:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:09 INFO - 'PWD': '/builds/slave/test', 13:56:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:56:09 INFO - 'SHELL': '/bin/bash', 13:56:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiWfWMpNue/Listeners', 13:56:09 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 13:56:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:56:09 INFO - 'USER': 'cltbld', 13:56:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:56:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:56:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:09 INFO - 'XPC_FLAGS': '0x0', 13:56:09 INFO - 'XPC_SERVICE_NAME': '0', 13:56:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:56:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:56:09 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Ril0UK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:56:09 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-M1OVUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:56:09 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-68Qtk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:56:09 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-ocN5ry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:56:10 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-ofp_hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:56:10 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-8uLT4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:56:10 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-v4FAi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:56:10 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-66FdHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:56:10 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-H3ZTei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:56:10 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-qoBPmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:56:10 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-tSY4uS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:56:10 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-ueie3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:56:10 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-3rvliX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:56:11 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-3ZQfIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:56:11 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-iqJyqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:56:11 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-5Mv8_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:56:11 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-ZzfDbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:56:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:56:11 INFO - Running setup.py install for manifestparser 13:56:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:56:11 INFO - Running setup.py install for mozcrash 13:56:11 INFO - Running setup.py install for mozdebug 13:56:11 INFO - Running setup.py install for mozdevice 13:56:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:56:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:56:12 INFO - Running setup.py install for mozfile 13:56:12 INFO - Running setup.py install for mozhttpd 13:56:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:56:12 INFO - Running setup.py install for mozinfo 13:56:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:56:12 INFO - Running setup.py install for mozInstall 13:56:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:56:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:56:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:56:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:56:12 INFO - Running setup.py install for mozleak 13:56:12 INFO - Running setup.py install for mozlog 13:56:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:56:12 INFO - Running setup.py install for moznetwork 13:56:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:56:13 INFO - Running setup.py install for mozprocess 13:56:13 INFO - Running setup.py install for mozprofile 13:56:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:56:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:56:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:56:13 INFO - Running setup.py install for mozrunner 13:56:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:56:13 INFO - Running setup.py install for mozscreenshot 13:56:13 INFO - Running setup.py install for moztest 13:56:13 INFO - Running setup.py install for mozversion 13:56:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:56:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:56:14 INFO - Cleaning up... 13:56:14 INFO - Return code: 0 13:56:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:56:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:56:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:56:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:14 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 0x106270490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa33246b360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106084d30>, '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 0x105eeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607cb20>, '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.01AjV2DU28/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.BiWfWMpNue/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 13:56:14 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 13:56:14 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 13:56:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01AjV2DU28/Render', 13:56:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:56:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:56:14 INFO - 'HOME': '/Users/cltbld', 13:56:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:56:14 INFO - 'LOGNAME': 'cltbld', 13:56:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:14 INFO - 'MOZ_NO_REMOTE': '1', 13:56:14 INFO - 'NO_EM_RESTART': '1', 13:56:14 INFO - 'PAGER': '/bin/cat', 13:56:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:56:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:14 INFO - 'PWD': '/builds/slave/test', 13:56:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:56:14 INFO - 'SHELL': '/bin/bash', 13:56:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiWfWMpNue/Listeners', 13:56:14 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 13:56:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:56:14 INFO - 'USER': 'cltbld', 13:56:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:56:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:56:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:14 INFO - 'XPC_FLAGS': '0x0', 13:56:14 INFO - 'XPC_SERVICE_NAME': '0', 13:56:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:56:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:56:14 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-kGUExy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:56: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)) 13:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:56:14 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-5cMYxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:56: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)) 13:56:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:56:14 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-p1qHv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:56:15 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)) 13:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:56:15 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-nE_fG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:56:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:56:15 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-h7Q3FQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:56:15 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)) 13:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:56:15 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-9sfROl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:56:15 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)) 13:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:56:15 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-qiBjHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:56:15 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)) 13:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:56:15 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-xM7g1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:56:15 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)) 13:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:56:15 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-1iqc4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:56: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)) 13:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:56:15 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-RydYn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:56: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)) 13:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:56:15 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-CIZrXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:56: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)) 13:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:56:15 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-jv7JCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:56: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)) 13:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:56:16 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-130vz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:56:16 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)) 13:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:56:16 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-vEbHh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:56:16 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)) 13:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:56:16 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-lhNjX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:56:16 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)) 13:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:56:16 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-uc9_uD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:56:16 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-zU7m7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:56:16 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)) 13:56:16 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)) 13:56:16 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)) 13:56:16 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)) 13:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:56:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:56:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:56:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:56:19 INFO - Downloading blessings-1.6.tar.gz 13:56:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:56:19 INFO - Installing collected packages: blessings 13:56:19 INFO - Running setup.py install for blessings 13:56:19 INFO - Successfully installed blessings 13:56:19 INFO - Cleaning up... 13:56:19 INFO - Return code: 0 13:56:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:56:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:56:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:56:20 INFO - Reading from file tmpfile_stdout 13:56:20 INFO - Current package versions: 13:56:20 INFO - blessings == 1.6 13:56:20 INFO - blobuploader == 1.2.4 13:56:20 INFO - docopt == 0.6.1 13:56:20 INFO - functools32 == 3.2.3-2 13:56:20 INFO - jsonschema == 2.5.1 13:56:20 INFO - manifestparser == 1.1 13:56:20 INFO - mozInstall == 1.12 13:56:20 INFO - mozcrash == 1.0 13:56:20 INFO - mozdebug == 0.1 13:56:20 INFO - mozdevice == 0.48 13:56:20 INFO - mozfile == 1.2 13:56:20 INFO - mozhttpd == 0.7 13:56:20 INFO - mozinfo == 0.9 13:56:20 INFO - mozleak == 0.1 13:56:20 INFO - mozlog == 3.4 13:56:20 INFO - moznetwork == 0.27 13:56:20 INFO - mozprocess == 0.23 13:56:20 INFO - mozprofile == 0.28 13:56:20 INFO - mozrunner == 6.13 13:56:20 INFO - mozscreenshot == 0.1 13:56:20 INFO - mozsystemmonitor == 0.3 13:56:20 INFO - moztest == 0.8 13:56:20 INFO - mozversion == 1.4 13:56:20 INFO - psutil == 3.1.1 13:56:20 INFO - requests == 1.2.3 13:56:20 INFO - wsgiref == 0.1.2 13:56:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:56:20 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')]} 13:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:20 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')]} 13:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:20 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 0x106270490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa33246b360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106084d30>, '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 0x105eeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607cb20>, '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.01AjV2DU28/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.BiWfWMpNue/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 13:56:20 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 13:56:20 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 13:56:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01AjV2DU28/Render', 13:56:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:56:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:56:20 INFO - 'HOME': '/Users/cltbld', 13:56:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:56:20 INFO - 'LOGNAME': 'cltbld', 13:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:20 INFO - 'MOZ_NO_REMOTE': '1', 13:56:20 INFO - 'NO_EM_RESTART': '1', 13:56:20 INFO - 'PAGER': '/bin/cat', 13:56:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:20 INFO - 'PWD': '/builds/slave/test', 13:56:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:56:20 INFO - 'SHELL': '/bin/bash', 13:56:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiWfWMpNue/Listeners', 13:56:20 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 13:56:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:56:20 INFO - 'USER': 'cltbld', 13:56:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:56:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:20 INFO - 'XPC_FLAGS': '0x0', 13:56:20 INFO - 'XPC_SERVICE_NAME': '0', 13:56:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:56:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:20 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)) 13:56:20 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)) 13:56:20 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)) 13:56:20 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)) 13:56:20 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)) 13:56:20 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)) 13:56:20 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)) 13:56:20 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)) 13:56:20 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)) 13:56:20 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)) 13:56:20 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)) 13:56:20 INFO - Cleaning up... 13:56:20 INFO - Return code: 0 13:56:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:56:20 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')]} 13:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:20 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')]} 13:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:20 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 0x106270490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa33246b360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106084d30>, '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 0x105eeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607cb20>, '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.01AjV2DU28/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.BiWfWMpNue/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 13:56:20 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 13:56:20 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 13:56:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01AjV2DU28/Render', 13:56:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:56:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:56:20 INFO - 'HOME': '/Users/cltbld', 13:56:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:56:20 INFO - 'LOGNAME': 'cltbld', 13:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:20 INFO - 'MOZ_NO_REMOTE': '1', 13:56:20 INFO - 'NO_EM_RESTART': '1', 13:56:20 INFO - 'PAGER': '/bin/cat', 13:56:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:20 INFO - 'PWD': '/builds/slave/test', 13:56:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:56:20 INFO - 'SHELL': '/bin/bash', 13:56:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiWfWMpNue/Listeners', 13:56:20 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 13:56:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:56:20 INFO - 'USER': 'cltbld', 13:56:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:56:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:20 INFO - 'XPC_FLAGS': '0x0', 13:56:20 INFO - 'XPC_SERVICE_NAME': '0', 13:56:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:56:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:56:20 INFO - Cleaning up... 13:56:20 INFO - Return code: 0 13:56:20 INFO - Running post-action listener: _resource_record_post_action 13:56:20 INFO - Running post-action listener: _start_resource_monitoring 13:56:20 INFO - Starting resource monitoring. 13:56:20 INFO - [mozharness: 2017-02-01 21:56:20.736809Z] Finished create-virtualenv step (success) 13:56:20 INFO - [mozharness: 2017-02-01 21:56:20.737841Z] Running install step. 13:56:20 INFO - Running pre-action listener: _resource_record_pre_action 13:56:20 INFO - Running main action method: install 13:56:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:56:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:56:20 INFO - Reading from file tmpfile_stdout 13:56:20 INFO - Detecting whether we're running mozinstall >=1.0... 13:56:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:56:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:56:21 INFO - Reading from file tmpfile_stdout 13:56:21 INFO - Output received: 13:56:21 INFO - Usage: mozinstall [options] installer 13:56:21 INFO - Options: 13:56:21 INFO - -h, --help show this help message and exit 13:56:21 INFO - -d DEST, --destination=DEST 13:56:21 INFO - Directory to install application into. [default: 13:56:21 INFO - "/builds/slave/test"] 13:56:21 INFO - --app=APP Application being installed. [default: firefox] 13:56:21 INFO - mkdir: /builds/slave/test/build/application 13:56:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:56:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 13:56:42 INFO - Reading from file tmpfile_stdout 13:56:42 INFO - Output received: 13:56:42 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 13:56:42 INFO - Running post-action listener: _resource_record_post_action 13:56:42 INFO - [mozharness: 2017-02-01 21:56:42.785150Z] Finished install step (success) 13:56:42 INFO - [mozharness: 2017-02-01 21:56:42.785278Z] Running run-tests step. 13:56:42 INFO - Running pre-action listener: _resource_record_pre_action 13:56:42 INFO - Running main action method: run_tests 13:56:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:56:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:56:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:56:42 INFO - 2017-02-01 13:56:42.799 screenresolution[1623:7418] starting screenresolution argv=screenresolution get 13:56:42 INFO - 2017-02-01 13:56:42.802 screenresolution[1623:7418] Display 0: 1600x1200x32@60 13:56:42 INFO - 2017-02-01 13:56:42.808 screenresolution[1624:7421] starting screenresolution argv=screenresolution list 13:56:42 INFO - Available Modes on Display 0 13:56:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:56:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:56:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:56:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:56:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:56:43 INFO - Intel Iris: 13:56:43 INFO - Chipset Model: Intel Iris 13:56:43 INFO - Type: GPU 13:56:43 INFO - Bus: Built-In 13:56:43 INFO - VRAM (Dynamic, Max): 1536 MB 13:56:43 INFO - Vendor: Intel (0x8086) 13:56:43 INFO - Device ID: 0x0a2e 13:56:43 INFO - Revision ID: 0x0009 13:56:43 INFO - Displays: 13:56:43 INFO - Display: 13:56:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:56:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:56:43 INFO - Display Serial Number: 1600x1200 60 13:56:43 INFO - Main Display: Yes 13:56:43 INFO - Mirror: Off 13:56:43 INFO - Online: Yes 13:56:43 INFO - Rotation: Supported 13:56:43 INFO - Return code: 0 13:56:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:56:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:56:43 INFO - Python 2.7.3 13:56:43 INFO - Return code: 0 13:56:43 INFO - grabbing minidump binary from tooltool 13:56: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')]} 13:56:43 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 0x106084d30>, '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 0x105eeadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10607cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:56:43 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 13:56:43 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 13:56:43 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 13:56:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:56:43 INFO - Return code: 0 13:56:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:56:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:56:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:56:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:56:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:56:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0114', '--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 13:56:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0114 --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 13:56:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.01AjV2DU28/Render', 13:56:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:56:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:56:43 INFO - 'HOME': '/Users/cltbld', 13:56:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:56:43 INFO - 'LOGNAME': 'cltbld', 13:56:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:56:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:56:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:43 INFO - 'MOZ_NO_REMOTE': '1', 13:56:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:56:43 INFO - 'NO_EM_RESTART': '1', 13:56:43 INFO - 'PAGER': '/bin/cat', 13:56:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:56:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:43 INFO - 'PWD': '/builds/slave/test', 13:56:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:56:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:56:43 INFO - 'SHELL': '/bin/bash', 13:56:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BiWfWMpNue/Listeners', 13:56:43 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 13:56:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:56:43 INFO - 'USER': 'cltbld', 13:56:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:56:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:56:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:43 INFO - 'XPC_FLAGS': '0x0', 13:56:43 INFO - 'XPC_SERVICE_NAME': '0', 13:56:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:56:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0114', '--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 13:56:43 INFO - mozversion application_buildid: 20170201114508 13:56:43 INFO - mozversion application_changeset: a15e503db4d1d7261680fb497af39b65babff013 13:56:43 INFO - mozversion application_display_name: Firefox 13:56:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:56:43 INFO - mozversion application_name: Firefox 13:56:43 INFO - mozversion application_remotingname: firefox 13:56:43 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:56:43 INFO - mozversion application_vendor: Mozilla 13:56:43 INFO - mozversion application_version: 52.0 13:56:43 INFO - mozversion platform_buildid: 20170201114508 13:56:43 INFO - mozversion platform_changeset: a15e503db4d1d7261680fb497af39b65babff013 13:56:43 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:56:43 INFO - mozversion platform_version: 52.0 13:56:43 INFO - using testdate: 1485986203 13:56:43 INFO - actual date: 1485986203 13:56:43 INFO - starting webserver on 'localhost:49241' 13:56:43 INFO - SUITE-START | Running 2 tests 13:56:43 INFO - TEST-START | dromaeo_css 13:56:43 INFO - operating with platform_type : mac_ 13:56:43 INFO - Initialising browser for dromaeo_css test... 13:56:43 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpiziTKK/profile http://localhost:49241/getInfo.html) 13:56:46 INFO - PROCESS | 1634 | 2017-02-01 13:56:46.221 plugin-container[1637:7603] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b4f, name = 'com.apple.tsm.portname' 13:56:46 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:56:46 INFO - PROCESS | 1634 | 2017-02-01 13:56:46.222 plugin-container[1637:7603] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 13:56:46 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:56:46 INFO - PROCESS | 1634 | 2017-02-01 13:56:46.222 plugin-container[1637:7603] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:56:46 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 13:56:46 INFO - PROCESS | 1634 | colorDepth:24 13:56:46 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 13:56:46 INFO - PROCESS | 1634 | __metrics 13:56:46 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 13:56:47 INFO - TEST-INFO | 1634: exit 0 13:56:47 INFO - Browser initialized. 13:56:47 INFO - Running cycle 1/1 for dromaeo_css test... 13:56:47 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpiziTKK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:56:49 INFO - PROCESS | 1638 | 2017-02-01 13:56:49.768 plugin-container[1639:7733] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 13:56:49 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:56:49 INFO - PROCESS | 1638 | 2017-02-01 13:56:49.769 plugin-container[1639:7733] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x880b, name = 'com.apple.CFPasteboardClient' 13:56:49 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:56:49 INFO - PROCESS | 1638 | 2017-02-01 13:56:49.769 plugin-container[1639:7733] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:56:59 INFO - PROCESS | 1638 | RSS: Main: 177352704 13:56:59 INFO - PROCESS | 1638 | 14:00:02 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 14:00:03 INFO - PROCESS | 1638 | RSS: Main: 181215232 14:00:03 INFO - PROCESS | 1638 | 14:03:06 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 14:03:06 INFO - PROCESS | 1638 | RSS: Main: 191270912 14:03:06 INFO - PROCESS | 1638 | 14:06:08 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 14:06:09 INFO - PROCESS | 1638 | RSS: Main: 194846720 14:06:09 INFO - PROCESS | 1638 | 14:09:11 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 14:09:11 INFO - PROCESS | 1638 | RSS: Main: 197890048 14:09:11 INFO - PROCESS | 1638 | 14:12:14 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 14:12:15 INFO - PROCESS | 1638 | RSS: Main: 202526720 14:12:15 INFO - PROCESS | 1638 | 14:15:17 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 14:15:17 INFO - PROCESS | 1638 | RSS: Main: 203149312 14:15:17 INFO - PROCESS | 1638 | 14:15:17 INFO - PROCESS | 1638 | __start_tp_report 14:15:17 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 14:15:17 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 14:15:17 INFO - PROCESS | 1638 | |i|pagename|runs| 14:15:17 INFO - PROCESS | 1638 | |0;dojo.html;3516.349475347061;3526.382882995476;3526.753127281088;3569.072041269271;3576.982115089424;5185.922211166836;5348.197770334452;5973.313068997067;6066.968615335392;6073.878522429549;5993.730282137303;6041.758329666819;6071.817845464632;6118.479929206012;6118.969405152968;5338.786448542063;5411.783528658869;5443.237946687467;5471.179323101527;5534.640248383862;5517.062099443085;5518.061929471999;5520.227168196456;5525.834224973237;5581;5513.255710479074;5514.889702205954;5515.117581186991;5515.310586176728;5524.171374293868;5328.440513746059;5358.651687640291;5433.728313584305;5473.616846820724;5476.123820188751;5265.631405801557;5397.8110766123;5399.082156033483;5420.512153906167;5437.8368648940595;5260.789568417259;6132.540059495555;6146.17026701394;6194.442500175006;6229.750809967596;6238.376162383771;6244.344343843922;6300.936990630081;6381.712822922979;6384.425401713822;6463.062855885917;6465.191851018622;6492.67536623167;6504.739810407578;6518.185226846645;6027.21646185993;6216.471599914058;6227.314995350508;6233.1273621693;6241.906371404432;5890.8821823563285;6144.078388241799;6394.4564712;6495.642739649365;6526.967365163144;4732.645051621143;5318.760655770499;6313.589616674902;6314;6317.810465686037;5407.432219616962;6430.582012169194;6431.453326467303;6432.549721519449;6442.033694945796;6133.1510485532735;6232.781852635227;6403.295637479873;6433.710483028275;6434.7104380303;6181.536384771161;6255.937440625627;6291.6854157291955;6304.936950630527;6426.132472116298;5224.817131400383;5228.816991405282;5242.108841496878;5251.264822924793;5269.604779641542;4057.2494088593703;4632.976835115803;4639.953600463952;4640;4696.953030469652;4726.527347265246;6077.908831367533;6131.529590167202;6141.723622436912;6154.5076393888385;6458.709358078898;6470.741170353227;6472.773452929125;6478.481721462272;6488.675566221765;6107.947487155049;6121.204243448323;6126.456603435028;6177.69111544421;6179.876402471926;5708.80019199326;5714.514266287329;5748.971255143698;5754.165645981309;5755.712214389264;6384.3934826191435;6484.5460817742305;6527.869442611026;6580.703868325841;6607.504437167136;6434.131392261985;6460.903086453613;6465.935340646533;6468.514861385509;6504.6747662615735;6678.065070890177;6696.129503164558;6698.464122870061;6703.363180497845;6708.597484150967;6233.158523599256;6285.74857005733;6299.275583307978;6300.180976473029;6387.361263873576;6490.48076153916;6491.967540162458;6497.122888410004;6509.772157974448;6514.902276465951;5882.441168089024;6328;6336.271328797063;6343.207099112611;6348.428641422294;5631.436856314336;6239.4696450800375;6315.936840631535;6322.620642761449;6339.714712837841;6052.757889684363;6136.88672321611;6177.19696439478;6256.311805701278;6275.435210831047;6255.71849266775;6294.087357333253;6305.306416294296;6310.085037669604;6322.430981211529;6109.450149486567;6232.688365581612;6245.906311405423;6280.309165991828;6295.276043255084 14:15:17 INFO - PROCESS | 1638 | |1;ext.html;480.20765736534713;538.5530010091624;545.1686178577669;554.2932760730071;561.696683790753;39395.818125456244;41089.79455102728;41155.794221028926;41192.17615647685;41202.763917082535;42840.57159428405;43167.78416107923;43256.56743432573;43297;43360.56639433605;9520.761980950463;9698.90301096987;9701.223902087851;9707.320487565874;9745.561449734743;9656.613735450608;9748.732664753572;9750.46372449515;9798.167155791767;9819.06718861707;9733.02669733028;9762.365446246007;9764.316497845119;9777.413355198676;9777.657781977632;9668.226541876667;9692.51537423126;9746;9751.707448776548;9756.90243097567;8467.772173034937;9214.769630759218;9462.763430914214;9531.571079301379;9535.47554884481;40207.59792402068;40613;40618.796906015574;40674.79662601668;41030.58969410328;2894.073896353168;3385.5937287525653;3388.102152929451;3419.692227699519;3467.9653203467897;30057.034503640873;30792.384152317052;30893.0732078038;31036.224094397596;31747.52378714321;2789.4560560690675;3193.786361182759;3313.9005829825146;3317.8672853085845;3355.6644335566493;472195;532534.3373283108;537138;539600;541679.2916035434;63589;63974.36025639777;64004.67997660075;64041;64472.677636612454;66176.66911665411;66200;66569.66715166393;66705.66588668297;66718.66640666766;16916.746248806277;17134.742978855327;17164.914175429043;17193.7420938686;17389.304427822997;13016.609501714964;13070;13078.869211307765;13082;13094.803577946339;16963.74554381685;18231.27074917015;19340.226390944204;19350;19447.513812154528;7150.749723759747;7644.579548124907;7683.193264707237;7863.803404914808;7989.840203195903;889.5774507109071;1022.8925962773801;1059.9152067834555;1092.3118435385657;1106.5739690219323;29922.653480593424;30233.546496802566;30309.090727278217;30398.848005759828;30502.54246186309;948.2082461144884;973.1582181413027;1028.9742756431149;1100.6037826382496;1109.6504601050642;14308.356123974447;15050.924745376204;15873.04761714301;15919.840801591836;16052.357905683875;699563;760705;773684;778557;1015115;58879.116813247834;59914.70042649759;60026;60028.799424011275;60054.39945600488;1217.6894891802533;1253.1603825436796;1361.7208472263162;1400.7968844517488;1426.9215193164475;26890.596641050008;29488.967886123883;29815.403691926473;30596;30612;49186;49452.50547494623;50201.74899125481;50227.24659130043;50785.144005558774;653293;654050;660360;660616;662858;23523;26119.955201792476;26698.59952100759;27155.04957326484;27207.455850883267;41501;45488.54511454843;46599.53400465952;46904.530954691385;47421.52578474171;31139.532907006866;38563.807180965036;39032.63385781598;40610.18779624332;40877;38865.41701874418;38871.2225755489;39046.80476597599;39658.40512392374;40218;37743.62256377511;38243;38252.617473826016;38577;38670.419943700304;33296.50165742499;34530;34610.65389346175;35159.82420087883;35336.29327413386;20149.39551813448;25997.350066248495;25997.740022599533;26197.5591342478;26577.202683919513 14:15:17 INFO - PROCESS | 1638 | |2;jquery.html;388.11896993824;388.37083924043054;390.0716295217382;395.5115432440935;418.8492142828581;20044;20903.790962090374;21202.469938251532;21215.151393944245;21794.673079903812;20935.267265645707;21044.89477552614;21084.789152108475;21086.05112769925;21635.567288654216;5810;6017.939820601804;6045.879082418349;6083.756649734005;6135.8772824543485;5718.1136923776885;5930.762769489215;5981.1028345748055;5986.73059712314;5998.55010874184;5977.7608895644125;5981.671008094553;5986.580939334249;5991.490723288525;6001.729922153491;5704.600677952566;5907.556933230004;5920.230370051909;5975.372585878464;5980.9102863456865;5161.741912904377;5196.402413722395;5260.815871444519;5260.947390526084;5265.842024739264;25528.489430211477;25860.482790344275;25969;25982.22053338403;26030.86984565065;2494.4387512809653;2543.1353339864536;2544.910928117507;2861.799674022819;3138.8901388451523;15767.211639418098;15896.966697164647;16142.435014774426;16349;16843.74734378985;1879.8684092113558;1995.8503112266492;1999.1203870297022;2006.9096890639955;2011.919523219084;110706.33940490898;117126;119757.80242197553;121529.78470215273;125804;11458.770824583462;11711.1802173847;11851.46668399924;11922.986546143675;12041.698957526132;13154.4080516377;14742.746866516467;15157.242137893061;15280.083195008336;15288.38846446152;8699.782505437288;8750.606222719993;8881.378303518692;8936.41913275635;8952.910470895338;8667.393282470166;8713.825723485495;8713.91286087131;8834.337424693174;8910.509921954224;4585.449746030497;4666.836660716858;4698.506656801055;4698.741569213657;4742.73914934675;1652.9586760331088;1674.6818104560339;1747.6679430908086;1859.0240123934773;1979.7822239553639;869.6217145541709;951.4291425144858;993.5826952679892;1011.858339832424;1012.9493525323704;20341.288054918008;20579.691304630443;21259.574808503745;21390.251341202984;21627.24304649361;947.3415975896824;979.2166266986383;997.7904640025675;1037.4553359486179;1064.685917654294;10069.848952265722;11179.993800557988;11362.97733203999;11432.828507572392;11510.712232194095;556207;593895;604499;608329;609460.9526952336;122043;132424.33787831;133079;133231;134339.32830335785;984.5815528400401;992.9602815887428;1053.8682664666917;1055.3193190392153;1056.4136904018344;19589.80410195937;19698.40904772859;19959.002049897157;19998.70001949943;20075;57830.421695784185;58111;58371.70814145902;59894.401055990624;60401.39598603958;80486.59756701413;82148.17851821645;85014;85160;86972;6847.9385695217325;7373.963130184315;8151.5924203790755;8385.413021088465;8400;11417.143714221633;12693.174943628635;12712.936435318134;12911.806322904977;12972.870271297166;5447.782088716407;10925.306577480504;11115.110071381338;11415.657530274106;11507.827382589101;10801.837972430578;11075;11132;11306.208565400671;11513.251638643462;11448.026917711755;11650.126240531825;11676.88323116758;11690.18168728215;11722.94138529302;6925.549839260432;8167.346612270885;8191.8771218430575;8512.148785121439;8561.957190214009;10868.782624347627;11266.493007814504;11297.096232301561;11453.59912403062;11474.254173478697 14:15:17 INFO - PROCESS | 1638 | |3;mootools.html;1298.2405292903652;1521.132954216097;1669.4240487031977;1702.3190723710522;1861.8324350808432;7699.499532530382;7946.205379462051;8211.260986511214;8623.223909848113;8644.222020018196;8175.059868115169;8400.033996090451;8417.200365965224;8555.657773689045;8599.05410404855;2337.9298621041353;2385.3082606044227;2395.8203134764876;2396.436837343223;2400.7839294463497;2401.7238017628024;2408.313630615275;2441.7558244175616;2445.2786428003783;2451.6812814334203;2383;2394.1740099665676;2411.6382542618658;2412.3245491262473;2414.601590737535;2368.561816064025;2377.6314671225987;2395.880205989702;2397.424618091663;2404.97595024051;2392.342105920886;2399.616061430163;2411.2284069097896;2413.444907671228;2420.673209116764;8492.193241642035;8536.146385361477;8566.186212309884;8568.357373196974;8627.6980305689;5174.534291913708;5316.760745766411;5322.813701520467;5327.627066105374;5334.653247538937;3459.844307006191;3538.964610353915;3590.281943611288;3595.7842529448053;3623.655752703489;1055.7730088031033;1085.913126949842;1098.8406681031206;1103.47584897174;1110.8833572474934;10945.01494865458;11001.284916480483;11018.173636977259;11023.44882755859;11033.558657653684;4522.638188944877;4681.695689780154;4747.8100875965265;4755.881102972453;4878.878028049327;9630.229581633452;9635.084666956627;9670.806583868423;9671.854922176173;9679.758006049906;5312.548433383128;5331.893362132813;5334.973325133427;5394.541463975606;5445.237666726662;4426.911461770746;4446.4886538047895;4853.587445067138;4908.410990681141;4920.630952678492;3463.2900255448076;3877.8254978526033;3904.668103211202;3978.0850404407147;4006.338954072604;1081.6484642491064;1130.886911308879;1285.3637449462576;1292.2311224821217;1300.4148133340034;903.5708038681705;917.1928702741545;947.209080417845;947.4078700811992;972.3048020665117;5226.92159617606;5924.4667979882015;5946.672932988728;5974.790882319098;6299.937000630027;917.9443639814267;945.8486642137225;969.1084202533686;983.1446641422009;1000.674780696262;3832.3676593362343;3956.5647778744315;3956.7625942443547;3957.445957565943;3964.702647301406;10049.799004019878;10340.638077667396;10381.273310868166;10388.065074143364;10413.635522756667;10170.338927969658;10211.285210035227;10234.385936843813;10261.486925653688;10373.792524149474;7527.548347099191;7903.446758726834;7932.405069619802;7946.046474423106;8107.878381824277;5825.300963884361;5867.266591676041;5867.501262392744;5951.583389162717;5955;9166.08339166078;9440.669576564787;9480;9555.952220238854;9646.807063858543;9445.716628501155;9635.084666956627;9696.85454718194;9744;9767.951160244153;7203.351698347174;7453.217412171861;7792.727254546064;7906.841863162589;8026.317762990211;7916.525008499509;8145.877811832946;8153.592320384076;8164.2652161303395;8173.346132309282;7015.088038554955;7142.321479459443;7262.709491620276;7269.854602908018;7300.087489063867;6119.877602448015;7044.436445084279;7068.787936361918;7130.750423735352;7213.819654508469;6689.899651505329;6711.966440167768;6800.28596997328;6800.863982720417;6833.69248383814;4889.315495830658;4890.437599676083;4929.408470983505;4955.578775804097;4959.82640607577;4340.956590434055;4777.4267087949665;4814.494478079751;4869.4400143982475;4888.315635810918 14:15:17 INFO - PROCESS | 1638 | |4;prototype.html;308.4263270317211;311.9485284927987;318.697237624257;321.54501380546526;322.3552894211577;4448.132614140244;5319.0957537218665;5370.133503877202;5513.443828730471;5698.632328241229;4501.797419116139;4977.67645103068;5478.835634930959;5483.232347471358;5525;4197.496300443951;4203.369494575808;4210.936835947463;4223.7043406961375;4236.195122926634;3446.914616656924;3982.7610343379347;4224.922644725606;4233.301505251645;4263.594958478955;3781.8676346327884;3872.1287710265246;4155.6883233757435;4185.142045880587;4193.308104162825;3995.1410446753944;4192.748435093874;4198.538160802309;4211.284081706117;4216.810243539048;3406.267652454734;3525.6954926677295;3539.752217344787;3677.191017976041;3688.686461650763;4208;4838.129136755382;5331.866703332409;5355.303810504648;5356.544693701001;4070.877873663765;4877.756112194376;4893.434101087653;4906.719399169034;4907.2639104134305;5074.695518268915;5627.380988091307;5763.394843541451;5806.071028635398;5874.23634927461;3480.207693038087;4012.8996775080564;4063.6342729154517;4136.531531306403;4157.792110394468;6161.552035271708;6164.291106522763;6171.166892469549;6176.289726681444;6199.101130336076;3311.817850018272;3545.014791716647;3865.555461121951;3946.881593552198;4119.588041195856;3993.101552150743;4106.843906948876;4175;4225.9577404226175;4247.193033323659;4880.267959806058;4887.902241955141;4907.938665760389;4914.213725803854;4941.813964648459;5593.804216852391;5866.6480011199465;5882.588218824641;5914.940850591525;5988.550858685616;1249.8375211222483;1327.4159369877416;1344.7713888638955;1394.3307212538039;1398.6153807702963;928.9907100929039;940.6378544260372;950.3632566180537;957.7318350861769;960.9759756006156;4329.004329004284;4551.340055691906;4779.378680771478;5150.819721309811;5205.817796377109;4938.456769755324;5068.797248110108;5723.54211663066;5757.568182386254;5779.913301300484;4371.956280437218;4396.890077748018;4771.761411929389;5256.395514515803;5294.97352513235;2408.674828898111;2523.785478234334;2760.419008227314;2992.5660779186896;3010.4882170031146;5188.377394712658;5268.998890210924;5673.404292549306;5740.6268592541355;5887.263257339096;5775.364709881909;5784.739686714108;5847.473727364558;5901.970490147522;6165.687867049856;6271;6272.40412160844;6289.3458840456815;6290.748370065146;6312.936870631327;5880.14925149479;6081.35118928657;6127.816165515042;6129.417705317987;6167.969160154171;5175.353080864892;5889.469947704727;5938.317093534299;5963.344032156372;6011.398860113954;5780.855478613068;5839.832033593383;6273.518197400304;6308.936910631019;6390.712417941297;3863.34323165057;3873.5158105236846;3987.641112299907;4010.779407132519;4044.757314561136;5817.254823552833;5984.820455386345;6044.848878778066;6073;6080.027195648675;5076.314697515788;5688.757408277408;5757.625754325955;5859;5888.941110588839;5444.455554444524;5918.496927761188;5922.3485416605;5929.851753706192;5955.434233747787;4875.561199492063;4905.724511626931;5581.609287349846;5695.658260504383;5772.913406298825;5907.556933229939;5907.700305932688;5913.497352725067;5921.927325436063;5934.762609495572;4020.8391664334267;5468.179773034077;5753.453421924911;5885.231983219332;5900.468957793819 14:15:17 INFO - PROCESS | 1638 | |5;yui.html;692.9341712537307;702.5749421599928;769.5344316688403;797.4856217739558;842.1830824100622;14908.105513669187;15103.54689359321;15329;15435.922820385897;15533.301001454933;14057.226852523107;14060.507882224125;14149.009569330152;14196.290185490736;14218.644533886658;685.2633419074515;832.2717622080679;854.3848429131016;856.6873091321654;856.7772379181396;681.3016657925616;688.8691148681735;822.0915887943826;835.8829763833069;842.1031601344548;660.4617236951868;879.3580686099151;885.1812073831712;908.1327332397577;909.4725059465494;753.5252790741825;870.5299138465222;896.7175339767984;897.9191872731485;900.7207765592688;556.9721513924328;661.0909998751716;668.421815129914;672.2806596941275;675.1358261425383;14519;14611.269436528131;14656.853431465763;14920.701585968329;15265.542033739006;14477.782833257404;14851.108933464027;14933.477328293458;15005.474808381763;15042.398304067825;9425.057494250588;10036;11670.066394688405;11776;12042.87957120435;4650.372199753024;4720.291956206562;4738.312944623045;4748.596369308613;4766.094442056033;87963.12036879678;91914;93598.99999999932;93736;93787;10206.285560010729;10575.312604680672;10664.89335106639;10754.78490430187;10911.290766100177;20746.896265518877;20995.79004209969;21115.683264750733;21255.78744212538;21353.46616334573;7890.5265684057995;7894;7918.485298455582;7945.046594408708;8053.275205231557;4203.789810509462;4318.78406079701;4336.631386332134;4402.933955990663;4428.689991700615;3282.1302354875975;3369.5451114099283;3400.9149771255916;3406.267652454734;3416.0776590320474;634.9492040636738;635.8092572228405;642.1235014205575;646.1664452855857;652.2759736692268;597.1103056445864;617.2839506172818;617.7683368736724;650.3171669746747;654.2508827392609;8626.482411055356;8648.16249031901;8947.865782013276;9136.771580710536;9207.033261507579;559.3315987395024;578.8697543052864;606.5390303369383;634.7810005548079;639.2712307968919;4285.271503844354;4370.125974804988;4381.496127945264;4392.165488557164;4456.242438785414;60141;87201.69197462044;104023.4798826001;117282.41358793322;118994;102845;104711;105831.41252881213;108250.45874770734;108932.45533772439;92273.0772692278;93861;97135.02864971402;97591;97949.51025244828;8348;8591.613377398156;8592.226699596817;8601.741947741577;8758.430702004349;42645.78677106595;47948;48843.5115648839;49834.50496485111;55090;64571.38571535749;70637.58724825578;76500.23499764931;78525;79027;12479.188852724545;12799.360031998549;12896.41966111546;12963.546275880677;13039;13663;14126.646833829236;14138.575842724735;14593.270336483345;14642.780358294847;11861.110416718608;12406.007519398247;13244.20534767917;13890.747156850035;14524.346404412037;14004.509842155476;14133.363998619881;14885.85114148888;14890.553283401516;14944.551663450115;13541.255230962392;13687.931560342135;13858.307084645527;13960.092593981359;14612.561623151323;10501;10546.789064218827;11182.832257516306;11221;11688.590899318484;11536.538538458368;11590.652280431601;11622.244554103956;11718.355490447766;11787.764244715228 14:15:17 INFO - PROCESS | 1638 | __end_tp_report 14:15:17 INFO - PROCESS | 1638 | __start_cc_report 14:15:17 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,349 14:15:17 INFO - PROCESS | 1638 | __end_cc_report 14:15:17 INFO - PROCESS | 1638 | __startTimestamp1485987317937__endTimestamp 14:15:17 INFO - PROCESS | 1638 | 14:15:17 INFO - PROCESS | 1638 | ------- Summary: start ------- 14:15:17 INFO - PROCESS | 1638 | Number of tests: 6 14:15:17 INFO - PROCESS | 1638 | 14:15:17 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5943.46 Median:6194.44 stddev:661.83 (10.7%) stddev-sans-first:635.88 14:15:17 INFO - PROCESS | 1638 | Values: 3516.3 3526.4 3526.8 3569.1 3577.0 5185.9 5348.2 5973.3 6067.0 6073.9 5993.7 6041.8 6071.8 6118.5 6119.0 5338.8 5411.8 5443.2 5471.2 5534.6 5517.1 5518.1 5520.2 5525.8 5581.0 5513.3 5514.9 5515.1 5515.3 5524.2 5328.4 5358.7 5433.7 5473.6 5476.1 5265.6 5397.8 5399.1 5420.5 5437.8 5260.8 6132.5 6146.2 6194.4 6229.8 6238.4 6244.3 6300.9 6381.7 6384.4 6463.1 6465.2 6492.7 6504.7 6518.2 6027.2 6216.5 6227.3 6233.1 6241.9 5890.9 6144.1 6394.5 6495.6 6527.0 4732.6 5318.8 6313.6 6314.0 6317.8 5407.4 6430.6 6431.5 6432.5 6442.0 6133.2 6232.8 6403.3 6433.7 6434.7 6181.5 6255.9 6291.7 6304.9 6426.1 5224.8 5228.8 5242.1 5251.3 5269.6 4057.2 4633.0 4640.0 4640.0 4697.0 4726.5 6077.9 6131.5 6141.7 6154.5 6458.7 6470.7 6472.8 6478.5 6488.7 6107.9 6121.2 6126.5 6177.7 6179.9 5708.8 5714.5 5749.0 5754.2 5755.7 6384.4 6484.5 6527.9 6580.7 6607.5 6434.1 6460.9 6465.9 6468.5 6504.7 6678.1 6696.1 6698.5 6703.4 6708.6 6233.2 6285.7 6299.3 6300.2 6387.4 6490.5 6492.0 6497.1 6509.8 6514.9 5882.4 6328.0 6336.3 6343.2 6348.4 5631.4 6239.5 6315.9 6322.6 6339.7 6052.8 6136.9 6177.2 6256.3 6275.4 6255.7 6294.1 6305.3 6310.1 6322.4 6109.5 6232.7 6245.9 6280.3 6295.3 14:15:17 INFO - PROCESS | 1638 | 14:15:17 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:78374.42 Median:40646.80 stddev:181699.31 (447.0%) stddev-sans-first:182114.90 14:15:17 INFO - PROCESS | 1638 | Values: 480.2 538.6 545.2 554.3 561.7 39395.8 41089.8 41155.8 41192.2 41202.8 42840.6 43167.8 43256.6 43297.0 43360.6 9520.8 9698.9 9701.2 9707.3 9745.6 9656.6 9748.7 9750.5 9798.2 9819.1 9733.0 9762.4 9764.3 9777.4 9777.7 9668.2 9692.5 9746.0 9751.7 9756.9 8467.8 9214.8 9462.8 9531.6 9535.5 40207.6 40613.0 40618.8 40674.8 41030.6 2894.1 3385.6 3388.1 3419.7 3468.0 30057.0 30792.4 30893.1 31036.2 31747.5 2789.5 3193.8 3313.9 3317.9 3355.7 472195.0 532534.3 537138.0 539600.0 541679.3 63589.0 63974.4 64004.7 64041.0 64472.7 66176.7 66200.0 66569.7 66705.7 66718.7 16916.7 17134.7 17164.9 17193.7 17389.3 13016.6 13070.0 13078.9 13082.0 13094.8 16963.7 18231.3 19340.2 19350.0 19447.5 7150.7 7644.6 7683.2 7863.8 7989.8 889.6 1022.9 1059.9 1092.3 1106.6 29922.7 30233.5 30309.1 30398.8 30502.5 948.2 973.2 1029.0 1100.6 1109.7 14308.4 15050.9 15873.0 15919.8 16052.4 699563.0 760705.0 773684.0 778557.0 1015115.0 58879.1 59914.7 60026.0 60028.8 60054.4 1217.7 1253.2 1361.7 1400.8 1426.9 26890.6 29489.0 29815.4 30596.0 30612.0 49186.0 49452.5 50201.7 50227.2 50785.1 653293.0 654050.0 660360.0 660616.0 662858.0 23523.0 26120.0 26698.6 27155.0 27207.5 41501.0 45488.5 46599.5 46904.5 47421.5 31139.5 38563.8 39032.6 40610.2 40877.0 38865.4 38871.2 39046.8 39658.4 40218.0 37743.6 38243.0 38252.6 38577.0 38670.4 33296.5 34530.0 34610.7 35159.8 35336.3 20149.4 25997.4 25997.7 26197.6 26577.2 14:15:17 INFO - PROCESS | 1638 | 14:15:17 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:35666.41 Median:12144.56 stddev:99465.89 (819.0%) stddev-sans-first:99709.65 14:15:17 INFO - PROCESS | 1638 | Values: 388.1 388.4 390.1 395.5 418.8 20044.0 20903.8 21202.5 21215.2 21794.7 20935.3 21044.9 21084.8 21086.1 21635.6 5810.0 6017.9 6045.9 6083.8 6135.9 5718.1 5930.8 5981.1 5986.7 5998.6 5977.8 5981.7 5986.6 5991.5 6001.7 5704.6 5907.6 5920.2 5975.4 5980.9 5161.7 5196.4 5260.8 5260.9 5265.8 25528.5 25860.5 25969.0 25982.2 26030.9 2494.4 2543.1 2544.9 2861.8 3138.9 15767.2 15897.0 16142.4 16349.0 16843.7 1879.9 1995.9 1999.1 2006.9 2011.9 110706.3 117126.0 119757.8 121529.8 125804.0 11458.8 11711.2 11851.5 11923.0 12041.7 13154.4 14742.7 15157.2 15280.1 15288.4 8699.8 8750.6 8881.4 8936.4 8952.9 8667.4 8713.8 8713.9 8834.3 8910.5 4585.4 4666.8 4698.5 4698.7 4742.7 1653.0 1674.7 1747.7 1859.0 1979.8 869.6 951.4 993.6 1011.9 1012.9 20341.3 20579.7 21259.6 21390.3 21627.2 947.3 979.2 997.8 1037.5 1064.7 10069.8 11180.0 11363.0 11432.8 11510.7 556207.0 593895.0 604499.0 608329.0 609461.0 122043.0 132424.3 133079.0 133231.0 134339.3 984.6 993.0 1053.9 1055.3 1056.4 19589.8 19698.4 19959.0 19998.7 20075.0 57830.4 58111.0 58371.7 59894.4 60401.4 80486.6 82148.2 85014.0 85160.0 86972.0 6847.9 7374.0 8151.6 8385.4 8400.0 11417.1 12693.2 12712.9 12911.8 12972.9 5447.8 10925.3 11115.1 11415.7 11507.8 10801.8 11075.0 11132.0 11306.2 11513.3 11448.0 11650.1 11676.9 11690.2 11722.9 6925.5 8167.3 8191.9 8512.1 8562.0 10868.8 11266.5 11297.1 11453.6 11474.3 14:15:17 INFO - PROCESS | 1638 | 14:15:17 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5577.77 Median:5067.18 stddev:3034.91 (59.9%) stddev-sans-first:3026.37 14:15:17 INFO - PROCESS | 1638 | Values: 1298.2 1521.1 1669.4 1702.3 1861.8 7699.5 7946.2 8211.3 8623.2 8644.2 8175.1 8400.0 8417.2 8555.7 8599.1 2337.9 2385.3 2395.8 2396.4 2400.8 2401.7 2408.3 2441.8 2445.3 2451.7 2383.0 2394.2 2411.6 2412.3 2414.6 2368.6 2377.6 2395.9 2397.4 2405.0 2392.3 2399.6 2411.2 2413.4 2420.7 8492.2 8536.1 8566.2 8568.4 8627.7 5174.5 5316.8 5322.8 5327.6 5334.7 3459.8 3539.0 3590.3 3595.8 3623.7 1055.8 1085.9 1098.8 1103.5 1110.9 10945.0 11001.3 11018.2 11023.4 11033.6 4522.6 4681.7 4747.8 4755.9 4878.9 9630.2 9635.1 9670.8 9671.9 9679.8 5312.5 5331.9 5335.0 5394.5 5445.2 4426.9 4446.5 4853.6 4908.4 4920.6 3463.3 3877.8 3904.7 3978.1 4006.3 1081.6 1130.9 1285.4 1292.2 1300.4 903.6 917.2 947.2 947.4 972.3 5226.9 5924.5 5946.7 5974.8 6299.9 917.9 945.8 969.1 983.1 1000.7 3832.4 3956.6 3956.8 3957.4 3964.7 10049.8 10340.6 10381.3 10388.1 10413.6 10170.3 10211.3 10234.4 10261.5 10373.8 7527.5 7903.4 7932.4 7946.0 8107.9 5825.3 5867.3 5867.5 5951.6 5955.0 9166.1 9440.7 9480.0 9556.0 9646.8 9445.7 9635.1 9696.9 9744.0 9768.0 7203.4 7453.2 7792.7 7906.8 8026.3 7916.5 8145.9 8153.6 8164.3 8173.3 7015.1 7142.3 7262.7 7269.9 7300.1 6119.9 7044.4 7068.8 7130.8 7213.8 6689.9 6712.0 6800.3 6800.9 6833.7 4889.3 4890.4 4929.4 4955.6 4959.8 4341.0 4777.4 4814.5 4869.4 4888.3 14:15:17 INFO - PROCESS | 1638 | 14:15:17 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4692.86 Median:5197.10 stddev:1473.57 (28.4%) stddev-sans-first:1440.49 14:15:17 INFO - PROCESS | 1638 | Values: 308.4 311.9 318.7 321.5 322.4 4448.1 5319.1 5370.1 5513.4 5698.6 4501.8 4977.7 5478.8 5483.2 5525.0 4197.5 4203.4 4210.9 4223.7 4236.2 3446.9 3982.8 4224.9 4233.3 4263.6 3781.9 3872.1 4155.7 4185.1 4193.3 3995.1 4192.7 4198.5 4211.3 4216.8 3406.3 3525.7 3539.8 3677.2 3688.7 4208.0 4838.1 5331.9 5355.3 5356.5 4070.9 4877.8 4893.4 4906.7 4907.3 5074.7 5627.4 5763.4 5806.1 5874.2 3480.2 4012.9 4063.6 4136.5 4157.8 6161.6 6164.3 6171.2 6176.3 6199.1 3311.8 3545.0 3865.6 3946.9 4119.6 3993.1 4106.8 4175.0 4226.0 4247.2 4880.3 4887.9 4907.9 4914.2 4941.8 5593.8 5866.6 5882.6 5914.9 5988.6 1249.8 1327.4 1344.8 1394.3 1398.6 929.0 940.6 950.4 957.7 961.0 4329.0 4551.3 4779.4 5150.8 5205.8 4938.5 5068.8 5723.5 5757.6 5779.9 4372.0 4396.9 4771.8 5256.4 5295.0 2408.7 2523.8 2760.4 2992.6 3010.5 5188.4 5269.0 5673.4 5740.6 5887.3 5775.4 5784.7 5847.5 5902.0 6165.7 6271.0 6272.4 6289.3 6290.7 6312.9 5880.1 6081.4 6127.8 6129.4 6168.0 5175.4 5889.5 5938.3 5963.3 6011.4 5780.9 5839.8 6273.5 6308.9 6390.7 3863.3 3873.5 3987.6 4010.8 4044.8 5817.3 5984.8 6044.8 6073.0 6080.0 5076.3 5688.8 5757.6 5859.0 5888.9 5444.5 5918.5 5922.3 5929.9 5955.4 4875.6 4905.7 5581.6 5695.7 5772.9 5907.6 5907.7 5913.5 5921.9 5934.8 4020.8 5468.2 5753.5 5885.2 5900.5 14:15:17 INFO - PROCESS | 1638 | 14:15:17 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:21009.95 Median:4415.81 stddev:30811.92 (697.8%) stddev-sans-first:30860.59 14:15:17 INFO - PROCESS | 1638 | Values: 692.9 702.6 769.5 797.5 842.2 14908.1 15103.5 15329.0 15435.9 15533.3 14057.2 14060.5 14149.0 14196.3 14218.6 685.3 832.3 854.4 856.7 856.8 681.3 688.9 822.1 835.9 842.1 660.5 879.4 885.2 908.1 909.5 753.5 870.5 896.7 897.9 900.7 557.0 661.1 668.4 672.3 675.1 14519.0 14611.3 14656.9 14920.7 15265.5 14477.8 14851.1 14933.5 15005.5 15042.4 9425.1 10036.0 11670.1 11776.0 12042.9 4650.4 4720.3 4738.3 4748.6 4766.1 87963.1 91914.0 93599.0 93736.0 93787.0 10206.3 10575.3 10664.9 10754.8 10911.3 20746.9 20995.8 21115.7 21255.8 21353.5 7890.5 7894.0 7918.5 7945.0 8053.3 4203.8 4318.8 4336.6 4402.9 4428.7 3282.1 3369.5 3400.9 3406.3 3416.1 634.9 635.8 642.1 646.2 652.3 597.1 617.3 617.8 650.3 654.3 8626.5 8648.2 8947.9 9136.8 9207.0 559.3 578.9 606.5 634.8 639.3 4285.3 4370.1 4381.5 4392.2 4456.2 60141.0 87201.7 104023.5 117282.4 118994.0 102845.0 104711.0 105831.4 108250.5 108932.5 92273.1 93861.0 97135.0 97591.0 97949.5 8348.0 8591.6 8592.2 8601.7 8758.4 42645.8 47948.0 48843.5 49834.5 55090.0 64571.4 70637.6 76500.2 78525.0 79027.0 12479.2 12799.4 12896.4 12963.5 13039.0 13663.0 14126.6 14138.6 14593.3 14642.8 11861.1 12406.0 13244.2 13890.7 14524.3 14004.5 14133.4 14885.9 14890.6 14944.6 13541.3 13687.9 13858.3 13960.1 14612.6 10501.0 10546.8 11182.8 11221.0 11688.6 11536.5 11590.7 11622.2 11718.4 11787.8 14:15:17 INFO - PROCESS | 1638 | -------- Summary: end -------- 14:15:17 INFO - PROCESS | 1638 | 14:15:18 INFO - TEST-INFO | 1638: exit 0 14:15:18 INFO - TEST-OK | dromaeo_css | took 1115133ms 14:15:18 INFO - TEST-START | kraken 14:15:18 INFO - operating with platform_type : mac_ 14:15:18 INFO - Initialising browser for kraken test... 14:15:18 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpDQr4rk/profile http://localhost:49241/getInfo.html) 14:15:21 INFO - PROCESS | 1650 | 2017-02-01 14:15:21.542 plugin-container[1652:10021] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 14:15:21 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:15:21 INFO - PROCESS | 1650 | 2017-02-01 14:15:21.543 plugin-container[1652:10021] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 14:15:21 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:15:21 INFO - PROCESS | 1650 | 2017-02-01 14:15:21.543 plugin-container[1652:10021] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:15:21 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 14:15:21 INFO - PROCESS | 1650 | colorDepth:24 14:15:21 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 14:15:21 INFO - PROCESS | 1650 | __metrics 14:15:21 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 14:15:22 INFO - TEST-INFO | 1650: exit 0 14:15:22 INFO - Browser initialized. 14:15:22 INFO - Running cycle 1/1 for kraken test... 14:15:22 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpDQr4rk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:15:25 INFO - PROCESS | 1653 | 2017-02-01 14:15:25.276 plugin-container[1654:10149] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 14:15:25 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:15:25 INFO - PROCESS | 1653 | 2017-02-01 14:15:25.277 plugin-container[1654:10149] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 14:15:25 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:15:25 INFO - PROCESS | 1653 | 2017-02-01 14:15:25.277 plugin-container[1654:10149] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:15:34 INFO - PROCESS | 1653 | RSS: Main: 177397760 14:15:34 INFO - PROCESS | 1653 | 14:15:37 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 14:15:38 INFO - PROCESS | 1653 | RSS: Main: 190185472 14:15:38 INFO - PROCESS | 1653 | 14:15:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 14:15:42 INFO - PROCESS | 1653 | RSS: Main: 199163904 14:15:42 INFO - PROCESS | 1653 | 14:15:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 14:15:45 INFO - PROCESS | 1653 | RSS: Main: 202166272 14:15:45 INFO - PROCESS | 1653 | 14:15:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 14:15:48 INFO - PROCESS | 1653 | RSS: Main: 202289152 14:15:48 INFO - PROCESS | 1653 | 14:15:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:15:51 INFO - PROCESS | 1653 | RSS: Main: 203558912 14:15:51 INFO - PROCESS | 1653 | 14:15:53 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 14:15:54 INFO - PROCESS | 1653 | RSS: Main: 207740928 14:15:54 INFO - PROCESS | 1653 | 14:15:57 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 14:15:57 INFO - PROCESS | 1653 | RSS: Main: 207458304 14:15:57 INFO - PROCESS | 1653 | 14:16:00 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 14:16:01 INFO - PROCESS | 1653 | RSS: Main: 206680064 14:16:01 INFO - PROCESS | 1653 | 14:16:03 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:16:03 INFO - PROCESS | 1653 | RSS: Main: 206184448 14:16:03 INFO - PROCESS | 1653 | 14:16:05 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:16:06 INFO - PROCESS | 1653 | RSS: Main: 206200832 14:16:06 INFO - PROCESS | 1653 | 14:16:08 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:16:09 INFO - PROCESS | 1653 | RSS: Main: 206163968 14:16:09 INFO - PROCESS | 1653 | 14:16:12 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:16:12 INFO - PROCESS | 1653 | RSS: Main: 206282752 14:16:12 INFO - PROCESS | 1653 | 14:16:15 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:16:16 INFO - PROCESS | 1653 | RSS: Main: 206594048 14:16:16 INFO - PROCESS | 1653 | 14:16:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 14:16:18 INFO - PROCESS | 1653 | RSS: Main: 208424960 14:16:18 INFO - PROCESS | 1653 | 14:16:18 INFO - PROCESS | 1653 | __start_tp_report 14:16:18 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 14:16:18 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 14:16:18 INFO - PROCESS | 1653 | |i|pagename|runs| 14:16:18 INFO - PROCESS | 1653 | |0;ai-astar;106;118;121;169;147;115;116;118;117;117 14:16:18 INFO - PROCESS | 1653 | |1;audio-beat-detection;153;141;103;177;147;146;116;112;141;109 14:16:18 INFO - PROCESS | 1653 | |2;audio-dft;198;195;197;196;196;195;195;196;200;195 14:16:18 INFO - PROCESS | 1653 | |3;audio-fft;81;58;59;86;63;76;84;87;68;75 14:16:18 INFO - PROCESS | 1653 | |4;audio-oscillator;87;69;89;72;69;70;84;69;69;87 14:16:18 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;89;90;132;86;89;86;90;93;89;87 14:16:18 INFO - PROCESS | 1653 | |6;imaging-darkroom;103;94;99;151;125;95;97;95;95;96 14:16:18 INFO - PROCESS | 1653 | |7;imaging-desaturate;89;92;105;134;99;134;143;119;87;88 14:16:18 INFO - PROCESS | 1653 | |8;json-parse-financial;54;55;55;58;60;60;59;58;58;59 14:16:18 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;66;75;71;78;75;68;68;71;103;102 14:16:18 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;102;102;81;87;89;86;81;95;85;87 14:16:18 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;137;145;162;153;135;140;137;145;203;166 14:16:18 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;162;164;164;174;167;162;162;165;165;166 14:16:18 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;66;68;65;66;66;66;65;68;68;70 14:16:18 INFO - PROCESS | 1653 | __end_tp_report 14:16:18 INFO - PROCESS | 1653 | __start_cc_report 14:16:18 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,910 14:16:18 INFO - PROCESS | 1653 | __end_cc_report 14:16:18 INFO - PROCESS | 1653 | __startTimestamp1485987378186__endTimestamp 14:16:18 INFO - PROCESS | 1653 | 14:16:18 INFO - PROCESS | 1653 | ------- Summary: start ------- 14:16:18 INFO - PROCESS | 1653 | Number of tests: 14 14:16:18 INFO - PROCESS | 1653 | 14:16:18 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:124.40 Median:118.00 stddev:18.86 (16.0%) stddev-sans-first:18.79 14:16:18 INFO - PROCESS | 1653 | Values: 106.0 118.0 121.0 169.0 147.0 115.0 116.0 118.0 117.0 117.0 14:16:18 INFO - PROCESS | 1653 | 14:16:18 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:134.50 Median:143.50 stddev:23.60 (16.4%) stddev-sans-first:24.06 14:16:18 INFO - PROCESS | 1653 | Values: 153.0 141.0 103.0 177.0 147.0 146.0 116.0 112.0 141.0 109.0 14:16:18 INFO - PROCESS | 1653 | 14:16:18 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:196.30 Median:196.00 stddev:1.64 (0.8%) stddev-sans-first:1.62 14:16:18 INFO - PROCESS | 1653 | Values: 198.0 195.0 197.0 196.0 196.0 195.0 195.0 196.0 200.0 195.0 14:16:18 INFO - PROCESS | 1653 | 14:16:18 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:73.70 Median:78.50 stddev:11.08 (14.1%) stddev-sans-first:11.43 14:16:18 INFO - PROCESS | 1653 | Values: 81.0 58.0 59.0 86.0 63.0 76.0 84.0 87.0 68.0 75.0 14:16:18 INFO - PROCESS | 1653 | 14:16:18 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:78.00 stddev:8.95 (11.5%) stddev-sans-first:8.65 14:16:18 INFO - PROCESS | 1653 | Values: 87.0 69.0 89.0 72.0 69.0 70.0 84.0 69.0 69.0 87.0 14:16:18 INFO - PROCESS | 1653 | 14:16:18 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:93.10 Median:89.00 stddev:13.83 (15.5%) stddev-sans-first:14.59 14:16:18 INFO - PROCESS | 1653 | Values: 89.0 90.0 132.0 86.0 89.0 86.0 90.0 93.0 89.0 87.0 14:16:18 INFO - PROCESS | 1653 | 14:16:18 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:105.00 Median:95.00 stddev:18.62 (19.6%) stddev-sans-first:19.74 14:16:18 INFO - PROCESS | 1653 | Values: 103.0 94.0 99.0 151.0 125.0 95.0 97.0 95.0 95.0 96.0 14:16:18 INFO - PROCESS | 1653 | 14:16:18 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:109.00 Median:87.50 stddev:21.69 (24.8%) stddev-sans-first:21.77 14:16:18 INFO - PROCESS | 1653 | Values: 89.0 92.0 105.0 134.0 99.0 134.0 143.0 119.0 87.0 88.0 14:16:18 INFO - PROCESS | 1653 | 14:16:18 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:58.50 stddev:2.17 (3.7%) stddev-sans-first:1.87 14:16:18 INFO - PROCESS | 1653 | Values: 54.0 55.0 55.0 58.0 60.0 60.0 59.0 58.0 58.0 59.0 14:16:18 INFO - PROCESS | 1653 | 14:16:18 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:77.70 Median:71.00 stddev:13.58 (19.1%) stddev-sans-first:13.73 14:16:18 INFO - PROCESS | 1653 | Values: 66.0 75.0 71.0 78.0 75.0 68.0 68.0 71.0 103.0 102.0 14:16:18 INFO - PROCESS | 1653 | 14:16:18 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:86.50 stddev:7.69 (8.9%) stddev-sans-first:6.70 14:16:18 INFO - PROCESS | 1653 | Values: 102.0 102.0 81.0 87.0 89.0 86.0 81.0 95.0 85.0 87.0 14:16:18 INFO - PROCESS | 1653 | 14:16:18 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:152.30 Median:149.00 stddev:20.76 (13.9%) stddev-sans-first:21.27 14:16:18 INFO - PROCESS | 1653 | Values: 137.0 145.0 162.0 153.0 135.0 140.0 137.0 145.0 203.0 166.0 14:16:18 INFO - PROCESS | 1653 | 14:16:18 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.10 Median:165.00 stddev:3.57 (2.2%) stddev-sans-first:3.61 14:16:18 INFO - PROCESS | 1653 | Values: 162.0 164.0 164.0 174.0 167.0 162.0 162.0 165.0 165.0 166.0 14:16:18 INFO - PROCESS | 1653 | 14:16:18 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.62 (2.4%) stddev-sans-first:1.69 14:16:18 INFO - PROCESS | 1653 | Values: 66.0 68.0 65.0 66.0 66.0 66.0 65.0 68.0 68.0 70.0 14:16:18 INFO - PROCESS | 1653 | -------- Summary: end -------- 14:16:18 INFO - PROCESS | 1653 | 14:16:19 INFO - TEST-INFO | 1653: exit 0 14:16:19 INFO - TEST-OK | kraken | took 60643ms 14:16:19 INFO - SUITE-END | took 1175s 14:16:19 INFO - Completed test suite (00:19:35) 14:16:19 INFO - javascript benchmark 14:16:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7445.52558198998, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3516.349475347061, 3526.382882995476, 3526.753127281088, 3569.072041269271, 3576.982115089424, 5185.922211166836, 5348.197770334452, 5973.313068997067, 6066.968615335392, 6073.878522429549, 5993.730282137303, 6041.758329666819, 6071.817845464632, 6118.479929206012, 6118.969405152968, 5338.786448542063, 5411.783528658869, 5443.237946687467, 5471.179323101527, 5534.640248383862, 5517.062099443085, 5518.061929471999, 5520.227168196456, 5525.834224973237, 5581.0, 5513.255710479074, 5514.889702205954, 5515.117581186991, 5515.310586176728, 5524.171374293868, 5328.440513746059, 5358.651687640291, 5433.728313584305, 5473.616846820724, 5476.123820188751, 5265.631405801557, 5397.8110766123, 5399.082156033483, 5420.512153906167, 5437.8368648940595, 5260.789568417259, 6132.540059495555, 6146.17026701394, 6194.442500175006, 6229.750809967596, 6238.376162383771, 6244.344343843922, 6300.936990630081, 6381.712822922979, 6384.425401713822, 6463.062855885917, 6465.191851018622, 6492.67536623167, 6504.739810407578, 6518.185226846645, 6027.21646185993, 6216.471599914058, 6227.314995350508, 6233.1273621693, 6241.906371404432, 5890.8821823563285, 6144.078388241799, 6394.4564712, 6495.642739649365, 6526.967365163144, 4732.645051621143, 5318.760655770499, 6313.589616674902, 6314.0, 6317.810465686037, 5407.432219616962, 6430.582012169194, 6431.453326467303, 6432.549721519449, 6442.033694945796, 6133.1510485532735, 6232.781852635227, 6403.295637479873, 6433.710483028275, 6434.7104380303, 6181.536384771161, 6255.937440625627, 6291.6854157291955, 6304.936950630527, 6426.132472116298, 5224.817131400383, 5228.816991405282, 5242.108841496878, 5251.264822924793, 5269.604779641542, 4057.2494088593703, 4632.976835115803, 4639.953600463952, 4640.0, 4696.953030469652, 4726.527347265246, 6077.908831367533, 6131.529590167202, 6141.723622436912, 6154.5076393888385, 6458.709358078898, 6470.741170353227, 6472.773452929125, 6478.481721462272, 6488.675566221765, 6107.947487155049, 6121.204243448323, 6126.456603435028, 6177.69111544421, 6179.876402471926, 5708.80019199326, 5714.514266287329, 5748.971255143698, 5754.165645981309, 5755.712214389264, 6384.3934826191435, 6484.5460817742305, 6527.869442611026, 6580.703868325841, 6607.504437167136, 6434.131392261985, 6460.903086453613, 6465.935340646533, 6468.514861385509, 6504.6747662615735, 6678.065070890177, 6696.129503164558, 6698.464122870061, 6703.363180497845, 6708.597484150967, 6233.158523599256, 6285.74857005733, 6299.275583307978, 6300.180976473029, 6387.361263873576, 6490.48076153916, 6491.967540162458, 6497.122888410004, 6509.772157974448, 6514.902276465951, 5882.441168089024, 6328.0, 6336.271328797063, 6343.207099112611, 6348.428641422294, 5631.436856314336, 6239.4696450800375, 6315.936840631535, 6322.620642761449, 6339.714712837841, 6052.757889684363, 6136.88672321611, 6177.19696439478, 6256.311805701278, 6275.435210831047, 6255.71849266775, 6294.087357333253, 6305.306416294296, 6310.085037669604, 6322.430981211529, 6109.450149486567, 6232.688365581612, 6245.906311405423, 6280.309165991828, 6295.276043255084], "value": 5904.196858477255, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.20765736534713, 538.5530010091624, 545.1686178577669, 554.2932760730071, 561.696683790753, 39395.818125456244, 41089.79455102728, 41155.794221028926, 41192.17615647685, 41202.763917082535, 42840.57159428405, 43167.78416107923, 43256.56743432573, 43297.0, 43360.56639433605, 9520.761980950463, 9698.90301096987, 9701.223902087851, 9707.320487565874, 9745.561449734743, 9656.613735450608, 9748.732664753572, 9750.46372449515, 9798.167155791767, 9819.06718861707, 9733.02669733028, 9762.365446246007, 9764.316497845119, 9777.413355198676, 9777.657781977632, 9668.226541876667, 9692.51537423126, 9746.0, 9751.707448776548, 9756.90243097567, 8467.772173034937, 9214.769630759218, 9462.763430914214, 9531.571079301379, 9535.47554884481, 40207.59792402068, 40613.0, 40618.796906015574, 40674.79662601668, 41030.58969410328, 2894.073896353168, 3385.5937287525653, 3388.102152929451, 3419.692227699519, 3467.9653203467897, 30057.034503640873, 30792.384152317052, 30893.0732078038, 31036.224094397596, 31747.52378714321, 2789.4560560690675, 3193.786361182759, 3313.9005829825146, 3317.8672853085845, 3355.6644335566493, 472195.0, 532534.3373283108, 537138.0, 539600.0, 541679.2916035434, 63589.0, 63974.36025639777, 64004.67997660075, 64041.0, 64472.677636612454, 66176.66911665411, 66200.0, 66569.66715166393, 66705.66588668297, 66718.66640666766, 16916.746248806277, 17134.742978855327, 17164.914175429043, 17193.7420938686, 17389.304427822997, 13016.609501714964, 13070.0, 13078.869211307765, 13082.0, 13094.803577946339, 16963.74554381685, 18231.27074917015, 19340.226390944204, 19350.0, 19447.513812154528, 7150.749723759747, 7644.579548124907, 7683.193264707237, 7863.803404914808, 7989.840203195903, 889.5774507109071, 1022.8925962773801, 1059.9152067834555, 1092.3118435385657, 1106.5739690219323, 29922.653480593424, 30233.546496802566, 30309.090727278217, 30398.848005759828, 30502.54246186309, 948.2082461144884, 973.1582181413027, 1028.9742756431149, 1100.6037826382496, 1109.6504601050642, 14308.356123974447, 15050.924745376204, 15873.04761714301, 15919.840801591836, 16052.357905683875, 699563.0, 760705.0, 773684.0, 778557.0, 1015115.0, 58879.116813247834, 59914.70042649759, 60026.0, 60028.799424011275, 60054.39945600488, 1217.6894891802533, 1253.1603825436796, 1361.7208472263162, 1400.7968844517488, 1426.9215193164475, 26890.596641050008, 29488.967886123883, 29815.403691926473, 30596.0, 30612.0, 49186.0, 49452.50547494623, 50201.74899125481, 50227.24659130043, 50785.144005558774, 653293.0, 654050.0, 660360.0, 660616.0, 662858.0, 23523.0, 26119.955201792476, 26698.59952100759, 27155.04957326484, 27207.455850883267, 41501.0, 45488.54511454843, 46599.53400465952, 46904.530954691385, 47421.52578474171, 31139.532907006866, 38563.807180965036, 39032.63385781598, 40610.18779624332, 40877.0, 38865.41701874418, 38871.2225755489, 39046.80476597599, 39658.40512392374, 40218.0, 37743.62256377511, 38243.0, 38252.617473826016, 38577.0, 38670.419943700304, 33296.50165742499, 34530.0, 34610.65389346175, 35159.82420087883, 35336.29327413386, 20149.39551813448, 25997.350066248495, 25997.740022599533, 26197.5591342478, 26577.202683919513], "value": 20386.673112832603, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.11896993824, 388.37083924043054, 390.0716295217382, 395.5115432440935, 418.8492142828581, 20044.0, 20903.790962090374, 21202.469938251532, 21215.151393944245, 21794.673079903812, 20935.267265645707, 21044.89477552614, 21084.789152108475, 21086.05112769925, 21635.567288654216, 5810.0, 6017.939820601804, 6045.879082418349, 6083.756649734005, 6135.8772824543485, 5718.1136923776885, 5930.762769489215, 5981.1028345748055, 5986.73059712314, 5998.55010874184, 5977.7608895644125, 5981.671008094553, 5986.580939334249, 5991.490723288525, 6001.729922153491, 5704.600677952566, 5907.556933230004, 5920.230370051909, 5975.372585878464, 5980.9102863456865, 5161.741912904377, 5196.402413722395, 5260.815871444519, 5260.947390526084, 5265.842024739264, 25528.489430211477, 25860.482790344275, 25969.0, 25982.22053338403, 26030.86984565065, 2494.4387512809653, 2543.1353339864536, 2544.910928117507, 2861.799674022819, 3138.8901388451523, 15767.211639418098, 15896.966697164647, 16142.435014774426, 16349.0, 16843.74734378985, 1879.8684092113558, 1995.8503112266492, 1999.1203870297022, 2006.9096890639955, 2011.919523219084, 110706.33940490898, 117126.0, 119757.80242197553, 121529.78470215273, 125804.0, 11458.770824583462, 11711.1802173847, 11851.46668399924, 11922.986546143675, 12041.698957526132, 13154.4080516377, 14742.746866516467, 15157.242137893061, 15280.083195008336, 15288.38846446152, 8699.782505437288, 8750.606222719993, 8881.378303518692, 8936.41913275635, 8952.910470895338, 8667.393282470166, 8713.825723485495, 8713.91286087131, 8834.337424693174, 8910.509921954224, 4585.449746030497, 4666.836660716858, 4698.506656801055, 4698.741569213657, 4742.73914934675, 1652.9586760331088, 1674.6818104560339, 1747.6679430908086, 1859.0240123934773, 1979.7822239553639, 869.6217145541709, 951.4291425144858, 993.5826952679892, 1011.858339832424, 1012.9493525323704, 20341.288054918008, 20579.691304630443, 21259.574808503745, 21390.251341202984, 21627.24304649361, 947.3415975896824, 979.2166266986383, 997.7904640025675, 1037.4553359486179, 1064.685917654294, 10069.848952265722, 11179.993800557988, 11362.97733203999, 11432.828507572392, 11510.712232194095, 556207.0, 593895.0, 604499.0, 608329.0, 609460.9526952336, 122043.0, 132424.33787831, 133079.0, 133231.0, 134339.32830335785, 984.5815528400401, 992.9602815887428, 1053.8682664666917, 1055.3193190392153, 1056.4136904018344, 19589.80410195937, 19698.40904772859, 19959.002049897157, 19998.70001949943, 20075.0, 57830.421695784185, 58111.0, 58371.70814145902, 59894.401055990624, 60401.39598603958, 80486.59756701413, 82148.17851821645, 85014.0, 85160.0, 86972.0, 6847.9385695217325, 7373.963130184315, 8151.5924203790755, 8385.413021088465, 8400.0, 11417.143714221633, 12693.174943628635, 12712.936435318134, 12911.806322904977, 12972.870271297166, 5447.782088716407, 10925.306577480504, 11115.110071381338, 11415.657530274106, 11507.827382589101, 10801.837972430578, 11075.0, 11132.0, 11306.208565400671, 11513.251638643462, 11448.026917711755, 11650.126240531825, 11676.88323116758, 11690.18168728215, 11722.94138529302, 6925.549839260432, 8167.346612270885, 8191.8771218430575, 8512.148785121439, 8561.957190214009, 10868.782624347627, 11266.493007814504, 11297.096232301561, 11453.59912403062, 11474.254173478697], "value": 9915.722975421493, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1298.2405292903652, 1521.132954216097, 1669.4240487031977, 1702.3190723710522, 1861.8324350808432, 7699.499532530382, 7946.205379462051, 8211.260986511214, 8623.223909848113, 8644.222020018196, 8175.059868115169, 8400.033996090451, 8417.200365965224, 8555.657773689045, 8599.05410404855, 2337.9298621041353, 2385.3082606044227, 2395.8203134764876, 2396.436837343223, 2400.7839294463497, 2401.7238017628024, 2408.313630615275, 2441.7558244175616, 2445.2786428003783, 2451.6812814334203, 2383.0, 2394.1740099665676, 2411.6382542618658, 2412.3245491262473, 2414.601590737535, 2368.561816064025, 2377.6314671225987, 2395.880205989702, 2397.424618091663, 2404.97595024051, 2392.342105920886, 2399.616061430163, 2411.2284069097896, 2413.444907671228, 2420.673209116764, 8492.193241642035, 8536.146385361477, 8566.186212309884, 8568.357373196974, 8627.6980305689, 5174.534291913708, 5316.760745766411, 5322.813701520467, 5327.627066105374, 5334.653247538937, 3459.844307006191, 3538.964610353915, 3590.281943611288, 3595.7842529448053, 3623.655752703489, 1055.7730088031033, 1085.913126949842, 1098.8406681031206, 1103.47584897174, 1110.8833572474934, 10945.01494865458, 11001.284916480483, 11018.173636977259, 11023.44882755859, 11033.558657653684, 4522.638188944877, 4681.695689780154, 4747.8100875965265, 4755.881102972453, 4878.878028049327, 9630.229581633452, 9635.084666956627, 9670.806583868423, 9671.854922176173, 9679.758006049906, 5312.548433383128, 5331.893362132813, 5334.973325133427, 5394.541463975606, 5445.237666726662, 4426.911461770746, 4446.4886538047895, 4853.587445067138, 4908.410990681141, 4920.630952678492, 3463.2900255448076, 3877.8254978526033, 3904.668103211202, 3978.0850404407147, 4006.338954072604, 1081.6484642491064, 1130.886911308879, 1285.3637449462576, 1292.2311224821217, 1300.4148133340034, 903.5708038681705, 917.1928702741545, 947.209080417845, 947.4078700811992, 972.3048020665117, 5226.92159617606, 5924.4667979882015, 5946.672932988728, 5974.790882319098, 6299.937000630027, 917.9443639814267, 945.8486642137225, 969.1084202533686, 983.1446641422009, 1000.674780696262, 3832.3676593362343, 3956.5647778744315, 3956.7625942443547, 3957.445957565943, 3964.702647301406, 10049.799004019878, 10340.638077667396, 10381.273310868166, 10388.065074143364, 10413.635522756667, 10170.338927969658, 10211.285210035227, 10234.385936843813, 10261.486925653688, 10373.792524149474, 7527.548347099191, 7903.446758726834, 7932.405069619802, 7946.046474423106, 8107.878381824277, 5825.300963884361, 5867.266591676041, 5867.501262392744, 5951.583389162717, 5955.0, 9166.08339166078, 9440.669576564787, 9480.0, 9555.952220238854, 9646.807063858543, 9445.716628501155, 9635.084666956627, 9696.85454718194, 9744.0, 9767.951160244153, 7203.351698347174, 7453.217412171861, 7792.727254546064, 7906.841863162589, 8026.317762990211, 7916.525008499509, 8145.877811832946, 8153.592320384076, 8164.2652161303395, 8173.346132309282, 7015.088038554955, 7142.321479459443, 7262.709491620276, 7269.854602908018, 7300.087489063867, 6119.877602448015, 7044.436445084279, 7068.787936361918, 7130.750423735352, 7213.819654508469, 6689.899651505329, 6711.966440167768, 6800.28596997328, 6800.863982720417, 6833.69248383814, 4889.315495830658, 4890.437599676083, 4929.408470983505, 4955.578775804097, 4959.82640607577, 4340.956590434055, 4777.4267087949665, 4814.494478079751, 4869.4400143982475, 4888.315635810918], "value": 4533.816619741157, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.4263270317211, 311.9485284927987, 318.697237624257, 321.54501380546526, 322.3552894211577, 4448.132614140244, 5319.0957537218665, 5370.133503877202, 5513.443828730471, 5698.632328241229, 4501.797419116139, 4977.67645103068, 5478.835634930959, 5483.232347471358, 5525.0, 4197.496300443951, 4203.369494575808, 4210.936835947463, 4223.7043406961375, 4236.195122926634, 3446.914616656924, 3982.7610343379347, 4224.922644725606, 4233.301505251645, 4263.594958478955, 3781.8676346327884, 3872.1287710265246, 4155.6883233757435, 4185.142045880587, 4193.308104162825, 3995.1410446753944, 4192.748435093874, 4198.538160802309, 4211.284081706117, 4216.810243539048, 3406.267652454734, 3525.6954926677295, 3539.752217344787, 3677.191017976041, 3688.686461650763, 4208.0, 4838.129136755382, 5331.866703332409, 5355.303810504648, 5356.544693701001, 4070.877873663765, 4877.756112194376, 4893.434101087653, 4906.719399169034, 4907.2639104134305, 5074.695518268915, 5627.380988091307, 5763.394843541451, 5806.071028635398, 5874.23634927461, 3480.207693038087, 4012.8996775080564, 4063.6342729154517, 4136.531531306403, 4157.792110394468, 6161.552035271708, 6164.291106522763, 6171.166892469549, 6176.289726681444, 6199.101130336076, 3311.817850018272, 3545.014791716647, 3865.555461121951, 3946.881593552198, 4119.588041195856, 3993.101552150743, 4106.843906948876, 4175.0, 4225.9577404226175, 4247.193033323659, 4880.267959806058, 4887.902241955141, 4907.938665760389, 4914.213725803854, 4941.813964648459, 5593.804216852391, 5866.6480011199465, 5882.588218824641, 5914.940850591525, 5988.550858685616, 1249.8375211222483, 1327.4159369877416, 1344.7713888638955, 1394.3307212538039, 1398.6153807702963, 928.9907100929039, 940.6378544260372, 950.3632566180537, 957.7318350861769, 960.9759756006156, 4329.004329004284, 4551.340055691906, 4779.378680771478, 5150.819721309811, 5205.817796377109, 4938.456769755324, 5068.797248110108, 5723.54211663066, 5757.568182386254, 5779.913301300484, 4371.956280437218, 4396.890077748018, 4771.761411929389, 5256.395514515803, 5294.97352513235, 2408.674828898111, 2523.785478234334, 2760.419008227314, 2992.5660779186896, 3010.4882170031146, 5188.377394712658, 5268.998890210924, 5673.404292549306, 5740.6268592541355, 5887.263257339096, 5775.364709881909, 5784.739686714108, 5847.473727364558, 5901.970490147522, 6165.687867049856, 6271.0, 6272.40412160844, 6289.3458840456815, 6290.748370065146, 6312.936870631327, 5880.14925149479, 6081.35118928657, 6127.816165515042, 6129.417705317987, 6167.969160154171, 5175.353080864892, 5889.469947704727, 5938.317093534299, 5963.344032156372, 6011.398860113954, 5780.855478613068, 5839.832033593383, 6273.518197400304, 6308.936910631019, 6390.712417941297, 3863.34323165057, 3873.5158105236846, 3987.641112299907, 4010.779407132519, 4044.757314561136, 5817.254823552833, 5984.820455386345, 6044.848878778066, 6073.0, 6080.027195648675, 5076.314697515788, 5688.757408277408, 5757.625754325955, 5859.0, 5888.941110588839, 5444.455554444524, 5918.496927761188, 5922.3485416605, 5929.851753706192, 5955.434233747787, 4875.561199492063, 4905.724511626931, 5581.609287349846, 5695.658260504383, 5772.913406298825, 5907.556933229939, 5907.700305932688, 5913.497352725067, 5921.927325436063, 5934.762609495572, 4020.8391664334267, 5468.179773034077, 5753.453421924911, 5885.231983219332, 5900.468957793819], "value": 4225.610058714659, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.9341712537307, 702.5749421599928, 769.5344316688403, 797.4856217739558, 842.1830824100622, 14908.105513669187, 15103.54689359321, 15329.0, 15435.922820385897, 15533.301001454933, 14057.226852523107, 14060.507882224125, 14149.009569330152, 14196.290185490736, 14218.644533886658, 685.2633419074515, 832.2717622080679, 854.3848429131016, 856.6873091321654, 856.7772379181396, 681.3016657925616, 688.8691148681735, 822.0915887943826, 835.8829763833069, 842.1031601344548, 660.4617236951868, 879.3580686099151, 885.1812073831712, 908.1327332397577, 909.4725059465494, 753.5252790741825, 870.5299138465222, 896.7175339767984, 897.9191872731485, 900.7207765592688, 556.9721513924328, 661.0909998751716, 668.421815129914, 672.2806596941275, 675.1358261425383, 14519.0, 14611.269436528131, 14656.853431465763, 14920.701585968329, 15265.542033739006, 14477.782833257404, 14851.108933464027, 14933.477328293458, 15005.474808381763, 15042.398304067825, 9425.057494250588, 10036.0, 11670.066394688405, 11776.0, 12042.87957120435, 4650.372199753024, 4720.291956206562, 4738.312944623045, 4748.596369308613, 4766.094442056033, 87963.12036879678, 91914.0, 93598.99999999932, 93736.0, 93787.0, 10206.285560010729, 10575.312604680672, 10664.89335106639, 10754.78490430187, 10911.290766100177, 20746.896265518877, 20995.79004209969, 21115.683264750733, 21255.78744212538, 21353.46616334573, 7890.5265684057995, 7894.0, 7918.485298455582, 7945.046594408708, 8053.275205231557, 4203.789810509462, 4318.78406079701, 4336.631386332134, 4402.933955990663, 4428.689991700615, 3282.1302354875975, 3369.5451114099283, 3400.9149771255916, 3406.267652454734, 3416.0776590320474, 634.9492040636738, 635.8092572228405, 642.1235014205575, 646.1664452855857, 652.2759736692268, 597.1103056445864, 617.2839506172818, 617.7683368736724, 650.3171669746747, 654.2508827392609, 8626.482411055356, 8648.16249031901, 8947.865782013276, 9136.771580710536, 9207.033261507579, 559.3315987395024, 578.8697543052864, 606.5390303369383, 634.7810005548079, 639.2712307968919, 4285.271503844354, 4370.125974804988, 4381.496127945264, 4392.165488557164, 4456.242438785414, 60141.0, 87201.69197462044, 104023.4798826001, 117282.41358793322, 118994.0, 102845.0, 104711.0, 105831.41252881213, 108250.45874770734, 108932.45533772439, 92273.0772692278, 93861.0, 97135.02864971402, 97591.0, 97949.51025244828, 8348.0, 8591.613377398156, 8592.226699596817, 8601.741947741577, 8758.430702004349, 42645.78677106595, 47948.0, 48843.5115648839, 49834.50496485111, 55090.0, 64571.38571535749, 70637.58724825578, 76500.23499764931, 78525.0, 79027.0, 12479.188852724545, 12799.360031998549, 12896.41966111546, 12963.546275880677, 13039.0, 13663.0, 14126.646833829236, 14138.575842724735, 14593.270336483345, 14642.780358294847, 11861.110416718608, 12406.007519398247, 13244.20534767917, 13890.747156850035, 14524.346404412037, 14004.509842155476, 14133.363998619881, 14885.85114148888, 14890.553283401516, 14944.551663450115, 13541.255230962392, 13687.931560342135, 13858.307084645527, 13960.092593981359, 14612.561623151323, 10501.0, 10546.789064218827, 11182.832257516306, 11221.0, 11688.590899318484, 11536.538538458368, 11590.652280431601, 11622.244554103956, 11718.355490447766, 11787.764244715228], "value": 7449.749803868795, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1521.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 121.0, 169.0, 147.0, 115.0, 116.0, 118.0, 117.0, 117.0], "value": 124.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 141.0, 103.0, 177.0, 147.0, 146.0, 116.0, 112.0, 141.0, 109.0], "value": 134.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 195.0, 197.0, 196.0, 196.0, 195.0, 195.0, 196.0, 200.0, 195.0], "value": 196.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 59.0, 86.0, 63.0, 76.0, 84.0, 87.0, 68.0, 75.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 89.0, 72.0, 69.0, 70.0, 84.0, 69.0, 69.0, 87.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 132.0, 86.0, 89.0, 86.0, 90.0, 93.0, 89.0, 87.0], "value": 93.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 94.0, 99.0, 151.0, 125.0, 95.0, 97.0, 95.0, 95.0, 96.0], "value": 105.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 105.0, 134.0, 99.0, 134.0, 143.0, 119.0, 87.0, 88.0], "value": 109.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 58.0, 60.0, 60.0, 59.0, 58.0, 58.0, 59.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 75.0, 71.0, 78.0, 75.0, 68.0, 68.0, 71.0, 103.0, 102.0], "value": 77.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 102.0, 81.0, 87.0, 89.0, 86.0, 81.0, 95.0, 85.0, 87.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 145.0, 162.0, 153.0, 135.0, 140.0, 137.0, 145.0, 203.0, 166.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 164.0, 174.0, 167.0, 162.0, 162.0, 165.0, 165.0, 166.0], "value": 165.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 65.0, 66.0, 66.0, 66.0, 65.0, 68.0, 68.0, 70.0], "value": 66.8, "unit": "score"}]}]} 14:16:19 INFO - Return code: 0 14:16:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:16:19 ERROR - # TBPL SUCCESS # 14:16:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:16:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:16:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:16:19 INFO - 2017-02-01 14:16:19.518 screenresolution[1658:10525] starting screenresolution argv=screenresolution get 14:16:19 INFO - 2017-02-01 14:16:19.521 screenresolution[1658:10525] Display 0: 1600x1200x32@60 14:16:19 INFO - 2017-02-01 14:16:19.528 screenresolution[1659:10528] starting screenresolution argv=screenresolution list 14:16:19 INFO - Available Modes on Display 0 14:16:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:16:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:16:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:16:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:16:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:16:19 INFO - Intel Iris: 14:16:19 INFO - Chipset Model: Intel Iris 14:16:19 INFO - Type: GPU 14:16:19 INFO - Bus: Built-In 14:16:19 INFO - VRAM (Dynamic, Max): 1536 MB 14:16:19 INFO - Vendor: Intel (0x8086) 14:16:19 INFO - Device ID: 0x0a2e 14:16:19 INFO - Revision ID: 0x0009 14:16:19 INFO - Displays: 14:16:19 INFO - Display: 14:16:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:16:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:16:19 INFO - Display Serial Number: 1600x1200 60 14:16:19 INFO - Main Display: Yes 14:16:19 INFO - Mirror: Off 14:16:19 INFO - Online: Yes 14:16:19 INFO - Rotation: Supported 14:16:19 INFO - Return code: 0 14:16:19 INFO - Running post-action listener: _resource_record_post_action 14:16:19 INFO - [mozharness: 2017-02-01 22:16:19.776448Z] Finished run-tests step (success) 14:16:19 INFO - Running post-run listener: _resource_record_post_run 14:16:19 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 40278016; Write bytes: 502901760; Read time: 388; Write time: 2041 14:16:19 INFO - TinderboxPrint: CPU usage
54.1% 14:16:19 INFO - TinderboxPrint: I/O read bytes / time
40,278,016 / 388 14:16:19 INFO - TinderboxPrint: I/O write bytes / time
502,901,760 / 2,041 14:16:19 INFO - TinderboxPrint: CPU idle
2,198.7 (45.9%) 14:16:19 INFO - TinderboxPrint: CPU system
964.6 (20.1%) 14:16:19 INFO - TinderboxPrint: CPU user
1,630.3 (34.0%) 14:16:19 INFO - TinderboxPrint: Swap in / out
232,185,856 / 0 14:16:19 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 192500224; Write bytes: 194500608; Read time: 18074; Write time: 510 14:16:19 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35760128; Write bytes: 307020800; Read time: 355; Write time: 1527 14:16:20 INFO - Running post-run listener: _upload_blobber_files 14:16:20 INFO - Blob upload gear active. 14:16:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:16:20 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 14:16:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:16:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:16:20 INFO - (blobuploader) - INFO - Open directory for files ... 14:16:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:16:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:16:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:16:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:16:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:16:21 INFO - (blobuploader) - INFO - Done attempting. 14:16:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:16:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:16:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:16:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:16:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:16:22 INFO - (blobuploader) - INFO - Done attempting. 14:16:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:16:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:16:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:16:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:16:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:16:24 INFO - (blobuploader) - INFO - Done attempting. 14:16:24 INFO - (blobuploader) - INFO - Iteration through files over. 14:16:24 INFO - Return code: 0 14:16:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:16:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:16:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec6d4cd75cde1000595e2285e31d27ba9ea834f0edf19480e622883a4685d4a02e703621d9e6470ff78bd039ef71b364051b2ba8316a2f7b22f2c2172122775a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f980f93747fc176f8b9abca47dab5a3dc794c948967c0a7b0ebeea35d607df43a0d29ab2a962f398b9fe2f6cbfa27d45bae8c0dd255eb95e166d7dbfa056231a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30033f6f53f2e7412cd457c60425f254ed19e62c9da1465de78c96b9eb978856539693f2ec67a897dae5748cde52a7b1546ad082d8bc7cd26ae8bfb788a1b3b7"} 14:16:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:16:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:16:24 INFO - Contents: 14:16:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec6d4cd75cde1000595e2285e31d27ba9ea834f0edf19480e622883a4685d4a02e703621d9e6470ff78bd039ef71b364051b2ba8316a2f7b22f2c2172122775a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f980f93747fc176f8b9abca47dab5a3dc794c948967c0a7b0ebeea35d607df43a0d29ab2a962f398b9fe2f6cbfa27d45bae8c0dd255eb95e166d7dbfa056231a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30033f6f53f2e7412cd457c60425f254ed19e62c9da1465de78c96b9eb978856539693f2ec67a897dae5748cde52a7b1546ad082d8bc7cd26ae8bfb788a1b3b7"} 14:16:24 INFO - Running post-run listener: copy_logs_to_upload_dir 14:16:24 INFO - Copying logs to upload dir... 14:16:24 INFO - mkdir: /builds/slave/test/build/upload/logs 14:16:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.575034 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-02-01 14:16:24.954755) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-01 14:16:24.958832) ========= 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.01AjV2DU28/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.BiWfWMpNue/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec6d4cd75cde1000595e2285e31d27ba9ea834f0edf19480e622883a4685d4a02e703621d9e6470ff78bd039ef71b364051b2ba8316a2f7b22f2c2172122775a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f980f93747fc176f8b9abca47dab5a3dc794c948967c0a7b0ebeea35d607df43a0d29ab2a962f398b9fe2f6cbfa27d45bae8c0dd255eb95e166d7dbfa056231a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30033f6f53f2e7412cd457c60425f254ed19e62c9da1465de78c96b9eb978856539693f2ec67a897dae5748cde52a7b1546ad082d8bc7cd26ae8bfb788a1b3b7"} build_url:https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011060 build_url: 'https://queue.taskcluster.net/v1/task/FeoC8OxcSrGf2t8qp0JeeQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec6d4cd75cde1000595e2285e31d27ba9ea834f0edf19480e622883a4685d4a02e703621d9e6470ff78bd039ef71b364051b2ba8316a2f7b22f2c2172122775a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f980f93747fc176f8b9abca47dab5a3dc794c948967c0a7b0ebeea35d607df43a0d29ab2a962f398b9fe2f6cbfa27d45bae8c0dd255eb95e166d7dbfa056231a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30033f6f53f2e7412cd457c60425f254ed19e62c9da1465de78c96b9eb978856539693f2ec67a897dae5748cde52a7b1546ad082d8bc7cd26ae8bfb788a1b3b7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-01 14:16:24.993414) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 14:16:24.993817) ========= 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.01AjV2DU28/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.BiWfWMpNue/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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.005007 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 14:16:25.042339) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-01 14:16:25.042652) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-01 14:16:25.042988) ========= ========= Total master_lag: 0.18 =========