builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0197 starttime: 1477440943.1 results: success (0) buildid: 20161025145223 builduid: 24a6603ab69840ca8c29db1d23b97ad2 revision: 2ffe206d6af194997784bc7fa4abc3ac38dcc5cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:43.105196) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:43.105578) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:43.105852) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:43.289394) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:43.289759) ========= 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.ZtrP8Elikb/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.zaMWudJtDy/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-25 17:15:43-- 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.80M=0.001s 2016-10-25 17:15:43 (8.80 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.464672 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:43.783550) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:43.783867) ========= 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.ZtrP8Elikb/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.zaMWudJtDy/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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.027106 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:43.832200) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-25 17:15:43.832575) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 2ffe206d6af194997784bc7fa4abc3ac38dcc5cd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 2ffe206d6af194997784bc7fa4abc3ac38dcc5cd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZtrP8Elikb/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.zaMWudJtDy/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-25 17:15:43,909 truncating revision to first 12 chars 2016-10-25 17:15:43,909 Setting DEBUG logging. 2016-10-25 17:15:43,909 attempt 1/10 2016-10-25 17:15:43,909 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/2ffe206d6af1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-25 17:15:44,895 unpacking tar archive at: larch-2ffe206d6af1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.254944 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-25 17:15:45.111964) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:45.112371) ========= script_repo_revision: 2ffe206d6af194997784bc7fa4abc3ac38dcc5cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:45.112824) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:45.113114) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 17:15:45.135932) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-25 17:15:45.136222) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZtrP8Elikb/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.zaMWudJtDy/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 17:15:45 INFO - MultiFileLogger online at 20161025 17:15:45 in /builds/slave/test 17:15:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 17:15:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:15:45 INFO - {'append_to_log': False, 17:15:45 INFO - 'base_work_dir': '/builds/slave/test', 17:15:45 INFO - 'blob_upload_branch': 'Larch', 17:15:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:15:45 INFO - 'branch': 'Larch', 17:15:45 INFO - 'buildbot_json_path': 'buildprops.json', 17:15:45 INFO - 'config_files': ('talos/mac_config.py',), 17:15:45 INFO - 'default_actions': ('clobber', 17:15:45 INFO - 'read-buildbot-config', 17:15:45 INFO - 'download-and-extract', 17:15:45 INFO - 'populate-webroot', 17:15:45 INFO - 'create-virtualenv', 17:15:45 INFO - 'install', 17:15:45 INFO - 'run-tests'), 17:15:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:15:45 INFO - 'download_minidump_stackwalk': True, 17:15:45 INFO - 'download_symbols': 'ondemand', 17:15:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:15:45 INFO - 'tooltool.py': '/tools/tooltool.py', 17:15:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:15:45 INFO - '/tools/misc-python/virtualenv.py')}, 17:15:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:15:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:15:45 INFO - 'installer_path': 'installer.exe', 17:15:45 INFO - 'log_level': 'info', 17:15:45 INFO - 'log_name': 'talos', 17:15:45 INFO - 'log_to_console': True, 17:15:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:15:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:15:45 INFO - 'opt_config_files': (), 17:15:45 INFO - 'pip_index': False, 17:15:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:15:45 INFO - 'cmd': ('bash', 17:15:45 INFO - '-c', 17:15:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:15:45 INFO - 'enabled': True, 17:15:45 INFO - 'halt_on_failure': False, 17:15:45 INFO - 'name': 'check_screen_resolution'},), 17:15:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:15:45 INFO - 'cmd': ('bash', 17:15:45 INFO - '-c', 17:15:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:15:45 INFO - 'enabled': True, 17:15:45 INFO - 'halt_on_failure': False, 17:15:45 INFO - 'name': 'check_screen_resolution'},), 17:15:45 INFO - 'run_cmd_checks_enabled': True, 17:15:45 INFO - 'sps_profile': False, 17:15:45 INFO - 'sps_profile_interval': 0, 17:15:45 INFO - 'suite': 'dromaeojs-e10s', 17:15:45 INFO - 'system_bits': '32', 17:15:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:15:45 INFO - 'title': 't-yosemite-r7-0197', 17:15:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:15:45 INFO - 'use_talos_json': True, 17:15:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:15:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:15:45 INFO - 'work_dir': 'build'} 17:15:45 INFO - [mozharness: 2016-10-26 00:15:45.344376Z] Running clobber step. 17:15:45 INFO - Running pre-action listener: _resource_record_pre_action 17:15:45 INFO - Running main action method: clobber 17:15:45 INFO - rmtree: /builds/slave/test/build 17:15:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:15:47 INFO - Running post-action listener: _resource_record_post_action 17:15:47 INFO - [mozharness: 2016-10-26 00:15:47.574389Z] Finished clobber step (success) 17:15:47 INFO - [mozharness: 2016-10-26 00:15:47.574527Z] Running read-buildbot-config step. 17:15:47 INFO - Running pre-action listener: _resource_record_pre_action 17:15:47 INFO - Running main action method: read_buildbot_config 17:15:47 INFO - Using buildbot properties: 17:15:47 INFO - { 17:15:47 INFO - "project": "", 17:15:47 INFO - "product": "firefox", 17:15:47 INFO - "script_repo_revision": "production", 17:15:47 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 17:15:47 INFO - "repository": "", 17:15:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 17:15:47 INFO - "buildid": "20161025145223", 17:15:47 INFO - "pgo_build": "False", 17:15:47 INFO - "basedir": "/builds/slave/test", 17:15:47 INFO - "buildnumber": 2, 17:15:47 INFO - "slavename": "t-yosemite-r7-0197", 17:15:47 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 17:15:47 INFO - "platform": "yosemite_r7", 17:15:47 INFO - "scheduler": "tests-larch-macosx64-talos", 17:15:47 INFO - "branch": "larch", 17:15:47 INFO - "repo_path": "projects/larch", 17:15:47 INFO - "revision": "2ffe206d6af194997784bc7fa4abc3ac38dcc5cd", 17:15:47 INFO - "stage_platform": "macosx64", 17:15:47 INFO - "builduid": "24a6603ab69840ca8c29db1d23b97ad2", 17:15:47 INFO - "slavebuilddir": "test" 17:15:47 INFO - } 17:15:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 17:15:47 INFO - Running post-action listener: _resource_record_post_action 17:15:47 INFO - [mozharness: 2016-10-26 00:15:47.577720Z] Finished read-buildbot-config step (success) 17:15:47 INFO - [mozharness: 2016-10-26 00:15:47.577831Z] Running download-and-extract step. 17:15:47 INFO - Running pre-action listener: _resource_record_pre_action 17:15:47 INFO - Running main action method: download_and_extract 17:15:47 INFO - mkdir: /builds/slave/test/build/tests 17:15:47 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')]} 17:15:47 INFO - trying https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 17:15:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 17:15:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 17:15:48 INFO - Downloaded 1199 bytes. 17:15:48 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 17:15:48 INFO - Using the following test package requirements: 17:15:48 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 17:15:48 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:15:48 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 17:15:48 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:15:48 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 17:15:48 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:15:48 INFO - u'jsshell-mac.zip'], 17:15:48 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:15:48 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 17:15:48 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 17:15:48 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:15:48 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 17:15:48 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:15:48 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 17:15:48 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:15:48 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 17:15:48 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:15:48 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 17:15:48 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:15:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 17:15:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:15:48 INFO - Fetch https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 17:15:49 INFO - Http code: 200 17:15:49 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 17:15:49 INFO - x-amz-cf-id: nStMPhIiI3ThTiaTSb264hXaKDmewby8I32FlUtqH2xe0NodX-rgpA== 17:15:49 INFO - x-amz-version-id: xj3y7kLOgYSGZZU0dd2G5xyB.2Lk1UXT 17:15:49 INFO - Expected file size: 16409300 17:15:49 INFO - Obtained file size: 16409300 17:15:56 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:15:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 17:15:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:15:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 17:15:56 INFO - Http code: 200 17:15:56 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 17:15:56 INFO - x-amz-cf-id: SXmE6Zl4vcQb9uAt54L1bBPW1uswzdBKf6H_ot0M1w5eC9iV4rfU7Q== 17:15:56 INFO - x-amz-version-id: xj3y7kLOgYSGZZU0dd2G5xyB.2Lk1UXT 17:15:57 INFO - Expected file size: 16409300 17:15:57 INFO - Obtained file size: 16409300 17:16:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 17:16:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:16:03 INFO - Fetch https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 17:16:04 INFO - Http code: 200 17:16:04 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 17:16:04 INFO - x-amz-cf-id: SUA86oZ0PbPEhrR1CMAWumBfhDo_UiCvTVfRc_Wz5PHU5NAFGC_5CA== 17:16:04 INFO - x-amz-version-id: Q92fF9gqErhvvOHVVbpLuKTln2GygOZG 17:16:04 INFO - Expected file size: 14409028 17:16:04 INFO - Obtained file size: 14409028 17:16:05 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')]} 17:16:05 INFO - trying https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:16:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 17:16:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 17:16:07 INFO - Downloaded 111295960 bytes. 17:16:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:16:07 INFO - mkdir: /builds/slave/test/properties 17:16:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:16:07 INFO - Writing to file /builds/slave/test/properties/build_url 17:16:07 INFO - Contents: 17:16:07 INFO - build_url:https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:16:08 INFO - Running post-action listener: _resource_record_post_action 17:16:08 INFO - Running post-action listener: set_extra_try_arguments 17:16:08 INFO - [mozharness: 2016-10-26 00:16:08.633666Z] Finished download-and-extract step (success) 17:16:08 INFO - [mozharness: 2016-10-26 00:16:08.633787Z] Running populate-webroot step. 17:16:08 INFO - Running pre-action listener: _resource_record_pre_action 17:16:08 INFO - Running main action method: populate_webroot 17:16:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:16:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:16:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:16:08 INFO - '--tppagecycles', 17:16:08 INFO - '7'], 17:16:08 INFO - 'tests': ['tsvgm']}}, 17:16:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:16:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:16:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:16:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:16:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:08 INFO - 'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:16:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:16:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:08 INFO - 'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['damp', 'tps']}, 17:16:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:08 INFO - 'tests': ['damp', 'tps']}, 17:16:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['dromaeo_dom']}, 17:16:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:16:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['basic_compositor_video']}, 17:16:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:16:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['a11yr', 17:16:08 INFO - 'ts_paint', 17:16:08 INFO - 'tpaint', 17:16:08 INFO - 'sessionrestore', 17:16:08 INFO - 'sessionrestore_no_auto_restore', 17:16:08 INFO - 'tabpaint']}, 17:16:08 INFO - 'other-e10s': {'tests': ['a11yr', 17:16:08 INFO - 'ts_paint', 17:16:08 INFO - 'tpaint', 17:16:08 INFO - 'sessionrestore', 17:16:08 INFO - 'sessionrestore_no_auto_restore', 17:16:08 INFO - 'tabpaint']}, 17:16:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:16:08 INFO - 'ts_paint', 17:16:08 INFO - 'tpaint', 17:16:08 INFO - 'sessionrestore', 17:16:08 INFO - 'sessionrestore_no_auto_restore', 17:16:08 INFO - 'tabpaint']}, 17:16:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:16:08 INFO - 'ts_paint', 17:16:08 INFO - 'tpaint', 17:16:08 INFO - 'sessionrestore', 17:16:08 INFO - 'sessionrestore_no_auto_restore', 17:16:08 INFO - 'tabpaint']}, 17:16:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['a11yr', 17:16:08 INFO - 'ts_paint', 17:16:08 INFO - 'tpaint', 17:16:08 INFO - 'sessionrestore', 17:16:08 INFO - 'sessionrestore_no_auto_restore', 17:16:08 INFO - 'tabpaint']}, 17:16:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['a11yr', 17:16:08 INFO - 'ts_paint', 17:16:08 INFO - 'tpaint', 17:16:08 INFO - 'sessionrestore', 17:16:08 INFO - 'sessionrestore_no_auto_restore', 17:16:08 INFO - 'tabpaint']}, 17:16:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['tsvgx', 17:16:08 INFO - 'tsvgr_opacity', 17:16:08 INFO - 'tart', 17:16:08 INFO - 'tscrollx', 17:16:08 INFO - 'cart']}, 17:16:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:16:08 INFO - 'tsvgr_opacity', 17:16:08 INFO - 'tart', 17:16:08 INFO - 'tscrollx', 17:16:08 INFO - 'cart']}, 17:16:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:08 INFO - 'talos_options': ['--disable-e10s'], 17:16:08 INFO - 'tests': ['tp5o']}, 17:16:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:08 INFO - 'tests': ['tp5o']}, 17:16:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:08 INFO - 'talos_options': ['--disable-e10s', 17:16:08 INFO - '--xperf_path', 17:16:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:16:08 INFO - 'tests': ['tp5n']}, 17:16:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:08 INFO - 'talos_options': ['--xperf_path', 17:16:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:16:08 INFO - 'tests': ['tp5n']}}, 17:16:08 INFO - 'talos.zip': {'path': '', 17:16:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:16:08 INFO - Running post-action listener: _resource_record_post_action 17:16:08 INFO - [mozharness: 2016-10-26 00:16:08.652430Z] Finished populate-webroot step (success) 17:16:08 INFO - [mozharness: 2016-10-26 00:16:08.652511Z] Running create-virtualenv step. 17:16:08 INFO - Running pre-action listener: _resource_record_pre_action 17:16:08 INFO - Running main action method: create_virtualenv 17:16:08 INFO - Creating virtualenv /builds/slave/test/build/venv 17:16:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:16:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:16:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:16:08 INFO - Using real prefix '/tools/python27' 17:16:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:16:09 INFO - Installing distribute.............................................................................................................................................................................................done. 17:16:12 INFO - Installing pip.................done. 17:16:12 INFO - Return code: 0 17:16:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:16:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:16:12 INFO - Reading from file tmpfile_stdout 17:16:12 INFO - Output received: 17:16:12 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) 17:16:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:16: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')]} 17:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16: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')]} 17:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1099fa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109970570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b1dbfc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980ed30>, '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 0x109634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109806b20>, '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.ZtrP8Elikb/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.zaMWudJtDy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 17:16:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:16:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:16:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZtrP8Elikb/Render', 17:16:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:12 INFO - 'HOME': '/Users/cltbld', 17:16:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:12 INFO - 'LOGNAME': 'cltbld', 17:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:12 INFO - 'MOZ_NO_REMOTE': '1', 17:16:12 INFO - 'NO_EM_RESTART': '1', 17:16:12 INFO - 'PAGER': '/bin/cat', 17:16:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:12 INFO - 'PWD': '/builds/slave/test', 17:16:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:12 INFO - 'SHELL': '/bin/bash', 17:16:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zaMWudJtDy/Listeners', 17:16:12 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 17:16:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:12 INFO - 'USER': 'cltbld', 17:16:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:12 INFO - 'XPC_FLAGS': '0x0', 17:16:12 INFO - 'XPC_SERVICE_NAME': '0', 17:16:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:16:12 INFO - Cleaning up... 17:16:12 INFO - Return code: 0 17:16:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:16: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')]} 17:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16: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')]} 17:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1099fa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109970570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b1dbfc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980ed30>, '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 0x109634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109806b20>, '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.ZtrP8Elikb/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.zaMWudJtDy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 17:16:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:16:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:16:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZtrP8Elikb/Render', 17:16:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:12 INFO - 'HOME': '/Users/cltbld', 17:16:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:12 INFO - 'LOGNAME': 'cltbld', 17:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:12 INFO - 'MOZ_NO_REMOTE': '1', 17:16:12 INFO - 'NO_EM_RESTART': '1', 17:16:12 INFO - 'PAGER': '/bin/cat', 17:16:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:12 INFO - 'PWD': '/builds/slave/test', 17:16:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:12 INFO - 'SHELL': '/bin/bash', 17:16:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zaMWudJtDy/Listeners', 17:16:12 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 17:16:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:12 INFO - 'USER': 'cltbld', 17:16:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:12 INFO - 'XPC_FLAGS': '0x0', 17:16:12 INFO - 'XPC_SERVICE_NAME': '0', 17:16:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:13 INFO - Downloading/unpacking psutil>=3.1.1 17:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:16:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:16:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:16:16 INFO - Installing collected packages: psutil 17:16:16 INFO - Running setup.py install for psutil 17:16:16 INFO - building 'psutil._psutil_osx' extension 17:16:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:16:17 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 17:16:17 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 17:16:17 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 17:16:17 INFO - building 'psutil._psutil_posix' extension 17:16:17 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 17:16:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:16:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:16:17 INFO - ^ 17:16:17 INFO - 1 warning generated. 17:16:17 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 17:16:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:16:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:16:18 INFO - Successfully installed psutil 17:16:18 INFO - Cleaning up... 17:16:18 INFO - Return code: 0 17:16:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:16:18 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')]} 17:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:18 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')]} 17:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:18 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 0x1099fa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109970570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b1dbfc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980ed30>, '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 0x109634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109806b20>, '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.ZtrP8Elikb/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.zaMWudJtDy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 17:16:18 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 17:16:18 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 17:16:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZtrP8Elikb/Render', 17:16:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:18 INFO - 'HOME': '/Users/cltbld', 17:16:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:18 INFO - 'LOGNAME': 'cltbld', 17:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:18 INFO - 'MOZ_NO_REMOTE': '1', 17:16:18 INFO - 'NO_EM_RESTART': '1', 17:16:18 INFO - 'PAGER': '/bin/cat', 17:16:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:18 INFO - 'PWD': '/builds/slave/test', 17:16:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:18 INFO - 'SHELL': '/bin/bash', 17:16:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zaMWudJtDy/Listeners', 17:16:18 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 17:16:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:18 INFO - 'USER': 'cltbld', 17:16:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:18 INFO - 'XPC_FLAGS': '0x0', 17:16:18 INFO - 'XPC_SERVICE_NAME': '0', 17:16:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:16: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 17:16: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 17:16: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 17:16: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 17:16: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 17:16: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 17:16:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:16:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:16:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:16:21 INFO - Installing collected packages: mozsystemmonitor 17:16:21 INFO - Running setup.py install for mozsystemmonitor 17:16:21 INFO - Successfully installed mozsystemmonitor 17:16:21 INFO - Cleaning up... 17:16:21 INFO - Return code: 0 17:16:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:16: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')]} 17:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16: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')]} 17:16:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:21 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 0x1099fa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109970570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b1dbfc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980ed30>, '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 0x109634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109806b20>, '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.ZtrP8Elikb/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.zaMWudJtDy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 17:16:21 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 17:16:21 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 17:16:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZtrP8Elikb/Render', 17:16:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:21 INFO - 'HOME': '/Users/cltbld', 17:16:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:21 INFO - 'LOGNAME': 'cltbld', 17:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:21 INFO - 'MOZ_NO_REMOTE': '1', 17:16:21 INFO - 'NO_EM_RESTART': '1', 17:16:21 INFO - 'PAGER': '/bin/cat', 17:16:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:21 INFO - 'PWD': '/builds/slave/test', 17:16:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:21 INFO - 'SHELL': '/bin/bash', 17:16:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zaMWudJtDy/Listeners', 17:16:21 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 17:16:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:21 INFO - 'USER': 'cltbld', 17:16:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:21 INFO - 'XPC_FLAGS': '0x0', 17:16:21 INFO - 'XPC_SERVICE_NAME': '0', 17:16:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:21 INFO - Downloading/unpacking jsonschema==2.5.1 17:16:21 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 17:16:21 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 17:16:21 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 17:16:21 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 17:16:21 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 17:16:21 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 17:16:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:16:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:16:24 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 17:16:24 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 17:16:24 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 17:16:24 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 17:16:24 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 17:16:24 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 17:16:24 INFO - Downloading functools32-3.2.3-2.tar.gz 17:16:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:16:24 INFO - warning: no files found matching '*.txt' 17:16:24 INFO - no previously-included directories found matching 'build' 17:16:24 INFO - no previously-included directories found matching 'dist' 17:16:24 INFO - no previously-included directories found matching '.git*' 17:16:24 INFO - Installing collected packages: jsonschema, functools32 17:16:24 INFO - Running setup.py install for functools32 17:16:24 INFO - warning: no files found matching '*.txt' 17:16:24 INFO - no previously-included directories found matching 'build' 17:16:24 INFO - no previously-included directories found matching 'dist' 17:16:24 INFO - no previously-included directories found matching '.git*' 17:16:24 INFO - Successfully installed jsonschema functools32 17:16:24 INFO - Cleaning up... 17:16:24 INFO - Return code: 0 17:16:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:24 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 0x1099fa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109970570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b1dbfc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980ed30>, '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 0x109634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109806b20>, '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.ZtrP8Elikb/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.zaMWudJtDy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 17:16:24 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 17:16:24 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 17:16:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZtrP8Elikb/Render', 17:16:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:24 INFO - 'HOME': '/Users/cltbld', 17:16:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:24 INFO - 'LOGNAME': 'cltbld', 17:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:24 INFO - 'MOZ_NO_REMOTE': '1', 17:16:24 INFO - 'NO_EM_RESTART': '1', 17:16:24 INFO - 'PAGER': '/bin/cat', 17:16:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:24 INFO - 'PWD': '/builds/slave/test', 17:16:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:24 INFO - 'SHELL': '/bin/bash', 17:16:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zaMWudJtDy/Listeners', 17:16:24 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 17:16:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:24 INFO - 'USER': 'cltbld', 17:16:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:24 INFO - 'XPC_FLAGS': '0x0', 17:16:24 INFO - 'XPC_SERVICE_NAME': '0', 17:16:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:16:25 INFO - Cleaning up... 17:16:25 INFO - Return code: 0 17:16:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:25 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 0x1099fa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109970570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b1dbfc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980ed30>, '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 0x109634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109806b20>, '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.ZtrP8Elikb/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.zaMWudJtDy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 17:16:25 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 17:16:25 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 17:16:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZtrP8Elikb/Render', 17:16:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:25 INFO - 'HOME': '/Users/cltbld', 17:16:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:25 INFO - 'LOGNAME': 'cltbld', 17:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:25 INFO - 'MOZ_NO_REMOTE': '1', 17:16:25 INFO - 'NO_EM_RESTART': '1', 17:16:25 INFO - 'PAGER': '/bin/cat', 17:16:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:25 INFO - 'PWD': '/builds/slave/test', 17:16:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:25 INFO - 'SHELL': '/bin/bash', 17:16:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zaMWudJtDy/Listeners', 17:16:25 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 17:16:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:25 INFO - 'USER': 'cltbld', 17:16:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:25 INFO - 'XPC_FLAGS': '0x0', 17:16:25 INFO - 'XPC_SERVICE_NAME': '0', 17:16:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:25 INFO - Downloading/unpacking blobuploader==1.2.4 17:16:25 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 17:16:25 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 17:16:25 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 17:16:25 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 17:16:25 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 17:16:25 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 17:16:27 INFO - Downloading blobuploader-1.2.4.tar.gz 17:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:16:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:16:28 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 17:16:28 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 17:16:28 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 17:16:28 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 17:16:28 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 17:16:28 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 17:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:16:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:16:28 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 17:16:28 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 17:16:28 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 17:16:28 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 17:16:28 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 17:16:28 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 17:16:29 INFO - Downloading docopt-0.6.1.tar.gz 17:16:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:16:29 INFO - Installing collected packages: blobuploader, requests, docopt 17:16:29 INFO - Running setup.py install for blobuploader 17:16:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:16:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:16:29 INFO - Running setup.py install for requests 17:16:29 INFO - Running setup.py install for docopt 17:16:30 INFO - Successfully installed blobuploader requests docopt 17:16:30 INFO - Cleaning up... 17:16:30 INFO - Return code: 0 17:16:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:30 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')]} 17:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:30 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')]} 17:16:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:30 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 0x1099fa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109970570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b1dbfc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980ed30>, '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 0x109634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109806b20>, '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.ZtrP8Elikb/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.zaMWudJtDy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 17:16:30 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 17:16:30 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 17:16:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZtrP8Elikb/Render', 17:16:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:30 INFO - 'HOME': '/Users/cltbld', 17:16:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:30 INFO - 'LOGNAME': 'cltbld', 17:16:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:30 INFO - 'MOZ_NO_REMOTE': '1', 17:16:30 INFO - 'NO_EM_RESTART': '1', 17:16:30 INFO - 'PAGER': '/bin/cat', 17:16:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:30 INFO - 'PWD': '/builds/slave/test', 17:16:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:30 INFO - 'SHELL': '/bin/bash', 17:16:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zaMWudJtDy/Listeners', 17:16:30 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 17:16:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:30 INFO - 'USER': 'cltbld', 17:16:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:30 INFO - 'XPC_FLAGS': '0x0', 17:16:30 INFO - 'XPC_SERVICE_NAME': '0', 17:16:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:16:30 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-W7Gc7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:16:30 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-jdFjtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:16:30 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-lYuR2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:16:30 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-pOtuh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:16:30 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-iQAnHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:16:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-U1CMF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:16:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-ovHGJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:16:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-dmu2AZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:16:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-G0zBVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:16:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-22MS38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:16:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-nAufcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:16:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-SGf25U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:16:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-LpbJT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:16:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-JOkpNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-TyHthA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:16:32 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-8mAYYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:16:32 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-EbGx1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:16:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:16:32 INFO - Running setup.py install for manifestparser 17:16:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:16:32 INFO - Running setup.py install for mozcrash 17:16:32 INFO - Running setup.py install for mozdebug 17:16:32 INFO - Running setup.py install for mozdevice 17:16:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:16:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:16:32 INFO - Running setup.py install for mozfile 17:16:33 INFO - Running setup.py install for mozhttpd 17:16:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:16:33 INFO - Running setup.py install for mozinfo 17:16:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:16:33 INFO - Running setup.py install for mozInstall 17:16:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:16:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:16:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:16:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:16:33 INFO - Running setup.py install for mozleak 17:16:33 INFO - Running setup.py install for mozlog 17:16:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:16:33 INFO - Running setup.py install for moznetwork 17:16:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:16:33 INFO - Running setup.py install for mozprocess 17:16:34 INFO - Running setup.py install for mozprofile 17:16:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:16:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:16:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:16:34 INFO - Running setup.py install for mozrunner 17:16:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:16:34 INFO - Running setup.py install for mozscreenshot 17:16:34 INFO - Running setup.py install for moztest 17:16:34 INFO - Running setup.py install for mozversion 17:16:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:16:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:16:34 INFO - Cleaning up... 17:16:34 INFO - Return code: 0 17:16:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:34 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 0x1099fa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109970570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b1dbfc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980ed30>, '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 0x109634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109806b20>, '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.ZtrP8Elikb/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.zaMWudJtDy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 17:16:34 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 17:16:34 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 17:16:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZtrP8Elikb/Render', 17:16:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:34 INFO - 'HOME': '/Users/cltbld', 17:16:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:34 INFO - 'LOGNAME': 'cltbld', 17:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:34 INFO - 'MOZ_NO_REMOTE': '1', 17:16:34 INFO - 'NO_EM_RESTART': '1', 17:16:34 INFO - 'PAGER': '/bin/cat', 17:16:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:34 INFO - 'PWD': '/builds/slave/test', 17:16:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:34 INFO - 'SHELL': '/bin/bash', 17:16:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zaMWudJtDy/Listeners', 17:16:34 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 17:16:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:34 INFO - 'USER': 'cltbld', 17:16:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:34 INFO - 'XPC_FLAGS': '0x0', 17:16:34 INFO - 'XPC_SERVICE_NAME': '0', 17:16:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:16:35 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-9jK41l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:16:35 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)) 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:16:35 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-YyYg1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:16:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:16:35 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-xpJ0U4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:16:35 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)) 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:16:35 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-CuVjZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:16:35 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)) 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:16:35 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-HRlBn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:16:35 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)) 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:16:35 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-xcshQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:16:35 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)) 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:16:35 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-mBQ4fW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:16:35 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)) 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:16:35 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-ETl46w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:16:35 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)) 17:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:16:35 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-XqHeQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:16:36 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)) 17:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:16:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-N83HNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:16:36 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)) 17:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:16:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-8PM8Ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:16:36 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)) 17:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:16:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-G_dWay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:16:36 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)) 17:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:16:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-3lzVHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:16:36 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)) 17:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:16:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-c4BZDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 17:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-5ULKRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:36 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)) 17:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:16:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-Og6ue8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:16:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-wLhNua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:16:36 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)) 17:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:16:36 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)) 17:16:36 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)) 17:16:36 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)) 17:16:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:16:36 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 17:16:36 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 17:16:36 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 17:16:36 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 17:16:36 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 17:16:36 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 17:16:39 INFO - Downloading blessings-1.6.tar.gz 17:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:16:39 INFO - Installing collected packages: blessings 17:16:39 INFO - Running setup.py install for blessings 17:16:39 INFO - Successfully installed blessings 17:16:39 INFO - Cleaning up... 17:16:39 INFO - Return code: 0 17:16:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:16:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:16:40 INFO - Reading from file tmpfile_stdout 17:16:40 INFO - Current package versions: 17:16:40 INFO - blessings == 1.6 17:16:40 INFO - blobuploader == 1.2.4 17:16:40 INFO - docopt == 0.6.1 17:16:40 INFO - functools32 == 3.2.3-2 17:16:40 INFO - jsonschema == 2.5.1 17:16:40 INFO - manifestparser == 1.1 17:16:40 INFO - mozInstall == 1.12 17:16:40 INFO - mozcrash == 0.17 17:16:40 INFO - mozdebug == 0.1 17:16:40 INFO - mozdevice == 0.48 17:16:40 INFO - mozfile == 1.2 17:16:40 INFO - mozhttpd == 0.7 17:16:40 INFO - mozinfo == 0.9 17:16:40 INFO - mozleak == 0.1 17:16:40 INFO - mozlog == 3.4 17:16:40 INFO - moznetwork == 0.27 17:16:40 INFO - mozprocess == 0.23 17:16:40 INFO - mozprofile == 0.28 17:16:40 INFO - mozrunner == 6.12 17:16:40 INFO - mozscreenshot == 0.1 17:16:40 INFO - mozsystemmonitor == 0.3 17:16:40 INFO - moztest == 0.7 17:16:40 INFO - mozversion == 1.4 17:16:40 INFO - psutil == 3.1.1 17:16:40 INFO - requests == 1.2.3 17:16:40 INFO - wsgiref == 0.1.2 17:16:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:40 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')]} 17:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:40 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')]} 17:16:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:40 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 0x1099fa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109970570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b1dbfc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980ed30>, '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 0x109634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109806b20>, '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.ZtrP8Elikb/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.zaMWudJtDy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 17:16:40 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 17:16:40 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 17:16:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZtrP8Elikb/Render', 17:16:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:40 INFO - 'HOME': '/Users/cltbld', 17:16:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:40 INFO - 'LOGNAME': 'cltbld', 17:16:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:40 INFO - 'MOZ_NO_REMOTE': '1', 17:16:40 INFO - 'NO_EM_RESTART': '1', 17:16:40 INFO - 'PAGER': '/bin/cat', 17:16:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:40 INFO - 'PWD': '/builds/slave/test', 17:16:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:40 INFO - 'SHELL': '/bin/bash', 17:16:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zaMWudJtDy/Listeners', 17:16:40 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 17:16:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:40 INFO - 'USER': 'cltbld', 17:16:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:40 INFO - 'XPC_FLAGS': '0x0', 17:16:40 INFO - 'XPC_SERVICE_NAME': '0', 17:16:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 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)) 17:16:40 INFO - Cleaning up... 17:16:40 INFO - Return code: 0 17:16:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:16:40 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')]} 17:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:40 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')]} 17:16:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:40 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 0x1099fa490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109970570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b1dbfc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980ed30>, '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 0x109634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109806b20>, '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.ZtrP8Elikb/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.zaMWudJtDy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 17:16:40 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 17:16:40 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 17:16:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZtrP8Elikb/Render', 17:16:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:40 INFO - 'HOME': '/Users/cltbld', 17:16:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:40 INFO - 'LOGNAME': 'cltbld', 17:16:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:40 INFO - 'MOZ_NO_REMOTE': '1', 17:16:40 INFO - 'NO_EM_RESTART': '1', 17:16:40 INFO - 'PAGER': '/bin/cat', 17:16:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:40 INFO - 'PWD': '/builds/slave/test', 17:16:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:40 INFO - 'SHELL': '/bin/bash', 17:16:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zaMWudJtDy/Listeners', 17:16:40 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 17:16:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:40 INFO - 'USER': 'cltbld', 17:16:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:40 INFO - 'XPC_FLAGS': '0x0', 17:16:40 INFO - 'XPC_SERVICE_NAME': '0', 17:16:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:16:40 INFO - Cleaning up... 17:16:40 INFO - Return code: 0 17:16:40 INFO - Running post-action listener: _resource_record_post_action 17:16:40 INFO - Running post-action listener: _start_resource_monitoring 17:16:40 INFO - Starting resource monitoring. 17:16:40 INFO - [mozharness: 2016-10-26 00:16:40.767584Z] Finished create-virtualenv step (success) 17:16:40 INFO - [mozharness: 2016-10-26 00:16:40.768601Z] Running install step. 17:16:40 INFO - Running pre-action listener: _resource_record_pre_action 17:16:40 INFO - Running main action method: install 17:16:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:16:41 INFO - Reading from file tmpfile_stdout 17:16:41 INFO - Detecting whether we're running mozinstall >=1.0... 17:16:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:16:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:16:41 INFO - Reading from file tmpfile_stdout 17:16:41 INFO - Output received: 17:16:41 INFO - Usage: mozinstall [options] installer 17:16:41 INFO - Options: 17:16:41 INFO - -h, --help show this help message and exit 17:16:41 INFO - -d DEST, --destination=DEST 17:16:41 INFO - Directory to install application into. [default: 17:16:41 INFO - "/builds/slave/test"] 17:16:41 INFO - --app=APP Application being installed. [default: firefox] 17:16:41 INFO - mkdir: /builds/slave/test/build/application 17:16:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:16:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:17:15 INFO - Reading from file tmpfile_stdout 17:17:15 INFO - Output received: 17:17:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:17:15 INFO - Running post-action listener: _resource_record_post_action 17:17:15 INFO - [mozharness: 2016-10-26 00:17:15.292282Z] Finished install step (success) 17:17:15 INFO - [mozharness: 2016-10-26 00:17:15.292394Z] Running run-tests step. 17:17:15 INFO - Running pre-action listener: _resource_record_pre_action 17:17:15 INFO - Running main action method: run_tests 17:17:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:17:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:17:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:17:15 INFO - 2016-10-25 17:17:15.306 screenresolution[1648:7745] starting screenresolution argv=screenresolution get 17:17:15 INFO - 2016-10-25 17:17:15.309 screenresolution[1648:7745] Display 0: 1600x1200x32@60 17:17:15 INFO - 2016-10-25 17:17:15.314 screenresolution[1649:7747] starting screenresolution argv=screenresolution list 17:17:15 INFO - Available Modes on Display 0 17:17:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:17:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:17:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:17:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:17:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:17:15 INFO - Intel Iris: 17:17:15 INFO - Chipset Model: Intel Iris 17:17:15 INFO - Type: GPU 17:17:15 INFO - Bus: Built-In 17:17:15 INFO - VRAM (Dynamic, Max): 1536 MB 17:17:15 INFO - Vendor: Intel (0x8086) 17:17:15 INFO - Device ID: 0x0a2e 17:17:15 INFO - Revision ID: 0x0009 17:17:15 INFO - Displays: 17:17:15 INFO - Display: 17:17:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:17:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:17:15 INFO - Display Serial Number: 1600x1200 60 17:17:15 INFO - Main Display: Yes 17:17:15 INFO - Mirror: Off 17:17:15 INFO - Online: Yes 17:17:15 INFO - Rotation: Supported 17:17:15 INFO - Return code: 0 17:17:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:17:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:17:15 INFO - Python 2.7.3 17:17:15 INFO - Return code: 0 17:17:15 INFO - grabbing minidump binary from tooltool 17:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:15 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 0x10980ed30>, '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 0x109634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109806b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:17:15 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 17:17:15 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 17:17:15 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 17:17:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:17:15 INFO - Return code: 0 17:17:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:17:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:17:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:17:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:17:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:17:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0197', '--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 17:17:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0197 --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 17:17:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZtrP8Elikb/Render', 17:17:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:15 INFO - 'HOME': '/Users/cltbld', 17:17:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:15 INFO - 'LOGNAME': 'cltbld', 17:17:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:17:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:17:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:15 INFO - 'MOZ_NO_REMOTE': '1', 17:17:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:17:15 INFO - 'NO_EM_RESTART': '1', 17:17:15 INFO - 'PAGER': '/bin/cat', 17:17:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:15 INFO - 'PWD': '/builds/slave/test', 17:17:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:17:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:15 INFO - 'SHELL': '/bin/bash', 17:17:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zaMWudJtDy/Listeners', 17:17:15 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 17:17:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:15 INFO - 'USER': 'cltbld', 17:17:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:15 INFO - 'XPC_FLAGS': '0x0', 17:17:15 INFO - 'XPC_SERVICE_NAME': '0', 17:17:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0197', '--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 17:17:15 INFO - mozversion application_buildid: 20161025145223 17:17:15 INFO - mozversion application_changeset: 2ffe206d6af194997784bc7fa4abc3ac38dcc5cd 17:17:15 INFO - mozversion application_display_name: Nightly 17:17:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:17:15 INFO - mozversion application_name: Firefox 17:17:15 INFO - mozversion application_remotingname: firefox 17:17:15 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 17:17:15 INFO - mozversion application_vendor: Mozilla 17:17:15 INFO - mozversion application_version: 52.0a1 17:17:15 INFO - mozversion platform_buildid: 20161025145223 17:17:15 INFO - mozversion platform_changeset: 2ffe206d6af194997784bc7fa4abc3ac38dcc5cd 17:17:15 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 17:17:15 INFO - mozversion platform_version: 52.0a1 17:17:15 INFO - using testdate: 1477441035 17:17:15 INFO - actual date: 1477441035 17:17:15 INFO - starting webserver on 'localhost:49268' 17:17:15 INFO - SUITE-START | Running 2 tests 17:17:15 INFO - TEST-START | dromaeo_css 17:17:15 INFO - operating with platform_type : mac_ 17:17:15 INFO - Initialising browser for dromaeo_css test... 17:17:15 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/tmp_ZhMWJ/profile http://localhost:49268/getInfo.html) 17:17:18 INFO - PROCESS | 1659 | 2016-10-25 17:17:18.812 plugin-container[1661:7916] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 17:17:18 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:17:18 INFO - PROCESS | 1659 | 2016-10-25 17:17:18.815 plugin-container[1661:7916] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4927, name = 'com.apple.CFPasteboardClient' 17:17:18 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:17:18 INFO - PROCESS | 1659 | 2016-10-25 17:17:18.815 plugin-container[1661:7916] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:17:18 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 17:17:18 INFO - PROCESS | 1659 | colorDepth:24 17:17:18 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 17:17:18 INFO - PROCESS | 1659 | __metrics 17:17:18 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 17:17:19 INFO - TEST-INFO | 1659: exit 0 17:17:19 INFO - Browser initialized. 17:17:19 INFO - Running cycle 1/1 for dromaeo_css test... 17:17:19 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/tmp_ZhMWJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:17:22 INFO - PROCESS | 1663 | 2016-10-25 17:17:22.221 plugin-container[1664:8037] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863b, name = 'com.apple.tsm.portname' 17:17:22 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:17:22 INFO - PROCESS | 1663 | 2016-10-25 17:17:22.222 plugin-container[1664:8037] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 17:17:22 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:17:22 INFO - PROCESS | 1663 | 2016-10-25 17:17:22.222 plugin-container[1664:8037] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:17:31 INFO - PROCESS | 1663 | RSS: Main: 241274880 17:17:31 INFO - PROCESS | 1663 | 17:18:21 INFO - PROCESS | 1663 | 1477441101585 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 17:20:34 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 17:20:36 INFO - PROCESS | 1663 | RSS: Main: 253997056 17:20:36 INFO - PROCESS | 1663 | 17:23:38 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 17:23:39 INFO - PROCESS | 1663 | RSS: Main: 267718656 17:23:39 INFO - PROCESS | 1663 | 17:26:41 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 17:26:41 INFO - PROCESS | 1663 | RSS: Main: 271880192 17:26:41 INFO - PROCESS | 1663 | 17:29:43 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 17:29:44 INFO - PROCESS | 1663 | RSS: Main: 271200256 17:29:44 INFO - PROCESS | 1663 | 17:32:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 17:32:47 INFO - PROCESS | 1663 | RSS: Main: 276426752 17:32:47 INFO - PROCESS | 1663 | 17:35:50 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 17:35:51 INFO - PROCESS | 1663 | RSS: Main: 279326720 17:35:51 INFO - PROCESS | 1663 | 17:35:51 INFO - PROCESS | 1663 | __start_tp_report 17:35:51 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 17:35:51 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 17:35:51 INFO - PROCESS | 1663 | |i|pagename|runs| 17:35:51 INFO - PROCESS | 1663 | |0;dojo.html;3313.1717070732316;3339.448990916499;3375.7468189885763;3392.932141357173;3427.7943323400596;4272.401863739079;5537.667739935597;5544.667319960805;5561.777528898844;5570.053090974534;4548.658850586203;5554.166874968757;5661.830145095653;5711.171880077397;5726.284214473191;5004.074246264453;5052.317937078503;5126.128558145106;5172.793088276483;5197.948020519784;5062.620303477235;5229.529342359187;5262.342207224097;5269.947300526984;5318.813841515548;4134.793260336971;5094.235864620299;5129.281900533928;5134.691918484903;5199.1161502544655;5143.8456846294675;5145.665531740425;5168.767405466725;5183.481651834786;5193.766280517386;5046.621503387224;5131.204663277198;5157.58739300855;5171.922421163686;5190.169572868323;4531.932021019687;5498.505134537911;5499.010178167928;5549.223108764776;5596.964561556125;5228.973855130738;5980.820575382702;6142.6314421134875;6148.616561273722;6169.7532098716;6187.07193920911;6196.10156527301;6213.658248796314;6229;6233.3143354231;5622.044252477089;5677.489025987598;5695.515881150066;5746.195532625436;5765.971170144123;5026.447090820006;5226.895462090737;5390.407055223922;6174.876502469925;6189.442950134419;6064.36324185963;6089.604175728652;6090.299615544269;6092.025275955826;6096.055111457731;6029.3669164737075;6067.211262535842;6097.237845269341;6197.659128747962;6218.533609979179;5774.538036957033;6096.817095487142;6159.922013647593;6201.1938448002375;6203.286622038433;5274.314339135888;6131.938680613226;6193.84515387119;6202.534809889186;6229.844253893689;4771.06964141989;4817.397825271841;4877.219644856806;4886.242632391985;4893.192623217201;4312.072904325585;4396.428464299621;4400.933985990213;4431.357453169337;4498.50516443191;5674.659520428705;5706.400827913009;5746.741396637161;5762.94237057624;5765.250517432707;6022.1870047543025;6143.047827586731;6152.509536839133;6236.781712640036;6249.375062493714;5626.409227031103;5749.712514374264;5776.220210271644;5808.94191058084;5924.7333869975955;5367.946320536823;5374.946250537523;5383.973080134652;5398.379186393615;5407.648502847381;6145.723442445101;6160.691965401712;6163;6164.506839452923;6176.073588961602;6042.425969532887;6113.724882380215;6116.214037636738;6148.8009838082935;6149.84625384369;6185.134081228542;6222.16000839899;6238.937610624017;6241.843953901189;6254.186955695731;5906.793262235801;6001.309849367204;6079.209702738615;6110.480609148272;6175.783847565313;6108.099244189703;6112.26032301701;6152.0156774915795;6250;6265.154204182377;6051.697415129314;6054.84862878414;6070.365345142047;6112.047575716935;6123.785667501616;5888.852778680396;6035.094735789667;6080.087986802015;6096.634201947897;6104.572679912363;4938.629602779734;5696.202531645656;5747.74135163927;5850.356460789395;5935.762569497172;5957.821265362046;5996.760129594942;6033.5173186145885;6054.727537260922;6060.787872424444;5829.009068458287;5832.562557808266;5847.619904706181;5868.559858010752;5886.558508111994 17:35:51 INFO - PROCESS | 1663 | |1;ext.html;480.9711417314961;485.91883060191066;488.4456142278513;532.9866753331166;550.0786183143234;40125.799371003144;41156.58843411565;41186.17627647449;41241.58758412415;44099.559004409944;42687.3596896046;43301.91745206364;43653;43768.343474847905;43948.780256098755;8588.656453741844;9315.462948613487;9447.385919915218;9634.662786802463;9655.420674759502;8196.385271104691;9527;9659.227261819073;9671.274654400913;9680.95159524205;8190.631421586043;9620.374675646095;9677.467739274338;9695.757606059835;9700.563474643623;8201.95899020501;9589;9648.951755241249;9670.178034867045;9673.613055477843;9501.192398646124;9572.66495672655;9615.471149086798;9624.133827955447;9634.903650963472;40652.796736016426;40997.385039224435;41428.37857432141;41486.37770433446;41696.79151604223;3032.3177285110896;3399.9660003399895;3423.2639982403653;3471.6007659119273;3735.1969326594913;30868.691313086805;31558.684413155803;31593.8420307897;31907;31933.52099718506;3174.7301479374282;3188.6333071696586;3190.3619276144864;3311.0397984584442;3346.8995930122137;484817;485700;487691;488423;489791.99999999645;60791.696041520394;61030;61142;61256.69371653113;61298.46753831101;63993.36006639967;64110;64183;64607.70784584282;64625.67687161534;15539.766903496457;15858.841411585736;15947.760783588255;16080.43718469848;16126.838731612768;12077.39613019363;12354.38228088592;12439.937800310941;12450.875491245153;12479.93760031194;15461.768073478906;17031.892926959707;17076.743848842278;17111.315547377966;17113.48659540216;3202.743780497601;6809.829754256183;6965.895511567331;6981.1622605286675;6981.930180698229;891.4517571693492;942.6465075596651;1032.2670903658336;1083.2525557365393;1084.8806631270554;30605.540916886264;30640.387192256476;30860;30904.845475772927;31054.844725776227;1061.7451811565322;1065.0787069185253;1071.0735214039798;1077.3589714120087;1159.2001518952065;15798.9210053949;16032.839671603368;16042.75935860963;16362.34550617962;16520.256588453547;558522;624143;634424.4508997106;643176;646040;58578;60283.69858150681;60294.69852650708;64390.678046609464;64424;1368.856270091646;1382.260490637504;1394.2610416479283;1412.7386433509628;1424.6224750440933;30542.84728576343;31406.842965785025;31460.528092079094;31968.840155800004;32012.67987320097;52178.73910630422;52832;52842.47157528376;52865.73567132294;52885.47114528805;587610;592658;596011;596238;596256;27058.917643294048;27207.319817004733;27300.72699272982;27522.449551009267;27658.893644254003;46550.76724616355;46681.06637867291;47417;47773;48014.759926200146;39386;39530.20939581135;39711;40696.79651601723;41070.58929410668;33884.49173262354;38622.42066369063;39036.41445378265;39973.000674983356;40357.39463908102;37710.62289377071;37935.019777022695;38254.23491530098;38643.227135457695;38707.80646096751;32342.83828580842;34772.11889795629;34787.65212347844;34816.12959675927;35225.47161792626;25461.745382545938;25702.8714856432;25748.742512575383;25995.74004260009;26263.60604590971 17:35:51 INFO - PROCESS | 1663 | |2;jquery.html;320.48882033157116;327.81314650649125;356.6451380876029;357.8461261657488;368.918837855672;19354.32259870903;19480.02599870006;20660.483487912792;20769.480762980933;20903.47741306468;19493.902530487365;19895.801041989576;20373.49066273344;20462.18151273951;20819.479513012182;5621.887562248752;5815.894979953817;5866;5892.675902825343;5924.703764811764;4426.557344265564;5724.656520608777;5838.795642152526;5851.941480585182;5877.500412464946;5535.723213839312;5799.333076696171;5815.156802263669;5818.41815818419;5828.621139625932;5679.659220446766;5802.535797136219;5824.563157763164;5828.067509198508;5829.300483941933;4816.060868130716;4979.551840334351;4983.152864013126;5058.620603454719;5076.314697515825;24792.25623231306;24842.50314993708;25098.247052588453;25198.370040748945;25404.98380064795;2182.923597674074;2285.645724912641;2303.6314189729565;2568.5376632206194;2861.0272507347395;12640.620781376574;14600.853991460055;14913.179775112365;15381.846181538265;15910.525034407678;1617.062103979689;1699.8555122814575;1870.3734249026593;1871.2234422714557;1893.4035778729656;72516.63741681258;104660;116045;116520;116765.99999999916;10115.39307641544;10331.32846364984;10545.261831671709;10636.787264254672;10758.677239682822;12977.286249256193;12994.830465258172;13014.869851301366;13044;13115.606531804062;8172.550509721904;8422.663093476192;8431.915680843236;8473.830523389497;8494.490330580185;8138.877916831252;8169.019717633922;8447.746567602982;8552.529610871461;8707.651693932172;6380.904286435707;6498.952829858579;6515.2181738190775;6520.315366886503;6643.468522518187;1721.0276193950378;1778.5731424458038;1805.5576383786056;1806.97289540657;1819.5178277756406;803.4135081390617;888.3337496877342;932.7434955387323;995.8406654935176;1044.253358848427;21475.8188299645;21659.458513537287;21711.565768684537;22022.779772202393;22137.003834827472;913.5706218077493;951.4909523404945;955.2310390136007;964.6671898195111;1034.6533911139852;10995.1176120775;11093.389863557582;11788.056955443544;11810.822837657443;11879.109066820023;478935;483301;485960;583858;594011;85330;102505.46241806379;110911;127226;128752;868.8653258744905;902.5893218585528;908.8366890380324;1004.3973615830589;1014.3305418423953;14822.258887055388;17106.48680539586;18984.52538686544;19170;19181.520461988002;43962.56037439585;54938.175927360324;55128;55333.893322134136;56884;83054;83062.75405869038;83153.58423207846;84490.15509844823;84913.57543212244;7625.428092892944;7733.4973226740085;7993.240642138988;8091.554964476775;8128.024637043356;9435.433873967584;11508.021818145551;12766.29785361814;12822.102452828212;13041.152325098838;10935.453227338443;11238.66284011481;11287.943560282147;11303.265287756269;11367.54529818798;10160.33957792741;10673.252872298865;10723.302985306242;11082.16883733707;11365.602203922823;10032.398056116657;10590.364578125336;11495.041043070272;11499.540018399504;11631.069514438994;7853.568053757098;7893.921060789548;8226.588670566329;8284.130166332448;8290.917090829014;8123.431359804993;11146.944265278946;11147.777044459228;11408.600698975495;11451.31292122442 17:35:51 INFO - PROCESS | 1663 | |3;mootools.html;1468.1117923656188;1559.165846272244;1665.9750103748445;1817.827306405891;1832.0839580209895;7749.30256276935;7974.242446967529;8341.499510029402;8957.37298389113;8983.820323593523;6835.658217089151;7786.676265034944;8380.580970951458;8790.208881200691;8791.988921274055;1974.7756391037576;2166.44755587325;2312.0520586559514;2313.2597568777996;2315.3169814904645;2260.513989492259;2286.314105768271;2318.8608683479047;2327.615943369342;2340.414896275931;1962.9901850490728;2004.3986803958826;2287.588234117858;2305.66567847662;2316.0388438797877;2001.2895422125155;2307.1232931486097;2314.5139520700673;2318.258157389636;2332.591796435617;2300.574393737164;2302.033146078634;2311.6879221305076;2312.456572705413;2321.721393432782;7617.733379331752;8670.739877803677;8839.337049721296;8847.424917380415;8862.0251772305;4177.248095342837;5189.455107213764;5227.817026404057;5231.843044708665;5236;2964.673885872552;3327.5674162358737;3425.554677891883;3485.041613556267;3544.2379888324135;1036.3678156324636;1039.8752149742004;1041.6354276003337;1047.3611097230682;1066.9253152279343;10686.893131068746;10992;11049.447527623666;11144.60993865219;11147.721306967309;4445.533219011957;4450.465944086665;4642.280446530725;4652.953470465252;4656.11533808582;9372.812543749087;9442.38624489406;9533.713988580215;9535;9553.283503737248;5199.6880187188135;5212.218167274939;5212.609054320941;5217.373915130209;5222.268882356473;4322.69741118125;4427.136708341843;4759.214729569651;4798.808047678054;4815.614750819926;3625.8912232633065;3629.8747388309707;3880.553736320303;3934.7048971327263;3981.6217459341315;1238.8389509363726;1247.65689435406;1254.0594554084437;1255.811628255769;1259.785836407813;897.2597606974273;914.9039350868196;924.902885197058;951.3198063384713;952.0717300631828;5462;5879.9710050741805;6026.276846778414;6067.696615169224;6119.051547010221;406.443172853193;406.4837656176639;406.50609509445576;415.25461796076115;420.0653545860436;3983.701222408331;4030.5969403059457;4040.5353384360587;4063.146739184804;4273.615374616299;9081.954590227138;10528.526216320284;10753.623623173151;10778.245522813328;10794.136469082454;8291.626876790559;10590.947045264878;10701.78596428067;10713.517891694892;10729.9463502682;8002.7199048033035;8102.635381407851;8161.183881611792;8305.792355191168;8310.584470776437;5922;6000.759969601167;6053.727582258721;6057.21256236704;6116.969415152896;9961.601535938482;10029.29794914349;10041.246906482189;10042.397456152654;10073;9745.902540974499;9921.702348929544;10213.795724085625;10266.281360304707;10331.121854642439;6936.514443988873;7169.103862017248;7239.420846332386;7534.736284230245;7686.692532298645;8050.677972881019;8203.917960820554;8304.792380190544;8312.916870831214;8320.417570769989;6400.87984602702;7061.258567850302;7221.350078492961;7349.77950661481;7481;7214.90717677763;7215.314545118205;7216.422686185198;7260.891086633599;7320.158181808947;5434.885848401147;6198.380161983766;6740.12378390806;6776.15298087739;6874.415674667509;4938.728369939544;5014.623903207347;5018.29743835856;5054.7978080876355;5106.617003724661;3975.383815508601;5017.899642007213;5039.143345631325;5091.567216786615;5100.209467532539 17:35:51 INFO - PROCESS | 1663 | |4;prototype.html;269.9041840146748;289.0677564853348;290.69331854893085;297.94934861073614;299.60601808621647;5410.431904650014;5599.692016939067;5660.075574304736;5685.720712839609;5713.485926229548;4700.6944548604315;5342.79787047914;5393.6763794172375;5508.283923090003;5572.4148964358765;4167.83328666853;4196.664266858659;4207.474065741782;4209.5790420957965;4248.746619747167;3599.280143971203;3701.5743189533146;4086;4132.338825787874;4198.748075115487;4161.583841615829;4163.604457576541;4167.145735124307;4167.666586673074;4212.4304174453555;4186.91626167478;4198.9370159447635;4209.6000879916355;4218.873433796991;4227;2812.676542197653;3200.519922011717;3494.0740703713536;3620.4388319810473;3622.8188590570367;4733.290006499018;4839.830605928811;5298.125809241471;5364.249005139283;5442.6462279951675;4319.69762116652;4369.213541562517;4766.404199475066;4787.712737235742;4817.614590832725;4770.976145119287;4838.564529192389;5486.271824375349;5753.7123143843055;5763.1355296705415;3316.5688460500223;3915.3735402335487;3916.6475017248586;3922.078311596801;3968.4047392891007;5374.850059976002;5570.604640004796;5588.580856435784;6158.84602884927;6164.229471316085;3996.680265578747;4009.7594144351433;4026.979865100656;4030.879073627796;4034.455348527911;3751.793651349202;4025.798710064485;4031.879043628696;4102.692298077656;4111.671066314688;3838.136419305634;4329.567043295709;4543.34121552373;4743.5967942724565;4758.357621721023;5868.677222752704;5877.412258774174;5898.705064746746;5980.342162362137;6008.399160084044;1275.4196840437578;1292.65744577687;1302.6873550347848;1322.543722415765;1374.3746595299115;787.9244830806023;815.5392203405057;839.7816567692444;857.1171693156059;918.6187732091241;5134.7946082156295;5182.300389447376;5186.962607478519;5472.835814925558;5523.420040895729;5233.712145832016;6000.409891378788;6029.819105426756;6060.818175454743;6081.5742897996715;4588.931166032512;5142.87142821425;5213.002523548975;5511.807086752025;5562.666240025612;2763.668359796837;2822.393185465135;3034.666186719459;3128.374325134982;3171.4925611901986;5400.676834175652;5770.509506691894;6165.383461653799;6203.31763506014;6217.129601855653;5185.729496273361;6055.547503261187;6197.4732147767945;6258;6340.682965851781;5016.767538445357;6189.381061893775;6196.535259855529;6209.627422354492;6221.346758590374;5090.363704536933;6015.87968240624;6057.394260573908;6103.481204097701;6142.6314421134875;4730.526947305242;6036.788712395045;6044.788432405021;6046.57673962836;6048.788292409745;5993.883180746075;6151;6158.846028849314;6185.443310102112;6296.811095667137;3297.6207736110655;3591.712662987007;3885.5725870153697;3896.37657974734;3917.8628747993685;5432.456754324694;5564.888702226014;6040.848978775566;6053.757849685963;6058.848528786815;4937.654364194472;5710.942890571041;5833.679147646751;5837.708114594338;5885.087346612454;4951.9752401237765;5476.5913439681435;5544.724713316041;5805.883882322245;5821.779210072664;5127.641065125555;5453.536449401845;5666.801661941812;5828.94171058301;5930.940690593039;6010.609310394811;6031.819045428644;6035.909461358171;6039.610889495353;6052.455279024909;5477.548449660922;5837.883242335214;5839.795607153729;5887.1757953885635;5912.408759124053 17:35:51 INFO - PROCESS | 1663 | |5;yui.html;687.6699184391492;688.2944981394071;694.2987582541634;709.0888208651882;752.4770284652169;15163.166025868573;15705.528834134995;16173.433929812474;16239.91880040601;16325.020498770082;14696.632584185401;14938.701225975501;14958.326875290611;14976.700465990674;15003;894.3739382432291;904.9366544341899;907.3104440625107;912.548288597142;913.2739472119648;710.733474946895;736.5507040705165;872.8472517309477;896.6144557840126;907.9137481939205;960.8318544254762;962.7641227899139;965.2760429677742;970.034815358719;970.5777986575827;902.3052249767642;908.1009800297724;964.9807003859919;964.9807003859919;965.0735294117655;711.900333953247;713.8286811165283;714.2393351081082;714.4855703893188;717.3364637710097;14734.263286835723;14902.403903843833;14907.329170187368;14967.326470308863;14974.925125374304;11938.902432956898;14676;14680.55958321252;14948.551543453714;15198.696026079417;11775.587854425054;11898.22661526998;11922;11999.400029998465;12122.784866979724;3272.4927636216426;3741.7754934704003;3789.4694742736037;3819.8472061117523;3862.517185351831;114675;116212;118544;118958;120027.399863001;11202.21584489078;11308.830367544493;11322.886771132347;11380.54477820878;11681.591144310078;23823;23849.046038158627;23861;23901.402464938168;23904.52190956206;7787.454878158588;7862.213778622092;7899.170587088388;7937.285644292041;8014.71948481812;4221.071364299849;4275.87172384829;4297.183535128315;4398.73607583546;4494.573015563582;3604.243108947098;3703.4259689748133;3743.288775132716;3753.9436908446396;3754.7934863582764;600.4055984575239;642.3031011352734;644.7066584703948;659.6635715784985;662.1392190152782;580.0052909260638;632.8892443822313;643.1960049937578;662.5726406467898;673.1867149878409;9243.630254789734;9286.303527235485;9369.48467834262;9462.621495140118;9787.217022638171;507.84003039042585;554.3597145297189;609.7865746988518;632.5319263744796;644.9806505804834;3843.1545060086737;4220.430241917364;4245.554216807191;4594.5864872161665;4702.131699663203;91347.51914884995;92994.53502732578;140925;146993;151191;127824;129037;129112;131014.34492827665;132721;111992.88007119988;113124.43437782758;113233.86766132398;113613;114438;8485.109063548238;8940.776480588038;8975.713829956858;9028.774280643036;9040.367174297737;62714.372856272676;62797;63083.36916630775;63999.920007599205;64764.35235647583;57441;58205.4179458217;85746.14253857381;91840.54079729559;94602.52698736462;8954.238889694188;11590.594329198437;11740.295582265091;11838.940805296263;13661.385237664528;14703.55889323322;14779.556613301618;15113.397752943238;15311.15788631636;15819.915116552162;13491.662708432368;13850.65373365674;14041.764536814171;14045.859541404456;14336.426542938583;13647.454101835816;13756.793648095087;13996.300184990507;14486.492972745904;14494.202818845064;13557;13615.455381784617;13666.974976876576;13916.930415347859;14173.645658858199;8702.738917832476;8868.822623547363;9449.574769135543;11190.666660041923;11714;9875.95062024714;9927.354721943051;10567.577296908037;11544.59593914209;11610.825837612274 17:35:51 INFO - PROCESS | 1663 | __end_tp_report 17:35:51 INFO - PROCESS | 1663 | __start_cc_report 17:35:51 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,377 17:35:51 INFO - PROCESS | 1663 | __end_cc_report 17:35:51 INFO - PROCESS | 1663 | __startTimestamp1477442151077__endTimestamp 17:35:51 INFO - PROCESS | 1663 | 17:35:51 INFO - PROCESS | 1663 | ------- Summary: start ------- 17:35:51 INFO - PROCESS | 1663 | Number of tests: 6 17:35:51 INFO - PROCESS | 1663 | 17:35:51 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5619.96 Median:5808.94 stddev:650.25 (11.2%) stddev-sans-first:626.56 17:35:51 INFO - PROCESS | 1663 | Values: 3313.2 3339.4 3375.7 3392.9 3427.8 4272.4 5537.7 5544.7 5561.8 5570.1 4548.7 5554.2 5661.8 5711.2 5726.3 5004.1 5052.3 5126.1 5172.8 5197.9 5062.6 5229.5 5262.3 5269.9 5318.8 4134.8 5094.2 5129.3 5134.7 5199.1 5143.8 5145.7 5168.8 5183.5 5193.8 5046.6 5131.2 5157.6 5171.9 5190.2 4531.9 5498.5 5499.0 5549.2 5597.0 5229.0 5980.8 6142.6 6148.6 6169.8 6187.1 6196.1 6213.7 6229.0 6233.3 5622.0 5677.5 5695.5 5746.2 5766.0 5026.4 5226.9 5390.4 6174.9 6189.4 6064.4 6089.6 6090.3 6092.0 6096.1 6029.4 6067.2 6097.2 6197.7 6218.5 5774.5 6096.8 6159.9 6201.2 6203.3 5274.3 6131.9 6193.8 6202.5 6229.8 4771.1 4817.4 4877.2 4886.2 4893.2 4312.1 4396.4 4400.9 4431.4 4498.5 5674.7 5706.4 5746.7 5762.9 5765.3 6022.2 6143.0 6152.5 6236.8 6249.4 5626.4 5749.7 5776.2 5808.9 5924.7 5367.9 5374.9 5384.0 5398.4 5407.6 6145.7 6160.7 6163.0 6164.5 6176.1 6042.4 6113.7 6116.2 6148.8 6149.8 6185.1 6222.2 6238.9 6241.8 6254.2 5906.8 6001.3 6079.2 6110.5 6175.8 6108.1 6112.3 6152.0 6250.0 6265.2 6051.7 6054.8 6070.4 6112.0 6123.8 5888.9 6035.1 6080.1 6096.6 6104.6 4938.6 5696.2 5747.7 5850.4 5935.8 5957.8 5996.8 6033.5 6054.7 6060.8 5829.0 5832.6 5847.6 5868.6 5886.6 17:35:51 INFO - PROCESS | 1663 | 17:35:51 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:70521.08 Median:41033.99 stddev:152403.99 (371.4%) stddev-sans-first:152740.79 17:35:51 INFO - PROCESS | 1663 | Values: 481.0 485.9 488.4 533.0 550.1 40125.8 41156.6 41186.2 41241.6 44099.6 42687.4 43301.9 43653.0 43768.3 43948.8 8588.7 9315.5 9447.4 9634.7 9655.4 8196.4 9527.0 9659.2 9671.3 9681.0 8190.6 9620.4 9677.5 9695.8 9700.6 8202.0 9589.0 9649.0 9670.2 9673.6 9501.2 9572.7 9615.5 9624.1 9634.9 40652.8 40997.4 41428.4 41486.4 41696.8 3032.3 3400.0 3423.3 3471.6 3735.2 30868.7 31558.7 31593.8 31907.0 31933.5 3174.7 3188.6 3190.4 3311.0 3346.9 484817.0 485700.0 487691.0 488423.0 489792.0 60791.7 61030.0 61142.0 61256.7 61298.5 63993.4 64110.0 64183.0 64607.7 64625.7 15539.8 15858.8 15947.8 16080.4 16126.8 12077.4 12354.4 12439.9 12450.9 12479.9 15461.8 17031.9 17076.7 17111.3 17113.5 3202.7 6809.8 6965.9 6981.2 6981.9 891.5 942.6 1032.3 1083.3 1084.9 30605.5 30640.4 30860.0 30904.8 31054.8 1061.7 1065.1 1071.1 1077.4 1159.2 15798.9 16032.8 16042.8 16362.3 16520.3 558522.0 624143.0 634424.5 643176.0 646040.0 58578.0 60283.7 60294.7 64390.7 64424.0 1368.9 1382.3 1394.3 1412.7 1424.6 30542.8 31406.8 31460.5 31968.8 32012.7 52178.7 52832.0 52842.5 52865.7 52885.5 587610.0 592658.0 596011.0 596238.0 596256.0 27058.9 27207.3 27300.7 27522.4 27658.9 46550.8 46681.1 47417.0 47773.0 48014.8 39386.0 39530.2 39711.0 40696.8 41070.6 33884.5 38622.4 39036.4 39973.0 40357.4 37710.6 37935.0 38254.2 38643.2 38707.8 32342.8 34772.1 34787.7 34816.1 35225.5 25461.7 25702.9 25748.7 25995.7 26263.6 17:35:51 INFO - PROCESS | 1663 | 17:35:51 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:32372.39 Median:25148.31 stddev:88223.28 (350.8%) stddev-sans-first:88437.94 17:35:51 INFO - PROCESS | 1663 | Values: 320.5 327.8 356.6 357.8 368.9 19354.3 19480.0 20660.5 20769.5 20903.5 19493.9 19895.8 20373.5 20462.2 20819.5 5621.9 5815.9 5866.0 5892.7 5924.7 4426.6 5724.7 5838.8 5851.9 5877.5 5535.7 5799.3 5815.2 5818.4 5828.6 5679.7 5802.5 5824.6 5828.1 5829.3 4816.1 4979.6 4983.2 5058.6 5076.3 24792.3 24842.5 25098.2 25198.4 25405.0 2182.9 2285.6 2303.6 2568.5 2861.0 12640.6 14600.9 14913.2 15381.8 15910.5 1617.1 1699.9 1870.4 1871.2 1893.4 72516.6 104660.0 116045.0 116520.0 116766.0 10115.4 10331.3 10545.3 10636.8 10758.7 12977.3 12994.8 13014.9 13044.0 13115.6 8172.6 8422.7 8431.9 8473.8 8494.5 8138.9 8169.0 8447.7 8552.5 8707.7 6380.9 6499.0 6515.2 6520.3 6643.5 1721.0 1778.6 1805.6 1807.0 1819.5 803.4 888.3 932.7 995.8 1044.3 21475.8 21659.5 21711.6 22022.8 22137.0 913.6 951.5 955.2 964.7 1034.7 10995.1 11093.4 11788.1 11810.8 11879.1 478935.0 483301.0 485960.0 583858.0 594011.0 85330.0 102505.5 110911.0 127226.0 128752.0 868.9 902.6 908.8 1004.4 1014.3 14822.3 17106.5 18984.5 19170.0 19181.5 43962.6 54938.2 55128.0 55333.9 56884.0 83054.0 83062.8 83153.6 84490.2 84913.6 7625.4 7733.5 7993.2 8091.6 8128.0 9435.4 11508.0 12766.3 12822.1 13041.2 10935.5 11238.7 11287.9 11303.3 11367.5 10160.3 10673.3 10723.3 11082.2 11365.6 10032.4 10590.4 11495.0 11499.5 11631.1 7853.6 7893.9 8226.6 8284.1 8290.9 8123.4 11146.9 11147.8 11408.6 11451.3 17:35:51 INFO - PROCESS | 1663 | 17:35:51 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5550.43 Median:4807.21 stddev:3131.79 (65.1%) stddev-sans-first:3125.55 17:35:51 INFO - PROCESS | 1663 | Values: 1468.1 1559.2 1666.0 1817.8 1832.1 7749.3 7974.2 8341.5 8957.4 8983.8 6835.7 7786.7 8380.6 8790.2 8792.0 1974.8 2166.4 2312.1 2313.3 2315.3 2260.5 2286.3 2318.9 2327.6 2340.4 1963.0 2004.4 2287.6 2305.7 2316.0 2001.3 2307.1 2314.5 2318.3 2332.6 2300.6 2302.0 2311.7 2312.5 2321.7 7617.7 8670.7 8839.3 8847.4 8862.0 4177.2 5189.5 5227.8 5231.8 5236.0 2964.7 3327.6 3425.6 3485.0 3544.2 1036.4 1039.9 1041.6 1047.4 1066.9 10686.9 10992.0 11049.4 11144.6 11147.7 4445.5 4450.5 4642.3 4653.0 4656.1 9372.8 9442.4 9533.7 9535.0 9553.3 5199.7 5212.2 5212.6 5217.4 5222.3 4322.7 4427.1 4759.2 4798.8 4815.6 3625.9 3629.9 3880.6 3934.7 3981.6 1238.8 1247.7 1254.1 1255.8 1259.8 897.3 914.9 924.9 951.3 952.1 5462.0 5880.0 6026.3 6067.7 6119.1 406.4 406.5 406.5 415.3 420.1 3983.7 4030.6 4040.5 4063.1 4273.6 9082.0 10528.5 10753.6 10778.2 10794.1 8291.6 10590.9 10701.8 10713.5 10729.9 8002.7 8102.6 8161.2 8305.8 8310.6 5922.0 6000.8 6053.7 6057.2 6117.0 9961.6 10029.3 10041.2 10042.4 10073.0 9745.9 9921.7 10213.8 10266.3 10331.1 6936.5 7169.1 7239.4 7534.7 7686.7 8050.7 8203.9 8304.8 8312.9 8320.4 6400.9 7061.3 7221.4 7349.8 7481.0 7214.9 7215.3 7216.4 7260.9 7320.2 5434.9 6198.4 6740.1 6776.2 6874.4 4938.7 5014.6 5018.3 5054.8 5106.6 3975.4 5017.9 5039.1 5091.6 5100.2 17:35:51 INFO - PROCESS | 1663 | 17:35:51 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4709.49 Median:5353.52 stddev:1493.85 (27.9%) stddev-sans-first:1460.40 17:35:51 INFO - PROCESS | 1663 | Values: 269.9 289.1 290.7 297.9 299.6 5410.4 5599.7 5660.1 5685.7 5713.5 4700.7 5342.8 5393.7 5508.3 5572.4 4167.8 4196.7 4207.5 4209.6 4248.7 3599.3 3701.6 4086.0 4132.3 4198.7 4161.6 4163.6 4167.1 4167.7 4212.4 4186.9 4198.9 4209.6 4218.9 4227.0 2812.7 3200.5 3494.1 3620.4 3622.8 4733.3 4839.8 5298.1 5364.2 5442.6 4319.7 4369.2 4766.4 4787.7 4817.6 4771.0 4838.6 5486.3 5753.7 5763.1 3316.6 3915.4 3916.6 3922.1 3968.4 5374.9 5570.6 5588.6 6158.8 6164.2 3996.7 4009.8 4027.0 4030.9 4034.5 3751.8 4025.8 4031.9 4102.7 4111.7 3838.1 4329.6 4543.3 4743.6 4758.4 5868.7 5877.4 5898.7 5980.3 6008.4 1275.4 1292.7 1302.7 1322.5 1374.4 787.9 815.5 839.8 857.1 918.6 5134.8 5182.3 5187.0 5472.8 5523.4 5233.7 6000.4 6029.8 6060.8 6081.6 4588.9 5142.9 5213.0 5511.8 5562.7 2763.7 2822.4 3034.7 3128.4 3171.5 5400.7 5770.5 6165.4 6203.3 6217.1 5185.7 6055.5 6197.5 6258.0 6340.7 5016.8 6189.4 6196.5 6209.6 6221.3 5090.4 6015.9 6057.4 6103.5 6142.6 4730.5 6036.8 6044.8 6046.6 6048.8 5993.9 6151.0 6158.8 6185.4 6296.8 3297.6 3591.7 3885.6 3896.4 3917.9 5432.5 5564.9 6040.8 6053.8 6058.8 4937.7 5710.9 5833.7 5837.7 5885.1 4952.0 5476.6 5544.7 5805.9 5821.8 5127.6 5453.5 5666.8 5828.9 5930.9 6010.6 6031.8 6035.9 6039.6 6052.5 5477.5 5837.9 5839.8 5887.2 5912.4 17:35:51 INFO - PROCESS | 1663 | 17:35:51 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:24229.91 Median:3831.50 stddev:38195.36 (996.9%) stddev-sans-first:38261.61 17:35:51 INFO - PROCESS | 1663 | Values: 687.7 688.3 694.3 709.1 752.5 15163.2 15705.5 16173.4 16239.9 16325.0 14696.6 14938.7 14958.3 14976.7 15003.0 894.4 904.9 907.3 912.5 913.3 710.7 736.6 872.8 896.6 907.9 960.8 962.8 965.3 970.0 970.6 902.3 908.1 965.0 965.0 965.1 711.9 713.8 714.2 714.5 717.3 14734.3 14902.4 14907.3 14967.3 14974.9 11938.9 14676.0 14680.6 14948.6 15198.7 11775.6 11898.2 11922.0 11999.4 12122.8 3272.5 3741.8 3789.5 3819.8 3862.5 114675.0 116212.0 118544.0 118958.0 120027.4 11202.2 11308.8 11322.9 11380.5 11681.6 23823.0 23849.0 23861.0 23901.4 23904.5 7787.5 7862.2 7899.2 7937.3 8014.7 4221.1 4275.9 4297.2 4398.7 4494.6 3604.2 3703.4 3743.3 3753.9 3754.8 600.4 642.3 644.7 659.7 662.1 580.0 632.9 643.2 662.6 673.2 9243.6 9286.3 9369.5 9462.6 9787.2 507.8 554.4 609.8 632.5 645.0 3843.2 4220.4 4245.6 4594.6 4702.1 91347.5 92994.5 140925.0 146993.0 151191.0 127824.0 129037.0 129112.0 131014.3 132721.0 111992.9 113124.4 113233.9 113613.0 114438.0 8485.1 8940.8 8975.7 9028.8 9040.4 62714.4 62797.0 63083.4 63999.9 64764.4 57441.0 58205.4 85746.1 91840.5 94602.5 8954.2 11590.6 11740.3 11838.9 13661.4 14703.6 14779.6 15113.4 15311.2 15819.9 13491.7 13850.7 14041.8 14045.9 14336.4 13647.5 13756.8 13996.3 14486.5 14494.2 13557.0 13615.5 13667.0 13916.9 14173.6 8702.7 8868.8 9449.6 11190.7 11714.0 9876.0 9927.4 10567.6 11544.6 11610.8 17:35:51 INFO - PROCESS | 1663 | -------- Summary: end -------- 17:35:51 INFO - PROCESS | 1663 | 17:35:51 INFO - TEST-INFO | 1663: exit 0 17:35:51 INFO - TEST-OK | dromaeo_css | took 1115799ms 17:35:51 INFO - TEST-START | kraken 17:35:51 INFO - operating with platform_type : mac_ 17:35:51 INFO - Initialising browser for kraken test... 17:35:51 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/tmpSvDVkw/profile http://localhost:49268/getInfo.html) 17:35:54 INFO - PROCESS | 1671 | 2016-10-25 17:35:54.508 plugin-container[1672:10200] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 17:35:54 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:35:54 INFO - PROCESS | 1671 | 2016-10-25 17:35:54.514 plugin-container[1672:10200] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4823, name = 'com.apple.CFPasteboardClient' 17:35:54 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:35:54 INFO - PROCESS | 1671 | 2016-10-25 17:35:54.514 plugin-container[1672:10200] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:35:54 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 17:35:54 INFO - PROCESS | 1671 | colorDepth:24 17:35:54 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 17:35:54 INFO - PROCESS | 1671 | __metrics 17:35:54 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 17:35:55 INFO - TEST-INFO | 1671: exit 0 17:35:55 INFO - Browser initialized. 17:35:55 INFO - Running cycle 1/1 for kraken test... 17:35:55 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/tmpSvDVkw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:35:57 INFO - PROCESS | 1673 | 2016-10-25 17:35:57.780 plugin-container[1674:10318] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853b, name = 'com.apple.tsm.portname' 17:35:57 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:35:57 INFO - PROCESS | 1673 | 2016-10-25 17:35:57.781 plugin-container[1674:10318] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4613, name = 'com.apple.CFPasteboardClient' 17:35:57 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:35:57 INFO - PROCESS | 1673 | 2016-10-25 17:35:57.781 plugin-container[1674:10318] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:36:07 INFO - PROCESS | 1673 | RSS: Main: 241500160 17:36:07 INFO - PROCESS | 1673 | 17:36:10 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 17:36:11 INFO - PROCESS | 1673 | RSS: Main: 252170240 17:36:11 INFO - PROCESS | 1673 | 17:36:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 17:36:14 INFO - PROCESS | 1673 | RSS: Main: 259760128 17:36:14 INFO - PROCESS | 1673 | 17:36:18 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 17:36:19 INFO - PROCESS | 1673 | RSS: Main: 261136384 17:36:19 INFO - PROCESS | 1673 | 17:36:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 17:36:21 INFO - PROCESS | 1673 | RSS: Main: 262529024 17:36:21 INFO - PROCESS | 1673 | 17:36:23 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:36:24 INFO - PROCESS | 1673 | RSS: Main: 262803456 17:36:24 INFO - PROCESS | 1673 | 17:36:27 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 17:36:28 INFO - PROCESS | 1673 | RSS: Main: 262897664 17:36:28 INFO - PROCESS | 1673 | 17:36:30 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 17:36:31 INFO - PROCESS | 1673 | RSS: Main: 263110656 17:36:31 INFO - PROCESS | 1673 | 17:36:34 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 17:36:35 INFO - PROCESS | 1673 | RSS: Main: 263200768 17:36:35 INFO - PROCESS | 1673 | 17:36:36 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:36:37 INFO - PROCESS | 1673 | RSS: Main: 263053312 17:36:37 INFO - PROCESS | 1673 | 17:36:39 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:36:39 INFO - PROCESS | 1673 | RSS: Main: 263081984 17:36:39 INFO - PROCESS | 1673 | 17:36:42 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:36:42 INFO - PROCESS | 1673 | RSS: Main: 263180288 17:36:42 INFO - PROCESS | 1673 | 17:36:45 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:36:46 INFO - PROCESS | 1673 | RSS: Main: 263602176 17:36:46 INFO - PROCESS | 1673 | 17:36:49 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:36:50 INFO - PROCESS | 1673 | RSS: Main: 263389184 17:36:50 INFO - PROCESS | 1673 | 17:36:51 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 17:36:52 INFO - PROCESS | 1673 | RSS: Main: 264908800 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - PROCESS | 1673 | __start_tp_report 17:36:52 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 17:36:52 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 17:36:52 INFO - PROCESS | 1673 | |i|pagename|runs| 17:36:52 INFO - PROCESS | 1673 | |0;ai-astar;107;177;128;113;117;120;134;175;156;118 17:36:52 INFO - PROCESS | 1673 | |1;audio-beat-detection;182;146;152;150;115;119;117;117;160;110 17:36:52 INFO - PROCESS | 1673 | |2;audio-dft;201;237;256;281;233;195;201;221;193;195 17:36:52 INFO - PROCESS | 1673 | |3;audio-fft;82;60;59;86;63;78;85;56;84;50 17:36:52 INFO - PROCESS | 1673 | |4;audio-oscillator;76;92;71;77;102;104;125;102;69;84 17:36:52 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;91;102;85;132;131;86;101;87;80;74 17:36:52 INFO - PROCESS | 1673 | |6;imaging-darkroom;96;98;121;97;96;96;104;101;97;97 17:36:52 INFO - PROCESS | 1673 | |7;imaging-desaturate;86;93;87;86;143;141;89;127;112;141 17:36:52 INFO - PROCESS | 1673 | |8;json-parse-financial;57;57;57;57;58;59;58;58;56;56 17:36:52 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;68;72;73;83;80;75;70;71;70;72 17:36:52 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;89;91;88;94;89;87;74;108;114;84 17:36:52 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;148;147;174;146;162;158;166;172;178;173 17:36:52 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;176;177;179;175;176;178;184;173;174;175 17:36:52 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;69;70;70;69;71;68;70;76;62;86 17:36:52 INFO - PROCESS | 1673 | __end_tp_report 17:36:52 INFO - PROCESS | 1673 | __start_cc_report 17:36:52 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,976 17:36:52 INFO - PROCESS | 1673 | __end_cc_report 17:36:52 INFO - PROCESS | 1673 | __startTimestamp1477442212133__endTimestamp 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - PROCESS | 1673 | ------- Summary: start ------- 17:36:52 INFO - PROCESS | 1673 | Number of tests: 14 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:134.50 Median:131.00 stddev:25.73 (19.6%) stddev-sans-first:25.29 17:36:52 INFO - PROCESS | 1673 | Values: 107.0 177.0 128.0 113.0 117.0 120.0 134.0 175.0 156.0 118.0 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:136.80 Median:148.00 stddev:24.42 (16.5%) stddev-sans-first:19.67 17:36:52 INFO - PROCESS | 1673 | Values: 182.0 146.0 152.0 150.0 115.0 119.0 117.0 117.0 160.0 110.0 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:221.30 Median:227.00 stddev:30.11 (13.3%) stddev-sans-first:31.03 17:36:52 INFO - PROCESS | 1673 | Values: 201.0 237.0 256.0 281.0 233.0 195.0 201.0 221.0 193.0 195.0 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:70.30 Median:80.00 stddev:13.94 (17.4%) stddev-sans-first:14.13 17:36:52 INFO - PROCESS | 1673 | Values: 82.0 60.0 59.0 86.0 63.0 78.0 85.0 56.0 84.0 50.0 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:73.50 stddev:18.00 (24.5%) stddev-sans-first:18.34 17:36:52 INFO - PROCESS | 1673 | Values: 76.0 92.0 71.0 77.0 102.0 104.0 125.0 102.0 69.0 84.0 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:96.90 Median:82.50 stddev:20.11 (24.4%) stddev-sans-first:21.22 17:36:52 INFO - PROCESS | 1673 | Values: 91.0 102.0 85.0 132.0 131.0 86.0 101.0 87.0 80.0 74.0 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:100.30 Median:96.50 stddev:7.72 (8.0%) stddev-sans-first:8.03 17:36:52 INFO - PROCESS | 1673 | Values: 96.0 98.0 121.0 97.0 96.0 96.0 104.0 101.0 97.0 97.0 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:110.50 Median:86.00 stddev:25.19 (29.3%) stddev-sans-first:25.11 17:36:52 INFO - PROCESS | 1673 | Values: 86.0 93.0 87.0 86.0 143.0 141.0 89.0 127.0 112.0 141.0 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:57.50 stddev:0.95 (1.6%) stddev-sans-first:1.00 17:36:52 INFO - PROCESS | 1673 | Values: 57.0 57.0 57.0 57.0 58.0 59.0 58.0 58.0 56.0 56.0 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:73.40 Median:72.50 stddev:4.72 (6.5%) stddev-sans-first:4.58 17:36:52 INFO - PROCESS | 1673 | Values: 68.0 72.0 73.0 83.0 80.0 75.0 70.0 71.0 70.0 72.0 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:91.80 Median:88.50 stddev:11.51 (13.0%) stddev-sans-first:12.16 17:36:52 INFO - PROCESS | 1673 | Values: 89.0 91.0 88.0 94.0 89.0 87.0 74.0 108.0 114.0 84.0 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:162.40 Median:169.00 stddev:12.15 (7.2%) stddev-sans-first:11.72 17:36:52 INFO - PROCESS | 1673 | Values: 148.0 147.0 174.0 146.0 162.0 158.0 166.0 172.0 178.0 173.0 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.70 Median:176.50 stddev:3.13 (1.8%) stddev-sans-first:3.31 17:36:52 INFO - PROCESS | 1673 | Values: 176.0 177.0 179.0 175.0 176.0 178.0 184.0 173.0 174.0 175.0 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:70.00 stddev:6.24 (8.9%) stddev-sans-first:6.58 17:36:52 INFO - PROCESS | 1673 | Values: 69.0 70.0 70.0 69.0 71.0 68.0 70.0 76.0 62.0 86.0 17:36:52 INFO - PROCESS | 1673 | -------- Summary: end -------- 17:36:52 INFO - PROCESS | 1673 | 17:36:52 INFO - TEST-INFO | 1673: exit 0 17:36:52 INFO - TEST-OK | kraken | took 61280ms 17:36:52 INFO - SUITE-END | took 1177s 17:36:53 INFO - Completed test suite (00:19:37) 17:36:53 INFO - javascript benchmark 17:36:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7313.475455678502, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3313.1717070732316, 3339.448990916499, 3375.7468189885763, 3392.932141357173, 3427.7943323400596, 4272.401863739079, 5537.667739935597, 5544.667319960805, 5561.777528898844, 5570.053090974534, 4548.658850586203, 5554.166874968757, 5661.830145095653, 5711.171880077397, 5726.284214473191, 5004.074246264453, 5052.317937078503, 5126.128558145106, 5172.793088276483, 5197.948020519784, 5062.620303477235, 5229.529342359187, 5262.342207224097, 5269.947300526984, 5318.813841515548, 4134.793260336971, 5094.235864620299, 5129.281900533928, 5134.691918484903, 5199.1161502544655, 5143.8456846294675, 5145.665531740425, 5168.767405466725, 5183.481651834786, 5193.766280517386, 5046.621503387224, 5131.204663277198, 5157.58739300855, 5171.922421163686, 5190.169572868323, 4531.932021019687, 5498.505134537911, 5499.010178167928, 5549.223108764776, 5596.964561556125, 5228.973855130738, 5980.820575382702, 6142.6314421134875, 6148.616561273722, 6169.7532098716, 6187.07193920911, 6196.10156527301, 6213.658248796314, 6229.0, 6233.3143354231, 5622.044252477089, 5677.489025987598, 5695.515881150066, 5746.195532625436, 5765.971170144123, 5026.447090820006, 5226.895462090737, 5390.407055223922, 6174.876502469925, 6189.442950134419, 6064.36324185963, 6089.604175728652, 6090.299615544269, 6092.025275955826, 6096.055111457731, 6029.3669164737075, 6067.211262535842, 6097.237845269341, 6197.659128747962, 6218.533609979179, 5774.538036957033, 6096.817095487142, 6159.922013647593, 6201.1938448002375, 6203.286622038433, 5274.314339135888, 6131.938680613226, 6193.84515387119, 6202.534809889186, 6229.844253893689, 4771.06964141989, 4817.397825271841, 4877.219644856806, 4886.242632391985, 4893.192623217201, 4312.072904325585, 4396.428464299621, 4400.933985990213, 4431.357453169337, 4498.50516443191, 5674.659520428705, 5706.400827913009, 5746.741396637161, 5762.94237057624, 5765.250517432707, 6022.1870047543025, 6143.047827586731, 6152.509536839133, 6236.781712640036, 6249.375062493714, 5626.409227031103, 5749.712514374264, 5776.220210271644, 5808.94191058084, 5924.7333869975955, 5367.946320536823, 5374.946250537523, 5383.973080134652, 5398.379186393615, 5407.648502847381, 6145.723442445101, 6160.691965401712, 6163.0, 6164.506839452923, 6176.073588961602, 6042.425969532887, 6113.724882380215, 6116.214037636738, 6148.8009838082935, 6149.84625384369, 6185.134081228542, 6222.16000839899, 6238.937610624017, 6241.843953901189, 6254.186955695731, 5906.793262235801, 6001.309849367204, 6079.209702738615, 6110.480609148272, 6175.783847565313, 6108.099244189703, 6112.26032301701, 6152.0156774915795, 6250.0, 6265.154204182377, 6051.697415129314, 6054.84862878414, 6070.365345142047, 6112.047575716935, 6123.785667501616, 5888.852778680396, 6035.094735789667, 6080.087986802015, 6096.634201947897, 6104.572679912363, 4938.629602779734, 5696.202531645656, 5747.74135163927, 5850.356460789395, 5935.762569497172, 5957.821265362046, 5996.760129594942, 6033.5173186145885, 6054.727537260922, 6060.787872424444, 5829.009068458287, 5832.562557808266, 5847.619904706181, 5868.559858010752, 5886.558508111994], "value": 5580.965680102862, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.9711417314961, 485.91883060191066, 488.4456142278513, 532.9866753331166, 550.0786183143234, 40125.799371003144, 41156.58843411565, 41186.17627647449, 41241.58758412415, 44099.559004409944, 42687.3596896046, 43301.91745206364, 43653.0, 43768.343474847905, 43948.780256098755, 8588.656453741844, 9315.462948613487, 9447.385919915218, 9634.662786802463, 9655.420674759502, 8196.385271104691, 9527.0, 9659.227261819073, 9671.274654400913, 9680.95159524205, 8190.631421586043, 9620.374675646095, 9677.467739274338, 9695.757606059835, 9700.563474643623, 8201.95899020501, 9589.0, 9648.951755241249, 9670.178034867045, 9673.613055477843, 9501.192398646124, 9572.66495672655, 9615.471149086798, 9624.133827955447, 9634.903650963472, 40652.796736016426, 40997.385039224435, 41428.37857432141, 41486.37770433446, 41696.79151604223, 3032.3177285110896, 3399.9660003399895, 3423.2639982403653, 3471.6007659119273, 3735.1969326594913, 30868.691313086805, 31558.684413155803, 31593.8420307897, 31907.0, 31933.52099718506, 3174.7301479374282, 3188.6333071696586, 3190.3619276144864, 3311.0397984584442, 3346.8995930122137, 484817.0, 485700.0, 487691.0, 488423.0, 489791.99999999645, 60791.696041520394, 61030.0, 61142.0, 61256.69371653113, 61298.46753831101, 63993.36006639967, 64110.0, 64183.0, 64607.70784584282, 64625.67687161534, 15539.766903496457, 15858.841411585736, 15947.760783588255, 16080.43718469848, 16126.838731612768, 12077.39613019363, 12354.38228088592, 12439.937800310941, 12450.875491245153, 12479.93760031194, 15461.768073478906, 17031.892926959707, 17076.743848842278, 17111.315547377966, 17113.48659540216, 3202.743780497601, 6809.829754256183, 6965.895511567331, 6981.1622605286675, 6981.930180698229, 891.4517571693492, 942.6465075596651, 1032.2670903658336, 1083.2525557365393, 1084.8806631270554, 30605.540916886264, 30640.387192256476, 30860.0, 30904.845475772927, 31054.844725776227, 1061.7451811565322, 1065.0787069185253, 1071.0735214039798, 1077.3589714120087, 1159.2001518952065, 15798.9210053949, 16032.839671603368, 16042.75935860963, 16362.34550617962, 16520.256588453547, 558522.0, 624143.0, 634424.4508997106, 643176.0, 646040.0, 58578.0, 60283.69858150681, 60294.69852650708, 64390.678046609464, 64424.0, 1368.856270091646, 1382.260490637504, 1394.2610416479283, 1412.7386433509628, 1424.6224750440933, 30542.84728576343, 31406.842965785025, 31460.528092079094, 31968.840155800004, 32012.67987320097, 52178.73910630422, 52832.0, 52842.47157528376, 52865.73567132294, 52885.47114528805, 587610.0, 592658.0, 596011.0, 596238.0, 596256.0, 27058.917643294048, 27207.319817004733, 27300.72699272982, 27522.449551009267, 27658.893644254003, 46550.76724616355, 46681.06637867291, 47417.0, 47773.0, 48014.759926200146, 39386.0, 39530.20939581135, 39711.0, 40696.79651601723, 41070.58929410668, 33884.49173262354, 38622.42066369063, 39036.41445378265, 39973.000674983356, 40357.39463908102, 37710.62289377071, 37935.019777022695, 38254.23491530098, 38643.227135457695, 38707.80646096751, 32342.83828580842, 34772.11889795629, 34787.65212347844, 34816.12959675927, 35225.47161792626, 25461.745382545938, 25702.8714856432, 25748.742512575383, 25995.74004260009, 26263.60604590971], "value": 20039.416627396196, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.48882033157116, 327.81314650649125, 356.6451380876029, 357.8461261657488, 368.918837855672, 19354.32259870903, 19480.02599870006, 20660.483487912792, 20769.480762980933, 20903.47741306468, 19493.902530487365, 19895.801041989576, 20373.49066273344, 20462.18151273951, 20819.479513012182, 5621.887562248752, 5815.894979953817, 5866.0, 5892.675902825343, 5924.703764811764, 4426.557344265564, 5724.656520608777, 5838.795642152526, 5851.941480585182, 5877.500412464946, 5535.723213839312, 5799.333076696171, 5815.156802263669, 5818.41815818419, 5828.621139625932, 5679.659220446766, 5802.535797136219, 5824.563157763164, 5828.067509198508, 5829.300483941933, 4816.060868130716, 4979.551840334351, 4983.152864013126, 5058.620603454719, 5076.314697515825, 24792.25623231306, 24842.50314993708, 25098.247052588453, 25198.370040748945, 25404.98380064795, 2182.923597674074, 2285.645724912641, 2303.6314189729565, 2568.5376632206194, 2861.0272507347395, 12640.620781376574, 14600.853991460055, 14913.179775112365, 15381.846181538265, 15910.525034407678, 1617.062103979689, 1699.8555122814575, 1870.3734249026593, 1871.2234422714557, 1893.4035778729656, 72516.63741681258, 104660.0, 116045.0, 116520.0, 116765.99999999916, 10115.39307641544, 10331.32846364984, 10545.261831671709, 10636.787264254672, 10758.677239682822, 12977.286249256193, 12994.830465258172, 13014.869851301366, 13044.0, 13115.606531804062, 8172.550509721904, 8422.663093476192, 8431.915680843236, 8473.830523389497, 8494.490330580185, 8138.877916831252, 8169.019717633922, 8447.746567602982, 8552.529610871461, 8707.651693932172, 6380.904286435707, 6498.952829858579, 6515.2181738190775, 6520.315366886503, 6643.468522518187, 1721.0276193950378, 1778.5731424458038, 1805.5576383786056, 1806.97289540657, 1819.5178277756406, 803.4135081390617, 888.3337496877342, 932.7434955387323, 995.8406654935176, 1044.253358848427, 21475.8188299645, 21659.458513537287, 21711.565768684537, 22022.779772202393, 22137.003834827472, 913.5706218077493, 951.4909523404945, 955.2310390136007, 964.6671898195111, 1034.6533911139852, 10995.1176120775, 11093.389863557582, 11788.056955443544, 11810.822837657443, 11879.109066820023, 478935.0, 483301.0, 485960.0, 583858.0, 594011.0, 85330.0, 102505.46241806379, 110911.0, 127226.0, 128752.0, 868.8653258744905, 902.5893218585528, 908.8366890380324, 1004.3973615830589, 1014.3305418423953, 14822.258887055388, 17106.48680539586, 18984.52538686544, 19170.0, 19181.520461988002, 43962.56037439585, 54938.175927360324, 55128.0, 55333.893322134136, 56884.0, 83054.0, 83062.75405869038, 83153.58423207846, 84490.15509844823, 84913.57543212244, 7625.428092892944, 7733.4973226740085, 7993.240642138988, 8091.554964476775, 8128.024637043356, 9435.433873967584, 11508.021818145551, 12766.29785361814, 12822.102452828212, 13041.152325098838, 10935.453227338443, 11238.66284011481, 11287.943560282147, 11303.265287756269, 11367.54529818798, 10160.33957792741, 10673.252872298865, 10723.302985306242, 11082.16883733707, 11365.602203922823, 10032.398056116657, 10590.364578125336, 11495.041043070272, 11499.540018399504, 11631.069514438994, 7853.568053757098, 7893.921060789548, 8226.588670566329, 8284.130166332448, 8290.917090829014, 8123.431359804993, 11146.944265278946, 11147.777044459228, 11408.600698975495, 11451.31292122442], "value": 9497.099776469973, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1468.1117923656188, 1559.165846272244, 1665.9750103748445, 1817.827306405891, 1832.0839580209895, 7749.30256276935, 7974.242446967529, 8341.499510029402, 8957.37298389113, 8983.820323593523, 6835.658217089151, 7786.676265034944, 8380.580970951458, 8790.208881200691, 8791.988921274055, 1974.7756391037576, 2166.44755587325, 2312.0520586559514, 2313.2597568777996, 2315.3169814904645, 2260.513989492259, 2286.314105768271, 2318.8608683479047, 2327.615943369342, 2340.414896275931, 1962.9901850490728, 2004.3986803958826, 2287.588234117858, 2305.66567847662, 2316.0388438797877, 2001.2895422125155, 2307.1232931486097, 2314.5139520700673, 2318.258157389636, 2332.591796435617, 2300.574393737164, 2302.033146078634, 2311.6879221305076, 2312.456572705413, 2321.721393432782, 7617.733379331752, 8670.739877803677, 8839.337049721296, 8847.424917380415, 8862.0251772305, 4177.248095342837, 5189.455107213764, 5227.817026404057, 5231.843044708665, 5236.0, 2964.673885872552, 3327.5674162358737, 3425.554677891883, 3485.041613556267, 3544.2379888324135, 1036.3678156324636, 1039.8752149742004, 1041.6354276003337, 1047.3611097230682, 1066.9253152279343, 10686.893131068746, 10992.0, 11049.447527623666, 11144.60993865219, 11147.721306967309, 4445.533219011957, 4450.465944086665, 4642.280446530725, 4652.953470465252, 4656.11533808582, 9372.812543749087, 9442.38624489406, 9533.713988580215, 9535.0, 9553.283503737248, 5199.6880187188135, 5212.218167274939, 5212.609054320941, 5217.373915130209, 5222.268882356473, 4322.69741118125, 4427.136708341843, 4759.214729569651, 4798.808047678054, 4815.614750819926, 3625.8912232633065, 3629.8747388309707, 3880.553736320303, 3934.7048971327263, 3981.6217459341315, 1238.8389509363726, 1247.65689435406, 1254.0594554084437, 1255.811628255769, 1259.785836407813, 897.2597606974273, 914.9039350868196, 924.902885197058, 951.3198063384713, 952.0717300631828, 5462.0, 5879.9710050741805, 6026.276846778414, 6067.696615169224, 6119.051547010221, 406.443172853193, 406.4837656176639, 406.50609509445576, 415.25461796076115, 420.0653545860436, 3983.701222408331, 4030.5969403059457, 4040.5353384360587, 4063.146739184804, 4273.615374616299, 9081.954590227138, 10528.526216320284, 10753.623623173151, 10778.245522813328, 10794.136469082454, 8291.626876790559, 10590.947045264878, 10701.78596428067, 10713.517891694892, 10729.9463502682, 8002.7199048033035, 8102.635381407851, 8161.183881611792, 8305.792355191168, 8310.584470776437, 5922.0, 6000.759969601167, 6053.727582258721, 6057.21256236704, 6116.969415152896, 9961.601535938482, 10029.29794914349, 10041.246906482189, 10042.397456152654, 10073.0, 9745.902540974499, 9921.702348929544, 10213.795724085625, 10266.281360304707, 10331.121854642439, 6936.514443988873, 7169.103862017248, 7239.420846332386, 7534.736284230245, 7686.692532298645, 8050.677972881019, 8203.917960820554, 8304.792380190544, 8312.916870831214, 8320.417570769989, 6400.87984602702, 7061.258567850302, 7221.350078492961, 7349.77950661481, 7481.0, 7214.90717677763, 7215.314545118205, 7216.422686185198, 7260.891086633599, 7320.158181808947, 5434.885848401147, 6198.380161983766, 6740.12378390806, 6776.15298087739, 6874.415674667509, 4938.728369939544, 5014.623903207347, 5018.29743835856, 5054.7978080876355, 5106.617003724661, 3975.383815508601, 5017.899642007213, 5039.143345631325, 5091.567216786615, 5100.209467532539], "value": 4384.4668966440395, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.9041840146748, 289.0677564853348, 290.69331854893085, 297.94934861073614, 299.60601808621647, 5410.431904650014, 5599.692016939067, 5660.075574304736, 5685.720712839609, 5713.485926229548, 4700.6944548604315, 5342.79787047914, 5393.6763794172375, 5508.283923090003, 5572.4148964358765, 4167.83328666853, 4196.664266858659, 4207.474065741782, 4209.5790420957965, 4248.746619747167, 3599.280143971203, 3701.5743189533146, 4086.0, 4132.338825787874, 4198.748075115487, 4161.583841615829, 4163.604457576541, 4167.145735124307, 4167.666586673074, 4212.4304174453555, 4186.91626167478, 4198.9370159447635, 4209.6000879916355, 4218.873433796991, 4227.0, 2812.676542197653, 3200.519922011717, 3494.0740703713536, 3620.4388319810473, 3622.8188590570367, 4733.290006499018, 4839.830605928811, 5298.125809241471, 5364.249005139283, 5442.6462279951675, 4319.69762116652, 4369.213541562517, 4766.404199475066, 4787.712737235742, 4817.614590832725, 4770.976145119287, 4838.564529192389, 5486.271824375349, 5753.7123143843055, 5763.1355296705415, 3316.5688460500223, 3915.3735402335487, 3916.6475017248586, 3922.078311596801, 3968.4047392891007, 5374.850059976002, 5570.604640004796, 5588.580856435784, 6158.84602884927, 6164.229471316085, 3996.680265578747, 4009.7594144351433, 4026.979865100656, 4030.879073627796, 4034.455348527911, 3751.793651349202, 4025.798710064485, 4031.879043628696, 4102.692298077656, 4111.671066314688, 3838.136419305634, 4329.567043295709, 4543.34121552373, 4743.5967942724565, 4758.357621721023, 5868.677222752704, 5877.412258774174, 5898.705064746746, 5980.342162362137, 6008.399160084044, 1275.4196840437578, 1292.65744577687, 1302.6873550347848, 1322.543722415765, 1374.3746595299115, 787.9244830806023, 815.5392203405057, 839.7816567692444, 857.1171693156059, 918.6187732091241, 5134.7946082156295, 5182.300389447376, 5186.962607478519, 5472.835814925558, 5523.420040895729, 5233.712145832016, 6000.409891378788, 6029.819105426756, 6060.818175454743, 6081.5742897996715, 4588.931166032512, 5142.87142821425, 5213.002523548975, 5511.807086752025, 5562.666240025612, 2763.668359796837, 2822.393185465135, 3034.666186719459, 3128.374325134982, 3171.4925611901986, 5400.676834175652, 5770.509506691894, 6165.383461653799, 6203.31763506014, 6217.129601855653, 5185.729496273361, 6055.547503261187, 6197.4732147767945, 6258.0, 6340.682965851781, 5016.767538445357, 6189.381061893775, 6196.535259855529, 6209.627422354492, 6221.346758590374, 5090.363704536933, 6015.87968240624, 6057.394260573908, 6103.481204097701, 6142.6314421134875, 4730.526947305242, 6036.788712395045, 6044.788432405021, 6046.57673962836, 6048.788292409745, 5993.883180746075, 6151.0, 6158.846028849314, 6185.443310102112, 6296.811095667137, 3297.6207736110655, 3591.712662987007, 3885.5725870153697, 3896.37657974734, 3917.8628747993685, 5432.456754324694, 5564.888702226014, 6040.848978775566, 6053.757849685963, 6058.848528786815, 4937.654364194472, 5710.942890571041, 5833.679147646751, 5837.708114594338, 5885.087346612454, 4951.9752401237765, 5476.5913439681435, 5544.724713316041, 5805.883882322245, 5821.779210072664, 5127.641065125555, 5453.536449401845, 5666.801661941812, 5828.94171058301, 5930.940690593039, 6010.609310394811, 6031.819045428644, 6035.909461358171, 6039.610889495353, 6052.455279024909, 5477.548449660922, 5837.883242335214, 5839.795607153729, 5887.1757953885635, 5912.408759124053], "value": 4216.107764318126, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.6699184391492, 688.2944981394071, 694.2987582541634, 709.0888208651882, 752.4770284652169, 15163.166025868573, 15705.528834134995, 16173.433929812474, 16239.91880040601, 16325.020498770082, 14696.632584185401, 14938.701225975501, 14958.326875290611, 14976.700465990674, 15003.0, 894.3739382432291, 904.9366544341899, 907.3104440625107, 912.548288597142, 913.2739472119648, 710.733474946895, 736.5507040705165, 872.8472517309477, 896.6144557840126, 907.9137481939205, 960.8318544254762, 962.7641227899139, 965.2760429677742, 970.034815358719, 970.5777986575827, 902.3052249767642, 908.1009800297724, 964.9807003859919, 964.9807003859919, 965.0735294117655, 711.900333953247, 713.8286811165283, 714.2393351081082, 714.4855703893188, 717.3364637710097, 14734.263286835723, 14902.403903843833, 14907.329170187368, 14967.326470308863, 14974.925125374304, 11938.902432956898, 14676.0, 14680.55958321252, 14948.551543453714, 15198.696026079417, 11775.587854425054, 11898.22661526998, 11922.0, 11999.400029998465, 12122.784866979724, 3272.4927636216426, 3741.7754934704003, 3789.4694742736037, 3819.8472061117523, 3862.517185351831, 114675.0, 116212.0, 118544.0, 118958.0, 120027.399863001, 11202.21584489078, 11308.830367544493, 11322.886771132347, 11380.54477820878, 11681.591144310078, 23823.0, 23849.046038158627, 23861.0, 23901.402464938168, 23904.52190956206, 7787.454878158588, 7862.213778622092, 7899.170587088388, 7937.285644292041, 8014.71948481812, 4221.071364299849, 4275.87172384829, 4297.183535128315, 4398.73607583546, 4494.573015563582, 3604.243108947098, 3703.4259689748133, 3743.288775132716, 3753.9436908446396, 3754.7934863582764, 600.4055984575239, 642.3031011352734, 644.7066584703948, 659.6635715784985, 662.1392190152782, 580.0052909260638, 632.8892443822313, 643.1960049937578, 662.5726406467898, 673.1867149878409, 9243.630254789734, 9286.303527235485, 9369.48467834262, 9462.621495140118, 9787.217022638171, 507.84003039042585, 554.3597145297189, 609.7865746988518, 632.5319263744796, 644.9806505804834, 3843.1545060086737, 4220.430241917364, 4245.554216807191, 4594.5864872161665, 4702.131699663203, 91347.51914884995, 92994.53502732578, 140925.0, 146993.0, 151191.0, 127824.0, 129037.0, 129112.0, 131014.34492827665, 132721.0, 111992.88007119988, 113124.43437782758, 113233.86766132398, 113613.0, 114438.0, 8485.109063548238, 8940.776480588038, 8975.713829956858, 9028.774280643036, 9040.367174297737, 62714.372856272676, 62797.0, 63083.36916630775, 63999.920007599205, 64764.35235647583, 57441.0, 58205.4179458217, 85746.14253857381, 91840.54079729559, 94602.52698736462, 8954.238889694188, 11590.594329198437, 11740.295582265091, 11838.940805296263, 13661.385237664528, 14703.55889323322, 14779.556613301618, 15113.397752943238, 15311.15788631636, 15819.915116552162, 13491.662708432368, 13850.65373365674, 14041.764536814171, 14045.859541404456, 14336.426542938583, 13647.454101835816, 13756.793648095087, 13996.300184990507, 14486.492972745904, 14494.202818845064, 13557.0, 13615.455381784617, 13666.974976876576, 13916.930415347859, 14173.645658858199, 8702.738917832476, 8868.822623547363, 9449.574769135543, 11190.666660041923, 11714.0, 9875.95062024714, 9927.354721943051, 10567.577296908037, 11544.59593914209, 11610.825837612274], "value": 7792.597087216381, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1593.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 177.0, 128.0, 113.0, 117.0, 120.0, 134.0, 175.0, 156.0, 118.0], "value": 134.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 146.0, 152.0, 150.0, 115.0, 119.0, 117.0, 117.0, 160.0, 110.0], "value": 136.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 237.0, 256.0, 281.0, 233.0, 195.0, 201.0, 221.0, 193.0, 195.0], "value": 221.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 59.0, 86.0, 63.0, 78.0, 85.0, 56.0, 84.0, 50.0], "value": 70.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 92.0, 71.0, 77.0, 102.0, 104.0, 125.0, 102.0, 69.0, 84.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 102.0, 85.0, 132.0, 131.0, 86.0, 101.0, 87.0, 80.0, 74.0], "value": 96.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 121.0, 97.0, 96.0, 96.0, 104.0, 101.0, 97.0, 97.0], "value": 100.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 93.0, 87.0, 86.0, 143.0, 141.0, 89.0, 127.0, 112.0, 141.0], "value": 110.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 57.0, 58.0, 59.0, 58.0, 58.0, 56.0, 56.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 73.0, 83.0, 80.0, 75.0, 70.0, 71.0, 70.0, 72.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 88.0, 94.0, 89.0, 87.0, 74.0, 108.0, 114.0, 84.0], "value": 91.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 174.0, 146.0, 162.0, 158.0, 166.0, 172.0, 178.0, 173.0], "value": 162.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 177.0, 179.0, 175.0, 176.0, 178.0, 184.0, 173.0, 174.0, 175.0], "value": 176.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 70.0, 69.0, 71.0, 68.0, 70.0, 76.0, 62.0, 86.0], "value": 71.1, "unit": "score"}]}]} 17:36:53 INFO - Return code: 0 17:36:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:36:53 ERROR - # TBPL SUCCESS # 17:36:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:36:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:36:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:36:53 INFO - 2016-10-25 17:36:53.434 screenresolution[1678:10701] starting screenresolution argv=screenresolution get 17:36:53 INFO - 2016-10-25 17:36:53.436 screenresolution[1678:10701] Display 0: 1600x1200x32@60 17:36:53 INFO - 2016-10-25 17:36:53.442 screenresolution[1679:10704] starting screenresolution argv=screenresolution list 17:36:53 INFO - Available Modes on Display 0 17:36:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:36:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:36:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:36:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:36:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:36:53 INFO - Intel Iris: 17:36:53 INFO - Chipset Model: Intel Iris 17:36:53 INFO - Type: GPU 17:36:53 INFO - Bus: Built-In 17:36:53 INFO - VRAM (Dynamic, Max): 1536 MB 17:36:53 INFO - Vendor: Intel (0x8086) 17:36:53 INFO - Device ID: 0x0a2e 17:36:53 INFO - Revision ID: 0x0009 17:36:53 INFO - Displays: 17:36:53 INFO - Display: 17:36:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:36:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:36:53 INFO - Display Serial Number: 1600x1200 60 17:36:53 INFO - Main Display: Yes 17:36:53 INFO - Mirror: Off 17:36:53 INFO - Online: Yes 17:36:53 INFO - Rotation: Supported 17:36:53 INFO - Return code: 0 17:36:53 INFO - Running post-action listener: _resource_record_post_action 17:36:53 INFO - [mozharness: 2016-10-26 00:36:53.677676Z] Finished run-tests step (success) 17:36:53 INFO - Running post-run listener: _resource_record_post_run 17:36:53 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 33837568; Write bytes: 708691456; Read time: 319; Write time: 2101 17:36:53 INFO - TinderboxPrint: CPU usage
54.5% 17:36:53 INFO - TinderboxPrint: I/O read bytes / time
33,837,568 / 319 17:36:53 INFO - TinderboxPrint: I/O write bytes / time
708,691,456 / 2,101 17:36:53 INFO - TinderboxPrint: CPU idle
2,206.3 (45.5%) 17:36:53 INFO - TinderboxPrint: CPU system
983.5 (20.3%) 17:36:53 INFO - TinderboxPrint: CPU user
1,659.9 (34.2%) 17:36:53 INFO - TinderboxPrint: Swap in / out
434,827,264 / 0 17:36:53 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 395706880; Write bytes: 390137856; Read time: 29508; Write time: 866 17:36:53 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 31826432; Write bytes: 300015104; Read time: 297; Write time: 1190 17:36:54 INFO - Running post-run listener: _upload_blobber_files 17:36:54 INFO - Blob upload gear active. 17:36:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:36:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:36:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:36:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:36:54 INFO - (blobuploader) - INFO - Open directory for files ... 17:36:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:36:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:36:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:56 INFO - (blobuploader) - INFO - Done attempting. 17:36:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:36:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:36:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:57 INFO - (blobuploader) - INFO - Done attempting. 17:36:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:36:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:37:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:37:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:37:00 INFO - (blobuploader) - INFO - Done attempting. 17:37:00 INFO - (blobuploader) - INFO - Iteration through files over. 17:37:00 INFO - Return code: 0 17:37:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:37:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:37:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d45ca2847bdc2aacbb4cee126d5548c502b539ce72fc5cf9b5ae88f31063e88d3561c4af81ac9eb485d2dfed622857a691e7bc682c6f5db4b2e458f051c28462", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9d5e1c85881c20c3abc51e09acbb89bb08302739974aaa898a8c4d20d44276f71e69fea69e3102c2d01188de9e666515b49b0d64e8990f5b5d99bfa37f018fe2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6c779461ab427a375fc3e1c3b523fcfe819714e8e57da44b69117acb01134eeafd83a6d87e3dd6fb83c7c9f36d88e11210f4766712538f2e686cff561bc80409"} 17:37:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:37:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:37:00 INFO - Contents: 17:37:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d45ca2847bdc2aacbb4cee126d5548c502b539ce72fc5cf9b5ae88f31063e88d3561c4af81ac9eb485d2dfed622857a691e7bc682c6f5db4b2e458f051c28462", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9d5e1c85881c20c3abc51e09acbb89bb08302739974aaa898a8c4d20d44276f71e69fea69e3102c2d01188de9e666515b49b0d64e8990f5b5d99bfa37f018fe2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6c779461ab427a375fc3e1c3b523fcfe819714e8e57da44b69117acb01134eeafd83a6d87e3dd6fb83c7c9f36d88e11210f4766712538f2e686cff561bc80409"} 17:37:00 INFO - Running post-run listener: copy_logs_to_upload_dir 17:37:00 INFO - Copying logs to upload dir... 17:37:00 INFO - mkdir: /builds/slave/test/build/upload/logs 17:37:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.085909 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-25 17:37:00.265394) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 17:37:00.268890) ========= 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.ZtrP8Elikb/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.zaMWudJtDy/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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/Larch/sha512/d45ca2847bdc2aacbb4cee126d5548c502b539ce72fc5cf9b5ae88f31063e88d3561c4af81ac9eb485d2dfed622857a691e7bc682c6f5db4b2e458f051c28462", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9d5e1c85881c20c3abc51e09acbb89bb08302739974aaa898a8c4d20d44276f71e69fea69e3102c2d01188de9e666515b49b0d64e8990f5b5d99bfa37f018fe2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6c779461ab427a375fc3e1c3b523fcfe819714e8e57da44b69117acb01134eeafd83a6d87e3dd6fb83c7c9f36d88e11210f4766712538f2e686cff561bc80409"} build_url:https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011014 build_url: 'https://queue.taskcluster.net/v1/task/Chdk7gsdR_qgkKMt4qYeDA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d45ca2847bdc2aacbb4cee126d5548c502b539ce72fc5cf9b5ae88f31063e88d3561c4af81ac9eb485d2dfed622857a691e7bc682c6f5db4b2e458f051c28462", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9d5e1c85881c20c3abc51e09acbb89bb08302739974aaa898a8c4d20d44276f71e69fea69e3102c2d01188de9e666515b49b0d64e8990f5b5d99bfa37f018fe2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6c779461ab427a375fc3e1c3b523fcfe819714e8e57da44b69117acb01134eeafd83a6d87e3dd6fb83c7c9f36d88e11210f4766712538f2e686cff561bc80409"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 17:37:00.302769) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 17:37:00.303452) ========= 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.ZtrP8Elikb/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.zaMWudJtDy/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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.005123 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 17:37:00.353863) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 17:37:00.354262) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 17:37:00.354679) ========= ========= Total master_lag: 0.19 =========