builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0287 starttime: 1482349071.17 results: success (0) buildid: 20161221092053 builduid: d9cd92c2e1c74995a16a5b9419719058 revision: c3f401e19160ed979541940f21ff70a111cc5972 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 11:37:51.171570) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 11:37:51.172282) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 11:37:51.172807) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 11:37:51.201143) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 11:37:51.201648) ========= 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.0xfwgUwmTu/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.mgF2aZjQMC/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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-12-21 11:37:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.4M=0.001s 2016-12-21 11:37:51 (18.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113923 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 11:37:51.341571) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 11:37:51.341920) ========= 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.0xfwgUwmTu/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.mgF2aZjQMC/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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.024148 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 11:37:51.390552) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-21 11:37:51.390919) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c3f401e19160ed979541940f21ff70a111cc5972 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c3f401e19160ed979541940f21ff70a111cc5972 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0xfwgUwmTu/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.mgF2aZjQMC/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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-12-21 11:37:51,489 truncating revision to first 12 chars 2016-12-21 11:37:51,489 Setting DEBUG logging. 2016-12-21 11:37:51,489 attempt 1/10 2016-12-21 11:37:51,489 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c3f401e19160?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 11:37:53,515 unpacking tar archive at: mozilla-aurora-c3f401e19160/testing/mozharness/ program finished with exit code 0 elapsedTime=2.296613 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-21 11:37:53.712131) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 11:37:53.712714) ========= script_repo_revision: c3f401e19160ed979541940f21ff70a111cc5972 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 11:37:53.713310) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 11:37:53.713746) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 11:37:53.734269) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-21 11:37:53.734660) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0xfwgUwmTu/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.mgF2aZjQMC/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 11:37:53 INFO - MultiFileLogger online at 20161221 11:37:53 in /builds/slave/test 11:37:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 11:37:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:37:53 INFO - {'append_to_log': False, 11:37:53 INFO - 'base_work_dir': '/builds/slave/test', 11:37:53 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:37:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:37:53 INFO - 'branch': 'Mozilla-Aurora', 11:37:53 INFO - 'buildbot_json_path': 'buildprops.json', 11:37:53 INFO - 'config_files': ('talos/mac_config.py',), 11:37:53 INFO - 'default_actions': ('clobber', 11:37:53 INFO - 'read-buildbot-config', 11:37:53 INFO - 'download-and-extract', 11:37:53 INFO - 'populate-webroot', 11:37:53 INFO - 'create-virtualenv', 11:37:53 INFO - 'install', 11:37:53 INFO - 'run-tests'), 11:37:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:37:53 INFO - 'download_minidump_stackwalk': True, 11:37:53 INFO - 'download_symbols': 'ondemand', 11:37:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:37:53 INFO - 'tooltool.py': '/tools/tooltool.py', 11:37:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:37:53 INFO - '/tools/misc-python/virtualenv.py')}, 11:37:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:37:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:37:53 INFO - 'installer_path': 'installer.exe', 11:37:53 INFO - 'log_level': 'info', 11:37:53 INFO - 'log_name': 'talos', 11:37:53 INFO - 'log_to_console': True, 11:37:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:37:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:37:53 INFO - 'opt_config_files': (), 11:37:53 INFO - 'pip_index': False, 11:37:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:37:53 INFO - 'cmd': ('bash', 11:37:53 INFO - '-c', 11:37:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:37:53 INFO - 'enabled': True, 11:37:53 INFO - 'halt_on_failure': False, 11:37:53 INFO - 'name': 'check_screen_resolution'},), 11:37:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:37:53 INFO - 'cmd': ('bash', 11:37:53 INFO - '-c', 11:37:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:37:53 INFO - 'enabled': True, 11:37:53 INFO - 'halt_on_failure': False, 11:37:53 INFO - 'name': 'check_screen_resolution'},), 11:37:53 INFO - 'run_cmd_checks_enabled': True, 11:37:53 INFO - 'sps_profile': False, 11:37:53 INFO - 'sps_profile_interval': 0, 11:37:53 INFO - 'suite': 'dromaeojs-e10s', 11:37:53 INFO - 'system_bits': '32', 11:37:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:37:53 INFO - 'title': 't-yosemite-r7-0287', 11:37:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:37:53 INFO - 'use_talos_json': True, 11:37:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:37:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:37:53 INFO - 'work_dir': 'build'} 11:37:53 INFO - [mozharness: 2016-12-21 19:37:53.942021Z] Running clobber step. 11:37:53 INFO - Running pre-action listener: _resource_record_pre_action 11:37:53 INFO - Running main action method: clobber 11:37:53 INFO - rmtree: /builds/slave/test/build 11:37:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:37:56 INFO - Running post-action listener: _resource_record_post_action 11:37:56 INFO - [mozharness: 2016-12-21 19:37:56.179293Z] Finished clobber step (success) 11:37:56 INFO - [mozharness: 2016-12-21 19:37:56.179496Z] Running read-buildbot-config step. 11:37:56 INFO - Running pre-action listener: _resource_record_pre_action 11:37:56 INFO - Running main action method: read_buildbot_config 11:37:56 INFO - Using buildbot properties: 11:37:56 INFO - { 11:37:56 INFO - "project": "", 11:37:56 INFO - "product": "firefox", 11:37:56 INFO - "script_repo_revision": "production", 11:37:56 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 11:37:56 INFO - "repository": "", 11:37:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 11:37:56 INFO - "buildid": "20161221092053", 11:37:56 INFO - "pgo_build": "False", 11:37:56 INFO - "basedir": "/builds/slave/test", 11:37:56 INFO - "buildnumber": 59, 11:37:56 INFO - "slavename": "t-yosemite-r7-0287", 11:37:56 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 11:37:56 INFO - "platform": "yosemite_r7", 11:37:56 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:37:56 INFO - "branch": "mozilla-aurora", 11:37:56 INFO - "repo_path": "releases/mozilla-aurora", 11:37:56 INFO - "revision": "c3f401e19160ed979541940f21ff70a111cc5972", 11:37:56 INFO - "stage_platform": "macosx64", 11:37:56 INFO - "builduid": "d9cd92c2e1c74995a16a5b9419719058", 11:37:56 INFO - "slavebuilddir": "test" 11:37:56 INFO - } 11:37:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 11:37:56 INFO - Running post-action listener: _resource_record_post_action 11:37:56 INFO - [mozharness: 2016-12-21 19:37:56.183506Z] Finished read-buildbot-config step (success) 11:37:56 INFO - [mozharness: 2016-12-21 19:37:56.183644Z] Running download-and-extract step. 11:37:56 INFO - Running pre-action listener: _resource_record_pre_action 11:37:56 INFO - Running main action method: download_and_extract 11:37:56 INFO - mkdir: /builds/slave/test/build/tests 11:37:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:37:56 INFO - trying https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 11:37:56 INFO - Downloading https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 11:37:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 11:37:57 INFO - Downloaded 1199 bytes. 11:37:57 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 11:37:57 INFO - Using the following test package requirements: 11:37:57 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:37:57 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:37:57 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 11:37:57 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:37:57 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 11:37:57 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:37:57 INFO - u'jsshell-mac.zip'], 11:37:57 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:37:57 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 11:37:57 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:37:57 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:37:57 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 11:37:57 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:37:57 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 11:37:57 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:37:57 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 11:37:57 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:37:57 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 11:37:57 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:37:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:37:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:37:57 INFO - Fetch https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:37:58 INFO - Http code: 200 11:37:58 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 11:37:58 INFO - x-amz-cf-id: Qkl3Mih6KhjGKS3_LCt3LZN28CJAyjIBjEWVtk8ZuYZADGzGXe88YA== 11:37:58 INFO - x-amz-version-id: favEv2CG.cMET8jBwMqut0_rcQZZKtwz 11:37:58 INFO - Expected file size: 16269681 11:37:58 INFO - Obtained file size: 16269681 11:38:04 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 11:38:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:38:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:38:04 INFO - Fetch https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:38:05 INFO - Http code: 200 11:38:05 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 11:38:05 INFO - x-amz-cf-id: GGUhRVNx7-tnZgpVJ2ztusTd-yfzlWpUVq6f2GepiM6zE1bIL1Qovw== 11:38:05 INFO - x-amz-version-id: favEv2CG.cMET8jBwMqut0_rcQZZKtwz 11:38:06 INFO - Expected file size: 16269681 11:38:06 INFO - Obtained file size: 16269681 11:38:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 11:38:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:38:12 INFO - Fetch https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 11:38:13 INFO - Http code: 200 11:38:13 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 11:38:13 INFO - x-amz-cf-id: lzML8joJTYN-OfbshoH21cWA_qE98HpOeTPK8E0MkdMCEiuCfxcDYw== 11:38:13 INFO - x-amz-version-id: qs_85HPWVw_1y2ETnJmZouQipJWI19B3 11:38:14 INFO - Expected file size: 14409038 11:38:14 INFO - Obtained file size: 14409038 11:38:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:14 INFO - trying https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:38:14 INFO - Downloading https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 11:38:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 11:38:17 INFO - Downloaded 109762557 bytes. 11:38:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:38:17 INFO - mkdir: /builds/slave/test/properties 11:38:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:38:17 INFO - Writing to file /builds/slave/test/properties/build_url 11:38:17 INFO - Contents: 11:38:17 INFO - build_url:https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:38:18 INFO - Running post-action listener: _resource_record_post_action 11:38:18 INFO - Running post-action listener: set_extra_try_arguments 11:38:18 INFO - [mozharness: 2016-12-21 19:38:18.080555Z] Finished download-and-extract step (success) 11:38:18 INFO - [mozharness: 2016-12-21 19:38:18.080718Z] Running populate-webroot step. 11:38:18 INFO - Running pre-action listener: _resource_record_pre_action 11:38:18 INFO - Running main action method: populate_webroot 11:38:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:38:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:38:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:38:18 INFO - '--tppagecycles', 11:38:18 INFO - '7'], 11:38:18 INFO - 'tests': ['tsvgm']}}, 11:38:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:38:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:38:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:38:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:38:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:38:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:38:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:38:18 INFO - 'talos_options': ['--disable-e10s'], 11:38:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:38:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:38:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:38:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:38:18 INFO - 'talos_options': ['--disable-e10s'], 11:38:18 INFO - 'tests': ['damp', 'tps']}, 11:38:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:38:18 INFO - 'tests': ['damp', 'tps']}, 11:38:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:38:18 INFO - 'tests': ['dromaeo_dom']}, 11:38:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:38:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:38:18 INFO - 'tests': ['basic_compositor_video']}, 11:38:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:38:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:38:18 INFO - 'tests': ['a11yr', 11:38:18 INFO - 'ts_paint', 11:38:18 INFO - 'tpaint', 11:38:18 INFO - 'sessionrestore', 11:38:18 INFO - 'sessionrestore_no_auto_restore', 11:38:18 INFO - 'tabpaint']}, 11:38:18 INFO - 'other-e10s': {'tests': ['a11yr', 11:38:18 INFO - 'ts_paint', 11:38:18 INFO - 'tpaint', 11:38:18 INFO - 'sessionrestore', 11:38:18 INFO - 'sessionrestore_no_auto_restore', 11:38:18 INFO - 'tabpaint']}, 11:38:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:38:18 INFO - 'ts_paint', 11:38:18 INFO - 'tpaint', 11:38:18 INFO - 'sessionrestore', 11:38:18 INFO - 'sessionrestore_no_auto_restore', 11:38:18 INFO - 'tabpaint']}, 11:38:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:38:18 INFO - 'ts_paint', 11:38:18 INFO - 'tpaint', 11:38:18 INFO - 'sessionrestore', 11:38:18 INFO - 'sessionrestore_no_auto_restore', 11:38:18 INFO - 'tabpaint']}, 11:38:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:38:18 INFO - 'tests': ['a11yr', 11:38:18 INFO - 'ts_paint', 11:38:18 INFO - 'tpaint', 11:38:18 INFO - 'sessionrestore', 11:38:18 INFO - 'sessionrestore_no_auto_restore', 11:38:18 INFO - 'tabpaint']}, 11:38:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:38:18 INFO - 'tests': ['a11yr', 11:38:18 INFO - 'ts_paint', 11:38:18 INFO - 'tpaint', 11:38:18 INFO - 'sessionrestore', 11:38:18 INFO - 'sessionrestore_no_auto_restore', 11:38:18 INFO - 'tabpaint']}, 11:38:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:38:18 INFO - 'tests': ['tsvgx', 11:38:18 INFO - 'tsvgr_opacity', 11:38:18 INFO - 'tart', 11:38:18 INFO - 'tscrollx', 11:38:18 INFO - 'cart']}, 11:38:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:38:18 INFO - 'tsvgr_opacity', 11:38:18 INFO - 'tart', 11:38:18 INFO - 'tscrollx', 11:38:18 INFO - 'cart']}, 11:38:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:38:18 INFO - 'talos_options': ['--disable-e10s'], 11:38:18 INFO - 'tests': ['tp5o']}, 11:38:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:38:18 INFO - 'tests': ['tp5o']}, 11:38:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:38:18 INFO - 'talos_options': ['--disable-e10s', 11:38:18 INFO - '--xperf_path', 11:38:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:38:18 INFO - 'tests': ['tp5n']}, 11:38:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:38:18 INFO - 'talos_options': ['--xperf_path', 11:38:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:38:18 INFO - 'tests': ['tp5n']}}, 11:38:18 INFO - 'talos.zip': {'path': '', 11:38:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:38:18 INFO - Running post-action listener: _resource_record_post_action 11:38:18 INFO - [mozharness: 2016-12-21 19:38:18.099245Z] Finished populate-webroot step (success) 11:38:18 INFO - [mozharness: 2016-12-21 19:38:18.099366Z] Running create-virtualenv step. 11:38:18 INFO - Running pre-action listener: _resource_record_pre_action 11:38:18 INFO - Running main action method: create_virtualenv 11:38:18 INFO - Creating virtualenv /builds/slave/test/build/venv 11:38:18 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 11:38:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:38:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:38:18 INFO - Using real prefix '/tools/python27' 11:38:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:38:18 INFO - Installing distribute.............................................................................................................................................................................................done. 11:38:21 INFO - Installing pip.................done. 11:38:21 INFO - Return code: 0 11:38:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:38:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:38:22 INFO - Reading from file tmpfile_stdout 11:38:22 INFO - Output received: 11:38:22 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) 11:38:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:38:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10904c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad935c6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e60d30>, '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 0x108cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e58b20>, '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.0xfwgUwmTu/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.mgF2aZjQMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 11:38:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:38:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:38:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0xfwgUwmTu/Render', 11:38:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:22 INFO - 'HOME': '/Users/cltbld', 11:38:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:22 INFO - 'LOGNAME': 'cltbld', 11:38:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:22 INFO - 'MOZ_NO_REMOTE': '1', 11:38:22 INFO - 'NO_EM_RESTART': '1', 11:38:22 INFO - 'PAGER': '/bin/cat', 11:38:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:22 INFO - 'PWD': '/builds/slave/test', 11:38:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:22 INFO - 'SHELL': '/bin/bash', 11:38:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mgF2aZjQMC/Listeners', 11:38:22 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 11:38:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:22 INFO - 'USER': 'cltbld', 11:38:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:22 INFO - 'XPC_FLAGS': '0x0', 11:38:22 INFO - 'XPC_SERVICE_NAME': '0', 11:38:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:22 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 11:38:22 INFO - Cleaning up... 11:38:22 INFO - Return code: 0 11:38:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:38:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10904c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad935c6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e60d30>, '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 0x108cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e58b20>, '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.0xfwgUwmTu/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.mgF2aZjQMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 11:38:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:38:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:38:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0xfwgUwmTu/Render', 11:38:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:22 INFO - 'HOME': '/Users/cltbld', 11:38:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:22 INFO - 'LOGNAME': 'cltbld', 11:38:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:22 INFO - 'MOZ_NO_REMOTE': '1', 11:38:22 INFO - 'NO_EM_RESTART': '1', 11:38:22 INFO - 'PAGER': '/bin/cat', 11:38:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:22 INFO - 'PWD': '/builds/slave/test', 11:38:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:22 INFO - 'SHELL': '/bin/bash', 11:38:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mgF2aZjQMC/Listeners', 11:38:22 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 11:38:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:22 INFO - 'USER': 'cltbld', 11:38:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:22 INFO - 'XPC_FLAGS': '0x0', 11:38:22 INFO - 'XPC_SERVICE_NAME': '0', 11:38:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:22 INFO - Downloading/unpacking psutil>=3.1.1 11:38:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:38:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:38:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:38:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:38:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:38:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:38:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:38:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:38:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:38:25 INFO - Installing collected packages: psutil 11:38:25 INFO - Running setup.py install for psutil 11:38:26 INFO - building 'psutil._psutil_osx' extension 11:38:26 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 11:38:26 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 11:38:26 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 11:38:26 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 11:38:26 INFO - building 'psutil._psutil_posix' extension 11:38:26 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 11:38:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:38:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:38:26 INFO - ^ 11:38:26 INFO - 1 warning generated. 11:38:26 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 11:38:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:38:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:38:27 INFO - Successfully installed psutil 11:38:27 INFO - Cleaning up... 11:38:27 INFO - Return code: 0 11:38:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:38:27 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')]} 11:38:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:27 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')]} 11:38:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:27 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 0x10904c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad935c6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e60d30>, '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 0x108cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e58b20>, '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.0xfwgUwmTu/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.mgF2aZjQMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 11:38:27 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 11:38:27 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 11:38:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0xfwgUwmTu/Render', 11:38:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:27 INFO - 'HOME': '/Users/cltbld', 11:38:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:27 INFO - 'LOGNAME': 'cltbld', 11:38:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:27 INFO - 'MOZ_NO_REMOTE': '1', 11:38:27 INFO - 'NO_EM_RESTART': '1', 11:38:27 INFO - 'PAGER': '/bin/cat', 11:38:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:27 INFO - 'PWD': '/builds/slave/test', 11:38:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:27 INFO - 'SHELL': '/bin/bash', 11:38:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mgF2aZjQMC/Listeners', 11:38:27 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 11:38:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:27 INFO - 'USER': 'cltbld', 11:38:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:27 INFO - 'XPC_FLAGS': '0x0', 11:38:27 INFO - 'XPC_SERVICE_NAME': '0', 11:38:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:38:27 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 11:38:27 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 11:38:27 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 11:38:27 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 11:38:27 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 11:38:27 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 11:38:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:38:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:38:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:38:30 INFO - Installing collected packages: mozsystemmonitor 11:38:30 INFO - Running setup.py install for mozsystemmonitor 11:38:30 INFO - Successfully installed mozsystemmonitor 11:38:30 INFO - Cleaning up... 11:38:30 INFO - Return code: 0 11:38:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:38: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')]} 11:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38: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')]} 11:38:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:30 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 0x10904c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad935c6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e60d30>, '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 0x108cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e58b20>, '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.0xfwgUwmTu/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.mgF2aZjQMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 11:38:30 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 11:38:30 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 11:38:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0xfwgUwmTu/Render', 11:38:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:30 INFO - 'HOME': '/Users/cltbld', 11:38:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:30 INFO - 'LOGNAME': 'cltbld', 11:38:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:30 INFO - 'MOZ_NO_REMOTE': '1', 11:38:30 INFO - 'NO_EM_RESTART': '1', 11:38:30 INFO - 'PAGER': '/bin/cat', 11:38:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:30 INFO - 'PWD': '/builds/slave/test', 11:38:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:30 INFO - 'SHELL': '/bin/bash', 11:38:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mgF2aZjQMC/Listeners', 11:38:30 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 11:38:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:30 INFO - 'USER': 'cltbld', 11:38:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:30 INFO - 'XPC_FLAGS': '0x0', 11:38:30 INFO - 'XPC_SERVICE_NAME': '0', 11:38:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:30 INFO - Downloading/unpacking jsonschema==2.5.1 11:38:30 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 11:38:30 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 11:38:30 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 11:38:30 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 11:38:30 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 11:38:30 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 11:38:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:38:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:38:33 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 11:38:33 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 11:38:33 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 11:38:33 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 11:38:33 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 11:38:33 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 11:38:33 INFO - Downloading functools32-3.2.3-2.tar.gz 11:38:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:38:33 INFO - warning: no files found matching '*.txt' 11:38:33 INFO - no previously-included directories found matching 'build' 11:38:33 INFO - no previously-included directories found matching 'dist' 11:38:33 INFO - no previously-included directories found matching '.git*' 11:38:33 INFO - Installing collected packages: jsonschema, functools32 11:38:33 INFO - Running setup.py install for functools32 11:38:34 INFO - warning: no files found matching '*.txt' 11:38:34 INFO - no previously-included directories found matching 'build' 11:38:34 INFO - no previously-included directories found matching 'dist' 11:38:34 INFO - no previously-included directories found matching '.git*' 11:38:34 INFO - Successfully installed jsonschema functools32 11:38:34 INFO - Cleaning up... 11:38:34 INFO - Return code: 0 11:38:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:38: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')]} 11:38:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38: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')]} 11:38:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:34 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 0x10904c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad935c6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e60d30>, '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 0x108cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e58b20>, '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.0xfwgUwmTu/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.mgF2aZjQMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 11:38:34 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 11:38:34 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 11:38:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0xfwgUwmTu/Render', 11:38:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:34 INFO - 'HOME': '/Users/cltbld', 11:38:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:34 INFO - 'LOGNAME': 'cltbld', 11:38:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:34 INFO - 'MOZ_NO_REMOTE': '1', 11:38:34 INFO - 'NO_EM_RESTART': '1', 11:38:34 INFO - 'PAGER': '/bin/cat', 11:38:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:34 INFO - 'PWD': '/builds/slave/test', 11:38:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:34 INFO - 'SHELL': '/bin/bash', 11:38:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mgF2aZjQMC/Listeners', 11:38:34 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 11:38:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:34 INFO - 'USER': 'cltbld', 11:38:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:34 INFO - 'XPC_FLAGS': '0x0', 11:38:34 INFO - 'XPC_SERVICE_NAME': '0', 11:38:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:38:34 INFO - Cleaning up... 11:38:34 INFO - Return code: 0 11:38:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:38: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')]} 11:38:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38: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')]} 11:38:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:34 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 0x10904c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad935c6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e60d30>, '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 0x108cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e58b20>, '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.0xfwgUwmTu/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.mgF2aZjQMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 11:38:34 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 11:38:34 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 11:38:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0xfwgUwmTu/Render', 11:38:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:34 INFO - 'HOME': '/Users/cltbld', 11:38:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:34 INFO - 'LOGNAME': 'cltbld', 11:38:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:34 INFO - 'MOZ_NO_REMOTE': '1', 11:38:34 INFO - 'NO_EM_RESTART': '1', 11:38:34 INFO - 'PAGER': '/bin/cat', 11:38:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:34 INFO - 'PWD': '/builds/slave/test', 11:38:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:34 INFO - 'SHELL': '/bin/bash', 11:38:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mgF2aZjQMC/Listeners', 11:38:34 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 11:38:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:34 INFO - 'USER': 'cltbld', 11:38:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:34 INFO - 'XPC_FLAGS': '0x0', 11:38:34 INFO - 'XPC_SERVICE_NAME': '0', 11:38:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:34 INFO - Downloading/unpacking blobuploader==1.2.4 11:38:34 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 11:38:34 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 11:38:34 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 11:38:34 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 11:38:34 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 11:38:34 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 11:38:37 INFO - Downloading blobuploader-1.2.4.tar.gz 11:38:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:38:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:38:37 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 11:38:37 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 11:38:37 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 11:38:37 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 11:38:37 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 11:38:37 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 11:38:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:38:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:38:38 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 11:38:38 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 11:38:38 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 11:38:38 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 11:38:38 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 11:38:38 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 11:38:38 INFO - Downloading docopt-0.6.1.tar.gz 11:38:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:38:38 INFO - Installing collected packages: blobuploader, requests, docopt 11:38:38 INFO - Running setup.py install for blobuploader 11:38:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:38:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:38:38 INFO - Running setup.py install for requests 11:38:39 INFO - Running setup.py install for docopt 11:38:39 INFO - Successfully installed blobuploader requests docopt 11:38:39 INFO - Cleaning up... 11:38:39 INFO - Return code: 0 11:38:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:38:39 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')]} 11:38:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:39 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')]} 11:38:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:39 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 0x10904c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad935c6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e60d30>, '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 0x108cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e58b20>, '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.0xfwgUwmTu/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.mgF2aZjQMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 11:38:39 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 11:38:39 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 11:38:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0xfwgUwmTu/Render', 11:38:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:39 INFO - 'HOME': '/Users/cltbld', 11:38:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:39 INFO - 'LOGNAME': 'cltbld', 11:38:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:39 INFO - 'MOZ_NO_REMOTE': '1', 11:38:39 INFO - 'NO_EM_RESTART': '1', 11:38:39 INFO - 'PAGER': '/bin/cat', 11:38:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:39 INFO - 'PWD': '/builds/slave/test', 11:38:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:39 INFO - 'SHELL': '/bin/bash', 11:38:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mgF2aZjQMC/Listeners', 11:38:39 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 11:38:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:39 INFO - 'USER': 'cltbld', 11:38:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:39 INFO - 'XPC_FLAGS': '0x0', 11:38:39 INFO - 'XPC_SERVICE_NAME': '0', 11:38:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:38:39 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-I8QzXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:38:39 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-lRZFGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:38:40 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-GvOPpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:38:40 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-kqGDgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:38:40 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-vKlKjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:38:40 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-zKjRix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:38:40 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-Wt9cGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:38:40 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-pkL32z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:38:40 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-HEzlvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:38:40 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-q0PjMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:38:40 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-1VPSxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:38:40 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-Oh0khZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:38:41 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-8F4v_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:38:41 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-nszBi7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:38:41 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-7_fsQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:38:41 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-6nR4Pu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:38:41 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-16tKmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:38:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:38:41 INFO - Running setup.py install for manifestparser 11:38:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:38:41 INFO - Running setup.py install for mozcrash 11:38:41 INFO - Running setup.py install for mozdebug 11:38:42 INFO - Running setup.py install for mozdevice 11:38:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:38:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:38:42 INFO - Running setup.py install for mozfile 11:38:42 INFO - Running setup.py install for mozhttpd 11:38:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:38:42 INFO - Running setup.py install for mozinfo 11:38:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:38:42 INFO - Running setup.py install for mozInstall 11:38:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:38:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:38:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:38:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:38:42 INFO - Running setup.py install for mozleak 11:38:42 INFO - Running setup.py install for mozlog 11:38:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:38:43 INFO - Running setup.py install for moznetwork 11:38:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:38:43 INFO - Running setup.py install for mozprocess 11:38:43 INFO - Running setup.py install for mozprofile 11:38:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:38:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:38:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:38:43 INFO - Running setup.py install for mozrunner 11:38:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:38:43 INFO - Running setup.py install for mozscreenshot 11:38:43 INFO - Running setup.py install for moztest 11:38:44 INFO - Running setup.py install for mozversion 11:38:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:38:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:38:44 INFO - Cleaning up... 11:38:44 INFO - Return code: 0 11:38:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:38:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:44 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 0x10904c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad935c6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e60d30>, '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 0x108cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e58b20>, '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.0xfwgUwmTu/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.mgF2aZjQMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 11:38:44 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 11:38:44 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 11:38:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0xfwgUwmTu/Render', 11:38:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:44 INFO - 'HOME': '/Users/cltbld', 11:38:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:44 INFO - 'LOGNAME': 'cltbld', 11:38:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:44 INFO - 'MOZ_NO_REMOTE': '1', 11:38:44 INFO - 'NO_EM_RESTART': '1', 11:38:44 INFO - 'PAGER': '/bin/cat', 11:38:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:44 INFO - 'PWD': '/builds/slave/test', 11:38:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:44 INFO - 'SHELL': '/bin/bash', 11:38:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mgF2aZjQMC/Listeners', 11:38:44 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 11:38:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:44 INFO - 'USER': 'cltbld', 11:38:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:44 INFO - 'XPC_FLAGS': '0x0', 11:38:44 INFO - 'XPC_SERVICE_NAME': '0', 11:38:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:38:44 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-hobegn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:38:44 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)) 11:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:38:44 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-pG0bTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:38:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:38:44 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-l2qJ9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:38:44 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)) 11:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:38:44 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-WfJGSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:38:44 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)) 11:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:38:44 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-isxSY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:38:44 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)) 11:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:38:44 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-RUXpWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:38:45 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)) 11:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:38:45 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-O0zPnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:38:45 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)) 11:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:38:45 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-HWyAX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:38:45 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)) 11:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:38:45 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-CyDuqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:38:45 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)) 11:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:38:45 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-snNZuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:38:45 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)) 11:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:38:45 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-qLJKMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:38:45 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)) 11:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:38:45 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-KNZUpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:38:45 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)) 11:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:38:45 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-IVLnib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:38:45 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)) 11:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:38:45 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-yFBpO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:38:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:38:45 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-nZeTd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:38:46 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)) 11:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:38:46 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-DvZbeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:38:46 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)) 11:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:38:46 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-3HWsJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:38:46 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)) 11:38:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:38:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:38:46 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)) 11:38:46 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)) 11:38:46 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)) 11:38:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:38:46 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 11:38:46 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 11:38:46 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 11:38:46 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 11:38:46 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 11:38:46 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 11:38:48 INFO - Downloading blessings-1.6.tar.gz 11:38:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:38:49 INFO - Installing collected packages: blessings 11:38:49 INFO - Running setup.py install for blessings 11:38:49 INFO - Successfully installed blessings 11:38:49 INFO - Cleaning up... 11:38:49 INFO - Return code: 0 11:38:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:38:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:38:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:38:49 INFO - Reading from file tmpfile_stdout 11:38:49 INFO - Current package versions: 11:38:49 INFO - blessings == 1.6 11:38:49 INFO - blobuploader == 1.2.4 11:38:49 INFO - docopt == 0.6.1 11:38:49 INFO - functools32 == 3.2.3-2 11:38:49 INFO - jsonschema == 2.5.1 11:38:49 INFO - manifestparser == 1.1 11:38:49 INFO - mozInstall == 1.12 11:38:49 INFO - mozcrash == 1.0 11:38:49 INFO - mozdebug == 0.1 11:38:49 INFO - mozdevice == 0.48 11:38:49 INFO - mozfile == 1.2 11:38:49 INFO - mozhttpd == 0.7 11:38:49 INFO - mozinfo == 0.9 11:38:49 INFO - mozleak == 0.1 11:38:49 INFO - mozlog == 3.4 11:38:49 INFO - moznetwork == 0.27 11:38:49 INFO - mozprocess == 0.23 11:38:49 INFO - mozprofile == 0.28 11:38:49 INFO - mozrunner == 6.13 11:38:49 INFO - mozscreenshot == 0.1 11:38:49 INFO - mozsystemmonitor == 0.3 11:38:49 INFO - moztest == 0.7 11:38:49 INFO - mozversion == 1.4 11:38:49 INFO - psutil == 3.1.1 11:38:49 INFO - requests == 1.2.3 11:38:49 INFO - wsgiref == 0.1.2 11:38:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:38:49 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')]} 11:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:49 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')]} 11:38:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:50 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 0x10904c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad935c6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e60d30>, '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 0x108cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e58b20>, '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.0xfwgUwmTu/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.mgF2aZjQMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 11:38:50 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 11:38:50 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 11:38:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0xfwgUwmTu/Render', 11:38:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:50 INFO - 'HOME': '/Users/cltbld', 11:38:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:50 INFO - 'LOGNAME': 'cltbld', 11:38:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:50 INFO - 'MOZ_NO_REMOTE': '1', 11:38:50 INFO - 'NO_EM_RESTART': '1', 11:38:50 INFO - 'PAGER': '/bin/cat', 11:38:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:50 INFO - 'PWD': '/builds/slave/test', 11:38:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:50 INFO - 'SHELL': '/bin/bash', 11:38:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mgF2aZjQMC/Listeners', 11:38:50 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 11:38:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:50 INFO - 'USER': 'cltbld', 11:38:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:50 INFO - 'XPC_FLAGS': '0x0', 11:38:50 INFO - 'XPC_SERVICE_NAME': '0', 11:38:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:50 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)) 11:38:50 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)) 11:38:50 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)) 11:38:50 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)) 11:38:50 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)) 11:38:50 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)) 11:38:50 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)) 11:38:50 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)) 11:38:50 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)) 11:38:50 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)) 11:38:50 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)) 11:38:50 INFO - Cleaning up... 11:38:50 INFO - Return code: 0 11:38:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:50 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 0x10904c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad935c6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e60d30>, '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 0x108cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e58b20>, '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.0xfwgUwmTu/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.mgF2aZjQMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 11:38:50 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 11:38:50 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 11:38:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0xfwgUwmTu/Render', 11:38:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:50 INFO - 'HOME': '/Users/cltbld', 11:38:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:50 INFO - 'LOGNAME': 'cltbld', 11:38:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:50 INFO - 'MOZ_NO_REMOTE': '1', 11:38:50 INFO - 'NO_EM_RESTART': '1', 11:38:50 INFO - 'PAGER': '/bin/cat', 11:38:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:50 INFO - 'PWD': '/builds/slave/test', 11:38:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:50 INFO - 'SHELL': '/bin/bash', 11:38:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mgF2aZjQMC/Listeners', 11:38:50 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 11:38:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:50 INFO - 'USER': 'cltbld', 11:38:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:50 INFO - 'XPC_FLAGS': '0x0', 11:38:50 INFO - 'XPC_SERVICE_NAME': '0', 11:38:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:38:51 INFO - Cleaning up... 11:38:51 INFO - Return code: 0 11:38:51 INFO - Running post-action listener: _resource_record_post_action 11:38:51 INFO - Running post-action listener: _start_resource_monitoring 11:38:51 INFO - Starting resource monitoring. 11:38:51 INFO - [mozharness: 2016-12-21 19:38:51.223738Z] Finished create-virtualenv step (success) 11:38:51 INFO - [mozharness: 2016-12-21 19:38:51.224694Z] Running install step. 11:38:51 INFO - Running pre-action listener: _resource_record_pre_action 11:38:51 INFO - Running main action method: install 11:38:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:38:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:38:51 INFO - Reading from file tmpfile_stdout 11:38:51 INFO - Detecting whether we're running mozinstall >=1.0... 11:38:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:38:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:38:51 INFO - Reading from file tmpfile_stdout 11:38:51 INFO - Output received: 11:38:51 INFO - Usage: mozinstall [options] installer 11:38:51 INFO - Options: 11:38:51 INFO - -h, --help show this help message and exit 11:38:51 INFO - -d DEST, --destination=DEST 11:38:51 INFO - Directory to install application into. [default: 11:38:51 INFO - "/builds/slave/test"] 11:38:51 INFO - --app=APP Application being installed. [default: firefox] 11:38:51 INFO - mkdir: /builds/slave/test/build/application 11:38:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:38:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 11:39:22 INFO - Reading from file tmpfile_stdout 11:39:22 INFO - Output received: 11:39:22 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:39:22 INFO - Running post-action listener: _resource_record_post_action 11:39:22 INFO - [mozharness: 2016-12-21 19:39:22.930913Z] Finished install step (success) 11:39:22 INFO - [mozharness: 2016-12-21 19:39:22.931059Z] Running run-tests step. 11:39:22 INFO - Running pre-action listener: _resource_record_pre_action 11:39:22 INFO - Running main action method: run_tests 11:39:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:39:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:39:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:39:22 INFO - 2016-12-21 11:39:22.944 screenresolution[1625:7428] starting screenresolution argv=screenresolution get 11:39:22 INFO - 2016-12-21 11:39:22.947 screenresolution[1625:7428] Display 0: 1600x1200x32@60 11:39:22 INFO - 2016-12-21 11:39:22.952 screenresolution[1626:7430] starting screenresolution argv=screenresolution list 11:39:22 INFO - Available Modes on Display 0 11:39:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:39:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:39:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:39:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:39:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:39:23 INFO - Intel Iris: 11:39:23 INFO - Chipset Model: Intel Iris 11:39:23 INFO - Type: GPU 11:39:23 INFO - Bus: Built-In 11:39:23 INFO - VRAM (Dynamic, Max): 1536 MB 11:39:23 INFO - Vendor: Intel (0x8086) 11:39:23 INFO - Device ID: 0x0a2e 11:39:23 INFO - Revision ID: 0x0009 11:39:23 INFO - Displays: 11:39:23 INFO - Display: 11:39:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:39:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:39:23 INFO - Display Serial Number: 1600x1200 60 11:39:23 INFO - Main Display: Yes 11:39:23 INFO - Mirror: Off 11:39:23 INFO - Online: Yes 11:39:23 INFO - Rotation: Supported 11:39:23 INFO - Return code: 0 11:39:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:39:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:39:23 INFO - Python 2.7.3 11:39:23 INFO - Return code: 0 11:39:23 INFO - grabbing minidump binary from tooltool 11:39:23 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')]} 11:39:23 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 0x108e60d30>, '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 0x108cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e58b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:39:23 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 11:39:23 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 11:39:23 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 11:39:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:39:23 INFO - Return code: 0 11:39:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:39:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:39:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:39:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:39:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:39:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0287', '--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 11:39:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0287 --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 11:39:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0xfwgUwmTu/Render', 11:39:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:23 INFO - 'HOME': '/Users/cltbld', 11:39:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:23 INFO - 'LOGNAME': 'cltbld', 11:39:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:39:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:23 INFO - 'MOZ_NO_REMOTE': '1', 11:39:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:39:23 INFO - 'NO_EM_RESTART': '1', 11:39:23 INFO - 'PAGER': '/bin/cat', 11:39:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:23 INFO - 'PWD': '/builds/slave/test', 11:39:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:39:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:23 INFO - 'SHELL': '/bin/bash', 11:39:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mgF2aZjQMC/Listeners', 11:39:23 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 11:39:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:23 INFO - 'USER': 'cltbld', 11:39:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:23 INFO - 'XPC_FLAGS': '0x0', 11:39:23 INFO - 'XPC_SERVICE_NAME': '0', 11:39:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0287', '--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 11:39:23 INFO - mozversion application_buildid: 20161221092053 11:39:23 INFO - mozversion application_changeset: c3f401e19160ed979541940f21ff70a111cc5972 11:39:23 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:39:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:39:23 INFO - mozversion application_name: Firefox 11:39:23 INFO - mozversion application_remotingname: firefox-dev 11:39:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:39:23 INFO - mozversion application_vendor: Mozilla 11:39:23 INFO - mozversion application_version: 52.0a2 11:39:23 INFO - mozversion platform_buildid: 20161221092053 11:39:23 INFO - mozversion platform_changeset: c3f401e19160ed979541940f21ff70a111cc5972 11:39:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:39:23 INFO - mozversion platform_version: 52.0a2 11:39:23 INFO - using testdate: 1482349163 11:39:23 INFO - actual date: 1482349163 11:39:23 INFO - starting webserver on 'localhost:49249' 11:39:23 INFO - SUITE-START | Running 2 tests 11:39:23 INFO - TEST-START | dromaeo_css 11:39:23 INFO - operating with platform_type : mac_ 11:39:23 INFO - Initialising browser for dromaeo_css test... 11:39:23 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/tmpwVFvaQ/profile http://localhost:49249/getInfo.html) 11:39:26 INFO - PROCESS | 1636 | 2016-12-21 11:39:26.573 plugin-container[1637:7598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8637, name = 'com.apple.tsm.portname' 11:39:26 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:39:26 INFO - PROCESS | 1636 | 2016-12-21 11:39:26.575 plugin-container[1637:7598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863b, name = 'com.apple.CFPasteboardClient' 11:39:26 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:39:26 INFO - PROCESS | 1636 | 2016-12-21 11:39:26.576 plugin-container[1637:7598] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:39:26 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 11:39:26 INFO - PROCESS | 1636 | colorDepth:24 11:39:26 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 11:39:26 INFO - PROCESS | 1636 | __metrics 11:39:26 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 11:39:27 INFO - TEST-INFO | 1636: exit 0 11:39:27 INFO - Browser initialized. 11:39:27 INFO - Running cycle 1/1 for dromaeo_css test... 11:39:27 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/tmpwVFvaQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:39:30 INFO - PROCESS | 1638 | 2016-12-21 11:39:30.292 plugin-container[1639:7731] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8647, name = 'com.apple.tsm.portname' 11:39:30 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:39:30 INFO - PROCESS | 1638 | 2016-12-21 11:39:30.292 plugin-container[1639:7731] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c17, name = 'com.apple.CFPasteboardClient' 11:39:30 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:39:30 INFO - PROCESS | 1638 | 2016-12-21 11:39:30.293 plugin-container[1639:7731] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:39:39 INFO - PROCESS | 1638 | RSS: Main: 229306368 11:39:39 INFO - PROCESS | 1638 | 11:40:29 INFO - PROCESS | 1638 | 1482349229562 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] 11:42:43 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 11:42:45 INFO - PROCESS | 1638 | RSS: Main: 234192896 11:42:45 INFO - PROCESS | 1638 | 11:45:47 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 11:45:48 INFO - PROCESS | 1638 | RSS: Main: 243499008 11:45:48 INFO - PROCESS | 1638 | 11:48:50 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 11:48:51 INFO - PROCESS | 1638 | RSS: Main: 246317056 11:48:51 INFO - PROCESS | 1638 | 11:51:53 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 11:51:53 INFO - PROCESS | 1638 | RSS: Main: 245542912 11:51:53 INFO - PROCESS | 1638 | 11:54:56 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 11:54:57 INFO - PROCESS | 1638 | RSS: Main: 249032704 11:54:57 INFO - PROCESS | 1638 | 11:57:59 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 11:57:59 INFO - PROCESS | 1638 | RSS: Main: 250687488 11:57:59 INFO - PROCESS | 1638 | 11:57:59 INFO - PROCESS | 1638 | __start_tp_report 11:57:59 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 11:57:59 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 11:57:59 INFO - PROCESS | 1638 | |i|pagename|runs| 11:57:59 INFO - PROCESS | 1638 | |0;dojo.html;2790.539560972439;2858.8570571471423;2886.5525843494265;3441.1741182116284;3458.5158077869105;4343.804528796204;5674.829755107348;5696.800611978581;5739.741711622976;5742;5702.600817942745;5712.571557133211;5712.914306285409;5720.313562372521;5751.309842818857;4326.480822301321;5250.159974404096;5277.419483856773;5281.339832520935;5318.840434786963;5261.684298942057;5277.340332458442;5296.205569164617;5297.284866543006;5367.087595108841;4363.672724545656;5149.098907691157;5155.974220128895;5281.207818827187;5294.788208471657;4290.356446533014;5191.402988656315;5212.192110222903;5219.295395121667;5247.47525247476;5108.310378098946;5177.767000484987;5181.766820493087;5182.766775495112;5253.605979551549;5717.456841600041;5754.539636829044;5760.308762948431;5784.855378615526;5813;6022.638641681513;6085.330613632497;6088.878222435571;6124.571280010401;6149.877002459971;6140.9030504681;6152.3540028297275;6153.938460615426;6155.630662160284;6193.907091393633;5042.193249080129;5486.506214440719;5755.654660720328;5760.74076666551;5789.102689083199;6245.094461303176;6253.906191407133;6269.185005949289;6303.873922521524;6317.842053948596;5857.414258574194;5913.201717768132;5984.162217289583;5999.5800293979;6005.6396616203165;5352.57179425645;6188.040853667689;6197.442230199304;6204.81385558434;6233.283172435228;6000.759969601254;6083.148359229712;6106.45041946227;6112.17485639433;6116.510679145657;6061.6666083365835;6114.297368146176;6123.160610921289;6135.877282454326;6137.570370074052;4806.918443350218;4857.368542089505;4879;4886.731229782396;4940.975295123501;4361.563843615677;4369.80335884886;4389.056352884081;4390.53899340571;4392.363107349416;4716.834910778106;5814.767409303665;5939.881202375928;6036.124761909498;6067;6121.026213050743;6167;6180.752769889244;6221.751129954751;6278.937210627927;5863.7947671832135;5999.310079340845;6031.306399764083;6062.393760623902;6070.089486576961;5297.735113244323;5324.5740340772645;5328.307320048369;5394.865128371744;5396.379416367168;5761.596688231783;6216.533759968111;6226.066090086523;6511.934880651228;6544.3782840630065;6132.20254427538;6136.73353236607;6178.289496707936;6180.072989051499;6227.501799856092;6459.61242325462;6467.15926929515;6469.191351081115;6480.384363485461;6512.5766825156215;4672.901868060935;6160.599561028519;6166.660833654192;6169.660668663087;6176.289726681489;5873.471387575139;6108.311716707834;6135.11040899076;6152.107944348134;6170.352113028068;6002.519798416029;6057.091436284416;6070.878582428415;6099.84750381244;6128.1726966858905;6062.78780242707;6070.998285282879;6093.29927058394;6104.969475152773;6122.418370254819;5806.303243610793;5986.820395388146;5990.88018239624;6002.789902353396;6009.90985135232;5856.970715146397;5888.705564721832;5914.674692891932;5964.135200396005;5992.340842507234;5732.656040637575;5738.139279108;5759.078547432391;5760.33756118035;5764.625299355528 11:57:59 INFO - PROCESS | 1638 | |1;ext.html;498.31730529175036;515.301766106925;523.5079025715827;558.7401858135966;560.6523955147809;36610.63389366109;39181.8040909795;39218;39303.41044884322;39316.803415982955;38009.23981520375;39372;39398.606013939854;39398.803005985;39513;9199.218066464358;9243.53782310885;9383.859242111374;9401.388909720847;9421.38760980534;9290.1174388433;9334.393264437824;9397.76505587359;9403.95298023509;9435.669751558697;9400.10698983599;9412.905870941271;9429.05709429055;9450.291228157881;9460.38507497014;9340.626374944994;9393.90606093944;9433.292503062297;9435.622575096988;9445.527723613792;7532.8116797079965;8365.82878397025;9291.256699464027;9293.349465537414;9391.812163756755;31151.37697246065;33916.321673566636;35492.64507354919;38497.422538661944;38545.03637409059;3271.607407111138;3481.1123163593375;3488.8255587220538;3611.3860643690637;3725.739198256123;27853.58219626707;29303.853480732672;29585.26036849096;29928.70071299281;30215.546766798296;2692.892284308625;3145.1036454610326;3191.4734068878606;3198.6801319867827;3211.309568442796;347555;349596;351177;351926;352304.4769552297;54961.450385495635;56007;56516.43483565112;56570;56602;58953.41046589565;59726.402735972086;59797.40202598005;59866;59941.80116397647;15593.688126237412;15707.921460392854;15828;15906.52280431589;15925.840741592667;12189.695257618454;12255.142140050111;12347.38263086842;12369.93815030919;12375.762423757733;14490.203038832755;15098.849011509965;15193.544193674208;15202.923985380003;15355.92322038405;3239.319742854027;6603.8349041273395;6783.82917466406;6840.794776156723;6885.896711549331;870.4472659861034;897.1387468030764;959.1415682963708;988.614440368257;1046.3617193511952;28612.427751444855;29231;29436.116916492112;29613.259668508028;30062.699373006428;738.5863916206941;954.8064918851436;956.2732323434101;1033.2921948465175;1129.971750706239;10371.896281037092;12229.147818437164;14287.78568321476;14498.84009279255;15000.699986000438;596798;597711;598677;598731;602853;52332.73833630884;52370.476295236556;52697;52828.73585632124;52933;1371.9588412347646;1372.512757970906;1399.5731301952799;1400.516821696513;1407.0291498865954;26947;27127.321816954733;28191;28536.286592835346;28581.14256572306;48328.758356207996;48974;49126.75436622794;49252.27617033387;49362.25956610582;549712;555143;562469;563490;563576;25194.118205862706;25430.491390171723;25479.23562293134;26189.73810261873;26869.328266793487;44325.11349773051;44385.77807110944;44829;45306;45703.31445028261;36955.07612309714;37193.442098368;37918.81040594779;38392.04019899413;38863.41704874486;37212.88361349164;37404;37487.43768843524;37639.43540846944;38142;31820.363592728478;36127.27745445024;36684.24868134954;37618.811905940296;37623.623763762014;28556.57165142483;33184.502232466046;33744.66255337415;33809.985700429024;35147.47278790769;20088.799112009277;22285.331440056823;22963.196288129835;24363.38266806185;24935.50128997446 11:57:59 INFO - PROCESS | 1638 | |2;jquery.html;350.5215381004928;363.3568583607014;367.3351234265979;385.0835012669845;391.44219487230725;18903;19194.94427806472;19248.903755481202;19758.60482790345;20071.799282007174;19058.2376704932;19553.315633952814;19556.706649400272;19650.70523942142;19763;5554.944450555493;5573.665580065189;5829.854253643651;5834.833033393317;5840.883182336351;4905.632077594177;5744.569157313197;5754.568407369443;5756.366799652035;5766.192733017381;5138.3577052868395;5490.478404551562;5519;5576;5710.314762228539;5570.248016517779;5660.235868157787;5688.061469857468;5702.486776190142;5732.828015159531;4933.703977761345;4954.142196676246;5004.649674522785;5006.799728010875;5018.971110922253;24008.159714410085;24034;24181.15365962183;24246.878765606234;24441.388965275833;2322.6632138340015;2358.551875143717;2677.799165062628;2796.9511433212547;2902.477554040272;14558.636034099232;14609.78085328721;14688.559343219613;15138.243087845674;15356.308966096552;1860.265195247883;1903.6858918278467;1905.1712505060343;1933.3909818407158;1939.8933058681766;116833;121335;122223;122602;125598.11602826056;10277.9486102569;10416.531256093494;10701.571937122428;10781.353118812896;10946.726331841608;13253.602391928258;13283.800742988862;13307;13313.068085234134;13326.33368331599;7296.635168241567;8279.50322980623;8288.419810613199;8385.580720963928;8390.202930721573;8272.669093236203;8282.58587070644;8284.668613255522;8449.408541402167;8450.746477605682;6219.191505104398;6435;6439.420452159328;6456.22525296958;6563.540552161398;1537.7847101405812;1736.7047601907707;1854.6661600911966;1875.90620468976;1904.5048287445363;847.0978407995465;856.8886044814134;943.3396622364372;944.891337496183;972.3485264872552;18899.527511812314;20714.48213794667;20718.378448646567;20734.377968660963;20821.271255505984;857.6842525182034;904.1546154345708;916.935814492979;920.2821798996794;1069.6790962711311;8731.51976641278;10284.434356110485;10938.124950003981;10978.451077446096;11026.614068507564;560281;603523;614378;619263;632670;61247.69376153091;70980.29019709736;78533.60733196298;111542.44228778804;112563.3115503268;737.7012310014453;971.5482300730195;982.8427451607708;984.0553069053789;984.8670429491926;14721.33753981095;17785.733214001542;17980.820191797913;18253.908730456264;18280.634387312446;52998;53109.734451327495;54697.72651136719;54907.45092549024;55689.72155139199;70805;71038.28961710524;75013;82916.17083829326;84820;7704.576248306173;7759.650815713194;7827.530348179127;8013.519188848688;8203.753887383387;10760.946195268974;10961.68459784799;12317.630471085882;12407.751844963232;12426.62720118398;8939.821203575762;10128.03783640553;10694.572217111228;10772.461376931278;11068.225224234226;9731.880833704216;9748;10562.366258024544;10710.464476776286;10881.510332034919;9101.635934562544;10928.562857485611;10976.957189067025;11055;11264.94367528157;7775.339096176662;7801.726939557316;8040.2361775631225;8047.356211503183;8090.352771778362;9118.544072796201;9460.385074970105;10543.736406589896;10886.40124793144;11076.944615276872 11:57:59 INFO - PROCESS | 1638 | |3;mootools.html;1467.7211329847325;1505.9548213553592;1685.1237356574575;1805.9729104063433;1816.3188804198426;7464.776056718293;7719.768406947801;8037.035555733306;8496.745097647074;8537.4450660707;7894.802629934255;8220.588970551464;8223.876641850376;8253.628586713598;8290.502569845798;2028.2191356327774;2073.8859362735043;2081.16753298681;2083.322920050982;2086.9669513590793;2251.4934139818574;2283.6117859963847;2315.4674424882205;2327.045911176418;2345.331580499558;2302.7121609798774;2306.2274138163734;2311.1217737259817;2335.4278201840575;2336.532693461306;2310.4570425949887;2313.2828823064906;2329.161501859338;2331.1258278145697;2346.6480027995854;2323.117215458132;2342.3090188394463;2342.941426464335;2343.6718859359703;2346.4016675747575;6955.860882782366;7099.325564071405;8307.335413166993;8330.291925186366;8358.122397148334;4664.043871006435;4683.437987441494;4818.795301174707;5215.478452154792;5233.607479439057;2940.4562604632524;3313.3042061167184;3524.3832329342517;3534;3555.2000799820094;970.9029097090305;982.7371827202082;1072.9935021509827;1085.5549224817787;1095.7863216672752;10464.947675261576;10752.247342686014;10756.085732712729;10773.35359878402;10937.125029997502;4173.8747837564315;4333.93818514466;4621.1219868224925;4743.454502732161;4751.40607424072;9454.85817712735;9454.85817712735;9461.290403219786;9469.384490008266;9471.810563788686;5160.354955630546;5202.3497062867145;5208.609354298443;5246.344206974129;5253.343332083489;4156.9792151039655;4690.906181876343;4695.5539223773685;4794.161021821167;4810.11012962603;3825.8087095645105;3841.846326146923;3894.82473288697;3895.9220815584094;3916.5691773904846;1098.3519723363254;1261.2873726344585;1275.1647670775658;1288.1884412819863;1301.6160232731368;658.3482352471019;864.828157846122;892.9330300227509;900.2033200617501;903.7785742493131;4762.904741905212;5503.587230957694;5535.58483113768;5620.662760234399;5622.156676498558;433.754928465416;434.2834323366471;434.611023134293;442.1665161171151;462.89353448706987;3747.494088298045;3764.153065560282;3781.773093614392;3800.239952009609;3850.4416859555768;9775.853362199572;10104.494775261355;10132.24008199388;10137.391756494633;10181.54183061764;10063.496825158712;10071.848922266172;10083;10085.49572521371;10093.293469457067;6188.566800324024;7646;7688.692452301845;7813.765587032398;7827.45207835446;5409.0263752524925;5489.972550137224;5510.283663123768;5611.803586874603;5651.01107306228;9176;9182.449053056838;9185.586648600962;9247.260219182315;9292.72121836346;8886.333524985523;8983.91016089857;9229.723108306762;9319.720408387759;9480.715578532656;6533.314002029719;6718.93281067183;6892.724291028303;6978.511504194657;7201.819954501179;7744.612769361622;7902.170272121344;7921.841563168819;7924.960375198087;7931.881021784793;6933.826654333682;7144.428445724434;7163.997040414242;7180.281971802778;7183.712651494091;5842.912356314574;6873.793786186422;7034.050403195503;7039.894401583878;7149.034880291302;6471.611703297817;6578.375054369827;6672.799816005528;6707.731690732511;6774.254831968578;3673.971288039353;4036.4550785643564;4750.5249475052215;4799.184138696361;4830.347903033045;3710.9628903711696;4069.7558146511305;4780.163471392423;4795.856124316276;4906.386701662293 11:57:59 INFO - PROCESS | 1638 | |4;prototype.html;275.74218106070816;309.16217051789647;311.9173419043953;312.25059856344785;318.87404475232285;4000.5399379071414;4666.486686464486;4756.334113224151;5318.388385335687;5360.266264205254;4206.8737937861915;5202.245674377213;5271.472852714726;5295.443978382272;5331.973340133294;4082.4692789937267;4088.97775556111;4110.732802367851;4128.298189307825;4139.586041395852;3291.358185153896;3657.158853463705;3903.063264816438;4067.7355971861884;4119.361498967664;3769.4157405602177;3955.0507878109192;3990.3415936370466;4022.2960981828064;4028.4964379452567;3712.461693054492;3732.7200459965475;3993.9600603993877;4017.8392864285393;4034.6570541503716;3357.9832100839585;3499.6500349965054;3509.806665733637;3525.894223173283;3550.5323799959037;4346.37044588026;4770.570648641639;5004.724740139291;5111.923321150186;5177.886754347795;4236.766977816219;4274.679399045084;4570.7029043112425;4571.9542804571865;4578.855286178456;4691.953080469186;4887.560119589254;5278.709670968095;5549.060167281235;5564.805231816867;3368.8315584220936;3560.8219589020446;3780.716446266541;3798.506194194737;3826.521684789401;5552.3892371839465;5606.887862242732;5836.824895253149;5845.678487683176;5861.589688721791;3857.228554289153;3885.747426417274;3886.6696330812197;3911.5501717302304;3944.3886197639413;3448.275862068994;3636.0364503406618;3836.3478208704587;3917.040325120364;3956.643902048829;4411.536788637211;4519.683622146418;4549.317602359606;4550.795214215368;4613.331066995334;4584.014436896016;4883.58489528387;5580;5580.298009107261;5684.459976302244;1214.732758793064;1228.0482625964771;1241.2924632959307;1262.2237324045645;1274.089026346167;837.2088376484164;856.5075081827974;873.5019441657588;880.5729221327651;890.1587999340691;4287.18440587877;4298.398224248608;4402.933955990663;5143.485651434901;5183.688978661293;4858.58702010326;4955.603551715926;5397.541208997279;5411.648242864201;5425.701586412706;4598.126355992351;4652.837150699709;5035.521625445577;5075.5178258065425;5116.897662046738;2767.681716602576;2804.78964077695;2825.816321939067;2833.0084470435595;2907.636545431821;4999.925001124986;5345.919811202835;5444.8094316698725;5893.2928048633585;5907.822765317047;5077.441481437039;5318.973405133027;5367.409584945653;5569.749361278752;5640.210370548127;5239.423663396947;5470.854258801205;5535.169724541351;5847.239858818326;5868.65253898444;4678.836240731558;5404.7567859445635;5529.778808847601;5531.391546929915;5546.500814926676;4336.37122617225;5355;5426.484483974016;5775.5090817281;5815.423514538444;5100.744962751922;6161.691915404302;6178.66017369049;6179.567430279837;6194.225721784777;3315.668433156665;3316;3913.473745239302;3920.5883382245593;3957.9313585331224;4591.910680640367;5506.91739623898;5627.649364152656;5750.597458177887;5848.853778655568;4749.501302363202;5182.766775495036;5453.7545810439415;5553.278073850373;5663.122216056518;4781.976090119527;5407.918881216706;5629.352624448241;5690.886182276414;5718.885622287614;4510.774461276988;4604.240300350405;5518.020269054826;5523.978064058162;5578.2748242728185;4895.996320754166;5589.273394458694;5623.606347555796;5638.718064096696;5672.631278966854;4201.873943781691;5315.149576067811;5523.944760552343;5527.439862113692;5571.888562228652 11:57:59 INFO - PROCESS | 1638 | |5;yui.html;624.6345887655722;687.0861753867357;763.1147868472573;764.2510339866931;792.857285688576;14205.71588568228;14750.704985900302;14953.401863925457;14964;15029.32368043438;13150.07949443537;13161.670958226025;13167.539136130237;13224;13315.400806963686;640.4620119099956;658.3646780856473;785.0540099180503;811.4360519438992;827.7185756842657;809.3646487507308;817.5912043978011;825.2201669422399;830.016430529821;831.9916800832005;826.4338927834452;826.9090400055989;852.9061803201643;880.6829541365104;883.0286684646901;848.8132610825609;859.3597769661579;872.2542226396434;873.3362644390309;875.5228400521731;620.0667994668041;632.3170975346615;649.1009951217551;662.2979641579941;663.8937769956832;13689.383977721027;14074.507392241325;14295.857041429557;14320.42718291267;14546;11612.709682257926;13479.258640774753;13555.59333220005;13738.793918091236;13977.602239776043;10949.671509854717;10968.451577421098;11159.741791074628;11381.430928453545;11498.310101393943;3606.1165014571598;3692.316921369555;3692.833822477968;3706.258748250361;3791.2227993261317;52927.735361323335;64310.67844660793;80255;84660.73008904871;86120;7922.881156782653;9224.454741551484;10210.285280030475;10333.276670632984;10744.086752625957;20843.37469875906;20972.37082887516;20985.370438886857;21001.78998210029;21039.8948005259;7475.8504829903095;7565.924340756632;7632.961835190899;7638.732644357421;7789.33790627803;4146.295129827937;4174.436451079066;4269.845516612608;4344.109457561192;4354.542773008852;2765.7787377010195;3456.1532424555644;3489.389356862539;3490.3891818931584;3520.3663340598937;612;635.7615894039735;647.9190101237346;651.6448535548043;660.5079215984123;610.377415036669;640.7501074580917;653.4413076819346;654.6432194454034;688.3185646210216;7872.409569282212;8315.750527484186;9168.862467063;9217.400868943498;9240.260779137652;638.2851206648584;657.3360905485495;658.0425480925243;661.9966900165468;664.5945972956492;4284.785760711952;4474.574915383034;4485.686001979895;4487;4515.864524064284;79245;100644.49677751564;108062.45968770263;119422;121556;53108.734456327475;55092.724536377864;57316.42683573111;103679.92640147306;104625;59726;75360.62319688367;79174.20825791784;79299;80945.19054809495;7796.454248202572;9740.172085372822;9845.753856153367;9908.455034973145;9923.00769923002;48696.02607947892;49449;50566.49433505618;50693.23960140527;52204.216936746736;51638.48361516487;52510;58414.12378814406;65098.02352964804;66657.0001449969;11983.580574679845;12000.879991200325;12041.277523348626;12329.753404932031;12463.813042804546;11250.549978001116;13325.66685832862;13625.727485450434;13884.791728124288;14053.437862485795;12032.09759268041;12985.675358116123;13004.609861704164;13167.341632918124;13467.730645387233;12354.691132721395;13103.344832758514;13158.473661053833;13568.86431135676;13598.796018059935;13001;13029.283389413295;13411.865881341451;13441.260730659907;13457;8807.51558664254;10427.68716938493;10866.315721063247;11076;11407.25852819564;9913.405195688281;10433;11015.669529914116;11027.779444411228;11315.717107072389 11:57:59 INFO - PROCESS | 1638 | __end_tp_report 11:57:59 INFO - PROCESS | 1638 | __start_cc_report 11:57:59 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,369 11:57:59 INFO - PROCESS | 1638 | __end_cc_report 11:57:59 INFO - PROCESS | 1638 | __startTimestamp1482350279886__endTimestamp 11:57:59 INFO - PROCESS | 1638 | 11:57:59 INFO - PROCESS | 1638 | ------- Summary: start ------- 11:57:59 INFO - PROCESS | 1638 | Number of tests: 6 11:57:59 INFO - PROCESS | 1638 | 11:57:59 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5670.33 Median:5914.67 stddev:700.52 (11.8%) stddev-sans-first:665.24 11:57:59 INFO - PROCESS | 1638 | Values: 2790.5 2858.9 2886.6 3441.2 3458.5 4343.8 5674.8 5696.8 5739.7 5742.0 5702.6 5712.6 5712.9 5720.3 5751.3 4326.5 5250.2 5277.4 5281.3 5318.8 5261.7 5277.3 5296.2 5297.3 5367.1 4363.7 5149.1 5156.0 5281.2 5294.8 4290.4 5191.4 5212.2 5219.3 5247.5 5108.3 5177.8 5181.8 5182.8 5253.6 5717.5 5754.5 5760.3 5784.9 5813.0 6022.6 6085.3 6088.9 6124.6 6149.9 6140.9 6152.4 6153.9 6155.6 6193.9 5042.2 5486.5 5755.7 5760.7 5789.1 6245.1 6253.9 6269.2 6303.9 6317.8 5857.4 5913.2 5984.2 5999.6 6005.6 5352.6 6188.0 6197.4 6204.8 6233.3 6000.8 6083.1 6106.5 6112.2 6116.5 6061.7 6114.3 6123.2 6135.9 6137.6 4806.9 4857.4 4879.0 4886.7 4941.0 4361.6 4369.8 4389.1 4390.5 4392.4 4716.8 5814.8 5939.9 6036.1 6067.0 6121.0 6167.0 6180.8 6221.8 6278.9 5863.8 5999.3 6031.3 6062.4 6070.1 5297.7 5324.6 5328.3 5394.9 5396.4 5761.6 6216.5 6226.1 6511.9 6544.4 6132.2 6136.7 6178.3 6180.1 6227.5 6459.6 6467.2 6469.2 6480.4 6512.6 4672.9 6160.6 6166.7 6169.7 6176.3 5873.5 6108.3 6135.1 6152.1 6170.4 6002.5 6057.1 6070.9 6099.8 6128.2 6062.8 6071.0 6093.3 6105.0 6122.4 5806.3 5986.8 5990.9 6002.8 6009.9 5857.0 5888.7 5914.7 5964.1 5992.3 5732.7 5738.1 5759.1 5760.3 5764.6 11:57:59 INFO - PROCESS | 1638 | 11:57:59 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:63418.13 Median:38075.62 stddev:137580.67 (361.3%) stddev-sans-first:137885.52 11:57:59 INFO - PROCESS | 1638 | Values: 498.3 515.3 523.5 558.7 560.7 36610.6 39181.8 39218.0 39303.4 39316.8 38009.2 39372.0 39398.6 39398.8 39513.0 9199.2 9243.5 9383.9 9401.4 9421.4 9290.1 9334.4 9397.8 9404.0 9435.7 9400.1 9412.9 9429.1 9450.3 9460.4 9340.6 9393.9 9433.3 9435.6 9445.5 7532.8 8365.8 9291.3 9293.3 9391.8 31151.4 33916.3 35492.6 38497.4 38545.0 3271.6 3481.1 3488.8 3611.4 3725.7 27853.6 29303.9 29585.3 29928.7 30215.5 2692.9 3145.1 3191.5 3198.7 3211.3 347555.0 349596.0 351177.0 351926.0 352304.5 54961.5 56007.0 56516.4 56570.0 56602.0 58953.4 59726.4 59797.4 59866.0 59941.8 15593.7 15707.9 15828.0 15906.5 15925.8 12189.7 12255.1 12347.4 12369.9 12375.8 14490.2 15098.8 15193.5 15202.9 15355.9 3239.3 6603.8 6783.8 6840.8 6885.9 870.4 897.1 959.1 988.6 1046.4 28612.4 29231.0 29436.1 29613.3 30062.7 738.6 954.8 956.3 1033.3 1130.0 10371.9 12229.1 14287.8 14498.8 15000.7 596798.0 597711.0 598677.0 598731.0 602853.0 52332.7 52370.5 52697.0 52828.7 52933.0 1372.0 1372.5 1399.6 1400.5 1407.0 26947.0 27127.3 28191.0 28536.3 28581.1 48328.8 48974.0 49126.8 49252.3 49362.3 549712.0 555143.0 562469.0 563490.0 563576.0 25194.1 25430.5 25479.2 26189.7 26869.3 44325.1 44385.8 44829.0 45306.0 45703.3 36955.1 37193.4 37918.8 38392.0 38863.4 37212.9 37404.0 37487.4 37639.4 38142.0 31820.4 36127.3 36684.2 37618.8 37623.6 28556.6 33184.5 33744.7 33810.0 35147.5 20088.8 22285.3 22963.2 24363.4 24935.5 11:57:59 INFO - PROCESS | 1638 | 11:57:59 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:34027.12 Median:2648.54 stddev:100509.22 (3794.9%) stddev-sans-first:100759.36 11:57:59 INFO - PROCESS | 1638 | Values: 350.5 363.4 367.3 385.1 391.4 18903.0 19194.9 19248.9 19758.6 20071.8 19058.2 19553.3 19556.7 19650.7 19763.0 5554.9 5573.7 5829.9 5834.8 5840.9 4905.6 5744.6 5754.6 5756.4 5766.2 5138.4 5490.5 5519.0 5576.0 5710.3 5570.2 5660.2 5688.1 5702.5 5732.8 4933.7 4954.1 5004.6 5006.8 5019.0 24008.2 24034.0 24181.2 24246.9 24441.4 2322.7 2358.6 2677.8 2797.0 2902.5 14558.6 14609.8 14688.6 15138.2 15356.3 1860.3 1903.7 1905.2 1933.4 1939.9 116833.0 121335.0 122223.0 122602.0 125598.1 10277.9 10416.5 10701.6 10781.4 10946.7 13253.6 13283.8 13307.0 13313.1 13326.3 7296.6 8279.5 8288.4 8385.6 8390.2 8272.7 8282.6 8284.7 8449.4 8450.7 6219.2 6435.0 6439.4 6456.2 6563.5 1537.8 1736.7 1854.7 1875.9 1904.5 847.1 856.9 943.3 944.9 972.3 18899.5 20714.5 20718.4 20734.4 20821.3 857.7 904.2 916.9 920.3 1069.7 8731.5 10284.4 10938.1 10978.5 11026.6 560281.0 603523.0 614378.0 619263.0 632670.0 61247.7 70980.3 78533.6 111542.4 112563.3 737.7 971.5 982.8 984.1 984.9 14721.3 17785.7 17980.8 18253.9 18280.6 52998.0 53109.7 54697.7 54907.5 55689.7 70805.0 71038.3 75013.0 82916.2 84820.0 7704.6 7759.7 7827.5 8013.5 8203.8 10760.9 10961.7 12317.6 12407.8 12426.6 8939.8 10128.0 10694.6 10772.5 11068.2 9731.9 9748.0 10562.4 10710.5 10881.5 9101.6 10928.6 10977.0 11055.0 11264.9 7775.3 7801.7 8040.2 8047.4 8090.4 9118.5 9460.4 10543.7 10886.4 11076.9 11:57:59 INFO - PROCESS | 1638 | 11:57:59 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5340.11 Median:4797.52 stddev:2971.85 (61.9%) stddev-sans-first:2965.94 11:57:59 INFO - PROCESS | 1638 | Values: 1467.7 1506.0 1685.1 1806.0 1816.3 7464.8 7719.8 8037.0 8496.7 8537.4 7894.8 8220.6 8223.9 8253.6 8290.5 2028.2 2073.9 2081.2 2083.3 2087.0 2251.5 2283.6 2315.5 2327.0 2345.3 2302.7 2306.2 2311.1 2335.4 2336.5 2310.5 2313.3 2329.2 2331.1 2346.6 2323.1 2342.3 2342.9 2343.7 2346.4 6955.9 7099.3 8307.3 8330.3 8358.1 4664.0 4683.4 4818.8 5215.5 5233.6 2940.5 3313.3 3524.4 3534.0 3555.2 970.9 982.7 1073.0 1085.6 1095.8 10464.9 10752.2 10756.1 10773.4 10937.1 4173.9 4333.9 4621.1 4743.5 4751.4 9454.9 9454.9 9461.3 9469.4 9471.8 5160.4 5202.3 5208.6 5246.3 5253.3 4157.0 4690.9 4695.6 4794.2 4810.1 3825.8 3841.8 3894.8 3895.9 3916.6 1098.4 1261.3 1275.2 1288.2 1301.6 658.3 864.8 892.9 900.2 903.8 4762.9 5503.6 5535.6 5620.7 5622.2 433.8 434.3 434.6 442.2 462.9 3747.5 3764.2 3781.8 3800.2 3850.4 9775.9 10104.5 10132.2 10137.4 10181.5 10063.5 10071.8 10083.0 10085.5 10093.3 6188.6 7646.0 7688.7 7813.8 7827.5 5409.0 5490.0 5510.3 5611.8 5651.0 9176.0 9182.4 9185.6 9247.3 9292.7 8886.3 8983.9 9229.7 9319.7 9480.7 6533.3 6718.9 6892.7 6978.5 7201.8 7744.6 7902.2 7921.8 7925.0 7931.9 6933.8 7144.4 7164.0 7180.3 7183.7 5842.9 6873.8 7034.1 7039.9 7149.0 6471.6 6578.4 6672.8 6707.7 6774.3 3674.0 4036.5 4750.5 4799.2 4830.3 3711.0 4069.8 4780.2 4795.9 4906.4 11:57:59 INFO - PROCESS | 1638 | 11:57:59 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4429.33 Median:4820.28 stddev:1365.29 (28.3%) stddev-sans-first:1333.05 11:57:59 INFO - PROCESS | 1638 | Values: 275.7 309.2 311.9 312.3 318.9 4000.5 4666.5 4756.3 5318.4 5360.3 4206.9 5202.2 5271.5 5295.4 5332.0 4082.5 4089.0 4110.7 4128.3 4139.6 3291.4 3657.2 3903.1 4067.7 4119.4 3769.4 3955.1 3990.3 4022.3 4028.5 3712.5 3732.7 3994.0 4017.8 4034.7 3358.0 3499.7 3509.8 3525.9 3550.5 4346.4 4770.6 5004.7 5111.9 5177.9 4236.8 4274.7 4570.7 4572.0 4578.9 4692.0 4887.6 5278.7 5549.1 5564.8 3368.8 3560.8 3780.7 3798.5 3826.5 5552.4 5606.9 5836.8 5845.7 5861.6 3857.2 3885.7 3886.7 3911.6 3944.4 3448.3 3636.0 3836.3 3917.0 3956.6 4411.5 4519.7 4549.3 4550.8 4613.3 4584.0 4883.6 5580.0 5580.3 5684.5 1214.7 1228.0 1241.3 1262.2 1274.1 837.2 856.5 873.5 880.6 890.2 4287.2 4298.4 4402.9 5143.5 5183.7 4858.6 4955.6 5397.5 5411.6 5425.7 4598.1 4652.8 5035.5 5075.5 5116.9 2767.7 2804.8 2825.8 2833.0 2907.6 4999.9 5345.9 5444.8 5893.3 5907.8 5077.4 5319.0 5367.4 5569.7 5640.2 5239.4 5470.9 5535.2 5847.2 5868.7 4678.8 5404.8 5529.8 5531.4 5546.5 4336.4 5355.0 5426.5 5775.5 5815.4 5100.7 6161.7 6178.7 6179.6 6194.2 3315.7 3316.0 3913.5 3920.6 3957.9 4591.9 5506.9 5627.6 5750.6 5848.9 4749.5 5182.8 5453.8 5553.3 5663.1 4782.0 5407.9 5629.4 5690.9 5718.9 4510.8 4604.2 5518.0 5524.0 5578.3 4896.0 5589.3 5623.6 5638.7 5672.6 4201.9 5315.1 5523.9 5527.4 5571.9 11:57:59 INFO - PROCESS | 1638 | 11:57:59 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:18604.72 Median:4501.43 stddev:26543.76 (589.7%) stddev-sans-first:26583.89 11:57:59 INFO - PROCESS | 1638 | Values: 624.6 687.1 763.1 764.3 792.9 14205.7 14750.7 14953.4 14964.0 15029.3 13150.1 13161.7 13167.5 13224.0 13315.4 640.5 658.4 785.1 811.4 827.7 809.4 817.6 825.2 830.0 832.0 826.4 826.9 852.9 880.7 883.0 848.8 859.4 872.3 873.3 875.5 620.1 632.3 649.1 662.3 663.9 13689.4 14074.5 14295.9 14320.4 14546.0 11612.7 13479.3 13555.6 13738.8 13977.6 10949.7 10968.5 11159.7 11381.4 11498.3 3606.1 3692.3 3692.8 3706.3 3791.2 52927.7 64310.7 80255.0 84660.7 86120.0 7922.9 9224.5 10210.3 10333.3 10744.1 20843.4 20972.4 20985.4 21001.8 21039.9 7475.9 7565.9 7633.0 7638.7 7789.3 4146.3 4174.4 4269.8 4344.1 4354.5 2765.8 3456.2 3489.4 3490.4 3520.4 612.0 635.8 647.9 651.6 660.5 610.4 640.8 653.4 654.6 688.3 7872.4 8315.8 9168.9 9217.4 9240.3 638.3 657.3 658.0 662.0 664.6 4284.8 4474.6 4485.7 4487.0 4515.9 79245.0 100644.5 108062.5 119422.0 121556.0 53108.7 55092.7 57316.4 103679.9 104625.0 59726.0 75360.6 79174.2 79299.0 80945.2 7796.5 9740.2 9845.8 9908.5 9923.0 48696.0 49449.0 50566.5 50693.2 52204.2 51638.5 52510.0 58414.1 65098.0 66657.0 11983.6 12000.9 12041.3 12329.8 12463.8 11250.5 13325.7 13625.7 13884.8 14053.4 12032.1 12985.7 13004.6 13167.3 13467.7 12354.7 13103.3 13158.5 13568.9 13598.8 13001.0 13029.3 13411.9 13441.3 13457.0 8807.5 10427.7 10866.3 11076.0 11407.3 9913.4 10433.0 11015.7 11027.8 11315.7 11:57:59 INFO - PROCESS | 1638 | -------- Summary: end -------- 11:57:59 INFO - PROCESS | 1638 | 11:58:00 INFO - TEST-INFO | 1638: exit 0 11:58:00 INFO - TEST-OK | dromaeo_css | took 1116984ms 11:58:00 INFO - TEST-START | kraken 11:58:00 INFO - operating with platform_type : mac_ 11:58:00 INFO - Initialising browser for kraken test... 11:58:00 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/tmpCGnXWR/profile http://localhost:49249/getInfo.html) 11:58:03 INFO - PROCESS | 1649 | 2016-12-21 11:58:03.442 plugin-container[1650:10167] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 11:58:03 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:58:03 INFO - PROCESS | 1649 | 2016-12-21 11:58:03.445 plugin-container[1650:10167] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 11:58:03 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:58:03 INFO - PROCESS | 1649 | 2016-12-21 11:58:03.446 plugin-container[1650:10167] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:58:03 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 11:58:03 INFO - PROCESS | 1649 | colorDepth:24 11:58:03 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 11:58:03 INFO - PROCESS | 1649 | __metrics 11:58:03 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 11:58:04 INFO - TEST-INFO | 1649: exit 0 11:58:04 INFO - Browser initialized. 11:58:04 INFO - Running cycle 1/1 for kraken test... 11:58:04 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/tmpCGnXWR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:58:07 INFO - PROCESS | 1651 | 2016-12-21 11:58:07.391 plugin-container[1652:10284] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 11:58:07 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:58:07 INFO - PROCESS | 1651 | 2016-12-21 11:58:07.392 plugin-container[1652:10284] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x880b, name = 'com.apple.CFPasteboardClient' 11:58:07 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:58:07 INFO - PROCESS | 1651 | 2016-12-21 11:58:07.392 plugin-container[1652:10284] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:58:16 INFO - PROCESS | 1651 | RSS: Main: 229404672 11:58:16 INFO - PROCESS | 1651 | 11:58:19 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 11:58:20 INFO - PROCESS | 1651 | RSS: Main: 241618944 11:58:20 INFO - PROCESS | 1651 | 11:58:23 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 11:58:24 INFO - PROCESS | 1651 | RSS: Main: 249360384 11:58:24 INFO - PROCESS | 1651 | 11:58:27 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 11:58:27 INFO - PROCESS | 1651 | RSS: Main: 252923904 11:58:27 INFO - PROCESS | 1651 | 11:58:29 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 11:58:30 INFO - PROCESS | 1651 | RSS: Main: 253456384 11:58:30 INFO - PROCESS | 1651 | 11:58:32 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:58:33 INFO - PROCESS | 1651 | RSS: Main: 254590976 11:58:33 INFO - PROCESS | 1651 | 11:58:36 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 11:58:36 INFO - PROCESS | 1651 | RSS: Main: 258924544 11:58:36 INFO - PROCESS | 1651 | 11:58:39 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 11:58:40 INFO - PROCESS | 1651 | RSS: Main: 258166784 11:58:40 INFO - PROCESS | 1651 | 11:58:43 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 11:58:43 INFO - PROCESS | 1651 | RSS: Main: 258183168 11:58:43 INFO - PROCESS | 1651 | 11:58:45 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:58:46 INFO - PROCESS | 1651 | RSS: Main: 257236992 11:58:46 INFO - PROCESS | 1651 | 11:58:48 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:58:48 INFO - PROCESS | 1651 | RSS: Main: 257224704 11:58:48 INFO - PROCESS | 1651 | 11:58:50 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:58:51 INFO - PROCESS | 1651 | RSS: Main: 257224704 11:58:51 INFO - PROCESS | 1651 | 11:58:54 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:58:55 INFO - PROCESS | 1651 | RSS: Main: 257216512 11:58:55 INFO - PROCESS | 1651 | 11:58:58 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:58:58 INFO - PROCESS | 1651 | RSS: Main: 257568768 11:58:58 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 11:59:00 INFO - PROCESS | 1651 | RSS: Main: 259305472 11:59:00 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | __start_tp_report 11:59:00 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 11:59:00 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 11:59:00 INFO - PROCESS | 1651 | |i|pagename|runs| 11:59:00 INFO - PROCESS | 1651 | |0;ai-astar;114;119;118;119;121;148;176;97;170;176 11:59:00 INFO - PROCESS | 1651 | |1;audio-beat-detection;144;115;142;145;113;116;112;110;139;112 11:59:00 INFO - PROCESS | 1651 | |2;audio-dft;197;200;195;198;197;198;226;258;198;195 11:59:00 INFO - PROCESS | 1651 | |3;audio-fft;82;59;61;88;60;58;64;70;107;59 11:59:00 INFO - PROCESS | 1651 | |4;audio-oscillator;89;72;83;96;85;85;119;103;103;88 11:59:00 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;88;86;88;119;137;97;135;133;89;85 11:59:00 INFO - PROCESS | 1651 | |6;imaging-darkroom;152;98;99;98;98;99;100;99;98;99 11:59:00 INFO - PROCESS | 1651 | |7;imaging-desaturate;88;92;87;86;94;90;89;93;88;90 11:59:00 INFO - PROCESS | 1651 | |8;json-parse-financial;58;56;58;60;61;61;51;73;81;84 11:59:00 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;69;79;68;73;77;72;70;90;65;80 11:59:00 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;95;98;74;73;75;99;75;77;104;106 11:59:00 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;157;127;154;158;169;146;145;166;215;179 11:59:00 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;189;197;169;168;167;167;166;165;167;166 11:59:00 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;61;78;97;93;76;67;67;69;67;68 11:59:00 INFO - PROCESS | 1651 | __end_tp_report 11:59:00 INFO - PROCESS | 1651 | __start_cc_report 11:59:00 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,958 11:59:00 INFO - PROCESS | 1651 | __end_cc_report 11:59:00 INFO - PROCESS | 1651 | __startTimestamp1482350340835__endTimestamp 11:59:00 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | ------- Summary: start ------- 11:59:00 INFO - PROCESS | 1651 | Number of tests: 14 11:59:00 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:135.80 Median:159.00 stddev:29.12 (18.3%) stddev-sans-first:29.80 11:59:00 INFO - PROCESS | 1651 | Values: 114.0 119.0 118.0 119.0 121.0 148.0 176.0 97.0 170.0 176.0 11:59:00 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:127.50 stddev:15.40 (12.1%) stddev-sans-first:14.68 11:59:00 INFO - PROCESS | 1651 | Values: 144.0 115.0 142.0 145.0 113.0 116.0 112.0 110.0 139.0 112.0 11:59:00 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:206.20 Median:198.00 stddev:20.37 (10.3%) stddev-sans-first:21.34 11:59:00 INFO - PROCESS | 1651 | Values: 197.0 200.0 195.0 198.0 197.0 198.0 226.0 258.0 198.0 195.0 11:59:00 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:70.80 Median:62.50 stddev:16.44 (26.3%) stddev-sans-first:16.93 11:59:00 INFO - PROCESS | 1651 | Values: 82.0 59.0 61.0 88.0 60.0 58.0 64.0 70.0 107.0 59.0 11:59:00 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:92.30 Median:85.00 stddev:13.29 (15.6%) stddev-sans-first:14.04 11:59:00 INFO - PROCESS | 1651 | Values: 89.0 72.0 83.0 96.0 85.0 85.0 119.0 103.0 103.0 88.0 11:59:00 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:105.70 Median:87.00 stddev:22.50 (25.9%) stddev-sans-first:22.94 11:59:00 INFO - PROCESS | 1651 | Values: 88.0 86.0 88.0 119.0 137.0 97.0 135.0 133.0 89.0 85.0 11:59:00 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:104.00 Median:98.50 stddev:16.88 (17.1%) stddev-sans-first:0.71 11:59:00 INFO - PROCESS | 1651 | Values: 152.0 98.0 99.0 98.0 98.0 99.0 100.0 99.0 98.0 99.0 11:59:00 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:89.70 Median:90.00 stddev:2.63 (2.9%) stddev-sans-first:2.71 11:59:00 INFO - PROCESS | 1651 | Values: 88.0 92.0 87.0 86.0 94.0 90.0 89.0 93.0 88.0 90.0 11:59:00 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:61.00 stddev:11.10 (18.2%) stddev-sans-first:11.53 11:59:00 INFO - PROCESS | 1651 | Values: 58.0 56.0 58.0 60.0 61.0 61.0 51.0 73.0 81.0 84.0 11:59:00 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:74.30 Median:75.00 stddev:7.36 (9.8%) stddev-sans-first:7.56 11:59:00 INFO - PROCESS | 1651 | Values: 69.0 79.0 68.0 73.0 77.0 72.0 70.0 90.0 65.0 80.0 11:59:00 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:76.00 stddev:13.86 (18.2%) stddev-sans-first:14.44 11:59:00 INFO - PROCESS | 1651 | Values: 95.0 98.0 74.0 73.0 75.0 99.0 75.0 77.0 104.0 106.0 11:59:00 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:161.60 Median:162.00 stddev:23.66 (14.6%) stddev-sans-first:25.03 11:59:00 INFO - PROCESS | 1651 | Values: 157.0 127.0 154.0 158.0 169.0 146.0 145.0 166.0 215.0 179.0 11:59:00 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.10 Median:167.50 stddev:11.23 (6.7%) stddev-sans-first:10.11 11:59:00 INFO - PROCESS | 1651 | Values: 189.0 197.0 169.0 168.0 167.0 167.0 166.0 165.0 167.0 166.0 11:59:00 INFO - PROCESS | 1651 | 11:59:00 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.30 Median:72.50 stddev:11.95 (16.5%) stddev-sans-first:11.67 11:59:00 INFO - PROCESS | 1651 | Values: 61.0 78.0 97.0 93.0 76.0 67.0 67.0 69.0 67.0 68.0 11:59:00 INFO - PROCESS | 1651 | -------- Summary: end -------- 11:59:00 INFO - PROCESS | 1651 | 11:59:01 INFO - TEST-INFO | 1651: exit 0 11:59:01 INFO - TEST-OK | kraken | took 61311ms 11:59:01 INFO - SUITE-END | took 1178s 11:59:01 INFO - Completed test suite (00:19:38) 11:59:01 INFO - javascript benchmark 11:59:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7012.28969792338, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2790.539560972439, 2858.8570571471423, 2886.5525843494265, 3441.1741182116284, 3458.5158077869105, 4343.804528796204, 5674.829755107348, 5696.800611978581, 5739.741711622976, 5742.0, 5702.600817942745, 5712.571557133211, 5712.914306285409, 5720.313562372521, 5751.309842818857, 4326.480822301321, 5250.159974404096, 5277.419483856773, 5281.339832520935, 5318.840434786963, 5261.684298942057, 5277.340332458442, 5296.205569164617, 5297.284866543006, 5367.087595108841, 4363.672724545656, 5149.098907691157, 5155.974220128895, 5281.207818827187, 5294.788208471657, 4290.356446533014, 5191.402988656315, 5212.192110222903, 5219.295395121667, 5247.47525247476, 5108.310378098946, 5177.767000484987, 5181.766820493087, 5182.766775495112, 5253.605979551549, 5717.456841600041, 5754.539636829044, 5760.308762948431, 5784.855378615526, 5813.0, 6022.638641681513, 6085.330613632497, 6088.878222435571, 6124.571280010401, 6149.877002459971, 6140.9030504681, 6152.3540028297275, 6153.938460615426, 6155.630662160284, 6193.907091393633, 5042.193249080129, 5486.506214440719, 5755.654660720328, 5760.74076666551, 5789.102689083199, 6245.094461303176, 6253.906191407133, 6269.185005949289, 6303.873922521524, 6317.842053948596, 5857.414258574194, 5913.201717768132, 5984.162217289583, 5999.5800293979, 6005.6396616203165, 5352.57179425645, 6188.040853667689, 6197.442230199304, 6204.81385558434, 6233.283172435228, 6000.759969601254, 6083.148359229712, 6106.45041946227, 6112.17485639433, 6116.510679145657, 6061.6666083365835, 6114.297368146176, 6123.160610921289, 6135.877282454326, 6137.570370074052, 4806.918443350218, 4857.368542089505, 4879.0, 4886.731229782396, 4940.975295123501, 4361.563843615677, 4369.80335884886, 4389.056352884081, 4390.53899340571, 4392.363107349416, 4716.834910778106, 5814.767409303665, 5939.881202375928, 6036.124761909498, 6067.0, 6121.026213050743, 6167.0, 6180.752769889244, 6221.751129954751, 6278.937210627927, 5863.7947671832135, 5999.310079340845, 6031.306399764083, 6062.393760623902, 6070.089486576961, 5297.735113244323, 5324.5740340772645, 5328.307320048369, 5394.865128371744, 5396.379416367168, 5761.596688231783, 6216.533759968111, 6226.066090086523, 6511.934880651228, 6544.3782840630065, 6132.20254427538, 6136.73353236607, 6178.289496707936, 6180.072989051499, 6227.501799856092, 6459.61242325462, 6467.15926929515, 6469.191351081115, 6480.384363485461, 6512.5766825156215, 4672.901868060935, 6160.599561028519, 6166.660833654192, 6169.660668663087, 6176.289726681489, 5873.471387575139, 6108.311716707834, 6135.11040899076, 6152.107944348134, 6170.352113028068, 6002.519798416029, 6057.091436284416, 6070.878582428415, 6099.84750381244, 6128.1726966858905, 6062.78780242707, 6070.998285282879, 6093.29927058394, 6104.969475152773, 6122.418370254819, 5806.303243610793, 5986.820395388146, 5990.88018239624, 6002.789902353396, 6009.90985135232, 5856.970715146397, 5888.705564721832, 5914.674692891932, 5964.135200396005, 5992.340842507234, 5732.656040637575, 5738.139279108, 5759.078547432391, 5760.33756118035, 5764.625299355528], "value": 5623.070626992127, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.31730529175036, 515.301766106925, 523.5079025715827, 558.7401858135966, 560.6523955147809, 36610.63389366109, 39181.8040909795, 39218.0, 39303.41044884322, 39316.803415982955, 38009.23981520375, 39372.0, 39398.606013939854, 39398.803005985, 39513.0, 9199.218066464358, 9243.53782310885, 9383.859242111374, 9401.388909720847, 9421.38760980534, 9290.1174388433, 9334.393264437824, 9397.76505587359, 9403.95298023509, 9435.669751558697, 9400.10698983599, 9412.905870941271, 9429.05709429055, 9450.291228157881, 9460.38507497014, 9340.626374944994, 9393.90606093944, 9433.292503062297, 9435.622575096988, 9445.527723613792, 7532.8116797079965, 8365.82878397025, 9291.256699464027, 9293.349465537414, 9391.812163756755, 31151.37697246065, 33916.321673566636, 35492.64507354919, 38497.422538661944, 38545.03637409059, 3271.607407111138, 3481.1123163593375, 3488.8255587220538, 3611.3860643690637, 3725.739198256123, 27853.58219626707, 29303.853480732672, 29585.26036849096, 29928.70071299281, 30215.546766798296, 2692.892284308625, 3145.1036454610326, 3191.4734068878606, 3198.6801319867827, 3211.309568442796, 347555.0, 349596.0, 351177.0, 351926.0, 352304.4769552297, 54961.450385495635, 56007.0, 56516.43483565112, 56570.0, 56602.0, 58953.41046589565, 59726.402735972086, 59797.40202598005, 59866.0, 59941.80116397647, 15593.688126237412, 15707.921460392854, 15828.0, 15906.52280431589, 15925.840741592667, 12189.695257618454, 12255.142140050111, 12347.38263086842, 12369.93815030919, 12375.762423757733, 14490.203038832755, 15098.849011509965, 15193.544193674208, 15202.923985380003, 15355.92322038405, 3239.319742854027, 6603.8349041273395, 6783.82917466406, 6840.794776156723, 6885.896711549331, 870.4472659861034, 897.1387468030764, 959.1415682963708, 988.614440368257, 1046.3617193511952, 28612.427751444855, 29231.0, 29436.116916492112, 29613.259668508028, 30062.699373006428, 738.5863916206941, 954.8064918851436, 956.2732323434101, 1033.2921948465175, 1129.971750706239, 10371.896281037092, 12229.147818437164, 14287.78568321476, 14498.84009279255, 15000.699986000438, 596798.0, 597711.0, 598677.0, 598731.0, 602853.0, 52332.73833630884, 52370.476295236556, 52697.0, 52828.73585632124, 52933.0, 1371.9588412347646, 1372.512757970906, 1399.5731301952799, 1400.516821696513, 1407.0291498865954, 26947.0, 27127.321816954733, 28191.0, 28536.286592835346, 28581.14256572306, 48328.758356207996, 48974.0, 49126.75436622794, 49252.27617033387, 49362.25956610582, 549712.0, 555143.0, 562469.0, 563490.0, 563576.0, 25194.118205862706, 25430.491390171723, 25479.23562293134, 26189.73810261873, 26869.328266793487, 44325.11349773051, 44385.77807110944, 44829.0, 45306.0, 45703.31445028261, 36955.07612309714, 37193.442098368, 37918.81040594779, 38392.04019899413, 38863.41704874486, 37212.88361349164, 37404.0, 37487.43768843524, 37639.43540846944, 38142.0, 31820.363592728478, 36127.27745445024, 36684.24868134954, 37618.811905940296, 37623.623763762014, 28556.57165142483, 33184.502232466046, 33744.66255337415, 33809.985700429024, 35147.47278790769, 20088.799112009277, 22285.331440056823, 22963.196288129835, 24363.38266806185, 24935.50128997446], "value": 18769.18632218402, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.5215381004928, 363.3568583607014, 367.3351234265979, 385.0835012669845, 391.44219487230725, 18903.0, 19194.94427806472, 19248.903755481202, 19758.60482790345, 20071.799282007174, 19058.2376704932, 19553.315633952814, 19556.706649400272, 19650.70523942142, 19763.0, 5554.944450555493, 5573.665580065189, 5829.854253643651, 5834.833033393317, 5840.883182336351, 4905.632077594177, 5744.569157313197, 5754.568407369443, 5756.366799652035, 5766.192733017381, 5138.3577052868395, 5490.478404551562, 5519.0, 5576.0, 5710.314762228539, 5570.248016517779, 5660.235868157787, 5688.061469857468, 5702.486776190142, 5732.828015159531, 4933.703977761345, 4954.142196676246, 5004.649674522785, 5006.799728010875, 5018.971110922253, 24008.159714410085, 24034.0, 24181.15365962183, 24246.878765606234, 24441.388965275833, 2322.6632138340015, 2358.551875143717, 2677.799165062628, 2796.9511433212547, 2902.477554040272, 14558.636034099232, 14609.78085328721, 14688.559343219613, 15138.243087845674, 15356.308966096552, 1860.265195247883, 1903.6858918278467, 1905.1712505060343, 1933.3909818407158, 1939.8933058681766, 116833.0, 121335.0, 122223.0, 122602.0, 125598.11602826056, 10277.9486102569, 10416.531256093494, 10701.571937122428, 10781.353118812896, 10946.726331841608, 13253.602391928258, 13283.800742988862, 13307.0, 13313.068085234134, 13326.33368331599, 7296.635168241567, 8279.50322980623, 8288.419810613199, 8385.580720963928, 8390.202930721573, 8272.669093236203, 8282.58587070644, 8284.668613255522, 8449.408541402167, 8450.746477605682, 6219.191505104398, 6435.0, 6439.420452159328, 6456.22525296958, 6563.540552161398, 1537.7847101405812, 1736.7047601907707, 1854.6661600911966, 1875.90620468976, 1904.5048287445363, 847.0978407995465, 856.8886044814134, 943.3396622364372, 944.891337496183, 972.3485264872552, 18899.527511812314, 20714.48213794667, 20718.378448646567, 20734.377968660963, 20821.271255505984, 857.6842525182034, 904.1546154345708, 916.935814492979, 920.2821798996794, 1069.6790962711311, 8731.51976641278, 10284.434356110485, 10938.124950003981, 10978.451077446096, 11026.614068507564, 560281.0, 603523.0, 614378.0, 619263.0, 632670.0, 61247.69376153091, 70980.29019709736, 78533.60733196298, 111542.44228778804, 112563.3115503268, 737.7012310014453, 971.5482300730195, 982.8427451607708, 984.0553069053789, 984.8670429491926, 14721.33753981095, 17785.733214001542, 17980.820191797913, 18253.908730456264, 18280.634387312446, 52998.0, 53109.734451327495, 54697.72651136719, 54907.45092549024, 55689.72155139199, 70805.0, 71038.28961710524, 75013.0, 82916.17083829326, 84820.0, 7704.576248306173, 7759.650815713194, 7827.530348179127, 8013.519188848688, 8203.753887383387, 10760.946195268974, 10961.68459784799, 12317.630471085882, 12407.751844963232, 12426.62720118398, 8939.821203575762, 10128.03783640553, 10694.572217111228, 10772.461376931278, 11068.225224234226, 9731.880833704216, 9748.0, 10562.366258024544, 10710.464476776286, 10881.510332034919, 9101.635934562544, 10928.562857485611, 10976.957189067025, 11055.0, 11264.94367528157, 7775.339096176662, 7801.726939557316, 8040.2361775631225, 8047.356211503183, 8090.352771778362, 9118.544072796201, 9460.385074970105, 10543.736406589896, 10886.40124793144, 11076.944615276872], "value": 9362.15808245864, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1467.7211329847325, 1505.9548213553592, 1685.1237356574575, 1805.9729104063433, 1816.3188804198426, 7464.776056718293, 7719.768406947801, 8037.035555733306, 8496.745097647074, 8537.4450660707, 7894.802629934255, 8220.588970551464, 8223.876641850376, 8253.628586713598, 8290.502569845798, 2028.2191356327774, 2073.8859362735043, 2081.16753298681, 2083.322920050982, 2086.9669513590793, 2251.4934139818574, 2283.6117859963847, 2315.4674424882205, 2327.045911176418, 2345.331580499558, 2302.7121609798774, 2306.2274138163734, 2311.1217737259817, 2335.4278201840575, 2336.532693461306, 2310.4570425949887, 2313.2828823064906, 2329.161501859338, 2331.1258278145697, 2346.6480027995854, 2323.117215458132, 2342.3090188394463, 2342.941426464335, 2343.6718859359703, 2346.4016675747575, 6955.860882782366, 7099.325564071405, 8307.335413166993, 8330.291925186366, 8358.122397148334, 4664.043871006435, 4683.437987441494, 4818.795301174707, 5215.478452154792, 5233.607479439057, 2940.4562604632524, 3313.3042061167184, 3524.3832329342517, 3534.0, 3555.2000799820094, 970.9029097090305, 982.7371827202082, 1072.9935021509827, 1085.5549224817787, 1095.7863216672752, 10464.947675261576, 10752.247342686014, 10756.085732712729, 10773.35359878402, 10937.125029997502, 4173.8747837564315, 4333.93818514466, 4621.1219868224925, 4743.454502732161, 4751.40607424072, 9454.85817712735, 9454.85817712735, 9461.290403219786, 9469.384490008266, 9471.810563788686, 5160.354955630546, 5202.3497062867145, 5208.609354298443, 5246.344206974129, 5253.343332083489, 4156.9792151039655, 4690.906181876343, 4695.5539223773685, 4794.161021821167, 4810.11012962603, 3825.8087095645105, 3841.846326146923, 3894.82473288697, 3895.9220815584094, 3916.5691773904846, 1098.3519723363254, 1261.2873726344585, 1275.1647670775658, 1288.1884412819863, 1301.6160232731368, 658.3482352471019, 864.828157846122, 892.9330300227509, 900.2033200617501, 903.7785742493131, 4762.904741905212, 5503.587230957694, 5535.58483113768, 5620.662760234399, 5622.156676498558, 433.754928465416, 434.2834323366471, 434.611023134293, 442.1665161171151, 462.89353448706987, 3747.494088298045, 3764.153065560282, 3781.773093614392, 3800.239952009609, 3850.4416859555768, 9775.853362199572, 10104.494775261355, 10132.24008199388, 10137.391756494633, 10181.54183061764, 10063.496825158712, 10071.848922266172, 10083.0, 10085.49572521371, 10093.293469457067, 6188.566800324024, 7646.0, 7688.692452301845, 7813.765587032398, 7827.45207835446, 5409.0263752524925, 5489.972550137224, 5510.283663123768, 5611.803586874603, 5651.01107306228, 9176.0, 9182.449053056838, 9185.586648600962, 9247.260219182315, 9292.72121836346, 8886.333524985523, 8983.91016089857, 9229.723108306762, 9319.720408387759, 9480.715578532656, 6533.314002029719, 6718.93281067183, 6892.724291028303, 6978.511504194657, 7201.819954501179, 7744.612769361622, 7902.170272121344, 7921.841563168819, 7924.960375198087, 7931.881021784793, 6933.826654333682, 7144.428445724434, 7163.997040414242, 7180.281971802778, 7183.712651494091, 5842.912356314574, 6873.793786186422, 7034.050403195503, 7039.894401583878, 7149.034880291302, 6471.611703297817, 6578.375054369827, 6672.799816005528, 6707.731690732511, 6774.254831968578, 3673.971288039353, 4036.4550785643564, 4750.5249475052215, 4799.184138696361, 4830.347903033045, 3710.9628903711696, 4069.7558146511305, 4780.163471392423, 4795.856124316276, 4906.386701662293], "value": 4255.695977441304, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.74218106070816, 309.16217051789647, 311.9173419043953, 312.25059856344785, 318.87404475232285, 4000.5399379071414, 4666.486686464486, 4756.334113224151, 5318.388385335687, 5360.266264205254, 4206.8737937861915, 5202.245674377213, 5271.472852714726, 5295.443978382272, 5331.973340133294, 4082.4692789937267, 4088.97775556111, 4110.732802367851, 4128.298189307825, 4139.586041395852, 3291.358185153896, 3657.158853463705, 3903.063264816438, 4067.7355971861884, 4119.361498967664, 3769.4157405602177, 3955.0507878109192, 3990.3415936370466, 4022.2960981828064, 4028.4964379452567, 3712.461693054492, 3732.7200459965475, 3993.9600603993877, 4017.8392864285393, 4034.6570541503716, 3357.9832100839585, 3499.6500349965054, 3509.806665733637, 3525.894223173283, 3550.5323799959037, 4346.37044588026, 4770.570648641639, 5004.724740139291, 5111.923321150186, 5177.886754347795, 4236.766977816219, 4274.679399045084, 4570.7029043112425, 4571.9542804571865, 4578.855286178456, 4691.953080469186, 4887.560119589254, 5278.709670968095, 5549.060167281235, 5564.805231816867, 3368.8315584220936, 3560.8219589020446, 3780.716446266541, 3798.506194194737, 3826.521684789401, 5552.3892371839465, 5606.887862242732, 5836.824895253149, 5845.678487683176, 5861.589688721791, 3857.228554289153, 3885.747426417274, 3886.6696330812197, 3911.5501717302304, 3944.3886197639413, 3448.275862068994, 3636.0364503406618, 3836.3478208704587, 3917.040325120364, 3956.643902048829, 4411.536788637211, 4519.683622146418, 4549.317602359606, 4550.795214215368, 4613.331066995334, 4584.014436896016, 4883.58489528387, 5580.0, 5580.298009107261, 5684.459976302244, 1214.732758793064, 1228.0482625964771, 1241.2924632959307, 1262.2237324045645, 1274.089026346167, 837.2088376484164, 856.5075081827974, 873.5019441657588, 880.5729221327651, 890.1587999340691, 4287.18440587877, 4298.398224248608, 4402.933955990663, 5143.485651434901, 5183.688978661293, 4858.58702010326, 4955.603551715926, 5397.541208997279, 5411.648242864201, 5425.701586412706, 4598.126355992351, 4652.837150699709, 5035.521625445577, 5075.5178258065425, 5116.897662046738, 2767.681716602576, 2804.78964077695, 2825.816321939067, 2833.0084470435595, 2907.636545431821, 4999.925001124986, 5345.919811202835, 5444.8094316698725, 5893.2928048633585, 5907.822765317047, 5077.441481437039, 5318.973405133027, 5367.409584945653, 5569.749361278752, 5640.210370548127, 5239.423663396947, 5470.854258801205, 5535.169724541351, 5847.239858818326, 5868.65253898444, 4678.836240731558, 5404.7567859445635, 5529.778808847601, 5531.391546929915, 5546.500814926676, 4336.37122617225, 5355.0, 5426.484483974016, 5775.5090817281, 5815.423514538444, 5100.744962751922, 6161.691915404302, 6178.66017369049, 6179.567430279837, 6194.225721784777, 3315.668433156665, 3316.0, 3913.473745239302, 3920.5883382245593, 3957.9313585331224, 4591.910680640367, 5506.91739623898, 5627.649364152656, 5750.597458177887, 5848.853778655568, 4749.501302363202, 5182.766775495036, 5453.7545810439415, 5553.278073850373, 5663.122216056518, 4781.976090119527, 5407.918881216706, 5629.352624448241, 5690.886182276414, 5718.885622287614, 4510.774461276988, 4604.240300350405, 5518.020269054826, 5523.978064058162, 5578.2748242728185, 4895.996320754166, 5589.273394458694, 5623.606347555796, 5638.718064096696, 5672.631278966854, 4201.873943781691, 5315.149576067811, 5523.944760552343, 5527.439862113692, 5571.888562228652], "value": 3997.479402137992, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [624.6345887655722, 687.0861753867357, 763.1147868472573, 764.2510339866931, 792.857285688576, 14205.71588568228, 14750.704985900302, 14953.401863925457, 14964.0, 15029.32368043438, 13150.07949443537, 13161.670958226025, 13167.539136130237, 13224.0, 13315.400806963686, 640.4620119099956, 658.3646780856473, 785.0540099180503, 811.4360519438992, 827.7185756842657, 809.3646487507308, 817.5912043978011, 825.2201669422399, 830.016430529821, 831.9916800832005, 826.4338927834452, 826.9090400055989, 852.9061803201643, 880.6829541365104, 883.0286684646901, 848.8132610825609, 859.3597769661579, 872.2542226396434, 873.3362644390309, 875.5228400521731, 620.0667994668041, 632.3170975346615, 649.1009951217551, 662.2979641579941, 663.8937769956832, 13689.383977721027, 14074.507392241325, 14295.857041429557, 14320.42718291267, 14546.0, 11612.709682257926, 13479.258640774753, 13555.59333220005, 13738.793918091236, 13977.602239776043, 10949.671509854717, 10968.451577421098, 11159.741791074628, 11381.430928453545, 11498.310101393943, 3606.1165014571598, 3692.316921369555, 3692.833822477968, 3706.258748250361, 3791.2227993261317, 52927.735361323335, 64310.67844660793, 80255.0, 84660.73008904871, 86120.0, 7922.881156782653, 9224.454741551484, 10210.285280030475, 10333.276670632984, 10744.086752625957, 20843.37469875906, 20972.37082887516, 20985.370438886857, 21001.78998210029, 21039.8948005259, 7475.8504829903095, 7565.924340756632, 7632.961835190899, 7638.732644357421, 7789.33790627803, 4146.295129827937, 4174.436451079066, 4269.845516612608, 4344.109457561192, 4354.542773008852, 2765.7787377010195, 3456.1532424555644, 3489.389356862539, 3490.3891818931584, 3520.3663340598937, 612.0, 635.7615894039735, 647.9190101237346, 651.6448535548043, 660.5079215984123, 610.377415036669, 640.7501074580917, 653.4413076819346, 654.6432194454034, 688.3185646210216, 7872.409569282212, 8315.750527484186, 9168.862467063, 9217.400868943498, 9240.260779137652, 638.2851206648584, 657.3360905485495, 658.0425480925243, 661.9966900165468, 664.5945972956492, 4284.785760711952, 4474.574915383034, 4485.686001979895, 4487.0, 4515.864524064284, 79245.0, 100644.49677751564, 108062.45968770263, 119422.0, 121556.0, 53108.734456327475, 55092.724536377864, 57316.42683573111, 103679.92640147306, 104625.0, 59726.0, 75360.62319688367, 79174.20825791784, 79299.0, 80945.19054809495, 7796.454248202572, 9740.172085372822, 9845.753856153367, 9908.455034973145, 9923.00769923002, 48696.02607947892, 49449.0, 50566.49433505618, 50693.23960140527, 52204.216936746736, 51638.48361516487, 52510.0, 58414.12378814406, 65098.02352964804, 66657.0001449969, 11983.580574679845, 12000.879991200325, 12041.277523348626, 12329.753404932031, 12463.813042804546, 11250.549978001116, 13325.66685832862, 13625.727485450434, 13884.791728124288, 14053.437862485795, 12032.09759268041, 12985.675358116123, 13004.609861704164, 13167.341632918124, 13467.730645387233, 12354.691132721395, 13103.344832758514, 13158.473661053833, 13568.86431135676, 13598.796018059935, 13001.0, 13029.283389413295, 13411.865881341451, 13441.260730659907, 13457.0, 8807.51558664254, 10427.68716938493, 10866.315721063247, 11076.0, 11407.25852819564, 9913.405195688281, 10433.0, 11015.669529914116, 11027.779444411228, 11315.717107072389], "value": 7072.2901015857, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1563.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 119.0, 118.0, 119.0, 121.0, 148.0, 176.0, 97.0, 170.0, 176.0], "value": 135.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 142.0, 145.0, 113.0, 116.0, 112.0, 110.0, 139.0, 112.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 200.0, 195.0, 198.0, 197.0, 198.0, 226.0, 258.0, 198.0, 195.0], "value": 206.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 61.0, 88.0, 60.0, 58.0, 64.0, 70.0, 107.0, 59.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 83.0, 96.0, 85.0, 85.0, 119.0, 103.0, 103.0, 88.0], "value": 92.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 119.0, 137.0, 97.0, 135.0, 133.0, 89.0, 85.0], "value": 105.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 98.0, 99.0, 98.0, 98.0, 99.0, 100.0, 99.0, 98.0, 99.0], "value": 104.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 92.0, 87.0, 86.0, 94.0, 90.0, 89.0, 93.0, 88.0, 90.0], "value": 89.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 58.0, 60.0, 61.0, 61.0, 51.0, 73.0, 81.0, 84.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 68.0, 73.0, 77.0, 72.0, 70.0, 90.0, 65.0, 80.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 74.0, 73.0, 75.0, 99.0, 75.0, 77.0, 104.0, 106.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 127.0, 154.0, 158.0, 169.0, 146.0, 145.0, 166.0, 215.0, 179.0], "value": 161.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 197.0, 169.0, 168.0, 167.0, 167.0, 166.0, 165.0, 167.0, 166.0], "value": 172.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 78.0, 97.0, 93.0, 76.0, 67.0, 67.0, 69.0, 67.0, 68.0], "value": 74.3, "unit": "score"}]}]} 11:59:02 INFO - Return code: 0 11:59:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:59:02 ERROR - # TBPL SUCCESS # 11:59:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:59:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:59:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:59:02 INFO - 2016-12-21 11:59:02.153 screenresolution[1656:10669] starting screenresolution argv=screenresolution get 11:59:02 INFO - 2016-12-21 11:59:02.156 screenresolution[1656:10669] Display 0: 1600x1200x32@60 11:59:02 INFO - 2016-12-21 11:59:02.162 screenresolution[1657:10672] starting screenresolution argv=screenresolution list 11:59:02 INFO - Available Modes on Display 0 11:59:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:59:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:59:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:59:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:59:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:59:02 INFO - Intel Iris: 11:59:02 INFO - Chipset Model: Intel Iris 11:59:02 INFO - Type: GPU 11:59:02 INFO - Bus: Built-In 11:59:02 INFO - VRAM (Dynamic, Max): 1536 MB 11:59:02 INFO - Vendor: Intel (0x8086) 11:59:02 INFO - Device ID: 0x0a2e 11:59:02 INFO - Revision ID: 0x0009 11:59:02 INFO - Displays: 11:59:02 INFO - Display: 11:59:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:59:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:59:02 INFO - Display Serial Number: 1600x1200 60 11:59:02 INFO - Main Display: Yes 11:59:02 INFO - Mirror: Off 11:59:02 INFO - Online: Yes 11:59:02 INFO - Rotation: Supported 11:59:02 INFO - Return code: 0 11:59:02 INFO - Running post-action listener: _resource_record_post_action 11:59:02 INFO - [mozharness: 2016-12-21 19:59:02.409656Z] Finished run-tests step (success) 11:59:02 INFO - Running post-run listener: _resource_record_post_run 11:59:02 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 37624320; Write bytes: 652425216; Read time: 243; Write time: 1899 11:59:02 INFO - TinderboxPrint: CPU usage
54.3% 11:59:02 INFO - TinderboxPrint: I/O read bytes / time
37,624,320 / 243 11:59:02 INFO - TinderboxPrint: I/O write bytes / time
652,425,216 / 1,899 11:59:02 INFO - TinderboxPrint: CPU idle
2,210.3 (45.7%) 11:59:02 INFO - TinderboxPrint: CPU system
979.5 (20.2%) 11:59:02 INFO - TinderboxPrint: CPU user
1,651.8 (34.1%) 11:59:02 INFO - TinderboxPrint: Swap in / out
386,809,856 / 0 11:59:02 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 340568576; Write bytes: 334345728; Read time: 27014; Write time: 675 11:59:02 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36657664; Write bytes: 301433344; Read time: 229; Write time: 1171 11:59:02 INFO - Running post-run listener: _upload_blobber_files 11:59:02 INFO - Blob upload gear active. 11:59:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:59:02 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 11:59:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:59:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:59:03 INFO - (blobuploader) - INFO - Open directory for files ... 11:59:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:59:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:59:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:59:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:59:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:59:04 INFO - (blobuploader) - INFO - Done attempting. 11:59:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:59:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:59:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:59:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:59:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:59:05 INFO - (blobuploader) - INFO - Done attempting. 11:59:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:59:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:59:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:59:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:59:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:59:08 INFO - (blobuploader) - INFO - Done attempting. 11:59:08 INFO - (blobuploader) - INFO - Iteration through files over. 11:59:08 INFO - Return code: 0 11:59:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:59:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:59:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f34e29b56314d695094563b43bd184ee5d75b8b80c99407d6c564c56bcee0dd687bb70cb54dc3a79b91d3decf037b1b919a4d0c82d6a3c7c37fe635469a0b58", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/94e162ac7d531adbfd692207fceaa4cb5adb3297de77a3f5651ebd63d56a5e151d2597ec2a7567a3dde96fbd4419ec9f27c4621286c232623570422888b6babd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d889ff46277acf79daa7fdd524b3920a4e741182a19f3be1725b9a5337d8a3e527cfa1e42e4e4bc25a6d312b0eb63386aa5c827fd8230c61d62059a7170af4f6"} 11:59:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:59:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:59:08 INFO - Contents: 11:59:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f34e29b56314d695094563b43bd184ee5d75b8b80c99407d6c564c56bcee0dd687bb70cb54dc3a79b91d3decf037b1b919a4d0c82d6a3c7c37fe635469a0b58", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/94e162ac7d531adbfd692207fceaa4cb5adb3297de77a3f5651ebd63d56a5e151d2597ec2a7567a3dde96fbd4419ec9f27c4621286c232623570422888b6babd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d889ff46277acf79daa7fdd524b3920a4e741182a19f3be1725b9a5337d8a3e527cfa1e42e4e4bc25a6d312b0eb63386aa5c827fd8230c61d62059a7170af4f6"} 11:59:08 INFO - Running post-run listener: copy_logs_to_upload_dir 11:59:08 INFO - Copying logs to upload dir... 11:59:08 INFO - mkdir: /builds/slave/test/build/upload/logs 11:59:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.437032 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-21 11:59:08.217371) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 11:59:08.221177) ========= 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.0xfwgUwmTu/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.mgF2aZjQMC/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f34e29b56314d695094563b43bd184ee5d75b8b80c99407d6c564c56bcee0dd687bb70cb54dc3a79b91d3decf037b1b919a4d0c82d6a3c7c37fe635469a0b58", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/94e162ac7d531adbfd692207fceaa4cb5adb3297de77a3f5651ebd63d56a5e151d2597ec2a7567a3dde96fbd4419ec9f27c4621286c232623570422888b6babd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d889ff46277acf79daa7fdd524b3920a4e741182a19f3be1725b9a5337d8a3e527cfa1e42e4e4bc25a6d312b0eb63386aa5c827fd8230c61d62059a7170af4f6"} build_url:https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011201 build_url: 'https://queue.taskcluster.net/v1/task/BxhOnofXRnWMaQG_c-F1Jw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f34e29b56314d695094563b43bd184ee5d75b8b80c99407d6c564c56bcee0dd687bb70cb54dc3a79b91d3decf037b1b919a4d0c82d6a3c7c37fe635469a0b58", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/94e162ac7d531adbfd692207fceaa4cb5adb3297de77a3f5651ebd63d56a5e151d2597ec2a7567a3dde96fbd4419ec9f27c4621286c232623570422888b6babd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d889ff46277acf79daa7fdd524b3920a4e741182a19f3be1725b9a5337d8a3e527cfa1e42e4e4bc25a6d312b0eb63386aa5c827fd8230c61d62059a7170af4f6"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 11:59:08.259310) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 11:59:08.259892) ========= 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.0xfwgUwmTu/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.mgF2aZjQMC/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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.005093 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 11:59:08.311093) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 11:59:08.311639) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 11:59:08.312165) ========= ========= Total master_lag: 0.19 =========