builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0220 starttime: 1483724784.2 results: success (0) buildid: 20170106071356 builduid: 5b954d9de179456985eca0e3652c1008 revision: d77136af6dd5f7f80bb602decfe929204c30439f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-06 09:46:24.204867) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-06 09:46:24.205291) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-06 09:46:24.205577) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-06 09:46:24.230234) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 09:46:24.230510) ========= 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.dWczegeiMR/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.innJe5PzNK/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-06 09:46:24-- 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% 8.13M=0.001s 2017-01-06 09:46:24 (8.13 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.625223 ========= master_lag: 0.25 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 09:46:25.100888) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 09:46:25.101193) ========= 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.dWczegeiMR/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.innJe5PzNK/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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.019240 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 09:46:25.144216) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-06 09:46:25.144541) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d77136af6dd5f7f80bb602decfe929204c30439f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d77136af6dd5f7f80bb602decfe929204c30439f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dWczegeiMR/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.innJe5PzNK/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-06 09:46:25,226 truncating revision to first 12 chars 2017-01-06 09:46:25,227 Setting DEBUG logging. 2017-01-06 09:46:25,227 attempt 1/10 2017-01-06 09:46:25,227 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d77136af6dd5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-06 09:46:26,601 unpacking tar archive at: mozilla-aurora-d77136af6dd5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.632993 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-06 09:46:26.806519) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-06 09:46:26.806807) ========= script_repo_revision: d77136af6dd5f7f80bb602decfe929204c30439f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-06 09:46:26.807194) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-06 09:46:26.807456) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-06 09:46:26.826714) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-01-06 09:46:26.826984) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dWczegeiMR/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.innJe5PzNK/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 09:46:27 INFO - MultiFileLogger online at 20170106 09:46:27 in /builds/slave/test 09:46:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:46:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:46:27 INFO - {'append_to_log': False, 09:46:27 INFO - 'base_work_dir': '/builds/slave/test', 09:46:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:46:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:46:27 INFO - 'branch': 'Mozilla-Aurora', 09:46:27 INFO - 'buildbot_json_path': 'buildprops.json', 09:46:27 INFO - 'config_files': ('talos/mac_config.py',), 09:46:27 INFO - 'default_actions': ('clobber', 09:46:27 INFO - 'read-buildbot-config', 09:46:27 INFO - 'download-and-extract', 09:46:27 INFO - 'populate-webroot', 09:46:27 INFO - 'create-virtualenv', 09:46:27 INFO - 'install', 09:46:27 INFO - 'run-tests'), 09:46:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:46:27 INFO - 'download_minidump_stackwalk': True, 09:46:27 INFO - 'download_symbols': 'ondemand', 09:46:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:46:27 INFO - 'tooltool.py': '/tools/tooltool.py', 09:46:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:46:27 INFO - '/tools/misc-python/virtualenv.py')}, 09:46:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:46:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:46:27 INFO - 'installer_path': 'installer.exe', 09:46:27 INFO - 'log_level': 'info', 09:46:27 INFO - 'log_name': 'talos', 09:46:27 INFO - 'log_to_console': True, 09:46:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:46:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:46:27 INFO - 'opt_config_files': (), 09:46:27 INFO - 'pip_index': False, 09:46:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:46:27 INFO - 'cmd': ('bash', 09:46:27 INFO - '-c', 09:46:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:46:27 INFO - 'enabled': True, 09:46:27 INFO - 'halt_on_failure': False, 09:46:27 INFO - 'name': 'check_screen_resolution'},), 09:46:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:46:27 INFO - 'cmd': ('bash', 09:46:27 INFO - '-c', 09:46:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:46:27 INFO - 'enabled': True, 09:46:27 INFO - 'halt_on_failure': False, 09:46:27 INFO - 'name': 'check_screen_resolution'},), 09:46:27 INFO - 'run_cmd_checks_enabled': True, 09:46:27 INFO - 'sps_profile': False, 09:46:27 INFO - 'sps_profile_interval': 0, 09:46:27 INFO - 'suite': 'dromaeojs-e10s', 09:46:27 INFO - 'system_bits': '32', 09:46:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:46:27 INFO - 'title': 't-yosemite-r7-0220', 09:46:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:46:27 INFO - 'use_talos_json': True, 09:46:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:46:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:46:27 INFO - 'work_dir': 'build'} 09:46:27 INFO - [mozharness: 2017-01-06 17:46:27.026137Z] Running clobber step. 09:46:27 INFO - Running pre-action listener: _resource_record_pre_action 09:46:27 INFO - Running main action method: clobber 09:46:27 INFO - rmtree: /builds/slave/test/build 09:46:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:46:28 INFO - Running post-action listener: _resource_record_post_action 09:46:28 INFO - [mozharness: 2017-01-06 17:46:28.486943Z] Finished clobber step (success) 09:46:28 INFO - [mozharness: 2017-01-06 17:46:28.487065Z] Running read-buildbot-config step. 09:46:28 INFO - Running pre-action listener: _resource_record_pre_action 09:46:28 INFO - Running main action method: read_buildbot_config 09:46:28 INFO - Using buildbot properties: 09:46:28 INFO - { 09:46:28 INFO - "project": "", 09:46:28 INFO - "product": "firefox", 09:46:28 INFO - "script_repo_revision": "production", 09:46:28 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:46:28 INFO - "repository": "", 09:46:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:46:28 INFO - "buildid": "20170106071356", 09:46:28 INFO - "pgo_build": "False", 09:46:28 INFO - "basedir": "/builds/slave/test", 09:46:28 INFO - "buildnumber": 24, 09:46:28 INFO - "slavename": "t-yosemite-r7-0220", 09:46:28 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:46:28 INFO - "platform": "yosemite_r7", 09:46:28 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:46:28 INFO - "branch": "mozilla-aurora", 09:46:28 INFO - "repo_path": "releases/mozilla-aurora", 09:46:28 INFO - "revision": "d77136af6dd5f7f80bb602decfe929204c30439f", 09:46:28 INFO - "stage_platform": "macosx64", 09:46:28 INFO - "builduid": "5b954d9de179456985eca0e3652c1008", 09:46:28 INFO - "slavebuilddir": "test" 09:46:28 INFO - } 09:46:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:46:28 INFO - Running post-action listener: _resource_record_post_action 09:46:28 INFO - [mozharness: 2017-01-06 17:46:28.490454Z] Finished read-buildbot-config step (success) 09:46:28 INFO - [mozharness: 2017-01-06 17:46:28.490572Z] Running download-and-extract step. 09:46:28 INFO - Running pre-action listener: _resource_record_pre_action 09:46:28 INFO - Running main action method: download_and_extract 09:46:28 INFO - mkdir: /builds/slave/test/build/tests 09:46:28 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')]} 09:46:28 INFO - trying https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:46:28 INFO - Downloading https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:46:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 09:46:29 INFO - Downloaded 1199 bytes. 09:46:29 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:46:29 INFO - Using the following test package requirements: 09:46:29 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:46:29 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:46:29 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:46:29 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:46:29 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:46:29 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:46:29 INFO - u'jsshell-mac.zip'], 09:46:29 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:46:29 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:46:29 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:46:29 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:46:29 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:46:29 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:46:29 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:46:29 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:46:29 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:46:29 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:46:29 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:46:29 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:46:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:46:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:46:29 INFO - Fetch https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:46:30 INFO - Http code: 200 09:46:30 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 09:46:30 INFO - x-amz-cf-id: NeihCn8QbKfCVqTtoNilM9S4CPVk3HQ-qrsjMu5dbeXPqy0oIywYnQ== 09:46:30 INFO - x-amz-version-id: 9gZaTgoWMGXPVo2SUzMiOqXuJrELp_UO 09:46:30 INFO - Expected file size: 16269516 09:46:30 INFO - Obtained file size: 16269516 09:46:37 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:46:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:46:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:46:37 INFO - Fetch https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:46:38 INFO - Http code: 200 09:46:38 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 09:46:38 INFO - x-amz-cf-id: ATi-fjfiN6La1FKLX30cCCjhql48N9CPgNuNHYmqSLnGyiLpEUgbfg== 09:46:38 INFO - x-amz-version-id: 9gZaTgoWMGXPVo2SUzMiOqXuJrELp_UO 09:46:38 INFO - Expected file size: 16269516 09:46:38 INFO - Obtained file size: 16269516 09:46:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:46:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:46:44 INFO - Fetch https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:46:45 INFO - Http code: 200 09:46:45 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 09:46:45 INFO - x-amz-cf-id: v3uzz9pWWfotjjlEC9u-kMV6m-xKljb7LwD0MIK0K_nvo7uFiAfKWQ== 09:46:45 INFO - x-amz-version-id: FCGOSxVIqOxSFJLpOM.0FVsiVvJONxCu 09:46:46 INFO - Expected file size: 14409038 09:46:46 INFO - Obtained file size: 14409038 09:46:46 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')]} 09:46:46 INFO - trying https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:46:46 INFO - Downloading https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:46:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 09:46:49 INFO - Downloaded 109867650 bytes. 09:46:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:46:49 INFO - mkdir: /builds/slave/test/properties 09:46:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:46:49 INFO - Writing to file /builds/slave/test/properties/build_url 09:46:49 INFO - Contents: 09:46:49 INFO - build_url:https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:46:49 INFO - Running post-action listener: _resource_record_post_action 09:46:49 INFO - Running post-action listener: set_extra_try_arguments 09:46:49 INFO - [mozharness: 2017-01-06 17:46:49.829757Z] Finished download-and-extract step (success) 09:46:49 INFO - [mozharness: 2017-01-06 17:46:49.829914Z] Running populate-webroot step. 09:46:49 INFO - Running pre-action listener: _resource_record_pre_action 09:46:49 INFO - Running main action method: populate_webroot 09:46:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:46:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:46:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:46:49 INFO - '--tppagecycles', 09:46:49 INFO - '7'], 09:46:49 INFO - 'tests': ['tsvgm']}}, 09:46:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:46:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:46:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:46:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:46:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:46:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:46:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:49 INFO - 'talos_options': ['--disable-e10s'], 09:46:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:46:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:46:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:49 INFO - 'talos_options': ['--disable-e10s'], 09:46:49 INFO - 'tests': ['damp', 'tps']}, 09:46:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:49 INFO - 'tests': ['damp', 'tps']}, 09:46:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:46:49 INFO - 'tests': ['dromaeo_dom']}, 09:46:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:46:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:46:49 INFO - 'tests': ['basic_compositor_video']}, 09:46:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:46:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:46:49 INFO - 'tests': ['a11yr', 09:46:49 INFO - 'ts_paint', 09:46:49 INFO - 'tpaint', 09:46:49 INFO - 'sessionrestore', 09:46:49 INFO - 'sessionrestore_no_auto_restore', 09:46:49 INFO - 'tabpaint']}, 09:46:49 INFO - 'other-e10s': {'tests': ['a11yr', 09:46:49 INFO - 'ts_paint', 09:46:49 INFO - 'tpaint', 09:46:49 INFO - 'sessionrestore', 09:46:49 INFO - 'sessionrestore_no_auto_restore', 09:46:49 INFO - 'tabpaint']}, 09:46:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:46:49 INFO - 'ts_paint', 09:46:49 INFO - 'tpaint', 09:46:49 INFO - 'sessionrestore', 09:46:49 INFO - 'sessionrestore_no_auto_restore', 09:46:49 INFO - 'tabpaint']}, 09:46:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:46:49 INFO - 'ts_paint', 09:46:49 INFO - 'tpaint', 09:46:49 INFO - 'sessionrestore', 09:46:49 INFO - 'sessionrestore_no_auto_restore', 09:46:49 INFO - 'tabpaint']}, 09:46:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:46:49 INFO - 'tests': ['a11yr', 09:46:49 INFO - 'ts_paint', 09:46:49 INFO - 'tpaint', 09:46:49 INFO - 'sessionrestore', 09:46:49 INFO - 'sessionrestore_no_auto_restore', 09:46:49 INFO - 'tabpaint']}, 09:46:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:46:49 INFO - 'tests': ['a11yr', 09:46:49 INFO - 'ts_paint', 09:46:49 INFO - 'tpaint', 09:46:49 INFO - 'sessionrestore', 09:46:49 INFO - 'sessionrestore_no_auto_restore', 09:46:49 INFO - 'tabpaint']}, 09:46:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:46:49 INFO - 'tests': ['tsvgx', 09:46:49 INFO - 'tsvgr_opacity', 09:46:49 INFO - 'tart', 09:46:49 INFO - 'tscrollx', 09:46:49 INFO - 'cart']}, 09:46:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:46:49 INFO - 'tsvgr_opacity', 09:46:49 INFO - 'tart', 09:46:49 INFO - 'tscrollx', 09:46:49 INFO - 'cart']}, 09:46:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:49 INFO - 'talos_options': ['--disable-e10s'], 09:46:49 INFO - 'tests': ['tp5o']}, 09:46:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:49 INFO - 'tests': ['tp5o']}, 09:46:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:49 INFO - 'talos_options': ['--disable-e10s', 09:46:49 INFO - '--xperf_path', 09:46:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:46:49 INFO - 'tests': ['tp5n']}, 09:46:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:49 INFO - 'talos_options': ['--xperf_path', 09:46:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:46:49 INFO - 'tests': ['tp5n']}}, 09:46:49 INFO - 'talos.zip': {'path': '', 09:46:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:46:49 INFO - Running post-action listener: _resource_record_post_action 09:46:49 INFO - [mozharness: 2017-01-06 17:46:49.848401Z] Finished populate-webroot step (success) 09:46:49 INFO - [mozharness: 2017-01-06 17:46:49.848510Z] Running create-virtualenv step. 09:46:49 INFO - Running pre-action listener: _resource_record_pre_action 09:46:49 INFO - Running main action method: create_virtualenv 09:46:49 INFO - Creating virtualenv /builds/slave/test/build/venv 09:46:49 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 09:46:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:46:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:46:49 INFO - Using real prefix '/tools/python27' 09:46:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:46:50 INFO - Installing distribute.............................................................................................................................................................................................done. 09:46:53 INFO - Installing pip.................done. 09:46:53 INFO - Return code: 0 09:46:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:46:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:46:53 INFO - Reading from file tmpfile_stdout 09:46:53 INFO - Output received: 09:46:53 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) 09:46:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:46:53 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')]} 09:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:53 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')]} 09:46:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:53 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 0x10211a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102090570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f1c84800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f2ed30>, '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 0x101d94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f26b20>, '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.dWczegeiMR/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.innJe5PzNK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 09:46:53 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 09:46:53 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 09:46:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dWczegeiMR/Render', 09:46:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:53 INFO - 'HOME': '/Users/cltbld', 09:46:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:53 INFO - 'LOGNAME': 'cltbld', 09:46:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:53 INFO - 'MOZ_NO_REMOTE': '1', 09:46:53 INFO - 'NO_EM_RESTART': '1', 09:46:53 INFO - 'PAGER': '/bin/cat', 09:46:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:53 INFO - 'PWD': '/builds/slave/test', 09:46:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:53 INFO - 'SHELL': '/bin/bash', 09:46:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.innJe5PzNK/Listeners', 09:46:53 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:46:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:53 INFO - 'USER': 'cltbld', 09:46:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:53 INFO - 'XPC_FLAGS': '0x0', 09:46:53 INFO - 'XPC_SERVICE_NAME': '0', 09:46:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:54 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 09:46:54 INFO - Cleaning up... 09:46:54 INFO - Return code: 0 09:46:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:46:54 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')]} 09:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:54 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')]} 09:46:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:54 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 0x10211a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102090570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f1c84800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f2ed30>, '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 0x101d94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f26b20>, '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.dWczegeiMR/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.innJe5PzNK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 09:46:54 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 09:46:54 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 09:46:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dWczegeiMR/Render', 09:46:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:54 INFO - 'HOME': '/Users/cltbld', 09:46:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:54 INFO - 'LOGNAME': 'cltbld', 09:46:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:54 INFO - 'MOZ_NO_REMOTE': '1', 09:46:54 INFO - 'NO_EM_RESTART': '1', 09:46:54 INFO - 'PAGER': '/bin/cat', 09:46:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:54 INFO - 'PWD': '/builds/slave/test', 09:46:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:54 INFO - 'SHELL': '/bin/bash', 09:46:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.innJe5PzNK/Listeners', 09:46:54 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:46:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:54 INFO - 'USER': 'cltbld', 09:46:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:54 INFO - 'XPC_FLAGS': '0x0', 09:46:54 INFO - 'XPC_SERVICE_NAME': '0', 09:46:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:54 INFO - Downloading/unpacking psutil>=3.1.1 09:46:54 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 09:46:54 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 09:46:54 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 09:46:54 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 09:46:54 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 09:46:54 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 09:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:46:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:46:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:46:57 INFO - Installing collected packages: psutil 09:46:57 INFO - Running setup.py install for psutil 09:46:58 INFO - building 'psutil._psutil_osx' extension 09:46:58 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 09:46:58 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 09:46:58 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 09:46:58 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 09:46:58 INFO - building 'psutil._psutil_posix' extension 09:46:58 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 09:46:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:46:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:46:58 INFO - ^ 09:46:58 INFO - 1 warning generated. 09:46:58 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 09:46:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:46:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:46:58 INFO - Successfully installed psutil 09:46:58 INFO - Cleaning up... 09:46:58 INFO - Return code: 0 09:46:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10211a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102090570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f1c84800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f2ed30>, '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 0x101d94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f26b20>, '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.dWczegeiMR/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.innJe5PzNK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 09:46:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:46:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dWczegeiMR/Render', 09:46:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:58 INFO - 'HOME': '/Users/cltbld', 09:46:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:58 INFO - 'LOGNAME': 'cltbld', 09:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:58 INFO - 'MOZ_NO_REMOTE': '1', 09:46:58 INFO - 'NO_EM_RESTART': '1', 09:46:58 INFO - 'PAGER': '/bin/cat', 09:46:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:58 INFO - 'PWD': '/builds/slave/test', 09:46:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:58 INFO - 'SHELL': '/bin/bash', 09:46:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.innJe5PzNK/Listeners', 09:46:58 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:46:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:58 INFO - 'USER': 'cltbld', 09:46:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:58 INFO - 'XPC_FLAGS': '0x0', 09:46:58 INFO - 'XPC_SERVICE_NAME': '0', 09:46:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:46:59 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 09:46:59 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 09:46:59 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 09:46:59 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 09:46:59 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 09:46:59 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 09:47:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:47:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:47:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:47:01 INFO - Installing collected packages: mozsystemmonitor 09:47:01 INFO - Running setup.py install for mozsystemmonitor 09:47:01 INFO - Successfully installed mozsystemmonitor 09:47:01 INFO - Cleaning up... 09:47:02 INFO - Return code: 0 09:47:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:02 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 0x10211a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102090570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f1c84800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f2ed30>, '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 0x101d94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f26b20>, '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.dWczegeiMR/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.innJe5PzNK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 09:47:02 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 09:47:02 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 09:47:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dWczegeiMR/Render', 09:47:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:02 INFO - 'HOME': '/Users/cltbld', 09:47:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:02 INFO - 'LOGNAME': 'cltbld', 09:47:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:02 INFO - 'MOZ_NO_REMOTE': '1', 09:47:02 INFO - 'NO_EM_RESTART': '1', 09:47:02 INFO - 'PAGER': '/bin/cat', 09:47:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:02 INFO - 'PWD': '/builds/slave/test', 09:47:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:02 INFO - 'SHELL': '/bin/bash', 09:47:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.innJe5PzNK/Listeners', 09:47:02 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:47:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:02 INFO - 'USER': 'cltbld', 09:47:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:02 INFO - 'XPC_FLAGS': '0x0', 09:47:02 INFO - 'XPC_SERVICE_NAME': '0', 09:47:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:02 INFO - Downloading/unpacking jsonschema==2.5.1 09:47:02 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 09:47:02 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 09:47:02 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 09:47:02 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 09:47:02 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 09:47:02 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 09:47:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:47:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:47:05 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 09:47:05 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 09:47:05 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 09:47:05 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 09:47:05 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 09:47:05 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 09:47:06 INFO - Downloading functools32-3.2.3-2.tar.gz 09:47:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:47:06 INFO - warning: no files found matching '*.txt' 09:47:06 INFO - no previously-included directories found matching 'build' 09:47:06 INFO - no previously-included directories found matching 'dist' 09:47:06 INFO - no previously-included directories found matching '.git*' 09:47:06 INFO - Installing collected packages: jsonschema, functools32 09:47:06 INFO - Running setup.py install for functools32 09:47:06 INFO - warning: no files found matching '*.txt' 09:47:06 INFO - no previously-included directories found matching 'build' 09:47:06 INFO - no previously-included directories found matching 'dist' 09:47:06 INFO - no previously-included directories found matching '.git*' 09:47:06 INFO - Successfully installed jsonschema functools32 09:47:06 INFO - Cleaning up... 09:47:06 INFO - Return code: 0 09:47:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10211a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102090570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f1c84800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f2ed30>, '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 0x101d94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f26b20>, '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.dWczegeiMR/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.innJe5PzNK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 09:47:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:47:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:47:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dWczegeiMR/Render', 09:47:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:06 INFO - 'HOME': '/Users/cltbld', 09:47:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:06 INFO - 'LOGNAME': 'cltbld', 09:47:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:06 INFO - 'MOZ_NO_REMOTE': '1', 09:47:06 INFO - 'NO_EM_RESTART': '1', 09:47:06 INFO - 'PAGER': '/bin/cat', 09:47:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:06 INFO - 'PWD': '/builds/slave/test', 09:47:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:06 INFO - 'SHELL': '/bin/bash', 09:47:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.innJe5PzNK/Listeners', 09:47:06 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:47:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:06 INFO - 'USER': 'cltbld', 09:47:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:06 INFO - 'XPC_FLAGS': '0x0', 09:47:06 INFO - 'XPC_SERVICE_NAME': '0', 09:47:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:47:06 INFO - Cleaning up... 09:47:06 INFO - Return code: 0 09:47:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10211a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102090570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f1c84800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f2ed30>, '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 0x101d94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f26b20>, '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.dWczegeiMR/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.innJe5PzNK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 09:47:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:47:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:47:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dWczegeiMR/Render', 09:47:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:07 INFO - 'HOME': '/Users/cltbld', 09:47:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:07 INFO - 'LOGNAME': 'cltbld', 09:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:07 INFO - 'MOZ_NO_REMOTE': '1', 09:47:07 INFO - 'NO_EM_RESTART': '1', 09:47:07 INFO - 'PAGER': '/bin/cat', 09:47:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:07 INFO - 'PWD': '/builds/slave/test', 09:47:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:07 INFO - 'SHELL': '/bin/bash', 09:47:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.innJe5PzNK/Listeners', 09:47:07 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:47:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:07 INFO - 'USER': 'cltbld', 09:47:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:07 INFO - 'XPC_FLAGS': '0x0', 09:47:07 INFO - 'XPC_SERVICE_NAME': '0', 09:47:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:07 INFO - Downloading/unpacking blobuploader==1.2.4 09:47: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 09:47: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 09:47: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 09:47: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 09:47: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 09:47: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 09:47:09 INFO - Downloading blobuploader-1.2.4.tar.gz 09:47:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:47:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:47:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:47:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:11 INFO - Downloading docopt-0.6.1.tar.gz 09:47:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:47:11 INFO - Installing collected packages: blobuploader, requests, docopt 09:47:11 INFO - Running setup.py install for blobuploader 09:47:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:47:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:47:11 INFO - Running setup.py install for requests 09:47:11 INFO - Running setup.py install for docopt 09:47:11 INFO - Successfully installed blobuploader requests docopt 09:47:11 INFO - Cleaning up... 09:47:12 INFO - Return code: 0 09:47:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10211a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102090570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f1c84800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f2ed30>, '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 0x101d94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f26b20>, '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.dWczegeiMR/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.innJe5PzNK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 09:47:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:47:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:47:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dWczegeiMR/Render', 09:47:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:12 INFO - 'HOME': '/Users/cltbld', 09:47:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:12 INFO - 'LOGNAME': 'cltbld', 09:47:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:12 INFO - 'MOZ_NO_REMOTE': '1', 09:47:12 INFO - 'NO_EM_RESTART': '1', 09:47:12 INFO - 'PAGER': '/bin/cat', 09:47:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:12 INFO - 'PWD': '/builds/slave/test', 09:47:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:12 INFO - 'SHELL': '/bin/bash', 09:47:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.innJe5PzNK/Listeners', 09:47:12 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:47:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:12 INFO - 'USER': 'cltbld', 09:47:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:12 INFO - 'XPC_FLAGS': '0x0', 09:47:12 INFO - 'XPC_SERVICE_NAME': '0', 09:47:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:47:12 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-WrfEgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:47:12 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-Ix8EQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:47:12 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-DNiPgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:47:12 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-pjTfwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:47:12 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-EssQsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:47:12 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-5LF5yo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:47:12 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-pa3TiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:47:13 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-WuGdvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:47:13 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-sD44nD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:47:13 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-0q6aeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:47:13 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-OdWQNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:47:13 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-AMSgZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:47:13 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-0zmDbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:47:13 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-3snkwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:13 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-4HvQYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:47:13 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-VExR1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:47:13 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-4Menok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:47:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:47:14 INFO - Running setup.py install for manifestparser 09:47:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:47:14 INFO - Running setup.py install for mozcrash 09:47:14 INFO - Running setup.py install for mozdebug 09:47:14 INFO - Running setup.py install for mozdevice 09:47:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:47:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:47:14 INFO - Running setup.py install for mozfile 09:47:14 INFO - Running setup.py install for mozhttpd 09:47:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:47:14 INFO - Running setup.py install for mozinfo 09:47:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:47:15 INFO - Running setup.py install for mozInstall 09:47:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:47:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:47:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:47:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:47:15 INFO - Running setup.py install for mozleak 09:47:15 INFO - Running setup.py install for mozlog 09:47:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:47:15 INFO - Running setup.py install for moznetwork 09:47:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:47:15 INFO - Running setup.py install for mozprocess 09:47:15 INFO - Running setup.py install for mozprofile 09:47:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:47:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:47:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:47:15 INFO - Running setup.py install for mozrunner 09:47:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:47:16 INFO - Running setup.py install for mozscreenshot 09:47:16 INFO - Running setup.py install for moztest 09:47:16 INFO - Running setup.py install for mozversion 09:47:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:47:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:47:16 INFO - Cleaning up... 09:47:16 INFO - Return code: 0 09:47:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10211a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102090570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f1c84800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f2ed30>, '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 0x101d94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f26b20>, '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.dWczegeiMR/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.innJe5PzNK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 09:47:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:47:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dWczegeiMR/Render', 09:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:16 INFO - 'HOME': '/Users/cltbld', 09:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:16 INFO - 'LOGNAME': 'cltbld', 09:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:16 INFO - 'MOZ_NO_REMOTE': '1', 09:47:16 INFO - 'NO_EM_RESTART': '1', 09:47:16 INFO - 'PAGER': '/bin/cat', 09:47:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:16 INFO - 'PWD': '/builds/slave/test', 09:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:16 INFO - 'SHELL': '/bin/bash', 09:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.innJe5PzNK/Listeners', 09:47:16 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:16 INFO - 'USER': 'cltbld', 09:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:16 INFO - 'XPC_FLAGS': '0x0', 09:47:16 INFO - 'XPC_SERVICE_NAME': '0', 09:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:47:16 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-xPfOTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:47:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:47:17 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-LdKIn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:47:17 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)) 09:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:47:17 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-_wc2Ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:47:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:47:17 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-TlpG94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:47:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:47:17 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-wcIP5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:47:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:47:17 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-5xYOl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:47:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:47:17 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-MENcTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:47:17 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)) 09:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:47:17 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-8F6fdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:47:17 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)) 09:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:47:17 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-k_6I51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:47:17 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)) 09:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:47:17 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-OtP_rP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:47:17 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)) 09:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:47:17 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-dzgGcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:47:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:47:18 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-KY0RHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:47:18 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)) 09:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:47:18 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-ZwHniW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:47:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:47:18 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-WmuBOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:47:18 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)) 09:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:18 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-WaIpU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:47:18 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-Vf936w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:47:18 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)) 09:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:47:18 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-HR5GMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:47:18 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)) 09:47:18 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)) 09:47:18 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)) 09:47:18 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)) 09:47:18 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)) 09:47:18 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)) 09:47:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:47:18 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 09:47:18 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 09:47:18 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 09:47:18 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 09:47:18 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 09:47:18 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 09:47:21 INFO - Downloading blessings-1.6.tar.gz 09:47:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:47:21 INFO - Installing collected packages: blessings 09:47:21 INFO - Running setup.py install for blessings 09:47:21 INFO - Successfully installed blessings 09:47:21 INFO - Cleaning up... 09:47:21 INFO - Return code: 0 09:47:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:47:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:47:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:47:21 INFO - Reading from file tmpfile_stdout 09:47:21 INFO - Current package versions: 09:47:21 INFO - blessings == 1.6 09:47:21 INFO - blobuploader == 1.2.4 09:47:21 INFO - docopt == 0.6.1 09:47:21 INFO - functools32 == 3.2.3-2 09:47:21 INFO - jsonschema == 2.5.1 09:47:21 INFO - manifestparser == 1.1 09:47:21 INFO - mozInstall == 1.12 09:47:21 INFO - mozcrash == 1.0 09:47:21 INFO - mozdebug == 0.1 09:47:21 INFO - mozdevice == 0.48 09:47:21 INFO - mozfile == 1.2 09:47:21 INFO - mozhttpd == 0.7 09:47:21 INFO - mozinfo == 0.9 09:47:21 INFO - mozleak == 0.1 09:47:21 INFO - mozlog == 3.4 09:47:21 INFO - moznetwork == 0.27 09:47:21 INFO - mozprocess == 0.23 09:47:21 INFO - mozprofile == 0.28 09:47:21 INFO - mozrunner == 6.13 09:47:21 INFO - mozscreenshot == 0.1 09:47:21 INFO - mozsystemmonitor == 0.3 09:47:21 INFO - moztest == 0.8 09:47:21 INFO - mozversion == 1.4 09:47:21 INFO - psutil == 3.1.1 09:47:21 INFO - requests == 1.2.3 09:47:21 INFO - wsgiref == 0.1.2 09:47:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:21 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')]} 09:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:21 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')]} 09:47:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:21 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 0x10211a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102090570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f1c84800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f2ed30>, '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 0x101d94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f26b20>, '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.dWczegeiMR/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.innJe5PzNK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 09:47:21 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 09:47:21 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 09:47:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dWczegeiMR/Render', 09:47:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:21 INFO - 'HOME': '/Users/cltbld', 09:47:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:21 INFO - 'LOGNAME': 'cltbld', 09:47:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:21 INFO - 'MOZ_NO_REMOTE': '1', 09:47:21 INFO - 'NO_EM_RESTART': '1', 09:47:21 INFO - 'PAGER': '/bin/cat', 09:47:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:21 INFO - 'PWD': '/builds/slave/test', 09:47:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:21 INFO - 'SHELL': '/bin/bash', 09:47:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.innJe5PzNK/Listeners', 09:47:21 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:47:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:21 INFO - 'USER': 'cltbld', 09:47:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:21 INFO - 'XPC_FLAGS': '0x0', 09:47:21 INFO - 'XPC_SERVICE_NAME': '0', 09:47:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:47:22 INFO - Cleaning up... 09:47:22 INFO - Return code: 0 09:47:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:47:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10211a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102090570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3f1c84800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f2ed30>, '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 0x101d94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f26b20>, '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.dWczegeiMR/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.innJe5PzNK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 09:47:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:47:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:47:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dWczegeiMR/Render', 09:47:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:22 INFO - 'HOME': '/Users/cltbld', 09:47:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:22 INFO - 'LOGNAME': 'cltbld', 09:47:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:22 INFO - 'MOZ_NO_REMOTE': '1', 09:47:22 INFO - 'NO_EM_RESTART': '1', 09:47:22 INFO - 'PAGER': '/bin/cat', 09:47:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:22 INFO - 'PWD': '/builds/slave/test', 09:47:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:22 INFO - 'SHELL': '/bin/bash', 09:47:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.innJe5PzNK/Listeners', 09:47:22 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:47:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:22 INFO - 'USER': 'cltbld', 09:47:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:22 INFO - 'XPC_FLAGS': '0x0', 09:47:22 INFO - 'XPC_SERVICE_NAME': '0', 09:47:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:47:22 INFO - Cleaning up... 09:47:22 INFO - Return code: 0 09:47:22 INFO - Running post-action listener: _resource_record_post_action 09:47:22 INFO - Running post-action listener: _start_resource_monitoring 09:47:22 INFO - Starting resource monitoring. 09:47:22 INFO - [mozharness: 2017-01-06 17:47:22.473600Z] Finished create-virtualenv step (success) 09:47:22 INFO - [mozharness: 2017-01-06 17:47:22.474562Z] Running install step. 09:47:22 INFO - Running pre-action listener: _resource_record_pre_action 09:47:22 INFO - Running main action method: install 09:47:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:47:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:47:22 INFO - Reading from file tmpfile_stdout 09:47:22 INFO - Detecting whether we're running mozinstall >=1.0... 09:47:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:47:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:47:22 INFO - Reading from file tmpfile_stdout 09:47:22 INFO - Output received: 09:47:22 INFO - Usage: mozinstall [options] installer 09:47:22 INFO - Options: 09:47:22 INFO - -h, --help show this help message and exit 09:47:22 INFO - -d DEST, --destination=DEST 09:47:22 INFO - Directory to install application into. [default: 09:47:22 INFO - "/builds/slave/test"] 09:47:22 INFO - --app=APP Application being installed. [default: firefox] 09:47:22 INFO - mkdir: /builds/slave/test/build/application 09:47:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:47:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:47:53 INFO - Reading from file tmpfile_stdout 09:47:53 INFO - Output received: 09:47:53 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:47:53 INFO - Running post-action listener: _resource_record_post_action 09:47:53 INFO - [mozharness: 2017-01-06 17:47:53.560704Z] Finished install step (success) 09:47:53 INFO - [mozharness: 2017-01-06 17:47:53.560903Z] Running run-tests step. 09:47:53 INFO - Running pre-action listener: _resource_record_pre_action 09:47:53 INFO - Running main action method: run_tests 09:47:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:47:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:47:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:47:53 INFO - 2017-01-06 09:47:53.575 screenresolution[1917:17722] starting screenresolution argv=screenresolution get 09:47:53 INFO - 2017-01-06 09:47:53.578 screenresolution[1917:17722] Display 0: 1600x1200x32@60 09:47:53 INFO - 2017-01-06 09:47:53.584 screenresolution[1918:17724] starting screenresolution argv=screenresolution list 09:47:53 INFO - Available Modes on Display 0 09:47:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:47:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:47:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:47:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:47:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:47:53 INFO - Intel Iris: 09:47:53 INFO - Chipset Model: Intel Iris 09:47:53 INFO - Type: GPU 09:47:53 INFO - Bus: Built-In 09:47:53 INFO - VRAM (Dynamic, Max): 1536 MB 09:47:53 INFO - Vendor: Intel (0x8086) 09:47:53 INFO - Device ID: 0x0a2e 09:47:53 INFO - Revision ID: 0x0009 09:47:53 INFO - Displays: 09:47:53 INFO - Display: 09:47:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:47:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:47:53 INFO - Display Serial Number: 1600x1200 60 09:47:53 INFO - Main Display: Yes 09:47:53 INFO - Mirror: Off 09:47:53 INFO - Online: Yes 09:47:53 INFO - Rotation: Supported 09:47:53 INFO - Return code: 0 09:47:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:47:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:47:53 INFO - Python 2.7.3 09:47:53 INFO - Return code: 0 09:47:53 INFO - grabbing minidump binary from tooltool 09:47:53 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')]} 09:47:53 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 0x101f2ed30>, '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 0x101d94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f26b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:47:53 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 09:47:53 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 09:47:53 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 09:47:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:47:53 INFO - Return code: 0 09:47:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:47:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:47:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:47:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:47:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:47:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0220', '--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 09:47:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0220 --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 09:47:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dWczegeiMR/Render', 09:47:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:53 INFO - 'HOME': '/Users/cltbld', 09:47:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:53 INFO - 'LOGNAME': 'cltbld', 09:47:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:47:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:47:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:53 INFO - 'MOZ_NO_REMOTE': '1', 09:47:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:47:53 INFO - 'NO_EM_RESTART': '1', 09:47:53 INFO - 'PAGER': '/bin/cat', 09:47:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:53 INFO - 'PWD': '/builds/slave/test', 09:47:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:47:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:53 INFO - 'SHELL': '/bin/bash', 09:47:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.innJe5PzNK/Listeners', 09:47:53 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:47:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:53 INFO - 'USER': 'cltbld', 09:47:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:53 INFO - 'XPC_FLAGS': '0x0', 09:47:53 INFO - 'XPC_SERVICE_NAME': '0', 09:47:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0220', '--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 09:47:54 INFO - mozversion application_buildid: 20170106071356 09:47:54 INFO - mozversion application_changeset: d77136af6dd5f7f80bb602decfe929204c30439f 09:47:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:47:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:47:54 INFO - mozversion application_name: Firefox 09:47:54 INFO - mozversion application_remotingname: firefox-dev 09:47:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:47:54 INFO - mozversion application_vendor: Mozilla 09:47:54 INFO - mozversion application_version: 52.0a2 09:47:54 INFO - mozversion platform_buildid: 20170106071356 09:47:54 INFO - mozversion platform_changeset: d77136af6dd5f7f80bb602decfe929204c30439f 09:47:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:47:54 INFO - mozversion platform_version: 52.0a2 09:47:54 INFO - using testdate: 1483724874 09:47:54 INFO - actual date: 1483724874 09:47:54 INFO - starting webserver on 'localhost:54562' 09:47:54 INFO - SUITE-START | Running 2 tests 09:47:54 INFO - TEST-START | dromaeo_css 09:47:54 INFO - operating with platform_type : mac_ 09:47:54 INFO - Initialising browser for dromaeo_css test... 09:47:54 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/tmpdBF3R7/profile http://localhost:54562/getInfo.html) 09:47:57 INFO - PROCESS | 1928 | 2017-01-06 09:47:57.032 plugin-container[1930:17895] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3b, name = 'com.apple.tsm.portname' 09:47:57 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:57 INFO - PROCESS | 1928 | 2017-01-06 09:47:57.036 plugin-container[1930:17895] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:47:57 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:57 INFO - PROCESS | 1928 | 2017-01-06 09:47:57.036 plugin-container[1930:17895] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:47:57 INFO - PROCESS | 1928 | __metrics Screen width/height:1600/1200 09:47:57 INFO - PROCESS | 1928 | colorDepth:24 09:47:57 INFO - PROCESS | 1928 | Browser inner width/height: 1024/689 09:47:57 INFO - PROCESS | 1928 | __metrics 09:47:57 ERROR - PROCESS | 1928 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 09:47:58 INFO - TEST-INFO | 1928: exit 0 09:47:58 INFO - Browser initialized. 09:47:58 INFO - Running cycle 1/1 for dromaeo_css test... 09:47:58 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/tmpdBF3R7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:48:00 INFO - PROCESS | 1931 | 2017-01-06 09:48:00.709 plugin-container[1932:18025] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 09:48:00 INFO - PROCESS | 1931 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:48:00 INFO - PROCESS | 1931 | 2017-01-06 09:48:00.710 plugin-container[1932:18025] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 09:48:00 INFO - PROCESS | 1931 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:48:00 INFO - PROCESS | 1931 | 2017-01-06 09:48:00.710 plugin-container[1932:18025] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:48:10 INFO - PROCESS | 1931 | RSS: Main: 229679104 09:48:10 INFO - PROCESS | 1931 | 09:51:13 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54562/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54562/tests/dromaeo/cssquery-ext.html) 09:51:15 INFO - PROCESS | 1931 | RSS: Main: 233852928 09:51:15 INFO - PROCESS | 1931 | 09:54:17 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54562/tests/dromaeo/cssquery-ext.html (next: http://localhost:54562/tests/dromaeo/cssquery-jquery.html) 09:54:17 INFO - PROCESS | 1931 | RSS: Main: 244031488 09:54:17 INFO - PROCESS | 1931 | 09:57:19 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54562/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54562/tests/dromaeo/cssquery-mootools.html) 09:57:20 INFO - PROCESS | 1931 | RSS: Main: 247189504 09:57:20 INFO - PROCESS | 1931 | 10:00:22 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54562/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54562/tests/dromaeo/cssquery-prototype.html) 10:00:23 INFO - PROCESS | 1931 | RSS: Main: 246378496 10:00:23 INFO - PROCESS | 1931 | 10:03:25 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54562/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54562/tests/dromaeo/cssquery-yui.html) 10:03:26 INFO - PROCESS | 1931 | RSS: Main: 249724928 10:03:26 INFO - PROCESS | 1931 | 10:06:29 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:54562/tests/dromaeo/cssquery-yui.html (next: http://localhost:54562/tests/dromaeo/cssquery-dojo.html) 10:06:29 INFO - PROCESS | 1931 | RSS: Main: 251502592 10:06:29 INFO - PROCESS | 1931 | 10:06:29 INFO - PROCESS | 1931 | __start_tp_report 10:06:29 INFO - PROCESS | 1931 | _x_x_mozilla_page_load 10:06:29 INFO - PROCESS | 1931 | _x_x_mozilla_page_load_details 10:06:29 INFO - PROCESS | 1931 | |i|pagename|runs| 10:06:29 INFO - PROCESS | 1931 | |0;dojo.html;3195.3130077033434;3406.6763657452548;3436.9828150859244;3444.7760895541774;3467.5145479632847;5886.764529418823;5935.347111817702;5949.524038076955;5951.940480595193;5976.342602313742;5914.852128696784;5957.463828255456;5981.58128930975;5989.610675306102;5999.49004334631;5323.733813309338;5371.006363822686;5405.648632838873;5412.323459567554;5420.593455490854;5418.431064738204;5419.458054194569;5423.945760542403;5438.510534051935;5455.754491047913;4107.219628270634;4752.358431611723;5454.400015998237;5458.181272809071;5508.366537848158;5327.946720532784;5335.306410166692;5390.6226564140525;5453.64551304164;5485.451454854483;5271.736413179326;5346.064438723208;5382.542483888874;5393.622446428752;5436.809711660112;5622.859428514279;5971.731272092723;5981.641101533922;6028.216331876872;6038.064100064497;5178.97410512945;6135.5398345124295;6142.406692085136;6149;6165.475934545569;6141.762184092961;6153.138560601519;6160.476359509447;6171.629702217836;6221.4089661482085;5905.73424195908;5926.1110833374905;5938.910916336258;5980.641161530322;5989.520838332923;6334.714937827809;6363.7772677956045;6410.55126141175;6414.230292364853;6434.067060276234;5144.254083157921;6128.075379514901;6152.538559608047;6175.3515880832765;6213.130161777355;4707.458642256116;5046.924296135561;5231.633785635046;5889.1755154278435;6290.99344104941;5058.848234552969;6141.731496609903;6212.1303017577575;6228.47058000066;6254.718537665816;6130.275842464065;6141.700809433414;6163.96918015407;6181.103739957681;6232.376762323732;5038.84883453497;5043.52086551777;5043.823466178666;5048.873778155502;5063.443021267658;3785.280796648628;4425.889352766142;4508.301213311942;4509.143262780061;4538.001639639274;5758.913616295759;5949.732262048218;5956.6723830188885;6050.606710563799;6122.479589234965;6269.247690277105;6310.558260921692;6333.936660633427;6339.366063393716;6343.333949935282;5906.822795316148;5992.670403127782;6016.458518733335;6079.696015199311;6087.360827113178;5444.6188766785945;5483.287172667528;5488.451154884479;5531.751071201806;5551.8889622207325;6449.774257900951;6521.413072823469;6523.4128928394775;6566.9343306566325;6569.9343006571235;6292.4651404629285;6306.558540902093;6329.208848893888;6330.556861019684;6354.364563543609;6262.46769024638;6337.493000559852;6472.255690595453;6513.088167656437;6526.151600291932;6140.8771824564155;6151.384861513813;6157.876842463215;6246;6257.2491301044165;6309.085182648582;6316.652584107918;6317.936820631735;6320.083587879823;6336.619802811846;5177.922331165111;5910.408959104056;6090.847728806638;6145;6218.253809542884;6121.510279177744;6124.045297608125;6129.817282374624;6169.691515424301;6213.720382582885;6022.698865056817;6116.847078822887;6137.223770008752;6138.1713468681155;6166;6102.4507794298725;6135.386461353829;6147.415995480422;6158.952977993662;6211.81364559064;6054;6074.544409169242;6083.330833608388;6159.630422174684;6174.9073763892675 10:06:29 INFO - PROCESS | 1931 | |1;ext.html;461.98152073917043;522.0524747583137;523.2073408785687;538.7925648625279;538.808722903369;31853.362932741362;39551.604483955154;40723;40744.99999999996;40773.59226407735;41667.95830104249;41966.16067678644;42170;42223.155536889244;42237.7888110559;9305.348625596194;9462.763430914249;9481.004494528079;9486.193673537757;9513.619455221782;8795.900512435945;9493.667721629745;9532.285078619132;9559.665411710592;9560.235181185522;7309.611173876973;8247.092819789817;8333.083360830335;8719.782005449852;9565.66520171794;8272.007359116884;9496.097870702271;9547.761305967337;9583.185429238523;9591.280653950911;9374.625014999392;9411.294152938557;9458.148766611039;9479.289053321028;9480.862296524461;39629.60370396288;39872;39881.202375952606;39891.80054099711;39976.000599984945;3297.159224397787;3615.7468977171607;3899.7270191086636;3942.526896772377;3947.664448521879;28311.292217694518;28914;28995.85502072476;29943.70056299431;30567.08298751041;3136.952945705816;3212.3254116635535;3233.321002589459;3240.4329242382723;3267.754918381107;282459.9385015391;361286;364386;364904;366347.16826415964;57998.42001580015;58162.4183758157;58334.708326458094;58349.70825145847;58632.70683646554;60825.39174608286;61199;61211;61702.691486543175;61875;15844.841551584337;15894.364225430852;16041.919790400972;16053.117078560792;16104.436344728112;12501.937490312672;12530.81203781944;12578.55975040869;12583.496660133493;12598.181118227469;16080.678386432206;16387.508374748777;16537.586560335847;16540.75188872168;16577.585560361087;3217.935641287208;5918.408159184047;6821.1814582250445;6983.790486285419;7017.087778588751;844.9239568438747;844.928181104613;903.2006674093475;939.6006697153819;1007.5868893753526;23003;29386.971455998937;29981;30361.696383036327;30554;921.447131720962;966.9218821014518;1041.5156952017196;1102.327580176092;1105.3312745788842;14683.853161468249;15750.133742644264;16073.437429690139;16108.27512761905;16162.515124546047;643194;650892;654067;655914;663609;57062.714686427134;57331.713341433024;57366.99999999916;57388.42611573831;57487;1317.6244770240628;1341.6645838540364;1365.9658508537366;1367.589723083091;1372.3961456958907;28754.137375878756;29377;29973.700262997092;30385.93649222355;30427.54358684577;50199;50405.24392134194;50647.98704025825;50679.74660126676;50764;577723;578604;580666;581392;582476;24910.626340605268;26467.867660661574;27289.45421091607;27390.726092739616;28427.431451370445;45942.08115837731;46043.76978115088;46499.76750116228;46718;47089.293660594434;37389.626103738614;37963.2407351857;38952.41571376375;39970.600293996686;40096.99757506086;38254.23491530209;38402.807985959895;38892.22215555616;39676.206475869745;39819.80540583787;37464;37528.24943501169;38129.618703813714;38172.61827381691;38241.235175295784;27944.720552794213;32640.183995400304;34161.80433684908;34616.65383346134;34622;24017.399565011016;25106.874465627556;25162.867670955216;25362.619560706236;25837.870810645825 10:06:29 INFO - PROCESS | 1931 | |2;jquery.html;332.1695760598504;376.79464691743;383.1111820576263;387.7305272835377;403.64277614311345;11125.499352529136;11577.53689852405;11667.766644667101;11693.356865372401;18549.907250463744;20327.390178294638;20591;20845.47886302843;21126;21325.68011479829;6530.967345163268;6889.965550172267;6892.5864448133025;6898.724051037952;6931.133608298963;6666.333366663343;7101.8224544386285;7427.665755041036;7502.474826762129;7587.658555365022;6974.511784175084;7530.096388433369;7534.849303013936;7535.849283014337;7571.583562904038;7276.454265930023;7568.810779730496;7612.238776122371;7644.617769111522;7680.3087722105;7154.499185057048;7243.311885370934;7251.383632391253;7255.52839065459;7271.200167981518;27144;27146.049888254012;27786.722132778617;27800.30499237515;28118.859405702842;2411.2525117213545;2499.125306142854;2545.5290771207387;2563.4360440703017;2602.984835913995;15845.68308633821;16034;16068.839311606851;16487.752683709754;17396.21717022737;1923.6729755941383;1999.7000449932482;2013.1846602126016;2056.907439165241;2061.154926480151;120161.19758203547;138122.3093884524;140274;149944;152295;17287.481375558753;21715.914204289722;21739.130434782746;21927.232546860785;22172.113115475524;35063.29873402569;35200.76797312082;35649.46525802115;35931.82034089813;36007;9740.318177727491;9901.306908516335;10639.521221544894;10640.308379955279;10710.08964238033;8755.036945935943;8800.383973121949;8821.250193733476;8955.686550970684;8965.865512017324;8249.381296402793;9654;9825.361351512129;9910.15763660097;9930.602775889029;3426.612222050074;3595.2629710909723;3786.3306369009783;3868;3887.319719049155;810.3112354498746;886.4105369929053;908.3777612335572;950.7670620697835;1023.559869256227;19480.02599870029;19830.603387932162;20396.694049589267;20657.17371305131;20758.377248682562;881.3345923827469;938.643315540104;945.2674177512483;959.7840485890619;1002.0830939690102;10461.110805581458;10897.237193396544;10903.01872831449;10943.726406839733;10963.725906852393;617491;623977;633211;642492;644327;87539.56230218807;96076.63731769354;108661;124697;126078;839.4921072750898;989.7773001074845;1007.6322142417964;1009.8434742614907;1010.8786945566579;18546.721799172752;18862.151203196165;18961.43115706531;18991.620167596848;19020.80979190246;49283.75358123187;49426.75286623688;50242.743931400546;55013.174802377194;56719.58201045007;74000.62999684968;80062.1993780078;80828;84833.72749408887;85216.1478385233;6664.666766661744;7334;7401.851962960818;7563.281488258606;7772.06735191758;10792.514336854703;12508.562200322946;12523.812142818048;12599.118061735591;12701.047421443243;10719.303245289031;10855.728606784893;11011.834822477509;11079.612213572485;11147.386893720599;8446.704365347183;10709.357438553712;10736.194785390971;10761.461926903467;10811.459427028774;10691.572337106429;10927.193404771826;11065.778684426428;11156.55373785072;11218.495167717309;7417.332440080419;7450.6647200875;7476.401887849068;7582.696692132253;7727.381809455118;9978.201743860329;10655.627053053106;10744.623938162127;10778.568857245622;11001.944990274997 10:06:29 INFO - PROCESS | 1931 | |3;mootools.html;1388.798624199491;1454.2147240490135;1625.983740162598;1729.3860679458799;1779.9466016019517;7520.88718669219;7802.882956755639;8083.474574152676;8536;8548.871766923494;7941.205879412056;8275.91724082759;8303.626336814843;8389.496630202193;8413.57932103394;1962.3131903833687;2326.5928462519078;2367.005857539829;2369.3839601703507;2377.4413012941945;2121.628714974881;2201.8330284949;2358.7169539655265;2390.3307074019303;2406.602910519762;1990.3033938121614;2338.9415264618438;2356.493353928905;2368.786809187173;2374.050379848057;2327.301809457156;2331.860088394702;2345.3667509772267;2352.752960939094;2358.5872472317446;2361.2916125162383;2366.8461549999365;2371.5019845832394;2373.5134297468976;2390.97609023911;8363.749087527383;8454;8479.533625650587;8491.980962284504;8520.829583408358;5089.949100508985;5233.162693968947;5245.973770131125;5248.606354523387;5249.186376111708;3030.9090727278217;3244.772865899388;3292.901212963615;3637.890863274106;3642.832514653777;1017.2065788684838;1063.9361638301727;1092.5738961804902;1096.8299913513417;1115.2639258089703;10960.28758130727;10979.890201097967;11031.72420689481;11123.55505779768;11157.888421115686;4574.862754117382;4641.257398816173;4716.410448693913;4718.221493453541;4811.206150985048;9480.573374198178;9561.091696288842;9576.186024187882;9597.520123993772;9608.231341492663;5278.366596008504;5280.762365693553;5282.6302158849285;5289.629725919149;5295.443978382292;4328.653707703439;4342.717723347972;4810.807567697253;4814.470408255089;4855.417349918032;3547.911302217465;3558.8611644273583;3786.6024067472517;3912.4326972588815;3928.4303775952326;1268.7969924811987;1274.3691872522975;1278.8657190994998;1288.9677758056123;1291.9031072669588;797.338209286291;849.9235068843834;871.372611719561;879.1033146190849;898.9865152022594;4948.787547050923;5707.1153971134545;5766.625169364062;5774;5825.7087145642545;383.92705385976575;428.2783509786021;439.8130794412362;442.40938347306064;443.41912095154805;3839.6928245740273;3841.8655347063277;3915.8433662653174;3930.567637559866;3939.881803545898;9859.457729824786;10060;10185.847212291821;10241.23190760681;10242.795144097225;10206.183505319557;10208.693739187836;10209.336393134423;10210.387376757419;10258.12805911491;7494.812629684301;7842.098158711707;7886.72396466121;7989.44073914832;7991.160928102466;5731.598788084961;5825.41745825431;5903.203067585821;5995.520358371233;6005.87988240224;9107.817843643223;9378.67174648884;9424.104710052534;9433.339666223574;9441.952790236004;9474.194693451134;9494.572744226389;9502.287328450531;9575.04249575037;9658.75853103678;6533.477321814338;6766.526343156129;6969.9303006969285;7060.8940865886025;7212.206657267591;7772.883406749016;7901.328387087162;7991.480553763987;8002.799930001796;8011.479253848481;7034.366906978396;7199.496035277481;7231.457640676865;7254.310840470147;7338.77983660491;7005.159380874327;7087.078679771596;7132.786016419515;7194.460415468756;7241;6734.158230221223;6736;6802.421794147355;6816.0457535946;6818.249992500721;4876.512663637483;4899.412070551414;4931.605471562339;4935.679180853233;4983.202687569971;4843.975780121077;4910.92633610489;4929.457759646364;4965.875853103701;5057.772400242072 10:06:29 INFO - PROCESS | 1931 | |4;prototype.html;300.17002986741664;307.60472792461684;315.48260852202384;318.42206395392344;320.77866272272144;4499.977500112499;5150;5371.623986320959;5628.887422251552;5703.543716502685;5297.867553311169;5423.349198096224;5555.805546805863;5561.916571251424;5562.471565201309;4006.2187873364705;4257.827918200945;4265.083007153461;4316.7121425644455;4331.7400955942585;3754.81225938702;3770.340190466671;3817.7136714746366;3979.363301871701;4300.440942677463;3915.941260881075;4126.0510082681;4156;4162.875113746577;4196.769177695225;3826.4834247376525;3980.283548961171;4011.756355529795;4076.90039629591;4172.687048471346;2802.7897907657007;3573.285342931398;3604.3151801157696;3642.5446819147605;3650.2623032725455;4919.803207871681;5177.404598471187;5180.559652429548;5412.888973300416;5491.2312276281355;3835.3096442640312;4132.938005929914;4211.7472951623;4708.340832283483;4715.184653908235;5774.769009239625;5822.505087067604;5823.5341172706085;5830.883382332372;5880.618054757128;3270.640229574745;3569.7679650822615;3693.5752388475403;3906;3965.662918651918;5010.845336505301;5339.893202135935;5992.310884248323;6095.359987201325;6180.567360284782;3491.860325586948;3738.551248931954;3836.832189812747;3992.54085780139;4011.919761604752;3825.464434979105;3848.825413641284;4069.38959156123;4086.5300490443383;4104.610062044101;4741.83403580873;4747.905041899143;4774.427068751772;4785.186518291899;4801.759912004456;5057.241413787888;5584.720763961785;5592.916106258409;5925.762969481172;5943.55423343251;1315.4869600855675;1328.9667758305927;1329.8936085113169;1338.290705925861;1345.2713263456608;762.3024932186987;772.7990722412213;895.6193617712446;896.8251590416445;909.4543274035526;4733;4752.619790416758;5008.749562521859;5061.988631255031;5149.742512874342;5427.945720542823;5783;5795.217645617788;5845.386234445407;5855;4657.4876763555985;5086.440491546001;5222.843314700489;5242.97378513105;5411.621186516984;2596.4807038592357;2759.6274502941847;2774.209350335145;2797.8177021922934;2878.8992385266415;5958.433948774859;5998.640081595118;6020.939790602038;6059.939400606026;6082.787102451393;4884.804607815719;4979.502049794992;5638.971805140948;5734.254546908875;5882.647041177457;5159.432462429129;5431.864203394789;5920.615160014671;6001.339852616209;6008.909866352008;4748.433017104417;5376.0591896418755;5560.6107572471155;5800.680962546931;5857.677827719516;5380.296885301436;5821.970890145522;6009.278886533644;6087.878242435215;6100.759971819542;6051;6106.572539922163;6206.441420272016;6396.328385519453;6425.228972523327;3615.222727113683;3995.92008159841;4057.9043658211526;4062.3797650515844;4098.344264917599;4788.6408519361885;5205.115130427912;6070.939290607037;6148.800983808114;6237.943407730088;5239.47605239473;5297.867553311044;5918.911216331845;5931.762729490944;5939.495142912728;5550.9444905552045;5865.747333183408;5926.762929482945;5971.552133589911;6000.789972350947;5657.349404818334;5712.6001179917;5815.680137592304;5850.2102216200265;5853.473187413153;5223.432970108876;5880.647161170344;5906.734196961061;5943.940560594512;5963.821085367446;5559.916601251065;5779.6014653918155;5807.274090738658;5812.3025236971835;5812.564057695774 10:06:29 INFO - PROCESS | 1931 | |5;yui.html;683.9897401538977;709.6664567653203;710.1052673631226;741.4920779266203;756.8675481790685;14131;15016.92491537542;15027.774583381259;15034.624134396645;15075.999999999985;13405.46378144873;13417.127886687358;13445.529406470776;13446.462141514328;13614;654.8526581519168;666.8032930285553;803.5379656697393;804.694216197844;827.1439060572309;819.778659761864;834.4200780457585;836.9539675317855;837.0165056059136;838.3125836813815;860.7460799064291;878.3061381508601;880.1110878013188;884.880541126946;885.0397318908991;865.8008658008662;875.308506280038;876.7983363826355;878.556329053827;882.5057967538199;654.326044174501;654.9607023578601;656.6979189572802;657.5068698476143;660.2407231683554;13734;14081.859181408157;14087.436502539886;14147.929260353736;14594;13418.329083545783;13565.660858478519;13672.93163534186;13854.653633659138;13858;11272.323660580392;11362.431878406047;11559.884401156049;11562.479688414041;11636.767264654743;3120.719135277813;3618.470102887828;3709.091272638194;3723.0506220913494;3732.682721968636;82688.75966860502;88590;89903.10096899078;90971;91675.8745856438;9566.808663826685;10372.377657340583;10611.946940265403;10770.892291076989;10802.243842931077;17586.560335991446;20977.37067887966;21304.36086917395;21412;21422.03600837966;7080.433565314763;7308.5614863108385;7625.885611715828;7640.159582445926;7687.730929417556;3728.664420202195;4109.671226301889;4219.38818871262;4268.658507319407;4321.719088259253;2631.8026148038975;2899.362140329124;3341.081202669237;3494.1090022044723;3499.3526197653514;651.9674016299166;681.1349586025719;686.6909890549173;694.004104110603;704.4505285877024;633.2211380002623;635.0315768453146;638.2659941067809;658.4732214228598;684.6302996381899;9109.344095259046;9184.357095003286;9307.488088155216;9392.812143757086;9584.424934503952;529.6768970927731;549.8570371703386;611.4863514647717;634.9776859256593;674.4873895839197;4175.5824417558;4276.593723596254;4345.217860785026;4347.174036932973;4504.301833215857;84549.15450845413;94020.0597994025;95569;96538.51730741301;98082.50958745304;67128;80091.19908800838;87018;87899.56050219836;92060.07939920649;81896.59051704823;83832;83964.16035839685;85091.5745421269;87334.56332718296;9539.475328856703;9750.414975101517;9821.656242031495;9823.263255256028;9838.9016109838;40546;41223.381649275885;43188.784056079516;49898;52775;55156.690598564215;63526;73826.26173738194;76593;78129.2187078122;12060.336681482602;12451.937740311603;12496.812547811973;12509.812352814897;12533.997280217378;14226.715465690835;14329.785053224;14413.78379324332;14437.989340746048;14750.2624868754;12707.745845083231;13172.934135329262;13316.933415332862;13432.331424936558;14090.577282681126;11075.446227688422;13124;13340.866591333963;13960.79058814139;14001;12811.231326120462;13012.479500819862;13629;13683.384247708678;13710.588682339545;10672.413017284123;10754.838677419688;10974.615888443866;11106.845681751445;11479.196456248317;9528.809423811623;10816.296940698829;11427.828582571101;11436.370999595103;11446.771064578828 10:06:29 INFO - PROCESS | 1931 | __end_tp_report 10:06:29 INFO - PROCESS | 1931 | __start_cc_report 10:06:29 INFO - PROCESS | 1931 | _x_x_mozilla_cycle_collect,374 10:06:29 INFO - PROCESS | 1931 | __end_cc_report 10:06:29 INFO - PROCESS | 1931 | __startTimestamp1483725989633__endTimestamp 10:06:29 INFO - PROCESS | 1931 | 10:06:29 INFO - PROCESS | 1931 | ------- Summary: start ------- 10:06:29 INFO - PROCESS | 1931 | Number of tests: 6 10:06:29 INFO - PROCESS | 1931 | 10:06:29 INFO - PROCESS | 1931 | [#0] dojo.html Cycles:165 Average:5796.91 Median:6054.00 stddev:666.91 (11.0%) stddev-sans-first:636.96 10:06:29 INFO - PROCESS | 1931 | Values: 3195.3 3406.7 3437.0 3444.8 3467.5 5886.8 5935.3 5949.5 5951.9 5976.3 5914.9 5957.5 5981.6 5989.6 5999.5 5323.7 5371.0 5405.6 5412.3 5420.6 5418.4 5419.5 5423.9 5438.5 5455.8 4107.2 4752.4 5454.4 5458.2 5508.4 5327.9 5335.3 5390.6 5453.6 5485.5 5271.7 5346.1 5382.5 5393.6 5436.8 5622.9 5971.7 5981.6 6028.2 6038.1 5179.0 6135.5 6142.4 6149.0 6165.5 6141.8 6153.1 6160.5 6171.6 6221.4 5905.7 5926.1 5938.9 5980.6 5989.5 6334.7 6363.8 6410.6 6414.2 6434.1 5144.3 6128.1 6152.5 6175.4 6213.1 4707.5 5046.9 5231.6 5889.2 6291.0 5058.8 6141.7 6212.1 6228.5 6254.7 6130.3 6141.7 6164.0 6181.1 6232.4 5038.8 5043.5 5043.8 5048.9 5063.4 3785.3 4425.9 4508.3 4509.1 4538.0 5758.9 5949.7 5956.7 6050.6 6122.5 6269.2 6310.6 6333.9 6339.4 6343.3 5906.8 5992.7 6016.5 6079.7 6087.4 5444.6 5483.3 5488.5 5531.8 5551.9 6449.8 6521.4 6523.4 6566.9 6569.9 6292.5 6306.6 6329.2 6330.6 6354.4 6262.5 6337.5 6472.3 6513.1 6526.2 6140.9 6151.4 6157.9 6246.0 6257.2 6309.1 6316.7 6317.9 6320.1 6336.6 5177.9 5910.4 6090.8 6145.0 6218.3 6121.5 6124.0 6129.8 6169.7 6213.7 6022.7 6116.8 6137.2 6138.2 6166.0 6102.5 6135.4 6147.4 6159.0 6211.8 6054.0 6074.5 6083.3 6159.6 6174.9 10:06:29 INFO - PROCESS | 1931 | 10:06:29 INFO - PROCESS | 1931 | [#1] ext.html Cycles:180 Average:66335.13 Median:39845.90 stddev:145546.16 (365.3%) stddev-sans-first:145870.42 10:06:29 INFO - PROCESS | 1931 | Values: 462.0 522.1 523.2 538.8 538.8 31853.4 39551.6 40723.0 40745.0 40773.6 41668.0 41966.2 42170.0 42223.2 42237.8 9305.3 9462.8 9481.0 9486.2 9513.6 8795.9 9493.7 9532.3 9559.7 9560.2 7309.6 8247.1 8333.1 8719.8 9565.7 8272.0 9496.1 9547.8 9583.2 9591.3 9374.6 9411.3 9458.1 9479.3 9480.9 39629.6 39872.0 39881.2 39891.8 39976.0 3297.2 3615.7 3899.7 3942.5 3947.7 28311.3 28914.0 28995.9 29943.7 30567.1 3137.0 3212.3 3233.3 3240.4 3267.8 282459.9 361286.0 364386.0 364904.0 366347.2 57998.4 58162.4 58334.7 58349.7 58632.7 60825.4 61199.0 61211.0 61702.7 61875.0 15844.8 15894.4 16041.9 16053.1 16104.4 12501.9 12530.8 12578.6 12583.5 12598.2 16080.7 16387.5 16537.6 16540.8 16577.6 3217.9 5918.4 6821.2 6983.8 7017.1 844.9 844.9 903.2 939.6 1007.6 23003.0 29387.0 29981.0 30361.7 30554.0 921.4 966.9 1041.5 1102.3 1105.3 14683.9 15750.1 16073.4 16108.3 16162.5 643194.0 650892.0 654067.0 655914.0 663609.0 57062.7 57331.7 57367.0 57388.4 57487.0 1317.6 1341.7 1366.0 1367.6 1372.4 28754.1 29377.0 29973.7 30385.9 30427.5 50199.0 50405.2 50648.0 50679.7 50764.0 577723.0 578604.0 580666.0 581392.0 582476.0 24910.6 26467.9 27289.5 27390.7 28427.4 45942.1 46043.8 46499.8 46718.0 47089.3 37389.6 37963.2 38952.4 39970.6 40097.0 38254.2 38402.8 38892.2 39676.2 39819.8 37464.0 37528.2 38129.6 38172.6 38241.2 27944.7 32640.2 34161.8 34616.7 34622.0 24017.4 25106.9 25162.9 25362.6 25837.9 10:06:29 INFO - PROCESS | 1931 | 10:06:29 INFO - PROCESS | 1931 | [#2] jquery.html Cycles:180 Average:37357.42 Median:19244.96 stddev:105212.90 (546.7%) stddev-sans-first:105471.32 10:06:29 INFO - PROCESS | 1931 | Values: 332.2 376.8 383.1 387.7 403.6 11125.5 11577.5 11667.8 11693.4 18549.9 20327.4 20591.0 20845.5 21126.0 21325.7 6531.0 6890.0 6892.6 6898.7 6931.1 6666.3 7101.8 7427.7 7502.5 7587.7 6974.5 7530.1 7534.8 7535.8 7571.6 7276.5 7568.8 7612.2 7644.6 7680.3 7154.5 7243.3 7251.4 7255.5 7271.2 27144.0 27146.0 27786.7 27800.3 28118.9 2411.3 2499.1 2545.5 2563.4 2603.0 15845.7 16034.0 16068.8 16487.8 17396.2 1923.7 1999.7 2013.2 2056.9 2061.2 120161.2 138122.3 140274.0 149944.0 152295.0 17287.5 21715.9 21739.1 21927.2 22172.1 35063.3 35200.8 35649.5 35931.8 36007.0 9740.3 9901.3 10639.5 10640.3 10710.1 8755.0 8800.4 8821.3 8955.7 8965.9 8249.4 9654.0 9825.4 9910.2 9930.6 3426.6 3595.3 3786.3 3868.0 3887.3 810.3 886.4 908.4 950.8 1023.6 19480.0 19830.6 20396.7 20657.2 20758.4 881.3 938.6 945.3 959.8 1002.1 10461.1 10897.2 10903.0 10943.7 10963.7 617491.0 623977.0 633211.0 642492.0 644327.0 87539.6 96076.6 108661.0 124697.0 126078.0 839.5 989.8 1007.6 1009.8 1010.9 18546.7 18862.2 18961.4 18991.6 19020.8 49283.8 49426.8 50242.7 55013.2 56719.6 74000.6 80062.2 80828.0 84833.7 85216.1 6664.7 7334.0 7401.9 7563.3 7772.1 10792.5 12508.6 12523.8 12599.1 12701.0 10719.3 10855.7 11011.8 11079.6 11147.4 8446.7 10709.4 10736.2 10761.5 10811.5 10691.6 10927.2 11065.8 11156.6 11218.5 7417.3 7450.7 7476.4 7582.7 7727.4 9978.2 10655.6 10744.6 10778.6 11001.9 10:06:29 INFO - PROCESS | 1931 | 10:06:29 INFO - PROCESS | 1931 | [#3] mootools.html Cycles:180 Average:5486.10 Median:4942.23 stddev:3035.03 (61.4%) stddev-sans-first:3027.93 10:06:29 INFO - PROCESS | 1931 | Values: 1388.8 1454.2 1626.0 1729.4 1779.9 7520.9 7802.9 8083.5 8536.0 8548.9 7941.2 8275.9 8303.6 8389.5 8413.6 1962.3 2326.6 2367.0 2369.4 2377.4 2121.6 2201.8 2358.7 2390.3 2406.6 1990.3 2338.9 2356.5 2368.8 2374.1 2327.3 2331.9 2345.4 2352.8 2358.6 2361.3 2366.8 2371.5 2373.5 2391.0 8363.7 8454.0 8479.5 8492.0 8520.8 5089.9 5233.2 5246.0 5248.6 5249.2 3030.9 3244.8 3292.9 3637.9 3642.8 1017.2 1063.9 1092.6 1096.8 1115.3 10960.3 10979.9 11031.7 11123.6 11157.9 4574.9 4641.3 4716.4 4718.2 4811.2 9480.6 9561.1 9576.2 9597.5 9608.2 5278.4 5280.8 5282.6 5289.6 5295.4 4328.7 4342.7 4810.8 4814.5 4855.4 3547.9 3558.9 3786.6 3912.4 3928.4 1268.8 1274.4 1278.9 1289.0 1291.9 797.3 849.9 871.4 879.1 899.0 4948.8 5707.1 5766.6 5774.0 5825.7 383.9 428.3 439.8 442.4 443.4 3839.7 3841.9 3915.8 3930.6 3939.9 9859.5 10060.0 10185.8 10241.2 10242.8 10206.2 10208.7 10209.3 10210.4 10258.1 7494.8 7842.1 7886.7 7989.4 7991.2 5731.6 5825.4 5903.2 5995.5 6005.9 9107.8 9378.7 9424.1 9433.3 9442.0 9474.2 9494.6 9502.3 9575.0 9658.8 6533.5 6766.5 6969.9 7060.9 7212.2 7772.9 7901.3 7991.5 8002.8 8011.5 7034.4 7199.5 7231.5 7254.3 7338.8 7005.2 7087.1 7132.8 7194.5 7241.0 6734.2 6736.0 6802.4 6816.0 6818.2 4876.5 4899.4 4931.6 4935.7 4983.2 4844.0 4910.9 4929.5 4965.9 5057.8 10:06:29 INFO - PROCESS | 1931 | 10:06:29 INFO - PROCESS | 1931 | [#4] prototype.html Cycles:180 Average:4660.30 Median:5223.14 stddev:1472.77 (28.2%) stddev-sans-first:1440.08 10:06:29 INFO - PROCESS | 1931 | Values: 300.2 307.6 315.5 318.4 320.8 4500.0 5150.0 5371.6 5628.9 5703.5 5297.9 5423.3 5555.8 5561.9 5562.5 4006.2 4257.8 4265.1 4316.7 4331.7 3754.8 3770.3 3817.7 3979.4 4300.4 3915.9 4126.1 4156.0 4162.9 4196.8 3826.5 3980.3 4011.8 4076.9 4172.7 2802.8 3573.3 3604.3 3642.5 3650.3 4919.8 5177.4 5180.6 5412.9 5491.2 3835.3 4132.9 4211.7 4708.3 4715.2 5774.8 5822.5 5823.5 5830.9 5880.6 3270.6 3569.8 3693.6 3906.0 3965.7 5010.8 5339.9 5992.3 6095.4 6180.6 3491.9 3738.6 3836.8 3992.5 4011.9 3825.5 3848.8 4069.4 4086.5 4104.6 4741.8 4747.9 4774.4 4785.2 4801.8 5057.2 5584.7 5592.9 5925.8 5943.6 1315.5 1329.0 1329.9 1338.3 1345.3 762.3 772.8 895.6 896.8 909.5 4733.0 4752.6 5008.7 5062.0 5149.7 5427.9 5783.0 5795.2 5845.4 5855.0 4657.5 5086.4 5222.8 5243.0 5411.6 2596.5 2759.6 2774.2 2797.8 2878.9 5958.4 5998.6 6020.9 6059.9 6082.8 4884.8 4979.5 5639.0 5734.3 5882.6 5159.4 5431.9 5920.6 6001.3 6008.9 4748.4 5376.1 5560.6 5800.7 5857.7 5380.3 5822.0 6009.3 6087.9 6100.8 6051.0 6106.6 6206.4 6396.3 6425.2 3615.2 3995.9 4057.9 4062.4 4098.3 4788.6 5205.1 6070.9 6148.8 6237.9 5239.5 5297.9 5918.9 5931.8 5939.5 5550.9 5865.7 5926.8 5971.6 6000.8 5657.3 5712.6 5815.7 5850.2 5853.5 5223.4 5880.6 5906.7 5943.9 5963.8 5559.9 5779.6 5807.3 5812.3 5812.6 10:06:29 INFO - PROCESS | 1931 | 10:06:29 INFO - PROCESS | 1931 | [#5] yui.html Cycles:180 Average:19453.05 Median:27201.15 stddev:27479.56 (101.0%) stddev-sans-first:27520.51 10:06:29 INFO - PROCESS | 1931 | Values: 684.0 709.7 710.1 741.5 756.9 14131.0 15016.9 15027.8 15034.6 15076.0 13405.5 13417.1 13445.5 13446.5 13614.0 654.9 666.8 803.5 804.7 827.1 819.8 834.4 837.0 837.0 838.3 860.7 878.3 880.1 884.9 885.0 865.8 875.3 876.8 878.6 882.5 654.3 655.0 656.7 657.5 660.2 13734.0 14081.9 14087.4 14147.9 14594.0 13418.3 13565.7 13672.9 13854.7 13858.0 11272.3 11362.4 11559.9 11562.5 11636.8 3120.7 3618.5 3709.1 3723.1 3732.7 82688.8 88590.0 89903.1 90971.0 91675.9 9566.8 10372.4 10611.9 10770.9 10802.2 17586.6 20977.4 21304.4 21412.0 21422.0 7080.4 7308.6 7625.9 7640.2 7687.7 3728.7 4109.7 4219.4 4268.7 4321.7 2631.8 2899.4 3341.1 3494.1 3499.4 652.0 681.1 686.7 694.0 704.5 633.2 635.0 638.3 658.5 684.6 9109.3 9184.4 9307.5 9392.8 9584.4 529.7 549.9 611.5 635.0 674.5 4175.6 4276.6 4345.2 4347.2 4504.3 84549.2 94020.1 95569.0 96538.5 98082.5 67128.0 80091.2 87018.0 87899.6 92060.1 81896.6 83832.0 83964.2 85091.6 87334.6 9539.5 9750.4 9821.7 9823.3 9838.9 40546.0 41223.4 43188.8 49898.0 52775.0 55156.7 63526.0 73826.3 76593.0 78129.2 12060.3 12451.9 12496.8 12509.8 12534.0 14226.7 14329.8 14413.8 14438.0 14750.3 12707.7 13172.9 13316.9 13432.3 14090.6 11075.4 13124.0 13340.9 13960.8 14001.0 12811.2 13012.5 13629.0 13683.4 13710.6 10672.4 10754.8 10974.6 11106.8 11479.2 9528.8 10816.3 11427.8 11436.4 11446.8 10:06:29 INFO - PROCESS | 1931 | -------- Summary: end -------- 10:06:29 INFO - PROCESS | 1931 | 10:06:30 INFO - TEST-INFO | 1931: exit 0 10:06:30 INFO - TEST-OK | dromaeo_css | took 1116111ms 10:06:30 INFO - TEST-START | kraken 10:06:30 INFO - operating with platform_type : mac_ 10:06:30 INFO - Initialising browser for kraken test... 10:06:30 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/tmpXqts15/profile http://localhost:54562/getInfo.html) 10:06:33 INFO - PROCESS | 1939 | 2017-01-06 10:06:33.155 plugin-container[1940:20164] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 10:06:33 INFO - PROCESS | 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:33 INFO - PROCESS | 1939 | 2017-01-06 10:06:33.157 plugin-container[1940:20164] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x880b, name = 'com.apple.CFPasteboardClient' 10:06:33 INFO - PROCESS | 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:33 INFO - PROCESS | 1939 | 2017-01-06 10:06:33.158 plugin-container[1940:20164] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:06:33 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 10:06:33 INFO - PROCESS | 1939 | colorDepth:24 10:06:33 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 10:06:33 INFO - PROCESS | 1939 | __metrics 10:06:33 ERROR - PROCESS | 1939 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 10:06:34 INFO - TEST-INFO | 1939: exit 0 10:06:34 INFO - Browser initialized. 10:06:34 INFO - Running cycle 1/1 for kraken test... 10:06:34 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/tmpXqts15/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:06:36 INFO - PROCESS | 1941 | 2017-01-06 10:06:36.807 plugin-container[1942:20286] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 10:06:36 INFO - PROCESS | 1941 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:36 INFO - PROCESS | 1941 | 2017-01-06 10:06:36.808 plugin-container[1942:20286] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 10:06:36 INFO - PROCESS | 1941 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:36 INFO - PROCESS | 1941 | 2017-01-06 10:06:36.808 plugin-container[1942:20286] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:06:46 INFO - PROCESS | 1941 | RSS: Main: 229945344 10:06:46 INFO - PROCESS | 1941 | 10:06:49 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:54562/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54562/tests/kraken/driver.html?testName=audio-beat-detection) 10:06:50 INFO - PROCESS | 1941 | RSS: Main: 241823744 10:06:50 INFO - PROCESS | 1941 | 10:06:52 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:54562/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54562/tests/kraken/driver.html?testName=audio-dft) 10:06:53 INFO - PROCESS | 1941 | RSS: Main: 249462784 10:06:53 INFO - PROCESS | 1941 | 10:06:56 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:54562/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54562/tests/kraken/driver.html?testName=audio-fft) 10:06:57 INFO - PROCESS | 1941 | RSS: Main: 253132800 10:06:57 INFO - PROCESS | 1941 | 10:06:59 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:54562/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54562/tests/kraken/driver.html?testName=audio-oscillator) 10:07:00 INFO - PROCESS | 1941 | RSS: Main: 253702144 10:07:00 INFO - PROCESS | 1941 | 10:07:02 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:54562/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54562/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:07:02 INFO - PROCESS | 1941 | RSS: Main: 254857216 10:07:02 INFO - PROCESS | 1941 | 10:07:05 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:54562/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54562/tests/kraken/driver.html?testName=imaging-darkroom) 10:07:06 INFO - PROCESS | 1941 | RSS: Main: 259141632 10:07:06 INFO - PROCESS | 1941 | 10:07:09 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:54562/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54562/tests/kraken/driver.html?testName=imaging-desaturate) 10:07:10 INFO - PROCESS | 1941 | RSS: Main: 256077824 10:07:10 INFO - PROCESS | 1941 | 10:07:13 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:54562/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54562/tests/kraken/driver.html?testName=json-parse-financial) 10:07:14 INFO - PROCESS | 1941 | RSS: Main: 257384448 10:07:14 INFO - PROCESS | 1941 | 10:07:15 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:54562/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54562/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:07:16 INFO - PROCESS | 1941 | RSS: Main: 256995328 10:07:16 INFO - PROCESS | 1941 | 10:07:18 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:54562/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54562/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:07:18 INFO - PROCESS | 1941 | RSS: Main: 257077248 10:07:18 INFO - PROCESS | 1941 | 10:07:20 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:54562/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54562/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:07:21 INFO - PROCESS | 1941 | RSS: Main: 257224704 10:07:21 INFO - PROCESS | 1941 | 10:07:24 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:54562/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54562/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:07:25 INFO - PROCESS | 1941 | RSS: Main: 257454080 10:07:25 INFO - PROCESS | 1941 | 10:07:28 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:54562/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54562/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:07:28 INFO - PROCESS | 1941 | RSS: Main: 257277952 10:07:28 INFO - PROCESS | 1941 | 10:07:30 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:54562/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54562/tests/kraken/driver.html?testName=ai-astar) 10:07:30 INFO - PROCESS | 1941 | RSS: Main: 258961408 10:07:30 INFO - PROCESS | 1941 | 10:07:30 INFO - PROCESS | 1941 | __start_tp_report 10:07:30 INFO - PROCESS | 1941 | _x_x_mozilla_page_load 10:07:30 INFO - PROCESS | 1941 | _x_x_mozilla_page_load_details 10:07:30 INFO - PROCESS | 1941 | |i|pagename|runs| 10:07:30 INFO - PROCESS | 1941 | |0;ai-astar;106;119;119;122;118;119;121;120;119;120 10:07:30 INFO - PROCESS | 1941 | |1;audio-beat-detection;143;114;109;151;112;123;114;110;138;113 10:07:30 INFO - PROCESS | 1941 | |2;audio-dft;195;202;197;202;233;193;194;192;196;194 10:07:30 INFO - PROCESS | 1941 | |3;audio-fft;84;74;54;81;66;70;50;84;104;58 10:07:30 INFO - PROCESS | 1941 | |4;audio-oscillator;87;70;92;75;68;59;113;100;75;86 10:07:30 INFO - PROCESS | 1941 | |5;imaging-gaussian-blur;122;131;88;87;88;115;79;118;134;131 10:07:30 INFO - PROCESS | 1941 | |6;imaging-darkroom;169;111;170;127;102;173;170;169;169;102 10:07:30 INFO - PROCESS | 1941 | |7;imaging-desaturate;136;89;87;112;117;90;94;87;88;92 10:07:30 INFO - PROCESS | 1941 | |8;json-parse-financial;57;57;57;60;59;60;60;59;60;59 10:07:30 INFO - PROCESS | 1941 | |9;json-stringify-tinderbox;75;66;66;83;72;70;70;70;73;75 10:07:30 INFO - PROCESS | 1941 | |10;stanford-crypto-aes;83;86;84;84;85;82;83;84;84;84 10:07:30 INFO - PROCESS | 1941 | |11;stanford-crypto-ccm;157;135;172;156;161;164;164;141;217;182 10:07:30 INFO - PROCESS | 1941 | |12;stanford-crypto-pbkdf2;168;167;166;168;170;185;181;177;168;226 10:07:30 INFO - PROCESS | 1941 | |13;stanford-crypto-sha256-iterative;68;67;71;72;67;65;63;62;83;86 10:07:30 INFO - PROCESS | 1941 | __end_tp_report 10:07:30 INFO - PROCESS | 1941 | __start_cc_report 10:07:30 INFO - PROCESS | 1941 | _x_x_mozilla_cycle_collect,959 10:07:30 INFO - PROCESS | 1941 | __end_cc_report 10:07:30 INFO - PROCESS | 1941 | __startTimestamp1483726050986__endTimestamp 10:07:30 INFO - PROCESS | 1941 | 10:07:30 INFO - PROCESS | 1941 | ------- Summary: start ------- 10:07:30 INFO - PROCESS | 1941 | Number of tests: 14 10:07:30 INFO - PROCESS | 1941 | 10:07:30 INFO - PROCESS | 1941 | [#0] ai-astar Cycles:10 Average:118.30 Median:119.50 stddev:4.47 (3.7%) stddev-sans-first:1.22 10:07:30 INFO - PROCESS | 1941 | Values: 106.0 119.0 119.0 122.0 118.0 119.0 121.0 120.0 119.0 120.0 10:07:30 INFO - PROCESS | 1941 | 10:07:30 INFO - PROCESS | 1941 | [#1] audio-beat-detection Cycles:10 Average:122.70 Median:118.50 stddev:15.48 (13.1%) stddev-sans-first:14.57 10:07:30 INFO - PROCESS | 1941 | Values: 143.0 114.0 109.0 151.0 112.0 123.0 114.0 110.0 138.0 113.0 10:07:30 INFO - PROCESS | 1941 | 10:07:30 INFO - PROCESS | 1941 | [#2] audio-dft Cycles:10 Average:199.80 Median:196.50 stddev:12.16 (6.2%) stddev-sans-first:12.78 10:07:30 INFO - PROCESS | 1941 | Values: 195.0 202.0 197.0 202.0 233.0 193.0 194.0 192.0 196.0 194.0 10:07:30 INFO - PROCESS | 1941 | 10:07:30 INFO - PROCESS | 1941 | [#3] audio-fft Cycles:10 Average:72.50 Median:72.00 stddev:16.46 (22.9%) stddev-sans-first:16.92 10:07:30 INFO - PROCESS | 1941 | Values: 84.0 74.0 54.0 81.0 66.0 70.0 50.0 84.0 104.0 58.0 10:07:30 INFO - PROCESS | 1941 | 10:07:30 INFO - PROCESS | 1941 | [#4] audio-oscillator Cycles:10 Average:82.50 Median:75.00 stddev:16.30 (21.7%) stddev-sans-first:17.20 10:07:30 INFO - PROCESS | 1941 | Values: 87.0 70.0 92.0 75.0 68.0 59.0 113.0 100.0 75.0 86.0 10:07:31 INFO - PROCESS | 1941 | 10:07:31 INFO - PROCESS | 1941 | [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:106.50 stddev:21.46 (20.1%) stddev-sans-first:22.26 10:07:31 INFO - PROCESS | 1941 | Values: 122.0 131.0 88.0 87.0 88.0 115.0 79.0 118.0 134.0 131.0 10:07:31 INFO - PROCESS | 1941 | 10:07:31 INFO - PROCESS | 1941 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:169.00 stddev:31.49 (18.6%) stddev-sans-first:32.30 10:07:31 INFO - PROCESS | 1941 | Values: 169.0 111.0 170.0 127.0 102.0 173.0 170.0 169.0 169.0 102.0 10:07:31 INFO - PROCESS | 1941 | 10:07:31 INFO - PROCESS | 1941 | [#7] imaging-desaturate Cycles:10 Average:99.20 Median:88.50 stddev:16.75 (18.9%) stddev-sans-first:11.30 10:07:31 INFO - PROCESS | 1941 | Values: 136.0 89.0 87.0 112.0 117.0 90.0 94.0 87.0 88.0 92.0 10:07:31 INFO - PROCESS | 1941 | 10:07:31 INFO - PROCESS | 1941 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:59.50 stddev:1.32 (2.2%) stddev-sans-first:1.22 10:07:31 INFO - PROCESS | 1941 | Values: 57.0 57.0 57.0 60.0 59.0 60.0 60.0 59.0 60.0 59.0 10:07:31 INFO - PROCESS | 1941 | 10:07:31 INFO - PROCESS | 1941 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.50 stddev:4.99 (6.9%) stddev-sans-first:5.17 10:07:31 INFO - PROCESS | 1941 | Values: 75.0 66.0 66.0 83.0 72.0 70.0 70.0 70.0 73.0 75.0 10:07:31 INFO - PROCESS | 1941 | 10:07:31 INFO - PROCESS | 1941 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.00 stddev:1.10 (1.3%) stddev-sans-first:1.12 10:07:31 INFO - PROCESS | 1941 | Values: 83.0 86.0 84.0 84.0 85.0 82.0 83.0 84.0 84.0 84.0 10:07:31 INFO - PROCESS | 1941 | 10:07:31 INFO - PROCESS | 1941 | [#11] stanford-crypto-ccm Cycles:10 Average:164.90 Median:164.00 stddev:22.81 (13.9%) stddev-sans-first:24.01 10:07:31 INFO - PROCESS | 1941 | Values: 157.0 135.0 172.0 156.0 161.0 164.0 164.0 141.0 217.0 182.0 10:07:31 INFO - PROCESS | 1941 | 10:07:31 INFO - PROCESS | 1941 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:173.50 stddev:18.23 (10.5%) stddev-sans-first:19.00 10:07:31 INFO - PROCESS | 1941 | Values: 168.0 167.0 166.0 168.0 170.0 185.0 181.0 177.0 168.0 226.0 10:07:31 INFO - PROCESS | 1941 | 10:07:31 INFO - PROCESS | 1941 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:69.50 stddev:8.09 (11.6%) stddev-sans-first:8.53 10:07:31 INFO - PROCESS | 1941 | Values: 68.0 67.0 71.0 72.0 67.0 65.0 63.0 62.0 83.0 86.0 10:07:31 INFO - PROCESS | 1941 | -------- Summary: end -------- 10:07:31 INFO - PROCESS | 1941 | 10:07:31 INFO - TEST-INFO | 1941: exit 0 10:07:32 INFO - TEST-OK | kraken | took 61746ms 10:07:32 INFO - SUITE-END | took 1177s 10:07:32 INFO - Completed test suite (00:19:37) 10:07:32 INFO - javascript benchmark 10:07:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7350.164557945248, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3195.3130077033434, 3406.6763657452548, 3436.9828150859244, 3444.7760895541774, 3467.5145479632847, 5886.764529418823, 5935.347111817702, 5949.524038076955, 5951.940480595193, 5976.342602313742, 5914.852128696784, 5957.463828255456, 5981.58128930975, 5989.610675306102, 5999.49004334631, 5323.733813309338, 5371.006363822686, 5405.648632838873, 5412.323459567554, 5420.593455490854, 5418.431064738204, 5419.458054194569, 5423.945760542403, 5438.510534051935, 5455.754491047913, 4107.219628270634, 4752.358431611723, 5454.400015998237, 5458.181272809071, 5508.366537848158, 5327.946720532784, 5335.306410166692, 5390.6226564140525, 5453.64551304164, 5485.451454854483, 5271.736413179326, 5346.064438723208, 5382.542483888874, 5393.622446428752, 5436.809711660112, 5622.859428514279, 5971.731272092723, 5981.641101533922, 6028.216331876872, 6038.064100064497, 5178.97410512945, 6135.5398345124295, 6142.406692085136, 6149.0, 6165.475934545569, 6141.762184092961, 6153.138560601519, 6160.476359509447, 6171.629702217836, 6221.4089661482085, 5905.73424195908, 5926.1110833374905, 5938.910916336258, 5980.641161530322, 5989.520838332923, 6334.714937827809, 6363.7772677956045, 6410.55126141175, 6414.230292364853, 6434.067060276234, 5144.254083157921, 6128.075379514901, 6152.538559608047, 6175.3515880832765, 6213.130161777355, 4707.458642256116, 5046.924296135561, 5231.633785635046, 5889.1755154278435, 6290.99344104941, 5058.848234552969, 6141.731496609903, 6212.1303017577575, 6228.47058000066, 6254.718537665816, 6130.275842464065, 6141.700809433414, 6163.96918015407, 6181.103739957681, 6232.376762323732, 5038.84883453497, 5043.52086551777, 5043.823466178666, 5048.873778155502, 5063.443021267658, 3785.280796648628, 4425.889352766142, 4508.301213311942, 4509.143262780061, 4538.001639639274, 5758.913616295759, 5949.732262048218, 5956.6723830188885, 6050.606710563799, 6122.479589234965, 6269.247690277105, 6310.558260921692, 6333.936660633427, 6339.366063393716, 6343.333949935282, 5906.822795316148, 5992.670403127782, 6016.458518733335, 6079.696015199311, 6087.360827113178, 5444.6188766785945, 5483.287172667528, 5488.451154884479, 5531.751071201806, 5551.8889622207325, 6449.774257900951, 6521.413072823469, 6523.4128928394775, 6566.9343306566325, 6569.9343006571235, 6292.4651404629285, 6306.558540902093, 6329.208848893888, 6330.556861019684, 6354.364563543609, 6262.46769024638, 6337.493000559852, 6472.255690595453, 6513.088167656437, 6526.151600291932, 6140.8771824564155, 6151.384861513813, 6157.876842463215, 6246.0, 6257.2491301044165, 6309.085182648582, 6316.652584107918, 6317.936820631735, 6320.083587879823, 6336.619802811846, 5177.922331165111, 5910.408959104056, 6090.847728806638, 6145.0, 6218.253809542884, 6121.510279177744, 6124.045297608125, 6129.817282374624, 6169.691515424301, 6213.720382582885, 6022.698865056817, 6116.847078822887, 6137.223770008752, 6138.1713468681155, 6166.0, 6102.4507794298725, 6135.386461353829, 6147.415995480422, 6158.952977993662, 6211.81364559064, 6054.0, 6074.544409169242, 6083.330833608388, 6159.630422174684, 6174.9073763892675], "value": 5755.625311407009, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.98152073917043, 522.0524747583137, 523.2073408785687, 538.7925648625279, 538.808722903369, 31853.362932741362, 39551.604483955154, 40723.0, 40744.99999999996, 40773.59226407735, 41667.95830104249, 41966.16067678644, 42170.0, 42223.155536889244, 42237.7888110559, 9305.348625596194, 9462.763430914249, 9481.004494528079, 9486.193673537757, 9513.619455221782, 8795.900512435945, 9493.667721629745, 9532.285078619132, 9559.665411710592, 9560.235181185522, 7309.611173876973, 8247.092819789817, 8333.083360830335, 8719.782005449852, 9565.66520171794, 8272.007359116884, 9496.097870702271, 9547.761305967337, 9583.185429238523, 9591.280653950911, 9374.625014999392, 9411.294152938557, 9458.148766611039, 9479.289053321028, 9480.862296524461, 39629.60370396288, 39872.0, 39881.202375952606, 39891.80054099711, 39976.000599984945, 3297.159224397787, 3615.7468977171607, 3899.7270191086636, 3942.526896772377, 3947.664448521879, 28311.292217694518, 28914.0, 28995.85502072476, 29943.70056299431, 30567.08298751041, 3136.952945705816, 3212.3254116635535, 3233.321002589459, 3240.4329242382723, 3267.754918381107, 282459.9385015391, 361286.0, 364386.0, 364904.0, 366347.16826415964, 57998.42001580015, 58162.4183758157, 58334.708326458094, 58349.70825145847, 58632.70683646554, 60825.39174608286, 61199.0, 61211.0, 61702.691486543175, 61875.0, 15844.841551584337, 15894.364225430852, 16041.919790400972, 16053.117078560792, 16104.436344728112, 12501.937490312672, 12530.81203781944, 12578.55975040869, 12583.496660133493, 12598.181118227469, 16080.678386432206, 16387.508374748777, 16537.586560335847, 16540.75188872168, 16577.585560361087, 3217.935641287208, 5918.408159184047, 6821.1814582250445, 6983.790486285419, 7017.087778588751, 844.9239568438747, 844.928181104613, 903.2006674093475, 939.6006697153819, 1007.5868893753526, 23003.0, 29386.971455998937, 29981.0, 30361.696383036327, 30554.0, 921.447131720962, 966.9218821014518, 1041.5156952017196, 1102.327580176092, 1105.3312745788842, 14683.853161468249, 15750.133742644264, 16073.437429690139, 16108.27512761905, 16162.515124546047, 643194.0, 650892.0, 654067.0, 655914.0, 663609.0, 57062.714686427134, 57331.713341433024, 57366.99999999916, 57388.42611573831, 57487.0, 1317.6244770240628, 1341.6645838540364, 1365.9658508537366, 1367.589723083091, 1372.3961456958907, 28754.137375878756, 29377.0, 29973.700262997092, 30385.93649222355, 30427.54358684577, 50199.0, 50405.24392134194, 50647.98704025825, 50679.74660126676, 50764.0, 577723.0, 578604.0, 580666.0, 581392.0, 582476.0, 24910.626340605268, 26467.867660661574, 27289.45421091607, 27390.726092739616, 28427.431451370445, 45942.08115837731, 46043.76978115088, 46499.76750116228, 46718.0, 47089.293660594434, 37389.626103738614, 37963.2407351857, 38952.41571376375, 39970.600293996686, 40096.99757506086, 38254.23491530209, 38402.807985959895, 38892.22215555616, 39676.206475869745, 39819.80540583787, 37464.0, 37528.24943501169, 38129.618703813714, 38172.61827381691, 38241.235175295784, 27944.720552794213, 32640.183995400304, 34161.80433684908, 34616.65383346134, 34622.0, 24017.399565011016, 25106.874465627556, 25162.867670955216, 25362.619560706236, 25837.870810645825], "value": 19357.617332370297, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.1695760598504, 376.79464691743, 383.1111820576263, 387.7305272835377, 403.64277614311345, 11125.499352529136, 11577.53689852405, 11667.766644667101, 11693.356865372401, 18549.907250463744, 20327.390178294638, 20591.0, 20845.47886302843, 21126.0, 21325.68011479829, 6530.967345163268, 6889.965550172267, 6892.5864448133025, 6898.724051037952, 6931.133608298963, 6666.333366663343, 7101.8224544386285, 7427.665755041036, 7502.474826762129, 7587.658555365022, 6974.511784175084, 7530.096388433369, 7534.849303013936, 7535.849283014337, 7571.583562904038, 7276.454265930023, 7568.810779730496, 7612.238776122371, 7644.617769111522, 7680.3087722105, 7154.499185057048, 7243.311885370934, 7251.383632391253, 7255.52839065459, 7271.200167981518, 27144.0, 27146.049888254012, 27786.722132778617, 27800.30499237515, 28118.859405702842, 2411.2525117213545, 2499.125306142854, 2545.5290771207387, 2563.4360440703017, 2602.984835913995, 15845.68308633821, 16034.0, 16068.839311606851, 16487.752683709754, 17396.21717022737, 1923.6729755941383, 1999.7000449932482, 2013.1846602126016, 2056.907439165241, 2061.154926480151, 120161.19758203547, 138122.3093884524, 140274.0, 149944.0, 152295.0, 17287.481375558753, 21715.914204289722, 21739.130434782746, 21927.232546860785, 22172.113115475524, 35063.29873402569, 35200.76797312082, 35649.46525802115, 35931.82034089813, 36007.0, 9740.318177727491, 9901.306908516335, 10639.521221544894, 10640.308379955279, 10710.08964238033, 8755.036945935943, 8800.383973121949, 8821.250193733476, 8955.686550970684, 8965.865512017324, 8249.381296402793, 9654.0, 9825.361351512129, 9910.15763660097, 9930.602775889029, 3426.612222050074, 3595.2629710909723, 3786.3306369009783, 3868.0, 3887.319719049155, 810.3112354498746, 886.4105369929053, 908.3777612335572, 950.7670620697835, 1023.559869256227, 19480.02599870029, 19830.603387932162, 20396.694049589267, 20657.17371305131, 20758.377248682562, 881.3345923827469, 938.643315540104, 945.2674177512483, 959.7840485890619, 1002.0830939690102, 10461.110805581458, 10897.237193396544, 10903.01872831449, 10943.726406839733, 10963.725906852393, 617491.0, 623977.0, 633211.0, 642492.0, 644327.0, 87539.56230218807, 96076.63731769354, 108661.0, 124697.0, 126078.0, 839.4921072750898, 989.7773001074845, 1007.6322142417964, 1009.8434742614907, 1010.8786945566579, 18546.721799172752, 18862.151203196165, 18961.43115706531, 18991.620167596848, 19020.80979190246, 49283.75358123187, 49426.75286623688, 50242.743931400546, 55013.174802377194, 56719.58201045007, 74000.62999684968, 80062.1993780078, 80828.0, 84833.72749408887, 85216.1478385233, 6664.666766661744, 7334.0, 7401.851962960818, 7563.281488258606, 7772.06735191758, 10792.514336854703, 12508.562200322946, 12523.812142818048, 12599.118061735591, 12701.047421443243, 10719.303245289031, 10855.728606784893, 11011.834822477509, 11079.612213572485, 11147.386893720599, 8446.704365347183, 10709.357438553712, 10736.194785390971, 10761.461926903467, 10811.459427028774, 10691.572337106429, 10927.193404771826, 11065.778684426428, 11156.55373785072, 11218.495167717309, 7417.332440080419, 7450.6647200875, 7476.401887849068, 7582.696692132253, 7727.381809455118, 9978.201743860329, 10655.627053053106, 10744.623938162127, 10778.568857245622, 11001.944990274997], "value": 10767.971366174826, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.798624199491, 1454.2147240490135, 1625.983740162598, 1729.3860679458799, 1779.9466016019517, 7520.88718669219, 7802.882956755639, 8083.474574152676, 8536.0, 8548.871766923494, 7941.205879412056, 8275.91724082759, 8303.626336814843, 8389.496630202193, 8413.57932103394, 1962.3131903833687, 2326.5928462519078, 2367.005857539829, 2369.3839601703507, 2377.4413012941945, 2121.628714974881, 2201.8330284949, 2358.7169539655265, 2390.3307074019303, 2406.602910519762, 1990.3033938121614, 2338.9415264618438, 2356.493353928905, 2368.786809187173, 2374.050379848057, 2327.301809457156, 2331.860088394702, 2345.3667509772267, 2352.752960939094, 2358.5872472317446, 2361.2916125162383, 2366.8461549999365, 2371.5019845832394, 2373.5134297468976, 2390.97609023911, 8363.749087527383, 8454.0, 8479.533625650587, 8491.980962284504, 8520.829583408358, 5089.949100508985, 5233.162693968947, 5245.973770131125, 5248.606354523387, 5249.186376111708, 3030.9090727278217, 3244.772865899388, 3292.901212963615, 3637.890863274106, 3642.832514653777, 1017.2065788684838, 1063.9361638301727, 1092.5738961804902, 1096.8299913513417, 1115.2639258089703, 10960.28758130727, 10979.890201097967, 11031.72420689481, 11123.55505779768, 11157.888421115686, 4574.862754117382, 4641.257398816173, 4716.410448693913, 4718.221493453541, 4811.206150985048, 9480.573374198178, 9561.091696288842, 9576.186024187882, 9597.520123993772, 9608.231341492663, 5278.366596008504, 5280.762365693553, 5282.6302158849285, 5289.629725919149, 5295.443978382292, 4328.653707703439, 4342.717723347972, 4810.807567697253, 4814.470408255089, 4855.417349918032, 3547.911302217465, 3558.8611644273583, 3786.6024067472517, 3912.4326972588815, 3928.4303775952326, 1268.7969924811987, 1274.3691872522975, 1278.8657190994998, 1288.9677758056123, 1291.9031072669588, 797.338209286291, 849.9235068843834, 871.372611719561, 879.1033146190849, 898.9865152022594, 4948.787547050923, 5707.1153971134545, 5766.625169364062, 5774.0, 5825.7087145642545, 383.92705385976575, 428.2783509786021, 439.8130794412362, 442.40938347306064, 443.41912095154805, 3839.6928245740273, 3841.8655347063277, 3915.8433662653174, 3930.567637559866, 3939.881803545898, 9859.457729824786, 10060.0, 10185.847212291821, 10241.23190760681, 10242.795144097225, 10206.183505319557, 10208.693739187836, 10209.336393134423, 10210.387376757419, 10258.12805911491, 7494.812629684301, 7842.098158711707, 7886.72396466121, 7989.44073914832, 7991.160928102466, 5731.598788084961, 5825.41745825431, 5903.203067585821, 5995.520358371233, 6005.87988240224, 9107.817843643223, 9378.67174648884, 9424.104710052534, 9433.339666223574, 9441.952790236004, 9474.194693451134, 9494.572744226389, 9502.287328450531, 9575.04249575037, 9658.75853103678, 6533.477321814338, 6766.526343156129, 6969.9303006969285, 7060.8940865886025, 7212.206657267591, 7772.883406749016, 7901.328387087162, 7991.480553763987, 8002.799930001796, 8011.479253848481, 7034.366906978396, 7199.496035277481, 7231.457640676865, 7254.310840470147, 7338.77983660491, 7005.159380874327, 7087.078679771596, 7132.786016419515, 7194.460415468756, 7241.0, 6734.158230221223, 6736.0, 6802.421794147355, 6816.0457535946, 6818.249992500721, 4876.512663637483, 4899.412070551414, 4931.605471562339, 4935.679180853233, 4983.202687569971, 4843.975780121077, 4910.92633610489, 4929.457759646364, 4965.875853103701, 5057.772400242072], "value": 4360.92599305079, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.17002986741664, 307.60472792461684, 315.48260852202384, 318.42206395392344, 320.77866272272144, 4499.977500112499, 5150.0, 5371.623986320959, 5628.887422251552, 5703.543716502685, 5297.867553311169, 5423.349198096224, 5555.805546805863, 5561.916571251424, 5562.471565201309, 4006.2187873364705, 4257.827918200945, 4265.083007153461, 4316.7121425644455, 4331.7400955942585, 3754.81225938702, 3770.340190466671, 3817.7136714746366, 3979.363301871701, 4300.440942677463, 3915.941260881075, 4126.0510082681, 4156.0, 4162.875113746577, 4196.769177695225, 3826.4834247376525, 3980.283548961171, 4011.756355529795, 4076.90039629591, 4172.687048471346, 2802.7897907657007, 3573.285342931398, 3604.3151801157696, 3642.5446819147605, 3650.2623032725455, 4919.803207871681, 5177.404598471187, 5180.559652429548, 5412.888973300416, 5491.2312276281355, 3835.3096442640312, 4132.938005929914, 4211.7472951623, 4708.340832283483, 4715.184653908235, 5774.769009239625, 5822.505087067604, 5823.5341172706085, 5830.883382332372, 5880.618054757128, 3270.640229574745, 3569.7679650822615, 3693.5752388475403, 3906.0, 3965.662918651918, 5010.845336505301, 5339.893202135935, 5992.310884248323, 6095.359987201325, 6180.567360284782, 3491.860325586948, 3738.551248931954, 3836.832189812747, 3992.54085780139, 4011.919761604752, 3825.464434979105, 3848.825413641284, 4069.38959156123, 4086.5300490443383, 4104.610062044101, 4741.83403580873, 4747.905041899143, 4774.427068751772, 4785.186518291899, 4801.759912004456, 5057.241413787888, 5584.720763961785, 5592.916106258409, 5925.762969481172, 5943.55423343251, 1315.4869600855675, 1328.9667758305927, 1329.8936085113169, 1338.290705925861, 1345.2713263456608, 762.3024932186987, 772.7990722412213, 895.6193617712446, 896.8251590416445, 909.4543274035526, 4733.0, 4752.619790416758, 5008.749562521859, 5061.988631255031, 5149.742512874342, 5427.945720542823, 5783.0, 5795.217645617788, 5845.386234445407, 5855.0, 4657.4876763555985, 5086.440491546001, 5222.843314700489, 5242.97378513105, 5411.621186516984, 2596.4807038592357, 2759.6274502941847, 2774.209350335145, 2797.8177021922934, 2878.8992385266415, 5958.433948774859, 5998.640081595118, 6020.939790602038, 6059.939400606026, 6082.787102451393, 4884.804607815719, 4979.502049794992, 5638.971805140948, 5734.254546908875, 5882.647041177457, 5159.432462429129, 5431.864203394789, 5920.615160014671, 6001.339852616209, 6008.909866352008, 4748.433017104417, 5376.0591896418755, 5560.6107572471155, 5800.680962546931, 5857.677827719516, 5380.296885301436, 5821.970890145522, 6009.278886533644, 6087.878242435215, 6100.759971819542, 6051.0, 6106.572539922163, 6206.441420272016, 6396.328385519453, 6425.228972523327, 3615.222727113683, 3995.92008159841, 4057.9043658211526, 4062.3797650515844, 4098.344264917599, 4788.6408519361885, 5205.115130427912, 6070.939290607037, 6148.800983808114, 6237.943407730088, 5239.47605239473, 5297.867553311044, 5918.911216331845, 5931.762729490944, 5939.495142912728, 5550.9444905552045, 5865.747333183408, 5926.762929482945, 5971.552133589911, 6000.789972350947, 5657.349404818334, 5712.6001179917, 5815.680137592304, 5850.2102216200265, 5853.473187413153, 5223.432970108876, 5880.647161170344, 5906.734196961061, 5943.940560594512, 5963.821085367446, 5559.916601251065, 5779.6014653918155, 5807.274090738658, 5812.3025236971835, 5812.564057695774], "value": 4183.448836560105, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.9897401538977, 709.6664567653203, 710.1052673631226, 741.4920779266203, 756.8675481790685, 14131.0, 15016.92491537542, 15027.774583381259, 15034.624134396645, 15075.999999999985, 13405.46378144873, 13417.127886687358, 13445.529406470776, 13446.462141514328, 13614.0, 654.8526581519168, 666.8032930285553, 803.5379656697393, 804.694216197844, 827.1439060572309, 819.778659761864, 834.4200780457585, 836.9539675317855, 837.0165056059136, 838.3125836813815, 860.7460799064291, 878.3061381508601, 880.1110878013188, 884.880541126946, 885.0397318908991, 865.8008658008662, 875.308506280038, 876.7983363826355, 878.556329053827, 882.5057967538199, 654.326044174501, 654.9607023578601, 656.6979189572802, 657.5068698476143, 660.2407231683554, 13734.0, 14081.859181408157, 14087.436502539886, 14147.929260353736, 14594.0, 13418.329083545783, 13565.660858478519, 13672.93163534186, 13854.653633659138, 13858.0, 11272.323660580392, 11362.431878406047, 11559.884401156049, 11562.479688414041, 11636.767264654743, 3120.719135277813, 3618.470102887828, 3709.091272638194, 3723.0506220913494, 3732.682721968636, 82688.75966860502, 88590.0, 89903.10096899078, 90971.0, 91675.8745856438, 9566.808663826685, 10372.377657340583, 10611.946940265403, 10770.892291076989, 10802.243842931077, 17586.560335991446, 20977.37067887966, 21304.36086917395, 21412.0, 21422.03600837966, 7080.433565314763, 7308.5614863108385, 7625.885611715828, 7640.159582445926, 7687.730929417556, 3728.664420202195, 4109.671226301889, 4219.38818871262, 4268.658507319407, 4321.719088259253, 2631.8026148038975, 2899.362140329124, 3341.081202669237, 3494.1090022044723, 3499.3526197653514, 651.9674016299166, 681.1349586025719, 686.6909890549173, 694.004104110603, 704.4505285877024, 633.2211380002623, 635.0315768453146, 638.2659941067809, 658.4732214228598, 684.6302996381899, 9109.344095259046, 9184.357095003286, 9307.488088155216, 9392.812143757086, 9584.424934503952, 529.6768970927731, 549.8570371703386, 611.4863514647717, 634.9776859256593, 674.4873895839197, 4175.5824417558, 4276.593723596254, 4345.217860785026, 4347.174036932973, 4504.301833215857, 84549.15450845413, 94020.0597994025, 95569.0, 96538.51730741301, 98082.50958745304, 67128.0, 80091.19908800838, 87018.0, 87899.56050219836, 92060.07939920649, 81896.59051704823, 83832.0, 83964.16035839685, 85091.5745421269, 87334.56332718296, 9539.475328856703, 9750.414975101517, 9821.656242031495, 9823.263255256028, 9838.9016109838, 40546.0, 41223.381649275885, 43188.784056079516, 49898.0, 52775.0, 55156.690598564215, 63526.0, 73826.26173738194, 76593.0, 78129.2187078122, 12060.336681482602, 12451.937740311603, 12496.812547811973, 12509.812352814897, 12533.997280217378, 14226.715465690835, 14329.785053224, 14413.78379324332, 14437.989340746048, 14750.2624868754, 12707.745845083231, 13172.934135329262, 13316.933415332862, 13432.331424936558, 14090.577282681126, 11075.446227688422, 13124.0, 13340.866591333963, 13960.79058814139, 14001.0, 12811.231326120462, 13012.479500819862, 13629.0, 13683.384247708678, 13710.588682339545, 10672.413017284123, 10754.838677419688, 10974.615888443866, 11106.845681751445, 11479.196456248317, 9528.809423811623, 10816.296940698829, 11427.828582571101, 11436.370999595103, 11446.771064578828], "value": 7203.4991240035615, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1578.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 119.0, 122.0, 118.0, 119.0, 121.0, 120.0, 119.0, 120.0], "value": 118.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 114.0, 109.0, 151.0, 112.0, 123.0, 114.0, 110.0, 138.0, 113.0], "value": 122.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 202.0, 197.0, 202.0, 233.0, 193.0, 194.0, 192.0, 196.0, 194.0], "value": 199.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 74.0, 54.0, 81.0, 66.0, 70.0, 50.0, 84.0, 104.0, 58.0], "value": 72.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 92.0, 75.0, 68.0, 59.0, 113.0, 100.0, 75.0, 86.0], "value": 82.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 131.0, 88.0, 87.0, 88.0, 115.0, 79.0, 118.0, 134.0, 131.0], "value": 109.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 111.0, 170.0, 127.0, 102.0, 173.0, 170.0, 169.0, 169.0, 102.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 89.0, 87.0, 112.0, 117.0, 90.0, 94.0, 87.0, 88.0, 92.0], "value": 99.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 60.0, 59.0, 60.0, 60.0, 59.0, 60.0, 59.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 66.0, 66.0, 83.0, 72.0, 70.0, 70.0, 70.0, 73.0, 75.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 84.0, 84.0, 85.0, 82.0, 83.0, 84.0, 84.0, 84.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 135.0, 172.0, 156.0, 161.0, 164.0, 164.0, 141.0, 217.0, 182.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 166.0, 168.0, 170.0, 185.0, 181.0, 177.0, 168.0, 226.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 71.0, 72.0, 67.0, 65.0, 63.0, 62.0, 83.0, 86.0], "value": 70.4, "unit": "score"}]}]} 10:07:32 INFO - Return code: 0 10:07:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:07:32 ERROR - # TBPL SUCCESS # 10:07:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:07:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:07:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:07:32 INFO - 2017-01-06 10:07:32.278 screenresolution[1946:20674] starting screenresolution argv=screenresolution get 10:07:32 INFO - 2017-01-06 10:07:32.282 screenresolution[1946:20674] Display 0: 1600x1200x32@60 10:07:32 INFO - 2017-01-06 10:07:32.289 screenresolution[1947:20677] starting screenresolution argv=screenresolution list 10:07:32 INFO - Available Modes on Display 0 10:07:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:07:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:07:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:07:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:07:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:07:32 INFO - Intel Iris: 10:07:32 INFO - Chipset Model: Intel Iris 10:07:32 INFO - Type: GPU 10:07:32 INFO - Bus: Built-In 10:07:32 INFO - VRAM (Dynamic, Max): 1536 MB 10:07:32 INFO - Vendor: Intel (0x8086) 10:07:32 INFO - Device ID: 0x0a2e 10:07:32 INFO - Revision ID: 0x0009 10:07:32 INFO - Displays: 10:07:32 INFO - Display: 10:07:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:07:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:07:32 INFO - Display Serial Number: 1600x1200 60 10:07:32 INFO - Main Display: Yes 10:07:32 INFO - Mirror: Off 10:07:32 INFO - Online: Yes 10:07:32 INFO - Rotation: Supported 10:07:32 INFO - Return code: 0 10:07:32 INFO - Running post-action listener: _resource_record_post_action 10:07:32 INFO - [mozharness: 2017-01-06 18:07:32.536483Z] Finished run-tests step (success) 10:07:32 INFO - Running post-run listener: _resource_record_post_run 10:07:32 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 786432; Write bytes: 661920768; Read time: 10; Write time: 1943 10:07:32 INFO - TinderboxPrint: CPU usage
54.5% 10:07:32 INFO - TinderboxPrint: I/O read bytes / time
786,432 / 10 10:07:32 INFO - TinderboxPrint: I/O write bytes / time
661,920,768 / 1,943 10:07:32 INFO - TinderboxPrint: CPU idle
2,200.4 (45.5%) 10:07:32 INFO - TinderboxPrint: CPU system
978.6 (20.2%) 10:07:32 INFO - TinderboxPrint: CPU user
1,658.8 (34.3%) 10:07:32 INFO - TinderboxPrint: Swap in / out
350,257,152 / 0 10:07:32 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349889024; Write bytes: 352516096; Read time: 26965; Write time: 752 10:07:32 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 655360; Write bytes: 308073472; Read time: 8; Write time: 1181 10:07:33 INFO - Running post-run listener: _upload_blobber_files 10:07:33 INFO - Blob upload gear active. 10:07:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:07:33 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 10:07:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:07:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:07:33 INFO - (blobuploader) - INFO - Open directory for files ... 10:07:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:07:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:07:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:34 INFO - (blobuploader) - INFO - Done attempting. 10:07:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:07:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:07:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:35 INFO - (blobuploader) - INFO - Done attempting. 10:07:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:07:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:07:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:37 INFO - (blobuploader) - INFO - Done attempting. 10:07:37 INFO - (blobuploader) - INFO - Iteration through files over. 10:07:37 INFO - Return code: 0 10:07:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:07:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:07:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d6eb638d2cb45bce8fe583a9a76a0bb2281a119ad58e4cd58e62568ae94b0fccdd908fc22076683004ddcaf432015551130e67865140ca52325532a658422e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a22558a89c94e394a8bd0b29026bec83a40a28d7d0f8977ece6727eace1a1691205aeb4b6915148bbc1632ffe5795c90032c60d97803fae4e2b601fc8779e29", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9e15fbe72d1df58390d30ca7a542b3f487fabfdc3bb878108c399b449f4d13dddb88455ccb735d9740b2207e1ced342e1373924a53d41295fa6cae9521ff13b9"} 10:07:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:07:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:07:37 INFO - Contents: 10:07:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d6eb638d2cb45bce8fe583a9a76a0bb2281a119ad58e4cd58e62568ae94b0fccdd908fc22076683004ddcaf432015551130e67865140ca52325532a658422e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a22558a89c94e394a8bd0b29026bec83a40a28d7d0f8977ece6727eace1a1691205aeb4b6915148bbc1632ffe5795c90032c60d97803fae4e2b601fc8779e29", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9e15fbe72d1df58390d30ca7a542b3f487fabfdc3bb878108c399b449f4d13dddb88455ccb735d9740b2207e1ced342e1373924a53d41295fa6cae9521ff13b9"} 10:07:37 INFO - Running post-run listener: copy_logs_to_upload_dir 10:07:37 INFO - Copying logs to upload dir... 10:07:37 INFO - mkdir: /builds/slave/test/build/upload/logs 10:07:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.173849 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-01-06 10:07:38.057897) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-06 10:07:38.060926) ========= 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.dWczegeiMR/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.innJe5PzNK/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d6eb638d2cb45bce8fe583a9a76a0bb2281a119ad58e4cd58e62568ae94b0fccdd908fc22076683004ddcaf432015551130e67865140ca52325532a658422e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a22558a89c94e394a8bd0b29026bec83a40a28d7d0f8977ece6727eace1a1691205aeb4b6915148bbc1632ffe5795c90032c60d97803fae4e2b601fc8779e29", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9e15fbe72d1df58390d30ca7a542b3f487fabfdc3bb878108c399b449f4d13dddb88455ccb735d9740b2207e1ced342e1373924a53d41295fa6cae9521ff13b9"} build_url:https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010996 build_url: 'https://queue.taskcluster.net/v1/task/VTc0HHQRS6KDNsJ2RXumqQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d6eb638d2cb45bce8fe583a9a76a0bb2281a119ad58e4cd58e62568ae94b0fccdd908fc22076683004ddcaf432015551130e67865140ca52325532a658422e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a22558a89c94e394a8bd0b29026bec83a40a28d7d0f8977ece6727eace1a1691205aeb4b6915148bbc1632ffe5795c90032c60d97803fae4e2b601fc8779e29", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9e15fbe72d1df58390d30ca7a542b3f487fabfdc3bb878108c399b449f4d13dddb88455ccb735d9740b2207e1ced342e1373924a53d41295fa6cae9521ff13b9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-06 10:07:38.094362) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 10:07:38.097698) ========= 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.dWczegeiMR/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.innJe5PzNK/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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.005334 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 10:07:38.146350) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-06 10:07:38.149249) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-06 10:07:38.149577) ========= ========= Total master_lag: 0.42 =========