builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0267 starttime: 1479508592.64 results: success (0) buildid: 20161118124722 builduid: e94473ca239248389982145aa07088f2 revision: f09e137ead39230eaa94f47988ccce2cfcda4195 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 14:36:32.636134) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 14:36:32.636609) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 14:36:32.636895) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 14:36:32.662535) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 14:36:32.662934) ========= 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.2sNC1oQPCv/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.6d4vl51Li5/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-18 14:36:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.4M=0.001s 2016-11-18 14:36:32 (19.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134740 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 14:36:32.820488) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 14:36:32.820821) ========= 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.2sNC1oQPCv/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.6d4vl51Li5/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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.023864 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 14:36:32.870219) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 14:36:32.870587) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f09e137ead39230eaa94f47988ccce2cfcda4195 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f09e137ead39230eaa94f47988ccce2cfcda4195 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2sNC1oQPCv/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.6d4vl51Li5/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-18 14:36:32,969 truncating revision to first 12 chars 2016-11-18 14:36:32,969 Setting DEBUG logging. 2016-11-18 14:36:32,969 attempt 1/10 2016-11-18 14:36:32,969 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f09e137ead39?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-18 14:36:33,645 unpacking tar archive at: mozilla-central-f09e137ead39/testing/mozharness/ program finished with exit code 0 elapsedTime=0.965105 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 14:36:33.858390) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 14:36:33.858728) ========= script_repo_revision: f09e137ead39230eaa94f47988ccce2cfcda4195 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 14:36:33.859191) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 14:36:33.859473) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 14:36:33.878135) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-18 14:36:33.878539) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2sNC1oQPCv/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.6d4vl51Li5/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 14:36:34 INFO - MultiFileLogger online at 20161118 14:36:34 in /builds/slave/test 14:36:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:36:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:36:34 INFO - {'append_to_log': False, 14:36:34 INFO - 'base_work_dir': '/builds/slave/test', 14:36:34 INFO - 'blob_upload_branch': 'Firefox', 14:36:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:36:34 INFO - 'branch': 'Firefox', 14:36:34 INFO - 'buildbot_json_path': 'buildprops.json', 14:36:34 INFO - 'config_files': ('talos/mac_config.py',), 14:36:34 INFO - 'default_actions': ('clobber', 14:36:34 INFO - 'read-buildbot-config', 14:36:34 INFO - 'download-and-extract', 14:36:34 INFO - 'populate-webroot', 14:36:34 INFO - 'create-virtualenv', 14:36:34 INFO - 'install', 14:36:34 INFO - 'run-tests'), 14:36:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:36:34 INFO - 'download_minidump_stackwalk': True, 14:36:34 INFO - 'download_symbols': 'ondemand', 14:36:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:36:34 INFO - 'tooltool.py': '/tools/tooltool.py', 14:36:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:36:34 INFO - '/tools/misc-python/virtualenv.py')}, 14:36:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:36:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:36:34 INFO - 'installer_path': 'installer.exe', 14:36:34 INFO - 'log_level': 'info', 14:36:34 INFO - 'log_name': 'talos', 14:36:34 INFO - 'log_to_console': True, 14:36:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:36:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:36:34 INFO - 'opt_config_files': (), 14:36:34 INFO - 'pip_index': False, 14:36:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:36:34 INFO - 'cmd': ('bash', 14:36:34 INFO - '-c', 14:36:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:36:34 INFO - 'enabled': True, 14:36:34 INFO - 'halt_on_failure': False, 14:36:34 INFO - 'name': 'check_screen_resolution'},), 14:36:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:36:34 INFO - 'cmd': ('bash', 14:36:34 INFO - '-c', 14:36:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:36:34 INFO - 'enabled': True, 14:36:34 INFO - 'halt_on_failure': False, 14:36:34 INFO - 'name': 'check_screen_resolution'},), 14:36:34 INFO - 'run_cmd_checks_enabled': True, 14:36:34 INFO - 'sps_profile': False, 14:36:34 INFO - 'sps_profile_interval': 0, 14:36:34 INFO - 'suite': 'dromaeojs-e10s', 14:36:34 INFO - 'system_bits': '32', 14:36:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:36:34 INFO - 'title': 't-yosemite-r7-0267', 14:36:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:36:34 INFO - 'use_talos_json': True, 14:36:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:36:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:36:34 INFO - 'work_dir': 'build'} 14:36:34 INFO - [mozharness: 2016-11-18 22:36:34.081311Z] Running clobber step. 14:36:34 INFO - Running pre-action listener: _resource_record_pre_action 14:36:34 INFO - Running main action method: clobber 14:36:34 INFO - rmtree: /builds/slave/test/build 14:36:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:36:36 INFO - Running post-action listener: _resource_record_post_action 14:36:36 INFO - [mozharness: 2016-11-18 22:36:36.488746Z] Finished clobber step (success) 14:36:36 INFO - [mozharness: 2016-11-18 22:36:36.488897Z] Running read-buildbot-config step. 14:36:36 INFO - Running pre-action listener: _resource_record_pre_action 14:36:36 INFO - Running main action method: read_buildbot_config 14:36:36 INFO - Using buildbot properties: 14:36:36 INFO - { 14:36:36 INFO - "project": "", 14:36:36 INFO - "product": "firefox", 14:36:36 INFO - "script_repo_revision": "production", 14:36:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:36:36 INFO - "repository": "", 14:36:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:36:36 INFO - "buildid": "20161118124722", 14:36:36 INFO - "pgo_build": "False", 14:36:36 INFO - "basedir": "/builds/slave/test", 14:36:36 INFO - "buildnumber": 32, 14:36:36 INFO - "slavename": "t-yosemite-r7-0267", 14:36:36 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 14:36:36 INFO - "platform": "yosemite_r7", 14:36:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:36:36 INFO - "branch": "mozilla-central", 14:36:36 INFO - "repo_path": "mozilla-central", 14:36:36 INFO - "revision": "f09e137ead39230eaa94f47988ccce2cfcda4195", 14:36:36 INFO - "stage_platform": "macosx64", 14:36:36 INFO - "builduid": "e94473ca239248389982145aa07088f2", 14:36:36 INFO - "slavebuilddir": "test" 14:36:36 INFO - } 14:36:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 14:36:36 INFO - Running post-action listener: _resource_record_post_action 14:36:36 INFO - [mozharness: 2016-11-18 22:36:36.492173Z] Finished read-buildbot-config step (success) 14:36:36 INFO - [mozharness: 2016-11-18 22:36:36.492309Z] Running download-and-extract step. 14:36:36 INFO - Running pre-action listener: _resource_record_pre_action 14:36:36 INFO - Running main action method: download_and_extract 14:36:36 INFO - mkdir: /builds/slave/test/build/tests 14:36:36 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')]} 14:36:36 INFO - trying https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 14:36:36 INFO - Downloading https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 14:36:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 14:36:37 INFO - Downloaded 1199 bytes. 14:36:37 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 14:36:37 INFO - Using the following test package requirements: 14:36:37 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 14:36:37 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 14:36:37 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 14:36:37 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 14:36:37 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 14:36:37 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 14:36:37 INFO - u'jsshell-mac.zip'], 14:36:37 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 14:36:37 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 14:36:37 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 14:36:37 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 14:36:37 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 14:36:37 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 14:36:37 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 14:36:37 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 14:36:37 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 14:36:37 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 14:36:37 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 14:36:37 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:36:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 14:36:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:36:37 INFO - Fetch https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 14:36:38 INFO - Http code: 200 14:36:38 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 14:36:38 INFO - x-amz-cf-id: 70Z7F3eboUfC2ZrebrjOcwAKnQ7Saf_1s11GfRNXzE_bOnvHdFWbOA== 14:36:38 INFO - x-amz-version-id: PrDdWqiEnZ.WYt4UdI.KUxUSQRIjVOK8 14:36:38 INFO - Expected file size: 16265452 14:36:38 INFO - Obtained file size: 16265452 14:36:44 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:36:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 14:36:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:36:44 INFO - Fetch https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 14:36:45 INFO - Http code: 200 14:36:45 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 14:36:45 INFO - x-amz-cf-id: ZhN3OqBj6MYihixSJE5NmdybFAas1Jo02AxPLTv2DiXTVsapKw7pUQ== 14:36:45 INFO - x-amz-version-id: PrDdWqiEnZ.WYt4UdI.KUxUSQRIjVOK8 14:36:45 INFO - Expected file size: 16265452 14:36:45 INFO - Obtained file size: 16265452 14:36:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 14:36:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:36:52 INFO - Fetch https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 14:36:53 INFO - Http code: 200 14:36:53 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 14:36:53 INFO - x-amz-cf-id: NXc3S71t0YAnp7cP-VgeCwhsVuUddxna1XhLVTPrbG2u_eJtmNXKBQ== 14:36:53 INFO - x-amz-version-id: rLoHiwoFjvwrIwn_gBeboCcfj6p5zb4_ 14:36:53 INFO - Expected file size: 14409392 14:36:53 INFO - Obtained file size: 14409392 14:36:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:53 INFO - trying https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 14:36:53 INFO - Downloading https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 14:36:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 14:36:56 INFO - Downloaded 107529496 bytes. 14:36:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 14:36:56 INFO - mkdir: /builds/slave/test/properties 14:36:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:36:56 INFO - Writing to file /builds/slave/test/properties/build_url 14:36:56 INFO - Contents: 14:36:56 INFO - build_url:https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 14:36:57 INFO - Running post-action listener: _resource_record_post_action 14:36:57 INFO - Running post-action listener: set_extra_try_arguments 14:36:57 INFO - [mozharness: 2016-11-18 22:36:57.022008Z] Finished download-and-extract step (success) 14:36:57 INFO - [mozharness: 2016-11-18 22:36:57.022159Z] Running populate-webroot step. 14:36:57 INFO - Running pre-action listener: _resource_record_pre_action 14:36:57 INFO - Running main action method: populate_webroot 14:36:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:36:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:36:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:36:57 INFO - '--tppagecycles', 14:36:57 INFO - '7'], 14:36:57 INFO - 'tests': ['tsvgm']}}, 14:36:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:36:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:36:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:36:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:36:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:36:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:36:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:36:57 INFO - 'talos_options': ['--disable-e10s'], 14:36:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:36:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:36:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:36:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:36:57 INFO - 'talos_options': ['--disable-e10s'], 14:36:57 INFO - 'tests': ['damp', 'tps']}, 14:36:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:36:57 INFO - 'tests': ['damp', 'tps']}, 14:36:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:36:57 INFO - 'tests': ['dromaeo_dom']}, 14:36:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:36:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:36:57 INFO - 'tests': ['basic_compositor_video']}, 14:36:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:36:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:36:57 INFO - 'tests': ['a11yr', 14:36:57 INFO - 'ts_paint', 14:36:57 INFO - 'tpaint', 14:36:57 INFO - 'sessionrestore', 14:36:57 INFO - 'sessionrestore_no_auto_restore', 14:36:57 INFO - 'tabpaint']}, 14:36:57 INFO - 'other-e10s': {'tests': ['a11yr', 14:36:57 INFO - 'ts_paint', 14:36:57 INFO - 'tpaint', 14:36:57 INFO - 'sessionrestore', 14:36:57 INFO - 'sessionrestore_no_auto_restore', 14:36:57 INFO - 'tabpaint']}, 14:36:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:36:57 INFO - 'ts_paint', 14:36:57 INFO - 'tpaint', 14:36:57 INFO - 'sessionrestore', 14:36:57 INFO - 'sessionrestore_no_auto_restore', 14:36:57 INFO - 'tabpaint']}, 14:36:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:36:57 INFO - 'ts_paint', 14:36:57 INFO - 'tpaint', 14:36:57 INFO - 'sessionrestore', 14:36:57 INFO - 'sessionrestore_no_auto_restore', 14:36:57 INFO - 'tabpaint']}, 14:36:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:36:57 INFO - 'tests': ['a11yr', 14:36:57 INFO - 'ts_paint', 14:36:57 INFO - 'tpaint', 14:36:57 INFO - 'sessionrestore', 14:36:57 INFO - 'sessionrestore_no_auto_restore', 14:36:57 INFO - 'tabpaint']}, 14:36:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:36:57 INFO - 'tests': ['a11yr', 14:36:57 INFO - 'ts_paint', 14:36:57 INFO - 'tpaint', 14:36:57 INFO - 'sessionrestore', 14:36:57 INFO - 'sessionrestore_no_auto_restore', 14:36:57 INFO - 'tabpaint']}, 14:36:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:36:57 INFO - 'tests': ['tsvgx', 14:36:57 INFO - 'tsvgr_opacity', 14:36:57 INFO - 'tart', 14:36:57 INFO - 'tscrollx', 14:36:57 INFO - 'cart']}, 14:36:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:36:57 INFO - 'tsvgr_opacity', 14:36:57 INFO - 'tart', 14:36:57 INFO - 'tscrollx', 14:36:57 INFO - 'cart']}, 14:36:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:36:57 INFO - 'talos_options': ['--disable-e10s'], 14:36:57 INFO - 'tests': ['tp5o']}, 14:36:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:36:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:36:57 INFO - 'talos_options': ['--disable-e10s', 14:36:57 INFO - '--xperf_path', 14:36:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:36:57 INFO - 'tests': ['tp5n']}, 14:36:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:36:57 INFO - 'talos_options': ['--xperf_path', 14:36:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:36:57 INFO - 'tests': ['tp5n']}}, 14:36:57 INFO - 'talos.zip': {'path': '', 14:36:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:36:57 INFO - Running post-action listener: _resource_record_post_action 14:36:57 INFO - [mozharness: 2016-11-18 22:36:57.040772Z] Finished populate-webroot step (success) 14:36:57 INFO - [mozharness: 2016-11-18 22:36:57.040899Z] Running create-virtualenv step. 14:36:57 INFO - Running pre-action listener: _resource_record_pre_action 14:36:57 INFO - Running main action method: create_virtualenv 14:36:57 INFO - Creating virtualenv /builds/slave/test/build/venv 14:36:57 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 14:36:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:36:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:36:57 INFO - Using real prefix '/tools/python27' 14:36:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:36:57 INFO - Installing distribute.............................................................................................................................................................................................done. 14:37:00 INFO - Installing pip.................done. 14:37:00 INFO - Return code: 0 14:37:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:37:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:37:01 INFO - Reading from file tmpfile_stdout 14:37:01 INFO - Output received: 14:37:01 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) 14:37:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:37:01 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')]} 14:37:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:01 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')]} 14:37:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:01 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 0x107e943f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb370497510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107caad30>, '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 0x107b11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ca2b20>, '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.2sNC1oQPCv/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.6d4vl51Li5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 14:37:01 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 14:37:01 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 14:37:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNC1oQPCv/Render', 14:37:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:01 INFO - 'HOME': '/Users/cltbld', 14:37:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:01 INFO - 'LOGNAME': 'cltbld', 14:37:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:01 INFO - 'MOZ_NO_REMOTE': '1', 14:37:01 INFO - 'NO_EM_RESTART': '1', 14:37:01 INFO - 'PAGER': '/bin/cat', 14:37:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:01 INFO - 'PWD': '/builds/slave/test', 14:37:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:01 INFO - 'SHELL': '/bin/bash', 14:37:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6d4vl51Li5/Listeners', 14:37:01 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 14:37:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:01 INFO - 'USER': 'cltbld', 14:37:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:01 INFO - 'XPC_FLAGS': '0x0', 14:37:01 INFO - 'XPC_SERVICE_NAME': '0', 14:37:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:01 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 14:37:01 INFO - Cleaning up... 14:37:01 INFO - Return code: 0 14:37:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:37:01 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')]} 14:37:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:01 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')]} 14:37:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:01 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 0x107e943f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb370497510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107caad30>, '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 0x107b11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ca2b20>, '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.2sNC1oQPCv/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.6d4vl51Li5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 14:37:01 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 14:37:01 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 14:37:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNC1oQPCv/Render', 14:37:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:01 INFO - 'HOME': '/Users/cltbld', 14:37:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:01 INFO - 'LOGNAME': 'cltbld', 14:37:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:01 INFO - 'MOZ_NO_REMOTE': '1', 14:37:01 INFO - 'NO_EM_RESTART': '1', 14:37:01 INFO - 'PAGER': '/bin/cat', 14:37:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:01 INFO - 'PWD': '/builds/slave/test', 14:37:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:01 INFO - 'SHELL': '/bin/bash', 14:37:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6d4vl51Li5/Listeners', 14:37:01 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 14:37:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:01 INFO - 'USER': 'cltbld', 14:37:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:01 INFO - 'XPC_FLAGS': '0x0', 14:37:01 INFO - 'XPC_SERVICE_NAME': '0', 14:37:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:01 INFO - Downloading/unpacking psutil>=3.1.1 14:37:01 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 14:37:01 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 14:37:01 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 14:37:01 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 14:37:01 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 14:37:01 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 14:37:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:37:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:37:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:37:04 INFO - Installing collected packages: psutil 14:37:04 INFO - Running setup.py install for psutil 14:37:05 INFO - building 'psutil._psutil_osx' extension 14:37:05 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 14:37:05 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 14:37:05 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 14:37:05 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 14:37:05 INFO - building 'psutil._psutil_posix' extension 14:37:05 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 14:37:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:37:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:37:05 INFO - ^ 14:37:05 INFO - 1 warning generated. 14:37:05 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 14:37:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:37:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:37:06 INFO - Successfully installed psutil 14:37:06 INFO - Cleaning up... 14:37:06 INFO - Return code: 0 14:37:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:37:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e943f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb370497510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107caad30>, '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 0x107b11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ca2b20>, '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.2sNC1oQPCv/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.6d4vl51Li5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 14:37:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:37:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:37:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNC1oQPCv/Render', 14:37:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:06 INFO - 'HOME': '/Users/cltbld', 14:37:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:06 INFO - 'LOGNAME': 'cltbld', 14:37:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:06 INFO - 'MOZ_NO_REMOTE': '1', 14:37:06 INFO - 'NO_EM_RESTART': '1', 14:37:06 INFO - 'PAGER': '/bin/cat', 14:37:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:06 INFO - 'PWD': '/builds/slave/test', 14:37:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:06 INFO - 'SHELL': '/bin/bash', 14:37:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6d4vl51Li5/Listeners', 14:37:06 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 14:37:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:06 INFO - 'USER': 'cltbld', 14:37:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:06 INFO - 'XPC_FLAGS': '0x0', 14:37:06 INFO - 'XPC_SERVICE_NAME': '0', 14:37:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:37:06 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 14:37:06 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 14:37:06 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 14:37:06 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 14:37:06 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 14:37:06 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 14:37:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:37:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:37:09 INFO - Installing collected packages: mozsystemmonitor 14:37:09 INFO - Running setup.py install for mozsystemmonitor 14:37:09 INFO - Successfully installed mozsystemmonitor 14:37:09 INFO - Cleaning up... 14:37:09 INFO - Return code: 0 14:37:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:37:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:09 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 0x107e943f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb370497510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107caad30>, '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 0x107b11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ca2b20>, '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.2sNC1oQPCv/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.6d4vl51Li5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 14:37:09 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 14:37:09 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 14:37:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNC1oQPCv/Render', 14:37:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:09 INFO - 'HOME': '/Users/cltbld', 14:37:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:09 INFO - 'LOGNAME': 'cltbld', 14:37:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:09 INFO - 'MOZ_NO_REMOTE': '1', 14:37:09 INFO - 'NO_EM_RESTART': '1', 14:37:09 INFO - 'PAGER': '/bin/cat', 14:37:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:09 INFO - 'PWD': '/builds/slave/test', 14:37:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:09 INFO - 'SHELL': '/bin/bash', 14:37:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6d4vl51Li5/Listeners', 14:37:09 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 14:37:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:09 INFO - 'USER': 'cltbld', 14:37:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:09 INFO - 'XPC_FLAGS': '0x0', 14:37:09 INFO - 'XPC_SERVICE_NAME': '0', 14:37:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:09 INFO - Downloading/unpacking jsonschema==2.5.1 14:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:37:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:37:12 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 14:37:12 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 14:37:12 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 14:37:12 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 14:37:12 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 14:37:12 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 14:37:12 INFO - Downloading functools32-3.2.3-2.tar.gz 14:37:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:37:12 INFO - warning: no files found matching '*.txt' 14:37:12 INFO - no previously-included directories found matching 'build' 14:37:12 INFO - no previously-included directories found matching 'dist' 14:37:12 INFO - no previously-included directories found matching '.git*' 14:37:12 INFO - Installing collected packages: jsonschema, functools32 14:37:12 INFO - Running setup.py install for functools32 14:37:12 INFO - warning: no files found matching '*.txt' 14:37:12 INFO - no previously-included directories found matching 'build' 14:37:12 INFO - no previously-included directories found matching 'dist' 14:37:12 INFO - no previously-included directories found matching '.git*' 14:37:12 INFO - Successfully installed jsonschema functools32 14:37:12 INFO - Cleaning up... 14:37:12 INFO - Return code: 0 14:37:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:37:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107e943f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb370497510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107caad30>, '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 0x107b11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ca2b20>, '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.2sNC1oQPCv/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.6d4vl51Li5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 14:37:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:37:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:37:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNC1oQPCv/Render', 14:37:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:12 INFO - 'HOME': '/Users/cltbld', 14:37:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:12 INFO - 'LOGNAME': 'cltbld', 14:37:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:12 INFO - 'MOZ_NO_REMOTE': '1', 14:37:12 INFO - 'NO_EM_RESTART': '1', 14:37:12 INFO - 'PAGER': '/bin/cat', 14:37:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:12 INFO - 'PWD': '/builds/slave/test', 14:37:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:12 INFO - 'SHELL': '/bin/bash', 14:37:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6d4vl51Li5/Listeners', 14:37:12 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 14:37:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:12 INFO - 'USER': 'cltbld', 14:37:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:12 INFO - 'XPC_FLAGS': '0x0', 14:37:12 INFO - 'XPC_SERVICE_NAME': '0', 14:37:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:37:13 INFO - Cleaning up... 14:37:13 INFO - Return code: 0 14:37:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:37:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:13 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 0x107e943f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb370497510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107caad30>, '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 0x107b11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ca2b20>, '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.2sNC1oQPCv/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.6d4vl51Li5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 14:37:13 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 14:37:13 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 14:37:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNC1oQPCv/Render', 14:37:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:13 INFO - 'HOME': '/Users/cltbld', 14:37:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:13 INFO - 'LOGNAME': 'cltbld', 14:37:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:13 INFO - 'MOZ_NO_REMOTE': '1', 14:37:13 INFO - 'NO_EM_RESTART': '1', 14:37:13 INFO - 'PAGER': '/bin/cat', 14:37:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:13 INFO - 'PWD': '/builds/slave/test', 14:37:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:13 INFO - 'SHELL': '/bin/bash', 14:37:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6d4vl51Li5/Listeners', 14:37:13 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 14:37:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:13 INFO - 'USER': 'cltbld', 14:37:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:13 INFO - 'XPC_FLAGS': '0x0', 14:37:13 INFO - 'XPC_SERVICE_NAME': '0', 14:37:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:13 INFO - Downloading/unpacking blobuploader==1.2.4 14:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:15 INFO - Downloading blobuploader-1.2.4.tar.gz 14:37:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:37:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:37:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:37:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:17 INFO - Downloading docopt-0.6.1.tar.gz 14:37:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:37:17 INFO - Installing collected packages: blobuploader, requests, docopt 14:37:17 INFO - Running setup.py install for blobuploader 14:37:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:37:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:37:17 INFO - Running setup.py install for requests 14:37:17 INFO - Running setup.py install for docopt 14:37:18 INFO - Successfully installed blobuploader requests docopt 14:37:18 INFO - Cleaning up... 14:37:18 INFO - Return code: 0 14:37:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:37:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:18 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 0x107e943f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb370497510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107caad30>, '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 0x107b11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ca2b20>, '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.2sNC1oQPCv/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.6d4vl51Li5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 14:37:18 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 14:37:18 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 14:37:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNC1oQPCv/Render', 14:37:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:18 INFO - 'HOME': '/Users/cltbld', 14:37:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:18 INFO - 'LOGNAME': 'cltbld', 14:37:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:18 INFO - 'MOZ_NO_REMOTE': '1', 14:37:18 INFO - 'NO_EM_RESTART': '1', 14:37:18 INFO - 'PAGER': '/bin/cat', 14:37:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:18 INFO - 'PWD': '/builds/slave/test', 14:37:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:18 INFO - 'SHELL': '/bin/bash', 14:37:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6d4vl51Li5/Listeners', 14:37:18 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 14:37:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:18 INFO - 'USER': 'cltbld', 14:37:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:18 INFO - 'XPC_FLAGS': '0x0', 14:37:18 INFO - 'XPC_SERVICE_NAME': '0', 14:37:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:37:18 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-Y2qasg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:37:18 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-eajFHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:37:18 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-Pi2gYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:37:18 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-lKH06L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:37:18 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-gG8cwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:37:18 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-PxNQJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:37:18 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-3SdEEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:37:19 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-3TYs_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:37:19 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-08CXR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:37:19 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-DfK0YC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:37:19 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-qrfs_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:37:19 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-axj3vq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:37:19 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-iVmLz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:37:19 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-76rZkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:37:19 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-hx9dRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:37:19 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-abg7Xu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:37:20 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-6ttF4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:37:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:37:20 INFO - Running setup.py install for manifestparser 14:37:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:37:20 INFO - Running setup.py install for mozcrash 14:37:20 INFO - Running setup.py install for mozdebug 14:37:20 INFO - Running setup.py install for mozdevice 14:37:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:37:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:37:20 INFO - Running setup.py install for mozfile 14:37:20 INFO - Running setup.py install for mozhttpd 14:37:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:37:21 INFO - Running setup.py install for mozinfo 14:37:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:37:21 INFO - Running setup.py install for mozInstall 14:37:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:37:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:37:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:37:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:37:21 INFO - Running setup.py install for mozleak 14:37:21 INFO - Running setup.py install for mozlog 14:37:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:37:21 INFO - Running setup.py install for moznetwork 14:37:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:37:21 INFO - Running setup.py install for mozprocess 14:37:21 INFO - Running setup.py install for mozprofile 14:37:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:37:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:37:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:37:22 INFO - Running setup.py install for mozrunner 14:37:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:37:22 INFO - Running setup.py install for mozscreenshot 14:37:22 INFO - Running setup.py install for moztest 14:37:22 INFO - Running setup.py install for mozversion 14:37:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:37:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:37:22 INFO - Cleaning up... 14:37:22 INFO - Return code: 0 14:37:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:37: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')]} 14:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37: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')]} 14:37:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:22 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 0x107e943f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb370497510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107caad30>, '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 0x107b11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ca2b20>, '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.2sNC1oQPCv/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.6d4vl51Li5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 14:37:22 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 14:37:22 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 14:37:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNC1oQPCv/Render', 14:37:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:22 INFO - 'HOME': '/Users/cltbld', 14:37:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:22 INFO - 'LOGNAME': 'cltbld', 14:37:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:22 INFO - 'MOZ_NO_REMOTE': '1', 14:37:22 INFO - 'NO_EM_RESTART': '1', 14:37:22 INFO - 'PAGER': '/bin/cat', 14:37:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:22 INFO - 'PWD': '/builds/slave/test', 14:37:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:22 INFO - 'SHELL': '/bin/bash', 14:37:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6d4vl51Li5/Listeners', 14:37:22 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 14:37:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:22 INFO - 'USER': 'cltbld', 14:37:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:22 INFO - 'XPC_FLAGS': '0x0', 14:37:22 INFO - 'XPC_SERVICE_NAME': '0', 14:37:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:37:23 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-qbvFDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:37:23 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)) 14:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:37:23 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-DGD6Fn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:37:23 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)) 14:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:37:23 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-xGc1Ui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:37:23 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)) 14:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:37:23 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-HpG4nJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:37:23 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-UDj2SZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:37:23 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)) 14:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:37:23 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-cvJQo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:37:23 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)) 14:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:37:23 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-HqvxAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:37:23 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)) 14:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:37:23 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-hxzcPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:37:23 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)) 14:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:37:23 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-iQ5tck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:37:23 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)) 14:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:37:23 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-Xp7OIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:37:24 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)) 14:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:37:24 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-MxC4pg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:37:24 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)) 14:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:37:24 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-rohjom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:37:24 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)) 14:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:37:24 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-bIrUpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:37:24 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)) 14:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:37:24 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-1Vy07m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:37:24 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)) 14:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:37:24 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-J41auU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:37:24 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)) 14:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:37:24 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-dd1ceS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:37:24 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)) 14:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:37:24 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-_gGnTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:37:24 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)) 14:37:24 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)) 14:37:24 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)) 14:37:24 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)) 14:37:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:37:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:37:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:27 INFO - Downloading blessings-1.6.tar.gz 14:37:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:37:27 INFO - Installing collected packages: blessings 14:37:27 INFO - Running setup.py install for blessings 14:37:27 INFO - Successfully installed blessings 14:37:27 INFO - Cleaning up... 14:37:27 INFO - Return code: 0 14:37:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:37:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:37:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:37:27 INFO - Reading from file tmpfile_stdout 14:37:27 INFO - Current package versions: 14:37:27 INFO - blessings == 1.6 14:37:27 INFO - blobuploader == 1.2.4 14:37:27 INFO - docopt == 0.6.1 14:37:27 INFO - functools32 == 3.2.3-2 14:37:27 INFO - jsonschema == 2.5.1 14:37:27 INFO - manifestparser == 1.1 14:37:27 INFO - mozInstall == 1.12 14:37:27 INFO - mozcrash == 1.0 14:37:27 INFO - mozdebug == 0.1 14:37:27 INFO - mozdevice == 0.49 14:37:27 INFO - mozfile == 1.2 14:37:27 INFO - mozhttpd == 0.7 14:37:27 INFO - mozinfo == 0.9 14:37:27 INFO - mozleak == 0.1 14:37:27 INFO - mozlog == 3.4 14:37:27 INFO - moznetwork == 0.27 14:37:27 INFO - mozprocess == 0.23 14:37:27 INFO - mozprofile == 0.28 14:37:27 INFO - mozrunner == 6.13 14:37:27 INFO - mozscreenshot == 0.1 14:37:27 INFO - mozsystemmonitor == 0.3 14:37:27 INFO - moztest == 0.7 14:37:27 INFO - mozversion == 1.4 14:37:27 INFO - psutil == 3.1.1 14:37:27 INFO - requests == 1.2.3 14:37:27 INFO - wsgiref == 0.1.2 14:37:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:37: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')]} 14:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37: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')]} 14:37:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:27 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 0x107e943f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb370497510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107caad30>, '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 0x107b11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ca2b20>, '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.2sNC1oQPCv/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.6d4vl51Li5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 14:37:27 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 14:37:27 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 14:37:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNC1oQPCv/Render', 14:37:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:27 INFO - 'HOME': '/Users/cltbld', 14:37:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:27 INFO - 'LOGNAME': 'cltbld', 14:37:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:27 INFO - 'MOZ_NO_REMOTE': '1', 14:37:27 INFO - 'NO_EM_RESTART': '1', 14:37:27 INFO - 'PAGER': '/bin/cat', 14:37:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:27 INFO - 'PWD': '/builds/slave/test', 14:37:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:27 INFO - 'SHELL': '/bin/bash', 14:37:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6d4vl51Li5/Listeners', 14:37:27 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 14:37:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:27 INFO - 'USER': 'cltbld', 14:37:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:27 INFO - 'XPC_FLAGS': '0x0', 14:37:27 INFO - 'XPC_SERVICE_NAME': '0', 14:37:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:28 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)) 14:37:28 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)) 14:37:28 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)) 14:37:28 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)) 14:37:28 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)) 14:37:28 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)) 14:37:28 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)) 14:37:28 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)) 14:37:28 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)) 14:37:28 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)) 14:37:28 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)) 14:37:28 INFO - Cleaning up... 14:37:28 INFO - Return code: 0 14:37:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:28 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 0x107e943f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e0a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb370497510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107caad30>, '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 0x107b11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ca2b20>, '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.2sNC1oQPCv/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.6d4vl51Li5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 14:37:28 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 14:37:28 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 14:37:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNC1oQPCv/Render', 14:37:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:28 INFO - 'HOME': '/Users/cltbld', 14:37:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:28 INFO - 'LOGNAME': 'cltbld', 14:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:28 INFO - 'MOZ_NO_REMOTE': '1', 14:37:28 INFO - 'NO_EM_RESTART': '1', 14:37:28 INFO - 'PAGER': '/bin/cat', 14:37:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:28 INFO - 'PWD': '/builds/slave/test', 14:37:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:28 INFO - 'SHELL': '/bin/bash', 14:37:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6d4vl51Li5/Listeners', 14:37:28 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 14:37:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:28 INFO - 'USER': 'cltbld', 14:37:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:28 INFO - 'XPC_FLAGS': '0x0', 14:37:28 INFO - 'XPC_SERVICE_NAME': '0', 14:37:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:37:28 INFO - Cleaning up... 14:37:28 INFO - Return code: 0 14:37:28 INFO - Running post-action listener: _resource_record_post_action 14:37:28 INFO - Running post-action listener: _start_resource_monitoring 14:37:28 INFO - Starting resource monitoring. 14:37:28 INFO - [mozharness: 2016-11-18 22:37:28.550245Z] Finished create-virtualenv step (success) 14:37:28 INFO - [mozharness: 2016-11-18 22:37:28.551187Z] Running install step. 14:37:28 INFO - Running pre-action listener: _resource_record_pre_action 14:37:28 INFO - Running main action method: install 14:37:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:37:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:37:28 INFO - Reading from file tmpfile_stdout 14:37:28 INFO - Detecting whether we're running mozinstall >=1.0... 14:37:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:37:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:37:28 INFO - Reading from file tmpfile_stdout 14:37:28 INFO - Output received: 14:37:28 INFO - Usage: mozinstall [options] installer 14:37:28 INFO - Options: 14:37:28 INFO - -h, --help show this help message and exit 14:37:28 INFO - -d DEST, --destination=DEST 14:37:28 INFO - Directory to install application into. [default: 14:37:28 INFO - "/builds/slave/test"] 14:37:28 INFO - --app=APP Application being installed. [default: firefox] 14:37:28 INFO - mkdir: /builds/slave/test/build/application 14:37:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:37:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:38:02 INFO - Reading from file tmpfile_stdout 14:38:02 INFO - Output received: 14:38:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:38:02 INFO - Running post-action listener: _resource_record_post_action 14:38:02 INFO - [mozharness: 2016-11-18 22:38:02.345406Z] Finished install step (success) 14:38:02 INFO - [mozharness: 2016-11-18 22:38:02.345507Z] Running run-tests step. 14:38:02 INFO - Running pre-action listener: _resource_record_pre_action 14:38:02 INFO - Running main action method: run_tests 14:38:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:38:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:38:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:38:02 INFO - 2016-11-18 14:38:02.358 screenresolution[1631:7458] starting screenresolution argv=screenresolution get 14:38:02 INFO - 2016-11-18 14:38:02.361 screenresolution[1631:7458] Display 0: 1600x1200x32@60 14:38:02 INFO - 2016-11-18 14:38:02.366 screenresolution[1632:7460] starting screenresolution argv=screenresolution list 14:38:02 INFO - Available Modes on Display 0 14:38:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:38:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:38:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:38:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:38:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:38:02 INFO - Intel Iris: 14:38:02 INFO - Chipset Model: Intel Iris 14:38:02 INFO - Type: GPU 14:38:02 INFO - Bus: Built-In 14:38:02 INFO - VRAM (Dynamic, Max): 1536 MB 14:38:02 INFO - Vendor: Intel (0x8086) 14:38:02 INFO - Device ID: 0x0a2e 14:38:02 INFO - Revision ID: 0x0009 14:38:02 INFO - Displays: 14:38:02 INFO - Display: 14:38:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:38:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:38:02 INFO - Display Serial Number: 1600x1200 60 14:38:02 INFO - Main Display: Yes 14:38:02 INFO - Mirror: Off 14:38:02 INFO - Online: Yes 14:38:02 INFO - Rotation: Supported 14:38:02 INFO - Return code: 0 14:38:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:38:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:38:02 INFO - Python 2.7.3 14:38:02 INFO - Return code: 0 14:38:02 INFO - grabbing minidump binary from tooltool 14:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:02 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 0x107caad30>, '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 0x107b11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ca2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:38:02 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 14:38:02 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 14:38:02 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 14:38:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:38:02 INFO - Return code: 0 14:38:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:38:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:38:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:38:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:38:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:38:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0267', '--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 14:38:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0267 --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 14:38:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2sNC1oQPCv/Render', 14:38:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:02 INFO - 'HOME': '/Users/cltbld', 14:38:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:02 INFO - 'LOGNAME': 'cltbld', 14:38:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:38:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:38:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:02 INFO - 'MOZ_NO_REMOTE': '1', 14:38:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:38:02 INFO - 'NO_EM_RESTART': '1', 14:38:02 INFO - 'PAGER': '/bin/cat', 14:38:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:02 INFO - 'PWD': '/builds/slave/test', 14:38:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:38:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:02 INFO - 'SHELL': '/bin/bash', 14:38:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6d4vl51Li5/Listeners', 14:38:02 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 14:38:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:02 INFO - 'USER': 'cltbld', 14:38:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:02 INFO - 'XPC_FLAGS': '0x0', 14:38:02 INFO - 'XPC_SERVICE_NAME': '0', 14:38:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0267', '--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 14:38:02 INFO - mozversion application_buildid: 20161118124722 14:38:02 INFO - mozversion application_changeset: f09e137ead39230eaa94f47988ccce2cfcda4195 14:38:02 INFO - mozversion application_display_name: Nightly 14:38:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:38:02 INFO - mozversion application_name: Firefox 14:38:02 INFO - mozversion application_remotingname: firefox 14:38:02 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:38:02 INFO - mozversion application_vendor: Mozilla 14:38:02 INFO - mozversion application_version: 53.0a1 14:38:02 INFO - mozversion platform_buildid: 20161118124722 14:38:02 INFO - mozversion platform_changeset: f09e137ead39230eaa94f47988ccce2cfcda4195 14:38:02 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:38:02 INFO - mozversion platform_version: 53.0a1 14:38:02 INFO - using testdate: 1479508682 14:38:02 INFO - actual date: 1479508682 14:38:02 INFO - starting webserver on 'localhost:49242' 14:38:02 INFO - SUITE-START | Running 2 tests 14:38:02 INFO - TEST-START | dromaeo_css 14:38:02 INFO - operating with platform_type : mac_ 14:38:02 INFO - Initialising browser for dromaeo_css test... 14:38:02 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/tmpDdDIyN/profile http://localhost:49242/getInfo.html) 14:38:06 INFO - PROCESS | 1642 | 2016-11-18 14:38:06.040 plugin-container[1644:7625] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3b, name = 'com.apple.tsm.portname' 14:38:06 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:06 INFO - PROCESS | 1642 | 2016-11-18 14:38:06.043 plugin-container[1644:7625] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f13, name = 'com.apple.CFPasteboardClient' 14:38:06 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:06 INFO - PROCESS | 1642 | 2016-11-18 14:38:06.043 plugin-container[1644:7625] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:38:06 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 14:38:06 INFO - PROCESS | 1642 | colorDepth:24 14:38:06 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 14:38:06 INFO - PROCESS | 1642 | __metrics 14:38:06 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 14:38:06 INFO - TEST-INFO | 1642: exit 0 14:38:06 INFO - Browser initialized. 14:38:06 INFO - Running cycle 1/1 for dromaeo_css test... 14:38:06 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/tmpDdDIyN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:38:09 INFO - PROCESS | 1645 | 2016-11-18 14:38:09.633 plugin-container[1646:7746] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x453f, name = 'com.apple.tsm.portname' 14:38:09 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:09 INFO - PROCESS | 1645 | 2016-11-18 14:38:09.636 plugin-container[1646:7746] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4613, name = 'com.apple.CFPasteboardClient' 14:38:09 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:09 INFO - PROCESS | 1645 | 2016-11-18 14:38:09.636 plugin-container[1646:7746] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:38:19 INFO - PROCESS | 1645 | RSS: Main: 242835456 14:38:19 INFO - PROCESS | 1645 | 14:39:08 INFO - PROCESS | 1645 | 1479508748909 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] 14:41:22 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 14:41:24 INFO - PROCESS | 1645 | RSS: Main: 249466880 14:41:24 INFO - PROCESS | 1645 | 14:44:26 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 14:44:27 INFO - PROCESS | 1645 | RSS: Main: 260317184 14:44:27 INFO - PROCESS | 1645 | 14:47:29 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 14:47:29 INFO - PROCESS | 1645 | RSS: Main: 264683520 14:47:29 INFO - PROCESS | 1645 | 14:50:31 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 14:50:32 INFO - PROCESS | 1645 | RSS: Main: 263294976 14:50:32 INFO - PROCESS | 1645 | 14:53:35 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 14:53:35 INFO - PROCESS | 1645 | RSS: Main: 268382208 14:53:35 INFO - PROCESS | 1645 | 14:56:38 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 14:56:39 INFO - PROCESS | 1645 | RSS: Main: 271011840 14:56:39 INFO - PROCESS | 1645 | 14:56:39 INFO - PROCESS | 1645 | __start_tp_report 14:56:39 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 14:56:39 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 14:56:39 INFO - PROCESS | 1645 | |i|pagename|runs| 14:56:39 INFO - PROCESS | 1645 | |0;dojo.html;2955.822650640962;3297.2086699192196;3310.652381499941;3342.715869151122;3358.865645374185;5519.558435325174;5567.33192016958;5626.2967129108865;5634.6619202847805;5678.48893599576;5040.344755181821;5395.082835917893;5526.115821468566;5598.860028499279;5761.53907687384;4214.304639734441;4790.736509491977;5030.346055012843;5238.659487133344;5259.86850328741;5077.543021128097;5229.764660590283;5235.450277720842;5278.102722537178;5286.973565132169;4471.7987690554;5043.974780126113;5171.250168725552;5175.171972484403;5242.239875218091;3703.888883333477;4744.5492678195515;5066.847994560169;5147.382314122292;5160.716160611165;4365.3451982202605;5022.070916880389;5072.442031376546;5107.87230319245;5139.12634852076;5451.700156491392;5522.806701765419;5527.060399732055;5572;5612.747426365823;6127.5710700251;6132.938670613281;6141.232345956755;6158.599691020069;6184.659843708594;6200.937990620127;6273.968630156865;6283.8743225135695;6308.3060863305;6315.842103947438;5623.803166889139;5643.5767317451355;5757.9712101439645;5779.104238842986;5816.680082595413;6299;6299.559030867887;6300.086487459293;6320.55756097078;6344.302126766052;6109.358517355702;6119.847003824851;6124.846878828065;6125.326214116443;6145.723442445101;6074.119252708332;6086.60437071589;6118.021116621319;6119.143319935212;6232.688365581794;6135.018397056449;6145.539084568675;6150.630962142286;6163.599366041193;6227.252729672377;6170.382961703794;6171.352008039181;6171.84570385735;6190.938090619126;6195.81412557624;4587.72473651582;4824.951750482521;4881.95118048822;4889.755512224375;4920.261960705922;4001.799910004488;4071.470708807836;4349.064951035542;4390.3414487827085;4475.149721552894;5568.359638641527;5768.971155144198;5783.797567085132;5789.942100579025;5792.4497172768515;6097.085437184369;6279.340669229757;6300.747970081237;6319.6208227506495;6324.272708638474;5567.888642227133;5706.229658995982;5711.8857622848145;5719.28508936383;5719.742611582489;5280.075986702312;5296.25852380667;5329.8934021319355;5339.519443250048;5407.729613519308;6251.78118765841;6295.527835412362;6331.05034244867;6332.303446620868;6333.20834895638;6105.9694701526205;6164.537659675452;6253.499720022478;6256;6279.591826531261;6231.906521402273;6265.373462653698;6278.121063051268;6323.652199129092;6346.270178929482;5208.5312321891215;6041.426064523863;6059.515238780798;6125.0218003337495;6214.223222097238;6145.631262124287;6165.568410211422;6232.096346029891;6285.622862628257;6299.181106456132;5816.447437493432;6032.939670603238;6037.09443583466;6074.605150665193;6115.205023346936;5753.453421924911;5817.418258174318;5832.62087964281;6013.488853447331;6054.061620448838;5650.491455769001;5692.914606280992;5706.657600543981;5746.224259724883;5789.45000224978;6050.273967123973;6050.818475445743;6078.179445774764;6081.604695694766;6081.969590152021;5792.681402522901;5820.883582328414;5827.155062515996;5834.562407819346;5843.415658434123 14:56:39 INFO - PROCESS | 1645 | |1;ext.html;466.5381272540185;520.5809323494587;552.2627292564424;563.636454486856;579.1978110317209;31982.68017319826;36389.63610363896;38449;38612.42081368778;38658.840234793;39167.21665566687;39989;39994;40025.39961900574;40079.39880901782;9048.909510904872;9229.492377919212;9354.345195836295;9384.530773461334;9402.294827887901;7903.762887113396;9156.587953542074;9244.630214791401;9346.626134954593;9352.20506256969;9271.860922086174;9279.118483744067;9335.206507446876;9336.766580835465;9344.159025687686;9169.312301577409;9224.07759224079;9304.209142222919;9315.115064068903;9368.90631093694;9159.450432974043;9230.86153707695;9252.537373131316;9281.675141370088;9287.953560232223;32475.188120296945;38305.23389532194;38769.41845872314;38818.611813881784;38926;3191.7446604271604;3198.8080715157166;3273.9672603273902;3328.8169150696704;3378.1385746634696;29723.405531889457;29979.850100749576;29992.800287988455;30487;30704.53943190854;2922.517784565544;2957.349383135707;2966.7478264347556;3022.22933152047;3071.5546245794458;463407;469728;471159;472519;473453;32214.19464513359;57719.55701107422;58040.54898627568;58084.838303234545;58144.709276454196;28318.85840570784;34040.65959340424;34428.48357274643;34436;34495.65504344975;14057.648558786112;14187.858121418654;14191.432342706175;14197.929010354883;14240;11923.821142682868;11969.222000569935;12058.216215945935;12077.637670869712;12085.395730213455;7490.812729681802;7539.547627142279;7569.318761311509;7573.318401343906;7619.771406857803;2893.7106289370895;3237.2877966847254;3244.6430892601793;3246.642869284377;3263.738900887923;859.621766422772;884.9030773198908;907.1880666803174;939.5302348825587;1015.6648306058983;29230.26924326909;29722.85138574293;29923.551146732818;30452.390952181275;32087.197820054684;928.4336554701626;948.0661548374845;987.4075554667143;1020.642775028734;1032.3031953431405;12757.617271481871;15108.773368399483;15177;15303.923480382527;15474.84525154734;571084;651113;658342;659465;659628.4037159567;51560.74219628878;55032.724836376365;55102.173467398025;55555.722221388634;55694.44305556973;1279.5649479177125;1315.9013074019488;1322.1934619881865;1337.1375462826422;1353.7833946568699;22615.321540353816;27304.180874573794;28855.422891542472;29332.12003639894;29692.406151877272;49533.25700114429;53963.730181348845;54771.90456190934;54974.72512637411;58495;603220;607332;607802;608398.9580052071;608925;40291.597084028785;41095;41336.37995430011;41521.584784151775;41649.58350416457;45121.77439112915;45587.54412455833;45881.311780322656;46065.53934460747;46414.76792616015;34326;35980.820095899355;36872.07819804527;38194.0451488715;38402.039949000384;29589.26026849346;31811;38132.6186738129;39055.609443905196;39087.6091239084;23662.763372366055;29418.558721618763;29565.704342956295;29942.85028574843;31714;31116.377672446877;32906.835465822514;33978.32043359069;34389.48415773815;34640.960771175894;25075.87462062678;25161.370965726004;25278.74721252764;25472.638800255147;25787.355316117246 14:56:39 INFO - PROCESS | 1645 | |2;jquery.html;361.1729140268783;362.2374900833745;368.55957131228183;374.55428040631665;392.9960700392995;17609.55976100595;17707.1146442678;17878.993881713108;18295.54261143472;19342;18318;19071.569632277566;19132.808671913277;19422.51443713904;19448.805511944876;5758.9424105759035;6054.60645058072;6072.878542429149;6077.361877002917;6081.939180608204;4849.660523763338;5818.767249310023;5858.589898707092;5866.03210470274;5867.295924489067;5791.507721843648;5806.477417032466;5810.854728631776;5822.330432000311;5842.123681447774;5558.610897237195;5910.763569457216;5951.58338916276;6021.916055110079;6023.397660233985;5540.36285827131;5714.771409143629;5793.797217097382;5796.072628379439;5797.507211886995;12882.613521594367;12897.226166430044;12942.22346659194;12952.417141228667;16854.578635534086;2088.1960445228547;2128.404046866864;2214.9114035438565;2264.30938563738;2485.638643288865;9173.128552787475;9822.410655360702;10149.391036537758;10208.642697505551;10303.484825758756;1804.9458516244533;1842.4933143385676;1849.7317888906168;1851.1207176591147;1871.419859843453;117981;122088.16867746902;122515.3874230632;122730;122982;11136;11391.829122563167;11404.828927566094;11448.484818183202;11615.941920290345;13834.23911684867;14104;14111.576652700436;14117.08238964464;14142.36359363831;7996.520208787491;8308.715103346629;8351.707690230813;8450.366222533334;8455.577221138918;8115.3507719382305;8141.715039973691;8147.511149331059;8314.459560128573;8341.20758527939;4345.239583072949;4459.7958551190995;4538.750368729751;4559.794809233593;4579.290210017452;1867.7105048717472;1937.3122541497846;1941.26232031827;1946.4160751774411;1949.2982526290525;774.4230548241596;821.5153059694809;860.8493513635088;917.7567944494714;919.6367434863191;17278.481645550655;18728.595355348403;20723.8963805183;20923.37229883106;21039.78960210378;880.317753740856;892.1925657280171;911.8449863523217;917.3624331089962;973.6494861849728;10271.94864025675;10334.759828820439;10588.523516441779;11044.834327485094;11047.281926674741;566564;573585;585630;586399;587163;121071.39464302621;126377.36811315885;127074;129709.35145324212;131898.34050829685;862.8490014247482;926.8702381666707;931.7996630724426;986.6842610364758;988.1847475832467;15501.224938753243;18671.253149873854;18978.620427591093;18991.71512427342;19018.42944711661;47181;51546.969060619325;53620.73189634027;53791;54375.18437223524;56528.15207771969;75071.87392189231;75449.49101017839;76543;77953.61023194848;7664.655090520829;7827.412944029106;7928.326092282221;7933.206679332021;8064.395170362129;9039.68361107358;9743.366681165702;12213.633590992284;12516.561920332744;12554.43505042257;10548.472576370998;10673.89326106729;10796.190285728444;11030.448477575928;11090.5563777448;10449.373037617766;10522.579096836042;10551.841722374324;10615.840762388416;10986.835197471884;10411.21915856329;10697.090747286567;10846.349219046882;11238.381888996184;11250.83123753128;7613.695452182073;7831.569263690551;7888.84222315562;7924.20757924203;7975.72084977023;9829.557669905014;10757.139428845518;11000.889991099986;11088.889111108785;11145.331280123219 14:56:39 INFO - PROCESS | 1645 | |3;mootools.html;1372.3755691160522;1479.926003699815;1610.911399873008;1661.4185035237667;1790.999999999999;7593.810154746134;7855.214478552142;8094.757157285284;8497.447665901713;8562.785930351743;7967.521948683068;8302.626381812817;8326.542040187787;8401.99176098869;8427.578621068937;2282.224043825099;2308.2498188088853;2319.524497478021;2323.674685544025;2326.1974618756503;2289.2445492987276;2325.6744055832196;2344.4724936889147;2345.7654234576576;2357.316378250305;2281.7490076091617;2289.027163455533;2307.0310469602723;2317.8377513574055;2320.918767843117;2275.6245219538755;2286.965695514569;2300.6549017647403;2305.3775480620307;2315.0623997281127;2299.8965046572775;2325.93022209334;2328.2433209207074;2330.8018818400287;2336.556054349668;8391.580420978988;8450.281726053292;8493.447925884797;8510.148985101503;8541.701040463555;5088.389393272794;5222.73886305687;5249.973750131225;5253.474652534755;5260.947390526084;3120.251139726472;3202.6957439043254;3255.137388592025;3380.6619338066;3510.3856825055514;951.857221416786;952.2286947572488;1030.8711411073616;1046.4610725476387;1048.2295512798087;9522.047795220491;10649.57401703931;10751.946240268904;10980.615678451215;11024.50389732464;4314.71954322968;4399.868003959887;4455.554444555519;4508.594226519629;4514.277715565494;8562.272206862486;8686.782830429163;8696.086910874394;8715.302775777922;8727.258183054384;5106.310648062538;5110.616703747234;5119.564836988898;5120.641555091108;5135.024345374367;4305.311150216013;4329.874232699458;4720.244760838249;4724.173269677793;4805.927911081337;3552.9215408985515;3603.9459408108373;3675.301692678436;3703.333399987974;3707.610700876423;1138.8633363996207;1145.8223975283845;1153.1121036801617;1154.8325492803497;1160.2980196980704;778.4084096087015;807.1928071928072;812.2162113560443;901.1259078693656;903.76953876761;4782.234842425265;4995.600351971833;5631.662100273997;5674.148877668383;5682.176084467729;414.8879802453321;423.3163441042755;429.0603578163811;429.8645926533132;430.2234466787416;3819.2170605025904;3843.7309388342546;3957.6240257175523;3962.7424217425773;3992.860249891298;9711.611535538499;10031.29780915329;10181.134603558781;10326.01902819231;10345.586176552853;8684.956575217084;9998.900010999942;10128.898711012944;10287.331323463952;10303.124234440007;7710.614469276514;7938.047434307805;7945.205479452008;7949.880751788728;7954.403419743427;5147.047796157723;5590.021746194318;5623.268975033383;5643.322801263875;5664.065429204135;9307.441553506811;9311.301652376234;9447.14975652167;9483.146516813244;9486;7968.960155199419;9124.45253284805;9393.248540116638;9495.05049495045;9618.89382720968;6627.204735431779;6853.211880633591;7039.577625342496;7202.603856787927;7341.559506429631;7912.683492660229;7978.84042319162;7996.160403157817;8008.399370047153;8111.472754270953;7185.568865868065;7273.599952002691;7309.451791115794;7328.2305357938785;7445.888311675221;7056;7122.85754284901;7158.212596614472;7253.528520646141;7297.708091676486;6692.6988285526295;6860.451163906776;6871.553349032297;6874.243833178446;6887.380135787603;4834.5890599298045;4924.581410580141;4942.8764280893265;4951.579115775199;4961;4822.445418776885;4892.339534162842;4966.751662416937;5104.387473503203;5113.92329115071 14:56:39 INFO - PROCESS | 1645 | |4;prototype.html;289.2292041708847;297.28502950404277;302.8637113299016;307.36989172196996;313.2982120051086;4780.569748722614;5271.314729085223;5315.78736850526;5420.295361602991;5441.564674826014;4924.6211060902915;5280.947190528093;5380.865478363043;5403.675779453236;5408.486193811592;3668.9082772930624;4112.814923328458;4131.586841315874;4161.292580261348;4172.290710579194;3637.963620363802;3673.5040769496177;4162.980069882883;4176.582341765829;4198.118395137009;3383.17112307485;3795.6583907448326;4006.879793606182;4028.5568587455355;4191.55988621195;3536.398812201919;4007.3788562772525;4008.9977505623738;4019.31671615826;4069.9389509157386;3103.8758449662214;3170.0648308748973;3334.549835772188;3467.7745946513396;3488.9127771805656;3934.252492026506;4136.345461815268;4497.482789479219;5262.184361423985;5273.31446911899;4039.8586049488126;4196.979015104936;4236.322188449864;4635.884102897421;4651.837185698518;5000.249962505617;5460.918086228709;5682.431756824326;5685.204071430003;5705.74324155414;3371.4882412602524;3496.318217947501;3803.82882770276;3810.466534685146;3830.253100645375;5542.75057622408;5604.579656525736;5617.775288988436;5782.653040817564;5917.437843404869;3694.7228957827733;3870.0905287257474;3874.3219936511;3876.2441323942126;3882.6699730522655;3322.5846769153854;3645.5625324960643;3772.2266935278203;3776;3925.156091440354;4210.221109094766;4560.247559152769;4601.838935637236;4642.326862604971;4653.86038418848;5439.755211015514;5660.915086273709;5682.221322820361;5715.199872017921;5730.799422020209;1268.6447794617523;1291.8708129187194;1295.7667619828333;1297.4874924404996;1310.994178906275;787.8739401695701;865.7489927055344;877.2674816528271;884.827458645558;887.4497811357;4642.837500687528;4976.676516026447;5070.873228169252;5079.806245532297;5112.4120726116225;4811.591014763714;5027.748612569357;5226.686398816083;5291.894162116736;5671.659700417906;4403.3174857897075;4951.821198672573;5097.566706829961;5160.638755287094;5246.790128394898;2435.7077150742025;2440.8535487870786;2473.134402958986;2490.589052806267;2898.2610433739587;5715.628484148517;5731.283589551306;5765.933302339081;5809.796657116981;5856.267966504187;5346.4386239445075;5679.914801277984;5783.94216057834;5871.266091738533;6131.417515336036;6176.66028368444;6180.6600636965395;6187.907181392283;6188.4739797116845;6245.250569931637;4651.930221046619;5946.5540084494705;5993.70031498432;6006.459418652343;6046.153538504701;4957.578605818546;5909.23179986599;5919.7864437789285;5944.3164036136395;5979.671118088547;5526.557875370041;5852.7658893643775;6075.392460753889;6197.4732147767945;6243.219597550306;3131.843407829554;3600.243948770683;3907.199024199975;3956.84172633103;3957.0305275207756;4928.950710492849;5860.677662728421;5943.554233432424;5999.730012149551;6004.699765011819;5440.871498888076;5677.460641239076;5700.429957004267;5719.799807006735;5799.391063938395;5692.516136128475;5701.061639042579;5715.542756579547;5721.914171287517;5729.453047677187;4533.574537622356;4545.727256364628;5267.97274531478;5583.860403489783;5629.915551266652;4269.252880745919;4425.424694789656;4764.809407623657;5632.408597097409;5698.8290351289525;4476.171908196994;4947.6393991652585;5578.30271216098;5581.804636837691;5611.971940140274 14:56:39 INFO - PROCESS | 1645 | |5;yui.html;647.7085311609775;683.9487038472116;738.5531753289263;762.2720302111484;773.6557232031746;11942.223755455889;12121.515139394425;13850;14699.677029067381;14775.926120369397;13203.867961320384;13361.665958351046;13362.131461455;13364;13403.865961340383;616.214326733414;638.4254171245869;641.8106658535731;673.2762280548405;822.3955392786297;794.8728203487443;803.148662417839;804.5293503300547;806.7458750493605;808.7250334886138;847.559269180026;849.1890244816186;857.3098655582254;857.6483641706901;858.159004175908;815.107457334224;849.9320054395664;850.5619605902903;851.2806678356809;851.3104385447807;609.6128958111631;631.0376675569747;638.9520785941027;639.0382474376081;640.0207682246171;13525.120867143603;13673.154124193206;13696.520621778289;13712.24582647954;14114.92942535291;10421.478926053667;11894.940525297317;11946.462409191607;13319.800202996963;13908.141899826565;12131.93934030333;12166.843091358158;12294.569690060895;12412.503499859995;12474.563390281388;4081.6530594899173;4113.85601503946;4118.299889018874;4133.524644665872;4138.151678905817;81169.78245326325;81730.18269817285;84886;88960.5551972249;90024.54987725019;10142.532637727525;10160.593576256866;10217.489125543692;10298.124659403884;10592.205584581186;15878.364865405485;18434.723479147822;18787.812121878607;18923.24307027731;18968.43094707161;7294.8905766413545;7323.157836848724;7402.1487528933785;7447.702091916262;7505.5871927043445;4078.8368465261055;4102.958970410317;4135;4156.418101465797;4168.333066709312;2865.355295058637;3256.3324518473655;3258.169166862482;3271.1004473769426;3299;573.8163787588015;585.3590318601125;591.9467247947705;596.9701514924236;597.8415719834247;554.9250851135126;588.4586180713753;593.8456001439657;607.6293460988793;632.3107812484415;8009.759707208793;8455.915440845512;8575.571221438902;8593;8602.18279263469;562.4291344285554;606.1756011823916;606.9575129740875;609.2445367744054;616.608453631938;3837.1558257183374;3992.960070399317;4049.655779258737;4208.053188032668;4209.7474151551005;62966.68516657387;78867;85327;88625;113477;51434;65668.34331656623;79029.60485197537;82208.17791822125;93582.06417935733;54859.72570137204;55124.72437637786;72324;77763;78883;9376.437413754924;9564.33049686543;9700.223982081558;9713.27150463704;9747.317687762072;50073.24890126578;51722.22416663678;52696.83199104145;53317.46682533125;53600.195997059294;58164.70917645385;68588.62822743617;70097.94853077106;77268;80111.99720007046;9135.817283654422;10436.895631043591;12141.332226727614;12190.329531875832;12460.252384856938;13706.451741930212;13790.724185516434;13806.240656763974;13846.584602461942;14128.717425651224;12790.744185116431;12821.807672885101;12892.097553171188;13361.53234636783;13456.192628442324;11925.284482931052;12219.98571521327;12362.44369003379;12631.11582189238;12669.493220271086;12516.123871329284;12597.748045038865;12661.93669031649;12727.554535591209;12759.681007974876;10361.533730981973;10450.477476126316;10600.840987385349;11192.38441885704;11197.38414387184;10677.89322106769;10948.507317170586;11157.49373834522;11257.268277561932;11318.094552435621 14:56:39 INFO - PROCESS | 1645 | __end_tp_report 14:56:39 INFO - PROCESS | 1645 | __start_cc_report 14:56:39 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,367 14:56:39 INFO - PROCESS | 1645 | __end_cc_report 14:56:39 INFO - PROCESS | 1645 | __startTimestamp1479509799114__endTimestamp 14:56:39 INFO - PROCESS | 1645 | 14:56:39 INFO - PROCESS | 1645 | ------- Summary: start ------- 14:56:39 INFO - PROCESS | 1645 | Number of tests: 6 14:56:39 INFO - PROCESS | 1645 | 14:56:39 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5647.17 Median:5816.45 stddev:705.93 (12.1%) stddev-sans-first:675.79 14:56:39 INFO - PROCESS | 1645 | Values: 2955.8 3297.2 3310.7 3342.7 3358.9 5519.6 5567.3 5626.3 5634.7 5678.5 5040.3 5395.1 5526.1 5598.9 5761.5 4214.3 4790.7 5030.3 5238.7 5259.9 5077.5 5229.8 5235.5 5278.1 5287.0 4471.8 5044.0 5171.3 5175.2 5242.2 3703.9 4744.5 5066.8 5147.4 5160.7 4365.3 5022.1 5072.4 5107.9 5139.1 5451.7 5522.8 5527.1 5572.0 5612.7 6127.6 6132.9 6141.2 6158.6 6184.7 6200.9 6274.0 6283.9 6308.3 6315.8 5623.8 5643.6 5758.0 5779.1 5816.7 6299.0 6299.6 6300.1 6320.6 6344.3 6109.4 6119.8 6124.8 6125.3 6145.7 6074.1 6086.6 6118.0 6119.1 6232.7 6135.0 6145.5 6150.6 6163.6 6227.3 6170.4 6171.4 6171.8 6190.9 6195.8 4587.7 4825.0 4882.0 4889.8 4920.3 4001.8 4071.5 4349.1 4390.3 4475.1 5568.4 5769.0 5783.8 5789.9 5792.4 6097.1 6279.3 6300.7 6319.6 6324.3 5567.9 5706.2 5711.9 5719.3 5719.7 5280.1 5296.3 5329.9 5339.5 5407.7 6251.8 6295.5 6331.1 6332.3 6333.2 6106.0 6164.5 6253.5 6256.0 6279.6 6231.9 6265.4 6278.1 6323.7 6346.3 5208.5 6041.4 6059.5 6125.0 6214.2 6145.6 6165.6 6232.1 6285.6 6299.2 5816.4 6032.9 6037.1 6074.6 6115.2 5753.5 5817.4 5832.6 6013.5 6054.1 5650.5 5692.9 5706.7 5746.2 5789.5 6050.3 6050.8 6078.2 6081.6 6082.0 5792.7 5820.9 5827.2 5834.6 5843.4 14:56:39 INFO - PROCESS | 1645 | 14:56:39 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:68581.44 Median:38872.31 stddev:154808.35 (398.2%) stddev-sans-first:155158.15 14:56:39 INFO - PROCESS | 1645 | Values: 466.5 520.6 552.3 563.6 579.2 31982.7 36389.6 38449.0 38612.4 38658.8 39167.2 39989.0 39994.0 40025.4 40079.4 9048.9 9229.5 9354.3 9384.5 9402.3 7903.8 9156.6 9244.6 9346.6 9352.2 9271.9 9279.1 9335.2 9336.8 9344.2 9169.3 9224.1 9304.2 9315.1 9368.9 9159.5 9230.9 9252.5 9281.7 9288.0 32475.2 38305.2 38769.4 38818.6 38926.0 3191.7 3198.8 3274.0 3328.8 3378.1 29723.4 29979.9 29992.8 30487.0 30704.5 2922.5 2957.3 2966.7 3022.2 3071.6 463407.0 469728.0 471159.0 472519.0 473453.0 32214.2 57719.6 58040.5 58084.8 58144.7 28318.9 34040.7 34428.5 34436.0 34495.7 14057.6 14187.9 14191.4 14197.9 14240.0 11923.8 11969.2 12058.2 12077.6 12085.4 7490.8 7539.5 7569.3 7573.3 7619.8 2893.7 3237.3 3244.6 3246.6 3263.7 859.6 884.9 907.2 939.5 1015.7 29230.3 29722.9 29923.6 30452.4 32087.2 928.4 948.1 987.4 1020.6 1032.3 12757.6 15108.8 15177.0 15303.9 15474.8 571084.0 651113.0 658342.0 659465.0 659628.4 51560.7 55032.7 55102.2 55555.7 55694.4 1279.6 1315.9 1322.2 1337.1 1353.8 22615.3 27304.2 28855.4 29332.1 29692.4 49533.3 53963.7 54771.9 54974.7 58495.0 603220.0 607332.0 607802.0 608399.0 608925.0 40291.6 41095.0 41336.4 41521.6 41649.6 45121.8 45587.5 45881.3 46065.5 46414.8 34326.0 35980.8 36872.1 38194.0 38402.0 29589.3 31811.0 38132.6 39055.6 39087.6 23662.8 29418.6 29565.7 29942.9 31714.0 31116.4 32906.8 33978.3 34389.5 34641.0 25075.9 25161.4 25278.7 25472.6 25787.4 14:56:39 INFO - PROCESS | 1645 | 14:56:39 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:33816.70 Median:361.71 stddev:97076.66 (26838.6%) stddev-sans-first:97316.48 14:56:39 INFO - PROCESS | 1645 | Values: 361.2 362.2 368.6 374.6 393.0 17609.6 17707.1 17879.0 18295.5 19342.0 18318.0 19071.6 19132.8 19422.5 19448.8 5758.9 6054.6 6072.9 6077.4 6081.9 4849.7 5818.8 5858.6 5866.0 5867.3 5791.5 5806.5 5810.9 5822.3 5842.1 5558.6 5910.8 5951.6 6021.9 6023.4 5540.4 5714.8 5793.8 5796.1 5797.5 12882.6 12897.2 12942.2 12952.4 16854.6 2088.2 2128.4 2214.9 2264.3 2485.6 9173.1 9822.4 10149.4 10208.6 10303.5 1804.9 1842.5 1849.7 1851.1 1871.4 117981.0 122088.2 122515.4 122730.0 122982.0 11136.0 11391.8 11404.8 11448.5 11615.9 13834.2 14104.0 14111.6 14117.1 14142.4 7996.5 8308.7 8351.7 8450.4 8455.6 8115.4 8141.7 8147.5 8314.5 8341.2 4345.2 4459.8 4538.8 4559.8 4579.3 1867.7 1937.3 1941.3 1946.4 1949.3 774.4 821.5 860.8 917.8 919.6 17278.5 18728.6 20723.9 20923.4 21039.8 880.3 892.2 911.8 917.4 973.6 10271.9 10334.8 10588.5 11044.8 11047.3 566564.0 573585.0 585630.0 586399.0 587163.0 121071.4 126377.4 127074.0 129709.4 131898.3 862.8 926.9 931.8 986.7 988.2 15501.2 18671.3 18978.6 18991.7 19018.4 47181.0 51547.0 53620.7 53791.0 54375.2 56528.2 75071.9 75449.5 76543.0 77953.6 7664.7 7827.4 7928.3 7933.2 8064.4 9039.7 9743.4 12213.6 12516.6 12554.4 10548.5 10673.9 10796.2 11030.4 11090.6 10449.4 10522.6 10551.8 10615.8 10986.8 10411.2 10697.1 10846.3 11238.4 11250.8 7613.7 7831.6 7888.8 7924.2 7975.7 9829.6 10757.1 11000.9 11088.9 11145.3 14:56:39 INFO - PROCESS | 1645 | 14:56:39 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5402.28 Median:5105.35 stddev:2981.18 (58.4%) stddev-sans-first:2974.16 14:56:39 INFO - PROCESS | 1645 | Values: 1372.4 1479.9 1610.9 1661.4 1791.0 7593.8 7855.2 8094.8 8497.4 8562.8 7967.5 8302.6 8326.5 8402.0 8427.6 2282.2 2308.2 2319.5 2323.7 2326.2 2289.2 2325.7 2344.5 2345.8 2357.3 2281.7 2289.0 2307.0 2317.8 2320.9 2275.6 2287.0 2300.7 2305.4 2315.1 2299.9 2325.9 2328.2 2330.8 2336.6 8391.6 8450.3 8493.4 8510.1 8541.7 5088.4 5222.7 5250.0 5253.5 5260.9 3120.3 3202.7 3255.1 3380.7 3510.4 951.9 952.2 1030.9 1046.5 1048.2 9522.0 10649.6 10751.9 10980.6 11024.5 4314.7 4399.9 4455.6 4508.6 4514.3 8562.3 8686.8 8696.1 8715.3 8727.3 5106.3 5110.6 5119.6 5120.6 5135.0 4305.3 4329.9 4720.2 4724.2 4805.9 3552.9 3603.9 3675.3 3703.3 3707.6 1138.9 1145.8 1153.1 1154.8 1160.3 778.4 807.2 812.2 901.1 903.8 4782.2 4995.6 5631.7 5674.1 5682.2 414.9 423.3 429.1 429.9 430.2 3819.2 3843.7 3957.6 3962.7 3992.9 9711.6 10031.3 10181.1 10326.0 10345.6 8685.0 9998.9 10128.9 10287.3 10303.1 7710.6 7938.0 7945.2 7949.9 7954.4 5147.0 5590.0 5623.3 5643.3 5664.1 9307.4 9311.3 9447.1 9483.1 9486.0 7969.0 9124.5 9393.2 9495.1 9618.9 6627.2 6853.2 7039.6 7202.6 7341.6 7912.7 7978.8 7996.2 8008.4 8111.5 7185.6 7273.6 7309.5 7328.2 7445.9 7056.0 7122.9 7158.2 7253.5 7297.7 6692.7 6860.5 6871.6 6874.2 6887.4 4834.6 4924.6 4942.9 4951.6 4961.0 4822.4 4892.3 4966.8 5104.4 5113.9 14:56:39 INFO - PROCESS | 1645 | 14:56:39 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4519.43 Median:5014.00 stddev:1452.79 (29.0%) stddev-sans-first:1421.74 14:56:39 INFO - PROCESS | 1645 | Values: 289.2 297.3 302.9 307.4 313.3 4780.6 5271.3 5315.8 5420.3 5441.6 4924.6 5280.9 5380.9 5403.7 5408.5 3668.9 4112.8 4131.6 4161.3 4172.3 3638.0 3673.5 4163.0 4176.6 4198.1 3383.2 3795.7 4006.9 4028.6 4191.6 3536.4 4007.4 4009.0 4019.3 4069.9 3103.9 3170.1 3334.5 3467.8 3488.9 3934.3 4136.3 4497.5 5262.2 5273.3 4039.9 4197.0 4236.3 4635.9 4651.8 5000.2 5460.9 5682.4 5685.2 5705.7 3371.5 3496.3 3803.8 3810.5 3830.3 5542.8 5604.6 5617.8 5782.7 5917.4 3694.7 3870.1 3874.3 3876.2 3882.7 3322.6 3645.6 3772.2 3776.0 3925.2 4210.2 4560.2 4601.8 4642.3 4653.9 5439.8 5660.9 5682.2 5715.2 5730.8 1268.6 1291.9 1295.8 1297.5 1311.0 787.9 865.7 877.3 884.8 887.4 4642.8 4976.7 5070.9 5079.8 5112.4 4811.6 5027.7 5226.7 5291.9 5671.7 4403.3 4951.8 5097.6 5160.6 5246.8 2435.7 2440.9 2473.1 2490.6 2898.3 5715.6 5731.3 5765.9 5809.8 5856.3 5346.4 5679.9 5783.9 5871.3 6131.4 6176.7 6180.7 6187.9 6188.5 6245.3 4651.9 5946.6 5993.7 6006.5 6046.2 4957.6 5909.2 5919.8 5944.3 5979.7 5526.6 5852.8 6075.4 6197.5 6243.2 3131.8 3600.2 3907.2 3956.8 3957.0 4929.0 5860.7 5943.6 5999.7 6004.7 5440.9 5677.5 5700.4 5719.8 5799.4 5692.5 5701.1 5715.5 5721.9 5729.5 4533.6 4545.7 5268.0 5583.9 5629.9 4269.3 4425.4 4764.8 5632.4 5698.8 4476.2 4947.6 5578.3 5581.8 5612.0 14:56:39 INFO - PROCESS | 1645 | 14:56:39 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:18401.70 Median:50753.62 stddev:25693.31 (50.6%) stddev-sans-first:25730.80 14:56:39 INFO - PROCESS | 1645 | Values: 647.7 683.9 738.6 762.3 773.7 11942.2 12121.5 13850.0 14699.7 14775.9 13203.9 13361.7 13362.1 13364.0 13403.9 616.2 638.4 641.8 673.3 822.4 794.9 803.1 804.5 806.7 808.7 847.6 849.2 857.3 857.6 858.2 815.1 849.9 850.6 851.3 851.3 609.6 631.0 639.0 639.0 640.0 13525.1 13673.2 13696.5 13712.2 14114.9 10421.5 11894.9 11946.5 13319.8 13908.1 12131.9 12166.8 12294.6 12412.5 12474.6 4081.7 4113.9 4118.3 4133.5 4138.2 81169.8 81730.2 84886.0 88960.6 90024.5 10142.5 10160.6 10217.5 10298.1 10592.2 15878.4 18434.7 18787.8 18923.2 18968.4 7294.9 7323.2 7402.1 7447.7 7505.6 4078.8 4103.0 4135.0 4156.4 4168.3 2865.4 3256.3 3258.2 3271.1 3299.0 573.8 585.4 591.9 597.0 597.8 554.9 588.5 593.8 607.6 632.3 8009.8 8455.9 8575.6 8593.0 8602.2 562.4 606.2 607.0 609.2 616.6 3837.2 3993.0 4049.7 4208.1 4209.7 62966.7 78867.0 85327.0 88625.0 113477.0 51434.0 65668.3 79029.6 82208.2 93582.1 54859.7 55124.7 72324.0 77763.0 78883.0 9376.4 9564.3 9700.2 9713.3 9747.3 50073.2 51722.2 52696.8 53317.5 53600.2 58164.7 68588.6 70097.9 77268.0 80112.0 9135.8 10436.9 12141.3 12190.3 12460.3 13706.5 13790.7 13806.2 13846.6 14128.7 12790.7 12821.8 12892.1 13361.5 13456.2 11925.3 12220.0 12362.4 12631.1 12669.5 12516.1 12597.7 12661.9 12727.6 12759.7 10361.5 10450.5 10600.8 11192.4 11197.4 10677.9 10948.5 11157.5 11257.3 11318.1 14:56:39 INFO - PROCESS | 1645 | -------- Summary: end -------- 14:56:39 INFO - PROCESS | 1645 | 14:56:39 INFO - TEST-INFO | 1645: exit 0 14:56:39 INFO - TEST-OK | dromaeo_css | took 1116824ms 14:56:39 INFO - TEST-START | kraken 14:56:39 INFO - operating with platform_type : mac_ 14:56:39 INFO - Initialising browser for kraken test... 14:56:39 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/tmpfzmI3a/profile http://localhost:49242/getInfo.html) 14:56:42 INFO - PROCESS | 1656 | 2016-11-18 14:56:42.762 plugin-container[1657:10047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 14:56:42 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:56:42 INFO - PROCESS | 1656 | 2016-11-18 14:56:42.765 plugin-container[1657:10047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c23, name = 'com.apple.CFPasteboardClient' 14:56:42 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:56:42 INFO - PROCESS | 1656 | 2016-11-18 14:56:42.765 plugin-container[1657:10047] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:56:42 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 14:56:42 INFO - PROCESS | 1656 | colorDepth:24 14:56:42 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 14:56:42 INFO - PROCESS | 1656 | __metrics 14:56:42 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 14:56:43 INFO - TEST-INFO | 1656: exit 0 14:56:43 INFO - Browser initialized. 14:56:43 INFO - Running cycle 1/1 for kraken test... 14:56:43 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/tmpfzmI3a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:56:46 INFO - PROCESS | 1658 | 2016-11-18 14:56:46.494 plugin-container[1659:10157] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.tsm.portname' 14:56:46 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:56:46 INFO - PROCESS | 1658 | 2016-11-18 14:56:46.495 plugin-container[1659:10157] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 14:56:46 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:56:46 INFO - PROCESS | 1658 | 2016-11-18 14:56:46.495 plugin-container[1659:10157] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:56:56 INFO - PROCESS | 1658 | RSS: Main: 243191808 14:56:56 INFO - PROCESS | 1658 | 14:56:59 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 14:57:00 INFO - PROCESS | 1658 | RSS: Main: 255045632 14:57:00 INFO - PROCESS | 1658 | 14:57:02 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 14:57:03 INFO - PROCESS | 1658 | RSS: Main: 262610944 14:57:03 INFO - PROCESS | 1658 | 14:57:07 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 14:57:07 INFO - PROCESS | 1658 | RSS: Main: 265625600 14:57:07 INFO - PROCESS | 1658 | 14:57:09 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 14:57:10 INFO - PROCESS | 1658 | RSS: Main: 266670080 14:57:10 INFO - PROCESS | 1658 | 14:57:12 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:57:12 INFO - PROCESS | 1658 | RSS: Main: 267022336 14:57:12 INFO - PROCESS | 1658 | 14:57:15 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 14:57:16 INFO - PROCESS | 1658 | RSS: Main: 272166912 14:57:16 INFO - PROCESS | 1658 | 14:57:19 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 14:57:20 INFO - PROCESS | 1658 | RSS: Main: 271589376 14:57:20 INFO - PROCESS | 1658 | 14:57:23 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 14:57:24 INFO - PROCESS | 1658 | RSS: Main: 272986112 14:57:24 INFO - PROCESS | 1658 | 14:57:25 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:57:26 INFO - PROCESS | 1658 | RSS: Main: 272183296 14:57:26 INFO - PROCESS | 1658 | 14:57:28 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:57:29 INFO - PROCESS | 1658 | RSS: Main: 272199680 14:57:29 INFO - PROCESS | 1658 | 14:57:31 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:57:32 INFO - PROCESS | 1658 | RSS: Main: 272134144 14:57:32 INFO - PROCESS | 1658 | 14:57:35 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:57:35 INFO - PROCESS | 1658 | RSS: Main: 272531456 14:57:35 INFO - PROCESS | 1658 | 14:57:38 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:57:39 INFO - PROCESS | 1658 | RSS: Main: 272297984 14:57:39 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 14:57:41 INFO - PROCESS | 1658 | RSS: Main: 275435520 14:57:41 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | __start_tp_report 14:57:41 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 14:57:41 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 14:57:41 INFO - PROCESS | 1658 | |i|pagename|runs| 14:57:41 INFO - PROCESS | 1658 | |0;ai-astar;167;173;126;116;118;117;178;174;128;119 14:57:41 INFO - PROCESS | 1658 | |1;audio-beat-detection;147;122;113;144;149;150;119;133;178;120 14:57:41 INFO - PROCESS | 1658 | |2;audio-dft;267;234;210;199;196;176;255;271;192;196 14:57:41 INFO - PROCESS | 1658 | |3;audio-fft;79;57;58;87;63;66;51;65;103;86 14:57:41 INFO - PROCESS | 1658 | |4;audio-oscillator;85;73;88;71;69;88;120;87;70;90 14:57:41 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;92;90;100;81;93;79;136;119;90;126 14:57:41 INFO - PROCESS | 1658 | |6;imaging-darkroom;97;114;155;114;98;96;119;154;100;147 14:57:41 INFO - PROCESS | 1658 | |7;imaging-desaturate;95;81;149;145;92;90;83;94;151;148 14:57:41 INFO - PROCESS | 1658 | |8;json-parse-financial;56;50;47;82;84;86;88;67;57;57 14:57:41 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;72;75;74;85;74;73;74;73;77;87 14:57:41 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;92;85;91;87;89;89;90;93;85;96 14:57:41 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;167;172;151;165;155;166;157;148;186;163 14:57:41 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;183;182;178;179;181;174;175;177;177;181 14:57:41 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;68;69;72;70;69;70;69;81;68;68 14:57:41 INFO - PROCESS | 1658 | __end_tp_report 14:57:41 INFO - PROCESS | 1658 | __start_cc_report 14:57:41 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,955 14:57:41 INFO - PROCESS | 1658 | __end_cc_report 14:57:41 INFO - PROCESS | 1658 | __startTimestamp1479509861437__endTimestamp 14:57:41 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | ------- Summary: start ------- 14:57:41 INFO - PROCESS | 1658 | Number of tests: 14 14:57:41 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:141.60 Median:147.50 stddev:27.41 (18.6%) stddev-sans-first:27.49 14:57:41 INFO - PROCESS | 1658 | Values: 167.0 173.0 126.0 116.0 118.0 117.0 178.0 174.0 128.0 119.0 14:57:41 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:145.50 stddev:19.92 (13.7%) stddev-sans-first:20.83 14:57:41 INFO - PROCESS | 1658 | Values: 147.0 122.0 113.0 144.0 149.0 150.0 119.0 133.0 178.0 120.0 14:57:41 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:219.60 Median:222.00 stddev:34.39 (15.5%) stddev-sans-first:31.91 14:57:41 INFO - PROCESS | 1658 | Values: 267.0 234.0 210.0 199.0 196.0 176.0 255.0 271.0 192.0 196.0 14:57:41 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:71.50 Median:65.50 stddev:16.52 (25.2%) stddev-sans-first:17.30 14:57:41 INFO - PROCESS | 1658 | Values: 79.0 57.0 58.0 87.0 63.0 66.0 51.0 65.0 103.0 86.0 14:57:41 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:84.10 Median:86.00 stddev:15.22 (17.7%) stddev-sans-first:16.14 14:57:41 INFO - PROCESS | 1658 | Values: 85.0 73.0 88.0 71.0 69.0 88.0 120.0 87.0 70.0 90.0 14:57:41 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:100.60 Median:85.50 stddev:19.56 (22.9%) stddev-sans-first:20.50 14:57:41 INFO - PROCESS | 1658 | Values: 92.0 90.0 100.0 81.0 93.0 79.0 136.0 119.0 90.0 126.0 14:57:41 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:119.40 Median:154.50 stddev:23.96 (15.5%) stddev-sans-first:24.01 14:57:41 INFO - PROCESS | 1658 | Values: 97.0 114.0 155.0 114.0 98.0 96.0 119.0 154.0 100.0 147.0 14:57:41 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:112.80 Median:86.50 stddev:30.85 (35.7%) stddev-sans-first:32.05 14:57:41 INFO - PROCESS | 1658 | Values: 95.0 81.0 149.0 145.0 92.0 90.0 83.0 94.0 151.0 148.0 14:57:41 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:67.40 Median:74.50 stddev:16.07 (21.6%) stddev-sans-first:16.51 14:57:41 INFO - PROCESS | 1658 | Values: 56.0 50.0 47.0 82.0 84.0 86.0 88.0 67.0 57.0 57.0 14:57:41 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:76.40 Median:74.50 stddev:5.25 (7.1%) stddev-sans-first:5.33 14:57:41 INFO - PROCESS | 1658 | Values: 72.0 75.0 74.0 85.0 74.0 73.0 74.0 73.0 77.0 87.0 14:57:41 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:89.70 Median:90.50 stddev:3.50 (3.9%) stddev-sans-first:3.61 14:57:41 INFO - PROCESS | 1658 | Values: 92.0 85.0 91.0 87.0 89.0 89.0 90.0 93.0 85.0 96.0 14:57:41 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:163.00 Median:165.50 stddev:11.10 (6.7%) stddev-sans-first:11.67 14:57:41 INFO - PROCESS | 1658 | Values: 167.0 172.0 151.0 165.0 155.0 166.0 157.0 148.0 186.0 163.0 14:57:41 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.70 Median:180.00 stddev:3.02 (1.7%) stddev-sans-first:2.77 14:57:41 INFO - PROCESS | 1658 | Values: 183.0 182.0 178.0 179.0 181.0 174.0 175.0 177.0 177.0 181.0 14:57:41 INFO - PROCESS | 1658 | 14:57:41 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:69.50 stddev:3.92 (5.6%) stddev-sans-first:4.06 14:57:41 INFO - PROCESS | 1658 | Values: 68.0 69.0 72.0 70.0 69.0 70.0 69.0 81.0 68.0 68.0 14:57:41 INFO - PROCESS | 1658 | -------- Summary: end -------- 14:57:41 INFO - PROCESS | 1658 | 14:57:42 INFO - TEST-INFO | 1658: exit 0 14:57:42 INFO - TEST-OK | kraken | took 62716ms 14:57:42 INFO - SUITE-END | took 1179s 14:57:42 INFO - Completed test suite (00:19:39) 14:57:42 INFO - javascript benchmark 14:57:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6942.394957818043, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2955.822650640962, 3297.2086699192196, 3310.652381499941, 3342.715869151122, 3358.865645374185, 5519.558435325174, 5567.33192016958, 5626.2967129108865, 5634.6619202847805, 5678.48893599576, 5040.344755181821, 5395.082835917893, 5526.115821468566, 5598.860028499279, 5761.53907687384, 4214.304639734441, 4790.736509491977, 5030.346055012843, 5238.659487133344, 5259.86850328741, 5077.543021128097, 5229.764660590283, 5235.450277720842, 5278.102722537178, 5286.973565132169, 4471.7987690554, 5043.974780126113, 5171.250168725552, 5175.171972484403, 5242.239875218091, 3703.888883333477, 4744.5492678195515, 5066.847994560169, 5147.382314122292, 5160.716160611165, 4365.3451982202605, 5022.070916880389, 5072.442031376546, 5107.87230319245, 5139.12634852076, 5451.700156491392, 5522.806701765419, 5527.060399732055, 5572.0, 5612.747426365823, 6127.5710700251, 6132.938670613281, 6141.232345956755, 6158.599691020069, 6184.659843708594, 6200.937990620127, 6273.968630156865, 6283.8743225135695, 6308.3060863305, 6315.842103947438, 5623.803166889139, 5643.5767317451355, 5757.9712101439645, 5779.104238842986, 5816.680082595413, 6299.0, 6299.559030867887, 6300.086487459293, 6320.55756097078, 6344.302126766052, 6109.358517355702, 6119.847003824851, 6124.846878828065, 6125.326214116443, 6145.723442445101, 6074.119252708332, 6086.60437071589, 6118.021116621319, 6119.143319935212, 6232.688365581794, 6135.018397056449, 6145.539084568675, 6150.630962142286, 6163.599366041193, 6227.252729672377, 6170.382961703794, 6171.352008039181, 6171.84570385735, 6190.938090619126, 6195.81412557624, 4587.72473651582, 4824.951750482521, 4881.95118048822, 4889.755512224375, 4920.261960705922, 4001.799910004488, 4071.470708807836, 4349.064951035542, 4390.3414487827085, 4475.149721552894, 5568.359638641527, 5768.971155144198, 5783.797567085132, 5789.942100579025, 5792.4497172768515, 6097.085437184369, 6279.340669229757, 6300.747970081237, 6319.6208227506495, 6324.272708638474, 5567.888642227133, 5706.229658995982, 5711.8857622848145, 5719.28508936383, 5719.742611582489, 5280.075986702312, 5296.25852380667, 5329.8934021319355, 5339.519443250048, 5407.729613519308, 6251.78118765841, 6295.527835412362, 6331.05034244867, 6332.303446620868, 6333.20834895638, 6105.9694701526205, 6164.537659675452, 6253.499720022478, 6256.0, 6279.591826531261, 6231.906521402273, 6265.373462653698, 6278.121063051268, 6323.652199129092, 6346.270178929482, 5208.5312321891215, 6041.426064523863, 6059.515238780798, 6125.0218003337495, 6214.223222097238, 6145.631262124287, 6165.568410211422, 6232.096346029891, 6285.622862628257, 6299.181106456132, 5816.447437493432, 6032.939670603238, 6037.09443583466, 6074.605150665193, 6115.205023346936, 5753.453421924911, 5817.418258174318, 5832.62087964281, 6013.488853447331, 6054.061620448838, 5650.491455769001, 5692.914606280992, 5706.657600543981, 5746.224259724883, 5789.45000224978, 6050.273967123973, 6050.818475445743, 6078.179445774764, 6081.604695694766, 6081.969590152021, 5792.681402522901, 5820.883582328414, 5827.155062515996, 5834.562407819346, 5843.415658434123], "value": 5598.516978006667, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.5381272540185, 520.5809323494587, 552.2627292564424, 563.636454486856, 579.1978110317209, 31982.68017319826, 36389.63610363896, 38449.0, 38612.42081368778, 38658.840234793, 39167.21665566687, 39989.0, 39994.0, 40025.39961900574, 40079.39880901782, 9048.909510904872, 9229.492377919212, 9354.345195836295, 9384.530773461334, 9402.294827887901, 7903.762887113396, 9156.587953542074, 9244.630214791401, 9346.626134954593, 9352.20506256969, 9271.860922086174, 9279.118483744067, 9335.206507446876, 9336.766580835465, 9344.159025687686, 9169.312301577409, 9224.07759224079, 9304.209142222919, 9315.115064068903, 9368.90631093694, 9159.450432974043, 9230.86153707695, 9252.537373131316, 9281.675141370088, 9287.953560232223, 32475.188120296945, 38305.23389532194, 38769.41845872314, 38818.611813881784, 38926.0, 3191.7446604271604, 3198.8080715157166, 3273.9672603273902, 3328.8169150696704, 3378.1385746634696, 29723.405531889457, 29979.850100749576, 29992.800287988455, 30487.0, 30704.53943190854, 2922.517784565544, 2957.349383135707, 2966.7478264347556, 3022.22933152047, 3071.5546245794458, 463407.0, 469728.0, 471159.0, 472519.0, 473453.0, 32214.19464513359, 57719.55701107422, 58040.54898627568, 58084.838303234545, 58144.709276454196, 28318.85840570784, 34040.65959340424, 34428.48357274643, 34436.0, 34495.65504344975, 14057.648558786112, 14187.858121418654, 14191.432342706175, 14197.929010354883, 14240.0, 11923.821142682868, 11969.222000569935, 12058.216215945935, 12077.637670869712, 12085.395730213455, 7490.812729681802, 7539.547627142279, 7569.318761311509, 7573.318401343906, 7619.771406857803, 2893.7106289370895, 3237.2877966847254, 3244.6430892601793, 3246.642869284377, 3263.738900887923, 859.621766422772, 884.9030773198908, 907.1880666803174, 939.5302348825587, 1015.6648306058983, 29230.26924326909, 29722.85138574293, 29923.551146732818, 30452.390952181275, 32087.197820054684, 928.4336554701626, 948.0661548374845, 987.4075554667143, 1020.642775028734, 1032.3031953431405, 12757.617271481871, 15108.773368399483, 15177.0, 15303.923480382527, 15474.84525154734, 571084.0, 651113.0, 658342.0, 659465.0, 659628.4037159567, 51560.74219628878, 55032.724836376365, 55102.173467398025, 55555.722221388634, 55694.44305556973, 1279.5649479177125, 1315.9013074019488, 1322.1934619881865, 1337.1375462826422, 1353.7833946568699, 22615.321540353816, 27304.180874573794, 28855.422891542472, 29332.12003639894, 29692.406151877272, 49533.25700114429, 53963.730181348845, 54771.90456190934, 54974.72512637411, 58495.0, 603220.0, 607332.0, 607802.0, 608398.9580052071, 608925.0, 40291.597084028785, 41095.0, 41336.37995430011, 41521.584784151775, 41649.58350416457, 45121.77439112915, 45587.54412455833, 45881.311780322656, 46065.53934460747, 46414.76792616015, 34326.0, 35980.820095899355, 36872.07819804527, 38194.0451488715, 38402.039949000384, 29589.26026849346, 31811.0, 38132.6186738129, 39055.609443905196, 39087.6091239084, 23662.763372366055, 29418.558721618763, 29565.704342956295, 29942.85028574843, 31714.0, 31116.377672446877, 32906.835465822514, 33978.32043359069, 34389.48415773815, 34640.960771175894, 25075.87462062678, 25161.370965726004, 25278.74721252764, 25472.638800255147, 25787.355316117246], "value": 18132.347022947495, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.1729140268783, 362.2374900833745, 368.55957131228183, 374.55428040631665, 392.9960700392995, 17609.55976100595, 17707.1146442678, 17878.993881713108, 18295.54261143472, 19342.0, 18318.0, 19071.569632277566, 19132.808671913277, 19422.51443713904, 19448.805511944876, 5758.9424105759035, 6054.60645058072, 6072.878542429149, 6077.361877002917, 6081.939180608204, 4849.660523763338, 5818.767249310023, 5858.589898707092, 5866.03210470274, 5867.295924489067, 5791.507721843648, 5806.477417032466, 5810.854728631776, 5822.330432000311, 5842.123681447774, 5558.610897237195, 5910.763569457216, 5951.58338916276, 6021.916055110079, 6023.397660233985, 5540.36285827131, 5714.771409143629, 5793.797217097382, 5796.072628379439, 5797.507211886995, 12882.613521594367, 12897.226166430044, 12942.22346659194, 12952.417141228667, 16854.578635534086, 2088.1960445228547, 2128.404046866864, 2214.9114035438565, 2264.30938563738, 2485.638643288865, 9173.128552787475, 9822.410655360702, 10149.391036537758, 10208.642697505551, 10303.484825758756, 1804.9458516244533, 1842.4933143385676, 1849.7317888906168, 1851.1207176591147, 1871.419859843453, 117981.0, 122088.16867746902, 122515.3874230632, 122730.0, 122982.0, 11136.0, 11391.829122563167, 11404.828927566094, 11448.484818183202, 11615.941920290345, 13834.23911684867, 14104.0, 14111.576652700436, 14117.08238964464, 14142.36359363831, 7996.520208787491, 8308.715103346629, 8351.707690230813, 8450.366222533334, 8455.577221138918, 8115.3507719382305, 8141.715039973691, 8147.511149331059, 8314.459560128573, 8341.20758527939, 4345.239583072949, 4459.7958551190995, 4538.750368729751, 4559.794809233593, 4579.290210017452, 1867.7105048717472, 1937.3122541497846, 1941.26232031827, 1946.4160751774411, 1949.2982526290525, 774.4230548241596, 821.5153059694809, 860.8493513635088, 917.7567944494714, 919.6367434863191, 17278.481645550655, 18728.595355348403, 20723.8963805183, 20923.37229883106, 21039.78960210378, 880.317753740856, 892.1925657280171, 911.8449863523217, 917.3624331089962, 973.6494861849728, 10271.94864025675, 10334.759828820439, 10588.523516441779, 11044.834327485094, 11047.281926674741, 566564.0, 573585.0, 585630.0, 586399.0, 587163.0, 121071.39464302621, 126377.36811315885, 127074.0, 129709.35145324212, 131898.34050829685, 862.8490014247482, 926.8702381666707, 931.7996630724426, 986.6842610364758, 988.1847475832467, 15501.224938753243, 18671.253149873854, 18978.620427591093, 18991.71512427342, 19018.42944711661, 47181.0, 51546.969060619325, 53620.73189634027, 53791.0, 54375.18437223524, 56528.15207771969, 75071.87392189231, 75449.49101017839, 76543.0, 77953.61023194848, 7664.655090520829, 7827.412944029106, 7928.326092282221, 7933.206679332021, 8064.395170362129, 9039.68361107358, 9743.366681165702, 12213.633590992284, 12516.561920332744, 12554.43505042257, 10548.472576370998, 10673.89326106729, 10796.190285728444, 11030.448477575928, 11090.5563777448, 10449.373037617766, 10522.579096836042, 10551.841722374324, 10615.840762388416, 10986.835197471884, 10411.21915856329, 10697.090747286567, 10846.349219046882, 11238.381888996184, 11250.83123753128, 7613.695452182073, 7831.569263690551, 7888.84222315562, 7924.20757924203, 7975.72084977023, 9829.557669905014, 10757.139428845518, 11000.889991099986, 11088.889111108785, 11145.331280123219], "value": 9152.49065443128, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1372.3755691160522, 1479.926003699815, 1610.911399873008, 1661.4185035237667, 1790.999999999999, 7593.810154746134, 7855.214478552142, 8094.757157285284, 8497.447665901713, 8562.785930351743, 7967.521948683068, 8302.626381812817, 8326.542040187787, 8401.99176098869, 8427.578621068937, 2282.224043825099, 2308.2498188088853, 2319.524497478021, 2323.674685544025, 2326.1974618756503, 2289.2445492987276, 2325.6744055832196, 2344.4724936889147, 2345.7654234576576, 2357.316378250305, 2281.7490076091617, 2289.027163455533, 2307.0310469602723, 2317.8377513574055, 2320.918767843117, 2275.6245219538755, 2286.965695514569, 2300.6549017647403, 2305.3775480620307, 2315.0623997281127, 2299.8965046572775, 2325.93022209334, 2328.2433209207074, 2330.8018818400287, 2336.556054349668, 8391.580420978988, 8450.281726053292, 8493.447925884797, 8510.148985101503, 8541.701040463555, 5088.389393272794, 5222.73886305687, 5249.973750131225, 5253.474652534755, 5260.947390526084, 3120.251139726472, 3202.6957439043254, 3255.137388592025, 3380.6619338066, 3510.3856825055514, 951.857221416786, 952.2286947572488, 1030.8711411073616, 1046.4610725476387, 1048.2295512798087, 9522.047795220491, 10649.57401703931, 10751.946240268904, 10980.615678451215, 11024.50389732464, 4314.71954322968, 4399.868003959887, 4455.554444555519, 4508.594226519629, 4514.277715565494, 8562.272206862486, 8686.782830429163, 8696.086910874394, 8715.302775777922, 8727.258183054384, 5106.310648062538, 5110.616703747234, 5119.564836988898, 5120.641555091108, 5135.024345374367, 4305.311150216013, 4329.874232699458, 4720.244760838249, 4724.173269677793, 4805.927911081337, 3552.9215408985515, 3603.9459408108373, 3675.301692678436, 3703.333399987974, 3707.610700876423, 1138.8633363996207, 1145.8223975283845, 1153.1121036801617, 1154.8325492803497, 1160.2980196980704, 778.4084096087015, 807.1928071928072, 812.2162113560443, 901.1259078693656, 903.76953876761, 4782.234842425265, 4995.600351971833, 5631.662100273997, 5674.148877668383, 5682.176084467729, 414.8879802453321, 423.3163441042755, 429.0603578163811, 429.8645926533132, 430.2234466787416, 3819.2170605025904, 3843.7309388342546, 3957.6240257175523, 3962.7424217425773, 3992.860249891298, 9711.611535538499, 10031.29780915329, 10181.134603558781, 10326.01902819231, 10345.586176552853, 8684.956575217084, 9998.900010999942, 10128.898711012944, 10287.331323463952, 10303.124234440007, 7710.614469276514, 7938.047434307805, 7945.205479452008, 7949.880751788728, 7954.403419743427, 5147.047796157723, 5590.021746194318, 5623.268975033383, 5643.322801263875, 5664.065429204135, 9307.441553506811, 9311.301652376234, 9447.14975652167, 9483.146516813244, 9486.0, 7968.960155199419, 9124.45253284805, 9393.248540116638, 9495.05049495045, 9618.89382720968, 6627.204735431779, 6853.211880633591, 7039.577625342496, 7202.603856787927, 7341.559506429631, 7912.683492660229, 7978.84042319162, 7996.160403157817, 8008.399370047153, 8111.472754270953, 7185.568865868065, 7273.599952002691, 7309.451791115794, 7328.2305357938785, 7445.888311675221, 7056.0, 7122.85754284901, 7158.212596614472, 7253.528520646141, 7297.708091676486, 6692.6988285526295, 6860.451163906776, 6871.553349032297, 6874.243833178446, 6887.380135787603, 4834.5890599298045, 4924.581410580141, 4942.8764280893265, 4951.579115775199, 4961.0, 4822.445418776885, 4892.339534162842, 4966.751662416937, 5104.387473503203, 5113.92329115071], "value": 4288.135488318505, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.2292041708847, 297.28502950404277, 302.8637113299016, 307.36989172196996, 313.2982120051086, 4780.569748722614, 5271.314729085223, 5315.78736850526, 5420.295361602991, 5441.564674826014, 4924.6211060902915, 5280.947190528093, 5380.865478363043, 5403.675779453236, 5408.486193811592, 3668.9082772930624, 4112.814923328458, 4131.586841315874, 4161.292580261348, 4172.290710579194, 3637.963620363802, 3673.5040769496177, 4162.980069882883, 4176.582341765829, 4198.118395137009, 3383.17112307485, 3795.6583907448326, 4006.879793606182, 4028.5568587455355, 4191.55988621195, 3536.398812201919, 4007.3788562772525, 4008.9977505623738, 4019.31671615826, 4069.9389509157386, 3103.8758449662214, 3170.0648308748973, 3334.549835772188, 3467.7745946513396, 3488.9127771805656, 3934.252492026506, 4136.345461815268, 4497.482789479219, 5262.184361423985, 5273.31446911899, 4039.8586049488126, 4196.979015104936, 4236.322188449864, 4635.884102897421, 4651.837185698518, 5000.249962505617, 5460.918086228709, 5682.431756824326, 5685.204071430003, 5705.74324155414, 3371.4882412602524, 3496.318217947501, 3803.82882770276, 3810.466534685146, 3830.253100645375, 5542.75057622408, 5604.579656525736, 5617.775288988436, 5782.653040817564, 5917.437843404869, 3694.7228957827733, 3870.0905287257474, 3874.3219936511, 3876.2441323942126, 3882.6699730522655, 3322.5846769153854, 3645.5625324960643, 3772.2266935278203, 3776.0, 3925.156091440354, 4210.221109094766, 4560.247559152769, 4601.838935637236, 4642.326862604971, 4653.86038418848, 5439.755211015514, 5660.915086273709, 5682.221322820361, 5715.199872017921, 5730.799422020209, 1268.6447794617523, 1291.8708129187194, 1295.7667619828333, 1297.4874924404996, 1310.994178906275, 787.8739401695701, 865.7489927055344, 877.2674816528271, 884.827458645558, 887.4497811357, 4642.837500687528, 4976.676516026447, 5070.873228169252, 5079.806245532297, 5112.4120726116225, 4811.591014763714, 5027.748612569357, 5226.686398816083, 5291.894162116736, 5671.659700417906, 4403.3174857897075, 4951.821198672573, 5097.566706829961, 5160.638755287094, 5246.790128394898, 2435.7077150742025, 2440.8535487870786, 2473.134402958986, 2490.589052806267, 2898.2610433739587, 5715.628484148517, 5731.283589551306, 5765.933302339081, 5809.796657116981, 5856.267966504187, 5346.4386239445075, 5679.914801277984, 5783.94216057834, 5871.266091738533, 6131.417515336036, 6176.66028368444, 6180.6600636965395, 6187.907181392283, 6188.4739797116845, 6245.250569931637, 4651.930221046619, 5946.5540084494705, 5993.70031498432, 6006.459418652343, 6046.153538504701, 4957.578605818546, 5909.23179986599, 5919.7864437789285, 5944.3164036136395, 5979.671118088547, 5526.557875370041, 5852.7658893643775, 6075.392460753889, 6197.4732147767945, 6243.219597550306, 3131.843407829554, 3600.243948770683, 3907.199024199975, 3956.84172633103, 3957.0305275207756, 4928.950710492849, 5860.677662728421, 5943.554233432424, 5999.730012149551, 6004.699765011819, 5440.871498888076, 5677.460641239076, 5700.429957004267, 5719.799807006735, 5799.391063938395, 5692.516136128475, 5701.061639042579, 5715.542756579547, 5721.914171287517, 5729.453047677187, 4533.574537622356, 4545.727256364628, 5267.97274531478, 5583.860403489783, 5629.915551266652, 4269.252880745919, 4425.424694789656, 4764.809407623657, 5632.408597097409, 5698.8290351289525, 4476.171908196994, 4947.6393991652585, 5578.30271216098, 5581.804636837691, 5611.971940140274], "value": 4055.3910932983026, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.7085311609775, 683.9487038472116, 738.5531753289263, 762.2720302111484, 773.6557232031746, 11942.223755455889, 12121.515139394425, 13850.0, 14699.677029067381, 14775.926120369397, 13203.867961320384, 13361.665958351046, 13362.131461455, 13364.0, 13403.865961340383, 616.214326733414, 638.4254171245869, 641.8106658535731, 673.2762280548405, 822.3955392786297, 794.8728203487443, 803.148662417839, 804.5293503300547, 806.7458750493605, 808.7250334886138, 847.559269180026, 849.1890244816186, 857.3098655582254, 857.6483641706901, 858.159004175908, 815.107457334224, 849.9320054395664, 850.5619605902903, 851.2806678356809, 851.3104385447807, 609.6128958111631, 631.0376675569747, 638.9520785941027, 639.0382474376081, 640.0207682246171, 13525.120867143603, 13673.154124193206, 13696.520621778289, 13712.24582647954, 14114.92942535291, 10421.478926053667, 11894.940525297317, 11946.462409191607, 13319.800202996963, 13908.141899826565, 12131.93934030333, 12166.843091358158, 12294.569690060895, 12412.503499859995, 12474.563390281388, 4081.6530594899173, 4113.85601503946, 4118.299889018874, 4133.524644665872, 4138.151678905817, 81169.78245326325, 81730.18269817285, 84886.0, 88960.5551972249, 90024.54987725019, 10142.532637727525, 10160.593576256866, 10217.489125543692, 10298.124659403884, 10592.205584581186, 15878.364865405485, 18434.723479147822, 18787.812121878607, 18923.24307027731, 18968.43094707161, 7294.8905766413545, 7323.157836848724, 7402.1487528933785, 7447.702091916262, 7505.5871927043445, 4078.8368465261055, 4102.958970410317, 4135.0, 4156.418101465797, 4168.333066709312, 2865.355295058637, 3256.3324518473655, 3258.169166862482, 3271.1004473769426, 3299.0, 573.8163787588015, 585.3590318601125, 591.9467247947705, 596.9701514924236, 597.8415719834247, 554.9250851135126, 588.4586180713753, 593.8456001439657, 607.6293460988793, 632.3107812484415, 8009.759707208793, 8455.915440845512, 8575.571221438902, 8593.0, 8602.18279263469, 562.4291344285554, 606.1756011823916, 606.9575129740875, 609.2445367744054, 616.608453631938, 3837.1558257183374, 3992.960070399317, 4049.655779258737, 4208.053188032668, 4209.7474151551005, 62966.68516657387, 78867.0, 85327.0, 88625.0, 113477.0, 51434.0, 65668.34331656623, 79029.60485197537, 82208.17791822125, 93582.06417935733, 54859.72570137204, 55124.72437637786, 72324.0, 77763.0, 78883.0, 9376.437413754924, 9564.33049686543, 9700.223982081558, 9713.27150463704, 9747.317687762072, 50073.24890126578, 51722.22416663678, 52696.83199104145, 53317.46682533125, 53600.195997059294, 58164.70917645385, 68588.62822743617, 70097.94853077106, 77268.0, 80111.99720007046, 9135.817283654422, 10436.895631043591, 12141.332226727614, 12190.329531875832, 12460.252384856938, 13706.451741930212, 13790.724185516434, 13806.240656763974, 13846.584602461942, 14128.717425651224, 12790.744185116431, 12821.807672885101, 12892.097553171188, 13361.53234636783, 13456.192628442324, 11925.284482931052, 12219.98571521327, 12362.44369003379, 12631.11582189238, 12669.493220271086, 12516.123871329284, 12597.748045038865, 12661.93669031649, 12727.554535591209, 12759.681007974876, 10361.533730981973, 10450.477476126316, 10600.840987385349, 11192.38441885704, 11197.38414387184, 10677.89322106769, 10948.507317170586, 11157.49373834522, 11257.268277561932, 11318.094552435621], "value": 6928.571042227621, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1632.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 173.0, 126.0, 116.0, 118.0, 117.0, 178.0, 174.0, 128.0, 119.0], "value": 141.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 122.0, 113.0, 144.0, 149.0, 150.0, 119.0, 133.0, 178.0, 120.0], "value": 137.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [267.0, 234.0, 210.0, 199.0, 196.0, 176.0, 255.0, 271.0, 192.0, 196.0], "value": 219.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 58.0, 87.0, 63.0, 66.0, 51.0, 65.0, 103.0, 86.0], "value": 71.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 73.0, 88.0, 71.0, 69.0, 88.0, 120.0, 87.0, 70.0, 90.0], "value": 84.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 100.0, 81.0, 93.0, 79.0, 136.0, 119.0, 90.0, 126.0], "value": 100.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 114.0, 155.0, 114.0, 98.0, 96.0, 119.0, 154.0, 100.0, 147.0], "value": 119.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 81.0, 149.0, 145.0, 92.0, 90.0, 83.0, 94.0, 151.0, 148.0], "value": 112.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 50.0, 47.0, 82.0, 84.0, 86.0, 88.0, 67.0, 57.0, 57.0], "value": 67.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 74.0, 85.0, 74.0, 73.0, 74.0, 73.0, 77.0, 87.0], "value": 76.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 85.0, 91.0, 87.0, 89.0, 89.0, 90.0, 93.0, 85.0, 96.0], "value": 89.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 172.0, 151.0, 165.0, 155.0, 166.0, 157.0, 148.0, 186.0, 163.0], "value": 163.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 178.0, 179.0, 181.0, 174.0, 175.0, 177.0, 177.0, 181.0], "value": 178.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 72.0, 70.0, 69.0, 70.0, 69.0, 81.0, 68.0, 68.0], "value": 70.4, "unit": "score"}]}]} 14:57:42 INFO - Return code: 0 14:57:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:57:42 ERROR - # TBPL SUCCESS # 14:57:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:57:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:57:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:57:42 INFO - 2016-11-18 14:57:42.788 screenresolution[1663:10545] starting screenresolution argv=screenresolution get 14:57:42 INFO - 2016-11-18 14:57:42.791 screenresolution[1663:10545] Display 0: 1600x1200x32@60 14:57:42 INFO - 2016-11-18 14:57:42.798 screenresolution[1664:10548] starting screenresolution argv=screenresolution list 14:57:42 INFO - Available Modes on Display 0 14:57:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:57:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:57:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:57:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:57:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:57:43 INFO - Intel Iris: 14:57:43 INFO - Chipset Model: Intel Iris 14:57:43 INFO - Type: GPU 14:57:43 INFO - Bus: Built-In 14:57:43 INFO - VRAM (Dynamic, Max): 1536 MB 14:57:43 INFO - Vendor: Intel (0x8086) 14:57:43 INFO - Device ID: 0x0a2e 14:57:43 INFO - Revision ID: 0x0009 14:57:43 INFO - Displays: 14:57:43 INFO - Display: 14:57:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:57:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:57:43 INFO - Display Serial Number: 1600x1200 60 14:57:43 INFO - Main Display: Yes 14:57:43 INFO - Mirror: Off 14:57:43 INFO - Online: Yes 14:57:43 INFO - Rotation: Supported 14:57:43 INFO - Return code: 0 14:57:43 INFO - Running post-action listener: _resource_record_post_action 14:57:43 INFO - [mozharness: 2016-11-18 22:57:43.037719Z] Finished run-tests step (success) 14:57:43 INFO - Running post-run listener: _resource_record_post_run 14:57:43 INFO - Total resource usage - Wall time: 1214s; CPU: 55.0%; Read bytes: 38353408; Write bytes: 693749760; Read time: 241; Write time: 1946 14:57:43 INFO - TinderboxPrint: CPU usage
54.5% 14:57:43 INFO - TinderboxPrint: I/O read bytes / time
38,353,408 / 241 14:57:43 INFO - TinderboxPrint: I/O write bytes / time
693,749,760 / 1,946 14:57:43 INFO - TinderboxPrint: CPU idle
2,209.7 (45.5%) 14:57:43 INFO - TinderboxPrint: CPU system
982.3 (20.2%) 14:57:43 INFO - TinderboxPrint: CPU user
1,665.7 (34.3%) 14:57:43 INFO - TinderboxPrint: Swap in / out
422,211,584 / 0 14:57:43 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 367141376; Write bytes: 366638592; Read time: 29023; Write time: 742 14:57:43 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 37399040; Write bytes: 308417024; Read time: 230; Write time: 1157 14:57:43 INFO - Running post-run listener: _upload_blobber_files 14:57:43 INFO - Blob upload gear active. 14:57:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:57:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:57:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:57:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:57:43 INFO - (blobuploader) - INFO - Open directory for files ... 14:57:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:57:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:57:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:57:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:57:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:57:44 INFO - (blobuploader) - INFO - Done attempting. 14:57:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:57:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:57:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:57:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:57:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:57:45 INFO - (blobuploader) - INFO - Done attempting. 14:57:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:57:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:57:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:57:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:57:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:57:48 INFO - (blobuploader) - INFO - Done attempting. 14:57:48 INFO - (blobuploader) - INFO - Iteration through files over. 14:57:48 INFO - Return code: 0 14:57:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:57:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:57:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a417dba43eaf58eef2ddfe8cc18958f4ee072593d4ea4b97fae2869d5209b197e2631f00237a0aef5ed19cddf1feafd9da92600b8a24f79bac360a9253b51a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d88ded0f22726b8da4d4a4e0ccfdb7f221b2324a80e6f051ac95cb161e1b87732f0c3b6d3d55772f48a11770abe3dcfb3618ae51e672acce0cff623b28c1e8dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a0dc914f659425fa8422b1fab6da30cdc7d33e7fdc351795a69e09a39092a531e3a9a9c4b7c543624b37c960184a35f2f096e8ccbfa00245042b6be30b2acb6"} 14:57:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:57:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:57:48 INFO - Contents: 14:57:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a417dba43eaf58eef2ddfe8cc18958f4ee072593d4ea4b97fae2869d5209b197e2631f00237a0aef5ed19cddf1feafd9da92600b8a24f79bac360a9253b51a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d88ded0f22726b8da4d4a4e0ccfdb7f221b2324a80e6f051ac95cb161e1b87732f0c3b6d3d55772f48a11770abe3dcfb3618ae51e672acce0cff623b28c1e8dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a0dc914f659425fa8422b1fab6da30cdc7d33e7fdc351795a69e09a39092a531e3a9a9c4b7c543624b37c960184a35f2f096e8ccbfa00245042b6be30b2acb6"} 14:57:48 INFO - Running post-run listener: copy_logs_to_upload_dir 14:57:48 INFO - Copying logs to upload dir... 14:57:48 INFO - mkdir: /builds/slave/test/build/upload/logs 14:57:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.989456 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-18 14:57:48.906005) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 14:57:48.909497) ========= 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.2sNC1oQPCv/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.6d4vl51Li5/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a417dba43eaf58eef2ddfe8cc18958f4ee072593d4ea4b97fae2869d5209b197e2631f00237a0aef5ed19cddf1feafd9da92600b8a24f79bac360a9253b51a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d88ded0f22726b8da4d4a4e0ccfdb7f221b2324a80e6f051ac95cb161e1b87732f0c3b6d3d55772f48a11770abe3dcfb3618ae51e672acce0cff623b28c1e8dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a0dc914f659425fa8422b1fab6da30cdc7d33e7fdc351795a69e09a39092a531e3a9a9c4b7c543624b37c960184a35f2f096e8ccbfa00245042b6be30b2acb6"} build_url:https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010954 build_url: 'https://queue.taskcluster.net/v1/task/AZBVS8C3TKiJsi5GuZsmPA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a417dba43eaf58eef2ddfe8cc18958f4ee072593d4ea4b97fae2869d5209b197e2631f00237a0aef5ed19cddf1feafd9da92600b8a24f79bac360a9253b51a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d88ded0f22726b8da4d4a4e0ccfdb7f221b2324a80e6f051ac95cb161e1b87732f0c3b6d3d55772f48a11770abe3dcfb3618ae51e672acce0cff623b28c1e8dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a0dc914f659425fa8422b1fab6da30cdc7d33e7fdc351795a69e09a39092a531e3a9a9c4b7c543624b37c960184a35f2f096e8ccbfa00245042b6be30b2acb6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 14:57:48.942635) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 14:57:48.943013) ========= 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.2sNC1oQPCv/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.6d4vl51Li5/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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.005106 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 14:57:48.992148) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 14:57:48.992480) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 14:57:48.992883) ========= ========= Total master_lag: 0.18 =========