builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0029 starttime: 1481732007.55 results: success (0) buildid: 20161214064001 builduid: 996d637145d44f09a99fbbf4b3c7f17c revision: 7bfeec8395e5ca28421d92f3bf51d56feafc0319 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 08:13:27.553405) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 08:13:27.553882) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 08:13:27.554173) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 08:13:27.580780) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:13:27.581168) ========= 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.Ga42o1WQqE/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.4cC6WDKkfd/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-14 08:13:27-- 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% 161M=0s 2016-12-14 08:13:27 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.084623 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:13:27.689887) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:13:27.690224) ========= 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.Ga42o1WQqE/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.4cC6WDKkfd/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.028976 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:13:27.741020) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-14 08:13:27.741370) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7bfeec8395e5ca28421d92f3bf51d56feafc0319 --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 7bfeec8395e5ca28421d92f3bf51d56feafc0319 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ga42o1WQqE/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.4cC6WDKkfd/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-14 08:13:27,834 truncating revision to first 12 chars 2016-12-14 08:13:27,834 Setting DEBUG logging. 2016-12-14 08:13:27,834 attempt 1/10 2016-12-14 08:13:27,834 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7bfeec8395e5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-14 08:13:29,500 unpacking tar archive at: mozilla-aurora-7bfeec8395e5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.937813 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-14 08:13:29.702222) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 08:13:29.702543) ========= script_repo_revision: 7bfeec8395e5ca28421d92f3bf51d56feafc0319 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 08:13:29.702970) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 08:13:29.703249) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 08:13:29.721786) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-14 08:13:29.722192) ========= /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.Ga42o1WQqE/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.4cC6WDKkfd/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 08:13:29 INFO - MultiFileLogger online at 20161214 08:13:29 in /builds/slave/test 08:13:29 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 08:13:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:13:29 INFO - {'append_to_log': False, 08:13:29 INFO - 'base_work_dir': '/builds/slave/test', 08:13:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:13:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:13:29 INFO - 'branch': 'Mozilla-Aurora', 08:13:29 INFO - 'buildbot_json_path': 'buildprops.json', 08:13:29 INFO - 'config_files': ('talos/mac_config.py',), 08:13:29 INFO - 'default_actions': ('clobber', 08:13:29 INFO - 'read-buildbot-config', 08:13:29 INFO - 'download-and-extract', 08:13:29 INFO - 'populate-webroot', 08:13:29 INFO - 'create-virtualenv', 08:13:29 INFO - 'install', 08:13:29 INFO - 'run-tests'), 08:13:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:13:29 INFO - 'download_minidump_stackwalk': True, 08:13:29 INFO - 'download_symbols': 'ondemand', 08:13:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:13:29 INFO - 'tooltool.py': '/tools/tooltool.py', 08:13:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:13:29 INFO - '/tools/misc-python/virtualenv.py')}, 08:13:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:13:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:13:29 INFO - 'installer_path': 'installer.exe', 08:13:29 INFO - 'log_level': 'info', 08:13:29 INFO - 'log_name': 'talos', 08:13:29 INFO - 'log_to_console': True, 08:13:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:13:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:13:29 INFO - 'opt_config_files': (), 08:13:29 INFO - 'pip_index': False, 08:13:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:13:29 INFO - 'cmd': ('bash', 08:13:29 INFO - '-c', 08:13:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:13:29 INFO - 'enabled': True, 08:13:29 INFO - 'halt_on_failure': False, 08:13:29 INFO - 'name': 'check_screen_resolution'},), 08:13:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:13:29 INFO - 'cmd': ('bash', 08:13:29 INFO - '-c', 08:13:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:13:29 INFO - 'enabled': True, 08:13:29 INFO - 'halt_on_failure': False, 08:13:29 INFO - 'name': 'check_screen_resolution'},), 08:13:29 INFO - 'run_cmd_checks_enabled': True, 08:13:29 INFO - 'sps_profile': False, 08:13:29 INFO - 'sps_profile_interval': 0, 08:13:29 INFO - 'suite': 'dromaeojs-e10s', 08:13:29 INFO - 'system_bits': '32', 08:13:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:13:29 INFO - 'title': 't-yosemite-r7-0029', 08:13:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:13:29 INFO - 'use_talos_json': True, 08:13:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:13:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:13:29 INFO - 'work_dir': 'build'} 08:13:29 INFO - [mozharness: 2016-12-14 16:13:29.926061Z] Running clobber step. 08:13:29 INFO - Running pre-action listener: _resource_record_pre_action 08:13:29 INFO - Running main action method: clobber 08:13:29 INFO - rmtree: /builds/slave/test/build 08:13:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:13:32 INFO - Running post-action listener: _resource_record_post_action 08:13:32 INFO - [mozharness: 2016-12-14 16:13:32.168242Z] Finished clobber step (success) 08:13:32 INFO - [mozharness: 2016-12-14 16:13:32.168378Z] Running read-buildbot-config step. 08:13:32 INFO - Running pre-action listener: _resource_record_pre_action 08:13:32 INFO - Running main action method: read_buildbot_config 08:13:32 INFO - Using buildbot properties: 08:13:32 INFO - { 08:13:32 INFO - "project": "", 08:13:32 INFO - "product": "firefox", 08:13:32 INFO - "script_repo_revision": "production", 08:13:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:13:32 INFO - "repository": "", 08:13:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:13:32 INFO - "buildid": "20161214064001", 08:13:32 INFO - "pgo_build": "False", 08:13:32 INFO - "basedir": "/builds/slave/test", 08:13:32 INFO - "buildnumber": 56, 08:13:32 INFO - "slavename": "t-yosemite-r7-0029", 08:13:32 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:13:32 INFO - "platform": "yosemite_r7", 08:13:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:13:32 INFO - "branch": "mozilla-aurora", 08:13:32 INFO - "repo_path": "releases/mozilla-aurora", 08:13:32 INFO - "revision": "7bfeec8395e5ca28421d92f3bf51d56feafc0319", 08:13:32 INFO - "stage_platform": "macosx64", 08:13:32 INFO - "builduid": "996d637145d44f09a99fbbf4b3c7f17c", 08:13:32 INFO - "slavebuilddir": "test" 08:13:32 INFO - } 08:13:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:13:32 INFO - Running post-action listener: _resource_record_post_action 08:13:32 INFO - [mozharness: 2016-12-14 16:13:32.171622Z] Finished read-buildbot-config step (success) 08:13:32 INFO - [mozharness: 2016-12-14 16:13:32.171762Z] Running download-and-extract step. 08:13:32 INFO - Running pre-action listener: _resource_record_pre_action 08:13:32 INFO - Running main action method: download_and_extract 08:13:32 INFO - mkdir: /builds/slave/test/build/tests 08:13: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')]} 08:13:32 INFO - trying https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:13:32 INFO - Downloading https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/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 08:13:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/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 08:13:32 INFO - Downloaded 1199 bytes. 08:13:32 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:13:32 INFO - Using the following test package requirements: 08:13:32 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:13:32 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:13:32 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:13:32 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:13:32 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:13:32 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:13:32 INFO - u'jsshell-mac.zip'], 08:13:32 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:13:32 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:13:32 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:13:32 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:13:32 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:13:32 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:13:32 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:13:32 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:13:32 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:13:32 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:13:32 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:13:32 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:13:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:13:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:13:32 INFO - Fetch https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:13:33 INFO - Http code: 200 08:13:33 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 08:13:33 INFO - x-amz-cf-id: VhyphYY5YtxItb1OISG-S1caradenqDrkvq1ooEHREwGnK8M38veAg== 08:13:33 INFO - x-amz-version-id: 9z8YGeWiYHkxXxbLNkhn6hySLcwz0Scx 08:13:33 INFO - Expected file size: 16280940 08:13:33 INFO - Obtained file size: 16280940 08:13:40 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 08:13:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:13:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:13:40 INFO - Fetch https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:13:40 INFO - Http code: 200 08:13:40 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 08:13:40 INFO - x-amz-cf-id: noBNS7n0zgnMKRJ5uhP2b22P3DKWrMfm1s7tqE29uDWpab1YStCl2w== 08:13:40 INFO - x-amz-version-id: 9z8YGeWiYHkxXxbLNkhn6hySLcwz0Scx 08:13:41 INFO - Expected file size: 16280940 08:13:41 INFO - Obtained file size: 16280940 08:13:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:13:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:13:47 INFO - Fetch https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:13:48 INFO - Http code: 200 08:13:48 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 08:13:48 INFO - x-amz-cf-id: PStwIdLDklqTnFbXrFnTT6KuSCDvMwItGYbH-YkJET2tyGvnx7Dhwg== 08:13:48 INFO - x-amz-version-id: 0I2DjqBNxCH1.HGJVvQZ.lF.0K5o1rtD 08:13:48 INFO - Expected file size: 14409038 08:13:48 INFO - Obtained file size: 14409038 08:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:13:49 INFO - trying https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:13:49 INFO - Downloading https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:13:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/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 08:13:52 INFO - Downloaded 109766849 bytes. 08:13:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:13:52 INFO - mkdir: /builds/slave/test/properties 08:13:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:13:52 INFO - Writing to file /builds/slave/test/properties/build_url 08:13:52 INFO - Contents: 08:13:52 INFO - build_url:https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:13:53 INFO - Running post-action listener: _resource_record_post_action 08:13:53 INFO - Running post-action listener: set_extra_try_arguments 08:13:53 INFO - [mozharness: 2016-12-14 16:13:53.019898Z] Finished download-and-extract step (success) 08:13:53 INFO - [mozharness: 2016-12-14 16:13:53.020048Z] Running populate-webroot step. 08:13:53 INFO - Running pre-action listener: _resource_record_pre_action 08:13:53 INFO - Running main action method: populate_webroot 08:13:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:13:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:13:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:13:53 INFO - '--tppagecycles', 08:13:53 INFO - '7'], 08:13:53 INFO - 'tests': ['tsvgm']}}, 08:13:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:13:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:13:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:13:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:13:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:13:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:13:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:13:53 INFO - 'talos_options': ['--disable-e10s'], 08:13:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:13:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:13:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:13:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:13:53 INFO - 'talos_options': ['--disable-e10s'], 08:13:53 INFO - 'tests': ['damp', 'tps']}, 08:13:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:13:53 INFO - 'tests': ['damp', 'tps']}, 08:13:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:13:53 INFO - 'tests': ['dromaeo_dom']}, 08:13:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:13:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:13:53 INFO - 'tests': ['basic_compositor_video']}, 08:13:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:13:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:13:53 INFO - 'tests': ['a11yr', 08:13:53 INFO - 'ts_paint', 08:13:53 INFO - 'tpaint', 08:13:53 INFO - 'sessionrestore', 08:13:53 INFO - 'sessionrestore_no_auto_restore', 08:13:53 INFO - 'tabpaint']}, 08:13:53 INFO - 'other-e10s': {'tests': ['a11yr', 08:13:53 INFO - 'ts_paint', 08:13:53 INFO - 'tpaint', 08:13:53 INFO - 'sessionrestore', 08:13:53 INFO - 'sessionrestore_no_auto_restore', 08:13:53 INFO - 'tabpaint']}, 08:13:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:13:53 INFO - 'ts_paint', 08:13:53 INFO - 'tpaint', 08:13:53 INFO - 'sessionrestore', 08:13:53 INFO - 'sessionrestore_no_auto_restore', 08:13:53 INFO - 'tabpaint']}, 08:13:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:13:53 INFO - 'ts_paint', 08:13:53 INFO - 'tpaint', 08:13:53 INFO - 'sessionrestore', 08:13:53 INFO - 'sessionrestore_no_auto_restore', 08:13:53 INFO - 'tabpaint']}, 08:13:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:13:53 INFO - 'tests': ['a11yr', 08:13:53 INFO - 'ts_paint', 08:13:53 INFO - 'tpaint', 08:13:53 INFO - 'sessionrestore', 08:13:53 INFO - 'sessionrestore_no_auto_restore', 08:13:53 INFO - 'tabpaint']}, 08:13:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:13:53 INFO - 'tests': ['a11yr', 08:13:53 INFO - 'ts_paint', 08:13:53 INFO - 'tpaint', 08:13:53 INFO - 'sessionrestore', 08:13:53 INFO - 'sessionrestore_no_auto_restore', 08:13:53 INFO - 'tabpaint']}, 08:13:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:13:53 INFO - 'tests': ['tsvgx', 08:13:53 INFO - 'tsvgr_opacity', 08:13:53 INFO - 'tart', 08:13:53 INFO - 'tscrollx', 08:13:53 INFO - 'cart']}, 08:13:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:13:53 INFO - 'tsvgr_opacity', 08:13:53 INFO - 'tart', 08:13:53 INFO - 'tscrollx', 08:13:53 INFO - 'cart']}, 08:13:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:13:53 INFO - 'talos_options': ['--disable-e10s'], 08:13:53 INFO - 'tests': ['tp5o']}, 08:13:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:13:53 INFO - 'tests': ['tp5o']}, 08:13:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:13:53 INFO - 'talos_options': ['--disable-e10s', 08:13:53 INFO - '--xperf_path', 08:13:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:13:53 INFO - 'tests': ['tp5n']}, 08:13:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:13:53 INFO - 'talos_options': ['--xperf_path', 08:13:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:13:53 INFO - 'tests': ['tp5n']}}, 08:13:53 INFO - 'talos.zip': {'path': '', 08:13:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:13:53 INFO - Running post-action listener: _resource_record_post_action 08:13:53 INFO - [mozharness: 2016-12-14 16:13:53.039170Z] Finished populate-webroot step (success) 08:13:53 INFO - [mozharness: 2016-12-14 16:13:53.039281Z] Running create-virtualenv step. 08:13:53 INFO - Running pre-action listener: _resource_record_pre_action 08:13:53 INFO - Running main action method: create_virtualenv 08:13:53 INFO - Creating virtualenv /builds/slave/test/build/venv 08:13:53 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 08:13:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:13:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:13:53 INFO - Using real prefix '/tools/python27' 08:13:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:13:53 INFO - Installing distribute.............................................................................................................................................................................................done. 08:13:56 INFO - Installing pip.................done. 08:13:56 INFO - Return code: 0 08:13:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:13:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:13:57 INFO - Reading from file tmpfile_stdout 08:13:57 INFO - Output received: 08:13:57 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) 08:13:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:13:57 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')]} 08:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:13:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:57 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')]} 08:13:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:13:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:57 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 0x10f471490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd08b4caf20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f285d30>, '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 0x10f0ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f27db20>, '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.Ga42o1WQqE/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.4cC6WDKkfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 08:13:57 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 08:13:57 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 08:13:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ga42o1WQqE/Render', 08:13:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:13:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:13:57 INFO - 'HOME': '/Users/cltbld', 08:13:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:13:57 INFO - 'LOGNAME': 'cltbld', 08:13:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:57 INFO - 'MOZ_NO_REMOTE': '1', 08:13:57 INFO - 'NO_EM_RESTART': '1', 08:13:57 INFO - 'PAGER': '/bin/cat', 08:13:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:13:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:57 INFO - 'PWD': '/builds/slave/test', 08:13:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:13:57 INFO - 'SHELL': '/bin/bash', 08:13:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cC6WDKkfd/Listeners', 08:13:57 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 08:13:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:13:57 INFO - 'USER': 'cltbld', 08:13:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:13:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:13:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:57 INFO - 'XPC_FLAGS': '0x0', 08:13:57 INFO - 'XPC_SERVICE_NAME': '0', 08:13:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:13:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:13:57 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 08:13:57 INFO - Cleaning up... 08:13:57 INFO - Return code: 0 08:13:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:13:57 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')]} 08:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:13:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:57 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')]} 08:13:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:13:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:13:57 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 0x10f471490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd08b4caf20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f285d30>, '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 0x10f0ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f27db20>, '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.Ga42o1WQqE/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.4cC6WDKkfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 08:13:57 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 08:13:57 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 08:13:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ga42o1WQqE/Render', 08:13:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:13:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:13:57 INFO - 'HOME': '/Users/cltbld', 08:13:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:13:57 INFO - 'LOGNAME': 'cltbld', 08:13:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:13:57 INFO - 'MOZ_NO_REMOTE': '1', 08:13:57 INFO - 'NO_EM_RESTART': '1', 08:13:57 INFO - 'PAGER': '/bin/cat', 08:13:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:13:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:13:57 INFO - 'PWD': '/builds/slave/test', 08:13:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:13:57 INFO - 'SHELL': '/bin/bash', 08:13:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cC6WDKkfd/Listeners', 08:13:57 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 08:13:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:13:57 INFO - 'USER': 'cltbld', 08:13:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:13:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:13:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:13:57 INFO - 'XPC_FLAGS': '0x0', 08:13:57 INFO - 'XPC_SERVICE_NAME': '0', 08:13:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:13:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:13:57 INFO - Downloading/unpacking psutil>=3.1.1 08:13:57 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 08:13:57 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 08:13:57 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 08:13:57 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 08:13:57 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 08:13:57 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 08:14:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:14:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:14:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:14:01 INFO - Installing collected packages: psutil 08:14:01 INFO - Running setup.py install for psutil 08:14:01 INFO - building 'psutil._psutil_osx' extension 08:14:01 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 08:14:01 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 08:14:01 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 08:14:01 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 08:14:01 INFO - building 'psutil._psutil_posix' extension 08:14:01 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 08:14:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:14:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:14:02 INFO - ^ 08:14:02 INFO - 1 warning generated. 08:14:02 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 08:14:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:14:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:14:02 INFO - Successfully installed psutil 08:14:02 INFO - Cleaning up... 08:14:02 INFO - Return code: 0 08:14:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:14:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:14:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:02 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 0x10f471490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd08b4caf20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f285d30>, '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 0x10f0ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f27db20>, '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.Ga42o1WQqE/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.4cC6WDKkfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 08:14:02 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 08:14:02 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 08:14:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ga42o1WQqE/Render', 08:14:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:14:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:14:02 INFO - 'HOME': '/Users/cltbld', 08:14:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:14:02 INFO - 'LOGNAME': 'cltbld', 08:14:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:02 INFO - 'MOZ_NO_REMOTE': '1', 08:14:02 INFO - 'NO_EM_RESTART': '1', 08:14:02 INFO - 'PAGER': '/bin/cat', 08:14:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:14:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:02 INFO - 'PWD': '/builds/slave/test', 08:14:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:14:02 INFO - 'SHELL': '/bin/bash', 08:14:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cC6WDKkfd/Listeners', 08:14:02 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 08:14:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:14:02 INFO - 'USER': 'cltbld', 08:14:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:14:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:14:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:02 INFO - 'XPC_FLAGS': '0x0', 08:14:02 INFO - 'XPC_SERVICE_NAME': '0', 08:14:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:14:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:14:02 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 08:14:02 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 08:14:02 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 08:14:02 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 08:14:02 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 08:14:02 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 08:14:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:14:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:14:05 INFO - Installing collected packages: mozsystemmonitor 08:14:05 INFO - Running setup.py install for mozsystemmonitor 08:14:05 INFO - Successfully installed mozsystemmonitor 08:14:05 INFO - Cleaning up... 08:14:05 INFO - Return code: 0 08:14:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:05 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 0x10f471490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd08b4caf20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f285d30>, '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 0x10f0ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f27db20>, '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.Ga42o1WQqE/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.4cC6WDKkfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 08:14:05 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 08:14:05 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 08:14:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ga42o1WQqE/Render', 08:14:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:14:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:14:05 INFO - 'HOME': '/Users/cltbld', 08:14:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:14:05 INFO - 'LOGNAME': 'cltbld', 08:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:05 INFO - 'MOZ_NO_REMOTE': '1', 08:14:05 INFO - 'NO_EM_RESTART': '1', 08:14:05 INFO - 'PAGER': '/bin/cat', 08:14:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:05 INFO - 'PWD': '/builds/slave/test', 08:14:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:14:05 INFO - 'SHELL': '/bin/bash', 08:14:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cC6WDKkfd/Listeners', 08:14:05 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 08:14:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:14:05 INFO - 'USER': 'cltbld', 08:14:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:14:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:05 INFO - 'XPC_FLAGS': '0x0', 08:14:05 INFO - 'XPC_SERVICE_NAME': '0', 08:14:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:05 INFO - Downloading/unpacking jsonschema==2.5.1 08:14:05 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 08:14:05 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 08:14:05 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 08:14:05 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 08:14:05 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 08:14:05 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 08:14:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:14:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:14:08 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 08:14:08 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 08:14:08 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 08:14:08 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 08:14:08 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 08:14:08 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 08:14:08 INFO - Downloading functools32-3.2.3-2.tar.gz 08:14:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:14:08 INFO - warning: no files found matching '*.txt' 08:14:08 INFO - no previously-included directories found matching 'build' 08:14:08 INFO - no previously-included directories found matching 'dist' 08:14:08 INFO - no previously-included directories found matching '.git*' 08:14:08 INFO - Installing collected packages: jsonschema, functools32 08:14:08 INFO - Running setup.py install for functools32 08:14:08 INFO - warning: no files found matching '*.txt' 08:14:08 INFO - no previously-included directories found matching 'build' 08:14:08 INFO - no previously-included directories found matching 'dist' 08:14:08 INFO - no previously-included directories found matching '.git*' 08:14:08 INFO - Successfully installed jsonschema functools32 08:14:08 INFO - Cleaning up... 08:14:09 INFO - Return code: 0 08:14:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:14:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f471490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd08b4caf20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f285d30>, '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 0x10f0ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f27db20>, '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.Ga42o1WQqE/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.4cC6WDKkfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 08:14:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:14:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:14:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ga42o1WQqE/Render', 08:14:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:14:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:14:09 INFO - 'HOME': '/Users/cltbld', 08:14:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:14:09 INFO - 'LOGNAME': 'cltbld', 08:14:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:09 INFO - 'MOZ_NO_REMOTE': '1', 08:14:09 INFO - 'NO_EM_RESTART': '1', 08:14:09 INFO - 'PAGER': '/bin/cat', 08:14:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:14:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:09 INFO - 'PWD': '/builds/slave/test', 08:14:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:14:09 INFO - 'SHELL': '/bin/bash', 08:14:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cC6WDKkfd/Listeners', 08:14:09 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 08:14:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:14:09 INFO - 'USER': 'cltbld', 08:14:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:14:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:14:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:09 INFO - 'XPC_FLAGS': '0x0', 08:14:09 INFO - 'XPC_SERVICE_NAME': '0', 08:14:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:14:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:14:09 INFO - Cleaning up... 08:14:09 INFO - Return code: 0 08:14:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:14:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f471490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd08b4caf20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f285d30>, '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 0x10f0ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f27db20>, '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.Ga42o1WQqE/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.4cC6WDKkfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 08:14:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:14:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:14:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ga42o1WQqE/Render', 08:14:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:14:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:14:09 INFO - 'HOME': '/Users/cltbld', 08:14:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:14:09 INFO - 'LOGNAME': 'cltbld', 08:14:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:09 INFO - 'MOZ_NO_REMOTE': '1', 08:14:09 INFO - 'NO_EM_RESTART': '1', 08:14:09 INFO - 'PAGER': '/bin/cat', 08:14:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:14:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:09 INFO - 'PWD': '/builds/slave/test', 08:14:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:14:09 INFO - 'SHELL': '/bin/bash', 08:14:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cC6WDKkfd/Listeners', 08:14:09 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 08:14:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:14:09 INFO - 'USER': 'cltbld', 08:14:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:14:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:14:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:09 INFO - 'XPC_FLAGS': '0x0', 08:14:09 INFO - 'XPC_SERVICE_NAME': '0', 08:14:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:14:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:09 INFO - Downloading/unpacking blobuploader==1.2.4 08:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:12 INFO - Downloading blobuploader-1.2.4.tar.gz 08:14:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:14:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:14:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:13 INFO - Downloading docopt-0.6.1.tar.gz 08:14:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:14:13 INFO - Installing collected packages: blobuploader, requests, docopt 08:14:13 INFO - Running setup.py install for blobuploader 08:14:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:14:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:14:13 INFO - Running setup.py install for requests 08:14:14 INFO - Running setup.py install for docopt 08:14:14 INFO - Successfully installed blobuploader requests docopt 08:14:14 INFO - Cleaning up... 08:14:14 INFO - Return code: 0 08:14:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:14:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:14 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 0x10f471490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd08b4caf20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f285d30>, '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 0x10f0ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f27db20>, '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.Ga42o1WQqE/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.4cC6WDKkfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 08:14:14 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 08:14:14 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 08:14:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ga42o1WQqE/Render', 08:14:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:14:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:14:14 INFO - 'HOME': '/Users/cltbld', 08:14:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:14:14 INFO - 'LOGNAME': 'cltbld', 08:14:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:14 INFO - 'MOZ_NO_REMOTE': '1', 08:14:14 INFO - 'NO_EM_RESTART': '1', 08:14:14 INFO - 'PAGER': '/bin/cat', 08:14:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:14:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:14 INFO - 'PWD': '/builds/slave/test', 08:14:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:14:14 INFO - 'SHELL': '/bin/bash', 08:14:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cC6WDKkfd/Listeners', 08:14:14 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 08:14:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:14:14 INFO - 'USER': 'cltbld', 08:14:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:14:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:14:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:14 INFO - 'XPC_FLAGS': '0x0', 08:14:14 INFO - 'XPC_SERVICE_NAME': '0', 08:14:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:14:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:14:14 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-nXJzV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:14:14 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-idyxY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:14:14 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-wcxlU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:14:14 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-TZHYvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:14:14 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-kFYaGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:14:15 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-lnoVih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:14:15 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-qfa_fH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:14:15 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-u14l4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:14:15 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-JqqQAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:14:15 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ah1P0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:14:15 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Nxm0kF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:14:15 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-6z2kCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:14:15 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ZzPv6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:14:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:14:15 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-s0PC6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:14:16 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-i5QUBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:14:16 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-z8eeqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:14:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:14:16 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Q2yqyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:14:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:14:16 INFO - Running setup.py install for manifestparser 08:14:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:14:16 INFO - Running setup.py install for mozcrash 08:14:16 INFO - Running setup.py install for mozdebug 08:14:16 INFO - Running setup.py install for mozdevice 08:14:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:14:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:14:16 INFO - Running setup.py install for mozfile 08:14:17 INFO - Running setup.py install for mozhttpd 08:14:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:14:17 INFO - Running setup.py install for mozinfo 08:14:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:14:17 INFO - Running setup.py install for mozInstall 08:14:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:14:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:14:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:14:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:14:17 INFO - Running setup.py install for mozleak 08:14:17 INFO - Running setup.py install for mozlog 08:14:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:14:17 INFO - Running setup.py install for moznetwork 08:14:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:14:17 INFO - Running setup.py install for mozprocess 08:14:18 INFO - Running setup.py install for mozprofile 08:14:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:14:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:14:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:14:18 INFO - Running setup.py install for mozrunner 08:14:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:14:18 INFO - Running setup.py install for mozscreenshot 08:14:18 INFO - Running setup.py install for moztest 08:14:18 INFO - Running setup.py install for mozversion 08:14:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:14:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:14:18 INFO - Cleaning up... 08:14:18 INFO - Return code: 0 08:14:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:14:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:18 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 0x10f471490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd08b4caf20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f285d30>, '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 0x10f0ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f27db20>, '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.Ga42o1WQqE/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.4cC6WDKkfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 08:14:18 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 08:14:18 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 08:14:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ga42o1WQqE/Render', 08:14:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:14:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:14:18 INFO - 'HOME': '/Users/cltbld', 08:14:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:14:18 INFO - 'LOGNAME': 'cltbld', 08:14:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:18 INFO - 'MOZ_NO_REMOTE': '1', 08:14:18 INFO - 'NO_EM_RESTART': '1', 08:14:18 INFO - 'PAGER': '/bin/cat', 08:14:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:14:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:18 INFO - 'PWD': '/builds/slave/test', 08:14:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:14:18 INFO - 'SHELL': '/bin/bash', 08:14:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cC6WDKkfd/Listeners', 08:14:18 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 08:14:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:14:18 INFO - 'USER': 'cltbld', 08:14:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:14:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:14:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:18 INFO - 'XPC_FLAGS': '0x0', 08:14:18 INFO - 'XPC_SERVICE_NAME': '0', 08:14:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:14:19 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-8P0ONr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:14:19 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)) 08:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:14:19 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-1ihRgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:14:19 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)) 08:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:14:19 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-yrgi3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:14:19 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)) 08:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:14:19 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-2LXgOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:14:19 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)) 08:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:14:19 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-E3nULC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:14:19 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)) 08:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:14:19 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-awg16p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:14:19 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)) 08:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:14:19 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-EtnrA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:14:19 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)) 08:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:14:19 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-_m1z3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:14:20 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)) 08:14:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:14:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-awaC9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:14:20 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)) 08:14:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:14:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-mfvv5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:14:20 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)) 08:14:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:14:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-IDUXGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:14:20 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)) 08:14:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:14:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-SANefC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:14:20 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)) 08:14:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:14:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-HfaDIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:14:20 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)) 08:14:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:14:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-qeNxUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:14:20 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)) 08:14:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:14:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-KUoveO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:14:20 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)) 08:14:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:14:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-qoJ9oC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:14:20 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)) 08:14:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:14:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-GrDjNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:14:20 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)) 08:14:20 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)) 08:14:20 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)) 08:14:20 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)) 08:14:20 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)) 08:14:20 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)) 08:14:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:14:20 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 08:14:20 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 08:14:20 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 08:14:20 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 08:14:20 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 08:14:20 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 08:14:23 INFO - Downloading blessings-1.6.tar.gz 08:14:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:14:23 INFO - Installing collected packages: blessings 08:14:23 INFO - Running setup.py install for blessings 08:14:23 INFO - Successfully installed blessings 08:14:23 INFO - Cleaning up... 08:14:23 INFO - Return code: 0 08:14:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:14:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:14:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:14:24 INFO - Reading from file tmpfile_stdout 08:14:24 INFO - Current package versions: 08:14:24 INFO - blessings == 1.6 08:14:24 INFO - blobuploader == 1.2.4 08:14:24 INFO - docopt == 0.6.1 08:14:24 INFO - functools32 == 3.2.3-2 08:14:24 INFO - jsonschema == 2.5.1 08:14:24 INFO - manifestparser == 1.1 08:14:24 INFO - mozInstall == 1.12 08:14:24 INFO - mozcrash == 1.0 08:14:24 INFO - mozdebug == 0.1 08:14:24 INFO - mozdevice == 0.48 08:14:24 INFO - mozfile == 1.2 08:14:24 INFO - mozhttpd == 0.7 08:14:24 INFO - mozinfo == 0.9 08:14:24 INFO - mozleak == 0.1 08:14:24 INFO - mozlog == 3.4 08:14:24 INFO - moznetwork == 0.27 08:14:24 INFO - mozprocess == 0.23 08:14:24 INFO - mozprofile == 0.28 08:14:24 INFO - mozrunner == 6.13 08:14:24 INFO - mozscreenshot == 0.1 08:14:24 INFO - mozsystemmonitor == 0.3 08:14:24 INFO - moztest == 0.7 08:14:24 INFO - mozversion == 1.4 08:14:24 INFO - psutil == 3.1.1 08:14:24 INFO - requests == 1.2.3 08:14:24 INFO - wsgiref == 0.1.2 08:14:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:14: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')]} 08:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14: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')]} 08:14:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:24 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 0x10f471490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd08b4caf20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f285d30>, '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 0x10f0ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f27db20>, '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.Ga42o1WQqE/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.4cC6WDKkfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 08:14:24 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 08:14:24 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 08:14:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ga42o1WQqE/Render', 08:14:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:14:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:14:24 INFO - 'HOME': '/Users/cltbld', 08:14:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:14:24 INFO - 'LOGNAME': 'cltbld', 08:14:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:24 INFO - 'MOZ_NO_REMOTE': '1', 08:14:24 INFO - 'NO_EM_RESTART': '1', 08:14:24 INFO - 'PAGER': '/bin/cat', 08:14:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:14:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:24 INFO - 'PWD': '/builds/slave/test', 08:14:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:14:24 INFO - 'SHELL': '/bin/bash', 08:14:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cC6WDKkfd/Listeners', 08:14:24 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 08:14:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:14:24 INFO - 'USER': 'cltbld', 08:14:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:14:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:14:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:24 INFO - 'XPC_FLAGS': '0x0', 08:14:24 INFO - 'XPC_SERVICE_NAME': '0', 08:14:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:14:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:25 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)) 08:14:25 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)) 08:14:25 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)) 08:14:25 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)) 08:14:25 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)) 08:14:25 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)) 08:14:25 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)) 08:14:25 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)) 08:14:25 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)) 08:14:25 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)) 08:14:25 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)) 08:14:25 INFO - Cleaning up... 08:14:25 INFO - Return code: 0 08:14:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f471490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd08b4caf20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f285d30>, '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 0x10f0ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f27db20>, '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.Ga42o1WQqE/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.4cC6WDKkfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 08:14:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:14:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:14:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ga42o1WQqE/Render', 08:14:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:14:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:14:25 INFO - 'HOME': '/Users/cltbld', 08:14:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:14:25 INFO - 'LOGNAME': 'cltbld', 08:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:25 INFO - 'MOZ_NO_REMOTE': '1', 08:14:25 INFO - 'NO_EM_RESTART': '1', 08:14:25 INFO - 'PAGER': '/bin/cat', 08:14:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:25 INFO - 'PWD': '/builds/slave/test', 08:14:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:14:25 INFO - 'SHELL': '/bin/bash', 08:14:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cC6WDKkfd/Listeners', 08:14:25 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 08:14:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:14:25 INFO - 'USER': 'cltbld', 08:14:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:14:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:25 INFO - 'XPC_FLAGS': '0x0', 08:14:25 INFO - 'XPC_SERVICE_NAME': '0', 08:14:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:14:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:14:25 INFO - Cleaning up... 08:14:25 INFO - Return code: 0 08:14:25 INFO - Running post-action listener: _resource_record_post_action 08:14:25 INFO - Running post-action listener: _start_resource_monitoring 08:14:25 INFO - Starting resource monitoring. 08:14:25 INFO - [mozharness: 2016-12-14 16:14:25.902159Z] Finished create-virtualenv step (success) 08:14:25 INFO - [mozharness: 2016-12-14 16:14:25.903121Z] Running install step. 08:14:25 INFO - Running pre-action listener: _resource_record_pre_action 08:14:25 INFO - Running main action method: install 08:14:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:14:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:14:26 INFO - Reading from file tmpfile_stdout 08:14:26 INFO - Detecting whether we're running mozinstall >=1.0... 08:14:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:14:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:14:26 INFO - Reading from file tmpfile_stdout 08:14:26 INFO - Output received: 08:14:26 INFO - Usage: mozinstall [options] installer 08:14:26 INFO - Options: 08:14:26 INFO - -h, --help show this help message and exit 08:14:26 INFO - -d DEST, --destination=DEST 08:14:26 INFO - Directory to install application into. [default: 08:14:26 INFO - "/builds/slave/test"] 08:14:26 INFO - --app=APP Application being installed. [default: firefox] 08:14:26 INFO - mkdir: /builds/slave/test/build/application 08:14:26 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'] 08:14:26 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 08:14:57 INFO - Reading from file tmpfile_stdout 08:14:57 INFO - Output received: 08:14:57 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:14:57 INFO - Running post-action listener: _resource_record_post_action 08:14:57 INFO - [mozharness: 2016-12-14 16:14:57.276152Z] Finished install step (success) 08:14:57 INFO - [mozharness: 2016-12-14 16:14:57.276299Z] Running run-tests step. 08:14:57 INFO - Running pre-action listener: _resource_record_pre_action 08:14:57 INFO - Running main action method: run_tests 08:14:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:14:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:14:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:14:57 INFO - 2016-12-14 08:14:57.291 screenresolution[1623:7352] starting screenresolution argv=screenresolution get 08:14:57 INFO - 2016-12-14 08:14:57.293 screenresolution[1623:7352] Display 0: 1600x1200x32@60 08:14:57 INFO - 2016-12-14 08:14:57.299 screenresolution[1624:7355] starting screenresolution argv=screenresolution list 08:14:57 INFO - Available Modes on Display 0 08:14:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:14:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:14:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:14:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:14:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:14:57 INFO - Intel Iris: 08:14:57 INFO - Chipset Model: Intel Iris 08:14:57 INFO - Type: GPU 08:14:57 INFO - Bus: Built-In 08:14:57 INFO - VRAM (Dynamic, Max): 1536 MB 08:14:57 INFO - Vendor: Intel (0x8086) 08:14:57 INFO - Device ID: 0x0a2e 08:14:57 INFO - Revision ID: 0x0009 08:14:57 INFO - Displays: 08:14:57 INFO - Display: 08:14:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:14:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:14:57 INFO - Display Serial Number: 1600x1200 60 08:14:57 INFO - Main Display: Yes 08:14:57 INFO - Mirror: Off 08:14:57 INFO - Online: Yes 08:14:57 INFO - Rotation: Supported 08:14:57 INFO - Return code: 0 08:14:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:14:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:14:57 INFO - Python 2.7.3 08:14:57 INFO - Return code: 0 08:14:57 INFO - grabbing minidump binary from tooltool 08:14:57 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')]} 08:14:57 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 0x10f285d30>, '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 0x10f0ebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f27db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:14:57 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 08:14:57 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 08:14:57 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 08:14:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:14:57 INFO - Return code: 0 08:14:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:14:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:14:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:14:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:14:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:14:57 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/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 08:14:57 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/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0029 --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 08:14:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ga42o1WQqE/Render', 08:14:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:14:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:14:57 INFO - 'HOME': '/Users/cltbld', 08:14:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:14:57 INFO - 'LOGNAME': 'cltbld', 08:14:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:14:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:14:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:57 INFO - 'MOZ_NO_REMOTE': '1', 08:14:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:14:57 INFO - 'NO_EM_RESTART': '1', 08:14:57 INFO - 'PAGER': '/bin/cat', 08:14:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:14:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:57 INFO - 'PWD': '/builds/slave/test', 08:14:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:14:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:14:57 INFO - 'SHELL': '/bin/bash', 08:14:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4cC6WDKkfd/Listeners', 08:14:57 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 08:14:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:14:57 INFO - 'USER': 'cltbld', 08:14:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:14:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:14:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:57 INFO - 'XPC_FLAGS': '0x0', 08:14:57 INFO - 'XPC_SERVICE_NAME': '0', 08:14:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:14:57 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/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 08:14:57 INFO - mozversion application_buildid: 20161214064001 08:14:57 INFO - mozversion application_changeset: 7bfeec8395e5ca28421d92f3bf51d56feafc0319 08:14:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:14:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:14:57 INFO - mozversion application_name: Firefox 08:14:57 INFO - mozversion application_remotingname: firefox-dev 08:14:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:14:57 INFO - mozversion application_vendor: Mozilla 08:14:57 INFO - mozversion application_version: 52.0a2 08:14:57 INFO - mozversion platform_buildid: 20161214064001 08:14:57 INFO - mozversion platform_changeset: 7bfeec8395e5ca28421d92f3bf51d56feafc0319 08:14:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:14:57 INFO - mozversion platform_version: 52.0a2 08:14:57 INFO - using testdate: 1481732097 08:14:57 INFO - actual date: 1481732097 08:14:57 INFO - starting webserver on 'localhost:49249' 08:14:57 INFO - SUITE-START | Running 2 tests 08:14:57 INFO - TEST-START | dromaeo_css 08:14:57 INFO - operating with platform_type : mac_ 08:14:57 INFO - Initialising browser for dromaeo_css test... 08:14:57 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmprEDa9X/profile http://localhost:49249/getInfo.html) 08:15:00 INFO - PROCESS | 1634 | 2016-12-14 08:15:00.860 plugin-container[1636:7533] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 08:15:00 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:15:00 INFO - PROCESS | 1634 | 2016-12-14 08:15:00.863 plugin-container[1636:7533] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c1b, name = 'com.apple.CFPasteboardClient' 08:15:00 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:15:00 INFO - PROCESS | 1634 | 2016-12-14 08:15:00.863 plugin-container[1636:7533] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:15:00 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 08:15:00 INFO - PROCESS | 1634 | colorDepth:24 08:15:00 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 08:15:00 INFO - PROCESS | 1634 | __metrics 08:15:01 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:15:01 INFO - TEST-INFO | 1634: exit 0 08:15:01 INFO - Browser initialized. 08:15:01 INFO - Running cycle 1/1 for dromaeo_css test... 08:15:01 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmprEDa9X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:15:04 INFO - PROCESS | 1637 | 2016-12-14 08:15:04.397 plugin-container[1638:7659] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 08:15:04 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:15:04 INFO - PROCESS | 1637 | 2016-12-14 08:15:04.398 plugin-container[1638:7659] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 08:15:04 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:15:04 INFO - PROCESS | 1637 | 2016-12-14 08:15:04.398 plugin-container[1638:7659] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:15:14 INFO - PROCESS | 1637 | RSS: Main: 228958208 08:15:14 INFO - PROCESS | 1637 | 08:16:03 INFO - PROCESS | 1637 | 1481732163732 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] 08:18:17 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 08:18:19 INFO - PROCESS | 1637 | RSS: Main: 233566208 08:18:19 INFO - PROCESS | 1637 | 08:21:21 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 08:21:22 INFO - PROCESS | 1637 | RSS: Main: 242995200 08:21:22 INFO - PROCESS | 1637 | 08:24:24 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 08:24:25 INFO - PROCESS | 1637 | RSS: Main: 245805056 08:24:25 INFO - PROCESS | 1637 | 08:27:27 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 08:27:27 INFO - PROCESS | 1637 | RSS: Main: 245485568 08:27:27 INFO - PROCESS | 1637 | 08:30:30 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 08:30:31 INFO - PROCESS | 1637 | RSS: Main: 248438784 08:30:31 INFO - PROCESS | 1637 | 08:33:33 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 08:33:33 INFO - PROCESS | 1637 | RSS: Main: 249286656 08:33:33 INFO - PROCESS | 1637 | 08:33:33 INFO - PROCESS | 1637 | __start_tp_report 08:33:34 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 08:33:34 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 08:33:34 INFO - PROCESS | 1637 | |i|pagename|runs| 08:33:34 INFO - PROCESS | 1637 | |0;dojo.html;2692.892284308628;3074.846257687115;3122.9382010116556;3250.219947212668;3441.3977553928066;5707.942920570798;5800.680962547063;5820.38885916979;5832.970835145824;5850.590458667895;5748;5809.854753631162;5820.621659592123;5829.271341082364;5865.912011319833;5250.212468129792;5251.684898906078;5257.447967963366;5301.3373328333955;5330.866728331784;5208.010478009185;5254.975279820436;5339.332583427072;5344.385395679507;5367.516923476886;5132.28148059272;5186.196139598368;5190.662606930575;5235.764390602432;5282.075636763551;5131.4098878629065;5220.060389129955;5221.921671174935;5223.138182199932;5233.4243233244315;5058.949410505921;5146.614003949681;5146.794128234867;5171.534561889411;5306.946930530684;5472.699001554913;5642.351129620105;5725.971370143123;5754.395788442237;5780.942190578125;6162.383761623847;6234.407731265523;6287.622742635411;6289.842753931189;6291.402316779899;6243.968780156116;6293;6302.432781049682;6320.873582528369;6324.114623952604;5929.525637948954;5951.851203719942;5955.226258763523;5965.999999999956;5985.581009329392;6218.19163508741;6228.252609686867;6247.812565623039;6275.278342990523;6338.366163383717;6240.344763799826;6240.375962403723;6243.531735119794;6253.46845518127;6269.279032911182;6277.843053923689;6292.622442653455;6302.968485157545;6315.273743519554;6336.493080553545;5181.4559471255725;6039.758409663652;6160.44555989963;6302.590331628521;6330.335314791972;6168.660723660152;6221.440070393596;6237.500999919996;6238.532110091761;6343.492520598341;4948.876278093005;4950.702957822542;4976.4525902150735;4990.151674215392;4993.00139972007;4407.603315701603;4488.349189367589;4489.304157855538;4500.009997800544;4517.909641807146;4514.413126293626;5339.932013597296;5779.162021506858;5926.436988486087;6021.638701677914;6109.602875813058;6294.748210071637;6300.684965751694;6326.2092238470195;6327.525435592257;4873.6832105913;5535.225068490415;5812.709364531756;5915.142304365929;5993.430624090704;4496.348029535699;5469.261649677323;5528.585356098309;5529.695866727368;5534.723263836792;6338.366163383809;6377.2984971654005;6398.776042838665;6399.360063993563;6419.967900160469;6129.84675383119;6215.751369945152;6267.310595834363;6284.717187726472;6324.083007963912;6241.750329986749;6260.718267677874;6267.874642507216;6320.62076275425;6340.239171299289;6162.969185154046;6163.137160797582;6174.907376389448;6191.969040154951;6208.81373558794;6200.596961197508;6210.130581718473;6221.688915554295;6251.6874156292915;6264.906026409516;4662;6024.66864322449;6045.879082418415;6062.969685151546;6087;6116.66358350295;6117.755289788359;6126.142340072304;6136.1821086261625;6138.662373569497;5916.26046744157;5940.79207227745;6063.363346848518;6064.575479716377;6088.8782224356155;6119.2350956130485;6230.532710046855;6247.875042499216;6249.031400132987;6251.6561589113035;5801.361850196559;5889.734961926638;5909.911351329648;5932.762689492372;6092.695365231632 08:33:34 INFO - PROCESS | 1637 | |1;ext.html;482.99758501207486;521.358728763621;529.8528685396118;531.8165232994617;542.2164971616012;37473.62526374735;38904.61095389045;39007.41488877669;39095.022624434365;40482.190356192856;39635;39679.008024799325;39700.20599588007;39794.80102599483;39844.80077599608;9200.355975081748;9428.340016198834;9429.622815087389;9431.481268530228;9455.621775128986;9296.721098367027;9307.534623268843;9377.953110234439;9380.390274632127;9396.859047114298;9371.718848434524;9397.107274808917;9400.57697403615;9402.81194376112;9411.058894110569;9318.254539636813;9322.673706420277;9385.48379839112;9399.201067909236;9405.200557952583;9232.8615070774;9236.030216827203;9254.814903701888;9295.581698823502;9343.112404321579;38236.42645360322;38604;38607.806960965296;38624.613753862664;38663.61336386628;3300.05948304732;3337.482690183001;3340.7828491148;3500.334936362083;3609.5848977367414;27767.166984990483;28509.857450712614;29066.27334316659;29303.120906372842;29852.253693657833;3121.406932682783;3160.9051728448185;3166.335069635379;3181.7931834430688;3183.1246407237963;282146.5892670544;291617;299690;346362;347693;54311;54623.45376546263;54655.72672136614;54669.453305466435;54723.72638136784;55859.441405586236;56093.43906560964;56402;56611.71694141503;56784.716076419354;14521.056131351428;14614.853851461561;14680.412783488755;14710.485133020507;14731;11303.94348028271;11380.715482112848;11418.143639226924;11418.257813242113;11432.428378581204;12643.557475488315;13278.800817987545;14455.927720361542;14505.564833055025;14604.926975365268;5354.170103633865;5559.997601295256;6104.114903338991;6245.344238854946;6266.592671476431;855.9771073567027;987.5062468765617;1011.7116621762763;1011.8330475471465;1047.989520104789;24995.62506562403;29460.705392945798;29599;30290.697093028786;30702.692973069985;989.8614194012844;992.5979978108877;1036.7926414717087;1141.3323205924462;1165.8717541070475;14681.632959176106;14700.485483008044;14714.77927831062;15014.849851501564;15014.849851501564;464803;471293;472195;473763;473924;51371.7431412848;51571.22643160356;51653;51674.74162629163;51765;1340.7050448901225;1362.2916083636453;1376;1377.0429551461639;1381.461230120254;24055;27190.320241994108;27958.3010424741;28072;28522.857385712938;45494.772526138484;46481.53518464772;46600;46718.53281467142;46735.53264467448;546339;546391;546618;546863;548497;24007.91964361573;24131.758682413652;24171.75828241695;24174.75825241725;24421.38946526351;42026.789866051695;42533;42588;42605;43210.35184472173;35297.47053794144;35628.46557301591;35643.64356356507;35727.46408803818;36362.27275454529;35464.936051918485;35678.4648230282;36521.269574608894;36567.08582285464;36856.26287474289;29391.85304073466;34295;34325.48511772375;34746.65253347434;35211.47182792209;30343.089707308816;31895;31932.201694956882;31943.201419963756;32038;23923.641145382484;24198.395040123432;24380.63429048601;24738.752612473643;24751.752482474945 08:33:34 INFO - PROCESS | 1637 | |2;jquery.html;367.35895861721286;370.9666130048295;378.1774640157656;380.3533992213238;394.7079161420548;17928.910355448206;18429.354972575962;19064.714029289553;19453.708194377097;19641.90179049103;18743.531411714714;18873.716894246598;19242.807571924277;19358.903205483988;19401.320953766586;5464.098423760085;5711.200431939532;5718.342390625069;5718.857028574298;7169.175544812335;5730.713464326788;7056.1179852518435;7478.2895624915545;7639.19788422216;7647.732329368473;6180.536459765513;7503.437242206829;7617.542947423172;7679.500832445873;7680.270374314431;6920.377166055079;7276.927230727731;7502.437317201177;7570.16728159902;7596.202398748134;6456.2575303840185;7141.678624461912;7159;7164;7358.705651773923;23673.7632623675;24831.87584062068;26595.73404265971;26598.33504162392;27122.186334409802;1850.3616252393037;2518.8488690678437;2788.3384123643905;3266.477363621833;3273.738100951918;15897.602559935978;16205.432809851716;16275.51173464798;16282.83717162825;16890.324387024506;1905.9809401905943;1950.8634395592314;1954.4429837496245;1957.3051566693762;2002.709607106976;141565;142258;149333.0133397326;150698.9999999989;152380;18810.81189188118;20881.26915557948;21043.368698939055;21576.029078691183;21757.456063598536;33294.83468078654;34837.95486135369;35063.12342191466;35660;35660.465093023624;8254.266604013104;9530.046995300554;9619.951900240454;9851.950740246397;10040.146587539995;6716.9588713749445;7913.604319783988;7931.52410855339;8036.116027236999;8071.636776345079;7802;8494.787630309169;9616.51917404127;9682.70951871445;9684.176844968115;3728.4034554471696;3830.16641859028;3857.286402015636;3858.6720128789366;3937.822797974041;702.420503084957;896.0949441064572;934.5000424772714;939.8825146856643;955;17836.78382761128;20188.39434816958;20237.785732856075;20258.088386022664;20435;920.5305294299956;934.3926447808979;935.9532023398802;940.7507010642186;997.0926456924165;10136.898631013742;10546.419946902994;10573.312734672221;10580.52387642558;10581.735456613646;574068;574203;575486;602029;611159;72376.27623723695;72618.18454538679;75544;118108.40945795388;129196;980.8136454073704;996.1234113979651;1007.3804610164842;1053.847192157133;1057.2757661002238;17434.912825435793;17536.649267014844;18173.636527269115;18220.36228732041;18518.629627407645;41327;50079.49920500749;53891;54526.72736636292;56277;78760;81981.18018819735;83194.16805831864;84623.73064403915;84758;7725.729599463992;7836.608169591612;7972.242636949481;8039.919600803917;8120.878186827321;8868.645254190018;11373.20387572895;12473;12477.376131193585;12686.11197216186;8914.821703566022;9115.635374584943;10699.625513107005;10832.458377081271;11172.608958686407;10037.598496060076;10630.096441802534;10803.243772936134;10986.285891417032;11254.987051165434;10831.945840271064;10949.014588687056;11201.21591488588;11339.319640821248;11352.716182095513;6499.935000650122;6750.189977202767;8094.878576821235;8143.348532117535;8195.754127376189;8674.956625217086;8685.826283474169;9175.632974680939;10999.890001100206;11118.49966751482 08:33:34 INFO - PROCESS | 1637 | |3;mootools.html;1336.9331533423328;1476.0700758522132;1636.484507380175;1771.1852547827998;1796.9820301796979;6629.701663425145;7525;7774.416918731104;8528.530930798803;8536.061033286342;7904.565248911307;8203.42576019679;8229.876551851727;8317.708880189195;8333.708320208794;2270.568591967525;2290.576243394969;2291;2293.289080385078;2298.908043678259;2277.4989502309463;2310.6418505131733;2314.7569505201964;2315.5716192504356;2318.733345665245;2302.5625131225097;2305.124052859911;2334.404726794665;2334.941626459335;2335;2234.7988681018705;2248.5727711734717;2264.0377839418184;2265.5355652091366;2266.852654577447;2314.1668999160456;2335.649652552114;2344.050659482912;2346.8005219556358;2351.7648235176516;8311.085780564134;8350.958245208796;8352.749417517483;8405.495670259805;8443.662253509852;5044.4451110378195;5206.427292997767;5207.010667973111;5214.348206474153;5215.765290561933;2740.8081434299406;3352.6479719629333;3577.713782897388;3592.604813470516;3597.406427939387;1050.6112738286806;1063.9468026598718;1065.4832406282947;1097.8627671541058;1099.6536091131268;9774.951125244399;9823.722916021014;10952.4523773811;10997.835032474519;11033.503492342863;3929.941050884239;3939.803009849496;4438.223310920576;4464.241079016575;4684.297355396649;9157.77105572366;9195.494247816434;9201.95399023014;9233.769155771026;9249.861252081224;4901.877453063702;5065.696058236517;5071.746412679351;5092.465291144451;5119.436861945183;4170.7706076166105;4211.073563816017;4331.740095594275;4562.634989200856;4667.113248482778;3174.507951267557;3664.6152154023976;3680.9447858282147;3773.415120656303;3801.9429708554394;1264.3046324521476;1270.9300988445539;1275.5089290623177;1288.2270637617355;1290.7547565962436;787.6927998080753;889.9510526920952;900.2842740021698;918.5866360137965;932.1843387036344;5772.278465191851;5823.097419899922;5858.765649373977;5863.413658634103;5899.646021238739;848.3467729848106;889.74642226965;894.695803426825;896.2381975320925;967.3953778888194;3169.3819705157275;3777.3011992780876;3795.6773674237447;3824;3885.4560361549406;9457.527123643791;10094.545745441326;10205.74485637865;10215.489225538693;10245.385276883411;9961.153301969269;10068.194544436428;10145;10145.898541014643;10183.949080254552;7743.961280193563;7983.361331093614;7989.800254993672;7992.600369981477;8005.439619226599;5444.646098003617;5445.618806683494;5531.0873705838085;5587.357453892691;5641.802536911188;9275.76810579741;9378.202852757318;9394.530273486434;9466.479343636167;9496.47769372691;9036.367454278137;9434.103760142776;9434.48110353937;9455.527223638928;9514.19129374011;6628.5691430056895;6821.147356580427;7009.9649501752165;7166.283371663;7316.451266155166;7848.529088254723;7963.243491868263;8019.358451323764;8020.238077382639;8042.798930026562;7017.4386049116965;7112.431005519443;7158.570485770871;7191.748288810076;7242.348188663045;6812.591244525344;6960.269171737097;7004.124484439445;7038.929610704033;7084.433245340463;6677.799666010027;6681.1314529110905;6775.322467753185;6785.321467853175;6794.8641027180165;4825.083234185494;4849.2726091086615;4873.829415970424;4876.561109500162;4904.754762261944;4741.810327586996;4781;4864.562189402971;4878.121938051185;4888.291197776373 08:33:34 INFO - PROCESS | 1637 | |4;prototype.html;290.22365173161796;299.57161259399066;304.12867135656353;309.5666067505491;312.95161209946684;5166.398416490891;5324.307839980797;5537.861553461165;5567.192757050226;5602.719864006804;4979.45226025137;5312.362516498026;5466.644668096571;5467.402983582796;5506.972465137669;3664.7617904836166;3861.4980052593123;4149.377593361005;4202.621764041236;4219.324908014718;3522.806245656501;3607.314610224062;4016.758994460327;4119.382092686106;4218.24576127247;3599.406097993828;4129.587041295877;4139.234241665286;4152.667786577066;4162.438070860426;3427.2974040321674;4139.254934111858;4147;4155.10665206982;4186.769727664977;3017.200441882903;3606.441001644749;3610.656987586175;3615.2407994321225;3622.8188590570367;5099.974500127512;5416.91874621881;5419.807642318684;5433.565314774848;5443.782248710087;4723.527647235283;4730.929036064462;4740.905181896377;4754.429468463772;4760.262159365304;4574.885627859297;5087.872803179913;5530.6128571000045;5781.161731548902;5905.8818823623715;3203.471427214507;3370.275390790992;3784.6026167252594;3936.626020528045;3943.1522222721965;5777.19119323295;6113.541484388688;6114.816555503342;6172.105044768484;6181.3818618138275;3372.898813035613;3975.2248311578965;3987.322155233617;3997.6002399760373;4003.459532963013;3921.000144963073;4025.4163146344204;4034.838606455709;4053.5135783706146;4067.5525692173837;4210.473690788652;4697.389339385926;4699.459562150328;4702.130105930414;4709.434867815816;5372;5584.274044374287;5844.912326315109;5931.466168044896;5933.495652869468;1202.939853007346;1225.332193954297;1297.033709886141;1308.168564010393;1312.7046414556648;854.2952064546773;874.3005595523556;885.6811547842772;887.6552023684123;892.7321803458937;4569.64663198453;4633.976830115827;4681.555252251031;5223.582113430916;5268.446813084647;5422.783088676488;5696.578953944557;5746.396628353963;5763.88472230553;5784.421557844182;4542.772861356919;4611.354410382549;4777.761111944389;5228.843134705965;5243.977424402205;2670.0120955246293;2674.4383679427538;2910.592517047615;2953.6307961504813;3016.849157542114;5830.096335068071;5861.684634569321;5868.415527807468;5890.319904443468;5935.258092738408;5422.783088676488;5786.276715410574;5806.44838740319;5809.215755872988;5844.47399734026;5861.018134585654;5891.057430811135;5894.764209431661;5907.4683278505145;5939.8518785044;5382.650127741685;5546.528545073714;5589.860253493695;5636.408177141341;5705.030144875464;5015.648904576645;5952.791652292149;5976.252968378953;6048.60684055535;6057.394260573908;6289.905651415141;6302.65335406557;6319.747210111728;6339.587926784744;6372.617642941438;3866.845326186921;3951.130751234724;3976.264391087658;3986.900327491836;3999.9000024998445;4831.806727730851;6048.518113062328;6071.264413757276;6102.206713127265;6108.236470441195;5721.799737009184;5807.680577568274;5864.589478736448;5878.442877348367;5896.587238893237;4669.509701481295;5545.72271386421;5777.7977770777825;5810;5849.415058494116;4577.7482238477205;5076.334924164843;5121.974390128025;5717;5746.9712651436475;5567.610267281251;5821.505172060253;5854.502367298657;5877.559183061202;5881.705914704162;4775.5463230993;5726.2269593606;5759.51044161251;5768.961586914395;5783.479486846204 08:33:34 INFO - PROCESS | 1637 | |5;yui.html;686.7836631461092;704.8977898204761;706.6996526476246;708.9645517724114;744.7840126363354;13899.444022239111;14333.641658958531;14570.927145364258;14712;14813.481528146516;12972.351382430888;13307.866921330784;13370.732585348287;13372.866271337283;13426.328683565831;792.0890975378303;806.5846089264033;810.3112354498688;812.321711371003;814.5682788122305;644.954853160279;661.6691654172913;669.2604671837622;809.8339840332718;822.7778499805049;861.418542483824;865.5412631305387;868.2533021601417;868.3574155125193;868.722008957134;842.9325653947669;858.7080392666492;859.8925134358174;860.0152825015323;860.7030574451804;629.0626965820894;634.1629049654458;635.1806170040642;636.2206297285834;638.3361304243581;10539.420331881744;11782.764344713143;13289.734205315839;13353.866461335456;13471.730565388736;10705.946470267676;11070.28043177191;11129.72175695606;12105.334206618632;13734.519291824838;11251.324920504796;11406.315621062762;11427.657170284905;11560.075193984461;11635.476403561861;4327.891802704926;4352.129574085164;4377.627901628365;4398.7580683062415;4417.249067658506;92797;94072.52963735137;94832;96470;97553.99999999929;9907.058829411195;10039.196864250842;10345.482725863676;10396;10468;19315.4205373839;19356.70964935527;19366;19378.515537111685;19474.610507789766;6587.934120658828;7405.629718514053;7452.441066920002;7479.252074792586;7480.513766605153;4062.6749860011128;4147.854825081107;4175;4190.895227619273;4194.3498757692605;3083.105899289181;3192.1700357906648;3265.42855000374;3287.2603664175367;3288.046466524681;614.5176036811082;616.5468380740152;628.5568674084759;630.9495240380851;647.7376662451715;554.5092593055175;579.994200057994;583.2359608912338;593.012633964453;603.4418163199024;8427.789305267417;8565;8707.521086340312;8717.171868672465;8997.730068097968;519.7661052526303;619.6275250320472;620.1039497925544;629.5026928726255;630.5964182922933;3542.6634469725336;4149.2738770715005;4155.812988415529;4306.590873866978;4333.8699839004885;100426.99573004176;105441;105531;105583;107141;64341;67089.66455167693;75128.24871751212;90721.54639226894;92103;68927;70330;81324;84229.15770842336;88722;8830.823383532423;9170.220531254652;9214;9292.955549779037;9313.487758173365;41693.79153104215;42251;48490;54697.17954230769;55041.72479137579;67744;67972.660136699;68354;74035.25964740284;76328;12072.215306005082;12080.697982550506;12304.507819687113;12428.502859885864;12598.685032874251;13728.93135534316;13770;14657.267136642911;14763.572067189598;14819.925900370428;12809.11680911681;12980.8052879205;13094.934525327693;13358.666033349244;13364.198148111145;10545.683629491128;11443.370614616277;12978.610641680765;13476.5283215087;13825.30873456303;10414.739631509274;12274.263544187377;12844.229346239255;13115.475380985035;13252.337383130998;10516.68449946503;10521.085893716112;10539.069043915317;11034.3589308781;11178.161637877354;9892.109710126124;10423.061924426838;10486.842697359392;10587.841182382424;10650 08:33:34 INFO - PROCESS | 1637 | __end_tp_report 08:33:34 INFO - PROCESS | 1637 | __start_cc_report 08:33:34 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,370 08:33:34 INFO - PROCESS | 1637 | __end_cc_report 08:33:34 INFO - PROCESS | 1637 | __startTimestamp1481733213997__endTimestamp 08:33:34 INFO - PROCESS | 1637 | 08:33:34 INFO - PROCESS | 1637 | ------- Summary: start ------- 08:33:34 INFO - PROCESS | 1637 | Number of tests: 6 08:33:34 INFO - PROCESS | 1637 | 08:33:34 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5748.65 Median:6021.64 stddev:695.27 (11.5%) stddev-sans-first:654.78 08:33:34 INFO - PROCESS | 1637 | Values: 2692.9 3074.8 3122.9 3250.2 3441.4 5707.9 5800.7 5820.4 5833.0 5850.6 5748.0 5809.9 5820.6 5829.3 5865.9 5250.2 5251.7 5257.4 5301.3 5330.9 5208.0 5255.0 5339.3 5344.4 5367.5 5132.3 5186.2 5190.7 5235.8 5282.1 5131.4 5220.1 5221.9 5223.1 5233.4 5058.9 5146.6 5146.8 5171.5 5306.9 5472.7 5642.4 5726.0 5754.4 5780.9 6162.4 6234.4 6287.6 6289.8 6291.4 6244.0 6293.0 6302.4 6320.9 6324.1 5929.5 5951.9 5955.2 5966.0 5985.6 6218.2 6228.3 6247.8 6275.3 6338.4 6240.3 6240.4 6243.5 6253.5 6269.3 6277.8 6292.6 6303.0 6315.3 6336.5 5181.5 6039.8 6160.4 6302.6 6330.3 6168.7 6221.4 6237.5 6238.5 6343.5 4948.9 4950.7 4976.5 4990.2 4993.0 4407.6 4488.3 4489.3 4500.0 4517.9 4514.4 5339.9 5779.2 5926.4 6021.6 6109.6 6294.7 6300.7 6326.2 6327.5 4873.7 5535.2 5812.7 5915.1 5993.4 4496.3 5469.3 5528.6 5529.7 5534.7 6338.4 6377.3 6398.8 6399.4 6420.0 6129.8 6215.8 6267.3 6284.7 6324.1 6241.8 6260.7 6267.9 6320.6 6340.2 6163.0 6163.1 6174.9 6192.0 6208.8 6200.6 6210.1 6221.7 6251.7 6264.9 4662.0 6024.7 6045.9 6063.0 6087.0 6116.7 6117.8 6126.1 6136.2 6138.7 5916.3 5940.8 6063.4 6064.6 6088.9 6119.2 6230.5 6247.9 6249.0 6251.7 5801.4 5889.7 5909.9 5932.8 6092.7 08:33:34 INFO - PROCESS | 1637 | 08:33:34 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:58034.75 Median:37164.94 stddev:121208.02 (326.1%) stddev-sans-first:121471.02 08:33:34 INFO - PROCESS | 1637 | Values: 483.0 521.4 529.9 531.8 542.2 37473.6 38904.6 39007.4 39095.0 40482.2 39635.0 39679.0 39700.2 39794.8 39844.8 9200.4 9428.3 9429.6 9431.5 9455.6 9296.7 9307.5 9378.0 9380.4 9396.9 9371.7 9397.1 9400.6 9402.8 9411.1 9318.3 9322.7 9385.5 9399.2 9405.2 9232.9 9236.0 9254.8 9295.6 9343.1 38236.4 38604.0 38607.8 38624.6 38663.6 3300.1 3337.5 3340.8 3500.3 3609.6 27767.2 28509.9 29066.3 29303.1 29852.3 3121.4 3160.9 3166.3 3181.8 3183.1 282146.6 291617.0 299690.0 346362.0 347693.0 54311.0 54623.5 54655.7 54669.5 54723.7 55859.4 56093.4 56402.0 56611.7 56784.7 14521.1 14614.9 14680.4 14710.5 14731.0 11303.9 11380.7 11418.1 11418.3 11432.4 12643.6 13278.8 14455.9 14505.6 14604.9 5354.2 5560.0 6104.1 6245.3 6266.6 856.0 987.5 1011.7 1011.8 1048.0 24995.6 29460.7 29599.0 30290.7 30702.7 989.9 992.6 1036.8 1141.3 1165.9 14681.6 14700.5 14714.8 15014.8 15014.8 464803.0 471293.0 472195.0 473763.0 473924.0 51371.7 51571.2 51653.0 51674.7 51765.0 1340.7 1362.3 1376.0 1377.0 1381.5 24055.0 27190.3 27958.3 28072.0 28522.9 45494.8 46481.5 46600.0 46718.5 46735.5 546339.0 546391.0 546618.0 546863.0 548497.0 24007.9 24131.8 24171.8 24174.8 24421.4 42026.8 42533.0 42588.0 42605.0 43210.4 35297.5 35628.5 35643.6 35727.5 36362.3 35464.9 35678.5 36521.3 36567.1 36856.3 29391.9 34295.0 34325.5 34746.7 35211.5 30343.1 31895.0 31932.2 31943.2 32038.0 23923.6 24198.4 24380.6 24738.8 24751.8 08:33:34 INFO - PROCESS | 1637 | 08:33:34 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:35772.47 Median:3843.73 stddev:98183.69 (2554.4%) stddev-sans-first:98423.14 08:33:34 INFO - PROCESS | 1637 | Values: 367.4 371.0 378.2 380.4 394.7 17928.9 18429.4 19064.7 19453.7 19641.9 18743.5 18873.7 19242.8 19358.9 19401.3 5464.1 5711.2 5718.3 5718.9 7169.2 5730.7 7056.1 7478.3 7639.2 7647.7 6180.5 7503.4 7617.5 7679.5 7680.3 6920.4 7276.9 7502.4 7570.2 7596.2 6456.3 7141.7 7159.0 7164.0 7358.7 23673.8 24831.9 26595.7 26598.3 27122.2 1850.4 2518.8 2788.3 3266.5 3273.7 15897.6 16205.4 16275.5 16282.8 16890.3 1906.0 1950.9 1954.4 1957.3 2002.7 141565.0 142258.0 149333.0 150699.0 152380.0 18810.8 20881.3 21043.4 21576.0 21757.5 33294.8 34838.0 35063.1 35660.0 35660.5 8254.3 9530.0 9620.0 9852.0 10040.1 6717.0 7913.6 7931.5 8036.1 8071.6 7802.0 8494.8 9616.5 9682.7 9684.2 3728.4 3830.2 3857.3 3858.7 3937.8 702.4 896.1 934.5 939.9 955.0 17836.8 20188.4 20237.8 20258.1 20435.0 920.5 934.4 936.0 940.8 997.1 10136.9 10546.4 10573.3 10580.5 10581.7 574068.0 574203.0 575486.0 602029.0 611159.0 72376.3 72618.2 75544.0 118108.4 129196.0 980.8 996.1 1007.4 1053.8 1057.3 17434.9 17536.6 18173.6 18220.4 18518.6 41327.0 50079.5 53891.0 54526.7 56277.0 78760.0 81981.2 83194.2 84623.7 84758.0 7725.7 7836.6 7972.2 8039.9 8120.9 8868.6 11373.2 12473.0 12477.4 12686.1 8914.8 9115.6 10699.6 10832.5 11172.6 10037.6 10630.1 10803.2 10986.3 11255.0 10831.9 10949.0 11201.2 11339.3 11352.7 6499.9 6750.2 8094.9 8143.3 8195.8 8675.0 8685.8 9175.6 10999.9 11118.5 08:33:34 INFO - PROCESS | 1637 | 08:33:34 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5408.56 Median:5105.95 stddev:2967.42 (58.1%) stddev-sans-first:2959.97 08:33:34 INFO - PROCESS | 1637 | Values: 1336.9 1476.1 1636.5 1771.2 1797.0 6629.7 7525.0 7774.4 8528.5 8536.1 7904.6 8203.4 8229.9 8317.7 8333.7 2270.6 2290.6 2291.0 2293.3 2298.9 2277.5 2310.6 2314.8 2315.6 2318.7 2302.6 2305.1 2334.4 2334.9 2335.0 2234.8 2248.6 2264.0 2265.5 2266.9 2314.2 2335.6 2344.1 2346.8 2351.8 8311.1 8351.0 8352.7 8405.5 8443.7 5044.4 5206.4 5207.0 5214.3 5215.8 2740.8 3352.6 3577.7 3592.6 3597.4 1050.6 1063.9 1065.5 1097.9 1099.7 9775.0 9823.7 10952.5 10997.8 11033.5 3929.9 3939.8 4438.2 4464.2 4684.3 9157.8 9195.5 9202.0 9233.8 9249.9 4901.9 5065.7 5071.7 5092.5 5119.4 4170.8 4211.1 4331.7 4562.6 4667.1 3174.5 3664.6 3680.9 3773.4 3801.9 1264.3 1270.9 1275.5 1288.2 1290.8 787.7 890.0 900.3 918.6 932.2 5772.3 5823.1 5858.8 5863.4 5899.6 848.3 889.7 894.7 896.2 967.4 3169.4 3777.3 3795.7 3824.0 3885.5 9457.5 10094.5 10205.7 10215.5 10245.4 9961.2 10068.2 10145.0 10145.9 10183.9 7744.0 7983.4 7989.8 7992.6 8005.4 5444.6 5445.6 5531.1 5587.4 5641.8 9275.8 9378.2 9394.5 9466.5 9496.5 9036.4 9434.1 9434.5 9455.5 9514.2 6628.6 6821.1 7010.0 7166.3 7316.5 7848.5 7963.2 8019.4 8020.2 8042.8 7017.4 7112.4 7158.6 7191.7 7242.3 6812.6 6960.3 7004.1 7038.9 7084.4 6677.8 6681.1 6775.3 6785.3 6794.9 4825.1 4849.3 4873.8 4876.6 4904.8 4741.8 4781.0 4864.6 4878.1 4888.3 08:33:34 INFO - PROCESS | 1637 | 08:33:34 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4649.64 Median:5256.21 stddev:1470.16 (28.0%) stddev-sans-first:1437.41 08:33:34 INFO - PROCESS | 1637 | Values: 290.2 299.6 304.1 309.6 313.0 5166.4 5324.3 5537.9 5567.2 5602.7 4979.5 5312.4 5466.6 5467.4 5507.0 3664.8 3861.5 4149.4 4202.6 4219.3 3522.8 3607.3 4016.8 4119.4 4218.2 3599.4 4129.6 4139.2 4152.7 4162.4 3427.3 4139.3 4147.0 4155.1 4186.8 3017.2 3606.4 3610.7 3615.2 3622.8 5100.0 5416.9 5419.8 5433.6 5443.8 4723.5 4730.9 4740.9 4754.4 4760.3 4574.9 5087.9 5530.6 5781.2 5905.9 3203.5 3370.3 3784.6 3936.6 3943.2 5777.2 6113.5 6114.8 6172.1 6181.4 3372.9 3975.2 3987.3 3997.6 4003.5 3921.0 4025.4 4034.8 4053.5 4067.6 4210.5 4697.4 4699.5 4702.1 4709.4 5372.0 5584.3 5844.9 5931.5 5933.5 1202.9 1225.3 1297.0 1308.2 1312.7 854.3 874.3 885.7 887.7 892.7 4569.6 4634.0 4681.6 5223.6 5268.4 5422.8 5696.6 5746.4 5763.9 5784.4 4542.8 4611.4 4777.8 5228.8 5244.0 2670.0 2674.4 2910.6 2953.6 3016.8 5830.1 5861.7 5868.4 5890.3 5935.3 5422.8 5786.3 5806.4 5809.2 5844.5 5861.0 5891.1 5894.8 5907.5 5939.9 5382.7 5546.5 5589.9 5636.4 5705.0 5015.6 5952.8 5976.3 6048.6 6057.4 6289.9 6302.7 6319.7 6339.6 6372.6 3866.8 3951.1 3976.3 3986.9 3999.9 4831.8 6048.5 6071.3 6102.2 6108.2 5721.8 5807.7 5864.6 5878.4 5896.6 4669.5 5545.7 5777.8 5810.0 5849.4 4577.7 5076.3 5122.0 5717.0 5747.0 5567.6 5821.5 5854.5 5877.6 5881.7 4775.5 5726.2 5759.5 5769.0 5783.5 08:33:34 INFO - PROCESS | 1637 | 08:33:34 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19502.45 Median:4343.00 stddev:28396.79 (653.9%) stddev-sans-first:28441.30 08:33:34 INFO - PROCESS | 1637 | Values: 686.8 704.9 706.7 709.0 744.8 13899.4 14333.6 14570.9 14712.0 14813.5 12972.4 13307.9 13370.7 13372.9 13426.3 792.1 806.6 810.3 812.3 814.6 645.0 661.7 669.3 809.8 822.8 861.4 865.5 868.3 868.4 868.7 842.9 858.7 859.9 860.0 860.7 629.1 634.2 635.2 636.2 638.3 10539.4 11782.8 13289.7 13353.9 13471.7 10705.9 11070.3 11129.7 12105.3 13734.5 11251.3 11406.3 11427.7 11560.1 11635.5 4327.9 4352.1 4377.6 4398.8 4417.2 92797.0 94072.5 94832.0 96470.0 97554.0 9907.1 10039.2 10345.5 10396.0 10468.0 19315.4 19356.7 19366.0 19378.5 19474.6 6587.9 7405.6 7452.4 7479.3 7480.5 4062.7 4147.9 4175.0 4190.9 4194.3 3083.1 3192.2 3265.4 3287.3 3288.0 614.5 616.5 628.6 630.9 647.7 554.5 580.0 583.2 593.0 603.4 8427.8 8565.0 8707.5 8717.2 8997.7 519.8 619.6 620.1 629.5 630.6 3542.7 4149.3 4155.8 4306.6 4333.9 100427.0 105441.0 105531.0 105583.0 107141.0 64341.0 67089.7 75128.2 90721.5 92103.0 68927.0 70330.0 81324.0 84229.2 88722.0 8830.8 9170.2 9214.0 9293.0 9313.5 41693.8 42251.0 48490.0 54697.2 55041.7 67744.0 67972.7 68354.0 74035.3 76328.0 12072.2 12080.7 12304.5 12428.5 12598.7 13728.9 13770.0 14657.3 14763.6 14819.9 12809.1 12980.8 13094.9 13358.7 13364.2 10545.7 11443.4 12978.6 13476.5 13825.3 10414.7 12274.3 12844.2 13115.5 13252.3 10516.7 10521.1 10539.1 11034.4 11178.2 9892.1 10423.1 10486.8 10587.8 10650.0 08:33:34 INFO - PROCESS | 1637 | -------- Summary: end -------- 08:33:34 INFO - PROCESS | 1637 | 08:33:34 INFO - TEST-INFO | 1637: exit 0 08:33:34 INFO - TEST-OK | dromaeo_css | took 1116861ms 08:33:34 INFO - TEST-START | kraken 08:33:34 INFO - operating with platform_type : mac_ 08:33:34 INFO - Initialising browser for kraken test... 08:33:34 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmp8ainKr/profile http://localhost:49249/getInfo.html) 08:33:37 INFO - PROCESS | 1649 | 2016-12-14 08:33:37.582 plugin-container[1650:10024] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 08:33:37 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:33:37 INFO - PROCESS | 1649 | 2016-12-14 08:33:37.585 plugin-container[1650:10024] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 08:33:37 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:33:37 INFO - PROCESS | 1649 | 2016-12-14 08:33:37.586 plugin-container[1650:10024] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:33:37 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 08:33:37 INFO - PROCESS | 1649 | colorDepth:24 08:33:37 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 08:33:37 INFO - PROCESS | 1649 | __metrics 08:33:37 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:33:38 INFO - TEST-INFO | 1649: exit 0 08:33:38 INFO - Browser initialized. 08:33:38 INFO - Running cycle 1/1 for kraken test... 08:33:38 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmp8ainKr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:33:41 INFO - PROCESS | 1651 | 2016-12-14 08:33:41.295 plugin-container[1652:10143] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8543, name = 'com.apple.tsm.portname' 08:33:41 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:33:41 INFO - PROCESS | 1651 | 2016-12-14 08:33:41.296 plugin-container[1652:10143] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4327, name = 'com.apple.CFPasteboardClient' 08:33:41 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:33:41 INFO - PROCESS | 1651 | 2016-12-14 08:33:41.296 plugin-container[1652:10143] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:33:50 INFO - PROCESS | 1651 | RSS: Main: 229523456 08:33:50 INFO - PROCESS | 1651 | 08:33:54 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 08:33:54 INFO - PROCESS | 1651 | RSS: Main: 241512448 08:33:54 INFO - PROCESS | 1651 | 08:33:57 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 08:33:58 INFO - PROCESS | 1651 | RSS: Main: 249171968 08:33:58 INFO - PROCESS | 1651 | 08:34:01 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 08:34:02 INFO - PROCESS | 1651 | RSS: Main: 252878848 08:34:02 INFO - PROCESS | 1651 | 08:34:04 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 08:34:04 INFO - PROCESS | 1651 | RSS: Main: 254193664 08:34:04 INFO - PROCESS | 1651 | 08:34:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:34:07 INFO - PROCESS | 1651 | RSS: Main: 254238720 08:34:07 INFO - PROCESS | 1651 | 08:34:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 08:34:10 INFO - PROCESS | 1651 | RSS: Main: 258498560 08:34:10 INFO - PROCESS | 1651 | 08:34:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 08:34:14 INFO - PROCESS | 1651 | RSS: Main: 258445312 08:34:14 INFO - PROCESS | 1651 | 08:34:17 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 08:34:17 INFO - PROCESS | 1651 | RSS: Main: 258080768 08:34:17 INFO - PROCESS | 1651 | 08:34:19 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:34:20 INFO - PROCESS | 1651 | RSS: Main: 257011712 08:34:20 INFO - PROCESS | 1651 | 08:34:21 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:34:22 INFO - PROCESS | 1651 | RSS: Main: 257126400 08:34:22 INFO - PROCESS | 1651 | 08:34:24 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:34:25 INFO - PROCESS | 1651 | RSS: Main: 257200128 08:34:25 INFO - PROCESS | 1651 | 08:34:28 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:34:29 INFO - PROCESS | 1651 | RSS: Main: 257159168 08:34:29 INFO - PROCESS | 1651 | 08:34:31 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:34:32 INFO - PROCESS | 1651 | RSS: Main: 257597440 08:34:32 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 08:34:34 INFO - PROCESS | 1651 | RSS: Main: 259346432 08:34:34 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | __start_tp_report 08:34:34 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 08:34:34 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 08:34:34 INFO - PROCESS | 1651 | |i|pagename|runs| 08:34:34 INFO - PROCESS | 1651 | |0;ai-astar;104;116;119;117;117;119;118;118;117;133 08:34:34 INFO - PROCESS | 1651 | |1;audio-beat-detection;147;122;119;143;117;124;143;143;162;116 08:34:34 INFO - PROCESS | 1651 | |2;audio-dft;200;228;276;200;198;195;196;193;195;195 08:34:34 INFO - PROCESS | 1651 | |3;audio-fft;83;61;61;86;62;61;61;61;79;59 08:34:34 INFO - PROCESS | 1651 | |4;audio-oscillator;82;70;89;71;69;69;95;65;72;77 08:34:34 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;88;88;88;119;109;100;89;90;88;85 08:34:34 INFO - PROCESS | 1651 | |6;imaging-darkroom;99;98;99;99;100;127;99;98;99;98 08:34:34 INFO - PROCESS | 1651 | |7;imaging-desaturate;90;94;73;143;147;121;93;84;142;144 08:34:34 INFO - PROCESS | 1651 | |8;json-parse-financial;57;57;55;59;60;61;59;60;59;60 08:34:34 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;69;74;75;81;73;74;79;70;74;73 08:34:34 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;85;88;84;86;83;90;79;97;72;85 08:34:34 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;140;139;139;143;152;152;143;144;192;139 08:34:34 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;167;182;165;166;169;168;168;176;230;179 08:34:34 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;68;67;68;68;68;68;67;67;68;67 08:34:34 INFO - PROCESS | 1651 | __end_tp_report 08:34:34 INFO - PROCESS | 1651 | __start_cc_report 08:34:34 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,995 08:34:34 INFO - PROCESS | 1651 | __end_cc_report 08:34:34 INFO - PROCESS | 1651 | __startTimestamp1481733274509__endTimestamp 08:34:34 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | ------- Summary: start ------- 08:34:34 INFO - PROCESS | 1651 | Number of tests: 14 08:34:34 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:117.80 Median:118.00 stddev:6.91 (5.9%) stddev-sans-first:5.22 08:34:34 INFO - PROCESS | 1651 | Values: 104.0 116.0 119.0 117.0 117.0 119.0 118.0 118.0 117.0 133.0 08:34:34 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:133.60 Median:143.00 stddev:15.90 (11.1%) stddev-sans-first:16.11 08:34:34 INFO - PROCESS | 1651 | Values: 147.0 122.0 119.0 143.0 117.0 124.0 143.0 143.0 162.0 116.0 08:34:34 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:207.60 Median:199.00 stddev:26.09 (13.1%) stddev-sans-first:27.53 08:34:34 INFO - PROCESS | 1651 | Values: 200.0 228.0 276.0 200.0 198.0 195.0 196.0 193.0 195.0 195.0 08:34:34 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:67.40 Median:61.50 stddev:10.69 (17.4%) stddev-sans-first:9.73 08:34:34 INFO - PROCESS | 1651 | Values: 83.0 61.0 61.0 86.0 62.0 61.0 61.0 61.0 79.0 59.0 08:34:34 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:74.50 stddev:9.79 (13.1%) stddev-sans-first:10.13 08:34:34 INFO - PROCESS | 1651 | Values: 82.0 70.0 89.0 71.0 69.0 69.0 95.0 65.0 72.0 77.0 08:34:34 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:94.40 Median:88.00 stddev:11.31 (12.8%) stddev-sans-first:11.75 08:34:34 INFO - PROCESS | 1651 | Values: 88.0 88.0 88.0 119.0 109.0 100.0 89.0 90.0 88.0 85.0 08:34:34 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:101.60 Median:99.00 stddev:8.95 (9.0%) stddev-sans-first:9.44 08:34:34 INFO - PROCESS | 1651 | Values: 99.0 98.0 99.0 99.0 100.0 127.0 99.0 98.0 99.0 98.0 08:34:34 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:113.10 Median:78.50 stddev:29.16 (37.1%) stddev-sans-first:29.71 08:34:34 INFO - PROCESS | 1651 | Values: 90.0 94.0 73.0 143.0 147.0 121.0 93.0 84.0 142.0 144.0 08:34:34 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:58.70 Median:59.50 stddev:1.83 (3.1%) stddev-sans-first:1.83 08:34:34 INFO - PROCESS | 1651 | Values: 57.0 57.0 55.0 59.0 60.0 61.0 59.0 60.0 59.0 60.0 08:34:34 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:74.20 Median:74.00 stddev:3.61 (4.9%) stddev-sans-first:3.31 08:34:34 INFO - PROCESS | 1651 | Values: 69.0 74.0 75.0 81.0 73.0 74.0 79.0 70.0 74.0 73.0 08:34:34 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:85.50 stddev:6.57 (7.7%) stddev-sans-first:6.97 08:34:34 INFO - PROCESS | 1651 | Values: 85.0 88.0 84.0 86.0 83.0 90.0 79.0 97.0 72.0 85.0 08:34:34 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:143.50 stddev:16.12 (11.2%) stddev-sans-first:16.82 08:34:34 INFO - PROCESS | 1651 | Values: 140.0 139.0 139.0 143.0 152.0 152.0 143.0 144.0 192.0 139.0 08:34:34 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.00 Median:172.50 stddev:19.52 (11.3%) stddev-sans-first:20.37 08:34:34 INFO - PROCESS | 1651 | Values: 167.0 182.0 165.0 166.0 169.0 168.0 168.0 176.0 230.0 179.0 08:34:34 INFO - PROCESS | 1651 | 08:34:34 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:0.52 (0.8%) stddev-sans-first:0.53 08:34:34 INFO - PROCESS | 1651 | Values: 68.0 67.0 68.0 68.0 68.0 68.0 67.0 67.0 68.0 67.0 08:34:34 INFO - PROCESS | 1651 | -------- Summary: end -------- 08:34:34 INFO - PROCESS | 1651 | 08:34:35 INFO - TEST-INFO | 1651: exit 0 08:34:35 INFO - TEST-OK | kraken | took 60992ms 08:34:35 INFO - SUITE-END | took 1177s 08:34:36 INFO - Completed test suite (00:19:38) 08:34:36 INFO - javascript benchmark 08:34:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7233.38202333369, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2692.892284308628, 3074.846257687115, 3122.9382010116556, 3250.219947212668, 3441.3977553928066, 5707.942920570798, 5800.680962547063, 5820.38885916979, 5832.970835145824, 5850.590458667895, 5748.0, 5809.854753631162, 5820.621659592123, 5829.271341082364, 5865.912011319833, 5250.212468129792, 5251.684898906078, 5257.447967963366, 5301.3373328333955, 5330.866728331784, 5208.010478009185, 5254.975279820436, 5339.332583427072, 5344.385395679507, 5367.516923476886, 5132.28148059272, 5186.196139598368, 5190.662606930575, 5235.764390602432, 5282.075636763551, 5131.4098878629065, 5220.060389129955, 5221.921671174935, 5223.138182199932, 5233.4243233244315, 5058.949410505921, 5146.614003949681, 5146.794128234867, 5171.534561889411, 5306.946930530684, 5472.699001554913, 5642.351129620105, 5725.971370143123, 5754.395788442237, 5780.942190578125, 6162.383761623847, 6234.407731265523, 6287.622742635411, 6289.842753931189, 6291.402316779899, 6243.968780156116, 6293.0, 6302.432781049682, 6320.873582528369, 6324.114623952604, 5929.525637948954, 5951.851203719942, 5955.226258763523, 5965.999999999956, 5985.581009329392, 6218.19163508741, 6228.252609686867, 6247.812565623039, 6275.278342990523, 6338.366163383717, 6240.344763799826, 6240.375962403723, 6243.531735119794, 6253.46845518127, 6269.279032911182, 6277.843053923689, 6292.622442653455, 6302.968485157545, 6315.273743519554, 6336.493080553545, 5181.4559471255725, 6039.758409663652, 6160.44555989963, 6302.590331628521, 6330.335314791972, 6168.660723660152, 6221.440070393596, 6237.500999919996, 6238.532110091761, 6343.492520598341, 4948.876278093005, 4950.702957822542, 4976.4525902150735, 4990.151674215392, 4993.00139972007, 4407.603315701603, 4488.349189367589, 4489.304157855538, 4500.009997800544, 4517.909641807146, 4514.413126293626, 5339.932013597296, 5779.162021506858, 5926.436988486087, 6021.638701677914, 6109.602875813058, 6294.748210071637, 6300.684965751694, 6326.2092238470195, 6327.525435592257, 4873.6832105913, 5535.225068490415, 5812.709364531756, 5915.142304365929, 5993.430624090704, 4496.348029535699, 5469.261649677323, 5528.585356098309, 5529.695866727368, 5534.723263836792, 6338.366163383809, 6377.2984971654005, 6398.776042838665, 6399.360063993563, 6419.967900160469, 6129.84675383119, 6215.751369945152, 6267.310595834363, 6284.717187726472, 6324.083007963912, 6241.750329986749, 6260.718267677874, 6267.874642507216, 6320.62076275425, 6340.239171299289, 6162.969185154046, 6163.137160797582, 6174.907376389448, 6191.969040154951, 6208.81373558794, 6200.596961197508, 6210.130581718473, 6221.688915554295, 6251.6874156292915, 6264.906026409516, 4662.0, 6024.66864322449, 6045.879082418415, 6062.969685151546, 6087.0, 6116.66358350295, 6117.755289788359, 6126.142340072304, 6136.1821086261625, 6138.662373569497, 5916.26046744157, 5940.79207227745, 6063.363346848518, 6064.575479716377, 6088.8782224356155, 6119.2350956130485, 6230.532710046855, 6247.875042499216, 6249.031400132987, 6251.6561589113035, 5801.361850196559, 5889.734961926638, 5909.911351329648, 5932.762689492372, 6092.695365231632], "value": 5701.1381775702675, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.99758501207486, 521.358728763621, 529.8528685396118, 531.8165232994617, 542.2164971616012, 37473.62526374735, 38904.61095389045, 39007.41488877669, 39095.022624434365, 40482.190356192856, 39635.0, 39679.008024799325, 39700.20599588007, 39794.80102599483, 39844.80077599608, 9200.355975081748, 9428.340016198834, 9429.622815087389, 9431.481268530228, 9455.621775128986, 9296.721098367027, 9307.534623268843, 9377.953110234439, 9380.390274632127, 9396.859047114298, 9371.718848434524, 9397.107274808917, 9400.57697403615, 9402.81194376112, 9411.058894110569, 9318.254539636813, 9322.673706420277, 9385.48379839112, 9399.201067909236, 9405.200557952583, 9232.8615070774, 9236.030216827203, 9254.814903701888, 9295.581698823502, 9343.112404321579, 38236.42645360322, 38604.0, 38607.806960965296, 38624.613753862664, 38663.61336386628, 3300.05948304732, 3337.482690183001, 3340.7828491148, 3500.334936362083, 3609.5848977367414, 27767.166984990483, 28509.857450712614, 29066.27334316659, 29303.120906372842, 29852.253693657833, 3121.406932682783, 3160.9051728448185, 3166.335069635379, 3181.7931834430688, 3183.1246407237963, 282146.5892670544, 291617.0, 299690.0, 346362.0, 347693.0, 54311.0, 54623.45376546263, 54655.72672136614, 54669.453305466435, 54723.72638136784, 55859.441405586236, 56093.43906560964, 56402.0, 56611.71694141503, 56784.716076419354, 14521.056131351428, 14614.853851461561, 14680.412783488755, 14710.485133020507, 14731.0, 11303.94348028271, 11380.715482112848, 11418.143639226924, 11418.257813242113, 11432.428378581204, 12643.557475488315, 13278.800817987545, 14455.927720361542, 14505.564833055025, 14604.926975365268, 5354.170103633865, 5559.997601295256, 6104.114903338991, 6245.344238854946, 6266.592671476431, 855.9771073567027, 987.5062468765617, 1011.7116621762763, 1011.8330475471465, 1047.989520104789, 24995.62506562403, 29460.705392945798, 29599.0, 30290.697093028786, 30702.692973069985, 989.8614194012844, 992.5979978108877, 1036.7926414717087, 1141.3323205924462, 1165.8717541070475, 14681.632959176106, 14700.485483008044, 14714.77927831062, 15014.849851501564, 15014.849851501564, 464803.0, 471293.0, 472195.0, 473763.0, 473924.0, 51371.7431412848, 51571.22643160356, 51653.0, 51674.74162629163, 51765.0, 1340.7050448901225, 1362.2916083636453, 1376.0, 1377.0429551461639, 1381.461230120254, 24055.0, 27190.320241994108, 27958.3010424741, 28072.0, 28522.857385712938, 45494.772526138484, 46481.53518464772, 46600.0, 46718.53281467142, 46735.53264467448, 546339.0, 546391.0, 546618.0, 546863.0, 548497.0, 24007.91964361573, 24131.758682413652, 24171.75828241695, 24174.75825241725, 24421.38946526351, 42026.789866051695, 42533.0, 42588.0, 42605.0, 43210.35184472173, 35297.47053794144, 35628.46557301591, 35643.64356356507, 35727.46408803818, 36362.27275454529, 35464.936051918485, 35678.4648230282, 36521.269574608894, 36567.08582285464, 36856.26287474289, 29391.85304073466, 34295.0, 34325.48511772375, 34746.65253347434, 35211.47182792209, 30343.089707308816, 31895.0, 31932.201694956882, 31943.201419963756, 32038.0, 23923.641145382484, 24198.395040123432, 24380.63429048601, 24738.752612473643, 24751.752482474945], "value": 18382.80864508525, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.35895861721286, 370.9666130048295, 378.1774640157656, 380.3533992213238, 394.7079161420548, 17928.910355448206, 18429.354972575962, 19064.714029289553, 19453.708194377097, 19641.90179049103, 18743.531411714714, 18873.716894246598, 19242.807571924277, 19358.903205483988, 19401.320953766586, 5464.098423760085, 5711.200431939532, 5718.342390625069, 5718.857028574298, 7169.175544812335, 5730.713464326788, 7056.1179852518435, 7478.2895624915545, 7639.19788422216, 7647.732329368473, 6180.536459765513, 7503.437242206829, 7617.542947423172, 7679.500832445873, 7680.270374314431, 6920.377166055079, 7276.927230727731, 7502.437317201177, 7570.16728159902, 7596.202398748134, 6456.2575303840185, 7141.678624461912, 7159.0, 7164.0, 7358.705651773923, 23673.7632623675, 24831.87584062068, 26595.73404265971, 26598.33504162392, 27122.186334409802, 1850.3616252393037, 2518.8488690678437, 2788.3384123643905, 3266.477363621833, 3273.738100951918, 15897.602559935978, 16205.432809851716, 16275.51173464798, 16282.83717162825, 16890.324387024506, 1905.9809401905943, 1950.8634395592314, 1954.4429837496245, 1957.3051566693762, 2002.709607106976, 141565.0, 142258.0, 149333.0133397326, 150698.9999999989, 152380.0, 18810.81189188118, 20881.26915557948, 21043.368698939055, 21576.029078691183, 21757.456063598536, 33294.83468078654, 34837.95486135369, 35063.12342191466, 35660.0, 35660.465093023624, 8254.266604013104, 9530.046995300554, 9619.951900240454, 9851.950740246397, 10040.146587539995, 6716.9588713749445, 7913.604319783988, 7931.52410855339, 8036.116027236999, 8071.636776345079, 7802.0, 8494.787630309169, 9616.51917404127, 9682.70951871445, 9684.176844968115, 3728.4034554471696, 3830.16641859028, 3857.286402015636, 3858.6720128789366, 3937.822797974041, 702.420503084957, 896.0949441064572, 934.5000424772714, 939.8825146856643, 955.0, 17836.78382761128, 20188.39434816958, 20237.785732856075, 20258.088386022664, 20435.0, 920.5305294299956, 934.3926447808979, 935.9532023398802, 940.7507010642186, 997.0926456924165, 10136.898631013742, 10546.419946902994, 10573.312734672221, 10580.52387642558, 10581.735456613646, 574068.0, 574203.0, 575486.0, 602029.0, 611159.0, 72376.27623723695, 72618.18454538679, 75544.0, 118108.40945795388, 129196.0, 980.8136454073704, 996.1234113979651, 1007.3804610164842, 1053.847192157133, 1057.2757661002238, 17434.912825435793, 17536.649267014844, 18173.636527269115, 18220.36228732041, 18518.629627407645, 41327.0, 50079.49920500749, 53891.0, 54526.72736636292, 56277.0, 78760.0, 81981.18018819735, 83194.16805831864, 84623.73064403915, 84758.0, 7725.729599463992, 7836.608169591612, 7972.242636949481, 8039.919600803917, 8120.878186827321, 8868.645254190018, 11373.20387572895, 12473.0, 12477.376131193585, 12686.11197216186, 8914.821703566022, 9115.635374584943, 10699.625513107005, 10832.458377081271, 11172.608958686407, 10037.598496060076, 10630.096441802534, 10803.243772936134, 10986.285891417032, 11254.987051165434, 10831.945840271064, 10949.014588687056, 11201.21591488588, 11339.319640821248, 11352.716182095513, 6499.935000650122, 6750.189977202767, 8094.878576821235, 8143.348532117535, 8195.754127376189, 8674.956625217086, 8685.826283474169, 9175.632974680939, 10999.890001100206, 11118.49966751482], "value": 10603.779818540987, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1336.9331533423328, 1476.0700758522132, 1636.484507380175, 1771.1852547827998, 1796.9820301796979, 6629.701663425145, 7525.0, 7774.416918731104, 8528.530930798803, 8536.061033286342, 7904.565248911307, 8203.42576019679, 8229.876551851727, 8317.708880189195, 8333.708320208794, 2270.568591967525, 2290.576243394969, 2291.0, 2293.289080385078, 2298.908043678259, 2277.4989502309463, 2310.6418505131733, 2314.7569505201964, 2315.5716192504356, 2318.733345665245, 2302.5625131225097, 2305.124052859911, 2334.404726794665, 2334.941626459335, 2335.0, 2234.7988681018705, 2248.5727711734717, 2264.0377839418184, 2265.5355652091366, 2266.852654577447, 2314.1668999160456, 2335.649652552114, 2344.050659482912, 2346.8005219556358, 2351.7648235176516, 8311.085780564134, 8350.958245208796, 8352.749417517483, 8405.495670259805, 8443.662253509852, 5044.4451110378195, 5206.427292997767, 5207.010667973111, 5214.348206474153, 5215.765290561933, 2740.8081434299406, 3352.6479719629333, 3577.713782897388, 3592.604813470516, 3597.406427939387, 1050.6112738286806, 1063.9468026598718, 1065.4832406282947, 1097.8627671541058, 1099.6536091131268, 9774.951125244399, 9823.722916021014, 10952.4523773811, 10997.835032474519, 11033.503492342863, 3929.941050884239, 3939.803009849496, 4438.223310920576, 4464.241079016575, 4684.297355396649, 9157.77105572366, 9195.494247816434, 9201.95399023014, 9233.769155771026, 9249.861252081224, 4901.877453063702, 5065.696058236517, 5071.746412679351, 5092.465291144451, 5119.436861945183, 4170.7706076166105, 4211.073563816017, 4331.740095594275, 4562.634989200856, 4667.113248482778, 3174.507951267557, 3664.6152154023976, 3680.9447858282147, 3773.415120656303, 3801.9429708554394, 1264.3046324521476, 1270.9300988445539, 1275.5089290623177, 1288.2270637617355, 1290.7547565962436, 787.6927998080753, 889.9510526920952, 900.2842740021698, 918.5866360137965, 932.1843387036344, 5772.278465191851, 5823.097419899922, 5858.765649373977, 5863.413658634103, 5899.646021238739, 848.3467729848106, 889.74642226965, 894.695803426825, 896.2381975320925, 967.3953778888194, 3169.3819705157275, 3777.3011992780876, 3795.6773674237447, 3824.0, 3885.4560361549406, 9457.527123643791, 10094.545745441326, 10205.74485637865, 10215.489225538693, 10245.385276883411, 9961.153301969269, 10068.194544436428, 10145.0, 10145.898541014643, 10183.949080254552, 7743.961280193563, 7983.361331093614, 7989.800254993672, 7992.600369981477, 8005.439619226599, 5444.646098003617, 5445.618806683494, 5531.0873705838085, 5587.357453892691, 5641.802536911188, 9275.76810579741, 9378.202852757318, 9394.530273486434, 9466.479343636167, 9496.47769372691, 9036.367454278137, 9434.103760142776, 9434.48110353937, 9455.527223638928, 9514.19129374011, 6628.5691430056895, 6821.147356580427, 7009.9649501752165, 7166.283371663, 7316.451266155166, 7848.529088254723, 7963.243491868263, 8019.358451323764, 8020.238077382639, 8042.798930026562, 7017.4386049116965, 7112.431005519443, 7158.570485770871, 7191.748288810076, 7242.348188663045, 6812.591244525344, 6960.269171737097, 7004.124484439445, 7038.929610704033, 7084.433245340463, 6677.799666010027, 6681.1314529110905, 6775.322467753185, 6785.321467853175, 6794.8641027180165, 4825.083234185494, 4849.2726091086615, 4873.829415970424, 4876.561109500162, 4904.754762261944, 4741.810327586996, 4781.0, 4864.562189402971, 4878.121938051185, 4888.291197776373], "value": 4388.16219240573, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.22365173161796, 299.57161259399066, 304.12867135656353, 309.5666067505491, 312.95161209946684, 5166.398416490891, 5324.307839980797, 5537.861553461165, 5567.192757050226, 5602.719864006804, 4979.45226025137, 5312.362516498026, 5466.644668096571, 5467.402983582796, 5506.972465137669, 3664.7617904836166, 3861.4980052593123, 4149.377593361005, 4202.621764041236, 4219.324908014718, 3522.806245656501, 3607.314610224062, 4016.758994460327, 4119.382092686106, 4218.24576127247, 3599.406097993828, 4129.587041295877, 4139.234241665286, 4152.667786577066, 4162.438070860426, 3427.2974040321674, 4139.254934111858, 4147.0, 4155.10665206982, 4186.769727664977, 3017.200441882903, 3606.441001644749, 3610.656987586175, 3615.2407994321225, 3622.8188590570367, 5099.974500127512, 5416.91874621881, 5419.807642318684, 5433.565314774848, 5443.782248710087, 4723.527647235283, 4730.929036064462, 4740.905181896377, 4754.429468463772, 4760.262159365304, 4574.885627859297, 5087.872803179913, 5530.6128571000045, 5781.161731548902, 5905.8818823623715, 3203.471427214507, 3370.275390790992, 3784.6026167252594, 3936.626020528045, 3943.1522222721965, 5777.19119323295, 6113.541484388688, 6114.816555503342, 6172.105044768484, 6181.3818618138275, 3372.898813035613, 3975.2248311578965, 3987.322155233617, 3997.6002399760373, 4003.459532963013, 3921.000144963073, 4025.4163146344204, 4034.838606455709, 4053.5135783706146, 4067.5525692173837, 4210.473690788652, 4697.389339385926, 4699.459562150328, 4702.130105930414, 4709.434867815816, 5372.0, 5584.274044374287, 5844.912326315109, 5931.466168044896, 5933.495652869468, 1202.939853007346, 1225.332193954297, 1297.033709886141, 1308.168564010393, 1312.7046414556648, 854.2952064546773, 874.3005595523556, 885.6811547842772, 887.6552023684123, 892.7321803458937, 4569.64663198453, 4633.976830115827, 4681.555252251031, 5223.582113430916, 5268.446813084647, 5422.783088676488, 5696.578953944557, 5746.396628353963, 5763.88472230553, 5784.421557844182, 4542.772861356919, 4611.354410382549, 4777.761111944389, 5228.843134705965, 5243.977424402205, 2670.0120955246293, 2674.4383679427538, 2910.592517047615, 2953.6307961504813, 3016.849157542114, 5830.096335068071, 5861.684634569321, 5868.415527807468, 5890.319904443468, 5935.258092738408, 5422.783088676488, 5786.276715410574, 5806.44838740319, 5809.215755872988, 5844.47399734026, 5861.018134585654, 5891.057430811135, 5894.764209431661, 5907.4683278505145, 5939.8518785044, 5382.650127741685, 5546.528545073714, 5589.860253493695, 5636.408177141341, 5705.030144875464, 5015.648904576645, 5952.791652292149, 5976.252968378953, 6048.60684055535, 6057.394260573908, 6289.905651415141, 6302.65335406557, 6319.747210111728, 6339.587926784744, 6372.617642941438, 3866.845326186921, 3951.130751234724, 3976.264391087658, 3986.900327491836, 3999.9000024998445, 4831.806727730851, 6048.518113062328, 6071.264413757276, 6102.206713127265, 6108.236470441195, 5721.799737009184, 5807.680577568274, 5864.589478736448, 5878.442877348367, 5896.587238893237, 4669.509701481295, 5545.72271386421, 5777.7977770777825, 5810.0, 5849.415058494116, 4577.7482238477205, 5076.334924164843, 5121.974390128025, 5717.0, 5746.9712651436475, 5567.610267281251, 5821.505172060253, 5854.502367298657, 5877.559183061202, 5881.705914704162, 4775.5463230993, 5726.2269593606, 5759.51044161251, 5768.961586914395, 5783.479486846204], "value": 4171.847317105491, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.7836631461092, 704.8977898204761, 706.6996526476246, 708.9645517724114, 744.7840126363354, 13899.444022239111, 14333.641658958531, 14570.927145364258, 14712.0, 14813.481528146516, 12972.351382430888, 13307.866921330784, 13370.732585348287, 13372.866271337283, 13426.328683565831, 792.0890975378303, 806.5846089264033, 810.3112354498688, 812.321711371003, 814.5682788122305, 644.954853160279, 661.6691654172913, 669.2604671837622, 809.8339840332718, 822.7778499805049, 861.418542483824, 865.5412631305387, 868.2533021601417, 868.3574155125193, 868.722008957134, 842.9325653947669, 858.7080392666492, 859.8925134358174, 860.0152825015323, 860.7030574451804, 629.0626965820894, 634.1629049654458, 635.1806170040642, 636.2206297285834, 638.3361304243581, 10539.420331881744, 11782.764344713143, 13289.734205315839, 13353.866461335456, 13471.730565388736, 10705.946470267676, 11070.28043177191, 11129.72175695606, 12105.334206618632, 13734.519291824838, 11251.324920504796, 11406.315621062762, 11427.657170284905, 11560.075193984461, 11635.476403561861, 4327.891802704926, 4352.129574085164, 4377.627901628365, 4398.7580683062415, 4417.249067658506, 92797.0, 94072.52963735137, 94832.0, 96470.0, 97553.99999999929, 9907.058829411195, 10039.196864250842, 10345.482725863676, 10396.0, 10468.0, 19315.4205373839, 19356.70964935527, 19366.0, 19378.515537111685, 19474.610507789766, 6587.934120658828, 7405.629718514053, 7452.441066920002, 7479.252074792586, 7480.513766605153, 4062.6749860011128, 4147.854825081107, 4175.0, 4190.895227619273, 4194.3498757692605, 3083.105899289181, 3192.1700357906648, 3265.42855000374, 3287.2603664175367, 3288.046466524681, 614.5176036811082, 616.5468380740152, 628.5568674084759, 630.9495240380851, 647.7376662451715, 554.5092593055175, 579.994200057994, 583.2359608912338, 593.012633964453, 603.4418163199024, 8427.789305267417, 8565.0, 8707.521086340312, 8717.171868672465, 8997.730068097968, 519.7661052526303, 619.6275250320472, 620.1039497925544, 629.5026928726255, 630.5964182922933, 3542.6634469725336, 4149.2738770715005, 4155.812988415529, 4306.590873866978, 4333.8699839004885, 100426.99573004176, 105441.0, 105531.0, 105583.0, 107141.0, 64341.0, 67089.66455167693, 75128.24871751212, 90721.54639226894, 92103.0, 68927.0, 70330.0, 81324.0, 84229.15770842336, 88722.0, 8830.823383532423, 9170.220531254652, 9214.0, 9292.955549779037, 9313.487758173365, 41693.79153104215, 42251.0, 48490.0, 54697.17954230769, 55041.72479137579, 67744.0, 67972.660136699, 68354.0, 74035.25964740284, 76328.0, 12072.215306005082, 12080.697982550506, 12304.507819687113, 12428.502859885864, 12598.685032874251, 13728.93135534316, 13770.0, 14657.267136642911, 14763.572067189598, 14819.925900370428, 12809.11680911681, 12980.8052879205, 13094.934525327693, 13358.666033349244, 13364.198148111145, 10545.683629491128, 11443.370614616277, 12978.610641680765, 13476.5283215087, 13825.30873456303, 10414.739631509274, 12274.263544187377, 12844.229346239255, 13115.475380985035, 13252.337383130998, 10516.68449946503, 10521.085893716112, 10539.069043915317, 11034.3589308781, 11178.161637877354, 9892.109710126124, 10423.061924426838, 10486.842697359392, 10587.841182382424, 10650.0], "value": 7039.761352160243, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1522.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 119.0, 117.0, 117.0, 119.0, 118.0, 118.0, 117.0, 133.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 122.0, 119.0, 143.0, 117.0, 124.0, 143.0, 143.0, 162.0, 116.0], "value": 133.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 228.0, 276.0, 200.0, 198.0, 195.0, 196.0, 193.0, 195.0, 195.0], "value": 207.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 61.0, 61.0, 86.0, 62.0, 61.0, 61.0, 61.0, 79.0, 59.0], "value": 67.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 70.0, 89.0, 71.0, 69.0, 69.0, 95.0, 65.0, 72.0, 77.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 119.0, 109.0, 100.0, 89.0, 90.0, 88.0, 85.0], "value": 94.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 98.0, 99.0, 99.0, 100.0, 127.0, 99.0, 98.0, 99.0, 98.0], "value": 101.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 73.0, 143.0, 147.0, 121.0, 93.0, 84.0, 142.0, 144.0], "value": 113.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 55.0, 59.0, 60.0, 61.0, 59.0, 60.0, 59.0, 60.0], "value": 58.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 75.0, 81.0, 73.0, 74.0, 79.0, 70.0, 74.0, 73.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 84.0, 86.0, 83.0, 90.0, 79.0, 97.0, 72.0, 85.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 139.0, 143.0, 152.0, 152.0, 143.0, 144.0, 192.0, 139.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 182.0, 165.0, 166.0, 169.0, 168.0, 168.0, 176.0, 230.0, 179.0], "value": 177.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 68.0, 68.0, 68.0, 67.0, 67.0, 68.0, 67.0], "value": 67.6, "unit": "score"}]}]} 08:34:36 INFO - Return code: 0 08:34:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:34:36 ERROR - # TBPL SUCCESS # 08:34:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:34:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:34:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:34:36 INFO - 2016-12-14 08:34:36.304 screenresolution[1656:10530] starting screenresolution argv=screenresolution get 08:34:36 INFO - 2016-12-14 08:34:36.306 screenresolution[1656:10530] Display 0: 1600x1200x32@60 08:34:36 INFO - 2016-12-14 08:34:36.312 screenresolution[1657:10533] starting screenresolution argv=screenresolution list 08:34:36 INFO - Available Modes on Display 0 08:34:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:34:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:34:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:34:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:34:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:34:36 INFO - Intel Iris: 08:34:36 INFO - Chipset Model: Intel Iris 08:34:36 INFO - Type: GPU 08:34:36 INFO - Bus: Built-In 08:34:36 INFO - VRAM (Dynamic, Max): 1536 MB 08:34:36 INFO - Vendor: Intel (0x8086) 08:34:36 INFO - Device ID: 0x0a2e 08:34:36 INFO - Revision ID: 0x0009 08:34:36 INFO - Displays: 08:34:36 INFO - Display: 08:34:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:34:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:34:36 INFO - Display Serial Number: 1600x1200 60 08:34:36 INFO - Main Display: Yes 08:34:36 INFO - Mirror: Off 08:34:36 INFO - Online: Yes 08:34:36 INFO - Rotation: Supported 08:34:36 INFO - Return code: 0 08:34:36 INFO - Running post-action listener: _resource_record_post_action 08:34:36 INFO - [mozharness: 2016-12-14 16:34:36.551460Z] Finished run-tests step (success) 08:34:36 INFO - Running post-run listener: _resource_record_post_run 08:34:36 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 39868928; Write bytes: 660306432; Read time: 409; Write time: 1754 08:34:36 INFO - TinderboxPrint: CPU usage
54.3% 08:34:36 INFO - TinderboxPrint: I/O read bytes / time
39,868,928 / 409 08:34:36 INFO - TinderboxPrint: I/O write bytes / time
660,306,432 / 1,754 08:34:36 INFO - TinderboxPrint: CPU idle
2,213.5 (45.7%) 08:34:36 INFO - TinderboxPrint: CPU system
975.6 (20.2%) 08:34:36 INFO - TinderboxPrint: CPU user
1,652.5 (34.1%) 08:34:36 INFO - TinderboxPrint: Swap in / out
388,325,376 / 0 08:34:36 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345909760; Write bytes: 345368576; Read time: 26775; Write time: 600 08:34:36 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 37734912; Write bytes: 307749376; Read time: 365; Write time: 1125 08:34:37 INFO - Running post-run listener: _upload_blobber_files 08:34:37 INFO - Blob upload gear active. 08:34:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:34:37 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 08:34:37 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'] 08:34:37 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 08:34:37 INFO - (blobuploader) - INFO - Open directory for files ... 08:34:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:34:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:34:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:34:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:34:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:34:38 INFO - (blobuploader) - INFO - Done attempting. 08:34:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:34:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:34:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:34:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:34:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:34:39 INFO - (blobuploader) - INFO - Done attempting. 08:34:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:34:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:34:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:34:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:34:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:34:41 INFO - (blobuploader) - INFO - Done attempting. 08:34:41 INFO - (blobuploader) - INFO - Iteration through files over. 08:34:41 INFO - Return code: 0 08:34:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:34:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:34:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7b3bbbed58f1ae7dcc27b2d61ae305e1c0b5b34de914db6488de7a6c444eca3a603d9b3fa16274d6ad2a328b75feab778fa2a2355c9f8b969bc6e9d9d0733022", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab4dcdca9f1515dcd25edf7e50ff09b93c2987105d7fa699cf90390cbe311184fd6a714de52f718369758868783c4cf3234737068566fd868d50a700bdb294c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4169723fe7d835779fc1955666969b3f5fa45945af20834301ca6f7f41a57015c987627c74037179793c9116a0730e44c7a49ef8a1d9b82aaceb31fe937f75d2"} 08:34:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:34:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:34:41 INFO - Contents: 08:34:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7b3bbbed58f1ae7dcc27b2d61ae305e1c0b5b34de914db6488de7a6c444eca3a603d9b3fa16274d6ad2a328b75feab778fa2a2355c9f8b969bc6e9d9d0733022", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab4dcdca9f1515dcd25edf7e50ff09b93c2987105d7fa699cf90390cbe311184fd6a714de52f718369758868783c4cf3234737068566fd868d50a700bdb294c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4169723fe7d835779fc1955666969b3f5fa45945af20834301ca6f7f41a57015c987627c74037179793c9116a0730e44c7a49ef8a1d9b82aaceb31fe937f75d2"} 08:34:41 INFO - Running post-run listener: copy_logs_to_upload_dir 08:34:41 INFO - Copying logs to upload dir... 08:34:41 INFO - mkdir: /builds/slave/test/build/upload/logs 08:34:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.281599 ========= master_lag: 1.64 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-14 08:34:43.644412) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 08:34:43.647402) ========= 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.Ga42o1WQqE/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.4cC6WDKkfd/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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/7b3bbbed58f1ae7dcc27b2d61ae305e1c0b5b34de914db6488de7a6c444eca3a603d9b3fa16274d6ad2a328b75feab778fa2a2355c9f8b969bc6e9d9d0733022", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab4dcdca9f1515dcd25edf7e50ff09b93c2987105d7fa699cf90390cbe311184fd6a714de52f718369758868783c4cf3234737068566fd868d50a700bdb294c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4169723fe7d835779fc1955666969b3f5fa45945af20834301ca6f7f41a57015c987627c74037179793c9116a0730e44c7a49ef8a1d9b82aaceb31fe937f75d2"} build_url:https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011167 build_url: 'https://queue.taskcluster.net/v1/task/cffjJIyGTc6uVjOfhEn7wQ/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/7b3bbbed58f1ae7dcc27b2d61ae305e1c0b5b34de914db6488de7a6c444eca3a603d9b3fa16274d6ad2a328b75feab778fa2a2355c9f8b969bc6e9d9d0733022", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab4dcdca9f1515dcd25edf7e50ff09b93c2987105d7fa699cf90390cbe311184fd6a714de52f718369758868783c4cf3234737068566fd868d50a700bdb294c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4169723fe7d835779fc1955666969b3f5fa45945af20834301ca6f7f41a57015c987627c74037179793c9116a0730e44c7a49ef8a1d9b82aaceb31fe937f75d2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 08:34:43.683483) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:34:43.683814) ========= 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.Ga42o1WQqE/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.4cC6WDKkfd/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.005028 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:34:43.732610) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 08:34:43.732903) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 08:34:43.733213) ========= ========= Total master_lag: 1.78 =========