builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0313 starttime: 1483525842.46 results: success (0) buildid: 20170104004006 builduid: 5cdabe96efff4c4f9bea0dbd25e82dae revision: 236b90305efbbc79f66ca1453be47f3688b26c7a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 02:30:42.462374) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 02:30:42.462831) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 02:30:42.463132) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 02:30:42.487831) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 02:30:42.488180) ========= 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.vDnBn5DIZn/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.4K3IUkp4J4/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-04 02:30:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.71M=0.002s 2017-01-04 02:30:42 (7.71 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124501 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 02:30:42.637314) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 02:30:42.637660) ========= 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.vDnBn5DIZn/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.4K3IUkp4J4/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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.019141 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 02:30:42.686242) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-04 02:30:42.686602) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 236b90305efbbc79f66ca1453be47f3688b26c7a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 236b90305efbbc79f66ca1453be47f3688b26c7a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vDnBn5DIZn/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.4K3IUkp4J4/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-04 02:30:42,722 truncating revision to first 12 chars 2017-01-04 02:30:42,722 Setting DEBUG logging. 2017-01-04 02:30:42,723 attempt 1/10 2017-01-04 02:30:42,723 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/236b90305efb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-04 02:30:44,861 unpacking tar archive at: mozilla-aurora-236b90305efb/testing/mozharness/ program finished with exit code 0 elapsedTime=2.394693 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-04 02:30:45.104887) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 02:30:45.105206) ========= script_repo_revision: 236b90305efbbc79f66ca1453be47f3688b26c7a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 02:30:45.105688) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 02:30:45.105965) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 02:30:45.125994) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-04 02:30:45.126295) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vDnBn5DIZn/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.4K3IUkp4J4/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 02:30:45 INFO - MultiFileLogger online at 20170104 02:30:45 in /builds/slave/test 02:30:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:30:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:30:45 INFO - {'append_to_log': False, 02:30:45 INFO - 'base_work_dir': '/builds/slave/test', 02:30:45 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:30:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:30:45 INFO - 'branch': 'Mozilla-Aurora', 02:30:45 INFO - 'buildbot_json_path': 'buildprops.json', 02:30:45 INFO - 'config_files': ('talos/mac_config.py',), 02:30:45 INFO - 'default_actions': ('clobber', 02:30:45 INFO - 'read-buildbot-config', 02:30:45 INFO - 'download-and-extract', 02:30:45 INFO - 'populate-webroot', 02:30:45 INFO - 'create-virtualenv', 02:30:45 INFO - 'install', 02:30:45 INFO - 'run-tests'), 02:30:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:30:45 INFO - 'download_minidump_stackwalk': True, 02:30:45 INFO - 'download_symbols': 'ondemand', 02:30:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:30:45 INFO - 'tooltool.py': '/tools/tooltool.py', 02:30:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:30:45 INFO - '/tools/misc-python/virtualenv.py')}, 02:30:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:30:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:30:45 INFO - 'installer_path': 'installer.exe', 02:30:45 INFO - 'log_level': 'info', 02:30:45 INFO - 'log_name': 'talos', 02:30:45 INFO - 'log_to_console': True, 02:30:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:30:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:30:45 INFO - 'opt_config_files': (), 02:30:45 INFO - 'pip_index': False, 02:30:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:30:45 INFO - 'cmd': ('bash', 02:30:45 INFO - '-c', 02:30:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:30:45 INFO - 'enabled': True, 02:30:45 INFO - 'halt_on_failure': False, 02:30:45 INFO - 'name': 'check_screen_resolution'},), 02:30:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:30:45 INFO - 'cmd': ('bash', 02:30:45 INFO - '-c', 02:30:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:30:45 INFO - 'enabled': True, 02:30:45 INFO - 'halt_on_failure': False, 02:30:45 INFO - 'name': 'check_screen_resolution'},), 02:30:45 INFO - 'run_cmd_checks_enabled': True, 02:30:45 INFO - 'sps_profile': False, 02:30:45 INFO - 'sps_profile_interval': 0, 02:30:45 INFO - 'suite': 'dromaeojs-e10s', 02:30:45 INFO - 'system_bits': '32', 02:30:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:30:45 INFO - 'title': 't-yosemite-r7-0313', 02:30:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:30:45 INFO - 'use_talos_json': True, 02:30:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:30:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:30:45 INFO - 'work_dir': 'build'} 02:30:45 INFO - [mozharness: 2017-01-04 10:30:45.277575Z] Running clobber step. 02:30:45 INFO - Running pre-action listener: _resource_record_pre_action 02:30:45 INFO - Running main action method: clobber 02:30:45 INFO - rmtree: /builds/slave/test/build 02:30:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:30:46 INFO - Running post-action listener: _resource_record_post_action 02:30:46 INFO - [mozharness: 2017-01-04 10:30:46.778588Z] Finished clobber step (success) 02:30:46 INFO - [mozharness: 2017-01-04 10:30:46.778722Z] Running read-buildbot-config step. 02:30:46 INFO - Running pre-action listener: _resource_record_pre_action 02:30:46 INFO - Running main action method: read_buildbot_config 02:30:46 INFO - Using buildbot properties: 02:30:46 INFO - { 02:30:46 INFO - "project": "", 02:30:46 INFO - "product": "firefox", 02:30:46 INFO - "script_repo_revision": "production", 02:30:46 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:30:46 INFO - "repository": "", 02:30:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:30:46 INFO - "buildid": "20170104004006", 02:30:46 INFO - "stage_platform": "macosx64", 02:30:46 INFO - "basedir": "/builds/slave/test", 02:30:46 INFO - "nightly_build": "True", 02:30:46 INFO - "buildnumber": 60, 02:30:46 INFO - "slavename": "t-yosemite-r7-0313", 02:30:46 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:30:46 INFO - "platform": "yosemite_r7", 02:30:46 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:30:46 INFO - "branch": "mozilla-aurora", 02:30:46 INFO - "repo_path": "releases/mozilla-aurora", 02:30:46 INFO - "revision": "236b90305efbbc79f66ca1453be47f3688b26c7a", 02:30:46 INFO - "pgo_build": "False", 02:30:46 INFO - "builduid": "5cdabe96efff4c4f9bea0dbd25e82dae", 02:30:46 INFO - "slavebuilddir": "test" 02:30:46 INFO - } 02:30:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:30:46 INFO - Running post-action listener: _resource_record_post_action 02:30:46 INFO - [mozharness: 2017-01-04 10:30:46.782137Z] Finished read-buildbot-config step (success) 02:30:46 INFO - [mozharness: 2017-01-04 10:30:46.782238Z] Running download-and-extract step. 02:30:46 INFO - Running pre-action listener: _resource_record_pre_action 02:30:46 INFO - Running main action method: download_and_extract 02:30:46 INFO - mkdir: /builds/slave/test/build/tests 02:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:30:46 INFO - trying https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:30:46 INFO - Downloading https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:30:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 02:30:48 INFO - Downloaded 1199 bytes. 02:30:48 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:30:48 INFO - Using the following test package requirements: 02:30:48 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:30:48 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:30:48 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:30:48 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:30:48 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:30:48 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:30:48 INFO - u'jsshell-mac.zip'], 02:30:48 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:30:48 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:30:48 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:30:48 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:30:48 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:30:48 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:30:48 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:30:48 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:30:48 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:30:48 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:30:48 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:30:48 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:30:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:30:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:30:48 INFO - Fetch https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:30:48 INFO - Http code: 200 02:30:48 INFO - via: 1.1 7db492e48a5d55351dcb787a8a14e113.cloudfront.net (CloudFront) 02:30:48 INFO - x-amz-cf-id: 6zrBsBrSyzkWORX4jZwuINpqyg6BFoMOFclREJtr9gsHuxsYZqc3lA== 02:30:48 INFO - x-amz-version-id: BlfzwRj1VpaHbreddTH.Bta5ck6fLugf 02:30:48 INFO - Expected file size: 16269819 02:30:48 INFO - Obtained file size: 16269819 02:30:55 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:30:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:30:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:30:55 INFO - Fetch https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:30:55 INFO - Http code: 200 02:30:55 INFO - via: 1.1 296faebadd40feee8b2eb0e10d5786d2.cloudfront.net (CloudFront) 02:30:55 INFO - x-amz-cf-id: 9Kx6hryC0wlpr3Etg5uc-lw9QyWZvXAe2C5J7EnW1MfcZ4oib113Mw== 02:30:55 INFO - x-amz-version-id: BlfzwRj1VpaHbreddTH.Bta5ck6fLugf 02:30:56 INFO - Expected file size: 16269819 02:30:56 INFO - Obtained file size: 16269819 02:31:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:31:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:31:02 INFO - Fetch https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:31:03 INFO - Http code: 200 02:31:03 INFO - via: 1.1 6eaa7f856e226a0db7cef6201d3b8393.cloudfront.net (CloudFront) 02:31:03 INFO - x-amz-cf-id: DUJA9rEe2zGvHPWkkEotU7HynEhhio1lG8_mT8SxR2JwQ3NGg2I6Sw== 02:31:03 INFO - x-amz-version-id: 5TPs0XYOZwruVKd3D8VxU2eVVukbqhVx 02:31:03 INFO - Expected file size: 14409038 02:31:03 INFO - Obtained file size: 14409038 02:31:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:03 INFO - trying https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:31:03 INFO - Downloading https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:31:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 02:31:06 INFO - Downloaded 109943671 bytes. 02:31:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:31:06 INFO - mkdir: /builds/slave/test/properties 02:31:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:31:06 INFO - Writing to file /builds/slave/test/properties/build_url 02:31:06 INFO - Contents: 02:31:06 INFO - build_url:https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:31:07 INFO - Running post-action listener: _resource_record_post_action 02:31:07 INFO - Running post-action listener: set_extra_try_arguments 02:31:07 INFO - [mozharness: 2017-01-04 10:31:07.336627Z] Finished download-and-extract step (success) 02:31:07 INFO - [mozharness: 2017-01-04 10:31:07.336770Z] Running populate-webroot step. 02:31:07 INFO - Running pre-action listener: _resource_record_pre_action 02:31:07 INFO - Running main action method: populate_webroot 02:31:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:31:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:31:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:31:07 INFO - '--tppagecycles', 02:31:07 INFO - '7'], 02:31:07 INFO - 'tests': ['tsvgm']}}, 02:31:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:31:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:31:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:31:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:31:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:31:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:31:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:07 INFO - 'talos_options': ['--disable-e10s'], 02:31:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:31:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:31:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:07 INFO - 'talos_options': ['--disable-e10s'], 02:31:07 INFO - 'tests': ['damp', 'tps']}, 02:31:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:07 INFO - 'tests': ['damp', 'tps']}, 02:31:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:31:07 INFO - 'tests': ['dromaeo_dom']}, 02:31:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:31:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:31:07 INFO - 'tests': ['basic_compositor_video']}, 02:31:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:31:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:31:07 INFO - 'tests': ['a11yr', 02:31:07 INFO - 'ts_paint', 02:31:07 INFO - 'tpaint', 02:31:07 INFO - 'sessionrestore', 02:31:07 INFO - 'sessionrestore_no_auto_restore', 02:31:07 INFO - 'tabpaint']}, 02:31:07 INFO - 'other-e10s': {'tests': ['a11yr', 02:31:07 INFO - 'ts_paint', 02:31:07 INFO - 'tpaint', 02:31:07 INFO - 'sessionrestore', 02:31:07 INFO - 'sessionrestore_no_auto_restore', 02:31:07 INFO - 'tabpaint']}, 02:31:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:31:07 INFO - 'ts_paint', 02:31:07 INFO - 'tpaint', 02:31:07 INFO - 'sessionrestore', 02:31:07 INFO - 'sessionrestore_no_auto_restore', 02:31:07 INFO - 'tabpaint']}, 02:31:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:31:07 INFO - 'ts_paint', 02:31:07 INFO - 'tpaint', 02:31:07 INFO - 'sessionrestore', 02:31:07 INFO - 'sessionrestore_no_auto_restore', 02:31:07 INFO - 'tabpaint']}, 02:31:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:31:07 INFO - 'tests': ['a11yr', 02:31:07 INFO - 'ts_paint', 02:31:07 INFO - 'tpaint', 02:31:07 INFO - 'sessionrestore', 02:31:07 INFO - 'sessionrestore_no_auto_restore', 02:31:07 INFO - 'tabpaint']}, 02:31:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:31:07 INFO - 'tests': ['a11yr', 02:31:07 INFO - 'ts_paint', 02:31:07 INFO - 'tpaint', 02:31:07 INFO - 'sessionrestore', 02:31:07 INFO - 'sessionrestore_no_auto_restore', 02:31:07 INFO - 'tabpaint']}, 02:31:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:31:07 INFO - 'tests': ['tsvgx', 02:31:07 INFO - 'tsvgr_opacity', 02:31:07 INFO - 'tart', 02:31:07 INFO - 'tscrollx', 02:31:07 INFO - 'cart']}, 02:31:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:31:07 INFO - 'tsvgr_opacity', 02:31:07 INFO - 'tart', 02:31:07 INFO - 'tscrollx', 02:31:07 INFO - 'cart']}, 02:31:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:07 INFO - 'talos_options': ['--disable-e10s'], 02:31:07 INFO - 'tests': ['tp5o']}, 02:31:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:07 INFO - 'tests': ['tp5o']}, 02:31:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:07 INFO - 'talos_options': ['--disable-e10s', 02:31:07 INFO - '--xperf_path', 02:31:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:31:07 INFO - 'tests': ['tp5n']}, 02:31:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:07 INFO - 'talos_options': ['--xperf_path', 02:31:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:31:07 INFO - 'tests': ['tp5n']}}, 02:31:07 INFO - 'talos.zip': {'path': '', 02:31:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:31:07 INFO - Running post-action listener: _resource_record_post_action 02:31:07 INFO - [mozharness: 2017-01-04 10:31:07.355414Z] Finished populate-webroot step (success) 02:31:07 INFO - [mozharness: 2017-01-04 10:31:07.355543Z] Running create-virtualenv step. 02:31:07 INFO - Running pre-action listener: _resource_record_pre_action 02:31:07 INFO - Running main action method: create_virtualenv 02:31:07 INFO - Creating virtualenv /builds/slave/test/build/venv 02:31:07 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 02:31:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:31:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:31:07 INFO - Using real prefix '/tools/python27' 02:31:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:31:08 INFO - Installing distribute.............................................................................................................................................................................................done. 02:31:11 INFO - Installing pip.................done. 02:31:11 INFO - Return code: 0 02:31:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:31:11 INFO - Reading from file tmpfile_stdout 02:31:11 INFO - Output received: 02:31:11 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) 02:31:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:31:11 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')]} 02:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:11 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')]} 02:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:11 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 0x10530e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105284570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b7720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105122d30>, '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 0x104f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10511ab20>, '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.vDnBn5DIZn/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.4K3IUkp4J4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 02:31:11 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 02:31:11 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 02:31:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vDnBn5DIZn/Render', 02:31:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:11 INFO - 'HOME': '/Users/cltbld', 02:31:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:11 INFO - 'LOGNAME': 'cltbld', 02:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:11 INFO - 'MOZ_NO_REMOTE': '1', 02:31:11 INFO - 'NO_EM_RESTART': '1', 02:31:11 INFO - 'PAGER': '/bin/cat', 02:31:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:11 INFO - 'PWD': '/builds/slave/test', 02:31:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:11 INFO - 'SHELL': '/bin/bash', 02:31:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4K3IUkp4J4/Listeners', 02:31:11 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 02:31:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:11 INFO - 'USER': 'cltbld', 02:31:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:11 INFO - 'XPC_FLAGS': '0x0', 02:31:11 INFO - 'XPC_SERVICE_NAME': '0', 02:31:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:11 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 02:31:11 INFO - Cleaning up... 02:31:11 INFO - Return code: 0 02:31:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:31:11 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')]} 02:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:11 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')]} 02:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:11 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 0x10530e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105284570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b7720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105122d30>, '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 0x104f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10511ab20>, '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.vDnBn5DIZn/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.4K3IUkp4J4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 02:31:11 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 02:31:11 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 02:31:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vDnBn5DIZn/Render', 02:31:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:11 INFO - 'HOME': '/Users/cltbld', 02:31:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:11 INFO - 'LOGNAME': 'cltbld', 02:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:11 INFO - 'MOZ_NO_REMOTE': '1', 02:31:11 INFO - 'NO_EM_RESTART': '1', 02:31:11 INFO - 'PAGER': '/bin/cat', 02:31:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:11 INFO - 'PWD': '/builds/slave/test', 02:31:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:11 INFO - 'SHELL': '/bin/bash', 02:31:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4K3IUkp4J4/Listeners', 02:31:11 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 02:31:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:11 INFO - 'USER': 'cltbld', 02:31:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:11 INFO - 'XPC_FLAGS': '0x0', 02:31:11 INFO - 'XPC_SERVICE_NAME': '0', 02:31:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:11 INFO - Downloading/unpacking psutil>=3.1.1 02:31:11 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 02:31:11 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 02:31:11 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 02:31:11 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 02:31:11 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 02:31:11 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 02:31:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:31:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:31:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:31:15 INFO - Installing collected packages: psutil 02:31:15 INFO - Running setup.py install for psutil 02:31:15 INFO - building 'psutil._psutil_osx' extension 02:31:15 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 02:31:15 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 02:31:15 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 02:31:15 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 02:31:16 INFO - building 'psutil._psutil_posix' extension 02:31:16 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 02:31:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:31:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:31:16 INFO - ^ 02:31:16 INFO - 1 warning generated. 02:31:16 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 02:31:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:31:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:31:16 INFO - Successfully installed psutil 02:31:16 INFO - Cleaning up... 02:31:16 INFO - Return code: 0 02:31:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:31:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:16 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 0x10530e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105284570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b7720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105122d30>, '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 0x104f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10511ab20>, '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.vDnBn5DIZn/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.4K3IUkp4J4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 02:31:16 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 02:31:16 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 02:31:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vDnBn5DIZn/Render', 02:31:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:16 INFO - 'HOME': '/Users/cltbld', 02:31:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:16 INFO - 'LOGNAME': 'cltbld', 02:31:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:16 INFO - 'MOZ_NO_REMOTE': '1', 02:31:16 INFO - 'NO_EM_RESTART': '1', 02:31:16 INFO - 'PAGER': '/bin/cat', 02:31:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:16 INFO - 'PWD': '/builds/slave/test', 02:31:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:16 INFO - 'SHELL': '/bin/bash', 02:31:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4K3IUkp4J4/Listeners', 02:31:16 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 02:31:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:16 INFO - 'USER': 'cltbld', 02:31:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:16 INFO - 'XPC_FLAGS': '0x0', 02:31:16 INFO - 'XPC_SERVICE_NAME': '0', 02:31:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:31: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 02:31: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 02:31: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 02:31: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 02:31: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 02:31: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 02:31:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:31:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:31:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:31:19 INFO - Installing collected packages: mozsystemmonitor 02:31:19 INFO - Running setup.py install for mozsystemmonitor 02:31:19 INFO - Successfully installed mozsystemmonitor 02:31:19 INFO - Cleaning up... 02:31:19 INFO - Return code: 0 02:31:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:31:19 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')]} 02:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:19 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')]} 02:31:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:19 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 0x10530e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105284570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b7720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105122d30>, '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 0x104f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10511ab20>, '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.vDnBn5DIZn/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.4K3IUkp4J4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 02:31:19 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 02:31:19 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 02:31:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vDnBn5DIZn/Render', 02:31:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:19 INFO - 'HOME': '/Users/cltbld', 02:31:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:19 INFO - 'LOGNAME': 'cltbld', 02:31:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:19 INFO - 'MOZ_NO_REMOTE': '1', 02:31:19 INFO - 'NO_EM_RESTART': '1', 02:31:19 INFO - 'PAGER': '/bin/cat', 02:31:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:19 INFO - 'PWD': '/builds/slave/test', 02:31:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:19 INFO - 'SHELL': '/bin/bash', 02:31:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4K3IUkp4J4/Listeners', 02:31:19 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 02:31:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:19 INFO - 'USER': 'cltbld', 02:31:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:19 INFO - 'XPC_FLAGS': '0x0', 02:31:19 INFO - 'XPC_SERVICE_NAME': '0', 02:31:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:19 INFO - Downloading/unpacking jsonschema==2.5.1 02:31:19 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 02:31:19 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 02:31:19 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 02:31:19 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 02:31:19 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 02:31:19 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 02:31:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:31:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:22 INFO - Downloading functools32-3.2.3-2.tar.gz 02:31:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:31:22 INFO - warning: no files found matching '*.txt' 02:31:22 INFO - no previously-included directories found matching 'build' 02:31:22 INFO - no previously-included directories found matching 'dist' 02:31:22 INFO - no previously-included directories found matching '.git*' 02:31:22 INFO - Installing collected packages: jsonschema, functools32 02:31:22 INFO - Running setup.py install for functools32 02:31:23 INFO - warning: no files found matching '*.txt' 02:31:23 INFO - no previously-included directories found matching 'build' 02:31:23 INFO - no previously-included directories found matching 'dist' 02:31:23 INFO - no previously-included directories found matching '.git*' 02:31:23 INFO - Successfully installed jsonschema functools32 02:31:23 INFO - Cleaning up... 02:31:23 INFO - Return code: 0 02:31:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:23 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 0x10530e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105284570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b7720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105122d30>, '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 0x104f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10511ab20>, '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.vDnBn5DIZn/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.4K3IUkp4J4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 02:31:23 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 02:31:23 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 02:31:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vDnBn5DIZn/Render', 02:31:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:23 INFO - 'HOME': '/Users/cltbld', 02:31:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:23 INFO - 'LOGNAME': 'cltbld', 02:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:23 INFO - 'MOZ_NO_REMOTE': '1', 02:31:23 INFO - 'NO_EM_RESTART': '1', 02:31:23 INFO - 'PAGER': '/bin/cat', 02:31:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:23 INFO - 'PWD': '/builds/slave/test', 02:31:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:23 INFO - 'SHELL': '/bin/bash', 02:31:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4K3IUkp4J4/Listeners', 02:31:23 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 02:31:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:23 INFO - 'USER': 'cltbld', 02:31:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:23 INFO - 'XPC_FLAGS': '0x0', 02:31:23 INFO - 'XPC_SERVICE_NAME': '0', 02:31:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:31:23 INFO - Cleaning up... 02:31:23 INFO - Return code: 0 02:31:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:23 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 0x10530e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105284570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b7720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105122d30>, '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 0x104f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10511ab20>, '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.vDnBn5DIZn/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.4K3IUkp4J4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 02:31:23 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 02:31:23 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 02:31:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vDnBn5DIZn/Render', 02:31:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:23 INFO - 'HOME': '/Users/cltbld', 02:31:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:23 INFO - 'LOGNAME': 'cltbld', 02:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:23 INFO - 'MOZ_NO_REMOTE': '1', 02:31:23 INFO - 'NO_EM_RESTART': '1', 02:31:23 INFO - 'PAGER': '/bin/cat', 02:31:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:23 INFO - 'PWD': '/builds/slave/test', 02:31:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:23 INFO - 'SHELL': '/bin/bash', 02:31:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4K3IUkp4J4/Listeners', 02:31:23 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 02:31:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:23 INFO - 'USER': 'cltbld', 02:31:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:23 INFO - 'XPC_FLAGS': '0x0', 02:31:23 INFO - 'XPC_SERVICE_NAME': '0', 02:31:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:23 INFO - Downloading/unpacking blobuploader==1.2.4 02:31:23 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 02:31:23 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 02:31:23 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 02:31:23 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 02:31:23 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 02:31:23 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 02:31:26 INFO - Downloading blobuploader-1.2.4.tar.gz 02:31:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:31:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:31:26 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 02:31:26 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 02:31:26 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 02:31:26 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 02:31:26 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 02:31:26 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 02:31:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:31:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:27 INFO - Downloading docopt-0.6.1.tar.gz 02:31:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:31:27 INFO - Installing collected packages: blobuploader, requests, docopt 02:31:27 INFO - Running setup.py install for blobuploader 02:31:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:31:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:31:27 INFO - Running setup.py install for requests 02:31:28 INFO - Running setup.py install for docopt 02:31:28 INFO - Successfully installed blobuploader requests docopt 02:31:28 INFO - Cleaning up... 02:31:28 INFO - Return code: 0 02:31:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:31: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')]} 02:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31: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')]} 02:31:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:28 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 0x10530e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105284570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b7720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105122d30>, '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 0x104f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10511ab20>, '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.vDnBn5DIZn/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.4K3IUkp4J4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 02:31:28 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 02:31:28 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 02:31:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vDnBn5DIZn/Render', 02:31:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:28 INFO - 'HOME': '/Users/cltbld', 02:31:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:28 INFO - 'LOGNAME': 'cltbld', 02:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:28 INFO - 'MOZ_NO_REMOTE': '1', 02:31:28 INFO - 'NO_EM_RESTART': '1', 02:31:28 INFO - 'PAGER': '/bin/cat', 02:31:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:28 INFO - 'PWD': '/builds/slave/test', 02:31:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:28 INFO - 'SHELL': '/bin/bash', 02:31:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4K3IUkp4J4/Listeners', 02:31:28 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 02:31:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:28 INFO - 'USER': 'cltbld', 02:31:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:28 INFO - 'XPC_FLAGS': '0x0', 02:31:28 INFO - 'XPC_SERVICE_NAME': '0', 02:31:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:31:28 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-ul6NKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:31:28 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-JdPJlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:31:28 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-Cy6VLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:31:29 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-7CBB4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:31:29 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-k4wlay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:31:29 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-wXqeEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:31:29 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-YZd20s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:31:29 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-bdvUfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:31:29 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-mLj3A3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:31:29 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-gI3Gm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:31:29 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-RRYjUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:31:29 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-ZdW5ws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:31:29 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-QNV7tQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:31:30 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-XimbZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:31:30 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-RQ7JbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:31:30 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-87MuPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:31:30 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-4ZaNo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:31:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:31:30 INFO - Running setup.py install for manifestparser 02:31:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:31:30 INFO - Running setup.py install for mozcrash 02:31:30 INFO - Running setup.py install for mozdebug 02:31:30 INFO - Running setup.py install for mozdevice 02:31:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:31:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:31:31 INFO - Running setup.py install for mozfile 02:31:31 INFO - Running setup.py install for mozhttpd 02:31:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:31:31 INFO - Running setup.py install for mozinfo 02:31:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:31:31 INFO - Running setup.py install for mozInstall 02:31:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:31:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:31:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:31:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:31:31 INFO - Running setup.py install for mozleak 02:31:31 INFO - Running setup.py install for mozlog 02:31:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:31:31 INFO - Running setup.py install for moznetwork 02:31:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:31:32 INFO - Running setup.py install for mozprocess 02:31:32 INFO - Running setup.py install for mozprofile 02:31:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:31:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:31:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:31:32 INFO - Running setup.py install for mozrunner 02:31:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:31:32 INFO - Running setup.py install for mozscreenshot 02:31:32 INFO - Running setup.py install for moztest 02:31:32 INFO - Running setup.py install for mozversion 02:31:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:31:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:31:33 INFO - Cleaning up... 02:31:33 INFO - Return code: 0 02:31:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:31:33 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')]} 02:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:33 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')]} 02:31:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:33 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 0x10530e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105284570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b7720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105122d30>, '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 0x104f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10511ab20>, '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.vDnBn5DIZn/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.4K3IUkp4J4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 02:31:33 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 02:31:33 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 02:31:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vDnBn5DIZn/Render', 02:31:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:33 INFO - 'HOME': '/Users/cltbld', 02:31:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:33 INFO - 'LOGNAME': 'cltbld', 02:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:33 INFO - 'MOZ_NO_REMOTE': '1', 02:31:33 INFO - 'NO_EM_RESTART': '1', 02:31:33 INFO - 'PAGER': '/bin/cat', 02:31:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:33 INFO - 'PWD': '/builds/slave/test', 02:31:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:33 INFO - 'SHELL': '/bin/bash', 02:31:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4K3IUkp4J4/Listeners', 02:31:33 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 02:31:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:33 INFO - 'USER': 'cltbld', 02:31:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:33 INFO - 'XPC_FLAGS': '0x0', 02:31:33 INFO - 'XPC_SERVICE_NAME': '0', 02:31:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:31:33 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-XKBmQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:31:33 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)) 02:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:31:33 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-lHCIdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:31:33 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)) 02:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:31:33 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-dfEhWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:31:33 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)) 02:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:31:33 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-9a9nHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:31:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:31:33 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-7kPwDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:31:33 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)) 02:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:31:33 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-5i0JGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:31:33 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)) 02:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:31:33 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-NNWd45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:31:34 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)) 02:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:31:34 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-TklORL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:31:34 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)) 02:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:31:34 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-sWDTRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:31:34 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)) 02:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:31:34 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-DNstwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:31:34 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)) 02:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:31:34 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-GWGsO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:31:34 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)) 02:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:31:34 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-6hdNZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:31:34 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)) 02:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:31:34 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-PXaNBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:31:34 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)) 02:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:31:34 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-q_wu3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:31:34 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)) 02:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:31:34 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-1jQ6xC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:31:34 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)) 02:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:31:34 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-Fi7xoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:31:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:31:35 INFO - Running setup.py (path:/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/pip-TJFn63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:31:35 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)) 02:31:35 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)) 02:31:35 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)) 02:31:35 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)) 02:31:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:31:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:31:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:31:35 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 02:31:35 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 02:31:35 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 02:31:35 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 02:31:35 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 02:31:35 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 02:31:37 INFO - Downloading blessings-1.6.tar.gz 02:31:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:31:37 INFO - Installing collected packages: blessings 02:31:37 INFO - Running setup.py install for blessings 02:31:38 INFO - Successfully installed blessings 02:31:38 INFO - Cleaning up... 02:31:38 INFO - Return code: 0 02:31:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:31:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:31:38 INFO - Reading from file tmpfile_stdout 02:31:38 INFO - Current package versions: 02:31:38 INFO - blessings == 1.6 02:31:38 INFO - blobuploader == 1.2.4 02:31:38 INFO - docopt == 0.6.1 02:31:38 INFO - functools32 == 3.2.3-2 02:31:38 INFO - jsonschema == 2.5.1 02:31:38 INFO - manifestparser == 1.1 02:31:38 INFO - mozInstall == 1.12 02:31:38 INFO - mozcrash == 1.0 02:31:38 INFO - mozdebug == 0.1 02:31:38 INFO - mozdevice == 0.48 02:31:38 INFO - mozfile == 1.2 02:31:38 INFO - mozhttpd == 0.7 02:31:38 INFO - mozinfo == 0.9 02:31:38 INFO - mozleak == 0.1 02:31:38 INFO - mozlog == 3.4 02:31:38 INFO - moznetwork == 0.27 02:31:38 INFO - mozprocess == 0.23 02:31:38 INFO - mozprofile == 0.28 02:31:38 INFO - mozrunner == 6.13 02:31:38 INFO - mozscreenshot == 0.1 02:31:38 INFO - mozsystemmonitor == 0.3 02:31:38 INFO - moztest == 0.8 02:31:38 INFO - mozversion == 1.4 02:31:38 INFO - psutil == 3.1.1 02:31:38 INFO - requests == 1.2.3 02:31:38 INFO - wsgiref == 0.1.2 02:31:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:31:38 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')]} 02:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:39 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 0x10530e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105284570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b7720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105122d30>, '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 0x104f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10511ab20>, '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.vDnBn5DIZn/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.4K3IUkp4J4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 02:31:39 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 02:31:39 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 02:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vDnBn5DIZn/Render', 02:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:39 INFO - 'HOME': '/Users/cltbld', 02:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:39 INFO - 'LOGNAME': 'cltbld', 02:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:39 INFO - 'MOZ_NO_REMOTE': '1', 02:31:39 INFO - 'NO_EM_RESTART': '1', 02:31:39 INFO - 'PAGER': '/bin/cat', 02:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:39 INFO - 'PWD': '/builds/slave/test', 02:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:39 INFO - 'SHELL': '/bin/bash', 02:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4K3IUkp4J4/Listeners', 02:31:39 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 02:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:39 INFO - 'USER': 'cltbld', 02:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:39 INFO - 'XPC_FLAGS': '0x0', 02:31:39 INFO - 'XPC_SERVICE_NAME': '0', 02:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:39 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)) 02:31:39 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)) 02:31:39 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)) 02:31:39 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)) 02:31:39 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)) 02:31:39 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)) 02:31:39 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)) 02:31:39 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)) 02:31:39 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)) 02:31:39 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)) 02:31:39 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)) 02:31:39 INFO - Cleaning up... 02:31:39 INFO - Return code: 0 02:31:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10530e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105284570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b7720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105122d30>, '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 0x104f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10511ab20>, '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.vDnBn5DIZn/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.4K3IUkp4J4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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 02:31:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:31:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vDnBn5DIZn/Render', 02:31:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:40 INFO - 'HOME': '/Users/cltbld', 02:31:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:40 INFO - 'LOGNAME': 'cltbld', 02:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:40 INFO - 'MOZ_NO_REMOTE': '1', 02:31:40 INFO - 'NO_EM_RESTART': '1', 02:31:40 INFO - 'PAGER': '/bin/cat', 02:31:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:40 INFO - 'PWD': '/builds/slave/test', 02:31:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:40 INFO - 'SHELL': '/bin/bash', 02:31:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4K3IUkp4J4/Listeners', 02:31:40 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 02:31:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:40 INFO - 'USER': 'cltbld', 02:31:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:40 INFO - 'XPC_FLAGS': '0x0', 02:31:40 INFO - 'XPC_SERVICE_NAME': '0', 02:31:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:31:40 INFO - Cleaning up... 02:31:40 INFO - Return code: 0 02:31:40 INFO - Running post-action listener: _resource_record_post_action 02:31:40 INFO - Running post-action listener: _start_resource_monitoring 02:31:40 INFO - Starting resource monitoring. 02:31:40 INFO - [mozharness: 2017-01-04 10:31:40.706100Z] Finished create-virtualenv step (success) 02:31:40 INFO - [mozharness: 2017-01-04 10:31:40.707197Z] Running install step. 02:31:40 INFO - Running pre-action listener: _resource_record_pre_action 02:31:40 INFO - Running main action method: install 02:31:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:31:40 INFO - Reading from file tmpfile_stdout 02:31:40 INFO - Detecting whether we're running mozinstall >=1.0... 02:31:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:31:41 INFO - Reading from file tmpfile_stdout 02:31:41 INFO - Output received: 02:31:41 INFO - Usage: mozinstall [options] installer 02:31:41 INFO - Options: 02:31:41 INFO - -h, --help show this help message and exit 02:31:41 INFO - -d DEST, --destination=DEST 02:31:41 INFO - Directory to install application into. [default: 02:31:41 INFO - "/builds/slave/test"] 02:31:41 INFO - --app=APP Application being installed. [default: firefox] 02:31:41 INFO - mkdir: /builds/slave/test/build/application 02:31:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:31:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:32:12 INFO - Reading from file tmpfile_stdout 02:32:12 INFO - Output received: 02:32:12 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:32:12 INFO - Running post-action listener: _resource_record_post_action 02:32:12 INFO - [mozharness: 2017-01-04 10:32:12.211074Z] Finished install step (success) 02:32:12 INFO - [mozharness: 2017-01-04 10:32:12.211222Z] Running run-tests step. 02:32:12 INFO - Running pre-action listener: _resource_record_pre_action 02:32:12 INFO - Running main action method: run_tests 02:32:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:32:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:32:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:32:12 INFO - 2017-01-04 02:32:12.225 screenresolution[1908:18413] starting screenresolution argv=screenresolution get 02:32:12 INFO - 2017-01-04 02:32:12.228 screenresolution[1908:18413] Display 0: 1600x1200x32@60 02:32:12 INFO - 2017-01-04 02:32:12.234 screenresolution[1909:18415] starting screenresolution argv=screenresolution list 02:32:12 INFO - Available Modes on Display 0 02:32:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:32:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:32:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:32:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:32:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:32:12 INFO - Intel Iris: 02:32:12 INFO - Chipset Model: Intel Iris 02:32:12 INFO - Type: GPU 02:32:12 INFO - Bus: Built-In 02:32:12 INFO - VRAM (Dynamic, Max): 1536 MB 02:32:12 INFO - Vendor: Intel (0x8086) 02:32:12 INFO - Device ID: 0x0a2e 02:32:12 INFO - Revision ID: 0x0009 02:32:12 INFO - Displays: 02:32:12 INFO - Display: 02:32:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:32:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:32:12 INFO - Display Serial Number: 1600x1200 60 02:32:12 INFO - Main Display: Yes 02:32:12 INFO - Mirror: Off 02:32:12 INFO - Online: Yes 02:32:12 INFO - Rotation: Supported 02:32:12 INFO - Return code: 0 02:32:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:32:12 INFO - Python 2.7.3 02:32:12 INFO - Return code: 0 02:32:12 INFO - grabbing minidump binary from tooltool 02:32: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')]} 02:32:12 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 0x105122d30>, '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 0x104f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10511ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:32:12 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 02:32:12 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 02:32:12 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 02:32:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:32:12 INFO - Return code: 0 02:32:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:32:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:32:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:32:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:32:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:32:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0313', '--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 02:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0313 --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 02:32:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vDnBn5DIZn/Render', 02:32:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:12 INFO - 'HOME': '/Users/cltbld', 02:32:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:12 INFO - 'LOGNAME': 'cltbld', 02:32:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:32:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:32:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:12 INFO - 'MOZ_NO_REMOTE': '1', 02:32:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:32:12 INFO - 'NO_EM_RESTART': '1', 02:32:12 INFO - 'PAGER': '/bin/cat', 02:32:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:12 INFO - 'PWD': '/builds/slave/test', 02:32:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:32:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:12 INFO - 'SHELL': '/bin/bash', 02:32:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4K3IUkp4J4/Listeners', 02:32:12 INFO - 'TMPDIR': '/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/', 02:32:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:12 INFO - 'USER': 'cltbld', 02:32:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:12 INFO - 'XPC_FLAGS': '0x0', 02:32:12 INFO - 'XPC_SERVICE_NAME': '0', 02:32:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0313', '--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 02:32:12 INFO - mozversion application_buildid: 20170104004006 02:32:12 INFO - mozversion application_changeset: 236b90305efbbc79f66ca1453be47f3688b26c7a 02:32:12 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:32:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:32:12 INFO - mozversion application_name: Firefox 02:32:12 INFO - mozversion application_remotingname: firefox-dev 02:32:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:32:12 INFO - mozversion application_vendor: Mozilla 02:32:12 INFO - mozversion application_version: 52.0a2 02:32:12 INFO - mozversion platform_buildid: 20170104004006 02:32:12 INFO - mozversion platform_changeset: 236b90305efbbc79f66ca1453be47f3688b26c7a 02:32:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:32:12 INFO - mozversion platform_version: 52.0a2 02:32:12 INFO - using testdate: 1483525932 02:32:12 INFO - actual date: 1483525932 02:32:12 INFO - starting webserver on 'localhost:53685' 02:32:12 INFO - SUITE-START | Running 2 tests 02:32:12 INFO - TEST-START | dromaeo_css 02:32:12 INFO - operating with platform_type : mac_ 02:32:12 INFO - Initialising browser for dromaeo_css test... 02:32:12 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/tmp8TaANt/profile http://localhost:53685/getInfo.html) 02:32:16 INFO - PROCESS | 1919 | 2017-01-04 02:32:16.315 plugin-container[1922:18590] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d47, name = 'com.apple.tsm.portname' 02:32:16 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:32:16 INFO - PROCESS | 1919 | 2017-01-04 02:32:16.316 plugin-container[1922:18590] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 02:32:16 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:32:16 INFO - PROCESS | 1919 | 2017-01-04 02:32:16.316 plugin-container[1922:18590] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:32:16 INFO - PROCESS | 1919 | __metrics Screen width/height:1600/1200 02:32:16 INFO - PROCESS | 1919 | colorDepth:24 02:32:16 INFO - PROCESS | 1919 | Browser inner width/height: 1024/689 02:32:16 INFO - PROCESS | 1919 | __metrics 02:32:16 ERROR - PROCESS | 1919 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:32:17 INFO - TEST-INFO | 1919: exit 0 02:32:17 INFO - Browser initialized. 02:32:17 INFO - Running cycle 1/1 for dromaeo_css test... 02:32:17 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/tmp8TaANt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:32:20 INFO - PROCESS | 1923 | 2017-01-04 02:32:20.003 plugin-container[1924:18723] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 02:32:20 INFO - PROCESS | 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:32:20 INFO - PROCESS | 1923 | 2017-01-04 02:32:20.004 plugin-container[1924:18723] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x881f, name = 'com.apple.CFPasteboardClient' 02:32:20 INFO - PROCESS | 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:32:20 INFO - PROCESS | 1923 | 2017-01-04 02:32:20.004 plugin-container[1924:18723] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:32:29 INFO - PROCESS | 1923 | RSS: Main: 229650432 02:32:29 INFO - PROCESS | 1923 | 02:35:33 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:53685/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53685/tests/dromaeo/cssquery-ext.html) 02:35:34 INFO - PROCESS | 1923 | RSS: Main: 233816064 02:35:34 INFO - PROCESS | 1923 | 02:38:37 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:53685/tests/dromaeo/cssquery-ext.html (next: http://localhost:53685/tests/dromaeo/cssquery-jquery.html) 02:38:37 INFO - PROCESS | 1923 | RSS: Main: 243322880 02:38:37 INFO - PROCESS | 1923 | 02:41:39 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:53685/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53685/tests/dromaeo/cssquery-mootools.html) 02:41:40 INFO - PROCESS | 1923 | RSS: Main: 246296576 02:41:40 INFO - PROCESS | 1923 | 02:44:42 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:53685/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53685/tests/dromaeo/cssquery-prototype.html) 02:44:42 INFO - PROCESS | 1923 | RSS: Main: 245452800 02:44:42 INFO - PROCESS | 1923 | 02:47:45 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:53685/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53685/tests/dromaeo/cssquery-yui.html) 02:47:46 INFO - PROCESS | 1923 | RSS: Main: 248889344 02:47:46 INFO - PROCESS | 1923 | 02:50:48 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:53685/tests/dromaeo/cssquery-yui.html (next: http://localhost:53685/tests/dromaeo/cssquery-dojo.html) 02:50:49 INFO - PROCESS | 1923 | RSS: Main: 251084800 02:50:49 INFO - PROCESS | 1923 | 02:50:49 INFO - PROCESS | 1923 | __start_tp_report 02:50:49 INFO - PROCESS | 1923 | _x_x_mozilla_page_load 02:50:49 INFO - PROCESS | 1923 | _x_x_mozilla_page_load_details 02:50:49 INFO - PROCESS | 1923 | |i|pagename|runs| 02:50:49 INFO - PROCESS | 1923 | |0;dojo.html;3285.2772390074183;3310.00699790063;3324.2520432902584;3330.9666903330976;3342.68244516771;5555.111182210842;5605.803796867111;5611.130274807406;5628.240187574676;5664.971675141629;5590.496855283024;5665.660060396379;5680.204771332006;5704.3154821421385;5726.997775389311;5121.948780512184;5192.506711862386;5204.401493828202;5257.815976440826;5258.395284542269;5241.213817927303;5249.370075590934;5255.658382205144;5274.973625131869;5281.973590132063;5240.9213861792105;5245.370555533342;5258.842234732964;5264.499872512124;5308.734563271821;5061.44324124346;5118.667286626394;5136.126858434075;5223.555997740196;5247.921281180786;4361.280388735855;5033.874153146164;5156.561692256163;5162.328897243372;5251.028559716465;5734.856628584277;5737.512311453532;5773.6824474654295;5798.855028624318;5808.97095514524;6063.848403789896;6066.726997285133;6141.969290153565;6148.631082135042;6152.415520525543;6163.599366041193;6165.630062196237;6181.690915454209;6187.752489900398;6212.378762123752;5729.226554415184;5747.741351639186;5755.309362876482;5775.8556036099435;5776.9422305776825;4942.258661200777;5121.513456221653;6157;6165.753369865155;6208.348123446973;5296.894062118736;5522.420145884705;6060.515158787286;6071.362506936796;6101.664408457489;6076.665783381868;6148.262208534915;6154.046122850965;6163.137160797492;6172.382761723881;4995.350604421402;5952.851178720567;5961.225040744675;6013.6993150342305;6084.665343406155;6015.488683461955;6025.969870150709;6082.148499210114;6109;6131.50947924165;4092.4270602115726;4805.063012712558;4806.279058141236;4840.927386089212;4842.806287748451;4301.913961720748;4307.353896915425;4308.073764140725;4331.6318112960125;4371.366151907955;4447.644188464915;5751.626144300607;5793.913091303634;5932.2584676915385;5966.254218222723;6121.727351053096;6130.734471166763;6134.987697083746;6148.047052706837;6232.8130156095385;5974.701264936736;5977;5984.73068711909;5988.790392336247;6002.609830361012;5316.282301889196;5328.573714102862;5338.626296159309;5348.224507446476;5355.464453554613;6194.194754681863;6249.437550620466;6260.561760676709;6289.591176573508;6335.366463353628;4909.68087074339;6151.231096112986;6171.352008039091;6174.38256174397;6177.938220617916;5592.636478628876;6343.9365606343345;6374.203224596926;6385.8722825544155;6393.4885209184085;5982.4316689914385;5994.250718660168;6000.399960003964;6022.427869352405;6023.036314189709;5422.566194704335;5964.045752679551;6173.59871608344;6179.876402472015;6211.565190436626;5507.091329930677;5519.613627046068;5926.081457374114;6071.666058366655;6079.300880398634;5467.58993075513;5554.944450555605;5585.525230355465;5944.464998150187;5991.7902873399225;5859.033259512133;5873.823785286278;5885.087811389241;5910.995130827856;5918.526517878646;5841.094630332305;5880.647161170344;5904.881902362014;5957.7319020645045;5970.0746384310505;5761.366249712444;5786.826395208151;5803.506701930383;5809.883802324014;5823.388544202798 02:50:49 INFO - PROCESS | 1923 | |1;ext.html;435.76468706898277;452.6808599937045;452.7306252779595;471.8796706839755;481.708566317378;32228.67771322289;32496.70013199469;37972.620273797285;41396;41451.963700907494;40274;40766.7961660192;40851;40865.59134408655;40913.7954310228;7759.41804364672;9294.07059294072;9329.346945713804;9337.299702522305;9349.953250233739;8073.515589064646;9310.022447643001;9387.436753794795;9415.246780257597;9449.007854175316;9354.579043943006;9366.531673416303;9387.859182112274;9395.154436100749;9402.764930876749;9312.860307095365;9313.90686093137;9352.485613291266;9352.999999999965;9369.203617692505;9224.631014759401;9292.488913109777;9299.814003719956;9312.487813170274;9342.626294948193;38232.235355293014;38265.426018609745;38590.80704596459;38680;38718;3203.471427214507;3232.191952011997;3316.668333166664;3646.1431563582537;3813;27456.45087098267;29217.977370792134;29476.820927162888;29486.26284342887;30102.397952040836;3027.5610036544576;3202.6316973548105;3213.0682102190335;3216.372807302577;3230.5800245967876;370279;370960;371807;373488;375302.12348938355;58560;58591.41408585945;58682.11976820351;58701.11948320779;58719.41280587225;60756.69621651952;61725.69137154285;62110;62230;62419.68790156111;15950;16146.919265403598;16176.75734863978;16186.271617777229;16227.756583651255;12500.874991250153;12543.372831358396;12555.686107847194;12586;12602.621921342374;16867.57831054209;17179.226934787963;17385.47843564695;17427.825721742873;17476.475705728848;3206.599175103112;3296.291297371077;6166.321704612489;6218.098375735493;6273.9372606273355;815.514768712615;929.2333824594737;933.3746389918771;940.6143481172686;984.9655262065793;29352.119436416942;29589.704102959124;29878.701212988028;30097.398052038836;30117;949.3686698345523;1022.5143057047992;1089.6839916424149;1095.315427857589;1112.3214838948236;11830.290182589073;14741.705165896621;15307.46423875159;15364.923175384276;15540.611484712972;692223;694388;697654.5117274381;698266.5086674534;700305;55797.44202557922;55808.72095639496;56165.59586010382;56263;56615.86768264694;1295.7861952777687;1332.6068809701162;1354.6477915741978;1356.0507644648785;1357.4027427931678;28625.856870715514;28822.567661485515;28987;29748.40503189881;29769.106926792232;50623;50726.7318317045;51178.48821511737;51186.720331992;51240.23139652983;625071;630373;631036;631670;632074;20416.795832042084;21764.58530195532;26626.7337326632;26710.19869403921;26930.461390772467;38775.80612096922;41345;44323.556764431945;44683.77658111689;45107.77446112748;36841.631583684895;37394.43908341431;37647;37868.62131378651;38062;30580.541291881083;36886.44670330001;36927;37766.24467510689;38248.426273605364;36667.81666091653;36971.815140924125;37268.06829829168;37935.43096853605;38106.23787524289;31846.363072737953;33017.66982330242;33199.00402987914;33936.490952635235;34093.97718068462;25528.74471255338;25550.105746298792;25654;25663.10179143721;25882.87058564695 02:50:49 INFO - PROCESS | 1923 | |2;jquery.html;317.2623650013718;363.5818808370374;375.2907005759115;380.7981769662077;396.3480075276171;18802.529936751573;19123.521911952208;19228.42314730557;19543.511412214702;19625.705614415776;19294.71057934132;19576.70634940477;19834.801651983475;20044.498887527818;20118.295859644917;5051.595872330223;5820.970895145529;5995.61028533144;6051.12258722485;6060.848478788022;6050.636961782285;6188.81433556994;6202.7518899244205;6202.81391558254;6222.03558448439;6008.429199226067;6166.691665416712;6175.444210021097;6568.441682456973;6619.172603424572;6466.870662586722;6593.439557637583;6595.142631457904;6652.634105124216;6672.633005184713;5622.493975542221;5671.971640141773;5697.487226149667;5715.342735585377;5720.713964301768;23637.409064773346;23649.64525532101;24036.639450408256;24106.638400424006;24428.755712443002;2279.863208207513;2359.374765687094;2817.0140450842246;3050.5119180930947;3098;12082;14287.499937502138;15482.458113965957;15697.529074127795;15948.76076858848;1791.1939627167826;1809.5747499337645;1857.6099019205983;1874.2221977879165;1875.3436297295973;95941.08117837604;102791.48604257006;106993;108557;113805;7591.620418979029;8842.955785221162;9056.592453339616;9797;11030.283031603081;9997.350172238781;12096.758064838654;12203.816942745865;12283.877161228453;12340.568080117153;7790.961045194737;7856.214378562098;7888.40836937232;7914.169012253746;7957.801054973672;7184.389326907167;8124.837503249902;8182.836343273101;8293.709720159766;8325.458845175044;3203.775735698479;3840.6735427488416;3899.0447340401784;3919.431682406035;3927.430522574268;1658.2372108829832;1927;1956.7651881774277;1997.3109277299309;1997.7802441731399;891.9286457083548;969.60730903984;974.2303580171726;981.9214462842956;986.2652324018509;18800.43598692014;19848.702269465968;19909.800901991086;20009.499762506053;20358.796412035987;885.4952676974192;912.4114945859883;912.5802131019806;940.3652534539159;940.7789169545151;9613;10151.340162889388;10159.796804064024;10215.693529194135;10371.429571373646;596341;601670;607514;624399;626526;100546.4972675132;120071;121333.18000230004;123569.38215308866;124080;937.803061357109;941.7410212191702;963.7927845513249;964.9324547281623;968.3415277611151;17031.31874724997;17167.399141030004;17446.778725489094;17493.475195744148;17775.8445701029;48171.03657926892;49282.75358623184;49943.50056499534;50201.24698129458;50396.748016261154;59253.40746592651;68276.6586167066;71541.6422917882;73498.26501734914;74885;5900.763969441175;7604.80987975305;7725.034370703662;7822.21777822236;7837.647305871135;9880.590025755115;12067.51729930828;12103.576374826838;12292.508299667914;12336.81494777561;8484.278836298983;9777.755556111155;9901.900980990386;9928.007199280015;9953.800923981624;9727.319087664078;9747.463889485864;9871;10151.79696406053;10283.794324113325;8763.737087887373;10012.749681258027;10078.244131690299;10458.372497650165;10541.57833686644;7499;7515.549067055994;7602.847943040998;7800.024996875391;7887.68449262046;8516;9999;10134.239932005277;10164.440955747505;10380.013898679614 02:50:49 INFO - PROCESS | 1923 | |3;mootools.html;1176.6293617510485;1402.9407797113179;1509.5622269541834;1742.877998540101;1770.557360659836;6478.805635830932;6871.484638652096;7506.249375062498;7936.444448888573;8429.157084291568;6180.783672571461;7336.889946650805;8212.589370531465;8317.126701696325;8384.496930184194;1869.598036422169;2287.553926984239;2304.562133194696;2313.803326717231;2318.084356679111;2273.6816845641624;2320.744718081018;2329.324495896188;2336.3458231695067;2339.5671800716923;2257.6500642400374;2278.9886050569726;2310.780475854791;2312.838101332907;2315.340128063502;2265.5160869630336;2268.4669102760836;2283.6117859963847;2283.862968221912;2293.885305734715;2198.758136604972;2254.7519772824976;2264.6489794081945;2264.7169103862016;2271.068861766668;8213;8236.341092712628;8240.958795206045;8300.335973122135;8308.916910830936;4865.829695960624;4992.151334273182;5151.639385243035;5162.664426812245;5180.507851754115;2894.2474956511246;3286.277019055804;3560.6795388414907;3561.9109522261892;3573.213892943548;872.9607167677469;1009.8939611340851;1074.6399956014745;1077.0306723948431;1085.3542142425324;10697.304675196086;10792.838107428395;10842.945785271024;10905.127589792797;10922.672319830417;3798.810059497014;4034.7175697700877;4328.220920234325;4601.631869450436;4739.146953548326;7559.357454616309;9212.216961558208;9256.537173141316;9268.95365523168;9274.814503710022;5129.538341549279;5137.743112844343;5139.640225184201;5143.919776846829;5153.768080436389;4227.154569086134;4485.484169320504;4675.392199014106;4698.060387922429;4703.318018887242;3825.3496905526126;3836.1176929306416;3885.669718073939;3934.940975885364;3954.387070004154;1253.943572539238;1255.805350170725;1263.684078980255;1266.936653167338;1268.6447794617523;768.7655265144185;887.5961437545901;893.8703191256437;920.6961702638114;927.1238679447857;5796.3913789052385;5837.708114594338;5864.091065884794;5869.325027621878;5876.588638795329;909.9545022748836;911.334725650279;940.7365937537501;941.3928016429502;984.3601658921758;3710.424884142962;3814.3134235837815;3858.7684738915195;3863.497745293094;3897.279003384383;9627.470489123165;9886.011398860057;9954.303198776017;9962.950185249172;9976.102150806462;9946.20430365569;10011.649592264235;10027.548760305805;10050.592916991627;10092.738407699038;7396.5562066275115;7709.922900770921;7712.421568382506;7729;7851.371890248766;5387.407385187548;5443.101888188405;5503.587230957774;5762.366139724543;5770.278715160605;7750;8176.713815016446;8771.596544552842;9097.909020909707;9367.063293670579;9174.174324310845;9287.839020122485;9445.527723613654;9446.149846513847;9567.760805979906;6587.077809106633;7075.327843854826;7292.34368906801;7308.196098429275;7558.622068896643;6860.176778786577;7545.7358992435;7804.2585954334245;7900.2889739923685;7902.091259505;6976.790696279119;7169.103862017248;7186;7247.021652077113;7270.6728197230195;6779.55932864382;6903.572457218938;6996;7021.894671579828;7119.003339532366;6641.302663220292;6654.401103900672;6721.361470660279;6730.259671436236;6765.661716914233;4525.275955847049;4565.977170114128;4738.07607516531;4892.192788189909;4905.190643543918;4787.54518320759;4883.60931125502;4899.951000490092;4966.230234313687;5047.041062198171 02:50:49 INFO - PROCESS | 1923 | |4;prototype.html;299.48189631936737;302.34391370725524;303.42652386988584;308.7067286078225;311.35549412715676;4623.930641040383;4679.929801052983;5121.692698438087;5219.895602087965;5413.972930135354;5054.924176137351;5229.477052294768;5229.9080367852785;5238.406988540678;5246.344206974129;3552.6447355264527;4024.134811015638;4062.329715596923;4074.2666320062376;4111.568285330042;3457.636948120449;3929.6659783918403;4030.1738143680477;4067.1458993611227;4076.1847630473876;4004.7597144171295;4029.9395509067385;4036.1725846201457;4041.7574945503216;4058.9188216235807;3309.5366648669205;3835.827387767557;4009.1179940413062;4013.4581831452674;4048.332025215836;2942.367391010943;3519.208178159919;3550.065214458101;3552.2717842842158;3558.466230065485;4471.463424389094;5012.498750124995;5122.487751224885;5131.589472842164;5173.094708426011;3813.161104556993;4431.825566224953;4598.793054312564;4614.607758340545;4632;5523.7238138093335;5528.5024347808485;5537.556995440355;5560.443955604448;5638.74625641843;3292.3250733599552;3761.2665530221616;3845.7115716321387;3878.0886491674432;3900.5124359455067;4946.72792996385;5805.796797112123;5812.244408226903;5826.417358264182;5840.532757379399;3323.5845519310087;3620.6741393274206;3715.368387374153;3951;3955.6439920406724;3173;3239.9838000809846;3325.700686938139;3962.9009274767786;3979.4826672532963;3843.9440644687543;4342;4452.554744525586;4598.632109431237;4618.399608050931;5284.841454756363;5286.9471305287225;5560.081771788727;5624.831255062355;5668.57485688575;1104.6685994201707;1231.9630411087683;1257.8176164456102;1281.333706472629;1286.3375361688738;871.8561437362891;895.8969718482458;904.9276057915351;906.9863952040724;908.7092130518303;4516.954830451719;4527.962416912403;5037.773300201499;5105;5132.538071573502;4889.828855990023;5002.649814513022;5395.811146609928;5440.483154100354;5458.707342915985;4679.876829560949;4688.647616953895;5044.495550444926;5048.242763585492;5070.467600901852;2795.343094372821;2831.59835881236;2834.1639216431204;2854.728800763924;2866.3837274985976;4882.707037577757;5309.070912590359;5386.326709161355;5592.468715472024;5708.573856111137;4032.644743676822;5222.843314700565;5497.670139791545;5518.9304010995875;5544.473275038865;5105.0810854045885;5615.241942337732;5667.263255776723;5748.913766293509;5770.769169233184;4622.375979242759;4850.733209673361;4850.902981940412;4981.501849814989;5041.899162016813;4148.709590328769;4790.976045119752;4809.134355815987;5073.974630126826;5355.4376790436445;4959;5675.0636145035605;5776.422357764358;5777.8555536111935;5902.822915312548;3650.4341827016856;3752.5872154062486;3762.435634654824;3762.6425489578446;3775.244951009864;4348.891277718082;5120.334356533627;5244.606654501004;5378.542823860016;5502.193881120577;4730.763461826826;4847.830325938575;5453.563714902719;5641.351244606923;5667.1015209905145;5625.668235458756;5634.929363420937;5642.379338272869;5650.0959846424375;5653.858653533694;5037.244413338029;5230.189320655304;5508;5516.61383703153;5538.529225015919;5007.974960125322;5180.912008478262;5347.582890533934;5551.666899986014;5600.523955463668;4504.549545045469;5039.571636410799;5040.974795126001;5530.225768392348;5539.362973258127 02:50:49 INFO - PROCESS | 1923 | |5;yui.html;653.7741734248287;688.7623769799418;696.3245651717832;763.9915311788438;789.7867575754548;14191.787123193148;14918.403263869459;14928.029678070918;14933.328000239988;14967.47613833516;13223.537176198812;13268.867311326883;13269.40287687054;13321.13412628181;13491.190528568293;630.0486265738745;652.282489261813;784.8901153838467;791.1100012485967;802.4904185842004;809.6437567470324;811.1401913971209;815.8490679224333;819.3690858039306;819.7622689420061;862.5644049754897;867.535868310454;868.0408148995364;868.097178933908;868.2489646455801;840.3739214285335;853.9786505337416;860.320346925934;862.7670528957178;862.9309655227598;642.0850288339099;642.2132887213173;642.3961476212345;643.9195100612424;644.935506449356;13442.32788360578;13856.584302470941;13868.4452621895;13921.58235252944;14204.786928196085;11899.821502677467;12108.636740897788;12123.878761212363;13250.536231231958;13750.724985500334;11805.350705711182;11979.28124312544;12001.279923204636;12245.693857653541;12351;4346.195953748566;4388.473383194005;4392.516823149451;4440.977795111036;4517.548245175489;67999.66000169968;88242.79393015162;90310.54844725734;90512.73718157032;90694.54652726761;9992.700218993441;9996.950015249877;10056.497175141214;10100.646477373257;10162.695119146438;18752.81247187538;18776;18777.24891004372;18843.811561884206;18917.148728306984;6294.685265736694;6996.790096297119;7148.356647901714;7208.681764525018;7238.92761072393;4061.756294622332;4148.91702165955;4155.3766934959995;4224.176285624335;4225.809838557272;3171.9207019824225;3230.582717227149;3242.821644809511;3272.672732726756;3275.5250488679008;612.7548980407872;616.8211218746603;618.0636335951037;620.1472974659843;626.1859582542768;533.5651444073068;579.9101139323411;590.153130258081;592.1029640095259;631.5074242091176;8434.28308593764;8533.871991920127;8588.613512391958;8661.566921653892;8894.822103557892;590.9586328956932;593.7061154728437;619.7087368936683;627.2003195925141;631.7346714379879;3991.960080399217;4147.3778933160265;4178.519470260898;4209.052963083343;4213.157368526307;62557;70933.64533177404;87545.56560951198;105843.4707826456;110307.44846275717;74355.14112147239;78869.60565197137;86135;89909.30272091848;106305;73652.63173684098;94419;96808.51595741976;98378;99304;8011.759647210593;8147.388945828968;8184.713535026245;8191.590420478833;8396.412251142605;54295.72852135714;55042.72478637581;57700.422995769506;60746;60764.480887978156;70235;75920.62039689766;79772.60113699627;85107.29785404133;86129;8406.873896891673;8654.30765538768;10086.142677872464;10163.7459063524;10389.428581428094;13493.32533373347;13748.968827338189;13752.31238438094;13995.370208340446;14108.224047677066;10649.89350106489;12274.711155328561;12330;12423.627291180917;13104.672383190496;11022.283551569122;11960.58137965167;12915.741685166431;13393.995450341068;13587.660308492366;12394;12429.875701243234;12559.183653062522;13050.34748262564;13316.267605281415;8146.714864979697;10457.68626941193;11062.225644205151;11217;11606.419679016184;9648.951755241178;10358;11210.775784484427;11251.21917235615;11432.199746017699 02:50:49 INFO - PROCESS | 1923 | __end_tp_report 02:50:49 INFO - PROCESS | 1923 | __start_cc_report 02:50:49 INFO - PROCESS | 1923 | _x_x_mozilla_cycle_collect,371 02:50:49 INFO - PROCESS | 1923 | __end_cc_report 02:50:49 INFO - PROCESS | 1923 | __startTimestamp1483527049232__endTimestamp 02:50:49 INFO - PROCESS | 1923 | 02:50:49 INFO - PROCESS | 1923 | ------- Summary: start ------- 02:50:49 INFO - PROCESS | 1923 | Number of tests: 6 02:50:49 INFO - PROCESS | 1923 | 02:50:49 INFO - PROCESS | 1923 | [#0] dojo.html Cycles:165 Average:5619.33 Median:5803.51 stddev:644.78 (11.1%) stddev-sans-first:620.21 02:50:49 INFO - PROCESS | 1923 | Values: 3285.3 3310.0 3324.3 3331.0 3342.7 5555.1 5605.8 5611.1 5628.2 5665.0 5590.5 5665.7 5680.2 5704.3 5727.0 5121.9 5192.5 5204.4 5257.8 5258.4 5241.2 5249.4 5255.7 5275.0 5282.0 5240.9 5245.4 5258.8 5264.5 5308.7 5061.4 5118.7 5136.1 5223.6 5247.9 4361.3 5033.9 5156.6 5162.3 5251.0 5734.9 5737.5 5773.7 5798.9 5809.0 6063.8 6066.7 6142.0 6148.6 6152.4 6163.6 6165.6 6181.7 6187.8 6212.4 5729.2 5747.7 5755.3 5775.9 5776.9 4942.3 5121.5 6157.0 6165.8 6208.3 5296.9 5522.4 6060.5 6071.4 6101.7 6076.7 6148.3 6154.0 6163.1 6172.4 4995.4 5952.9 5961.2 6013.7 6084.7 6015.5 6026.0 6082.1 6109.0 6131.5 4092.4 4805.1 4806.3 4840.9 4842.8 4301.9 4307.4 4308.1 4331.6 4371.4 4447.6 5751.6 5793.9 5932.3 5966.3 6121.7 6130.7 6135.0 6148.0 6232.8 5974.7 5977.0 5984.7 5988.8 6002.6 5316.3 5328.6 5338.6 5348.2 5355.5 6194.2 6249.4 6260.6 6289.6 6335.4 4909.7 6151.2 6171.4 6174.4 6177.9 5592.6 6343.9 6374.2 6385.9 6393.5 5982.4 5994.3 6000.4 6022.4 6023.0 5422.6 5964.0 6173.6 6179.9 6211.6 5507.1 5519.6 5926.1 6071.7 6079.3 5467.6 5554.9 5585.5 5944.5 5991.8 5859.0 5873.8 5885.1 5911.0 5918.5 5841.1 5880.6 5904.9 5957.7 5970.1 5761.4 5786.8 5803.5 5809.9 5823.4 02:50:49 INFO - PROCESS | 1923 | 02:50:49 INFO - PROCESS | 1923 | [#1] ext.html Cycles:180 Average:69178.72 Median:38084.12 stddev:156644.03 (411.3%) stddev-sans-first:156998.43 02:50:49 INFO - PROCESS | 1923 | Values: 435.8 452.7 452.7 471.9 481.7 32228.7 32496.7 37972.6 41396.0 41452.0 40274.0 40766.8 40851.0 40865.6 40913.8 7759.4 9294.1 9329.3 9337.3 9350.0 8073.5 9310.0 9387.4 9415.2 9449.0 9354.6 9366.5 9387.9 9395.2 9402.8 9312.9 9313.9 9352.5 9353.0 9369.2 9224.6 9292.5 9299.8 9312.5 9342.6 38232.2 38265.4 38590.8 38680.0 38718.0 3203.5 3232.2 3316.7 3646.1 3813.0 27456.5 29218.0 29476.8 29486.3 30102.4 3027.6 3202.6 3213.1 3216.4 3230.6 370279.0 370960.0 371807.0 373488.0 375302.1 58560.0 58591.4 58682.1 58701.1 58719.4 60756.7 61725.7 62110.0 62230.0 62419.7 15950.0 16146.9 16176.8 16186.3 16227.8 12500.9 12543.4 12555.7 12586.0 12602.6 16867.6 17179.2 17385.5 17427.8 17476.5 3206.6 3296.3 6166.3 6218.1 6273.9 815.5 929.2 933.4 940.6 985.0 29352.1 29589.7 29878.7 30097.4 30117.0 949.4 1022.5 1089.7 1095.3 1112.3 11830.3 14741.7 15307.5 15364.9 15540.6 692223.0 694388.0 697654.5 698266.5 700305.0 55797.4 55808.7 56165.6 56263.0 56615.9 1295.8 1332.6 1354.6 1356.1 1357.4 28625.9 28822.6 28987.0 29748.4 29769.1 50623.0 50726.7 51178.5 51186.7 51240.2 625071.0 630373.0 631036.0 631670.0 632074.0 20416.8 21764.6 26626.7 26710.2 26930.5 38775.8 41345.0 44323.6 44683.8 45107.8 36841.6 37394.4 37647.0 37868.6 38062.0 30580.5 36886.4 36927.0 37766.2 38248.4 36667.8 36971.8 37268.1 37935.4 38106.2 31846.4 33017.7 33199.0 33936.5 34094.0 25528.7 25550.1 25654.0 25663.1 25882.9 02:50:49 INFO - PROCESS | 1923 | 02:50:49 INFO - PROCESS | 1923 | [#2] jquery.html Cycles:180 Average:34055.24 Median:27724.17 stddev:101405.00 (365.8%) stddev-sans-first:101657.83 02:50:49 INFO - PROCESS | 1923 | Values: 317.3 363.6 375.3 380.8 396.3 18802.5 19123.5 19228.4 19543.5 19625.7 19294.7 19576.7 19834.8 20044.5 20118.3 5051.6 5821.0 5995.6 6051.1 6060.8 6050.6 6188.8 6202.8 6202.8 6222.0 6008.4 6166.7 6175.4 6568.4 6619.2 6466.9 6593.4 6595.1 6652.6 6672.6 5622.5 5672.0 5697.5 5715.3 5720.7 23637.4 23649.6 24036.6 24106.6 24428.8 2279.9 2359.4 2817.0 3050.5 3098.0 12082.0 14287.5 15482.5 15697.5 15948.8 1791.2 1809.6 1857.6 1874.2 1875.3 95941.1 102791.5 106993.0 108557.0 113805.0 7591.6 8843.0 9056.6 9797.0 11030.3 9997.4 12096.8 12203.8 12283.9 12340.6 7791.0 7856.2 7888.4 7914.2 7957.8 7184.4 8124.8 8182.8 8293.7 8325.5 3203.8 3840.7 3899.0 3919.4 3927.4 1658.2 1927.0 1956.8 1997.3 1997.8 891.9 969.6 974.2 981.9 986.3 18800.4 19848.7 19909.8 20009.5 20358.8 885.5 912.4 912.6 940.4 940.8 9613.0 10151.3 10159.8 10215.7 10371.4 596341.0 601670.0 607514.0 624399.0 626526.0 100546.5 120071.0 121333.2 123569.4 124080.0 937.8 941.7 963.8 964.9 968.3 17031.3 17167.4 17446.8 17493.5 17775.8 48171.0 49282.8 49943.5 50201.2 50396.7 59253.4 68276.7 71541.6 73498.3 74885.0 5900.8 7604.8 7725.0 7822.2 7837.6 9880.6 12067.5 12103.6 12292.5 12336.8 8484.3 9777.8 9901.9 9928.0 9953.8 9727.3 9747.5 9871.0 10151.8 10283.8 8763.7 10012.7 10078.2 10458.4 10541.6 7499.0 7515.5 7602.8 7800.0 7887.7 8516.0 9999.0 10134.2 10164.4 10380.0 02:50:49 INFO - PROCESS | 1923 | 02:50:49 INFO - PROCESS | 1923 | [#3] mootools.html Cycles:180 Average:5348.81 Median:5158.22 stddev:2908.34 (56.4%) stddev-sans-first:2899.59 02:50:49 INFO - PROCESS | 1923 | Values: 1176.6 1402.9 1509.6 1742.9 1770.6 6478.8 6871.5 7506.2 7936.4 8429.2 6180.8 7336.9 8212.6 8317.1 8384.5 1869.6 2287.6 2304.6 2313.8 2318.1 2273.7 2320.7 2329.3 2336.3 2339.6 2257.7 2279.0 2310.8 2312.8 2315.3 2265.5 2268.5 2283.6 2283.9 2293.9 2198.8 2254.8 2264.6 2264.7 2271.1 8213.0 8236.3 8241.0 8300.3 8308.9 4865.8 4992.2 5151.6 5162.7 5180.5 2894.2 3286.3 3560.7 3561.9 3573.2 873.0 1009.9 1074.6 1077.0 1085.4 10697.3 10792.8 10842.9 10905.1 10922.7 3798.8 4034.7 4328.2 4601.6 4739.1 7559.4 9212.2 9256.5 9269.0 9274.8 5129.5 5137.7 5139.6 5143.9 5153.8 4227.2 4485.5 4675.4 4698.1 4703.3 3825.3 3836.1 3885.7 3934.9 3954.4 1253.9 1255.8 1263.7 1266.9 1268.6 768.8 887.6 893.9 920.7 927.1 5796.4 5837.7 5864.1 5869.3 5876.6 910.0 911.3 940.7 941.4 984.4 3710.4 3814.3 3858.8 3863.5 3897.3 9627.5 9886.0 9954.3 9963.0 9976.1 9946.2 10011.6 10027.5 10050.6 10092.7 7396.6 7709.9 7712.4 7729.0 7851.4 5387.4 5443.1 5503.6 5762.4 5770.3 7750.0 8176.7 8771.6 9097.9 9367.1 9174.2 9287.8 9445.5 9446.1 9567.8 6587.1 7075.3 7292.3 7308.2 7558.6 6860.2 7545.7 7804.3 7900.3 7902.1 6976.8 7169.1 7186.0 7247.0 7270.7 6779.6 6903.6 6996.0 7021.9 7119.0 6641.3 6654.4 6721.4 6730.3 6765.7 4525.3 4566.0 4738.1 4892.2 4905.2 4787.5 4883.6 4900.0 4966.2 5047.0 02:50:49 INFO - PROCESS | 1923 | 02:50:49 INFO - PROCESS | 1923 | [#4] prototype.html Cycles:180 Average:4393.44 Median:4952.86 stddev:1342.08 (27.1%) stddev-sans-first:1310.20 02:50:49 INFO - PROCESS | 1923 | Values: 299.5 302.3 303.4 308.7 311.4 4623.9 4679.9 5121.7 5219.9 5414.0 5054.9 5229.5 5229.9 5238.4 5246.3 3552.6 4024.1 4062.3 4074.3 4111.6 3457.6 3929.7 4030.2 4067.1 4076.2 4004.8 4029.9 4036.2 4041.8 4058.9 3309.5 3835.8 4009.1 4013.5 4048.3 2942.4 3519.2 3550.1 3552.3 3558.5 4471.5 5012.5 5122.5 5131.6 5173.1 3813.2 4431.8 4598.8 4614.6 4632.0 5523.7 5528.5 5537.6 5560.4 5638.7 3292.3 3761.3 3845.7 3878.1 3900.5 4946.7 5805.8 5812.2 5826.4 5840.5 3323.6 3620.7 3715.4 3951.0 3955.6 3173.0 3240.0 3325.7 3962.9 3979.5 3843.9 4342.0 4452.6 4598.6 4618.4 5284.8 5286.9 5560.1 5624.8 5668.6 1104.7 1232.0 1257.8 1281.3 1286.3 871.9 895.9 904.9 907.0 908.7 4517.0 4528.0 5037.8 5105.0 5132.5 4889.8 5002.6 5395.8 5440.5 5458.7 4679.9 4688.6 5044.5 5048.2 5070.5 2795.3 2831.6 2834.2 2854.7 2866.4 4882.7 5309.1 5386.3 5592.5 5708.6 4032.6 5222.8 5497.7 5518.9 5544.5 5105.1 5615.2 5667.3 5748.9 5770.8 4622.4 4850.7 4850.9 4981.5 5041.9 4148.7 4791.0 4809.1 5074.0 5355.4 4959.0 5675.1 5776.4 5777.9 5902.8 3650.4 3752.6 3762.4 3762.6 3775.2 4348.9 5120.3 5244.6 5378.5 5502.2 4730.8 4847.8 5453.6 5641.4 5667.1 5625.7 5634.9 5642.4 5650.1 5653.9 5037.2 5230.2 5508.0 5516.6 5538.5 5008.0 5180.9 5347.6 5551.7 5600.5 4504.5 5039.6 5041.0 5530.2 5539.4 02:50:49 INFO - PROCESS | 1923 | 02:50:49 INFO - PROCESS | 1923 | [#5] yui.html Cycles:180 Average:19822.84 Median:29140.17 stddev:28890.10 (99.1%) stddev-sans-first:28935.29 02:50:49 INFO - PROCESS | 1923 | Values: 653.8 688.8 696.3 764.0 789.8 14191.8 14918.4 14928.0 14933.3 14967.5 13223.5 13268.9 13269.4 13321.1 13491.2 630.0 652.3 784.9 791.1 802.5 809.6 811.1 815.8 819.4 819.8 862.6 867.5 868.0 868.1 868.2 840.4 854.0 860.3 862.8 862.9 642.1 642.2 642.4 643.9 644.9 13442.3 13856.6 13868.4 13921.6 14204.8 11899.8 12108.6 12123.9 13250.5 13750.7 11805.4 11979.3 12001.3 12245.7 12351.0 4346.2 4388.5 4392.5 4441.0 4517.5 67999.7 88242.8 90310.5 90512.7 90694.5 9992.7 9997.0 10056.5 10100.6 10162.7 18752.8 18776.0 18777.2 18843.8 18917.1 6294.7 6996.8 7148.4 7208.7 7238.9 4061.8 4148.9 4155.4 4224.2 4225.8 3171.9 3230.6 3242.8 3272.7 3275.5 612.8 616.8 618.1 620.1 626.2 533.6 579.9 590.2 592.1 631.5 8434.3 8533.9 8588.6 8661.6 8894.8 591.0 593.7 619.7 627.2 631.7 3992.0 4147.4 4178.5 4209.1 4213.2 62557.0 70933.6 87545.6 105843.5 110307.4 74355.1 78869.6 86135.0 89909.3 106305.0 73652.6 94419.0 96808.5 98378.0 99304.0 8011.8 8147.4 8184.7 8191.6 8396.4 54295.7 55042.7 57700.4 60746.0 60764.5 70235.0 75920.6 79772.6 85107.3 86129.0 8406.9 8654.3 10086.1 10163.7 10389.4 13493.3 13749.0 13752.3 13995.4 14108.2 10649.9 12274.7 12330.0 12423.6 13104.7 11022.3 11960.6 12915.7 13394.0 13587.7 12394.0 12429.9 12559.2 13050.3 13316.3 8146.7 10457.7 11062.2 11217.0 11606.4 9649.0 10358.0 11210.8 11251.2 11432.2 02:50:49 INFO - PROCESS | 1923 | -------- Summary: end -------- 02:50:49 INFO - PROCESS | 1923 | 02:50:49 INFO - TEST-INFO | 1923: exit 0 02:50:49 INFO - TEST-OK | dromaeo_css | took 1117070ms 02:50:49 INFO - TEST-START | kraken 02:50:49 INFO - operating with platform_type : mac_ 02:50:49 INFO - Initialising browser for kraken test... 02:50:49 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/tmpFAgjIG/profile http://localhost:53685/getInfo.html) 02:50:52 INFO - PROCESS | 1928 | 2017-01-04 02:50:52.929 plugin-container[1930:20776] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8643, name = 'com.apple.tsm.portname' 02:50:52 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:50:52 INFO - PROCESS | 1928 | 2017-01-04 02:50:52.930 plugin-container[1930:20776] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 02:50:52 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:50:52 INFO - PROCESS | 1928 | 2017-01-04 02:50:52.930 plugin-container[1930:20776] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:50:52 INFO - PROCESS | 1928 | __metrics Screen width/height:1600/1200 02:50:52 INFO - PROCESS | 1928 | colorDepth:24 02:50:52 INFO - PROCESS | 1928 | Browser inner width/height: 1024/689 02:50:52 INFO - PROCESS | 1928 | __metrics 02:50:53 ERROR - PROCESS | 1928 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:50:53 INFO - TEST-INFO | 1928: exit 0 02:50:53 INFO - Browser initialized. 02:50:53 INFO - Running cycle 1/1 for kraken test... 02:50:53 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/tmpFAgjIG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:50:56 INFO - PROCESS | 1931 | 2017-01-04 02:50:56.658 plugin-container[1932:20898] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8643, name = 'com.apple.tsm.portname' 02:50:56 INFO - PROCESS | 1931 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:50:56 INFO - PROCESS | 1931 | 2017-01-04 02:50:56.659 plugin-container[1932:20898] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c17, name = 'com.apple.CFPasteboardClient' 02:50:56 INFO - PROCESS | 1931 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:50:56 INFO - PROCESS | 1931 | 2017-01-04 02:50:56.659 plugin-container[1932:20898] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:51:06 INFO - PROCESS | 1931 | RSS: Main: 229412864 02:51:06 INFO - PROCESS | 1931 | 02:51:08 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:53685/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53685/tests/kraken/driver.html?testName=audio-beat-detection) 02:51:09 INFO - PROCESS | 1931 | RSS: Main: 241143808 02:51:09 INFO - PROCESS | 1931 | 02:51:12 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:53685/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53685/tests/kraken/driver.html?testName=audio-dft) 02:51:13 INFO - PROCESS | 1931 | RSS: Main: 248737792 02:51:13 INFO - PROCESS | 1931 | 02:51:16 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:53685/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53685/tests/kraken/driver.html?testName=audio-fft) 02:51:17 INFO - PROCESS | 1931 | RSS: Main: 250753024 02:51:17 INFO - PROCESS | 1931 | 02:51:19 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:53685/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53685/tests/kraken/driver.html?testName=audio-oscillator) 02:51:20 INFO - PROCESS | 1931 | RSS: Main: 252280832 02:51:20 INFO - PROCESS | 1931 | 02:51:22 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:53685/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53685/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:51:22 INFO - PROCESS | 1931 | RSS: Main: 253882368 02:51:22 INFO - PROCESS | 1931 | 02:51:25 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:53685/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53685/tests/kraken/driver.html?testName=imaging-darkroom) 02:51:26 INFO - PROCESS | 1931 | RSS: Main: 258207744 02:51:26 INFO - PROCESS | 1931 | 02:51:29 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:53685/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53685/tests/kraken/driver.html?testName=imaging-desaturate) 02:51:30 INFO - PROCESS | 1931 | RSS: Main: 257200128 02:51:30 INFO - PROCESS | 1931 | 02:51:33 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:53685/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53685/tests/kraken/driver.html?testName=json-parse-financial) 02:51:33 INFO - PROCESS | 1931 | RSS: Main: 257429504 02:51:33 INFO - PROCESS | 1931 | 02:51:35 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:53685/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53685/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:51:35 INFO - PROCESS | 1931 | RSS: Main: 256442368 02:51:35 INFO - PROCESS | 1931 | 02:51:37 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:53685/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53685/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:51:38 INFO - PROCESS | 1931 | RSS: Main: 256512000 02:51:38 INFO - PROCESS | 1931 | 02:51:40 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:53685/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53685/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:51:41 INFO - PROCESS | 1931 | RSS: Main: 256532480 02:51:41 INFO - PROCESS | 1931 | 02:51:44 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:53685/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53685/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:51:45 INFO - PROCESS | 1931 | RSS: Main: 256856064 02:51:45 INFO - PROCESS | 1931 | 02:51:48 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:53685/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53685/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:51:48 INFO - PROCESS | 1931 | RSS: Main: 256970752 02:51:48 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:53685/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53685/tests/kraken/driver.html?testName=ai-astar) 02:51:50 INFO - PROCESS | 1931 | RSS: Main: 258863104 02:51:50 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | __start_tp_report 02:51:50 INFO - PROCESS | 1931 | _x_x_mozilla_page_load 02:51:50 INFO - PROCESS | 1931 | _x_x_mozilla_page_load_details 02:51:50 INFO - PROCESS | 1931 | |i|pagename|runs| 02:51:50 INFO - PROCESS | 1931 | |0;ai-astar;116;120;116;119;120;119;118;118;120;119 02:51:50 INFO - PROCESS | 1931 | |1;audio-beat-detection;180;149;113;150;126;114;114;139;180;140 02:51:50 INFO - PROCESS | 1931 | |2;audio-dft;206;238;195;200;282;278;202;277;275;200 02:51:50 INFO - PROCESS | 1931 | |3;audio-fft;86;56;58;86;52;72;89;83;100;80 02:51:50 INFO - PROCESS | 1931 | |4;audio-oscillator;86;72;87;72;68;70;86;68;73;87 02:51:50 INFO - PROCESS | 1931 | |5;imaging-gaussian-blur;89;87;90;86;118;133;101;87;89;86 02:51:50 INFO - PROCESS | 1931 | |6;imaging-darkroom;99;142;151;110;111;171;115;99;107;100 02:51:50 INFO - PROCESS | 1931 | |7;imaging-desaturate;87;134;112;85;106;124;142;90;88;89 02:51:50 INFO - PROCESS | 1931 | |8;json-parse-financial;50;60;58;59;60;63;59;62;60;59 02:51:50 INFO - PROCESS | 1931 | |9;json-stringify-tinderbox;68;76;73;79;101;72;71;71;88;106 02:51:50 INFO - PROCESS | 1931 | |10;stanford-crypto-aes;84;85;86;92;103;81;82;101;105;100 02:51:50 INFO - PROCESS | 1931 | |11;stanford-crypto-ccm;140;139;139;137;161;163;148;142;230;147 02:51:50 INFO - PROCESS | 1931 | |12;stanford-crypto-pbkdf2;185;164;166;165;167;179;161;208;226;188 02:51:50 INFO - PROCESS | 1931 | |13;stanford-crypto-sha256-iterative;68;70;68;69;77;94;89;90;73;68 02:51:50 INFO - PROCESS | 1931 | __end_tp_report 02:51:50 INFO - PROCESS | 1931 | __start_cc_report 02:51:50 INFO - PROCESS | 1931 | _x_x_mozilla_cycle_collect,989 02:51:50 INFO - PROCESS | 1931 | __end_cc_report 02:51:50 INFO - PROCESS | 1931 | __startTimestamp1483527110807__endTimestamp 02:51:50 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | ------- Summary: start ------- 02:51:50 INFO - PROCESS | 1931 | Number of tests: 14 02:51:50 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | [#0] ai-astar Cycles:10 Average:118.50 Median:119.00 stddev:1.51 (1.3%) stddev-sans-first:1.30 02:51:50 INFO - PROCESS | 1931 | Values: 116.0 120.0 116.0 119.0 120.0 119.0 118.0 118.0 120.0 119.0 02:51:50 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:144.50 stddev:25.07 (17.3%) stddev-sans-first:22.14 02:51:50 INFO - PROCESS | 1931 | Values: 180.0 149.0 113.0 150.0 126.0 114.0 114.0 139.0 180.0 140.0 02:51:50 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | [#2] audio-dft Cycles:10 Average:235.30 Median:256.50 stddev:38.60 (15.0%) stddev-sans-first:39.46 02:51:50 INFO - PROCESS | 1931 | Values: 206.0 238.0 195.0 200.0 282.0 278.0 202.0 277.0 275.0 200.0 02:51:50 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | [#3] audio-fft Cycles:10 Average:76.20 Median:81.50 stddev:16.07 (19.7%) stddev-sans-first:16.65 02:51:50 INFO - PROCESS | 1931 | Values: 86.0 56.0 58.0 86.0 52.0 72.0 89.0 83.0 100.0 80.0 02:51:50 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | [#4] audio-oscillator Cycles:10 Average:76.90 Median:79.50 stddev:8.43 (10.6%) stddev-sans-first:8.27 02:51:50 INFO - PROCESS | 1931 | Values: 86.0 72.0 87.0 72.0 68.0 70.0 86.0 68.0 73.0 87.0 02:51:50 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | [#5] imaging-gaussian-blur Cycles:10 Average:96.60 Median:87.00 stddev:16.23 (18.7%) stddev-sans-first:16.98 02:51:50 INFO - PROCESS | 1931 | Values: 89.0 87.0 90.0 86.0 118.0 133.0 101.0 87.0 89.0 86.0 02:51:50 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | [#6] imaging-darkroom Cycles:10 Average:120.50 Median:146.50 stddev:25.17 (17.2%) stddev-sans-first:25.46 02:51:50 INFO - PROCESS | 1931 | Values: 99.0 142.0 151.0 110.0 111.0 171.0 115.0 99.0 107.0 100.0 02:51:50 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | [#7] imaging-desaturate Cycles:10 Average:105.70 Median:86.00 stddev:21.37 (24.8%) stddev-sans-first:21.57 02:51:50 INFO - PROCESS | 1931 | Values: 87.0 134.0 112.0 85.0 106.0 124.0 142.0 90.0 88.0 89.0 02:51:50 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | [#8] json-parse-financial Cycles:10 Average:59.00 Median:60.00 stddev:3.50 (5.8%) stddev-sans-first:1.58 02:51:50 INFO - PROCESS | 1931 | Values: 50.0 60.0 58.0 59.0 60.0 63.0 59.0 62.0 60.0 59.0 02:51:50 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | [#9] json-stringify-tinderbox Cycles:10 Average:80.50 Median:72.50 stddev:13.39 (18.5%) stddev-sans-first:13.42 02:51:50 INFO - PROCESS | 1931 | Values: 68.0 76.0 73.0 79.0 101.0 72.0 71.0 71.0 88.0 106.0 02:51:50 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | [#10] stanford-crypto-aes Cycles:10 Average:91.90 Median:83.00 stddev:9.46 (11.4%) stddev-sans-first:9.59 02:51:50 INFO - PROCESS | 1931 | Values: 84.0 85.0 86.0 92.0 103.0 81.0 82.0 101.0 105.0 100.0 02:51:50 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | [#11] stanford-crypto-ccm Cycles:10 Average:154.60 Median:147.50 stddev:28.02 (19.0%) stddev-sans-first:29.22 02:51:50 INFO - PROCESS | 1931 | Values: 140.0 139.0 139.0 137.0 161.0 163.0 148.0 142.0 230.0 147.0 02:51:50 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.90 Median:182.00 stddev:21.57 (11.9%) stddev-sans-first:22.83 02:51:50 INFO - PROCESS | 1931 | Values: 185.0 164.0 166.0 165.0 167.0 179.0 161.0 208.0 226.0 188.0 02:51:50 INFO - PROCESS | 1931 | 02:51:50 INFO - PROCESS | 1931 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.60 Median:75.00 stddev:10.39 (13.9%) stddev-sans-first:10.55 02:51:50 INFO - PROCESS | 1931 | Values: 68.0 70.0 68.0 69.0 77.0 94.0 89.0 90.0 73.0 68.0 02:51:50 INFO - PROCESS | 1931 | -------- Summary: end -------- 02:51:50 INFO - PROCESS | 1931 | 02:51:51 INFO - TEST-INFO | 1931: exit 0 02:51:51 INFO - TEST-OK | kraken | took 61984ms 02:51:51 INFO - SUITE-END | took 1179s 02:51:51 INFO - Completed test suite (00:19:39) 02:51:51 INFO - javascript benchmark 02:51:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7017.906060426275, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3285.2772390074183, 3310.00699790063, 3324.2520432902584, 3330.9666903330976, 3342.68244516771, 5555.111182210842, 5605.803796867111, 5611.130274807406, 5628.240187574676, 5664.971675141629, 5590.496855283024, 5665.660060396379, 5680.204771332006, 5704.3154821421385, 5726.997775389311, 5121.948780512184, 5192.506711862386, 5204.401493828202, 5257.815976440826, 5258.395284542269, 5241.213817927303, 5249.370075590934, 5255.658382205144, 5274.973625131869, 5281.973590132063, 5240.9213861792105, 5245.370555533342, 5258.842234732964, 5264.499872512124, 5308.734563271821, 5061.44324124346, 5118.667286626394, 5136.126858434075, 5223.555997740196, 5247.921281180786, 4361.280388735855, 5033.874153146164, 5156.561692256163, 5162.328897243372, 5251.028559716465, 5734.856628584277, 5737.512311453532, 5773.6824474654295, 5798.855028624318, 5808.97095514524, 6063.848403789896, 6066.726997285133, 6141.969290153565, 6148.631082135042, 6152.415520525543, 6163.599366041193, 6165.630062196237, 6181.690915454209, 6187.752489900398, 6212.378762123752, 5729.226554415184, 5747.741351639186, 5755.309362876482, 5775.8556036099435, 5776.9422305776825, 4942.258661200777, 5121.513456221653, 6157.0, 6165.753369865155, 6208.348123446973, 5296.894062118736, 5522.420145884705, 6060.515158787286, 6071.362506936796, 6101.664408457489, 6076.665783381868, 6148.262208534915, 6154.046122850965, 6163.137160797492, 6172.382761723881, 4995.350604421402, 5952.851178720567, 5961.225040744675, 6013.6993150342305, 6084.665343406155, 6015.488683461955, 6025.969870150709, 6082.148499210114, 6109.0, 6131.50947924165, 4092.4270602115726, 4805.063012712558, 4806.279058141236, 4840.927386089212, 4842.806287748451, 4301.913961720748, 4307.353896915425, 4308.073764140725, 4331.6318112960125, 4371.366151907955, 4447.644188464915, 5751.626144300607, 5793.913091303634, 5932.2584676915385, 5966.254218222723, 6121.727351053096, 6130.734471166763, 6134.987697083746, 6148.047052706837, 6232.8130156095385, 5974.701264936736, 5977.0, 5984.73068711909, 5988.790392336247, 6002.609830361012, 5316.282301889196, 5328.573714102862, 5338.626296159309, 5348.224507446476, 5355.464453554613, 6194.194754681863, 6249.437550620466, 6260.561760676709, 6289.591176573508, 6335.366463353628, 4909.68087074339, 6151.231096112986, 6171.352008039091, 6174.38256174397, 6177.938220617916, 5592.636478628876, 6343.9365606343345, 6374.203224596926, 6385.8722825544155, 6393.4885209184085, 5982.4316689914385, 5994.250718660168, 6000.399960003964, 6022.427869352405, 6023.036314189709, 5422.566194704335, 5964.045752679551, 6173.59871608344, 6179.876402472015, 6211.565190436626, 5507.091329930677, 5519.613627046068, 5926.081457374114, 6071.666058366655, 6079.300880398634, 5467.58993075513, 5554.944450555605, 5585.525230355465, 5944.464998150187, 5991.7902873399225, 5859.033259512133, 5873.823785286278, 5885.087811389241, 5910.995130827856, 5918.526517878646, 5841.094630332305, 5880.647161170344, 5904.881902362014, 5957.7319020645045, 5970.0746384310505, 5761.366249712444, 5786.826395208151, 5803.506701930383, 5809.883802324014, 5823.388544202798], "value": 5580.671595166795, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.76468706898277, 452.6808599937045, 452.7306252779595, 471.8796706839755, 481.708566317378, 32228.67771322289, 32496.70013199469, 37972.620273797285, 41396.0, 41451.963700907494, 40274.0, 40766.7961660192, 40851.0, 40865.59134408655, 40913.7954310228, 7759.41804364672, 9294.07059294072, 9329.346945713804, 9337.299702522305, 9349.953250233739, 8073.515589064646, 9310.022447643001, 9387.436753794795, 9415.246780257597, 9449.007854175316, 9354.579043943006, 9366.531673416303, 9387.859182112274, 9395.154436100749, 9402.764930876749, 9312.860307095365, 9313.90686093137, 9352.485613291266, 9352.999999999965, 9369.203617692505, 9224.631014759401, 9292.488913109777, 9299.814003719956, 9312.487813170274, 9342.626294948193, 38232.235355293014, 38265.426018609745, 38590.80704596459, 38680.0, 38718.0, 3203.471427214507, 3232.191952011997, 3316.668333166664, 3646.1431563582537, 3813.0, 27456.45087098267, 29217.977370792134, 29476.820927162888, 29486.26284342887, 30102.397952040836, 3027.5610036544576, 3202.6316973548105, 3213.0682102190335, 3216.372807302577, 3230.5800245967876, 370279.0, 370960.0, 371807.0, 373488.0, 375302.12348938355, 58560.0, 58591.41408585945, 58682.11976820351, 58701.11948320779, 58719.41280587225, 60756.69621651952, 61725.69137154285, 62110.0, 62230.0, 62419.68790156111, 15950.0, 16146.919265403598, 16176.75734863978, 16186.271617777229, 16227.756583651255, 12500.874991250153, 12543.372831358396, 12555.686107847194, 12586.0, 12602.621921342374, 16867.57831054209, 17179.226934787963, 17385.47843564695, 17427.825721742873, 17476.475705728848, 3206.599175103112, 3296.291297371077, 6166.321704612489, 6218.098375735493, 6273.9372606273355, 815.514768712615, 929.2333824594737, 933.3746389918771, 940.6143481172686, 984.9655262065793, 29352.119436416942, 29589.704102959124, 29878.701212988028, 30097.398052038836, 30117.0, 949.3686698345523, 1022.5143057047992, 1089.6839916424149, 1095.315427857589, 1112.3214838948236, 11830.290182589073, 14741.705165896621, 15307.46423875159, 15364.923175384276, 15540.611484712972, 692223.0, 694388.0, 697654.5117274381, 698266.5086674534, 700305.0, 55797.44202557922, 55808.72095639496, 56165.59586010382, 56263.0, 56615.86768264694, 1295.7861952777687, 1332.6068809701162, 1354.6477915741978, 1356.0507644648785, 1357.4027427931678, 28625.856870715514, 28822.567661485515, 28987.0, 29748.40503189881, 29769.106926792232, 50623.0, 50726.7318317045, 51178.48821511737, 51186.720331992, 51240.23139652983, 625071.0, 630373.0, 631036.0, 631670.0, 632074.0, 20416.795832042084, 21764.58530195532, 26626.7337326632, 26710.19869403921, 26930.461390772467, 38775.80612096922, 41345.0, 44323.556764431945, 44683.77658111689, 45107.77446112748, 36841.631583684895, 37394.43908341431, 37647.0, 37868.62131378651, 38062.0, 30580.541291881083, 36886.44670330001, 36927.0, 37766.24467510689, 38248.426273605364, 36667.81666091653, 36971.815140924125, 37268.06829829168, 37935.43096853605, 38106.23787524289, 31846.363072737953, 33017.66982330242, 33199.00402987914, 33936.490952635235, 34093.97718068462, 25528.74471255338, 25550.105746298792, 25654.0, 25663.10179143721, 25882.87058564695], "value": 19087.07863173248, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.2623650013718, 363.5818808370374, 375.2907005759115, 380.7981769662077, 396.3480075276171, 18802.529936751573, 19123.521911952208, 19228.42314730557, 19543.511412214702, 19625.705614415776, 19294.71057934132, 19576.70634940477, 19834.801651983475, 20044.498887527818, 20118.295859644917, 5051.595872330223, 5820.970895145529, 5995.61028533144, 6051.12258722485, 6060.848478788022, 6050.636961782285, 6188.81433556994, 6202.7518899244205, 6202.81391558254, 6222.03558448439, 6008.429199226067, 6166.691665416712, 6175.444210021097, 6568.441682456973, 6619.172603424572, 6466.870662586722, 6593.439557637583, 6595.142631457904, 6652.634105124216, 6672.633005184713, 5622.493975542221, 5671.971640141773, 5697.487226149667, 5715.342735585377, 5720.713964301768, 23637.409064773346, 23649.64525532101, 24036.639450408256, 24106.638400424006, 24428.755712443002, 2279.863208207513, 2359.374765687094, 2817.0140450842246, 3050.5119180930947, 3098.0, 12082.0, 14287.499937502138, 15482.458113965957, 15697.529074127795, 15948.76076858848, 1791.1939627167826, 1809.5747499337645, 1857.6099019205983, 1874.2221977879165, 1875.3436297295973, 95941.08117837604, 102791.48604257006, 106993.0, 108557.0, 113805.0, 7591.620418979029, 8842.955785221162, 9056.592453339616, 9797.0, 11030.283031603081, 9997.350172238781, 12096.758064838654, 12203.816942745865, 12283.877161228453, 12340.568080117153, 7790.961045194737, 7856.214378562098, 7888.40836937232, 7914.169012253746, 7957.801054973672, 7184.389326907167, 8124.837503249902, 8182.836343273101, 8293.709720159766, 8325.458845175044, 3203.775735698479, 3840.6735427488416, 3899.0447340401784, 3919.431682406035, 3927.430522574268, 1658.2372108829832, 1927.0, 1956.7651881774277, 1997.3109277299309, 1997.7802441731399, 891.9286457083548, 969.60730903984, 974.2303580171726, 981.9214462842956, 986.2652324018509, 18800.43598692014, 19848.702269465968, 19909.800901991086, 20009.499762506053, 20358.796412035987, 885.4952676974192, 912.4114945859883, 912.5802131019806, 940.3652534539159, 940.7789169545151, 9613.0, 10151.340162889388, 10159.796804064024, 10215.693529194135, 10371.429571373646, 596341.0, 601670.0, 607514.0, 624399.0, 626526.0, 100546.4972675132, 120071.0, 121333.18000230004, 123569.38215308866, 124080.0, 937.803061357109, 941.7410212191702, 963.7927845513249, 964.9324547281623, 968.3415277611151, 17031.31874724997, 17167.399141030004, 17446.778725489094, 17493.475195744148, 17775.8445701029, 48171.03657926892, 49282.75358623184, 49943.50056499534, 50201.24698129458, 50396.748016261154, 59253.40746592651, 68276.6586167066, 71541.6422917882, 73498.26501734914, 74885.0, 5900.763969441175, 7604.80987975305, 7725.034370703662, 7822.21777822236, 7837.647305871135, 9880.590025755115, 12067.51729930828, 12103.576374826838, 12292.508299667914, 12336.81494777561, 8484.278836298983, 9777.755556111155, 9901.900980990386, 9928.007199280015, 9953.800923981624, 9727.319087664078, 9747.463889485864, 9871.0, 10151.79696406053, 10283.794324113325, 8763.737087887373, 10012.749681258027, 10078.244131690299, 10458.372497650165, 10541.57833686644, 7499.0, 7515.549067055994, 7602.847943040998, 7800.024996875391, 7887.68449262046, 8516.0, 9999.0, 10134.239932005277, 10164.440955747505, 10380.013898679614], "value": 9200.122713661003, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1176.6293617510485, 1402.9407797113179, 1509.5622269541834, 1742.877998540101, 1770.557360659836, 6478.805635830932, 6871.484638652096, 7506.249375062498, 7936.444448888573, 8429.157084291568, 6180.783672571461, 7336.889946650805, 8212.589370531465, 8317.126701696325, 8384.496930184194, 1869.598036422169, 2287.553926984239, 2304.562133194696, 2313.803326717231, 2318.084356679111, 2273.6816845641624, 2320.744718081018, 2329.324495896188, 2336.3458231695067, 2339.5671800716923, 2257.6500642400374, 2278.9886050569726, 2310.780475854791, 2312.838101332907, 2315.340128063502, 2265.5160869630336, 2268.4669102760836, 2283.6117859963847, 2283.862968221912, 2293.885305734715, 2198.758136604972, 2254.7519772824976, 2264.6489794081945, 2264.7169103862016, 2271.068861766668, 8213.0, 8236.341092712628, 8240.958795206045, 8300.335973122135, 8308.916910830936, 4865.829695960624, 4992.151334273182, 5151.639385243035, 5162.664426812245, 5180.507851754115, 2894.2474956511246, 3286.277019055804, 3560.6795388414907, 3561.9109522261892, 3573.213892943548, 872.9607167677469, 1009.8939611340851, 1074.6399956014745, 1077.0306723948431, 1085.3542142425324, 10697.304675196086, 10792.838107428395, 10842.945785271024, 10905.127589792797, 10922.672319830417, 3798.810059497014, 4034.7175697700877, 4328.220920234325, 4601.631869450436, 4739.146953548326, 7559.357454616309, 9212.216961558208, 9256.537173141316, 9268.95365523168, 9274.814503710022, 5129.538341549279, 5137.743112844343, 5139.640225184201, 5143.919776846829, 5153.768080436389, 4227.154569086134, 4485.484169320504, 4675.392199014106, 4698.060387922429, 4703.318018887242, 3825.3496905526126, 3836.1176929306416, 3885.669718073939, 3934.940975885364, 3954.387070004154, 1253.943572539238, 1255.805350170725, 1263.684078980255, 1266.936653167338, 1268.6447794617523, 768.7655265144185, 887.5961437545901, 893.8703191256437, 920.6961702638114, 927.1238679447857, 5796.3913789052385, 5837.708114594338, 5864.091065884794, 5869.325027621878, 5876.588638795329, 909.9545022748836, 911.334725650279, 940.7365937537501, 941.3928016429502, 984.3601658921758, 3710.424884142962, 3814.3134235837815, 3858.7684738915195, 3863.497745293094, 3897.279003384383, 9627.470489123165, 9886.011398860057, 9954.303198776017, 9962.950185249172, 9976.102150806462, 9946.20430365569, 10011.649592264235, 10027.548760305805, 10050.592916991627, 10092.738407699038, 7396.5562066275115, 7709.922900770921, 7712.421568382506, 7729.0, 7851.371890248766, 5387.407385187548, 5443.101888188405, 5503.587230957774, 5762.366139724543, 5770.278715160605, 7750.0, 8176.713815016446, 8771.596544552842, 9097.909020909707, 9367.063293670579, 9174.174324310845, 9287.839020122485, 9445.527723613654, 9446.149846513847, 9567.760805979906, 6587.077809106633, 7075.327843854826, 7292.34368906801, 7308.196098429275, 7558.622068896643, 6860.176778786577, 7545.7358992435, 7804.2585954334245, 7900.2889739923685, 7902.091259505, 6976.790696279119, 7169.103862017248, 7186.0, 7247.021652077113, 7270.6728197230195, 6779.55932864382, 6903.572457218938, 6996.0, 7021.894671579828, 7119.003339532366, 6641.302663220292, 6654.401103900672, 6721.361470660279, 6730.259671436236, 6765.661716914233, 4525.275955847049, 4565.977170114128, 4738.07607516531, 4892.192788189909, 4905.190643543918, 4787.54518320759, 4883.60931125502, 4899.951000490092, 4966.230234313687, 5047.041062198171], "value": 4351.746358458551, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.48189631936737, 302.34391370725524, 303.42652386988584, 308.7067286078225, 311.35549412715676, 4623.930641040383, 4679.929801052983, 5121.692698438087, 5219.895602087965, 5413.972930135354, 5054.924176137351, 5229.477052294768, 5229.9080367852785, 5238.406988540678, 5246.344206974129, 3552.6447355264527, 4024.134811015638, 4062.329715596923, 4074.2666320062376, 4111.568285330042, 3457.636948120449, 3929.6659783918403, 4030.1738143680477, 4067.1458993611227, 4076.1847630473876, 4004.7597144171295, 4029.9395509067385, 4036.1725846201457, 4041.7574945503216, 4058.9188216235807, 3309.5366648669205, 3835.827387767557, 4009.1179940413062, 4013.4581831452674, 4048.332025215836, 2942.367391010943, 3519.208178159919, 3550.065214458101, 3552.2717842842158, 3558.466230065485, 4471.463424389094, 5012.498750124995, 5122.487751224885, 5131.589472842164, 5173.094708426011, 3813.161104556993, 4431.825566224953, 4598.793054312564, 4614.607758340545, 4632.0, 5523.7238138093335, 5528.5024347808485, 5537.556995440355, 5560.443955604448, 5638.74625641843, 3292.3250733599552, 3761.2665530221616, 3845.7115716321387, 3878.0886491674432, 3900.5124359455067, 4946.72792996385, 5805.796797112123, 5812.244408226903, 5826.417358264182, 5840.532757379399, 3323.5845519310087, 3620.6741393274206, 3715.368387374153, 3951.0, 3955.6439920406724, 3173.0, 3239.9838000809846, 3325.700686938139, 3962.9009274767786, 3979.4826672532963, 3843.9440644687543, 4342.0, 4452.554744525586, 4598.632109431237, 4618.399608050931, 5284.841454756363, 5286.9471305287225, 5560.081771788727, 5624.831255062355, 5668.57485688575, 1104.6685994201707, 1231.9630411087683, 1257.8176164456102, 1281.333706472629, 1286.3375361688738, 871.8561437362891, 895.8969718482458, 904.9276057915351, 906.9863952040724, 908.7092130518303, 4516.954830451719, 4527.962416912403, 5037.773300201499, 5105.0, 5132.538071573502, 4889.828855990023, 5002.649814513022, 5395.811146609928, 5440.483154100354, 5458.707342915985, 4679.876829560949, 4688.647616953895, 5044.495550444926, 5048.242763585492, 5070.467600901852, 2795.343094372821, 2831.59835881236, 2834.1639216431204, 2854.728800763924, 2866.3837274985976, 4882.707037577757, 5309.070912590359, 5386.326709161355, 5592.468715472024, 5708.573856111137, 4032.644743676822, 5222.843314700565, 5497.670139791545, 5518.9304010995875, 5544.473275038865, 5105.0810854045885, 5615.241942337732, 5667.263255776723, 5748.913766293509, 5770.769169233184, 4622.375979242759, 4850.733209673361, 4850.902981940412, 4981.501849814989, 5041.899162016813, 4148.709590328769, 4790.976045119752, 4809.134355815987, 5073.974630126826, 5355.4376790436445, 4959.0, 5675.0636145035605, 5776.422357764358, 5777.8555536111935, 5902.822915312548, 3650.4341827016856, 3752.5872154062486, 3762.435634654824, 3762.6425489578446, 3775.244951009864, 4348.891277718082, 5120.334356533627, 5244.606654501004, 5378.542823860016, 5502.193881120577, 4730.763461826826, 4847.830325938575, 5453.563714902719, 5641.351244606923, 5667.1015209905145, 5625.668235458756, 5634.929363420937, 5642.379338272869, 5650.0959846424375, 5653.858653533694, 5037.244413338029, 5230.189320655304, 5508.0, 5516.61383703153, 5538.529225015919, 5007.974960125322, 5180.912008478262, 5347.582890533934, 5551.666899986014, 5600.523955463668, 4504.549545045469, 5039.571636410799, 5040.974795126001, 5530.225768392348, 5539.362973258127], "value": 3969.5980829999353, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [653.7741734248287, 688.7623769799418, 696.3245651717832, 763.9915311788438, 789.7867575754548, 14191.787123193148, 14918.403263869459, 14928.029678070918, 14933.328000239988, 14967.47613833516, 13223.537176198812, 13268.867311326883, 13269.40287687054, 13321.13412628181, 13491.190528568293, 630.0486265738745, 652.282489261813, 784.8901153838467, 791.1100012485967, 802.4904185842004, 809.6437567470324, 811.1401913971209, 815.8490679224333, 819.3690858039306, 819.7622689420061, 862.5644049754897, 867.535868310454, 868.0408148995364, 868.097178933908, 868.2489646455801, 840.3739214285335, 853.9786505337416, 860.320346925934, 862.7670528957178, 862.9309655227598, 642.0850288339099, 642.2132887213173, 642.3961476212345, 643.9195100612424, 644.935506449356, 13442.32788360578, 13856.584302470941, 13868.4452621895, 13921.58235252944, 14204.786928196085, 11899.821502677467, 12108.636740897788, 12123.878761212363, 13250.536231231958, 13750.724985500334, 11805.350705711182, 11979.28124312544, 12001.279923204636, 12245.693857653541, 12351.0, 4346.195953748566, 4388.473383194005, 4392.516823149451, 4440.977795111036, 4517.548245175489, 67999.66000169968, 88242.79393015162, 90310.54844725734, 90512.73718157032, 90694.54652726761, 9992.700218993441, 9996.950015249877, 10056.497175141214, 10100.646477373257, 10162.695119146438, 18752.81247187538, 18776.0, 18777.24891004372, 18843.811561884206, 18917.148728306984, 6294.685265736694, 6996.790096297119, 7148.356647901714, 7208.681764525018, 7238.92761072393, 4061.756294622332, 4148.91702165955, 4155.3766934959995, 4224.176285624335, 4225.809838557272, 3171.9207019824225, 3230.582717227149, 3242.821644809511, 3272.672732726756, 3275.5250488679008, 612.7548980407872, 616.8211218746603, 618.0636335951037, 620.1472974659843, 626.1859582542768, 533.5651444073068, 579.9101139323411, 590.153130258081, 592.1029640095259, 631.5074242091176, 8434.28308593764, 8533.871991920127, 8588.613512391958, 8661.566921653892, 8894.822103557892, 590.9586328956932, 593.7061154728437, 619.7087368936683, 627.2003195925141, 631.7346714379879, 3991.960080399217, 4147.3778933160265, 4178.519470260898, 4209.052963083343, 4213.157368526307, 62557.0, 70933.64533177404, 87545.56560951198, 105843.4707826456, 110307.44846275717, 74355.14112147239, 78869.60565197137, 86135.0, 89909.30272091848, 106305.0, 73652.63173684098, 94419.0, 96808.51595741976, 98378.0, 99304.0, 8011.759647210593, 8147.388945828968, 8184.713535026245, 8191.590420478833, 8396.412251142605, 54295.72852135714, 55042.72478637581, 57700.422995769506, 60746.0, 60764.480887978156, 70235.0, 75920.62039689766, 79772.60113699627, 85107.29785404133, 86129.0, 8406.873896891673, 8654.30765538768, 10086.142677872464, 10163.7459063524, 10389.428581428094, 13493.32533373347, 13748.968827338189, 13752.31238438094, 13995.370208340446, 14108.224047677066, 10649.89350106489, 12274.711155328561, 12330.0, 12423.627291180917, 13104.672383190496, 11022.283551569122, 11960.58137965167, 12915.741685166431, 13393.995450341068, 13587.660308492366, 12394.0, 12429.875701243234, 12559.183653062522, 13050.34748262564, 13316.267605281415, 8146.714864979697, 10457.68626941193, 11062.225644205151, 11217.0, 11606.419679016184, 9648.951755241178, 10358.0, 11210.775784484427, 11251.21917235615, 11432.199746017699], "value": 7056.160122188796, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1613.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 120.0, 116.0, 119.0, 120.0, 119.0, 118.0, 118.0, 120.0, 119.0], "value": 118.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 149.0, 113.0, 150.0, 126.0, 114.0, 114.0, 139.0, 180.0, 140.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 238.0, 195.0, 200.0, 282.0, 278.0, 202.0, 277.0, 275.0, 200.0], "value": 235.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 56.0, 58.0, 86.0, 52.0, 72.0, 89.0, 83.0, 100.0, 80.0], "value": 76.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 87.0, 72.0, 68.0, 70.0, 86.0, 68.0, 73.0, 87.0], "value": 76.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 86.0, 118.0, 133.0, 101.0, 87.0, 89.0, 86.0], "value": 96.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 142.0, 151.0, 110.0, 111.0, 171.0, 115.0, 99.0, 107.0, 100.0], "value": 120.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 134.0, 112.0, 85.0, 106.0, 124.0, 142.0, 90.0, 88.0, 89.0], "value": 105.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 60.0, 58.0, 59.0, 60.0, 63.0, 59.0, 62.0, 60.0, 59.0], "value": 59.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 76.0, 73.0, 79.0, 101.0, 72.0, 71.0, 71.0, 88.0, 106.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 86.0, 92.0, 103.0, 81.0, 82.0, 101.0, 105.0, 100.0], "value": 91.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 139.0, 137.0, 161.0, 163.0, 148.0, 142.0, 230.0, 147.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 164.0, 166.0, 165.0, 167.0, 179.0, 161.0, 208.0, 226.0, 188.0], "value": 180.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 68.0, 69.0, 77.0, 94.0, 89.0, 90.0, 73.0, 68.0], "value": 76.6, "unit": "score"}]}]} 02:51:52 INFO - Return code: 0 02:51:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:51:52 ERROR - # TBPL SUCCESS # 02:51:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:51:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:51:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:51:52 INFO - 2017-01-04 02:51:52.139 screenresolution[1936:21278] starting screenresolution argv=screenresolution get 02:51:52 INFO - 2017-01-04 02:51:52.142 screenresolution[1936:21278] Display 0: 1600x1200x32@60 02:51:52 INFO - 2017-01-04 02:51:52.149 screenresolution[1937:21281] starting screenresolution argv=screenresolution list 02:51:52 INFO - Available Modes on Display 0 02:51:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:51:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:51:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:51:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:51:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:51:52 INFO - Intel Iris: 02:51:52 INFO - Chipset Model: Intel Iris 02:51:52 INFO - Type: GPU 02:51:52 INFO - Bus: Built-In 02:51:52 INFO - VRAM (Dynamic, Max): 1536 MB 02:51:52 INFO - Vendor: Intel (0x8086) 02:51:52 INFO - Device ID: 0x0a2e 02:51:52 INFO - Revision ID: 0x0009 02:51:52 INFO - Displays: 02:51:52 INFO - Display: 02:51:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:51:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:51:52 INFO - Display Serial Number: 1600x1200 60 02:51:52 INFO - Main Display: Yes 02:51:52 INFO - Mirror: Off 02:51:52 INFO - Online: Yes 02:51:52 INFO - Rotation: Supported 02:51:52 INFO - Return code: 0 02:51:52 INFO - Running post-action listener: _resource_record_post_action 02:51:52 INFO - [mozharness: 2017-01-04 10:51:52.390493Z] Finished run-tests step (success) 02:51:52 INFO - Running post-run listener: _resource_record_post_run 02:51:52 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 7122944; Write bytes: 659930624; Read time: 26; Write time: 1904 02:51:52 INFO - TinderboxPrint: CPU usage
54.4% 02:51:52 INFO - TinderboxPrint: I/O read bytes / time
7,122,944 / 26 02:51:52 INFO - TinderboxPrint: I/O write bytes / time
659,930,624 / 1,904 02:51:52 INFO - TinderboxPrint: CPU idle
2,209.3 (45.6%) 02:51:52 INFO - TinderboxPrint: CPU system
979.4 (20.2%) 02:51:52 INFO - TinderboxPrint: CPU user
1,657.0 (34.2%) 02:51:52 INFO - TinderboxPrint: Swap in / out
358,916,096 / 0 02:51:52 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344916480; Write bytes: 345794048; Read time: 26832; Write time: 692 02:51:52 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 7122944; Write bytes: 307255296; Read time: 26; Write time: 1181 02:51:52 INFO - Running post-run listener: _upload_blobber_files 02:51:52 INFO - Blob upload gear active. 02:51:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:51:52 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 02:51:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:51:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:51:53 INFO - (blobuploader) - INFO - Open directory for files ... 02:51:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:51:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:51:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:51:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:51:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:51:54 INFO - (blobuploader) - INFO - Done attempting. 02:51:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:51:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:51:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:51:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:51:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:51:55 INFO - (blobuploader) - INFO - Done attempting. 02:51:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:51:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:51:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:51:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:51:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:51:58 INFO - (blobuploader) - INFO - Done attempting. 02:51:58 INFO - (blobuploader) - INFO - Iteration through files over. 02:51:58 INFO - Return code: 0 02:51:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:51:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:51:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b5a700b0f957bffd25e6196390b10c83d83ef8e4f01856b14de764c7c88d8880f25867cc480528a44d6047e654bb1cf3abb86ea15bdd637d05ac925cbb22397", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb1d7df71b5b5902a8161e29217e09647573db365c6aa35866af77c5674bb01a2d6e585844b19ded9e9582ce17c2ff79f2b378a5cff2f2a4768531adb83a928f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bd842e9f8f77c739f5a61d3fb9bd60935862175b37ed5ead433d10caa2698e85d8473ab8bb8679a58dad5e2b01fcdb881aee69309772c6f044e2b353094e0990"} 02:51:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:51:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:51:58 INFO - Contents: 02:51:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b5a700b0f957bffd25e6196390b10c83d83ef8e4f01856b14de764c7c88d8880f25867cc480528a44d6047e654bb1cf3abb86ea15bdd637d05ac925cbb22397", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb1d7df71b5b5902a8161e29217e09647573db365c6aa35866af77c5674bb01a2d6e585844b19ded9e9582ce17c2ff79f2b378a5cff2f2a4768531adb83a928f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bd842e9f8f77c739f5a61d3fb9bd60935862175b37ed5ead433d10caa2698e85d8473ab8bb8679a58dad5e2b01fcdb881aee69309772c6f044e2b353094e0990"} 02:51:58 INFO - Running post-run listener: copy_logs_to_upload_dir 02:51:58 INFO - Copying logs to upload dir... 02:51:58 INFO - mkdir: /builds/slave/test/build/upload/logs 02:51:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.319159 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-04 02:51:58.485802) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 02:51:58.489851) ========= 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.vDnBn5DIZn/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.4K3IUkp4J4/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b5a700b0f957bffd25e6196390b10c83d83ef8e4f01856b14de764c7c88d8880f25867cc480528a44d6047e654bb1cf3abb86ea15bdd637d05ac925cbb22397", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb1d7df71b5b5902a8161e29217e09647573db365c6aa35866af77c5674bb01a2d6e585844b19ded9e9582ce17c2ff79f2b378a5cff2f2a4768531adb83a928f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bd842e9f8f77c739f5a61d3fb9bd60935862175b37ed5ead433d10caa2698e85d8473ab8bb8679a58dad5e2b01fcdb881aee69309772c6f044e2b353094e0990"} build_url:https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011206 build_url: 'https://queue.taskcluster.net/v1/task/X4H5Ox8xRF-zzlAgYjEYrg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b5a700b0f957bffd25e6196390b10c83d83ef8e4f01856b14de764c7c88d8880f25867cc480528a44d6047e654bb1cf3abb86ea15bdd637d05ac925cbb22397", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb1d7df71b5b5902a8161e29217e09647573db365c6aa35866af77c5674bb01a2d6e585844b19ded9e9582ce17c2ff79f2b378a5cff2f2a4768531adb83a928f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bd842e9f8f77c739f5a61d3fb9bd60935862175b37ed5ead433d10caa2698e85d8473ab8bb8679a58dad5e2b01fcdb881aee69309772c6f044e2b353094e0990"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 02:51:58.525878) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 02:51:58.526512) ========= 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.vDnBn5DIZn/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.4K3IUkp4J4/Listeners TMPDIR=/var/folders/r0/nfy68rhs4pz8qyrz7_3847cc00000w/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.005237 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 02:51:58.576322) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 02:51:58.579748) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 02:51:58.580147) ========= ========= Total master_lag: 0.19 =========