builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0176 starttime: 1482514313.63 results: success (0) buildid: 20161223062836 builduid: 99f1a47b5a8b45ffaf8e665e758f9600 revision: ec2672807887aa46bb4c2966478afa08d610d72d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 09:31:53.625348) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 09:31:53.626192) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 09:31:53.626475) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 09:31:53.653928) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 09:31:53.654261) ========= 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.i2ilZXxtwW/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.9CAI3juTUZ/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-23 09:31:53-- 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% 19.0M=0.001s 2016-12-23 09:31:54 (19.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.471156 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 09:31:54.149889) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 09:31:54.150200) ========= 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.i2ilZXxtwW/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.9CAI3juTUZ/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.027296 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 09:31:54.199236) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-23 09:31:54.199596) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ec2672807887aa46bb4c2966478afa08d610d72d --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 ec2672807887aa46bb4c2966478afa08d610d72d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.i2ilZXxtwW/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.9CAI3juTUZ/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-23 09:31:54,296 truncating revision to first 12 chars 2016-12-23 09:31:54,296 Setting DEBUG logging. 2016-12-23 09:31:54,296 attempt 1/10 2016-12-23 09:31:54,296 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ec2672807887?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-23 09:31:55,906 unpacking tar archive at: mozilla-aurora-ec2672807887/testing/mozharness/ program finished with exit code 0 elapsedTime=1.881879 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-23 09:31:56.105207) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 09:31:56.105625) ========= script_repo_revision: ec2672807887aa46bb4c2966478afa08d610d72d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 09:31:56.106031) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 09:31:56.106662) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 09:31:56.127266) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-23 09:31:56.127622) ========= /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.i2ilZXxtwW/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.9CAI3juTUZ/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:31:56 INFO - MultiFileLogger online at 20161223 09:31:56 in /builds/slave/test 09:31:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:31:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:31:56 INFO - {'append_to_log': False, 09:31:56 INFO - 'base_work_dir': '/builds/slave/test', 09:31:56 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:31:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:31:56 INFO - 'branch': 'Mozilla-Aurora', 09:31:56 INFO - 'buildbot_json_path': 'buildprops.json', 09:31:56 INFO - 'config_files': ('talos/mac_config.py',), 09:31:56 INFO - 'default_actions': ('clobber', 09:31:56 INFO - 'read-buildbot-config', 09:31:56 INFO - 'download-and-extract', 09:31:56 INFO - 'populate-webroot', 09:31:56 INFO - 'create-virtualenv', 09:31:56 INFO - 'install', 09:31:56 INFO - 'run-tests'), 09:31:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:31:56 INFO - 'download_minidump_stackwalk': True, 09:31:56 INFO - 'download_symbols': 'ondemand', 09:31:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:31:56 INFO - 'tooltool.py': '/tools/tooltool.py', 09:31:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:31:56 INFO - '/tools/misc-python/virtualenv.py')}, 09:31:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:31:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:31:56 INFO - 'installer_path': 'installer.exe', 09:31:56 INFO - 'log_level': 'info', 09:31:56 INFO - 'log_name': 'talos', 09:31:56 INFO - 'log_to_console': True, 09:31:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:31:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:31:56 INFO - 'opt_config_files': (), 09:31:56 INFO - 'pip_index': False, 09:31:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:31:56 INFO - 'cmd': ('bash', 09:31:56 INFO - '-c', 09:31:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:31:56 INFO - 'enabled': True, 09:31:56 INFO - 'halt_on_failure': False, 09:31:56 INFO - 'name': 'check_screen_resolution'},), 09:31:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:31:56 INFO - 'cmd': ('bash', 09:31:56 INFO - '-c', 09:31:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:31:56 INFO - 'enabled': True, 09:31:56 INFO - 'halt_on_failure': False, 09:31:56 INFO - 'name': 'check_screen_resolution'},), 09:31:56 INFO - 'run_cmd_checks_enabled': True, 09:31:56 INFO - 'sps_profile': False, 09:31:56 INFO - 'sps_profile_interval': 0, 09:31:56 INFO - 'suite': 'dromaeojs-e10s', 09:31:56 INFO - 'system_bits': '32', 09:31:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:31:56 INFO - 'title': 't-yosemite-r7-0176', 09:31:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:31:56 INFO - 'use_talos_json': True, 09:31:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:31:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:31:56 INFO - 'work_dir': 'build'} 09:31:56 INFO - [mozharness: 2016-12-23 17:31:56.338861Z] Running clobber step. 09:31:56 INFO - Running pre-action listener: _resource_record_pre_action 09:31:56 INFO - Running main action method: clobber 09:31:56 INFO - rmtree: /builds/slave/test/build 09:31:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:31:58 INFO - Running post-action listener: _resource_record_post_action 09:31:58 INFO - [mozharness: 2016-12-23 17:31:58.771615Z] Finished clobber step (success) 09:31:58 INFO - [mozharness: 2016-12-23 17:31:58.771743Z] Running read-buildbot-config step. 09:31:58 INFO - Running pre-action listener: _resource_record_pre_action 09:31:58 INFO - Running main action method: read_buildbot_config 09:31:58 INFO - Using buildbot properties: 09:31:58 INFO - { 09:31:58 INFO - "project": "", 09:31:58 INFO - "product": "firefox", 09:31:58 INFO - "script_repo_revision": "production", 09:31:58 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:31:58 INFO - "repository": "", 09:31:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:31:58 INFO - "buildid": "20161223062836", 09:31:58 INFO - "pgo_build": "False", 09:31:58 INFO - "basedir": "/builds/slave/test", 09:31:58 INFO - "buildnumber": 69, 09:31:58 INFO - "slavename": "t-yosemite-r7-0176", 09:31:58 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:31:58 INFO - "platform": "yosemite_r7", 09:31:58 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:31:58 INFO - "branch": "mozilla-aurora", 09:31:58 INFO - "repo_path": "releases/mozilla-aurora", 09:31:58 INFO - "revision": "ec2672807887aa46bb4c2966478afa08d610d72d", 09:31:58 INFO - "stage_platform": "macosx64", 09:31:58 INFO - "builduid": "99f1a47b5a8b45ffaf8e665e758f9600", 09:31:58 INFO - "slavebuilddir": "test" 09:31:58 INFO - } 09:31:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:31:58 INFO - Running post-action listener: _resource_record_post_action 09:31:58 INFO - [mozharness: 2016-12-23 17:31:58.775011Z] Finished read-buildbot-config step (success) 09:31:58 INFO - [mozharness: 2016-12-23 17:31:58.775121Z] Running download-and-extract step. 09:31:58 INFO - Running pre-action listener: _resource_record_pre_action 09:31:58 INFO - Running main action method: download_and_extract 09:31:58 INFO - mkdir: /builds/slave/test/build/tests 09:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:58 INFO - trying https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:31:58 INFO - Downloading https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:31:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 09:31:59 INFO - Downloaded 1199 bytes. 09:31:59 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:31:59 INFO - Using the following test package requirements: 09:31:59 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:31:59 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:31:59 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:31:59 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:31:59 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:31:59 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:31:59 INFO - u'jsshell-mac.zip'], 09:31:59 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:31:59 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:31:59 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:31:59 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:31:59 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:31:59 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:31:59 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:31:59 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:31:59 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:31:59 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:31:59 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:31:59 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:31:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:31:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:31:59 INFO - Fetch https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:32:00 INFO - Http code: 200 09:32:00 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 09:32:00 INFO - x-amz-cf-id: U0L_o0tLetm-0vrk4R8AcHDSiNKjmS3GJBKHrLcZhyUsiBkLUWZ8ow== 09:32:00 INFO - x-amz-version-id: yYy2ARtCUHy5N73Ri3oYiS0kqSDseFIs 09:32:00 INFO - Expected file size: 16271179 09:32:00 INFO - Obtained file size: 16271179 09:32:07 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:32:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:32:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:32:07 INFO - Fetch https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:32:08 INFO - Http code: 200 09:32:08 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 09:32:08 INFO - x-amz-cf-id: PKX0JZYfeciyolarHZJUQz1drm1agXjfBVerLQfijCASOM429IXMow== 09:32:08 INFO - x-amz-version-id: yYy2ARtCUHy5N73Ri3oYiS0kqSDseFIs 09:32:08 INFO - Expected file size: 16271179 09:32:08 INFO - Obtained file size: 16271179 09:32:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:32:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:32:15 INFO - Fetch https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:32:15 INFO - Http code: 200 09:32:15 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 09:32:15 INFO - x-amz-cf-id: SCmlnhOtYMhmJIancps1PhJVk_NEVuBuzHkC0isIrMWY-8X8hz7pig== 09:32:15 INFO - x-amz-version-id: YFut8fTc18PPODuCV0CLcj8xq__OHd6c 09:32:16 INFO - Expected file size: 14409038 09:32:16 INFO - Obtained file size: 14409038 09:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:16 INFO - trying https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:32:16 INFO - Downloading https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:32:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 09:32:19 INFO - Downloaded 109788108 bytes. 09:32:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:32:19 INFO - mkdir: /builds/slave/test/properties 09:32:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:32:19 INFO - Writing to file /builds/slave/test/properties/build_url 09:32:19 INFO - Contents: 09:32:19 INFO - build_url:https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:32:19 INFO - Running post-action listener: _resource_record_post_action 09:32:19 INFO - Running post-action listener: set_extra_try_arguments 09:32:19 INFO - [mozharness: 2016-12-23 17:32:19.942707Z] Finished download-and-extract step (success) 09:32:19 INFO - [mozharness: 2016-12-23 17:32:19.942882Z] Running populate-webroot step. 09:32:19 INFO - Running pre-action listener: _resource_record_pre_action 09:32:19 INFO - Running main action method: populate_webroot 09:32:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:32:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:32:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:32:19 INFO - '--tppagecycles', 09:32:19 INFO - '7'], 09:32:19 INFO - 'tests': ['tsvgm']}}, 09:32:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:32:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:32:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:32:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:32:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:32:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:32:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:19 INFO - 'talos_options': ['--disable-e10s'], 09:32:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:32:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:32:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:19 INFO - 'talos_options': ['--disable-e10s'], 09:32:19 INFO - 'tests': ['damp', 'tps']}, 09:32:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:19 INFO - 'tests': ['damp', 'tps']}, 09:32:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:32:19 INFO - 'tests': ['dromaeo_dom']}, 09:32:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:32:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:32:19 INFO - 'tests': ['basic_compositor_video']}, 09:32:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:32:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:32:19 INFO - 'tests': ['a11yr', 09:32:19 INFO - 'ts_paint', 09:32:19 INFO - 'tpaint', 09:32:19 INFO - 'sessionrestore', 09:32:19 INFO - 'sessionrestore_no_auto_restore', 09:32:19 INFO - 'tabpaint']}, 09:32:19 INFO - 'other-e10s': {'tests': ['a11yr', 09:32:19 INFO - 'ts_paint', 09:32:19 INFO - 'tpaint', 09:32:19 INFO - 'sessionrestore', 09:32:19 INFO - 'sessionrestore_no_auto_restore', 09:32:19 INFO - 'tabpaint']}, 09:32:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:32:19 INFO - 'ts_paint', 09:32:19 INFO - 'tpaint', 09:32:19 INFO - 'sessionrestore', 09:32:19 INFO - 'sessionrestore_no_auto_restore', 09:32:19 INFO - 'tabpaint']}, 09:32:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:32:19 INFO - 'ts_paint', 09:32:19 INFO - 'tpaint', 09:32:19 INFO - 'sessionrestore', 09:32:19 INFO - 'sessionrestore_no_auto_restore', 09:32:19 INFO - 'tabpaint']}, 09:32:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:32:19 INFO - 'tests': ['a11yr', 09:32:19 INFO - 'ts_paint', 09:32:19 INFO - 'tpaint', 09:32:19 INFO - 'sessionrestore', 09:32:19 INFO - 'sessionrestore_no_auto_restore', 09:32:19 INFO - 'tabpaint']}, 09:32:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:32:19 INFO - 'tests': ['a11yr', 09:32:19 INFO - 'ts_paint', 09:32:19 INFO - 'tpaint', 09:32:19 INFO - 'sessionrestore', 09:32:19 INFO - 'sessionrestore_no_auto_restore', 09:32:19 INFO - 'tabpaint']}, 09:32:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:32:19 INFO - 'tests': ['tsvgx', 09:32:19 INFO - 'tsvgr_opacity', 09:32:19 INFO - 'tart', 09:32:19 INFO - 'tscrollx', 09:32:19 INFO - 'cart']}, 09:32:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:32:19 INFO - 'tsvgr_opacity', 09:32:19 INFO - 'tart', 09:32:19 INFO - 'tscrollx', 09:32:19 INFO - 'cart']}, 09:32:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:19 INFO - 'talos_options': ['--disable-e10s'], 09:32:19 INFO - 'tests': ['tp5o']}, 09:32:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:19 INFO - 'tests': ['tp5o']}, 09:32:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:19 INFO - 'talos_options': ['--disable-e10s', 09:32:19 INFO - '--xperf_path', 09:32:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:32:19 INFO - 'tests': ['tp5n']}, 09:32:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:19 INFO - 'talos_options': ['--xperf_path', 09:32:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:32:19 INFO - 'tests': ['tp5n']}}, 09:32:19 INFO - 'talos.zip': {'path': '', 09:32:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:32:19 INFO - Running post-action listener: _resource_record_post_action 09:32:19 INFO - [mozharness: 2016-12-23 17:32:19.961749Z] Finished populate-webroot step (success) 09:32:19 INFO - [mozharness: 2016-12-23 17:32:19.961889Z] Running create-virtualenv step. 09:32:19 INFO - Running pre-action listener: _resource_record_pre_action 09:32:19 INFO - Running main action method: create_virtualenv 09:32:19 INFO - Creating virtualenv /builds/slave/test/build/venv 09:32:19 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:32:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:32:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:32:20 INFO - Using real prefix '/tools/python27' 09:32:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:32:20 INFO - Installing distribute.............................................................................................................................................................................................done. 09:32:23 INFO - Installing pip.................done. 09:32:23 INFO - Return code: 0 09:32:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:32:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:32:23 INFO - Reading from file tmpfile_stdout 09:32:23 INFO - Output received: 09:32:23 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:32:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:32: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')]} 09:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32: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')]} 09:32:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109d98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a11d743e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109bacd30>, '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 0x109a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba4b20>, '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.i2ilZXxtwW/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.9CAI3juTUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32: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', 'pip>=1.5'] in /builds/slave/test/build 09:32: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 pip>=1.5 09:32:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i2ilZXxtwW/Render', 09:32:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:23 INFO - 'HOME': '/Users/cltbld', 09:32:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:23 INFO - 'LOGNAME': 'cltbld', 09:32:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:23 INFO - 'MOZ_NO_REMOTE': '1', 09:32:23 INFO - 'NO_EM_RESTART': '1', 09:32:23 INFO - 'PAGER': '/bin/cat', 09:32:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:23 INFO - 'PWD': '/builds/slave/test', 09:32:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:23 INFO - 'SHELL': '/bin/bash', 09:32:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9CAI3juTUZ/Listeners', 09:32:23 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 09:32:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:23 INFO - 'USER': 'cltbld', 09:32:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:23 INFO - 'XPC_FLAGS': '0x0', 09:32:23 INFO - 'XPC_SERVICE_NAME': '0', 09:32:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:32:24 INFO - Cleaning up... 09:32:24 INFO - Return code: 0 09:32:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109d98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a11d743e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109bacd30>, '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 0x109a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba4b20>, '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.i2ilZXxtwW/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.9CAI3juTUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:32:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:32:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i2ilZXxtwW/Render', 09:32:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:24 INFO - 'HOME': '/Users/cltbld', 09:32:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:24 INFO - 'LOGNAME': 'cltbld', 09:32:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:24 INFO - 'MOZ_NO_REMOTE': '1', 09:32:24 INFO - 'NO_EM_RESTART': '1', 09:32:24 INFO - 'PAGER': '/bin/cat', 09:32:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:24 INFO - 'PWD': '/builds/slave/test', 09:32:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:24 INFO - 'SHELL': '/bin/bash', 09:32:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9CAI3juTUZ/Listeners', 09:32:24 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 09:32:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:24 INFO - 'USER': 'cltbld', 09:32:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:24 INFO - 'XPC_FLAGS': '0x0', 09:32:24 INFO - 'XPC_SERVICE_NAME': '0', 09:32:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:24 INFO - Downloading/unpacking psutil>=3.1.1 09:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:32:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:32:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:32:28 INFO - Installing collected packages: psutil 09:32:28 INFO - Running setup.py install for psutil 09:32:28 INFO - building 'psutil._psutil_osx' extension 09:32:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:32:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:32:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:32:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:32:29 INFO - building 'psutil._psutil_posix' extension 09:32:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:32:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:32:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:32:29 INFO - ^ 09:32:29 INFO - 1 warning generated. 09:32:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:32:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:32:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:32:29 INFO - Successfully installed psutil 09:32:29 INFO - Cleaning up... 09:32:29 INFO - Return code: 0 09:32:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:32:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:29 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 0x109d98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a11d743e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109bacd30>, '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 0x109a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba4b20>, '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.i2ilZXxtwW/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.9CAI3juTUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:32:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:32:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i2ilZXxtwW/Render', 09:32:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:29 INFO - 'HOME': '/Users/cltbld', 09:32:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:29 INFO - 'LOGNAME': 'cltbld', 09:32:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:29 INFO - 'MOZ_NO_REMOTE': '1', 09:32:29 INFO - 'NO_EM_RESTART': '1', 09:32:29 INFO - 'PAGER': '/bin/cat', 09:32:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:29 INFO - 'PWD': '/builds/slave/test', 09:32:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:29 INFO - 'SHELL': '/bin/bash', 09:32:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9CAI3juTUZ/Listeners', 09:32:29 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 09:32:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:29 INFO - 'USER': 'cltbld', 09:32:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:29 INFO - 'XPC_FLAGS': '0x0', 09:32:29 INFO - 'XPC_SERVICE_NAME': '0', 09:32:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:32:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:32:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:32:32 INFO - Installing collected packages: mozsystemmonitor 09:32:32 INFO - Running setup.py install for mozsystemmonitor 09:32:32 INFO - Successfully installed mozsystemmonitor 09:32:32 INFO - Cleaning up... 09:32:32 INFO - Return code: 0 09:32:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:32 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 0x109d98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a11d743e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109bacd30>, '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 0x109a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba4b20>, '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.i2ilZXxtwW/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.9CAI3juTUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:32:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i2ilZXxtwW/Render', 09:32:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:32 INFO - 'HOME': '/Users/cltbld', 09:32:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:32 INFO - 'LOGNAME': 'cltbld', 09:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:32 INFO - 'MOZ_NO_REMOTE': '1', 09:32:32 INFO - 'NO_EM_RESTART': '1', 09:32:32 INFO - 'PAGER': '/bin/cat', 09:32:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:32 INFO - 'PWD': '/builds/slave/test', 09:32:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:32 INFO - 'SHELL': '/bin/bash', 09:32:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9CAI3juTUZ/Listeners', 09:32:32 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 09:32:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:32 INFO - 'USER': 'cltbld', 09:32:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:32 INFO - 'XPC_FLAGS': '0x0', 09:32:32 INFO - 'XPC_SERVICE_NAME': '0', 09:32:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:33 INFO - Downloading/unpacking jsonschema==2.5.1 09:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:32:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:32: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 09:32: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 09:32: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 09:32: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 09:32: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 09:32: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 09:32:36 INFO - Downloading functools32-3.2.3-2.tar.gz 09:32:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:32:36 INFO - warning: no files found matching '*.txt' 09:32:36 INFO - no previously-included directories found matching 'build' 09:32:36 INFO - no previously-included directories found matching 'dist' 09:32:36 INFO - no previously-included directories found matching '.git*' 09:32:36 INFO - Installing collected packages: jsonschema, functools32 09:32:36 INFO - Running setup.py install for functools32 09:32:36 INFO - warning: no files found matching '*.txt' 09:32:36 INFO - no previously-included directories found matching 'build' 09:32:36 INFO - no previously-included directories found matching 'dist' 09:32:36 INFO - no previously-included directories found matching '.git*' 09:32:36 INFO - Successfully installed jsonschema functools32 09:32:36 INFO - Cleaning up... 09:32:36 INFO - Return code: 0 09:32:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:36 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 0x109d98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a11d743e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109bacd30>, '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 0x109a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba4b20>, '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.i2ilZXxtwW/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.9CAI3juTUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:32:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:32:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i2ilZXxtwW/Render', 09:32:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:36 INFO - 'HOME': '/Users/cltbld', 09:32:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:36 INFO - 'LOGNAME': 'cltbld', 09:32:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:36 INFO - 'MOZ_NO_REMOTE': '1', 09:32:36 INFO - 'NO_EM_RESTART': '1', 09:32:36 INFO - 'PAGER': '/bin/cat', 09:32:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:36 INFO - 'PWD': '/builds/slave/test', 09:32:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:36 INFO - 'SHELL': '/bin/bash', 09:32:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9CAI3juTUZ/Listeners', 09:32:36 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 09:32:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:36 INFO - 'USER': 'cltbld', 09:32:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:36 INFO - 'XPC_FLAGS': '0x0', 09:32:36 INFO - 'XPC_SERVICE_NAME': '0', 09:32:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:32:36 INFO - Cleaning up... 09:32:36 INFO - Return code: 0 09:32:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:36 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 0x109d98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a11d743e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109bacd30>, '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 0x109a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba4b20>, '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.i2ilZXxtwW/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.9CAI3juTUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:32:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:32:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i2ilZXxtwW/Render', 09:32:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:36 INFO - 'HOME': '/Users/cltbld', 09:32:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:36 INFO - 'LOGNAME': 'cltbld', 09:32:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:36 INFO - 'MOZ_NO_REMOTE': '1', 09:32:36 INFO - 'NO_EM_RESTART': '1', 09:32:36 INFO - 'PAGER': '/bin/cat', 09:32:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:36 INFO - 'PWD': '/builds/slave/test', 09:32:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:36 INFO - 'SHELL': '/bin/bash', 09:32:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9CAI3juTUZ/Listeners', 09:32:36 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 09:32:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:36 INFO - 'USER': 'cltbld', 09:32:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:36 INFO - 'XPC_FLAGS': '0x0', 09:32:36 INFO - 'XPC_SERVICE_NAME': '0', 09:32:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:36 INFO - Downloading/unpacking blobuploader==1.2.4 09:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:39 INFO - Downloading blobuploader-1.2.4.tar.gz 09:32:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:32:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:32:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:40 INFO - Downloading docopt-0.6.1.tar.gz 09:32:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:32:40 INFO - Installing collected packages: blobuploader, requests, docopt 09:32:40 INFO - Running setup.py install for blobuploader 09:32:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:32:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:32:41 INFO - Running setup.py install for requests 09:32:41 INFO - Running setup.py install for docopt 09:32:41 INFO - Successfully installed blobuploader requests docopt 09:32:41 INFO - Cleaning up... 09:32:41 INFO - Return code: 0 09:32:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:41 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 0x109d98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a11d743e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109bacd30>, '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 0x109a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba4b20>, '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.i2ilZXxtwW/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.9CAI3juTUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:32:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:32:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i2ilZXxtwW/Render', 09:32:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:41 INFO - 'HOME': '/Users/cltbld', 09:32:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:41 INFO - 'LOGNAME': 'cltbld', 09:32:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:41 INFO - 'MOZ_NO_REMOTE': '1', 09:32:41 INFO - 'NO_EM_RESTART': '1', 09:32:41 INFO - 'PAGER': '/bin/cat', 09:32:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:41 INFO - 'PWD': '/builds/slave/test', 09:32:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:41 INFO - 'SHELL': '/bin/bash', 09:32:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9CAI3juTUZ/Listeners', 09:32:41 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 09:32:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:41 INFO - 'USER': 'cltbld', 09:32:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:41 INFO - 'XPC_FLAGS': '0x0', 09:32:41 INFO - 'XPC_SERVICE_NAME': '0', 09:32:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:32:42 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-5s4DXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:32:42 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-rBdBsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:32:42 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-5zDI6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:32:42 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-BqKx_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:32:42 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-z37vLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:32:42 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-Osu_QQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:32:42 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-52pwFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:32:42 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-JTuHoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:32:42 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-GDjS0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:32:42 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-osmCsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:32:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-5YAegz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:32:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-60AR61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:32:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-wGifN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:32:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-D3XXUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-ATJ8WT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:32:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-7vxDOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:32:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-ZCp8wd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:32:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:32:43 INFO - Running setup.py install for manifestparser 09:32:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:32:43 INFO - Running setup.py install for mozcrash 09:32:44 INFO - Running setup.py install for mozdebug 09:32:44 INFO - Running setup.py install for mozdevice 09:32:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:32:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:32:44 INFO - Running setup.py install for mozfile 09:32:44 INFO - Running setup.py install for mozhttpd 09:32:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:32:44 INFO - Running setup.py install for mozinfo 09:32:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:32:44 INFO - Running setup.py install for mozInstall 09:32:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:32:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:32:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:32:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:32:44 INFO - Running setup.py install for mozleak 09:32:45 INFO - Running setup.py install for mozlog 09:32:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:32:45 INFO - Running setup.py install for moznetwork 09:32:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:32:45 INFO - Running setup.py install for mozprocess 09:32:45 INFO - Running setup.py install for mozprofile 09:32:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:32:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:32:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:32:45 INFO - Running setup.py install for mozrunner 09:32:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:32:45 INFO - Running setup.py install for mozscreenshot 09:32:46 INFO - Running setup.py install for moztest 09:32:46 INFO - Running setup.py install for mozversion 09:32:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:32:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:32:46 INFO - Cleaning up... 09:32:46 INFO - Return code: 0 09:32:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:46 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 0x109d98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a11d743e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109bacd30>, '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 0x109a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba4b20>, '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.i2ilZXxtwW/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.9CAI3juTUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:32:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:32:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i2ilZXxtwW/Render', 09:32:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:46 INFO - 'HOME': '/Users/cltbld', 09:32:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:46 INFO - 'LOGNAME': 'cltbld', 09:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:46 INFO - 'MOZ_NO_REMOTE': '1', 09:32:46 INFO - 'NO_EM_RESTART': '1', 09:32:46 INFO - 'PAGER': '/bin/cat', 09:32:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:46 INFO - 'PWD': '/builds/slave/test', 09:32:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:46 INFO - 'SHELL': '/bin/bash', 09:32:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9CAI3juTUZ/Listeners', 09:32:46 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 09:32:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:46 INFO - 'USER': 'cltbld', 09:32:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:46 INFO - 'XPC_FLAGS': '0x0', 09:32:46 INFO - 'XPC_SERVICE_NAME': '0', 09:32:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:32:46 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-ENhSeS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:32:46 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-tyoWfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:32:46 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-wNnWaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:32:46 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-Zq5zLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:32:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-rw0pv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:32:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-ELPNMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:32:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-6HI77e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:32:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-lZnn47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:32:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-wqyfog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:32:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-IwqbGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:32:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-fbVccC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:32:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-9Lp0nx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:32:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-sWBXZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:32:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-URUn4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:48 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-BtSgfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:32:48 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-x9Vni2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:32:48 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-JRugwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:32:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:51 INFO - Downloading blessings-1.6.tar.gz 09:32:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:32:51 INFO - Installing collected packages: blessings 09:32:51 INFO - Running setup.py install for blessings 09:32:51 INFO - Successfully installed blessings 09:32:51 INFO - Cleaning up... 09:32:51 INFO - Return code: 0 09:32:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:32:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:32:51 INFO - Reading from file tmpfile_stdout 09:32:51 INFO - Current package versions: 09:32:51 INFO - blessings == 1.6 09:32:51 INFO - blobuploader == 1.2.4 09:32:51 INFO - docopt == 0.6.1 09:32:51 INFO - functools32 == 3.2.3-2 09:32:51 INFO - jsonschema == 2.5.1 09:32:51 INFO - manifestparser == 1.1 09:32:51 INFO - mozInstall == 1.12 09:32:51 INFO - mozcrash == 1.0 09:32:51 INFO - mozdebug == 0.1 09:32:51 INFO - mozdevice == 0.48 09:32:51 INFO - mozfile == 1.2 09:32:51 INFO - mozhttpd == 0.7 09:32:51 INFO - mozinfo == 0.9 09:32:51 INFO - mozleak == 0.1 09:32:51 INFO - mozlog == 3.4 09:32:51 INFO - moznetwork == 0.27 09:32:51 INFO - mozprocess == 0.23 09:32:51 INFO - mozprofile == 0.28 09:32:51 INFO - mozrunner == 6.13 09:32:51 INFO - mozscreenshot == 0.1 09:32:51 INFO - mozsystemmonitor == 0.3 09:32:51 INFO - moztest == 0.7 09:32:51 INFO - mozversion == 1.4 09:32:51 INFO - psutil == 3.1.1 09:32:51 INFO - requests == 1.2.3 09:32:51 INFO - wsgiref == 0.1.2 09:32:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:51 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 0x109d98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a11d743e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109bacd30>, '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 0x109a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba4b20>, '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.i2ilZXxtwW/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.9CAI3juTUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:32:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i2ilZXxtwW/Render', 09:32:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:51 INFO - 'HOME': '/Users/cltbld', 09:32:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:51 INFO - 'LOGNAME': 'cltbld', 09:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:51 INFO - 'MOZ_NO_REMOTE': '1', 09:32:51 INFO - 'NO_EM_RESTART': '1', 09:32:51 INFO - 'PAGER': '/bin/cat', 09:32:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:51 INFO - 'PWD': '/builds/slave/test', 09:32:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:51 INFO - 'SHELL': '/bin/bash', 09:32:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9CAI3juTUZ/Listeners', 09:32:51 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 09:32:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:51 INFO - 'USER': 'cltbld', 09:32:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:51 INFO - 'XPC_FLAGS': '0x0', 09:32:51 INFO - 'XPC_SERVICE_NAME': '0', 09:32:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:32:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:32:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:32:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:32:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:32:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:32:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:32:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:32:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:32:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:32:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:32:51 INFO - Cleaning up... 09:32:51 INFO - Return code: 0 09:32:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:51 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 0x109d98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a11d743e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109bacd30>, '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 0x109a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba4b20>, '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.i2ilZXxtwW/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.9CAI3juTUZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:32:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i2ilZXxtwW/Render', 09:32:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:51 INFO - 'HOME': '/Users/cltbld', 09:32:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:51 INFO - 'LOGNAME': 'cltbld', 09:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:51 INFO - 'MOZ_NO_REMOTE': '1', 09:32:51 INFO - 'NO_EM_RESTART': '1', 09:32:51 INFO - 'PAGER': '/bin/cat', 09:32:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:51 INFO - 'PWD': '/builds/slave/test', 09:32:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:51 INFO - 'SHELL': '/bin/bash', 09:32:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9CAI3juTUZ/Listeners', 09:32:51 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 09:32:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:51 INFO - 'USER': 'cltbld', 09:32:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:51 INFO - 'XPC_FLAGS': '0x0', 09:32:51 INFO - 'XPC_SERVICE_NAME': '0', 09:32:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:32:52 INFO - Cleaning up... 09:32:52 INFO - Return code: 0 09:32:52 INFO - Running post-action listener: _resource_record_post_action 09:32:52 INFO - Running post-action listener: _start_resource_monitoring 09:32:52 INFO - Starting resource monitoring. 09:32:52 INFO - [mozharness: 2016-12-23 17:32:52.223155Z] Finished create-virtualenv step (success) 09:32:52 INFO - [mozharness: 2016-12-23 17:32:52.224116Z] Running install step. 09:32:52 INFO - Running pre-action listener: _resource_record_pre_action 09:32:52 INFO - Running main action method: install 09:32:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:32:52 INFO - Reading from file tmpfile_stdout 09:32:52 INFO - Detecting whether we're running mozinstall >=1.0... 09:32:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:32:52 INFO - Reading from file tmpfile_stdout 09:32:52 INFO - Output received: 09:32:52 INFO - Usage: mozinstall [options] installer 09:32:52 INFO - Options: 09:32:52 INFO - -h, --help show this help message and exit 09:32:52 INFO - -d DEST, --destination=DEST 09:32:52 INFO - Directory to install application into. [default: 09:32:52 INFO - "/builds/slave/test"] 09:32:52 INFO - --app=APP Application being installed. [default: firefox] 09:32:52 INFO - mkdir: /builds/slave/test/build/application 09:32:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:33:23 INFO - Reading from file tmpfile_stdout 09:33:23 INFO - Output received: 09:33:23 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:33:23 INFO - Running post-action listener: _resource_record_post_action 09:33:23 INFO - [mozharness: 2016-12-23 17:33:23.461391Z] Finished install step (success) 09:33:23 INFO - [mozharness: 2016-12-23 17:33:23.461489Z] Running run-tests step. 09:33:23 INFO - Running pre-action listener: _resource_record_pre_action 09:33:23 INFO - Running main action method: run_tests 09:33:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:33:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:33:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:33:23 INFO - 2016-12-23 09:33:23.475 screenresolution[1629:7896] starting screenresolution argv=screenresolution get 09:33:23 INFO - 2016-12-23 09:33:23.478 screenresolution[1629:7896] Display 0: 1600x1200x32@60 09:33:23 INFO - 2016-12-23 09:33:23.484 screenresolution[1630:7898] starting screenresolution argv=screenresolution list 09:33:23 INFO - Available Modes on Display 0 09:33:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:33:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:33:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:33:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:33:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:33:23 INFO - Intel Iris: 09:33:23 INFO - Chipset Model: Intel Iris 09:33:23 INFO - Type: GPU 09:33:23 INFO - Bus: Built-In 09:33:23 INFO - VRAM (Dynamic, Max): 1536 MB 09:33:23 INFO - Vendor: Intel (0x8086) 09:33:23 INFO - Device ID: 0x0a2e 09:33:23 INFO - Revision ID: 0x0009 09:33:23 INFO - Displays: 09:33:23 INFO - Display: 09:33:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:33:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:33:23 INFO - Display Serial Number: 1600x1200 60 09:33:23 INFO - Main Display: Yes 09:33:23 INFO - Mirror: Off 09:33:23 INFO - Online: Yes 09:33:23 INFO - Rotation: Supported 09:33:23 INFO - Return code: 0 09:33:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:33:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:33:23 INFO - Python 2.7.3 09:33:23 INFO - Return code: 0 09:33:23 INFO - grabbing minidump binary from tooltool 09:33: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')]} 09:33:23 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109bacd30>, '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 0x109a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:33:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:33:23 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:33:23 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:33:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:33:23 INFO - Return code: 0 09:33:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:33:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:33:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:33:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:33:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:33:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:33:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0176 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:33:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i2ilZXxtwW/Render', 09:33:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:23 INFO - 'HOME': '/Users/cltbld', 09:33:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:23 INFO - 'LOGNAME': 'cltbld', 09:33:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:33:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:33:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:23 INFO - 'MOZ_NO_REMOTE': '1', 09:33:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:33:23 INFO - 'NO_EM_RESTART': '1', 09:33:23 INFO - 'PAGER': '/bin/cat', 09:33:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:23 INFO - 'PWD': '/builds/slave/test', 09:33:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:33:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:23 INFO - 'SHELL': '/bin/bash', 09:33:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9CAI3juTUZ/Listeners', 09:33:23 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 09:33:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:23 INFO - 'USER': 'cltbld', 09:33:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:23 INFO - 'XPC_FLAGS': '0x0', 09:33:23 INFO - 'XPC_SERVICE_NAME': '0', 09:33:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:33:24 INFO - mozversion application_buildid: 20161223062836 09:33:24 INFO - mozversion application_changeset: ec2672807887aa46bb4c2966478afa08d610d72d 09:33:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:33:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:33:24 INFO - mozversion application_name: Firefox 09:33:24 INFO - mozversion application_remotingname: firefox-dev 09:33:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:33:24 INFO - mozversion application_vendor: Mozilla 09:33:24 INFO - mozversion application_version: 52.0a2 09:33:24 INFO - mozversion platform_buildid: 20161223062836 09:33:24 INFO - mozversion platform_changeset: ec2672807887aa46bb4c2966478afa08d610d72d 09:33:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:33:24 INFO - mozversion platform_version: 52.0a2 09:33:24 INFO - using testdate: 1482514404 09:33:24 INFO - actual date: 1482514404 09:33:24 INFO - starting webserver on 'localhost:49240' 09:33:24 INFO - SUITE-START | Running 2 tests 09:33:24 INFO - TEST-START | dromaeo_css 09:33:24 INFO - operating with platform_type : mac_ 09:33:24 INFO - Initialising browser for dromaeo_css test... 09:33:24 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpTv5uwL/profile http://localhost:49240/getInfo.html) 09:33:27 INFO - PROCESS | 1640 | 2016-12-23 09:33:27.475 plugin-container[1642:8074] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 09:33:27 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:33:27 INFO - PROCESS | 1640 | 2016-12-23 09:33:27.480 plugin-container[1642:8074] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d07, name = 'com.apple.CFPasteboardClient' 09:33:27 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:33:27 INFO - PROCESS | 1640 | 2016-12-23 09:33:27.480 plugin-container[1642:8074] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:33:27 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 09:33:27 INFO - PROCESS | 1640 | colorDepth:24 09:33:27 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 09:33:27 INFO - PROCESS | 1640 | __metrics 09:33:27 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 09:33:28 INFO - TEST-INFO | 1640: exit 0 09:33:28 INFO - Browser initialized. 09:33:28 INFO - Running cycle 1/1 for dromaeo_css test... 09:33:28 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpTv5uwL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:33:31 INFO - PROCESS | 1644 | 2016-12-23 09:33:31.070 plugin-container[1645:8219] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 09:33:31 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:33:31 INFO - PROCESS | 1644 | 2016-12-23 09:33:31.070 plugin-container[1645:8219] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 09:33:31 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:33:31 INFO - PROCESS | 1644 | 2016-12-23 09:33:31.070 plugin-container[1645:8219] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:33:40 INFO - PROCESS | 1644 | RSS: Main: 229380096 09:33:40 INFO - PROCESS | 1644 | 09:34:30 INFO - PROCESS | 1644 | 1482514470287 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:36:44 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 09:36:46 INFO - PROCESS | 1644 | RSS: Main: 231903232 09:36:46 INFO - PROCESS | 1644 | 09:39:48 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 09:39:49 INFO - PROCESS | 1644 | RSS: Main: 246329344 09:39:49 INFO - PROCESS | 1644 | 09:42:51 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 09:42:52 INFO - PROCESS | 1644 | RSS: Main: 249102336 09:42:52 INFO - PROCESS | 1644 | 09:45:54 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 09:45:54 INFO - PROCESS | 1644 | RSS: Main: 248983552 09:45:54 INFO - PROCESS | 1644 | 09:48:57 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 09:48:58 INFO - PROCESS | 1644 | RSS: Main: 252805120 09:48:58 INFO - PROCESS | 1644 | 09:52:00 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 09:52:01 INFO - PROCESS | 1644 | RSS: Main: 254062592 09:52:01 INFO - PROCESS | 1644 | 09:52:01 INFO - PROCESS | 1644 | __start_tp_report 09:52:01 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 09:52:01 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 09:52:01 INFO - PROCESS | 1644 | |i|pagename|runs| 09:52:01 INFO - PROCESS | 1644 | |0;dojo.html;3373.460246360583;3373.7469689773266;3427.126082848873;3429.2969941162055;3433.467812489065;5751.482366587012;5763.711814409278;5814.534837213024;5848.561357898164;5848.707564621774;5820.272465941757;5862.179294898707;5891.823245302648;5897.292324921004;5922.526197904168;5279.86800329991;5340.519353258206;5369.275147855029;5404.189371594272;5406.432324605918;5367.490088441592;5396.325459317585;5407.621466497347;5409.134538473845;5483.424240454755;5251.579873610102;5305.283786688806;5352.946470535284;5397.082495975674;5404.810831620894;5333.519983201511;5349.625526213166;5381.219723140122;5415.9458405415835;5443.755031023574;4417.955820441786;5246.606504512177;5374.086405311067;5404.270423492818;5412.07994640912;5885.529157667376;5945.583809133363;5981.760729570812;6007.068904319837;6013.248343957005;6142.222533142085;6201.6899155042065;6228.37716228378;6249.437550620421;6297.370262973712;5127.692338459667;6238.189035425411;6264.561480696353;6290.622562646256;6310.526710496685;5965.22452081231;5970.611910225822;5988.850278743066;6004.459598636144;6017.759289628453;6333.398327158912;6342.112104305401;6378.649174295459;6417.9679101605125;6511.28375878653;6192.783252586138;6242.219722534684;6246.437820596168;6264.624122552661;6289.528285378615;6307.590006649553;6315.621062736251;6320.209973753281;6328.715207815567;6344.904826427607;6153.923063463876;6165.907511387333;6238.189035425366;6248.937510624926;6273.153124328157;6278.215223097113;6280.842978925472;6281.905771413432;6282.340354262737;6314.652694101868;4994.1759609663995;5021.774020169101;5027.069992051409;5049.949500505021;5056.115179843513;4003.899902502461;4435.955640443619;4439.023414848728;4502.144592527409;4524.705894116872;4583.770811459413;5533.750981205855;6013.067974463965;6110.511159107261;6120.051392034242;5988.6406815590335;6274.560780745304;6315.842103947346;6316.273628532777;6372.107904893319;5889.234399528034;5913.46778789911;5950.732217050243;6032.517398608101;6058.90911636317;4298.699091063596;5430.104032834617;5501.257330260363;5529.391766905637;5605.187247849039;6494.935050649433;6528.6735663215695;6530.1510803596175;6530.869382612416;6553.967230163818;6273.215848018997;6324.968375158279;6331.841703957438;6342.112104305494;6344.953082741481;6216.471599914058;6376.713047912763;6415.101885736094;6438.066480360415;6473.77341793035;6264.874702506016;6274.1843560336865;6277.71750271248;6278.874422511615;6291.402316779899;6210.068489726577;6303.590266632654;6309.432151106423;6323.304436511888;6347.492200624031;5987.760489580368;6091.482224011009;6109.283207920342;6116.235470566179;6140.4428991762525;6135.110408990582;6178.258608966953;6195.876082478415;6211.3167551570195;6234.501239900706;5997.370276120944;6055.9091613626715;6126.356732543019;6145.446909778141;6163.106349579196;6044.274687037584;6111.40942266768;6136.478399335928;6155.47678447337;6175.171393735913;5936.28764548257;6011.308699499614;6032.6983650818165;6040.335563087969;6041.577089603861 09:52:01 INFO - PROCESS | 1644 | |1;ext.html;441.86964845370625;495.7769003948224;509.8980203959208;520.2638266852404;520.6875874475315;29732.851335743344;39047.41428878566;40109.79945100274;40162;42045.57954420462;41227.76316710496;41467.58532414675;41723;41762.32950681977;41795;9274.582643781012;9402.952985235099;9417.62329506819;9459.763505912373;9460.858087128665;8181.509109453422;9180.44917304964;9382.812343753121;9494.240460763122;9504.619815207383;8285;9336.439813611205;9377.484238366887;9427.34008619397;9488.383255088396;9408.717738467856;9481.24150067993;9493.430394176303;9495.952520237424;9498.430094194302;9256.72229833106;9286.442813431217;9360.953195234048;9364.90635093654;9383.671571494964;39368.409473857915;39617.80191099026;39657.20685586301;39689.404658930136;39704;2912.970870291312;3258.3157536917274;3336.799792012487;3418.453047512386;3493.8602455901987;29036.12891613255;29325;29773.851130744424;29818.701812982028;30237.848810756026;3145;3149.7637677174303;3156.352947645727;3175.380800743856;3294.1105901406727;455631;457757;458626;459969;460283;43094.569054309686;47942.28086578704;52458.73770631122;57890.421095789345;57934;55941.32176034725;59903.700481498185;60136;60451.395486044574;60523;14721.337539810735;16107.838921610868;16134.870559060753;16139.515814525583;16197.919010404872;12509.311987840756;12540.122191446511;12604.991600671925;12617;12633.178843375152;12554.811677824657;15878.285477153557;15921.283542240628;15967.760483592756;16090.275937582837;5217.399999000164;5280.577553795687;6050.45545900871;6091.7563297467605;6299.622022678654;807.5518087461516;826.4999675196419;835.3108685334623;975.7703647166333;1030.7474668706216;29267.853660731987;29876.103716888527;30015.549766753516;30742.014152640502;31114.91097811611;957.7405711489508;1039.2309690828731;1044.8171569975225;1047.8323468245044;1122.8484154639182;13906.721865562633;15071.171085590398;15121.941464097408;15126.016808907385;15169.848301516844;506713;633864;634006;634063.8296808578;642389;53472.73263633657;53723.46276537185;53749.656258593066;53819;54193.72903135538;1185.057878986208;1325.3108383640454;1363.16165558181;1364.4064831798;1376.8554301798167;19974;28317.506574383413;28989.275268118465;29023.274418139714;29563.965261215755;48939.51060489492;49246.26130608116;49353.012939740285;49457.752711236215;49623.751881240365;556044;556336;556460;557578;557597;25384.238472845846;25543.489130217662;25910.093146739775;25933.96264149413;25992.480150397263;43487.78256108699;44353;44504.33243501415;44511.77744111259;44636.33045504385;36627.90116296515;37029;37165.81417092897;37205;38441.231175376895;27217.455650887267;36634.8168259157;37889.62110378861;37935.62064379321;38341.658041968396;30337.848310758305;35914.64085359217;36201.45697814482;36802;37805.43291850569;31536.684633153374;33415.49876751899;33467;33481;33551.83224083864;24396;25159.748402516474;25200.557813132807;25402;26795.928162873268 09:52:01 INFO - PROCESS | 1644 | |2;jquery.html;340.1920438957476;370.9888703338899;380.70875780028274;387.5794762682489;398.0983073338888;18786.718199227005;19509.804901950974;19642.90178549109;19689;19880;11417.429128543561;11685.240459370136;11805.645830625073;11815.881841181586;11822.231554948923;5029.673071250366;5321.893562128775;5373.865653358658;5420.91868621971;5431.456854314576;3874.4575759393706;4481.865544033668;5409;5454.481824226692;5465.262189604412;5117.692938423688;6094.725737341808;6180;6197.101420294055;6200.069989501589;6173.783917562886;6210.347913469089;6242.18851549298;6563.146790917151;6591.373819487141;5861.384554621746;5890.646561206298;5894.263217097863;5909.054551271776;5919.911201331984;22607.208747693752;23069.961851716715;24181.87909060461;24390.878045609657;24524;2146.216630929715;2171.695962565226;2219;2246.61807492725;2255.9121915783903;9731.464769437678;9801.16690081344;9829.311948163631;10141.746456338651;10326.793464130675;1838.5403649087727;1899.5061284066105;1901.9073840807414;1908.1031914999928;1917.6835822089338;116469;130242.34878825545;133164;136781;138979.30510347383;11549.36478493691;11550.364729939934;11569.479373428216;11569.88430115705;11615.36115513638;11807;14580;14748.410063597335;14752.852471475362;14755.77866332006;8248.711295104642;8488.915110848937;8489.4481858679;8578.914210857936;8655.6970506032;6896.793096207121;8330.50016998982;8362.916370836336;8393.95803020981;8561.357898157663;3740;4581.908361832811;4608.539146085364;4626.8380606679275;4696.8590942271785;1462.0350568624656;1898.4209816005975;1960.4804726747423;1961.8430525557922;1964.9705254421194;725.2783480436999;758.893754874318;886.9822603548022;922.7462447826902;970.69908328417;19809.801901981085;19876.70184947227;20134;20322.796772032387;21023.474413139793;899.1458114790844;904.9185573298435;938.2353381993653;939.6241503398695;969.1665167955553;5854.882902342015;5980.910286345708;6149.66176860268;6166.81499555014;10620.468976551141;446525;447271;447543;530266;611176;128235.35882320529;132997.3350133243;137213;138518;138718.30640846933;437.77017066039946;451.34780242549203;452.8120829855639;456.97943592538417;458.42467703031815;11401.486933088197;11569.595064172714;11578;11623.07015438746;11824.586139485076;54072.45927540674;54776.45223547713;55142;56433.71783141058;57386.713066436074;64191.67904160449;79383;79521;79636;82594.17405825865;6591.868162636816;8024.197580241929;8353.33173346143;8407.327413807003;8492.532910689972;9239.445633262027;11814.82277765817;12402.317872517098;12769;12801.487940482277;10830.783384332111;10894.074003709773;11030.407854154108;11061.668149955514;11140.944295278472;10844.45777711127;10910.890891091305;10997.395143267197;11094.810923328478;11198.272112312674;8881.978572463991;9058.36591438593;9568.234541236545;11382.260153090023;11553;7976.521408715495;8127.918720812716;8144.0634327050775;8467.195616416431;8477.364197685074;8198.631061602362;10471.581136754749;10906.890931090587;11042.447877606248;11305.491252893804 09:52:01 INFO - PROCESS | 1644 | |3;mootools.html;1434.4549071352885;1439.1940513312545;1478.9778153327698;1738.243863919196;1746.6332070265244;6284.245890493142;7524.172341042481;7798.727044553441;8041.9947506561675;8530;8072.71745488908;8352.832943341129;8386.245237928586;8473.025602055766;8535.359848011409;2319.605667036608;2363.9409014774596;2365.420471984358;2367.810575153992;2375.655529948165;2347.225415612852;2379.0602711928777;2389.2115601851347;2399.6040653292184;2403.387136280246;2336.7312759032675;2337.4039619896903;2353.9646905296436;2363.929082127539;2365.7042869641296;2315.3979965209;2315.6758053872472;2334.7665233476687;2342.1099982006813;2350.847194932324;2331.63859601762;2349.048635302705;2363.5036642305136;2364.810815134785;2364.9408764780846;8413.28487078599;8479.109693482218;8526.957365213135;8537.957310213471;8551.358648101355;4241.533431322552;5286.471352864722;5293.391260005072;5296.5762738981175;5306.946930530722;3484.6515348465205;3514.578250609943;3566.0906468850276;3584;3615.2407994321225;1052.973675658107;1063.0857462582173;1093.792179485903;1108.894655007781;1110.8333749937494;10950.616728414547;11068.612598559092;11076.446177691163;11078.169137314733;11120.110391168688;3950.506186726659;4497.887552811206;4656.743879086615;4685.250359942393;4789.30555069513;8745.518996455128;8906.376553641183;9522.52387380642;9608.087231712976;9634.373765705206;4440.866773996785;5262.210668399694;5265.473452654704;5270;5274.815381461707;3855.575886652466;3966.8809935701975;4472.463304403427;4742.264948932921;4878.8780280492565;2978.359652674642;3862.3240932836643;3882.2235552889533;3952.7628342299554;3969.801509924492;1256.2462522486435;1274.6430999320205;1274.8215249865027;1285.517930775959;1288.5425673885636;787.1420152034195;866.3848667446058;884.6151923913119;886.7118186589385;888.9155530224748;5404.432534583812;5592.888142237214;5742.942570574241;5783.971080144656;5867.530597552186;931.2736065868634;964.9903500965041;1000.8748906386702;1005.0803514784037;1024.7233247023262;3645.2527231917393;3759.882285457754;3765.4351847223134;3772.830222639988;3806.4861243732294;10022.448765317831;10326.535305911251;10395.272330936761;10430;10430.37417754937;10243.641472548425;10352.948235258926;10353.43056131905;10362.896371036193;10482.318649287772;7695.422843286776;7877.842443151219;8071.717489887826;8072.636731347104;8076.798080048045;3908.941365879571;5397.78408863641;5430.348358196884;5559.63862348946;5584.888302234014;9365.719028429157;9385.15533601979;9409.764755881159;9483.193928515877;9511.001344858967;9595.13643772064;9596.088371604686;9602.95198524003;9678.322517423994;9754.463504507037;6300;6494.6752662367635;6636.535442518977;6726.630035348103;6869.484788640972;6307.369263073656;6682.9331706682315;8065.677372905018;8118.228768267005;8127.1466496017065;7043.190033146254;7199.316064973819;7208.243134470805;7232.674529646073;7293.744718935024;7023.29767023314;7041.471889608402;7059.364657180879;7077.327653872874;7146.142462904485;5818.359980402237;6558.901616475661;6664.5334826561675;6754.425873800782;6778.796636100925;3945.2109578083923;4163.834126444601;4755.643326750576;4767.880802979953;4875.268709693432;4814.277858321279;4867.464578896389;4881.51184881509;4933.963867587847;4938.975305123451 09:52:01 INFO - PROCESS | 1644 | |4;prototype.html;273.17501146537455;295.1087715100397;310.4412058295068;313.0498935730058;313.4264296337702;4059.533153687327;4244.681648876332;5534.916976245355;5547.977716155785;5617.034038086667;5035.370578677665;5369.812056578021;5387;5458.645188062773;5468.343798744147;3758.9624103759024;3966.988417953418;4156.854510092147;4199.55904630014;4231.937340153457;4102.487189101347;4238.618524332809;4238.9152216955645;4249.3201087825955;4267.615914567688;3832.8466861325514;4172.77049762263;4188.623023927846;4202.642775364083;4208.13733184698;3666.88160111166;4026.724900431247;4160.188763191179;4178.958210417918;4183.218159923662;3119.391718614871;3498.4752287156875;3606.2607165531003;3638.853761065256;3686.078480379905;4810.759462026885;4952.514245726267;5033.597312215014;5344.625876188668;5442.782288708447;4015.8554811878766;4067.776272305022;4760.643216683263;4851.162070188943;4871.82948596801;4994;5535.972320138373;5759.942400576025;5809.941900581025;5899.793507227269;3481.8781342652887;3688.3729765939856;3908.7654740715366;3996.320625493644;4068.9796551017353;4783.856484305442;5087.771050302746;5227.895442091175;6089.451949324582;6098.908516372258;3439.793612383265;3456.2396272820442;3774;3894.8636797711943;4121.608447197571;3939.546952100488;4054;4106.178764247163;4108.568600296986;4138.896527586774;3979.422983667352;4739.881502962385;4774.591495508833;4785.784639691222;4842.903141937141;4955.851324460272;5345.9842629900195;5685.687287199161;5826.359100498942;5893.970530147321;1172.8944395004492;1229.3730197599289;1318.8878945289566;1326.8540460549332;1353.066384194922;769.7690692792252;891.028778631295;930.7440453875239;930.8464103422859;935;4492.663050271242;4588.787848506059;4978.303037574742;5206.349206349207;5227.666944929095;4967.82612608557;5143.434222235551;5672.5745569081655;5679;5707.686077265707;4002.8598999034893;4503.572160644733;4639.698419602715;5027.899442011139;5196.480351964773;2920.664123625768;2969.1294484474643;2984.850757462118;2995.831625665992;3001.5197568388953;4730.989329534934;4912.975435122801;4982.750862456863;5015.918393866577;5799.375390381012;4504.603135108236;4834.613230941516;5190.78171533644;5234.895302094013;5474.427019300922;4887.706737595756;5173.586113110942;5579.860503487445;5749.338826034976;5817.621854579524;4907.165781817171;4989.8752531185555;5243.737813109406;5656.490915817596;5709.231022953947;4656.67403281767;4699.872030712672;4777.378940737682;5155.044743119209;5448.431277513235;4805.903881922272;5211.71335576547;5451.618386712892;5451.964126815892;5923.89701655906;3283.095804433375;3879.3792993120965;3946.743461674982;3951.7277565399436;3959.7627340552494;4952.851414457572;5183.922241166461;5266.920996185137;5411.458854114557;5490.881838621034;5415.350157981067;5783.219265399117;5850.970745146247;5859.794907178399;5866.67733274674;5812.709364531672;5827.825165245049;5850.2102216200265;5859.648421094748;5893.5874488785375;5515.917261241005;5722.570807189394;5724.74238659253;5735.8852822944145;5775.826725198251;4621.2837010263465;5159.690418574897;5861.505316144356;5864.413558644272;5895.557833162444;4766.761661916821;5003.649744517995;5004.674696144739;5620.718964051863;5751.798687045934 09:52:01 INFO - PROCESS | 1644 | |5;yui.html;650.8210242183401;651.244556314675;751.9135299440562;776.8213310938487;802.2138304461628;12748.362581870893;13318.00114991377;13643.931780341098;14592.197429141394;14606.561803145909;14560;14828.777568336483;14891.329890154942;15017.098974061564;15299.923500382483;668.5520701130255;793.0523024985145;820.9302209312216;821.5440430561036;821.765796747927;791.6833266693311;799.6881216325638;800.5676934455387;813.056854049303;813.6826637611337;857.695518091078;862.3015357560365;862.7756783236372;863.8445079885649;865.7965378136164;850.4429598612918;852.3436953545767;853.2193043365313;855.9315254779602;857.1813917708573;625.0530446373748;631.3024108360241;633.3887798274687;633.6261605652641;636.2333388267127;14231.928840355731;14245.857541424557;14253.28733563328;14282.785758213635;14297.285135743276;10695.358278503314;11104.944475277653;12912.354382280848;13145.73708525824;13148.737025259441;11057.612983545618;11177.608783692613;11256.54973801047;11267.830982535268;11298.491567879466;2990.685977972325;3489.8255087245534;3604.0629436346476;3650.470681751158;3669.0643885809213;81708;85694.57152714259;85906.71139932905;86623.11447861647;87041;10188.745281367877;10285.588576456857;10326.586936522455;10336.276460647832;10367.740806479747;19748.605027899648;19798.60402791965;19834.305799296955;19934.900325498278;19979.800201997794;7324.780256592311;7481.625918704043;7515.436342274351;7651.311381975649;7737.458377913492;3430.9142271442915;3672.1186915140706;3750.3436898542645;4212.662986961036;4322.675799315001;2804.6634403871667;3526.100844284691;3527.012436517779;3546.060294022086;3553.5202747629255;615.9599626024294;633.2116514938931;633.3128555517316;647.2815175155573;647.8315637934077;539.7355295905057;548.7201527221146;603.4448307557058;653.2030922274818;666.6233578028398;8489.490630562186;8937.642494300286;9048.683296084606;9110.134537218953;9148.222401095982;547.6221407228999;627.2034516164446;638.4158494977044;658.367966751914;659.5086660438004;4330.675199359997;4553.795079221443;4674.602658773974;4743.217369134123;4817.301491283744;114308;120343;121842.39078804727;123274.38362808128;124136;100161.4975775364;106230;106646;107327.46336268426;108383.45808270908;85970.57014714886;88120.11879881247;90303;90654;92705.072949271;9602.231821454407;9621.615135394786;9701.369410988264;9745.171660408661;9757.365771224848;43948.780256098515;54788.452115478336;55253.447465526435;56163;56240.7187964074;70154.64922675354;77041.61479192568;79589.60205198936;80200.19799802161;81158.18841811742;12199.69500762488;12378.690532736753;12400.255984640951;12432.875671243171;12523.185992910796;11730.941345293219;13239.470421183045;14237.572872813833;14356.569302920929;15084.472043478425;11003;11007.61473348429;11111.553309189138;12070.430843990225;12796.104272701194;11388.658340249805;13383.19700817954;13391.129576577492;13441.529546465827;13894.096883702527;12709.872901270868;12925.030622703147;13054.412551435018;13563.118397304144;13601.183928964294;10580.735481612714;10887.945560272148;11567.826482602599;11675.241109327866;11731.120165987755;10622.309549879234;10661.09380702649;10674.83987740169;10679.466026698788;10760.300580462244 09:52:01 INFO - PROCESS | 1644 | __end_tp_report 09:52:01 INFO - PROCESS | 1644 | __start_cc_report 09:52:01 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,367 09:52:01 INFO - PROCESS | 1644 | __end_cc_report 09:52:01 INFO - PROCESS | 1644 | __startTimestamp1482515521101__endTimestamp 09:52:01 INFO - PROCESS | 1644 | 09:52:01 INFO - PROCESS | 1644 | ------- Summary: start ------- 09:52:01 INFO - PROCESS | 1644 | Number of tests: 6 09:52:01 INFO - PROCESS | 1644 | 09:52:01 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5829.22 Median:6055.91 stddev:668.26 (11.0%) stddev-sans-first:641.94 09:52:01 INFO - PROCESS | 1644 | Values: 3373.5 3373.7 3427.1 3429.3 3433.5 5751.5 5763.7 5814.5 5848.6 5848.7 5820.3 5862.2 5891.8 5897.3 5922.5 5279.9 5340.5 5369.3 5404.2 5406.4 5367.5 5396.3 5407.6 5409.1 5483.4 5251.6 5305.3 5352.9 5397.1 5404.8 5333.5 5349.6 5381.2 5415.9 5443.8 4418.0 5246.6 5374.1 5404.3 5412.1 5885.5 5945.6 5981.8 6007.1 6013.2 6142.2 6201.7 6228.4 6249.4 6297.4 5127.7 6238.2 6264.6 6290.6 6310.5 5965.2 5970.6 5988.9 6004.5 6017.8 6333.4 6342.1 6378.6 6418.0 6511.3 6192.8 6242.2 6246.4 6264.6 6289.5 6307.6 6315.6 6320.2 6328.7 6344.9 6153.9 6165.9 6238.2 6248.9 6273.2 6278.2 6280.8 6281.9 6282.3 6314.7 4994.2 5021.8 5027.1 5049.9 5056.1 4003.9 4436.0 4439.0 4502.1 4524.7 4583.8 5533.8 6013.1 6110.5 6120.1 5988.6 6274.6 6315.8 6316.3 6372.1 5889.2 5913.5 5950.7 6032.5 6058.9 4298.7 5430.1 5501.3 5529.4 5605.2 6494.9 6528.7 6530.2 6530.9 6554.0 6273.2 6325.0 6331.8 6342.1 6345.0 6216.5 6376.7 6415.1 6438.1 6473.8 6264.9 6274.2 6277.7 6278.9 6291.4 6210.1 6303.6 6309.4 6323.3 6347.5 5987.8 6091.5 6109.3 6116.2 6140.4 6135.1 6178.3 6195.9 6211.3 6234.5 5997.4 6055.9 6126.4 6145.4 6163.1 6044.3 6111.4 6136.5 6155.5 6175.2 5936.3 6011.3 6032.7 6040.3 6041.6 09:52:01 INFO - PROCESS | 1644 | 09:52:01 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:66785.34 Median:39696.70 stddev:146012.96 (367.8%) stddev-sans-first:146337.60 09:52:01 INFO - PROCESS | 1644 | Values: 441.9 495.8 509.9 520.3 520.7 29732.9 39047.4 40109.8 40162.0 42045.6 41227.8 41467.6 41723.0 41762.3 41795.0 9274.6 9403.0 9417.6 9459.8 9460.9 8181.5 9180.4 9382.8 9494.2 9504.6 8285.0 9336.4 9377.5 9427.3 9488.4 9408.7 9481.2 9493.4 9496.0 9498.4 9256.7 9286.4 9361.0 9364.9 9383.7 39368.4 39617.8 39657.2 39689.4 39704.0 2913.0 3258.3 3336.8 3418.5 3493.9 29036.1 29325.0 29773.9 29818.7 30237.8 3145.0 3149.8 3156.4 3175.4 3294.1 455631.0 457757.0 458626.0 459969.0 460283.0 43094.6 47942.3 52458.7 57890.4 57934.0 55941.3 59903.7 60136.0 60451.4 60523.0 14721.3 16107.8 16134.9 16139.5 16197.9 12509.3 12540.1 12605.0 12617.0 12633.2 12554.8 15878.3 15921.3 15967.8 16090.3 5217.4 5280.6 6050.5 6091.8 6299.6 807.6 826.5 835.3 975.8 1030.7 29267.9 29876.1 30015.5 30742.0 31114.9 957.7 1039.2 1044.8 1047.8 1122.8 13906.7 15071.2 15121.9 15126.0 15169.8 506713.0 633864.0 634006.0 634063.8 642389.0 53472.7 53723.5 53749.7 53819.0 54193.7 1185.1 1325.3 1363.2 1364.4 1376.9 19974.0 28317.5 28989.3 29023.3 29564.0 48939.5 49246.3 49353.0 49457.8 49623.8 556044.0 556336.0 556460.0 557578.0 557597.0 25384.2 25543.5 25910.1 25934.0 25992.5 43487.8 44353.0 44504.3 44511.8 44636.3 36627.9 37029.0 37165.8 37205.0 38441.2 27217.5 36634.8 37889.6 37935.6 38341.7 30337.8 35914.6 36201.5 36802.0 37805.4 31536.7 33415.5 33467.0 33481.0 33551.8 24396.0 25159.7 25200.6 25402.0 26795.9 09:52:01 INFO - PROCESS | 1644 | 09:52:01 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:32039.81 Median:223481.39 stddev:85501.39 (38.3%) stddev-sans-first:85708.12 09:52:01 INFO - PROCESS | 1644 | Values: 340.2 371.0 380.7 387.6 398.1 18786.7 19509.8 19642.9 19689.0 19880.0 11417.4 11685.2 11805.6 11815.9 11822.2 5029.7 5321.9 5373.9 5420.9 5431.5 3874.5 4481.9 5409.0 5454.5 5465.3 5117.7 6094.7 6180.0 6197.1 6200.1 6173.8 6210.3 6242.2 6563.1 6591.4 5861.4 5890.6 5894.3 5909.1 5919.9 22607.2 23070.0 24181.9 24390.9 24524.0 2146.2 2171.7 2219.0 2246.6 2255.9 9731.5 9801.2 9829.3 10141.7 10326.8 1838.5 1899.5 1901.9 1908.1 1917.7 116469.0 130242.3 133164.0 136781.0 138979.3 11549.4 11550.4 11569.5 11569.9 11615.4 11807.0 14580.0 14748.4 14752.9 14755.8 8248.7 8488.9 8489.4 8578.9 8655.7 6896.8 8330.5 8362.9 8394.0 8561.4 3740.0 4581.9 4608.5 4626.8 4696.9 1462.0 1898.4 1960.5 1961.8 1965.0 725.3 758.9 887.0 922.7 970.7 19809.8 19876.7 20134.0 20322.8 21023.5 899.1 904.9 938.2 939.6 969.2 5854.9 5980.9 6149.7 6166.8 10620.5 446525.0 447271.0 447543.0 530266.0 611176.0 128235.4 132997.3 137213.0 138518.0 138718.3 437.8 451.3 452.8 457.0 458.4 11401.5 11569.6 11578.0 11623.1 11824.6 54072.5 54776.5 55142.0 56433.7 57386.7 64191.7 79383.0 79521.0 79636.0 82594.2 6591.9 8024.2 8353.3 8407.3 8492.5 9239.4 11814.8 12402.3 12769.0 12801.5 10830.8 10894.1 11030.4 11061.7 11140.9 10844.5 10910.9 10997.4 11094.8 11198.3 8882.0 9058.4 9568.2 11382.3 11553.0 7976.5 8127.9 8144.1 8467.2 8477.4 8198.6 10471.6 10906.9 11042.4 11305.5 09:52:01 INFO - PROCESS | 1644 | 09:52:01 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5425.69 Median:4871.37 stddev:3012.10 (61.8%) stddev-sans-first:3005.61 09:52:01 INFO - PROCESS | 1644 | Values: 1434.5 1439.2 1479.0 1738.2 1746.6 6284.2 7524.2 7798.7 8042.0 8530.0 8072.7 8352.8 8386.2 8473.0 8535.4 2319.6 2363.9 2365.4 2367.8 2375.7 2347.2 2379.1 2389.2 2399.6 2403.4 2336.7 2337.4 2354.0 2363.9 2365.7 2315.4 2315.7 2334.8 2342.1 2350.8 2331.6 2349.0 2363.5 2364.8 2364.9 8413.3 8479.1 8527.0 8538.0 8551.4 4241.5 5286.5 5293.4 5296.6 5306.9 3484.7 3514.6 3566.1 3584.0 3615.2 1053.0 1063.1 1093.8 1108.9 1110.8 10950.6 11068.6 11076.4 11078.2 11120.1 3950.5 4497.9 4656.7 4685.3 4789.3 8745.5 8906.4 9522.5 9608.1 9634.4 4440.9 5262.2 5265.5 5270.0 5274.8 3855.6 3966.9 4472.5 4742.3 4878.9 2978.4 3862.3 3882.2 3952.8 3969.8 1256.2 1274.6 1274.8 1285.5 1288.5 787.1 866.4 884.6 886.7 888.9 5404.4 5592.9 5742.9 5784.0 5867.5 931.3 965.0 1000.9 1005.1 1024.7 3645.3 3759.9 3765.4 3772.8 3806.5 10022.4 10326.5 10395.3 10430.0 10430.4 10243.6 10352.9 10353.4 10362.9 10482.3 7695.4 7877.8 8071.7 8072.6 8076.8 3908.9 5397.8 5430.3 5559.6 5584.9 9365.7 9385.2 9409.8 9483.2 9511.0 9595.1 9596.1 9603.0 9678.3 9754.5 6300.0 6494.7 6636.5 6726.6 6869.5 6307.4 6682.9 8065.7 8118.2 8127.1 7043.2 7199.3 7208.2 7232.7 7293.7 7023.3 7041.5 7059.4 7077.3 7146.1 5818.4 6558.9 6664.5 6754.4 6778.8 3945.2 4163.8 4755.6 4767.9 4875.3 4814.3 4867.5 4881.5 4934.0 4939.0 09:52:01 INFO - PROCESS | 1644 | 09:52:01 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4486.52 Median:4932.74 stddev:1353.10 (27.4%) stddev-sans-first:1319.43 09:52:01 INFO - PROCESS | 1644 | Values: 273.2 295.1 310.4 313.0 313.4 4059.5 4244.7 5534.9 5548.0 5617.0 5035.4 5369.8 5387.0 5458.6 5468.3 3759.0 3967.0 4156.9 4199.6 4231.9 4102.5 4238.6 4238.9 4249.3 4267.6 3832.8 4172.8 4188.6 4202.6 4208.1 3666.9 4026.7 4160.2 4179.0 4183.2 3119.4 3498.5 3606.3 3638.9 3686.1 4810.8 4952.5 5033.6 5344.6 5442.8 4015.9 4067.8 4760.6 4851.2 4871.8 4994.0 5536.0 5759.9 5809.9 5899.8 3481.9 3688.4 3908.8 3996.3 4069.0 4783.9 5087.8 5227.9 6089.5 6098.9 3439.8 3456.2 3774.0 3894.9 4121.6 3939.5 4054.0 4106.2 4108.6 4138.9 3979.4 4739.9 4774.6 4785.8 4842.9 4955.9 5346.0 5685.7 5826.4 5894.0 1172.9 1229.4 1318.9 1326.9 1353.1 769.8 891.0 930.7 930.8 935.0 4492.7 4588.8 4978.3 5206.3 5227.7 4967.8 5143.4 5672.6 5679.0 5707.7 4002.9 4503.6 4639.7 5027.9 5196.5 2920.7 2969.1 2984.9 2995.8 3001.5 4731.0 4913.0 4982.8 5015.9 5799.4 4504.6 4834.6 5190.8 5234.9 5474.4 4887.7 5173.6 5579.9 5749.3 5817.6 4907.2 4989.9 5243.7 5656.5 5709.2 4656.7 4699.9 4777.4 5155.0 5448.4 4805.9 5211.7 5451.6 5452.0 5923.9 3283.1 3879.4 3946.7 3951.7 3959.8 4952.9 5183.9 5266.9 5411.5 5490.9 5415.4 5783.2 5851.0 5859.8 5866.7 5812.7 5827.8 5850.2 5859.6 5893.6 5515.9 5722.6 5724.7 5735.9 5775.8 4621.3 5159.7 5861.5 5864.4 5895.6 4766.8 5003.6 5004.7 5620.7 5751.8 09:52:01 INFO - PROCESS | 1644 | 09:52:01 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:21193.04 Median:3670.59 stddev:31974.27 (871.1%) stddev-sans-first:32026.76 09:52:01 INFO - PROCESS | 1644 | Values: 650.8 651.2 751.9 776.8 802.2 12748.4 13318.0 13643.9 14592.2 14606.6 14560.0 14828.8 14891.3 15017.1 15299.9 668.6 793.1 820.9 821.5 821.8 791.7 799.7 800.6 813.1 813.7 857.7 862.3 862.8 863.8 865.8 850.4 852.3 853.2 855.9 857.2 625.1 631.3 633.4 633.6 636.2 14231.9 14245.9 14253.3 14282.8 14297.3 10695.4 11104.9 12912.4 13145.7 13148.7 11057.6 11177.6 11256.5 11267.8 11298.5 2990.7 3489.8 3604.1 3650.5 3669.1 81708.0 85694.6 85906.7 86623.1 87041.0 10188.7 10285.6 10326.6 10336.3 10367.7 19748.6 19798.6 19834.3 19934.9 19979.8 7324.8 7481.6 7515.4 7651.3 7737.5 3430.9 3672.1 3750.3 4212.7 4322.7 2804.7 3526.1 3527.0 3546.1 3553.5 616.0 633.2 633.3 647.3 647.8 539.7 548.7 603.4 653.2 666.6 8489.5 8937.6 9048.7 9110.1 9148.2 547.6 627.2 638.4 658.4 659.5 4330.7 4553.8 4674.6 4743.2 4817.3 114308.0 120343.0 121842.4 123274.4 124136.0 100161.5 106230.0 106646.0 107327.5 108383.5 85970.6 88120.1 90303.0 90654.0 92705.1 9602.2 9621.6 9701.4 9745.2 9757.4 43948.8 54788.5 55253.4 56163.0 56240.7 70154.6 77041.6 79589.6 80200.2 81158.2 12199.7 12378.7 12400.3 12432.9 12523.2 11730.9 13239.5 14237.6 14356.6 15084.5 11003.0 11007.6 11111.6 12070.4 12796.1 11388.7 13383.2 13391.1 13441.5 13894.1 12709.9 12925.0 13054.4 13563.1 13601.2 10580.7 10887.9 11567.8 11675.2 11731.1 10622.3 10661.1 10674.8 10679.5 10760.3 09:52:01 INFO - PROCESS | 1644 | -------- Summary: end -------- 09:52:01 INFO - PROCESS | 1644 | 09:52:01 INFO - TEST-INFO | 1644: exit 0 09:52:01 INFO - TEST-OK | dromaeo_css | took 1117662ms 09:52:01 INFO - TEST-START | kraken 09:52:01 INFO - operating with platform_type : mac_ 09:52:01 INFO - Initialising browser for kraken test... 09:52:01 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpF1fj7d/profile http://localhost:49240/getInfo.html) 09:52:04 INFO - PROCESS | 1651 | 2016-12-23 09:52:04.829 plugin-container[1652:10577] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473f, name = 'com.apple.tsm.portname' 09:52:04 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:52:04 INFO - PROCESS | 1651 | 2016-12-23 09:52:04.832 plugin-container[1652:10577] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 09:52:04 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:52:04 INFO - PROCESS | 1651 | 2016-12-23 09:52:04.832 plugin-container[1652:10577] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:52:04 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 09:52:04 INFO - PROCESS | 1651 | colorDepth:24 09:52:04 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 09:52:04 INFO - PROCESS | 1651 | __metrics 09:52:04 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 09:52:05 INFO - TEST-INFO | 1651: exit 0 09:52:05 INFO - Browser initialized. 09:52:05 INFO - Running cycle 1/1 for kraken test... 09:52:05 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpF1fj7d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:52:08 INFO - PROCESS | 1653 | 2016-12-23 09:52:08.705 plugin-container[1654:10701] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 09:52:08 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:52:08 INFO - PROCESS | 1653 | 2016-12-23 09:52:08.705 plugin-container[1654:10701] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843b, name = 'com.apple.CFPasteboardClient' 09:52:08 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:52:08 INFO - PROCESS | 1653 | 2016-12-23 09:52:08.705 plugin-container[1654:10701] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:52:18 INFO - PROCESS | 1653 | RSS: Main: 229462016 09:52:18 INFO - PROCESS | 1653 | 09:52:21 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 09:52:22 INFO - PROCESS | 1653 | RSS: Main: 241508352 09:52:22 INFO - PROCESS | 1653 | 09:52:24 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 09:52:25 INFO - PROCESS | 1653 | RSS: Main: 249147392 09:52:25 INFO - PROCESS | 1653 | 09:52:28 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 09:52:29 INFO - PROCESS | 1653 | RSS: Main: 252985344 09:52:29 INFO - PROCESS | 1653 | 09:52:31 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 09:52:31 INFO - PROCESS | 1653 | RSS: Main: 254242816 09:52:31 INFO - PROCESS | 1653 | 09:52:33 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:52:34 INFO - PROCESS | 1653 | RSS: Main: 254164992 09:52:34 INFO - PROCESS | 1653 | 09:52:37 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 09:52:38 INFO - PROCESS | 1653 | RSS: Main: 258617344 09:52:38 INFO - PROCESS | 1653 | 09:52:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 09:52:42 INFO - PROCESS | 1653 | RSS: Main: 258347008 09:52:42 INFO - PROCESS | 1653 | 09:52:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 09:52:45 INFO - PROCESS | 1653 | RSS: Main: 258428928 09:52:45 INFO - PROCESS | 1653 | 09:52:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:52:47 INFO - PROCESS | 1653 | RSS: Main: 257650688 09:52:47 INFO - PROCESS | 1653 | 09:52:49 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:52:50 INFO - PROCESS | 1653 | RSS: Main: 257708032 09:52:50 INFO - PROCESS | 1653 | 09:52:52 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:52:53 INFO - PROCESS | 1653 | RSS: Main: 258166784 09:52:53 INFO - PROCESS | 1653 | 09:52:56 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:52:57 INFO - PROCESS | 1653 | RSS: Main: 258154496 09:52:57 INFO - PROCESS | 1653 | 09:53:00 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:53:00 INFO - PROCESS | 1653 | RSS: Main: 258252800 09:53:00 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 09:53:02 INFO - PROCESS | 1653 | RSS: Main: 259715072 09:53:02 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | __start_tp_report 09:53:02 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 09:53:02 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 09:53:02 INFO - PROCESS | 1653 | |i|pagename|runs| 09:53:02 INFO - PROCESS | 1653 | |0;ai-astar;106;118;120;118;117;118;116;117;117;118 09:53:02 INFO - PROCESS | 1653 | |1;audio-beat-detection;142;108;113;143;113;119;112;114;143;111 09:53:02 INFO - PROCESS | 1653 | |2;audio-dft;193;203;198;192;196;192;194;226;263;194 09:53:02 INFO - PROCESS | 1653 | |3;audio-fft;76;58;59;85;61;62;50;69;97;56 09:53:02 INFO - PROCESS | 1653 | |4;audio-oscillator;94;58;101;104;102;103;93;62;67;81 09:53:02 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;83;76;106;106;110;132;83;74;97;95 09:53:02 INFO - PROCESS | 1653 | |6;imaging-darkroom;140;120;152;155;108;96;97;132;108;153 09:53:02 INFO - PROCESS | 1653 | |7;imaging-desaturate;98;93;90;89;96;91;134;144;91;93 09:53:02 INFO - PROCESS | 1653 | |8;json-parse-financial;58;57;57;60;61;60;60;61;61;60 09:53:02 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;68;75;68;77;80;75;69;71;70;69 09:53:02 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;86;87;81;83;86;86;87;96;104;83 09:53:02 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;148;154;177;154;163;162;140;157;214;161 09:53:02 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;170;169;166;180;238;173;166;170;166;197 09:53:02 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;69;69;66;67;71;68;68;68;67;68 09:53:02 INFO - PROCESS | 1653 | __end_tp_report 09:53:02 INFO - PROCESS | 1653 | __start_cc_report 09:53:02 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,991 09:53:02 INFO - PROCESS | 1653 | __end_cc_report 09:53:02 INFO - PROCESS | 1653 | __startTimestamp1482515582656__endTimestamp 09:53:02 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | ------- Summary: start ------- 09:53:02 INFO - PROCESS | 1653 | Number of tests: 14 09:53:02 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:116.50 Median:118.00 stddev:3.84 (3.3%) stddev-sans-first:1.12 09:53:02 INFO - PROCESS | 1653 | Values: 106.0 118.0 120.0 118.0 117.0 118.0 116.0 117.0 117.0 118.0 09:53:02 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:116.50 stddev:14.66 (12.6%) stddev-sans-first:13.60 09:53:02 INFO - PROCESS | 1653 | Values: 142.0 108.0 113.0 143.0 113.0 119.0 112.0 114.0 143.0 111.0 09:53:02 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:205.10 Median:197.00 stddev:22.76 (11.6%) stddev-sans-first:23.72 09:53:02 INFO - PROCESS | 1653 | Values: 193.0 203.0 198.0 192.0 196.0 192.0 194.0 226.0 263.0 194.0 09:53:02 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:67.30 Median:65.50 stddev:14.62 (22.3%) stddev-sans-first:15.17 09:53:02 INFO - PROCESS | 1653 | Values: 76.0 58.0 59.0 85.0 61.0 62.0 50.0 69.0 97.0 56.0 09:53:02 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:86.50 Median:64.50 stddev:18.11 (28.1%) stddev-sans-first:19.00 09:53:02 INFO - PROCESS | 1653 | Values: 94.0 58.0 101.0 104.0 102.0 103.0 93.0 62.0 67.0 81.0 09:53:02 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:96.20 Median:79.50 stddev:18.00 (22.6%) stddev-sans-first:18.45 09:53:02 INFO - PROCESS | 1653 | Values: 83.0 76.0 106.0 106.0 110.0 132.0 83.0 74.0 97.0 95.0 09:53:02 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:126.10 Median:152.50 stddev:23.34 (15.3%) stddev-sans-first:24.21 09:53:02 INFO - PROCESS | 1653 | Values: 140.0 120.0 152.0 155.0 108.0 96.0 97.0 132.0 108.0 153.0 09:53:02 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:101.90 Median:92.00 stddev:19.88 (21.6%) stddev-sans-first:21.04 09:53:02 INFO - PROCESS | 1653 | Values: 98.0 93.0 90.0 89.0 96.0 91.0 134.0 144.0 91.0 93.0 09:53:02 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:59.50 Median:60.00 stddev:1.58 (2.6%) stddev-sans-first:1.58 09:53:02 INFO - PROCESS | 1653 | Values: 58.0 57.0 57.0 60.0 61.0 60.0 60.0 61.0 61.0 60.0 09:53:02 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:73.00 stddev:4.24 (5.8%) stddev-sans-first:4.21 09:53:02 INFO - PROCESS | 1653 | Values: 68.0 75.0 68.0 77.0 80.0 75.0 69.0 71.0 70.0 69.0 09:53:02 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:86.00 stddev:6.94 (8.1%) stddev-sans-first:7.32 09:53:02 INFO - PROCESS | 1653 | Values: 86.0 87.0 81.0 83.0 86.0 86.0 87.0 96.0 104.0 83.0 09:53:02 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:163.00 Median:161.50 stddev:20.42 (12.6%) stddev-sans-first:20.93 09:53:02 INFO - PROCESS | 1653 | Values: 148.0 154.0 177.0 154.0 163.0 162.0 140.0 157.0 214.0 161.0 09:53:02 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.50 Median:171.50 stddev:22.63 (13.2%) stddev-sans-first:23.74 09:53:02 INFO - PROCESS | 1653 | Values: 170.0 169.0 166.0 180.0 238.0 173.0 166.0 170.0 166.0 197.0 09:53:02 INFO - PROCESS | 1653 | 09:53:02 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:1.37 (2.0%) stddev-sans-first:1.41 09:53:02 INFO - PROCESS | 1653 | Values: 69.0 69.0 66.0 67.0 71.0 68.0 68.0 68.0 67.0 68.0 09:53:02 INFO - PROCESS | 1653 | -------- Summary: end -------- 09:53:02 INFO - PROCESS | 1653 | 09:53:03 INFO - TEST-INFO | 1653: exit 0 09:53:03 INFO - TEST-OK | kraken | took 62187ms 09:53:03 INFO - SUITE-END | took 1179s 09:53:04 INFO - Completed test suite (00:19:40) 09:53:04 INFO - javascript benchmark 09:53:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7075.100986436442, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3373.460246360583, 3373.7469689773266, 3427.126082848873, 3429.2969941162055, 3433.467812489065, 5751.482366587012, 5763.711814409278, 5814.534837213024, 5848.561357898164, 5848.707564621774, 5820.272465941757, 5862.179294898707, 5891.823245302648, 5897.292324921004, 5922.526197904168, 5279.86800329991, 5340.519353258206, 5369.275147855029, 5404.189371594272, 5406.432324605918, 5367.490088441592, 5396.325459317585, 5407.621466497347, 5409.134538473845, 5483.424240454755, 5251.579873610102, 5305.283786688806, 5352.946470535284, 5397.082495975674, 5404.810831620894, 5333.519983201511, 5349.625526213166, 5381.219723140122, 5415.9458405415835, 5443.755031023574, 4417.955820441786, 5246.606504512177, 5374.086405311067, 5404.270423492818, 5412.07994640912, 5885.529157667376, 5945.583809133363, 5981.760729570812, 6007.068904319837, 6013.248343957005, 6142.222533142085, 6201.6899155042065, 6228.37716228378, 6249.437550620421, 6297.370262973712, 5127.692338459667, 6238.189035425411, 6264.561480696353, 6290.622562646256, 6310.526710496685, 5965.22452081231, 5970.611910225822, 5988.850278743066, 6004.459598636144, 6017.759289628453, 6333.398327158912, 6342.112104305401, 6378.649174295459, 6417.9679101605125, 6511.28375878653, 6192.783252586138, 6242.219722534684, 6246.437820596168, 6264.624122552661, 6289.528285378615, 6307.590006649553, 6315.621062736251, 6320.209973753281, 6328.715207815567, 6344.904826427607, 6153.923063463876, 6165.907511387333, 6238.189035425366, 6248.937510624926, 6273.153124328157, 6278.215223097113, 6280.842978925472, 6281.905771413432, 6282.340354262737, 6314.652694101868, 4994.1759609663995, 5021.774020169101, 5027.069992051409, 5049.949500505021, 5056.115179843513, 4003.899902502461, 4435.955640443619, 4439.023414848728, 4502.144592527409, 4524.705894116872, 4583.770811459413, 5533.750981205855, 6013.067974463965, 6110.511159107261, 6120.051392034242, 5988.6406815590335, 6274.560780745304, 6315.842103947346, 6316.273628532777, 6372.107904893319, 5889.234399528034, 5913.46778789911, 5950.732217050243, 6032.517398608101, 6058.90911636317, 4298.699091063596, 5430.104032834617, 5501.257330260363, 5529.391766905637, 5605.187247849039, 6494.935050649433, 6528.6735663215695, 6530.1510803596175, 6530.869382612416, 6553.967230163818, 6273.215848018997, 6324.968375158279, 6331.841703957438, 6342.112104305494, 6344.953082741481, 6216.471599914058, 6376.713047912763, 6415.101885736094, 6438.066480360415, 6473.77341793035, 6264.874702506016, 6274.1843560336865, 6277.71750271248, 6278.874422511615, 6291.402316779899, 6210.068489726577, 6303.590266632654, 6309.432151106423, 6323.304436511888, 6347.492200624031, 5987.760489580368, 6091.482224011009, 6109.283207920342, 6116.235470566179, 6140.4428991762525, 6135.110408990582, 6178.258608966953, 6195.876082478415, 6211.3167551570195, 6234.501239900706, 5997.370276120944, 6055.9091613626715, 6126.356732543019, 6145.446909778141, 6163.106349579196, 6044.274687037584, 6111.40942266768, 6136.478399335928, 6155.47678447337, 6175.171393735913, 5936.28764548257, 6011.308699499614, 6032.6983650818165, 6040.335563087969, 6041.577089603861], "value": 5786.72776697921, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [441.86964845370625, 495.7769003948224, 509.8980203959208, 520.2638266852404, 520.6875874475315, 29732.851335743344, 39047.41428878566, 40109.79945100274, 40162.0, 42045.57954420462, 41227.76316710496, 41467.58532414675, 41723.0, 41762.32950681977, 41795.0, 9274.582643781012, 9402.952985235099, 9417.62329506819, 9459.763505912373, 9460.858087128665, 8181.509109453422, 9180.44917304964, 9382.812343753121, 9494.240460763122, 9504.619815207383, 8285.0, 9336.439813611205, 9377.484238366887, 9427.34008619397, 9488.383255088396, 9408.717738467856, 9481.24150067993, 9493.430394176303, 9495.952520237424, 9498.430094194302, 9256.72229833106, 9286.442813431217, 9360.953195234048, 9364.90635093654, 9383.671571494964, 39368.409473857915, 39617.80191099026, 39657.20685586301, 39689.404658930136, 39704.0, 2912.970870291312, 3258.3157536917274, 3336.799792012487, 3418.453047512386, 3493.8602455901987, 29036.12891613255, 29325.0, 29773.851130744424, 29818.701812982028, 30237.848810756026, 3145.0, 3149.7637677174303, 3156.352947645727, 3175.380800743856, 3294.1105901406727, 455631.0, 457757.0, 458626.0, 459969.0, 460283.0, 43094.569054309686, 47942.28086578704, 52458.73770631122, 57890.421095789345, 57934.0, 55941.32176034725, 59903.700481498185, 60136.0, 60451.395486044574, 60523.0, 14721.337539810735, 16107.838921610868, 16134.870559060753, 16139.515814525583, 16197.919010404872, 12509.311987840756, 12540.122191446511, 12604.991600671925, 12617.0, 12633.178843375152, 12554.811677824657, 15878.285477153557, 15921.283542240628, 15967.760483592756, 16090.275937582837, 5217.399999000164, 5280.577553795687, 6050.45545900871, 6091.7563297467605, 6299.622022678654, 807.5518087461516, 826.4999675196419, 835.3108685334623, 975.7703647166333, 1030.7474668706216, 29267.853660731987, 29876.103716888527, 30015.549766753516, 30742.014152640502, 31114.91097811611, 957.7405711489508, 1039.2309690828731, 1044.8171569975225, 1047.8323468245044, 1122.8484154639182, 13906.721865562633, 15071.171085590398, 15121.941464097408, 15126.016808907385, 15169.848301516844, 506713.0, 633864.0, 634006.0, 634063.8296808578, 642389.0, 53472.73263633657, 53723.46276537185, 53749.656258593066, 53819.0, 54193.72903135538, 1185.057878986208, 1325.3108383640454, 1363.16165558181, 1364.4064831798, 1376.8554301798167, 19974.0, 28317.506574383413, 28989.275268118465, 29023.274418139714, 29563.965261215755, 48939.51060489492, 49246.26130608116, 49353.012939740285, 49457.752711236215, 49623.751881240365, 556044.0, 556336.0, 556460.0, 557578.0, 557597.0, 25384.238472845846, 25543.489130217662, 25910.093146739775, 25933.96264149413, 25992.480150397263, 43487.78256108699, 44353.0, 44504.33243501415, 44511.77744111259, 44636.33045504385, 36627.90116296515, 37029.0, 37165.81417092897, 37205.0, 38441.231175376895, 27217.455650887267, 36634.8168259157, 37889.62110378861, 37935.62064379321, 38341.658041968396, 30337.848310758305, 35914.64085359217, 36201.45697814482, 36802.0, 37805.43291850569, 31536.684633153374, 33415.49876751899, 33467.0, 33481.0, 33551.83224083864, 24396.0, 25159.748402516474, 25200.557813132807, 25402.0, 26795.928162873268], "value": 18978.46905482079, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.1920438957476, 370.9888703338899, 380.70875780028274, 387.5794762682489, 398.0983073338888, 18786.718199227005, 19509.804901950974, 19642.90178549109, 19689.0, 19880.0, 11417.429128543561, 11685.240459370136, 11805.645830625073, 11815.881841181586, 11822.231554948923, 5029.673071250366, 5321.893562128775, 5373.865653358658, 5420.91868621971, 5431.456854314576, 3874.4575759393706, 4481.865544033668, 5409.0, 5454.481824226692, 5465.262189604412, 5117.692938423688, 6094.725737341808, 6180.0, 6197.101420294055, 6200.069989501589, 6173.783917562886, 6210.347913469089, 6242.18851549298, 6563.146790917151, 6591.373819487141, 5861.384554621746, 5890.646561206298, 5894.263217097863, 5909.054551271776, 5919.911201331984, 22607.208747693752, 23069.961851716715, 24181.87909060461, 24390.878045609657, 24524.0, 2146.216630929715, 2171.695962565226, 2219.0, 2246.61807492725, 2255.9121915783903, 9731.464769437678, 9801.16690081344, 9829.311948163631, 10141.746456338651, 10326.793464130675, 1838.5403649087727, 1899.5061284066105, 1901.9073840807414, 1908.1031914999928, 1917.6835822089338, 116469.0, 130242.34878825545, 133164.0, 136781.0, 138979.30510347383, 11549.36478493691, 11550.364729939934, 11569.479373428216, 11569.88430115705, 11615.36115513638, 11807.0, 14580.0, 14748.410063597335, 14752.852471475362, 14755.77866332006, 8248.711295104642, 8488.915110848937, 8489.4481858679, 8578.914210857936, 8655.6970506032, 6896.793096207121, 8330.50016998982, 8362.916370836336, 8393.95803020981, 8561.357898157663, 3740.0, 4581.908361832811, 4608.539146085364, 4626.8380606679275, 4696.8590942271785, 1462.0350568624656, 1898.4209816005975, 1960.4804726747423, 1961.8430525557922, 1964.9705254421194, 725.2783480436999, 758.893754874318, 886.9822603548022, 922.7462447826902, 970.69908328417, 19809.801901981085, 19876.70184947227, 20134.0, 20322.796772032387, 21023.474413139793, 899.1458114790844, 904.9185573298435, 938.2353381993653, 939.6241503398695, 969.1665167955553, 5854.882902342015, 5980.910286345708, 6149.66176860268, 6166.81499555014, 10620.468976551141, 446525.0, 447271.0, 447543.0, 530266.0, 611176.0, 128235.35882320529, 132997.3350133243, 137213.0, 138518.0, 138718.30640846933, 437.77017066039946, 451.34780242549203, 452.8120829855639, 456.97943592538417, 458.42467703031815, 11401.486933088197, 11569.595064172714, 11578.0, 11623.07015438746, 11824.586139485076, 54072.45927540674, 54776.45223547713, 55142.0, 56433.71783141058, 57386.713066436074, 64191.67904160449, 79383.0, 79521.0, 79636.0, 82594.17405825865, 6591.868162636816, 8024.197580241929, 8353.33173346143, 8407.327413807003, 8492.532910689972, 9239.445633262027, 11814.82277765817, 12402.317872517098, 12769.0, 12801.487940482277, 10830.783384332111, 10894.074003709773, 11030.407854154108, 11061.668149955514, 11140.944295278472, 10844.45777711127, 10910.890891091305, 10997.395143267197, 11094.810923328478, 11198.272112312674, 8881.978572463991, 9058.36591438593, 9568.234541236545, 11382.260153090023, 11553.0, 7976.521408715495, 8127.918720812716, 8144.0634327050775, 8467.195616416431, 8477.364197685074, 8198.631061602362, 10471.581136754749, 10906.890931090587, 11042.447877606248, 11305.491252893804], "value": 8800.498564036623, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1434.4549071352885, 1439.1940513312545, 1478.9778153327698, 1738.243863919196, 1746.6332070265244, 6284.245890493142, 7524.172341042481, 7798.727044553441, 8041.9947506561675, 8530.0, 8072.71745488908, 8352.832943341129, 8386.245237928586, 8473.025602055766, 8535.359848011409, 2319.605667036608, 2363.9409014774596, 2365.420471984358, 2367.810575153992, 2375.655529948165, 2347.225415612852, 2379.0602711928777, 2389.2115601851347, 2399.6040653292184, 2403.387136280246, 2336.7312759032675, 2337.4039619896903, 2353.9646905296436, 2363.929082127539, 2365.7042869641296, 2315.3979965209, 2315.6758053872472, 2334.7665233476687, 2342.1099982006813, 2350.847194932324, 2331.63859601762, 2349.048635302705, 2363.5036642305136, 2364.810815134785, 2364.9408764780846, 8413.28487078599, 8479.109693482218, 8526.957365213135, 8537.957310213471, 8551.358648101355, 4241.533431322552, 5286.471352864722, 5293.391260005072, 5296.5762738981175, 5306.946930530722, 3484.6515348465205, 3514.578250609943, 3566.0906468850276, 3584.0, 3615.2407994321225, 1052.973675658107, 1063.0857462582173, 1093.792179485903, 1108.894655007781, 1110.8333749937494, 10950.616728414547, 11068.612598559092, 11076.446177691163, 11078.169137314733, 11120.110391168688, 3950.506186726659, 4497.887552811206, 4656.743879086615, 4685.250359942393, 4789.30555069513, 8745.518996455128, 8906.376553641183, 9522.52387380642, 9608.087231712976, 9634.373765705206, 4440.866773996785, 5262.210668399694, 5265.473452654704, 5270.0, 5274.815381461707, 3855.575886652466, 3966.8809935701975, 4472.463304403427, 4742.264948932921, 4878.8780280492565, 2978.359652674642, 3862.3240932836643, 3882.2235552889533, 3952.7628342299554, 3969.801509924492, 1256.2462522486435, 1274.6430999320205, 1274.8215249865027, 1285.517930775959, 1288.5425673885636, 787.1420152034195, 866.3848667446058, 884.6151923913119, 886.7118186589385, 888.9155530224748, 5404.432534583812, 5592.888142237214, 5742.942570574241, 5783.971080144656, 5867.530597552186, 931.2736065868634, 964.9903500965041, 1000.8748906386702, 1005.0803514784037, 1024.7233247023262, 3645.2527231917393, 3759.882285457754, 3765.4351847223134, 3772.830222639988, 3806.4861243732294, 10022.448765317831, 10326.535305911251, 10395.272330936761, 10430.0, 10430.37417754937, 10243.641472548425, 10352.948235258926, 10353.43056131905, 10362.896371036193, 10482.318649287772, 7695.422843286776, 7877.842443151219, 8071.717489887826, 8072.636731347104, 8076.798080048045, 3908.941365879571, 5397.78408863641, 5430.348358196884, 5559.63862348946, 5584.888302234014, 9365.719028429157, 9385.15533601979, 9409.764755881159, 9483.193928515877, 9511.001344858967, 9595.13643772064, 9596.088371604686, 9602.95198524003, 9678.322517423994, 9754.463504507037, 6300.0, 6494.6752662367635, 6636.535442518977, 6726.630035348103, 6869.484788640972, 6307.369263073656, 6682.9331706682315, 8065.677372905018, 8118.228768267005, 8127.1466496017065, 7043.190033146254, 7199.316064973819, 7208.243134470805, 7232.674529646073, 7293.744718935024, 7023.29767023314, 7041.471889608402, 7059.364657180879, 7077.327653872874, 7146.142462904485, 5818.359980402237, 6558.901616475661, 6664.5334826561675, 6754.425873800782, 6778.796636100925, 3945.2109578083923, 4163.834126444601, 4755.643326750576, 4767.880802979953, 4875.268709693432, 4814.277858321279, 4867.464578896389, 4881.51184881509, 4933.963867587847, 4938.975305123451], "value": 4408.336845639942, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [273.17501146537455, 295.1087715100397, 310.4412058295068, 313.0498935730058, 313.4264296337702, 4059.533153687327, 4244.681648876332, 5534.916976245355, 5547.977716155785, 5617.034038086667, 5035.370578677665, 5369.812056578021, 5387.0, 5458.645188062773, 5468.343798744147, 3758.9624103759024, 3966.988417953418, 4156.854510092147, 4199.55904630014, 4231.937340153457, 4102.487189101347, 4238.618524332809, 4238.9152216955645, 4249.3201087825955, 4267.615914567688, 3832.8466861325514, 4172.77049762263, 4188.623023927846, 4202.642775364083, 4208.13733184698, 3666.88160111166, 4026.724900431247, 4160.188763191179, 4178.958210417918, 4183.218159923662, 3119.391718614871, 3498.4752287156875, 3606.2607165531003, 3638.853761065256, 3686.078480379905, 4810.759462026885, 4952.514245726267, 5033.597312215014, 5344.625876188668, 5442.782288708447, 4015.8554811878766, 4067.776272305022, 4760.643216683263, 4851.162070188943, 4871.82948596801, 4994.0, 5535.972320138373, 5759.942400576025, 5809.941900581025, 5899.793507227269, 3481.8781342652887, 3688.3729765939856, 3908.7654740715366, 3996.320625493644, 4068.9796551017353, 4783.856484305442, 5087.771050302746, 5227.895442091175, 6089.451949324582, 6098.908516372258, 3439.793612383265, 3456.2396272820442, 3774.0, 3894.8636797711943, 4121.608447197571, 3939.546952100488, 4054.0, 4106.178764247163, 4108.568600296986, 4138.896527586774, 3979.422983667352, 4739.881502962385, 4774.591495508833, 4785.784639691222, 4842.903141937141, 4955.851324460272, 5345.9842629900195, 5685.687287199161, 5826.359100498942, 5893.970530147321, 1172.8944395004492, 1229.3730197599289, 1318.8878945289566, 1326.8540460549332, 1353.066384194922, 769.7690692792252, 891.028778631295, 930.7440453875239, 930.8464103422859, 935.0, 4492.663050271242, 4588.787848506059, 4978.303037574742, 5206.349206349207, 5227.666944929095, 4967.82612608557, 5143.434222235551, 5672.5745569081655, 5679.0, 5707.686077265707, 4002.8598999034893, 4503.572160644733, 4639.698419602715, 5027.899442011139, 5196.480351964773, 2920.664123625768, 2969.1294484474643, 2984.850757462118, 2995.831625665992, 3001.5197568388953, 4730.989329534934, 4912.975435122801, 4982.750862456863, 5015.918393866577, 5799.375390381012, 4504.603135108236, 4834.613230941516, 5190.78171533644, 5234.895302094013, 5474.427019300922, 4887.706737595756, 5173.586113110942, 5579.860503487445, 5749.338826034976, 5817.621854579524, 4907.165781817171, 4989.8752531185555, 5243.737813109406, 5656.490915817596, 5709.231022953947, 4656.67403281767, 4699.872030712672, 4777.378940737682, 5155.044743119209, 5448.431277513235, 4805.903881922272, 5211.71335576547, 5451.618386712892, 5451.964126815892, 5923.89701655906, 3283.095804433375, 3879.3792993120965, 3946.743461674982, 3951.7277565399436, 3959.7627340552494, 4952.851414457572, 5183.922241166461, 5266.920996185137, 5411.458854114557, 5490.881838621034, 5415.350157981067, 5783.219265399117, 5850.970745146247, 5859.794907178399, 5866.67733274674, 5812.709364531672, 5827.825165245049, 5850.2102216200265, 5859.648421094748, 5893.5874488785375, 5515.917261241005, 5722.570807189394, 5724.74238659253, 5735.8852822944145, 5775.826725198251, 4621.2837010263465, 5159.690418574897, 5861.505316144356, 5864.413558644272, 5895.557833162444, 4766.761661916821, 5003.649744517995, 5004.674696144739, 5620.718964051863, 5751.798687045934], "value": 4056.0355288946116, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.8210242183401, 651.244556314675, 751.9135299440562, 776.8213310938487, 802.2138304461628, 12748.362581870893, 13318.00114991377, 13643.931780341098, 14592.197429141394, 14606.561803145909, 14560.0, 14828.777568336483, 14891.329890154942, 15017.098974061564, 15299.923500382483, 668.5520701130255, 793.0523024985145, 820.9302209312216, 821.5440430561036, 821.765796747927, 791.6833266693311, 799.6881216325638, 800.5676934455387, 813.056854049303, 813.6826637611337, 857.695518091078, 862.3015357560365, 862.7756783236372, 863.8445079885649, 865.7965378136164, 850.4429598612918, 852.3436953545767, 853.2193043365313, 855.9315254779602, 857.1813917708573, 625.0530446373748, 631.3024108360241, 633.3887798274687, 633.6261605652641, 636.2333388267127, 14231.928840355731, 14245.857541424557, 14253.28733563328, 14282.785758213635, 14297.285135743276, 10695.358278503314, 11104.944475277653, 12912.354382280848, 13145.73708525824, 13148.737025259441, 11057.612983545618, 11177.608783692613, 11256.54973801047, 11267.830982535268, 11298.491567879466, 2990.685977972325, 3489.8255087245534, 3604.0629436346476, 3650.470681751158, 3669.0643885809213, 81708.0, 85694.57152714259, 85906.71139932905, 86623.11447861647, 87041.0, 10188.745281367877, 10285.588576456857, 10326.586936522455, 10336.276460647832, 10367.740806479747, 19748.605027899648, 19798.60402791965, 19834.305799296955, 19934.900325498278, 19979.800201997794, 7324.780256592311, 7481.625918704043, 7515.436342274351, 7651.311381975649, 7737.458377913492, 3430.9142271442915, 3672.1186915140706, 3750.3436898542645, 4212.662986961036, 4322.675799315001, 2804.6634403871667, 3526.100844284691, 3527.012436517779, 3546.060294022086, 3553.5202747629255, 615.9599626024294, 633.2116514938931, 633.3128555517316, 647.2815175155573, 647.8315637934077, 539.7355295905057, 548.7201527221146, 603.4448307557058, 653.2030922274818, 666.6233578028398, 8489.490630562186, 8937.642494300286, 9048.683296084606, 9110.134537218953, 9148.222401095982, 547.6221407228999, 627.2034516164446, 638.4158494977044, 658.367966751914, 659.5086660438004, 4330.675199359997, 4553.795079221443, 4674.602658773974, 4743.217369134123, 4817.301491283744, 114308.0, 120343.0, 121842.39078804727, 123274.38362808128, 124136.0, 100161.4975775364, 106230.0, 106646.0, 107327.46336268426, 108383.45808270908, 85970.57014714886, 88120.11879881247, 90303.0, 90654.0, 92705.072949271, 9602.231821454407, 9621.615135394786, 9701.369410988264, 9745.171660408661, 9757.365771224848, 43948.780256098515, 54788.452115478336, 55253.447465526435, 56163.0, 56240.7187964074, 70154.64922675354, 77041.61479192568, 79589.60205198936, 80200.19799802161, 81158.18841811742, 12199.69500762488, 12378.690532736753, 12400.255984640951, 12432.875671243171, 12523.185992910796, 11730.941345293219, 13239.470421183045, 14237.572872813833, 14356.569302920929, 15084.472043478425, 11003.0, 11007.61473348429, 11111.553309189138, 12070.430843990225, 12796.104272701194, 11388.658340249805, 13383.19700817954, 13391.129576577492, 13441.529546465827, 13894.096883702527, 12709.872901270868, 12925.030622703147, 13054.412551435018, 13563.118397304144, 13601.183928964294, 10580.735481612714, 10887.945560272148, 11567.826482602599, 11675.241109327866, 11731.120165987755, 10622.309549879234, 10661.09380702649, 10674.83987740169, 10679.466026698788, 10760.300580462244], "value": 7257.27249235263, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1551.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 120.0, 118.0, 117.0, 118.0, 116.0, 117.0, 117.0, 118.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 108.0, 113.0, 143.0, 113.0, 119.0, 112.0, 114.0, 143.0, 111.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 203.0, 198.0, 192.0, 196.0, 192.0, 194.0, 226.0, 263.0, 194.0], "value": 205.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 58.0, 59.0, 85.0, 61.0, 62.0, 50.0, 69.0, 97.0, 56.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 58.0, 101.0, 104.0, 102.0, 103.0, 93.0, 62.0, 67.0, 81.0], "value": 86.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 76.0, 106.0, 106.0, 110.0, 132.0, 83.0, 74.0, 97.0, 95.0], "value": 96.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 120.0, 152.0, 155.0, 108.0, 96.0, 97.0, 132.0, 108.0, 153.0], "value": 126.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 93.0, 90.0, 89.0, 96.0, 91.0, 134.0, 144.0, 91.0, 93.0], "value": 101.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 57.0, 60.0, 61.0, 60.0, 60.0, 61.0, 61.0, 60.0], "value": 59.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 68.0, 77.0, 80.0, 75.0, 69.0, 71.0, 70.0, 69.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 81.0, 83.0, 86.0, 86.0, 87.0, 96.0, 104.0, 83.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 154.0, 177.0, 154.0, 163.0, 162.0, 140.0, 157.0, 214.0, 161.0], "value": 163.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 166.0, 180.0, 238.0, 173.0, 166.0, 170.0, 166.0, 197.0], "value": 179.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 66.0, 67.0, 71.0, 68.0, 68.0, 68.0, 67.0, 68.0], "value": 68.1, "unit": "score"}]}]} 09:53:04 INFO - Return code: 0 09:53:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:53:04 ERROR - # TBPL SUCCESS # 09:53:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:53:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:53:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:53:04 INFO - 2016-12-23 09:53:04.509 screenresolution[1658:11079] starting screenresolution argv=screenresolution get 09:53:04 INFO - 2016-12-23 09:53:04.512 screenresolution[1658:11079] Display 0: 1600x1200x32@60 09:53:04 INFO - 2016-12-23 09:53:04.518 screenresolution[1659:11082] starting screenresolution argv=screenresolution list 09:53:04 INFO - Available Modes on Display 0 09:53:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:53:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:53:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:53:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:53:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:53:04 INFO - Intel Iris: 09:53:04 INFO - Chipset Model: Intel Iris 09:53:04 INFO - Type: GPU 09:53:04 INFO - Bus: Built-In 09:53:04 INFO - VRAM (Dynamic, Max): 1536 MB 09:53:04 INFO - Vendor: Intel (0x8086) 09:53:04 INFO - Device ID: 0x0a2e 09:53:04 INFO - Revision ID: 0x0009 09:53:04 INFO - Displays: 09:53:04 INFO - Display: 09:53:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:53:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:53:04 INFO - Display Serial Number: 1600x1200 60 09:53:04 INFO - Main Display: Yes 09:53:04 INFO - Mirror: Off 09:53:04 INFO - Online: Yes 09:53:04 INFO - Rotation: Supported 09:53:04 INFO - Return code: 0 09:53:04 INFO - Running post-action listener: _resource_record_post_action 09:53:04 INFO - [mozharness: 2016-12-23 17:53:04.751183Z] Finished run-tests step (success) 09:53:04 INFO - Running post-run listener: _resource_record_post_run 09:53:04 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 34353664; Write bytes: 663436800; Read time: 316; Write time: 1923 09:53:04 INFO - TinderboxPrint: CPU usage
54.2% 09:53:04 INFO - TinderboxPrint: I/O read bytes / time
34,353,664 / 316 09:53:04 INFO - TinderboxPrint: I/O write bytes / time
663,436,800 / 1,923 09:53:04 INFO - TinderboxPrint: CPU idle
2,220.3 (45.8%) 09:53:04 INFO - TinderboxPrint: CPU system
977.8 (20.2%) 09:53:04 INFO - TinderboxPrint: CPU user
1,651.6 (34.1%) 09:53:04 INFO - TinderboxPrint: Swap in / out
379,174,912 / 0 09:53:04 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 348080640; Write bytes: 345020416; Read time: 26808; Write time: 617 09:53:04 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 30130688; Write bytes: 311141888; Read time: 286; Write time: 1276 09:53:05 INFO - Running post-run listener: _upload_blobber_files 09:53:05 INFO - Blob upload gear active. 09:53:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:53:05 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 09:53:05 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'] 09:53:05 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 09:53:05 INFO - (blobuploader) - INFO - Open directory for files ... 09:53:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:53:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:53:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:06 INFO - (blobuploader) - INFO - Done attempting. 09:53:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:53:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:53:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:07 INFO - (blobuploader) - INFO - Done attempting. 09:53:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:53:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:53:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:10 INFO - (blobuploader) - INFO - Done attempting. 09:53:10 INFO - (blobuploader) - INFO - Iteration through files over. 09:53:10 INFO - Return code: 0 09:53:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:53:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:53:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3df7cc2d5bb99c5ff5aa8b9cba49aa7f8d2639d45338f4b0bd5bbaca7eca96396c788c766c6dd2b96b50905467a92860702c0480d3c9e1b8807afa7117286328", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2df0a47756d16dd7bd84b8d57ad2b2f571c0af0dc71ea6af64dea6db1928b32ff54b17229119167f8bd2a05b91576256dfcb99a136af112c8cb45e7b3129047d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e366ca1f124539da668359acafa85444e4dc47639e0c5329a37c738c21cd0498039a2868c51ac6a4d5d77d8c245c4204f6cda8ea820d469ead668faca776e9ec"} 09:53:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:53:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:53:10 INFO - Contents: 09:53:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3df7cc2d5bb99c5ff5aa8b9cba49aa7f8d2639d45338f4b0bd5bbaca7eca96396c788c766c6dd2b96b50905467a92860702c0480d3c9e1b8807afa7117286328", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2df0a47756d16dd7bd84b8d57ad2b2f571c0af0dc71ea6af64dea6db1928b32ff54b17229119167f8bd2a05b91576256dfcb99a136af112c8cb45e7b3129047d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e366ca1f124539da668359acafa85444e4dc47639e0c5329a37c738c21cd0498039a2868c51ac6a4d5d77d8c245c4204f6cda8ea820d469ead668faca776e9ec"} 09:53:10 INFO - Running post-run listener: copy_logs_to_upload_dir 09:53:10 INFO - Copying logs to upload dir... 09:53:10 INFO - mkdir: /builds/slave/test/build/upload/logs 09:53:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.905341 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-23 09:53:10.073375) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 09:53:10.077271) ========= 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.i2ilZXxtwW/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.9CAI3juTUZ/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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/3df7cc2d5bb99c5ff5aa8b9cba49aa7f8d2639d45338f4b0bd5bbaca7eca96396c788c766c6dd2b96b50905467a92860702c0480d3c9e1b8807afa7117286328", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2df0a47756d16dd7bd84b8d57ad2b2f571c0af0dc71ea6af64dea6db1928b32ff54b17229119167f8bd2a05b91576256dfcb99a136af112c8cb45e7b3129047d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e366ca1f124539da668359acafa85444e4dc47639e0c5329a37c738c21cd0498039a2868c51ac6a4d5d77d8c245c4204f6cda8ea820d469ead668faca776e9ec"} build_url:https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011208 build_url: 'https://queue.taskcluster.net/v1/task/ZPS9qub2SOWidigMRtOtEw/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/3df7cc2d5bb99c5ff5aa8b9cba49aa7f8d2639d45338f4b0bd5bbaca7eca96396c788c766c6dd2b96b50905467a92860702c0480d3c9e1b8807afa7117286328", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2df0a47756d16dd7bd84b8d57ad2b2f571c0af0dc71ea6af64dea6db1928b32ff54b17229119167f8bd2a05b91576256dfcb99a136af112c8cb45e7b3129047d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e366ca1f124539da668359acafa85444e4dc47639e0c5329a37c738c21cd0498039a2868c51ac6a4d5d77d8c245c4204f6cda8ea820d469ead668faca776e9ec"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 09:53:10.112059) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 09:53:10.112359) ========= 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.i2ilZXxtwW/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.9CAI3juTUZ/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.005302 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 09:53:10.160635) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 09:53:10.160956) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 09:53:10.161285) ========= ========= Total master_lag: 0.18 =========