builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0005 starttime: 1484996062.74 results: success (0) buildid: 20170121004004 builduid: b1e21b1400f94c1cb3f53e3f32612070 revision: a891cb2fa60257c3a8cb84bb0356fba508e42f3e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-21 02:54:22.744865) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-21 02:54:22.745363) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-21 02:54:22.745658) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-21 02:54:22.774651) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-21 02:54:22.775058) ========= 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.b9Ad1QhwM9/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.8v6tfFhstf/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-21 02:54:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.18M=0.002s 2017-01-21 02:54:23 (7.18 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.623406 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-21 02:54:23.422392) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-21 02:54:23.422736) ========= 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.b9Ad1QhwM9/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.8v6tfFhstf/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.019759 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-21 02:54:23.472151) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-21 02:54:23.472516) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a891cb2fa60257c3a8cb84bb0356fba508e42f3e --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 a891cb2fa60257c3a8cb84bb0356fba508e42f3e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b9Ad1QhwM9/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.8v6tfFhstf/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-21 02:54:24,000 truncating revision to first 12 chars 2017-01-21 02:54:24,000 Setting DEBUG logging. 2017-01-21 02:54:24,000 attempt 1/10 2017-01-21 02:54:24,000 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a891cb2fa602?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-21 02:54:25,188 unpacking tar archive at: mozilla-aurora-a891cb2fa602/testing/mozharness/ program finished with exit code 0 elapsedTime=1.422491 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-21 02:54:24.918947) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-21 02:54:24.919278) ========= script_repo_revision: a891cb2fa60257c3a8cb84bb0356fba508e42f3e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-21 02:54:24.919686) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-21 02:54:24.919969) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-21 02:54:24.942727) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-01-21 02:54:24.943263) ========= /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.b9Ad1QhwM9/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.8v6tfFhstf/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:54:25 INFO - MultiFileLogger online at 20170121 02:54:25 in /builds/slave/test 02:54:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:54:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:54:25 INFO - {'append_to_log': False, 02:54:25 INFO - 'base_work_dir': '/builds/slave/test', 02:54:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:54:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:54:25 INFO - 'branch': 'Mozilla-Aurora', 02:54:25 INFO - 'buildbot_json_path': 'buildprops.json', 02:54:25 INFO - 'config_files': ('talos/mac_config.py',), 02:54:25 INFO - 'default_actions': ('clobber', 02:54:25 INFO - 'read-buildbot-config', 02:54:25 INFO - 'download-and-extract', 02:54:25 INFO - 'populate-webroot', 02:54:25 INFO - 'create-virtualenv', 02:54:25 INFO - 'install', 02:54:25 INFO - 'run-tests'), 02:54:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:54:25 INFO - 'download_minidump_stackwalk': True, 02:54:25 INFO - 'download_symbols': 'ondemand', 02:54:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:54:25 INFO - 'tooltool.py': '/tools/tooltool.py', 02:54:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:54:25 INFO - '/tools/misc-python/virtualenv.py')}, 02:54:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:54:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:54:25 INFO - 'installer_path': 'installer.exe', 02:54:25 INFO - 'log_level': 'info', 02:54:25 INFO - 'log_name': 'talos', 02:54:25 INFO - 'log_to_console': True, 02:54:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:54:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:54:25 INFO - 'opt_config_files': (), 02:54:25 INFO - 'pip_index': False, 02:54:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:54:25 INFO - 'cmd': ('bash', 02:54:25 INFO - '-c', 02:54:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:54:25 INFO - 'enabled': True, 02:54:25 INFO - 'halt_on_failure': False, 02:54:25 INFO - 'name': 'check_screen_resolution'},), 02:54:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:54:25 INFO - 'cmd': ('bash', 02:54:25 INFO - '-c', 02:54:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:54:25 INFO - 'enabled': True, 02:54:25 INFO - 'halt_on_failure': False, 02:54:25 INFO - 'name': 'check_screen_resolution'},), 02:54:25 INFO - 'run_cmd_checks_enabled': True, 02:54:25 INFO - 'sps_profile': False, 02:54:25 INFO - 'sps_profile_interval': 0, 02:54:25 INFO - 'suite': 'dromaeojs-e10s', 02:54:25 INFO - 'system_bits': '32', 02:54:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:54:25 INFO - 'title': 't-yosemite-r7-0005', 02:54:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:54:25 INFO - 'use_talos_json': True, 02:54:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:54:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:54:25 INFO - 'work_dir': 'build'} 02:54:25 INFO - [mozharness: 2017-01-21 10:54:25.599125Z] Running clobber step. 02:54:25 INFO - Running pre-action listener: _resource_record_pre_action 02:54:25 INFO - Running main action method: clobber 02:54:25 INFO - rmtree: /builds/slave/test/build 02:54:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:54:27 INFO - Running post-action listener: _resource_record_post_action 02:54:27 INFO - [mozharness: 2017-01-21 10:54:27.295898Z] Finished clobber step (success) 02:54:27 INFO - [mozharness: 2017-01-21 10:54:27.296041Z] Running read-buildbot-config step. 02:54:27 INFO - Running pre-action listener: _resource_record_pre_action 02:54:27 INFO - Running main action method: read_buildbot_config 02:54:27 INFO - Using buildbot properties: 02:54:27 INFO - { 02:54:27 INFO - "project": "", 02:54:27 INFO - "product": "firefox", 02:54:27 INFO - "script_repo_revision": "production", 02:54:27 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:54:27 INFO - "repository": "", 02:54:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:54:27 INFO - "buildid": "20170121004004", 02:54:27 INFO - "stage_platform": "macosx64", 02:54:27 INFO - "basedir": "/builds/slave/test", 02:54:27 INFO - "nightly_build": "True", 02:54:27 INFO - "buildnumber": 74, 02:54:27 INFO - "slavename": "t-yosemite-r7-0005", 02:54:27 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:54:27 INFO - "platform": "yosemite_r7", 02:54:27 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:54:27 INFO - "branch": "mozilla-aurora", 02:54:27 INFO - "repo_path": "releases/mozilla-aurora", 02:54:27 INFO - "revision": "a891cb2fa60257c3a8cb84bb0356fba508e42f3e", 02:54:27 INFO - "pgo_build": "False", 02:54:27 INFO - "builduid": "b1e21b1400f94c1cb3f53e3f32612070", 02:54:27 INFO - "slavebuilddir": "test" 02:54:27 INFO - } 02:54:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:54:27 INFO - Running post-action listener: _resource_record_post_action 02:54:27 INFO - [mozharness: 2017-01-21 10:54:27.301407Z] Finished read-buildbot-config step (success) 02:54:27 INFO - [mozharness: 2017-01-21 10:54:27.301593Z] Running download-and-extract step. 02:54:27 INFO - Running pre-action listener: _resource_record_pre_action 02:54:27 INFO - Running main action method: download_and_extract 02:54:27 INFO - mkdir: /builds/slave/test/build/tests 02:54:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:54:27 INFO - trying https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:54:27 INFO - Downloading https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:54:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 02:54:28 INFO - Downloaded 1199 bytes. 02:54:28 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:54:28 INFO - Using the following test package requirements: 02:54:28 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:54:28 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:54:28 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:54:28 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:54:28 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:54:28 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:54:28 INFO - u'jsshell-mac.zip'], 02:54:28 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:54:28 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:54:28 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:54:28 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:54:28 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:54:28 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:54:28 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:54:28 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:54:28 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:54:28 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:54:28 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:54:28 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:54:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:54:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:54:28 INFO - Fetch https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:54:28 INFO - Http code: 200 02:54:28 INFO - via: 1.1 6be093c16fa776bbd432bbe9bd03f6af.cloudfront.net (CloudFront) 02:54:28 INFO - x-amz-cf-id: 73-jdfKms5TC4mNl14G4wi5c_vlcjcQA7sELeV8qiBJNU3H1P-6z4Q== 02:54:28 INFO - x-amz-version-id: 7nc01xrKXjjnsUkhhHFdbnxa_WrhpGuQ 02:54:29 INFO - Expected file size: 16277617 02:54:29 INFO - Obtained file size: 16277617 02:54:36 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:54:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:54:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:54:36 INFO - Fetch https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:54:36 INFO - Http code: 200 02:54:36 INFO - via: 1.1 b04a4cffa8fb4f524ff7edcab1b5ae31.cloudfront.net (CloudFront) 02:54:36 INFO - x-amz-cf-id: 6gH_P55-3fQJMGvcrVagmao3MvKV2BFzleSrB5vvWNh3eLItF1SCMA== 02:54:36 INFO - x-amz-version-id: 7nc01xrKXjjnsUkhhHFdbnxa_WrhpGuQ 02:54:37 INFO - Expected file size: 16277617 02:54:37 INFO - Obtained file size: 16277617 02:54:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:54:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:54:43 INFO - Fetch https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:54:44 INFO - Http code: 200 02:54:44 INFO - via: 1.1 296faebadd40feee8b2eb0e10d5786d2.cloudfront.net (CloudFront) 02:54:44 INFO - x-amz-cf-id: z8_uYulJkhXEeTKpv8Ngom9hoJ1Blk2gcrvNnEBN1xqVz4Q-0PladQ== 02:54:44 INFO - x-amz-version-id: pcx.roIIE.Y4lH6uyBsCu4xOvzDPpywD 02:54:44 INFO - Expected file size: 14402992 02:54:44 INFO - Obtained file size: 14402992 02:54:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:54:44 INFO - trying https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:54:44 INFO - Downloading https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:54:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 02:54:48 INFO - Downloaded 110780176 bytes. 02:54:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:54:48 INFO - mkdir: /builds/slave/test/properties 02:54:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:54:48 INFO - Writing to file /builds/slave/test/properties/build_url 02:54:48 INFO - Contents: 02:54:48 INFO - build_url:https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:54:48 INFO - Running post-action listener: _resource_record_post_action 02:54:48 INFO - Running post-action listener: set_extra_try_arguments 02:54:48 INFO - [mozharness: 2017-01-21 10:54:48.972897Z] Finished download-and-extract step (success) 02:54:48 INFO - [mozharness: 2017-01-21 10:54:48.973028Z] Running populate-webroot step. 02:54:48 INFO - Running pre-action listener: _resource_record_pre_action 02:54:48 INFO - Running main action method: populate_webroot 02:54:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:54:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:54:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:54:48 INFO - '--tppagecycles', 02:54:48 INFO - '7'], 02:54:48 INFO - 'tests': ['tsvgm']}}, 02:54:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:54:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:54:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:54:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:54:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:54:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:54:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:48 INFO - 'talos_options': ['--disable-e10s'], 02:54:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:54:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:54:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:48 INFO - 'talos_options': ['--disable-e10s'], 02:54:48 INFO - 'tests': ['damp', 'tps']}, 02:54:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:48 INFO - 'tests': ['damp', 'tps']}, 02:54:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:54:48 INFO - 'tests': ['dromaeo_dom']}, 02:54:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:54:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:54:48 INFO - 'tests': ['basic_compositor_video']}, 02:54:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:54:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:54:48 INFO - 'tests': ['a11yr', 02:54:48 INFO - 'ts_paint', 02:54:48 INFO - 'tpaint', 02:54:48 INFO - 'sessionrestore', 02:54:48 INFO - 'sessionrestore_no_auto_restore', 02:54:48 INFO - 'tabpaint']}, 02:54:48 INFO - 'other-e10s': {'tests': ['a11yr', 02:54:48 INFO - 'ts_paint', 02:54:48 INFO - 'tpaint', 02:54:48 INFO - 'sessionrestore', 02:54:48 INFO - 'sessionrestore_no_auto_restore', 02:54:48 INFO - 'tabpaint']}, 02:54:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:54:48 INFO - 'ts_paint', 02:54:48 INFO - 'tpaint', 02:54:48 INFO - 'sessionrestore', 02:54:48 INFO - 'sessionrestore_no_auto_restore', 02:54:48 INFO - 'tabpaint']}, 02:54:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:54:48 INFO - 'ts_paint', 02:54:48 INFO - 'tpaint', 02:54:48 INFO - 'sessionrestore', 02:54:48 INFO - 'sessionrestore_no_auto_restore', 02:54:48 INFO - 'tabpaint']}, 02:54:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:54:48 INFO - 'tests': ['a11yr', 02:54:48 INFO - 'ts_paint', 02:54:48 INFO - 'tpaint', 02:54:48 INFO - 'sessionrestore', 02:54:48 INFO - 'sessionrestore_no_auto_restore', 02:54:48 INFO - 'tabpaint']}, 02:54:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:54:48 INFO - 'tests': ['a11yr', 02:54:48 INFO - 'ts_paint', 02:54:48 INFO - 'tpaint', 02:54:48 INFO - 'sessionrestore', 02:54:48 INFO - 'sessionrestore_no_auto_restore', 02:54:48 INFO - 'tabpaint']}, 02:54:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:54:48 INFO - 'tests': ['tsvgx', 02:54:48 INFO - 'tsvgr_opacity', 02:54:48 INFO - 'tart', 02:54:48 INFO - 'tscrollx', 02:54:48 INFO - 'cart']}, 02:54:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:54:48 INFO - 'tsvgr_opacity', 02:54:48 INFO - 'tart', 02:54:48 INFO - 'tscrollx', 02:54:48 INFO - 'cart']}, 02:54:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:48 INFO - 'talos_options': ['--disable-e10s'], 02:54:48 INFO - 'tests': ['tp5o']}, 02:54:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:48 INFO - 'tests': ['tp5o']}, 02:54:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:48 INFO - 'talos_options': ['--disable-e10s', 02:54:48 INFO - '--xperf_path', 02:54:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:54:48 INFO - 'tests': ['tp5n']}, 02:54:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:48 INFO - 'talos_options': ['--xperf_path', 02:54:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:54:48 INFO - 'tests': ['tp5n']}}, 02:54:48 INFO - 'talos.zip': {'path': '', 02:54:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:54:48 INFO - Running post-action listener: _resource_record_post_action 02:54:48 INFO - [mozharness: 2017-01-21 10:54:48.991727Z] Finished populate-webroot step (success) 02:54:48 INFO - [mozharness: 2017-01-21 10:54:48.991856Z] Running create-virtualenv step. 02:54:48 INFO - Running pre-action listener: _resource_record_pre_action 02:54:48 INFO - Running main action method: create_virtualenv 02:54:48 INFO - Creating virtualenv /builds/slave/test/build/venv 02:54:48 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:54:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:54:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:54:49 INFO - Using real prefix '/tools/python27' 02:54:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:54:49 INFO - Installing distribute.............................................................................................................................................................................................done. 02:54:52 INFO - Installing pip.................done. 02:54:52 INFO - Return code: 0 02:54:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:54:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:54:52 INFO - Reading from file tmpfile_stdout 02:54:52 INFO - Output received: 02:54:52 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:54:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:54:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:54:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:54:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:54:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:52 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 0x104978490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a9a4770a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10478cd30>, '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 0x1045f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104784b20>, '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.b9Ad1QhwM9/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.8v6tfFhstf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:54:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:54:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:54:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9Ad1QhwM9/Render', 02:54:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:54:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:54:52 INFO - 'HOME': '/Users/cltbld', 02:54:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:54:52 INFO - 'LOGNAME': 'cltbld', 02:54:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:54:52 INFO - 'MOZ_NO_REMOTE': '1', 02:54:52 INFO - 'NO_EM_RESTART': '1', 02:54:52 INFO - 'PAGER': '/bin/cat', 02:54:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:54:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:54:52 INFO - 'PWD': '/builds/slave/test', 02:54:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:54:52 INFO - 'SHELL': '/bin/bash', 02:54:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8v6tfFhstf/Listeners', 02:54:52 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 02:54:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:54:52 INFO - 'USER': 'cltbld', 02:54:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:54:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:54:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:54:52 INFO - 'XPC_FLAGS': '0x0', 02:54:52 INFO - 'XPC_SERVICE_NAME': '0', 02:54:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:54:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:54:53 INFO - Cleaning up... 02:54:53 INFO - Return code: 0 02:54:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:54:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:54:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:54:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:53 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 0x104978490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a9a4770a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10478cd30>, '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 0x1045f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104784b20>, '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.b9Ad1QhwM9/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.8v6tfFhstf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:54:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:54:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:54:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9Ad1QhwM9/Render', 02:54:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:54:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:54:53 INFO - 'HOME': '/Users/cltbld', 02:54:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:54:53 INFO - 'LOGNAME': 'cltbld', 02:54:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:54:53 INFO - 'MOZ_NO_REMOTE': '1', 02:54:53 INFO - 'NO_EM_RESTART': '1', 02:54:53 INFO - 'PAGER': '/bin/cat', 02:54:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:54:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:54:53 INFO - 'PWD': '/builds/slave/test', 02:54:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:54:53 INFO - 'SHELL': '/bin/bash', 02:54:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8v6tfFhstf/Listeners', 02:54:53 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 02:54:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:54:53 INFO - 'USER': 'cltbld', 02:54:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:54:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:54:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:54:53 INFO - 'XPC_FLAGS': '0x0', 02:54:53 INFO - 'XPC_SERVICE_NAME': '0', 02:54:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:54:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:54:53 INFO - Downloading/unpacking psutil>=3.1.1 02:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:54:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:54:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:54:57 INFO - Installing collected packages: psutil 02:54:57 INFO - Running setup.py install for psutil 02:54:57 INFO - building 'psutil._psutil_osx' extension 02:54:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:54:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:54:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:54:58 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:54:58 INFO - building 'psutil._psutil_posix' extension 02:54:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:54:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:54:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:54:58 INFO - ^ 02:54:58 INFO - 1 warning generated. 02:54:58 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:54:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:54:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:54:58 INFO - Successfully installed psutil 02:54:58 INFO - Cleaning up... 02:54:58 INFO - Return code: 0 02:54:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:54:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:54:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:54:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:54:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:58 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 0x104978490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a9a4770a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10478cd30>, '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 0x1045f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104784b20>, '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.b9Ad1QhwM9/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.8v6tfFhstf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:54:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:54:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:54:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9Ad1QhwM9/Render', 02:54:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:54:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:54:58 INFO - 'HOME': '/Users/cltbld', 02:54:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:54:58 INFO - 'LOGNAME': 'cltbld', 02:54:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:54:58 INFO - 'MOZ_NO_REMOTE': '1', 02:54:58 INFO - 'NO_EM_RESTART': '1', 02:54:58 INFO - 'PAGER': '/bin/cat', 02:54:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:54:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:54:58 INFO - 'PWD': '/builds/slave/test', 02:54:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:54:58 INFO - 'SHELL': '/bin/bash', 02:54:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8v6tfFhstf/Listeners', 02:54:58 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 02:54:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:54:58 INFO - 'USER': 'cltbld', 02:54:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:54:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:54:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:54:58 INFO - 'XPC_FLAGS': '0x0', 02:54:58 INFO - 'XPC_SERVICE_NAME': '0', 02:54:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:54:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:54:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:55:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:55:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:55:01 INFO - Installing collected packages: mozsystemmonitor 02:55:01 INFO - Running setup.py install for mozsystemmonitor 02:55:01 INFO - Successfully installed mozsystemmonitor 02:55:01 INFO - Cleaning up... 02:55:01 INFO - Return code: 0 02:55:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:55:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104978490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a9a4770a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10478cd30>, '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 0x1045f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104784b20>, '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.b9Ad1QhwM9/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.8v6tfFhstf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:55:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:55:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9Ad1QhwM9/Render', 02:55:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:01 INFO - 'HOME': '/Users/cltbld', 02:55:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:01 INFO - 'LOGNAME': 'cltbld', 02:55:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:01 INFO - 'MOZ_NO_REMOTE': '1', 02:55:01 INFO - 'NO_EM_RESTART': '1', 02:55:01 INFO - 'PAGER': '/bin/cat', 02:55:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:01 INFO - 'PWD': '/builds/slave/test', 02:55:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:01 INFO - 'SHELL': '/bin/bash', 02:55:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8v6tfFhstf/Listeners', 02:55:01 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 02:55:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:01 INFO - 'USER': 'cltbld', 02:55:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:01 INFO - 'XPC_FLAGS': '0x0', 02:55:01 INFO - 'XPC_SERVICE_NAME': '0', 02:55:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:02 INFO - Downloading/unpacking jsonschema==2.5.1 02:55: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 02:55: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 02:55: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 02:55: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 02:55: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 02:55: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 02:55:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:55:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:55:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:05 INFO - Downloading functools32-3.2.3-2.tar.gz 02:55:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:55:05 INFO - warning: no files found matching '*.txt' 02:55:05 INFO - no previously-included directories found matching 'build' 02:55:05 INFO - no previously-included directories found matching 'dist' 02:55:05 INFO - no previously-included directories found matching '.git*' 02:55:05 INFO - Installing collected packages: jsonschema, functools32 02:55:05 INFO - Running setup.py install for functools32 02:55:05 INFO - warning: no files found matching '*.txt' 02:55:05 INFO - no previously-included directories found matching 'build' 02:55:05 INFO - no previously-included directories found matching 'dist' 02:55:05 INFO - no previously-included directories found matching '.git*' 02:55:05 INFO - Successfully installed jsonschema functools32 02:55:05 INFO - Cleaning up... 02:55:05 INFO - Return code: 0 02:55:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:55: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')]} 02:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55: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')]} 02:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55: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', '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 0x104978490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a9a4770a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10478cd30>, '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 0x1045f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104784b20>, '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.b9Ad1QhwM9/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.8v6tfFhstf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:55: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 functools32==3.2.3-2 02:55:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9Ad1QhwM9/Render', 02:55:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:05 INFO - 'HOME': '/Users/cltbld', 02:55:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:05 INFO - 'LOGNAME': 'cltbld', 02:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:05 INFO - 'MOZ_NO_REMOTE': '1', 02:55:05 INFO - 'NO_EM_RESTART': '1', 02:55:05 INFO - 'PAGER': '/bin/cat', 02:55:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:05 INFO - 'PWD': '/builds/slave/test', 02:55:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:05 INFO - 'SHELL': '/bin/bash', 02:55:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8v6tfFhstf/Listeners', 02:55:05 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 02:55:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:05 INFO - 'USER': 'cltbld', 02:55:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:05 INFO - 'XPC_FLAGS': '0x0', 02:55:05 INFO - 'XPC_SERVICE_NAME': '0', 02:55:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:55:05 INFO - Cleaning up... 02:55:05 INFO - Return code: 0 02:55:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:55: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')]} 02:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55: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')]} 02:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55: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', '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 0x104978490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a9a4770a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10478cd30>, '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 0x1045f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104784b20>, '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.b9Ad1QhwM9/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.8v6tfFhstf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:55: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 blobuploader==1.2.4 02:55:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9Ad1QhwM9/Render', 02:55:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:05 INFO - 'HOME': '/Users/cltbld', 02:55:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:05 INFO - 'LOGNAME': 'cltbld', 02:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:05 INFO - 'MOZ_NO_REMOTE': '1', 02:55:05 INFO - 'NO_EM_RESTART': '1', 02:55:05 INFO - 'PAGER': '/bin/cat', 02:55:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:05 INFO - 'PWD': '/builds/slave/test', 02:55:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:05 INFO - 'SHELL': '/bin/bash', 02:55:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8v6tfFhstf/Listeners', 02:55:05 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 02:55:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:05 INFO - 'USER': 'cltbld', 02:55:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:05 INFO - 'XPC_FLAGS': '0x0', 02:55:05 INFO - 'XPC_SERVICE_NAME': '0', 02:55:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:05 INFO - Downloading/unpacking blobuploader==1.2.4 02:55: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 02:55: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 02:55: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 02:55: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 02:55: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 02:55: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 02:55:08 INFO - Downloading blobuploader-1.2.4.tar.gz 02:55:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:55:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:55: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 02:55: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 02:55: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 02:55: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 02:55: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 02:55: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 02:55:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:55:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:55: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 02:55: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 02:55: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 02:55: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 02:55: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 02:55: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 02:55:09 INFO - Downloading docopt-0.6.1.tar.gz 02:55:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:55:09 INFO - Installing collected packages: blobuploader, requests, docopt 02:55:09 INFO - Running setup.py install for blobuploader 02:55:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:55:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:55:10 INFO - Running setup.py install for requests 02:55:10 INFO - Running setup.py install for docopt 02:55:10 INFO - Successfully installed blobuploader requests docopt 02:55:10 INFO - Cleaning up... 02:55:10 INFO - Return code: 0 02:55:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:10 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 0x104978490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a9a4770a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10478cd30>, '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 0x1045f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104784b20>, '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.b9Ad1QhwM9/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.8v6tfFhstf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:55:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:55:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9Ad1QhwM9/Render', 02:55:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:10 INFO - 'HOME': '/Users/cltbld', 02:55:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:10 INFO - 'LOGNAME': 'cltbld', 02:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:10 INFO - 'MOZ_NO_REMOTE': '1', 02:55:10 INFO - 'NO_EM_RESTART': '1', 02:55:10 INFO - 'PAGER': '/bin/cat', 02:55:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:10 INFO - 'PWD': '/builds/slave/test', 02:55:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:10 INFO - 'SHELL': '/bin/bash', 02:55:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8v6tfFhstf/Listeners', 02:55:10 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 02:55:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:10 INFO - 'USER': 'cltbld', 02:55:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:10 INFO - 'XPC_FLAGS': '0x0', 02:55:10 INFO - 'XPC_SERVICE_NAME': '0', 02:55:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:55:11 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-h5Jv2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:55:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:55:11 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-mQgmhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:55:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:55:11 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-opVwZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:55:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:55:11 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-urdJQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:55:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:55:11 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-1cLBpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:55:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:55:11 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-ZaY6Uj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:55:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:55:11 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-JdMiei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:55:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:55:11 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-P5Tkrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:55:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:55:11 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-1PG0hZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:55:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Ktugmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:55:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-5Ax12q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:55:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-6tQvYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:55:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-xrKRd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:55:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-6f1HEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:55:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-XJG_Cg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:55:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-SSWWIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:55:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-TMW709-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:55:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:55:12 INFO - Running setup.py install for manifestparser 02:55:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:55:13 INFO - Running setup.py install for mozcrash 02:55:13 INFO - Running setup.py install for mozdebug 02:55:13 INFO - Running setup.py install for mozdevice 02:55:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:55:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:55:13 INFO - Running setup.py install for mozfile 02:55:13 INFO - Running setup.py install for mozhttpd 02:55:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:55:13 INFO - Running setup.py install for mozinfo 02:55:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:55:13 INFO - Running setup.py install for mozInstall 02:55:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:55:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:55:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:55:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:55:14 INFO - Running setup.py install for mozleak 02:55:14 INFO - Running setup.py install for mozlog 02:55:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:55:14 INFO - Running setup.py install for moznetwork 02:55:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:55:14 INFO - Running setup.py install for mozprocess 02:55:14 INFO - Running setup.py install for mozprofile 02:55:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:55:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:55:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:55:14 INFO - Running setup.py install for mozrunner 02:55:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:55:14 INFO - Running setup.py install for mozscreenshot 02:55:15 INFO - Running setup.py install for moztest 02:55:15 INFO - Running setup.py install for mozversion 02:55:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:55:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:55:15 INFO - Cleaning up... 02:55:15 INFO - Return code: 0 02:55:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:55:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:15 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 0x104978490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a9a4770a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10478cd30>, '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 0x1045f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104784b20>, '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.b9Ad1QhwM9/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.8v6tfFhstf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:55:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:55:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9Ad1QhwM9/Render', 02:55:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:15 INFO - 'HOME': '/Users/cltbld', 02:55:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:15 INFO - 'LOGNAME': 'cltbld', 02:55:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:15 INFO - 'MOZ_NO_REMOTE': '1', 02:55:15 INFO - 'NO_EM_RESTART': '1', 02:55:15 INFO - 'PAGER': '/bin/cat', 02:55:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:15 INFO - 'PWD': '/builds/slave/test', 02:55:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:15 INFO - 'SHELL': '/bin/bash', 02:55:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8v6tfFhstf/Listeners', 02:55:15 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 02:55:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:15 INFO - 'USER': 'cltbld', 02:55:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:15 INFO - 'XPC_FLAGS': '0x0', 02:55:15 INFO - 'XPC_SERVICE_NAME': '0', 02:55:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:55:15 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-K307wT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:55:15 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:55:15 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-TTX84r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:55:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:55:15 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-nN0DlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:55:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:55:16 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Occsqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:55:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:55:16 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-4TVr1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:55:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:55:16 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-zHf17G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:55:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:55:16 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-KOy4Rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:55:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:55:16 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-mSXC2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:55:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:55:16 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-JUSrNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:55:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:55:16 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-DoSotz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:55:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:55:16 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-KLjFI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:55:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:55:16 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-TxNrgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:55:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:55:17 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-IENNfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:55:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:55:17 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-L99ZC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:55:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:55:17 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-UIoqt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:55:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:55:17 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-LPEy_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:55:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:55:17 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-KwwpkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:55:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:55:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:55:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:55:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:55:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:55:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:55:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:55:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:20 INFO - Downloading blessings-1.6.tar.gz 02:55:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:55:20 INFO - Installing collected packages: blessings 02:55:20 INFO - Running setup.py install for blessings 02:55:20 INFO - Successfully installed blessings 02:55:20 INFO - Cleaning up... 02:55:20 INFO - Return code: 0 02:55:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:55:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:55:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:55:20 INFO - Reading from file tmpfile_stdout 02:55:20 INFO - Current package versions: 02:55:20 INFO - blessings == 1.6 02:55:20 INFO - blobuploader == 1.2.4 02:55:20 INFO - docopt == 0.6.1 02:55:20 INFO - functools32 == 3.2.3-2 02:55:20 INFO - jsonschema == 2.5.1 02:55:20 INFO - manifestparser == 1.1 02:55:20 INFO - mozInstall == 1.12 02:55:20 INFO - mozcrash == 1.0 02:55:20 INFO - mozdebug == 0.1 02:55:20 INFO - mozdevice == 0.48 02:55:20 INFO - mozfile == 1.2 02:55:20 INFO - mozhttpd == 0.7 02:55:20 INFO - mozinfo == 0.9 02:55:20 INFO - mozleak == 0.1 02:55:20 INFO - mozlog == 3.4 02:55:20 INFO - moznetwork == 0.27 02:55:20 INFO - mozprocess == 0.23 02:55:20 INFO - mozprofile == 0.28 02:55:20 INFO - mozrunner == 6.13 02:55:20 INFO - mozscreenshot == 0.1 02:55:20 INFO - mozsystemmonitor == 0.3 02:55:20 INFO - moztest == 0.8 02:55:20 INFO - mozversion == 1.4 02:55:20 INFO - psutil == 3.1.1 02:55:20 INFO - requests == 1.2.3 02:55:20 INFO - wsgiref == 0.1.2 02:55:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:20 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 0x104978490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a9a4770a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10478cd30>, '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 0x1045f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104784b20>, '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.b9Ad1QhwM9/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.8v6tfFhstf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:55:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:55:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9Ad1QhwM9/Render', 02:55:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:20 INFO - 'HOME': '/Users/cltbld', 02:55:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:20 INFO - 'LOGNAME': 'cltbld', 02:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:20 INFO - 'MOZ_NO_REMOTE': '1', 02:55:20 INFO - 'NO_EM_RESTART': '1', 02:55:20 INFO - 'PAGER': '/bin/cat', 02:55:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:20 INFO - 'PWD': '/builds/slave/test', 02:55:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:20 INFO - 'SHELL': '/bin/bash', 02:55:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8v6tfFhstf/Listeners', 02:55:20 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 02:55:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:20 INFO - 'USER': 'cltbld', 02:55:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:20 INFO - 'XPC_FLAGS': '0x0', 02:55:20 INFO - 'XPC_SERVICE_NAME': '0', 02:55:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:55:20 INFO - Cleaning up... 02:55:21 INFO - Return code: 0 02:55:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:55:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:21 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 0x104978490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a9a4770a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10478cd30>, '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 0x1045f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104784b20>, '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.b9Ad1QhwM9/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.8v6tfFhstf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:55:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:55:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9Ad1QhwM9/Render', 02:55:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:21 INFO - 'HOME': '/Users/cltbld', 02:55:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:21 INFO - 'LOGNAME': 'cltbld', 02:55:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:21 INFO - 'MOZ_NO_REMOTE': '1', 02:55:21 INFO - 'NO_EM_RESTART': '1', 02:55:21 INFO - 'PAGER': '/bin/cat', 02:55:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:21 INFO - 'PWD': '/builds/slave/test', 02:55:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:21 INFO - 'SHELL': '/bin/bash', 02:55:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8v6tfFhstf/Listeners', 02:55:21 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 02:55:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:21 INFO - 'USER': 'cltbld', 02:55:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:21 INFO - 'XPC_FLAGS': '0x0', 02:55:21 INFO - 'XPC_SERVICE_NAME': '0', 02:55:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:55:21 INFO - Cleaning up... 02:55:21 INFO - Return code: 0 02:55:21 INFO - Running post-action listener: _resource_record_post_action 02:55:21 INFO - Running post-action listener: _start_resource_monitoring 02:55:21 INFO - Starting resource monitoring. 02:55:21 INFO - [mozharness: 2017-01-21 10:55:21.299163Z] Finished create-virtualenv step (success) 02:55:21 INFO - [mozharness: 2017-01-21 10:55:21.300150Z] Running install step. 02:55:21 INFO - Running pre-action listener: _resource_record_pre_action 02:55:21 INFO - Running main action method: install 02:55:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:55:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:55:21 INFO - Reading from file tmpfile_stdout 02:55:21 INFO - Detecting whether we're running mozinstall >=1.0... 02:55:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:55:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:55:21 INFO - Reading from file tmpfile_stdout 02:55:21 INFO - Output received: 02:55:21 INFO - Usage: mozinstall [options] installer 02:55:21 INFO - Options: 02:55:21 INFO - -h, --help show this help message and exit 02:55:21 INFO - -d DEST, --destination=DEST 02:55:21 INFO - Directory to install application into. [default: 02:55:21 INFO - "/builds/slave/test"] 02:55:21 INFO - --app=APP Application being installed. [default: firefox] 02:55:21 INFO - mkdir: /builds/slave/test/build/application 02:55:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:55:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:55:52 INFO - Reading from file tmpfile_stdout 02:55:52 INFO - Output received: 02:55:52 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:55:52 INFO - Running post-action listener: _resource_record_post_action 02:55:52 INFO - [mozharness: 2017-01-21 10:55:52.843727Z] Finished install step (success) 02:55:52 INFO - [mozharness: 2017-01-21 10:55:52.843890Z] Running run-tests step. 02:55:52 INFO - Running pre-action listener: _resource_record_pre_action 02:55:52 INFO - Running main action method: run_tests 02:55:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:55:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:55:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:55:52 INFO - 2017-01-21 02:55:52.858 screenresolution[2019:28818] starting screenresolution argv=screenresolution get 02:55:52 INFO - 2017-01-21 02:55:52.861 screenresolution[2019:28818] Display 0: 1600x1200x32@60 02:55:52 INFO - 2017-01-21 02:55:52.866 screenresolution[2020:28820] starting screenresolution argv=screenresolution list 02:55:52 INFO - Available Modes on Display 0 02:55:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:55:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:55:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:55:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:55:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:55:53 INFO - Intel Iris: 02:55:53 INFO - Chipset Model: Intel Iris 02:55:53 INFO - Type: GPU 02:55:53 INFO - Bus: Built-In 02:55:53 INFO - VRAM (Dynamic, Max): 1536 MB 02:55:53 INFO - Vendor: Intel (0x8086) 02:55:53 INFO - Device ID: 0x0a2e 02:55:53 INFO - Revision ID: 0x0009 02:55:53 INFO - Displays: 02:55:53 INFO - Display: 02:55:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:55:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:55:53 INFO - Display Serial Number: 1600x1200 60 02:55:53 INFO - Main Display: Yes 02:55:53 INFO - Mirror: Off 02:55:53 INFO - Online: Yes 02:55:53 INFO - Rotation: Supported 02:55:53 INFO - Return code: 0 02:55:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:55:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:55:53 INFO - Python 2.7.3 02:55:53 INFO - Return code: 0 02:55:53 INFO - grabbing minidump binary from tooltool 02:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:53 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 0x10478cd30>, '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 0x1045f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104784b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:55:53 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:55:53 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:55:53 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:55:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:55:53 INFO - Return code: 0 02:55:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:55:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:55:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:55:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:55:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:55:53 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/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:55:53 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/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0005 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:55:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9Ad1QhwM9/Render', 02:55:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:53 INFO - 'HOME': '/Users/cltbld', 02:55:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:53 INFO - 'LOGNAME': 'cltbld', 02:55:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:55:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:53 INFO - 'MOZ_NO_REMOTE': '1', 02:55:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:55:53 INFO - 'NO_EM_RESTART': '1', 02:55:53 INFO - 'PAGER': '/bin/cat', 02:55:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:53 INFO - 'PWD': '/builds/slave/test', 02:55:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:55:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:53 INFO - 'SHELL': '/bin/bash', 02:55:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8v6tfFhstf/Listeners', 02:55:53 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 02:55:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:53 INFO - 'USER': 'cltbld', 02:55:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:53 INFO - 'XPC_FLAGS': '0x0', 02:55:53 INFO - 'XPC_SERVICE_NAME': '0', 02:55:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:53 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/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:55:53 INFO - mozversion application_buildid: 20170121004004 02:55:53 INFO - mozversion application_changeset: a891cb2fa60257c3a8cb84bb0356fba508e42f3e 02:55:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:55:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:55:53 INFO - mozversion application_name: Firefox 02:55:53 INFO - mozversion application_remotingname: firefox-dev 02:55:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:55:53 INFO - mozversion application_vendor: Mozilla 02:55:53 INFO - mozversion application_version: 52.0a2 02:55:53 INFO - mozversion platform_buildid: 20170121004004 02:55:53 INFO - mozversion platform_changeset: a891cb2fa60257c3a8cb84bb0356fba508e42f3e 02:55:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:55:53 INFO - mozversion platform_version: 52.0a2 02:55:53 INFO - using testdate: 1484996153 02:55:53 INFO - actual date: 1484996153 02:55:53 INFO - starting webserver on 'localhost:56945' 02:55:53 INFO - SUITE-START | Running 2 tests 02:55:53 INFO - TEST-START | dromaeo_css 02:55:53 INFO - operating with platform_type : mac_ 02:55:53 INFO - Initialising browser for dromaeo_css test... 02:55:53 INFO - TEST-INFO | started process 2030 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpKmyymb/profile http://localhost:56945/getInfo.html) 02:55:56 INFO - PROCESS | 2030 | 2017-01-21 02:55:56.624 plugin-container[2033:29002] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a43, name = 'com.apple.tsm.portname' 02:55:56 INFO - PROCESS | 2030 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:55:56 INFO - PROCESS | 2030 | 2017-01-21 02:55:56.624 plugin-container[2033:29002] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d0b, name = 'com.apple.CFPasteboardClient' 02:55:56 INFO - PROCESS | 2030 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:55:56 INFO - PROCESS | 2030 | 2017-01-21 02:55:56.624 plugin-container[2033:29002] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:55:56 INFO - PROCESS | 2030 | __metrics Screen width/height:1600/1200 02:55:56 INFO - PROCESS | 2030 | colorDepth:24 02:55:56 INFO - PROCESS | 2030 | Browser inner width/height: 1024/689 02:55:56 INFO - PROCESS | 2030 | __metrics 02:55:56 ERROR - PROCESS | 2030 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 02:55:57 INFO - TEST-INFO | 2030: exit 0 02:55:57 INFO - Browser initialized. 02:55:57 INFO - Running cycle 1/1 for dromaeo_css test... 02:55:57 INFO - TEST-INFO | started process 2034 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpKmyymb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:56:00 INFO - PROCESS | 2034 | 2017-01-21 02:56:00.097 plugin-container[2035:29135] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 02:56:00 INFO - PROCESS | 2034 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:56:00 INFO - PROCESS | 2034 | 2017-01-21 02:56:00.099 plugin-container[2035:29135] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 02:56:00 INFO - PROCESS | 2034 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:56:00 INFO - PROCESS | 2034 | 2017-01-21 02:56:00.099 plugin-container[2035:29135] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:56:09 INFO - PROCESS | 2034 | RSS: Main: 228937728 02:56:09 INFO - PROCESS | 2034 | 02:59:11 INFO - PROCESS | 2034 | Cycle 1(1): loaded http://localhost:56945/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56945/tests/dromaeo/cssquery-ext.html) 02:59:13 INFO - PROCESS | 2034 | RSS: Main: 233947136 02:59:13 INFO - PROCESS | 2034 | 03:02:15 INFO - PROCESS | 2034 | Cycle 1(1): loaded http://localhost:56945/tests/dromaeo/cssquery-ext.html (next: http://localhost:56945/tests/dromaeo/cssquery-jquery.html) 03:02:16 INFO - PROCESS | 2034 | RSS: Main: 246816768 03:02:16 INFO - PROCESS | 2034 | 03:05:18 INFO - PROCESS | 2034 | Cycle 1(1): loaded http://localhost:56945/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56945/tests/dromaeo/cssquery-mootools.html) 03:05:19 INFO - PROCESS | 2034 | RSS: Main: 249737216 03:05:19 INFO - PROCESS | 2034 | 03:08:21 INFO - PROCESS | 2034 | Cycle 1(1): loaded http://localhost:56945/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56945/tests/dromaeo/cssquery-prototype.html) 03:08:21 INFO - PROCESS | 2034 | RSS: Main: 249147392 03:08:21 INFO - PROCESS | 2034 | 03:11:24 INFO - PROCESS | 2034 | Cycle 1(1): loaded http://localhost:56945/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56945/tests/dromaeo/cssquery-yui.html) 03:11:25 INFO - PROCESS | 2034 | RSS: Main: 252571648 03:11:25 INFO - PROCESS | 2034 | 03:14:27 INFO - PROCESS | 2034 | Cycle 1(1): loaded http://localhost:56945/tests/dromaeo/cssquery-yui.html (next: http://localhost:56945/tests/dromaeo/cssquery-dojo.html) 03:14:27 INFO - PROCESS | 2034 | RSS: Main: 253886464 03:14:27 INFO - PROCESS | 2034 | 03:14:27 INFO - PROCESS | 2034 | __start_tp_report 03:14:27 INFO - PROCESS | 2034 | _x_x_mozilla_page_load 03:14:27 INFO - PROCESS | 2034 | _x_x_mozilla_page_load_details 03:14:27 INFO - PROCESS | 2034 | |i|pagename|runs| 03:14:27 INFO - PROCESS | 2034 | |0;dojo.html;3357.5803024621923;3359.8824041158564;3374.0721301642047;3380.154961259685;3380.5098260752206;5600.131979543172;5605.495505404513;5644.266245388104;5659.264295641566;5669.262995810544;4626.4910859805395;5530.976769297673;5578.525825304854;5628.690422026787;5681.346645135811;5161.27742116104;5190.143626301656;5200.947990520084;5250.186221135711;5258.0535503609335;5242.370915490127;5244.632875698702;5250.18622113573;5262.947370526284;5273.446288139748;5133.281340612317;5163;5223.399309079447;5228.503292187236;5284.709340986245;3776.716746244029;5193.195054766498;5289.44460831611;5299.231611416323;5341.03861304965;5199.090159222159;5211.062008838408;5245.10833158364;5248.448912864133;5254.343207099113;5548.112302031655;5725.341585717612;5733.541316694655;5820.35976042635;5820.388859169769;6109.28320792043;6112.412294442025;6127.830981140894;6133.478654314388;6151.87696246077;6205.968970155165;6255.655938923357;6256.906146407808;6274.121622972787;6279.811605651792;5799.2750906136735;5803.361630220715;5803.82588522345;5808.912866307009;5841.977653910549;5022.422421421583;5158.97420512895;6175.505959523227;6182.196314479179;6243.219597550306;6099.969500152471;6129.11127886454;6140.508759299245;6140.6315621061985;6165.630062196193;6102.206713127265;6206.596571222946;6258.561900666909;6280.874382512416;6284.057391391237;5797.826065218051;6029.6683682397015;6107.816765497042;6112.602680825732;6124.594542075475;6111.236095488064;6149.41580549847;6159.784407545804;6165.198524191826;6172.321044685081;4920.114379411741;4965;4967.602591792648;4972.378452693413;4992.151334273182;4314.741115533078;4351.673624478113;4352.347147927836;4400.889977750518;4462.129884672523;5693.601447898607;5830.562707796932;5856.385079566669;5862.973979553561;5877.23595932526;6151.5386346024225;6183.072539119168;6188.659623720649;6215.285242197115;6231.532635052298;5638.548916086703;5689.175069614883;5788.942110578924;5809.448102430262;5824.825255242349;5447.073997420448;5449.1826226066405;5449.700266485382;5451.863703407367;5460.945390546044;6291.905621415683;6296.748130074746;6336.96831515858;6337.207849018872;6342.302346741947;4982.451930287593;5020.945601423742;5683.715814209356;6125.418085281891;6176.5985210962945;6294.748210071546;6317.6209427434505;6335.3981371769605;6343.3022367538615;6352.015437607179;6155.261368635793;6184.8763024738355;6206;6225.159603453476;6228.252609686867;6049.364816694184;6063.575549711478;6071.969640151771;6112.541559382975;6290.1508296381235;4603.355530225704;5200.791968321225;6071.939280607137;6136.2636483622255;6143.723532440961;6032.517398608013;6048.879022419439;6059.969700151471;6083.270007599116;6088.995315772848;5702.857428564319;5840.8247752567495;5843.474087332161;5893.734781934738;5952.136940143741;6024.66864322449;6061.424164704346;6105.389461053859;6111.5110791137495;6137.84655383619;5943.197668314722;5971.223740913827;5980.192673988956;5996.520278377807;5997.13041608973 03:14:27 INFO - PROCESS | 2034 | |1;ext.html;464.58883887759333;482.12975579079745;490.9877253068675;497.2292945933802;508.51182864450124;32208.67791322089;38288.234235315314;40251.396229056554;42449.93875153123;42507.14985700284;41665.37501937473;42093.947651308736;42125.78937105318;42168.789156054256;42277.577224227745;9341.299402544802;9485.383450075757;9505.429674219535;9527.23782097434;9530.80938381232;8184.181581841828;9469.81060378792;9501.524923753785;9509.191718703918;9543.236541076732;9482.905170948306;9503.80992380152;9549.331546791727;9555.140037396633;9568.138867501924;9476.28927830412;9523.19052880506;9535.904640953571;9539.761505962337;9550.904490955105;9194.310426718022;9339.486328251944;9348.57931393089;9352.906470935271;9374.125174965035;39335.21329573393;39393.80303098495;39655.40516892249;39670.603293967266;39773.00567485807;3140.811551306929;3158.210447388153;3277.4592192288483;3901.843926242947;3967.761934283952;29371;29490.55764163518;30593.69406305953;31191.887551793192;31485.842570787;3101.922451938697;3215.614126304835;3236.676332366768;3293.0943990402593;3358.6641335866216;350414;416003;426642;428770;436978.9999999968;61961;62659.746805063645;62747;62787.6860615694;62815;58896;59235.815283694086;59504.404955950755;59554.70222648859;59679.70160149172;16166.838331616533;16295.429659961845;16372.181390930406;16384.75422868658;16395.672086558203;12385.380730963596;12394.070444716683;12411.937940310241;12430.005599552014;12441.75116497665;18026.97498920926;18690.532736681693;18744.062796860104;18744.531386715167;18988.24047038103;7651.923480765121;7754.302112809874;7851.489653172525;7858.960705196552;7903;810.8743144812563;949.7863725008549;961.9711408657752;1053.536443964653;1060.1677683018793;28248.85875570609;28593.857030714713;29918;29975.287974809748;29999.70000299969;796.9880451793227;1021.3718563083798;1024.3456817240103;1027.337367398024;1066.99466502667;14615.488457903923;15256;15392.538223853302;15441.382344706086;15621.453249136453;673605;683631;683764;683920;687302;48560.02879942452;56511.15233271504;57126.42873571294;57257.42742572521;57363.42636573665;1167.7430965187646;1321.4648067532664;1346.4412268908488;1346.7845144776984;1349.2039696579066;23547;28235.294117647223;29193.562096568145;29754.55368169436;30024.24939376533;49635.25547116724;49666.50333496619;49986.00027999347;50133.498665012885;50140.247896282315;577586;583328;584876;586347;588012;21833.344999650035;26101.477970440104;26593.734062659125;26607.20178394651;26607.866960665073;45466.54533454623;45806.54193458023;45977.77011114923;46242.53757462382;46285.30572041355;38318.616813831504;38422.80788596039;39099.82700518989;39319;39550.80224598858;37429.064273393386;37596.436053458674;37990.620093799815;38915.6108438912;39145.021374464726;32377;32778.01665950025;34886.65113348936;36969.70606028776;39032.41451378288;33137;34828.43052044428;34837.65162348344;35024.824875875456;35100.64899350974;25711;25750.87124564365;25770.613440798777;25801.612975805;26193.607095893196 03:14:27 INFO - PROCESS | 2034 | |2;jquery.html;368.97786132832016;377.70538979595915;386.325861371906;398.02285389369104;405.66127283718095;18146.909265453673;19097.331593394218;19443.805561944413;19448.513787155327;19535;19587.118579663915;19853.404397868086;19885;20266.392008239778;20268.39194824154;4865.7105866945285;5627.761892383669;5807.999999999989;5899.292084949812;5912.763489460416;5855.76576936922;5894.292684877821;5921.230240068785;5962;5979.701014949257;5634.154876768476;5787.47912687858;5835.591508594379;5850.297964244297;5886.88226235475;5782.681952492611;5795.188673585681;5808.041673123929;5823.446772556643;5830.4461076197695;4803.687760295569;4909.754512274372;5052.797888084509;5329.413764485904;5408.432114627947;10479.58081676732;12458.377081145907;12610.621681349574;12629.747405051847;12765.106442549026;2193.9890300548554;2261.683364328995;2268.5576312619046;2340.2745149003695;2770.819896706708;7739.806504837367;7949.880751788728;8502.532360720157;9923.511473278993;13401.592832752616;1518.9772153417707;1761.3747119772552;1766.7349897515346;1833.60577475842;1850.4541160357726;103661.96338036674;106447;114496.42751786271;127898;129898;10612.20408469368;11613.419329033515;11681.415929203677;11971.760564788656;12197.634070977885;14407.495737649131;14424.350904209336;14442.566722998326;14459.710805784036;14461.638459038608;8132.634031468599;8338.458000230086;8397.6221070052;8470.957645211734;8516.361272904556;6845.8973115403305;8224.259816616533;8247.381446391544;8262.669493220204;8412.116727743622;4438.622717069021;4511.30074838394;4514.6388288936805;4571.22289210835;4649.256119020941;1720.7762990811366;1773.9556511087326;1883.4632129842928;1967.5277933296193;1981.4451953453056;816.4529765057425;915.2860768600502;928.5542939389131;932.5104320231796;966.7969726357403;20793.37619871376;20812;20884.686729699068;21047.368578942656;21076;918.8024574716468;927.7773334399695;945.2532499325594;966.9226461883032;967.7483854197959;10450.216233782498;11000.22998390105;11002.834957475645;11012.50443730018;11027.393493357782;472614;613504;616394;631325;632346;101352.49323753334;103986.96013039924;124047;124697;124831.37584312202;950.5294879034922;989.3569180033036;993.3245393132739;998.5406712911996;1011.8684571005722;18225.90887045556;18539.629207416045;18866.3396781112;18945.52636184051;19225.711614326076;55931.44068559425;56128;56459.71770141123;56765.58086047882;56799.29602111943;82763;83784.16215837763;84173.57913210394;85626.57186714027;86705;6646.401823835685;7856.489328193649;7871.173526779606;8075.071366792659;8130.430869839054;9615.5672994714;10317.896821031694;12559.623211303675;12691.111622186727;12960.35198240103;10715.571377144826;10775.353478791298;10917.072048875934;11044.889551104707;11062.391568463812;8618.913810862063;10755.784884302113;10840.945795270973;10851.40317282557;11044.779104418027;9569.090936361314;9788.433850583873;10924.945375273073;11132.276402033842;11236.438178091226;7834.4907581007055;7909.169537198742;8069.991251093613;8111.986001749781;8116.391270654843;7784.494007889469;9211.493367864832;9456.479893605643;10704.518296676824;10741.409222492837 03:14:27 INFO - PROCESS | 2034 | |3;mootools.html;1288.619857142143;1365.1365885566202;1580.2888700084964;1782.1801971093296;1790.704533751931;7007.5445096068715;7691.538507689528;7839.686412543491;7983.680652773889;8433.367497437694;7442.809150535902;8033.277005069543;8051.396145289097;8332.583370831464;8368.748937531884;2284.565932472829;2310.9884450577724;2314.467672435341;2318;2320.2807129789735;2281.9201327953524;2313.2944451942153;2323.0591610397814;2329.790318871301;2332.8133749300014;2310.480141968052;2317.1310758465575;2335.708036495438;2338.0180324263765;2349.4243910242017;2296.609576372021;2319.5824751544715;2326.174208156097;2329.1731435340466;2333.2533589251448;2275.203678712454;2342.508073304608;2345.6598793174894;2345.8592484450987;2349.6945397098266;8375.162483751576;8409.873851892227;8416.873746893802;8432.072472028072;8432.704855330034;4410.558944105596;4991.5258050485145;5146.228065790124;5156.226566015091;5166.992436474889;3254.1213872254593;3332.866685332584;3413.1566209749294;3459.6021457532456;3563.251717139404;834.3158609939852;1034.3276870034463;1063.643679367413;1064.7178497698117;1074.1997212077054;10705.197110216764;10745.62390316343;10777.299475534066;10868.021878031015;10913.618023369145;4513.480949690762;4589.79345929434;4688.062387522509;4701.271302948048;4753.215719406328;9449.669261575811;9513.096255855682;9538.475383853816;9546.997565255548;9593.232541396672;5168.147255702842;5170.870728231749;5172.612054095883;5181.145111056709;5198.4801519847715;4212.49450065994;4228.6617070634275;4533.569310915524;4729.196036673774;4757.9048419032115;3843.9231215375535;3856.8071596420627;3917.275304068756;3961.3067713150085;3973.98663340852;1263.0527104671496;1278.8273583066352;1280.6862318731971;1288.8775566321185;1293.1852932652369;817.464560712734;878.9604467798964;879.8416285068749;895.0154829687422;899.4153800029902;5486.451354864562;5552.91670624941;5649.54803615718;5692.886142277214;5699.546615613075;924.1359329027381;968.0899954043177;991.7322322972757;1014.2139841622801;1019.5412064570825;3239.9956013635847;3740.36413809653;3833.8274777635074;3856.8457261709777;3920.9019774505864;7603.2776886195725;9966.800663986825;10060.547275372626;10077.445740484198;10099.444530550743;9554.617815287449;10016.949915250523;10030.548625311878;10073.848892266622;10073.94963025195;6011.9699401503585;6602.2407423146;7508.061492313461;7521.7743467696055;7532.472726909064;4175.770332631734;5296.364436267672;5437.891242175056;5457.918131227955;5562.527185189304;9018;9190.908090919005;9330.253579713471;9340.859887101835;9391.342606017513;9363.906360936577;9422;9430.056994300516;9483;9582.089701478348;6563.146790917151;6795.864082718219;6971.337722916315;7034.437245020284;7265.20082790882;7798;7974.003249593801;7995.320397766022;7997.480163789335;8002.359811215205;5878.529717622494;7193.208747037717;7203.099612548432;7238.131424229126;7303.452241081834;6943.722251110101;6998.475114366341;7015.298470152944;7094.432445404458;7127.144742630918;6680.5991640501725;6692.99605059245;6757.121574195323;6766.627835469097;6782;4843.854684359475;4846.176150054429;4867.440244371943;4883.462819089827;4898.510148985073;4771.809127634856;4813.326134341265;4914.213725803854;4930.556249937523;4942.3080768693135 03:14:27 INFO - PROCESS | 2034 | |4;prototype.html;268.6708781742365;307.16298087710976;313.09827696234845;316.6912260545968;317.3763554615181;5122.643583951378;5277.1002439121885;5368.436314187008;5379.408265090837;5435.917417214907;5087.872803179922;5130.743462826853;5241.42344342122;5281.947180528194;5368.62419630626;4064.4919385076864;4067.511898572168;4085.59144085592;4091.1204091120403;4092.4679791627195;3773.415120656303;3850.4801851749935;3914.629879542166;4078.3498768602085;4094.754314741125;4014.959850401488;4026.1947610477873;4034.798260086984;4038.273110840062;4040.3131467650423;3541.9728278799116;3679.0618392309925;3720.1815600567834;4061.939070913939;4079.286124928126;3554.36021516127;3562.590302115264;3573.803440810754;3574.928501429983;3580.6777390034763;4972.8508144755715;5109.540141387256;5111.131107711698;5141.922871156936;5182.896342073175;4638.930416043762;4640.5127461616385;4651.046535460223;4653;4677.976610116962;5061.746912654389;5105.33630628016;5275.947240527622;5583.162525621149;5615.2700148980375;3263.3310171414805;3743.625637436262;3859.4982652255026;3895.357266051098;3895.961040389588;5532.695701736403;5665.490105890449;5878.412158784087;5887.322957859858;5943.286805583314;3745.176061266517;3869.5356557213313;3949.921001579952;3953.9604603954167;3963.1875465529497;3325.6840600142946;3401.2857299967286;3418.3505134024454;3851.807409629507;4006.779627120478;3863.3001479348995;4586.9679322152915;4614.723116613014;4617.399738034102;4665.6734028617675;5600.631507424216;5607.495325420732;5642.4075472075665;5643.35101463337;5693.3562519987245;1048.0672201740458;1154.7690461907653;1213.1871645997203;1289.7420515896858;1315.2634524666025;808.8918182090573;835.9707410240612;855.6620135046621;874.2175752701297;922.6540047482194;4970.061675946384;5011.749412529359;5024.648274620815;5054.545090941833;5081.390233172043;5229.790808367623;5243.685378877203;5303.681779093266;5530.391656917735;5566.888662226733;4435.3125265583885;4970.602351811846;4987.351644286292;4991.975040124777;4997.825076122318;2281.6235321172153;2365.656979737963;2882.5930348159104;2885.614904845686;2921.62018937541;5143.177091665316;5258.792136796551;5274;5692.943070569324;5753;4355.60799528044;4954.355933728664;5355.919661205085;5510.283663123768;5583.218349431083;4846.830360937351;5186.948130518646;5235.869103272448;5590.496855283044;5660.433956604389;4865.756712164307;5252.5535329497425;5429.864253393696;5492.413834448281;5505.449455054462;5382.865428364322;5394.489542927987;5395.56835453155;5405.756740946588;5463.388300451297;4875.171220892527;4897.608191344755;5025.597952163891;5467.316585426822;5786.797462088806;3200.535922291301;3424.7499662623463;3518.3842827504573;3730.291244663505;3736.8692095777606;4848.466668666373;4969.602431805374;5330.333708286465;5388.405493238279;5489.173075866012;5094.974525127499;5292.724128624728;5346.091164501966;5352.892942141213;5362.919556206738;4315;4485.46174459067;4695.060987802385;4719.764011799465;5265.78936842522;4690.390249267573;5198;5257.237700533477;5342.492463215988;5392.514673679388;5157.891053423532;5387.299651045496;5416.891662166656;5531.7234138292115;5682.772689092555;5225.764840582259;5508.118701007819;5520.889582208253;5583.972080139573;5593.02122128634 03:14:27 INFO - PROCESS | 2034 | |5;yui.html;596.4333882811329;703.5391818358974;710.5239489542006;755.8412733326002;786.5595266650676;13584.932075339622;14451.927740361283;14510.347034383452;14537;14553.563393098197;12602.243865368084;12913.354332283396;12999.675008124801;13088.672783180426;13090.280034598094;832.1927730101792;841.7643059943196;855.2687452228348;855.80744332525;856.0626114404716;672.6703915081624;686.9244383117853;827.0736774812189;832.50881979632;834.7454026521908;864.7665130414784;890.9331800114985;901.998781352698;902.6028547439106;904.159132007233;872.5375550958003;880.0803160697055;880.8414485392627;883.9779005524881;887.8978917424514;658.3054877104653;660.7511802692915;660.7522179182806;661.6956200147938;662.069791942317;13440.260785656785;13762;13775.035747497579;13808.309584520734;13818;12277;12290.20113692607;12358.196717213352;12405.503779848796;12427.192232504858;10028.749281267954;10281.53733082013;10336.17310615149;11260;11548.884511154865;3949.585293544194;3953.525576930758;4092.9181416371507;4147.025449019478;4157.937630935538;69040.92877213692;83353.58323208406;91814.5409272956;93610.53194734051;95731;9275.257979361635;9444.386114902509;9560.378575392577;9590.856137157949;9596.808063838684;18391;18604.44186674402;18643.44069677912;18742.1566029529;18807.623847523246;6872.656367181621;7024.1571011478945;7031.542949708252;7169.5698258104685;7171.211166771651;3938.4289278055094;3985.860494882665;3987.0829709166474;4014.297497937849;4091.3044782387065;3242.854071566785;3355.5470011548127;3394.372041172391;3405.3700065487965;3412.9488057679155;540.9134538473826;637.7385272038442;641.7112299465259;642.7364780439997;659.6042374575312;564.5850300029475;571.5513322042177;571.7884382778399;650.2164891305965;669.2805234373069;6618.86762264752;7297.452691048193;7560.168381478033;8309.418340716213;8861.202491775772;545.2012801246115;598.6647477412663;644.1239913717341;646.809191288571;672.1799404726227;3635.581908080552;4004.7797371144866;4281.272183728774;4306.246406878784;4435.3125265583885;75100.24899750932;77841;112413;115473.4226328863;116270.837291626;97350.51324743472;99833.00166998382;101704;104165.47917260365;104282;86011.56994214989;90501;90822.54588727147;91904;92996;7293.671784769592;8405.915940840758;8440.366972476966;8479.533625650649;8610.182032706884;48375.5162448371;51822.74088629533;54599;55429.722851385486;56011.719941400035;58661.70669146627;74132.62933685297;80789;80839.19160808552;81755.59122204351;9402.38884472507;10059.994000599881;10235.027672371112;10373.688789336333;10640.946795265974;11442.885571144181;11739;11798.646040618794;11997.64007079789;12114.091443141624;10784.622538211439;10901.182411319025;10993.780124397626;11080.501377437873;11168.441577921234;10819.242653014213;10844.620438284623;10932.890671093188;11103.555857765921;11275.66173014811;9561.330706850455;10828.891711082788;11147.219694621299;11174.441277936234;11701.356425396685;7920.722774702857;8924.152205540464;8973.775655608662;9077.273818094669;9234.445933243758;9313.580888860153;9492.193163580896;9496.47769372691;9508.524573771421;9547.42715437076 03:14:27 INFO - PROCESS | 2034 | __end_tp_report 03:14:27 INFO - PROCESS | 2034 | __start_cc_report 03:14:27 INFO - PROCESS | 2034 | _x_x_mozilla_cycle_collect,373 03:14:27 INFO - PROCESS | 2034 | __end_cc_report 03:14:27 INFO - PROCESS | 2034 | __startTimestamp1484997267746__endTimestamp 03:14:27 INFO - PROCESS | 2034 | 03:14:27 INFO - PROCESS | 2034 | ------- Summary: start ------- 03:14:27 INFO - PROCESS | 2034 | Number of tests: 6 03:14:27 INFO - PROCESS | 2034 | 03:14:27 INFO - PROCESS | 2034 | [#0] dojo.html Cycles:165 Average:5674.49 Median:5843.47 stddev:652.50 (11.2%) stddev-sans-first:628.67 03:14:27 INFO - PROCESS | 2034 | Values: 3357.6 3359.9 3374.1 3380.2 3380.5 5600.1 5605.5 5644.3 5659.3 5669.3 4626.5 5531.0 5578.5 5628.7 5681.3 5161.3 5190.1 5200.9 5250.2 5258.1 5242.4 5244.6 5250.2 5262.9 5273.4 5133.3 5163.0 5223.4 5228.5 5284.7 3776.7 5193.2 5289.4 5299.2 5341.0 5199.1 5211.1 5245.1 5248.4 5254.3 5548.1 5725.3 5733.5 5820.4 5820.4 6109.3 6112.4 6127.8 6133.5 6151.9 6206.0 6255.7 6256.9 6274.1 6279.8 5799.3 5803.4 5803.8 5808.9 5842.0 5022.4 5159.0 6175.5 6182.2 6243.2 6100.0 6129.1 6140.5 6140.6 6165.6 6102.2 6206.6 6258.6 6280.9 6284.1 5797.8 6029.7 6107.8 6112.6 6124.6 6111.2 6149.4 6159.8 6165.2 6172.3 4920.1 4965.0 4967.6 4972.4 4992.2 4314.7 4351.7 4352.3 4400.9 4462.1 5693.6 5830.6 5856.4 5863.0 5877.2 6151.5 6183.1 6188.7 6215.3 6231.5 5638.5 5689.2 5788.9 5809.4 5824.8 5447.1 5449.2 5449.7 5451.9 5460.9 6291.9 6296.7 6337.0 6337.2 6342.3 4982.5 5020.9 5683.7 6125.4 6176.6 6294.7 6317.6 6335.4 6343.3 6352.0 6155.3 6184.9 6206.0 6225.2 6228.3 6049.4 6063.6 6072.0 6112.5 6290.2 4603.4 5200.8 6071.9 6136.3 6143.7 6032.5 6048.9 6060.0 6083.3 6089.0 5702.9 5840.8 5843.5 5893.7 5952.1 6024.7 6061.4 6105.4 6111.5 6137.8 5943.2 5971.2 5980.2 5996.5 5997.1 03:14:27 INFO - PROCESS | 2034 | 03:14:27 INFO - PROCESS | 2034 | [#1] ext.html Cycles:180 Average:69069.50 Median:21811.58 stddev:152860.64 (700.8%) stddev-sans-first:153202.67 03:14:27 INFO - PROCESS | 2034 | Values: 464.6 482.1 491.0 497.2 508.5 32208.7 38288.2 40251.4 42449.9 42507.1 41665.4 42093.9 42125.8 42168.8 42277.6 9341.3 9485.4 9505.4 9527.2 9530.8 8184.2 9469.8 9501.5 9509.2 9543.2 9482.9 9503.8 9549.3 9555.1 9568.1 9476.3 9523.2 9535.9 9539.8 9550.9 9194.3 9339.5 9348.6 9352.9 9374.1 39335.2 39393.8 39655.4 39670.6 39773.0 3140.8 3158.2 3277.5 3901.8 3967.8 29371.0 29490.6 30593.7 31191.9 31485.8 3101.9 3215.6 3236.7 3293.1 3358.7 350414.0 416003.0 426642.0 428770.0 436979.0 61961.0 62659.7 62747.0 62787.7 62815.0 58896.0 59235.8 59504.4 59554.7 59679.7 16166.8 16295.4 16372.2 16384.8 16395.7 12385.4 12394.1 12411.9 12430.0 12441.8 18027.0 18690.5 18744.1 18744.5 18988.2 7651.9 7754.3 7851.5 7859.0 7903.0 810.9 949.8 962.0 1053.5 1060.2 28248.9 28593.9 29918.0 29975.3 29999.7 797.0 1021.4 1024.3 1027.3 1067.0 14615.5 15256.0 15392.5 15441.4 15621.5 673605.0 683631.0 683764.0 683920.0 687302.0 48560.0 56511.2 57126.4 57257.4 57363.4 1167.7 1321.5 1346.4 1346.8 1349.2 23547.0 28235.3 29193.6 29754.6 30024.2 49635.3 49666.5 49986.0 50133.5 50140.2 577586.0 583328.0 584876.0 586347.0 588012.0 21833.3 26101.5 26593.7 26607.2 26607.9 45466.5 45806.5 45977.8 46242.5 46285.3 38318.6 38422.8 39099.8 39319.0 39550.8 37429.1 37596.4 37990.6 38915.6 39145.0 32377.0 32778.0 34886.7 36969.7 39032.4 33137.0 34828.4 34837.7 35024.8 35100.6 25711.0 25750.9 25770.6 25801.6 26193.6 03:14:27 INFO - PROCESS | 2034 | 03:14:27 INFO - PROCESS | 2034 | [#2] jquery.html Cycles:180 Average:34197.84 Median:4474.96 stddev:99490.00 (2223.3%) stddev-sans-first:99736.67 03:14:27 INFO - PROCESS | 2034 | Values: 369.0 377.7 386.3 398.0 405.7 18146.9 19097.3 19443.8 19448.5 19535.0 19587.1 19853.4 19885.0 20266.4 20268.4 4865.7 5627.8 5808.0 5899.3 5912.8 5855.8 5894.3 5921.2 5962.0 5979.7 5634.2 5787.5 5835.6 5850.3 5886.9 5782.7 5795.2 5808.0 5823.4 5830.4 4803.7 4909.8 5052.8 5329.4 5408.4 10479.6 12458.4 12610.6 12629.7 12765.1 2194.0 2261.7 2268.6 2340.3 2770.8 7739.8 7949.9 8502.5 9923.5 13401.6 1519.0 1761.4 1766.7 1833.6 1850.5 103662.0 106447.0 114496.4 127898.0 129898.0 10612.2 11613.4 11681.4 11971.8 12197.6 14407.5 14424.4 14442.6 14459.7 14461.6 8132.6 8338.5 8397.6 8471.0 8516.4 6845.9 8224.3 8247.4 8262.7 8412.1 4438.6 4511.3 4514.6 4571.2 4649.3 1720.8 1774.0 1883.5 1967.5 1981.4 816.5 915.3 928.6 932.5 966.8 20793.4 20812.0 20884.7 21047.4 21076.0 918.8 927.8 945.3 966.9 967.7 10450.2 11000.2 11002.8 11012.5 11027.4 472614.0 613504.0 616394.0 631325.0 632346.0 101352.5 103987.0 124047.0 124697.0 124831.4 950.5 989.4 993.3 998.5 1011.9 18225.9 18539.6 18866.3 18945.5 19225.7 55931.4 56128.0 56459.7 56765.6 56799.3 82763.0 83784.2 84173.6 85626.6 86705.0 6646.4 7856.5 7871.2 8075.1 8130.4 9615.6 10317.9 12559.6 12691.1 12960.4 10715.6 10775.4 10917.1 11044.9 11062.4 8618.9 10755.8 10840.9 10851.4 11044.8 9569.1 9788.4 10924.9 11132.3 11236.4 7834.5 7909.2 8070.0 8112.0 8116.4 7784.5 9211.5 9456.5 10704.5 10741.4 03:14:27 INFO - PROCESS | 2034 | 03:14:27 INFO - PROCESS | 2034 | [#3] mootools.html Cycles:180 Average:5379.10 Median:5068.88 stddev:2933.26 (57.9%) stddev-sans-first:2925.37 03:14:27 INFO - PROCESS | 2034 | Values: 1288.6 1365.1 1580.3 1782.2 1790.7 7007.5 7691.5 7839.7 7983.7 8433.4 7442.8 8033.3 8051.4 8332.6 8368.7 2284.6 2311.0 2314.5 2318.0 2320.3 2281.9 2313.3 2323.1 2329.8 2332.8 2310.5 2317.1 2335.7 2338.0 2349.4 2296.6 2319.6 2326.2 2329.2 2333.3 2275.2 2342.5 2345.7 2345.9 2349.7 8375.2 8409.9 8416.9 8432.1 8432.7 4410.6 4991.5 5146.2 5156.2 5167.0 3254.1 3332.9 3413.2 3459.6 3563.3 834.3 1034.3 1063.6 1064.7 1074.2 10705.2 10745.6 10777.3 10868.0 10913.6 4513.5 4589.8 4688.1 4701.3 4753.2 9449.7 9513.1 9538.5 9547.0 9593.2 5168.1 5170.9 5172.6 5181.1 5198.5 4212.5 4228.7 4533.6 4729.2 4757.9 3843.9 3856.8 3917.3 3961.3 3974.0 1263.1 1278.8 1280.7 1288.9 1293.2 817.5 879.0 879.8 895.0 899.4 5486.5 5552.9 5649.5 5692.9 5699.5 924.1 968.1 991.7 1014.2 1019.5 3240.0 3740.4 3833.8 3856.8 3920.9 7603.3 9966.8 10060.5 10077.4 10099.4 9554.6 10016.9 10030.5 10073.8 10073.9 6012.0 6602.2 7508.1 7521.8 7532.5 4175.8 5296.4 5437.9 5457.9 5562.5 9018.0 9190.9 9330.3 9340.9 9391.3 9363.9 9422.0 9430.1 9483.0 9582.1 6563.1 6795.9 6971.3 7034.4 7265.2 7798.0 7974.0 7995.3 7997.5 8002.4 5878.5 7193.2 7203.1 7238.1 7303.5 6943.7 6998.5 7015.3 7094.4 7127.1 6680.6 6693.0 6757.1 6766.6 6782.0 4843.9 4846.2 4867.4 4883.5 4898.5 4771.8 4813.3 4914.2 4930.6 4942.3 03:14:27 INFO - PROCESS | 2034 | 03:14:27 INFO - PROCESS | 2034 | [#4] prototype.html Cycles:180 Average:4419.86 Median:5018.20 stddev:1345.38 (26.8%) stddev-sans-first:1312.57 03:14:27 INFO - PROCESS | 2034 | Values: 268.7 307.2 313.1 316.7 317.4 5122.6 5277.1 5368.4 5379.4 5435.9 5087.9 5130.7 5241.4 5281.9 5368.6 4064.5 4067.5 4085.6 4091.1 4092.5 3773.4 3850.5 3914.6 4078.3 4094.8 4015.0 4026.2 4034.8 4038.3 4040.3 3542.0 3679.1 3720.2 4061.9 4079.3 3554.4 3562.6 3573.8 3574.9 3580.7 4972.9 5109.5 5111.1 5141.9 5182.9 4638.9 4640.5 4651.0 4653.0 4678.0 5061.7 5105.3 5275.9 5583.2 5615.3 3263.3 3743.6 3859.5 3895.4 3896.0 5532.7 5665.5 5878.4 5887.3 5943.3 3745.2 3869.5 3949.9 3954.0 3963.2 3325.7 3401.3 3418.4 3851.8 4006.8 3863.3 4587.0 4614.7 4617.4 4665.7 5600.6 5607.5 5642.4 5643.4 5693.4 1048.1 1154.8 1213.2 1289.7 1315.3 808.9 836.0 855.7 874.2 922.7 4970.1 5011.7 5024.6 5054.5 5081.4 5229.8 5243.7 5303.7 5530.4 5566.9 4435.3 4970.6 4987.4 4992.0 4997.8 2281.6 2365.7 2882.6 2885.6 2921.6 5143.2 5258.8 5274.0 5692.9 5753.0 4355.6 4954.4 5355.9 5510.3 5583.2 4846.8 5186.9 5235.9 5590.5 5660.4 4865.8 5252.6 5429.9 5492.4 5505.4 5382.9 5394.5 5395.6 5405.8 5463.4 4875.2 4897.6 5025.6 5467.3 5786.8 3200.5 3424.7 3518.4 3730.3 3736.9 4848.5 4969.6 5330.3 5388.4 5489.2 5095.0 5292.7 5346.1 5352.9 5362.9 4315.0 4485.5 4695.1 4719.8 5265.8 4690.4 5198.0 5257.2 5342.5 5392.5 5157.9 5387.3 5416.9 5531.7 5682.8 5225.8 5508.1 5520.9 5584.0 5593.0 03:14:27 INFO - PROCESS | 2034 | 03:14:27 INFO - PROCESS | 2034 | [#5] yui.html Cycles:180 Average:19917.05 Median:597.55 stddev:30324.82 (5074.9%) stddev-sans-first:30375.19 03:14:27 INFO - PROCESS | 2034 | Values: 596.4 703.5 710.5 755.8 786.6 13584.9 14451.9 14510.3 14537.0 14553.6 12602.2 12913.4 12999.7 13088.7 13090.3 832.2 841.8 855.3 855.8 856.1 672.7 686.9 827.1 832.5 834.7 864.8 890.9 902.0 902.6 904.2 872.5 880.1 880.8 884.0 887.9 658.3 660.8 660.8 661.7 662.1 13440.3 13762.0 13775.0 13808.3 13818.0 12277.0 12290.2 12358.2 12405.5 12427.2 10028.7 10281.5 10336.2 11260.0 11548.9 3949.6 3953.5 4092.9 4147.0 4157.9 69040.9 83353.6 91814.5 93610.5 95731.0 9275.3 9444.4 9560.4 9590.9 9596.8 18391.0 18604.4 18643.4 18742.2 18807.6 6872.7 7024.2 7031.5 7169.6 7171.2 3938.4 3985.9 3987.1 4014.3 4091.3 3242.9 3355.5 3394.4 3405.4 3412.9 540.9 637.7 641.7 642.7 659.6 564.6 571.6 571.8 650.2 669.3 6618.9 7297.5 7560.2 8309.4 8861.2 545.2 598.7 644.1 646.8 672.2 3635.6 4004.8 4281.3 4306.2 4435.3 75100.2 77841.0 112413.0 115473.4 116270.8 97350.5 99833.0 101704.0 104165.5 104282.0 86011.6 90501.0 90822.5 91904.0 92996.0 7293.7 8405.9 8440.4 8479.5 8610.2 48375.5 51822.7 54599.0 55429.7 56011.7 58661.7 74132.6 80789.0 80839.2 81755.6 9402.4 10060.0 10235.0 10373.7 10640.9 11442.9 11739.0 11798.6 11997.6 12114.1 10784.6 10901.2 10993.8 11080.5 11168.4 10819.2 10844.6 10932.9 11103.6 11275.7 9561.3 10828.9 11147.2 11174.4 11701.4 7920.7 8924.2 8973.8 9077.3 9234.4 9313.6 9492.2 9496.5 9508.5 9547.4 03:14:27 INFO - PROCESS | 2034 | -------- Summary: end -------- 03:14:27 INFO - PROCESS | 2034 | 03:14:28 INFO - TEST-INFO | 2034: exit 0 03:14:28 INFO - TEST-OK | dromaeo_css | took 1115071ms 03:14:28 INFO - TEST-START | kraken 03:14:28 INFO - operating with platform_type : mac_ 03:14:28 INFO - Initialising browser for kraken test... 03:14:28 INFO - TEST-INFO | started process 2038 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpNN3xxA/profile http://localhost:56945/getInfo.html) 03:14:31 INFO - PROCESS | 2038 | 2017-01-21 03:14:31.655 plugin-container[2040:31106] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 03:14:31 INFO - PROCESS | 2038 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:14:31 INFO - PROCESS | 2038 | 2017-01-21 03:14:31.656 plugin-container[2040:31106] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 03:14:31 INFO - PROCESS | 2038 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:14:31 INFO - PROCESS | 2038 | 2017-01-21 03:14:31.656 plugin-container[2040:31106] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:14:31 INFO - PROCESS | 2038 | __metrics Screen width/height:1600/1200 03:14:31 INFO - PROCESS | 2038 | colorDepth:24 03:14:31 INFO - PROCESS | 2038 | Browser inner width/height: 1024/689 03:14:31 INFO - PROCESS | 2038 | __metrics 03:14:31 ERROR - PROCESS | 2038 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 03:14:32 INFO - TEST-INFO | 2038: exit 0 03:14:32 INFO - Browser initialized. 03:14:32 INFO - Running cycle 1/1 for kraken test... 03:14:32 INFO - TEST-INFO | started process 2041 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpNN3xxA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:14:35 INFO - PROCESS | 2041 | 2017-01-21 03:14:35.379 plugin-container[2042:31225] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 03:14:35 INFO - PROCESS | 2041 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:14:35 INFO - PROCESS | 2041 | 2017-01-21 03:14:35.380 plugin-container[2042:31225] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x421b, name = 'com.apple.CFPasteboardClient' 03:14:35 INFO - PROCESS | 2041 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:14:35 INFO - PROCESS | 2041 | 2017-01-21 03:14:35.380 plugin-container[2042:31225] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:14:44 INFO - PROCESS | 2041 | RSS: Main: 228913152 03:14:44 INFO - PROCESS | 2041 | 03:14:47 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56945/tests/kraken/driver.html?testName=audio-beat-detection) 03:14:48 INFO - PROCESS | 2041 | RSS: Main: 241197056 03:14:48 INFO - PROCESS | 2041 | 03:14:51 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56945/tests/kraken/driver.html?testName=audio-dft) 03:14:51 INFO - PROCESS | 2041 | RSS: Main: 249262080 03:14:51 INFO - PROCESS | 2041 | 03:14:55 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56945/tests/kraken/driver.html?testName=audio-fft) 03:14:55 INFO - PROCESS | 2041 | RSS: Main: 253009920 03:14:55 INFO - PROCESS | 2041 | 03:14:57 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56945/tests/kraken/driver.html?testName=audio-oscillator) 03:14:58 INFO - PROCESS | 2041 | RSS: Main: 253853696 03:14:58 INFO - PROCESS | 2041 | 03:15:00 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56945/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:15:00 INFO - PROCESS | 2041 | RSS: Main: 254562304 03:15:00 INFO - PROCESS | 2041 | 03:15:03 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56945/tests/kraken/driver.html?testName=imaging-darkroom) 03:15:04 INFO - PROCESS | 2041 | RSS: Main: 258904064 03:15:04 INFO - PROCESS | 2041 | 03:15:07 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56945/tests/kraken/driver.html?testName=imaging-desaturate) 03:15:08 INFO - PROCESS | 2041 | RSS: Main: 258809856 03:15:08 INFO - PROCESS | 2041 | 03:15:10 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56945/tests/kraken/driver.html?testName=json-parse-financial) 03:15:11 INFO - PROCESS | 2041 | RSS: Main: 258203648 03:15:11 INFO - PROCESS | 2041 | 03:15:13 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56945/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:15:13 INFO - PROCESS | 2041 | RSS: Main: 257851392 03:15:13 INFO - PROCESS | 2041 | 03:15:15 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56945/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:15:16 INFO - PROCESS | 2041 | RSS: Main: 257523712 03:15:16 INFO - PROCESS | 2041 | 03:15:18 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56945/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:15:19 INFO - PROCESS | 2041 | RSS: Main: 257507328 03:15:19 INFO - PROCESS | 2041 | 03:15:22 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56945/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:15:22 INFO - PROCESS | 2041 | RSS: Main: 257613824 03:15:22 INFO - PROCESS | 2041 | 03:15:25 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56945/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:15:26 INFO - PROCESS | 2041 | RSS: Main: 257937408 03:15:26 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56945/tests/kraken/driver.html?testName=ai-astar) 03:15:28 INFO - PROCESS | 2041 | RSS: Main: 259964928 03:15:28 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | __start_tp_report 03:15:28 INFO - PROCESS | 2041 | _x_x_mozilla_page_load 03:15:28 INFO - PROCESS | 2041 | _x_x_mozilla_page_load_details 03:15:28 INFO - PROCESS | 2041 | |i|pagename|runs| 03:15:28 INFO - PROCESS | 2041 | |0;ai-astar;116;119;132;172;161;118;117;120;123;165 03:15:28 INFO - PROCESS | 2041 | |1;audio-beat-detection;146;114;116;146;112;116;112;120;127;98 03:15:28 INFO - PROCESS | 2041 | |2;audio-dft;196;198;203;201;182;195;192;194;198;202 03:15:28 INFO - PROCESS | 2041 | |3;audio-fft;89;85;86;108;61;58;73;61;78;58 03:15:28 INFO - PROCESS | 2041 | |4;audio-oscillator;88;71;90;70;73;56;104;96;71;121 03:15:28 INFO - PROCESS | 2041 | |5;imaging-gaussian-blur;89;86;89;85;91;85;89;102;88;86 03:15:28 INFO - PROCESS | 2041 | |6;imaging-darkroom;95;108;153;96;127;153;95;125;95;99 03:15:28 INFO - PROCESS | 2041 | |7;imaging-desaturate;90;88;87;89;95;90;106;91;89;86 03:15:28 INFO - PROCESS | 2041 | |8;json-parse-financial;56;54;56;60;62;61;60;61;60;58 03:15:28 INFO - PROCESS | 2041 | |9;json-stringify-tinderbox;68;76;71;118;117;80;69;71;69;72 03:15:28 INFO - PROCESS | 2041 | |10;stanford-crypto-aes;82;84;83;96;83;80;80;81;85;76 03:15:28 INFO - PROCESS | 2041 | |11;stanford-crypto-ccm;153;148;152;153;180;162;147;159;208;187 03:15:28 INFO - PROCESS | 2041 | |12;stanford-crypto-pbkdf2;167;167;166;166;223;237;174;175;235;211 03:15:28 INFO - PROCESS | 2041 | |13;stanford-crypto-sha256-iterative;76;93;91;95;90;82;67;74;77;88 03:15:28 INFO - PROCESS | 2041 | __end_tp_report 03:15:28 INFO - PROCESS | 2041 | __start_cc_report 03:15:28 INFO - PROCESS | 2041 | _x_x_mozilla_cycle_collect,950 03:15:28 INFO - PROCESS | 2041 | __end_cc_report 03:15:28 INFO - PROCESS | 2041 | __startTimestamp1484997328762__endTimestamp 03:15:28 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | ------- Summary: start ------- 03:15:28 INFO - PROCESS | 2041 | Number of tests: 14 03:15:28 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | [#0] ai-astar Cycles:10 Average:134.30 Median:127.50 stddev:22.48 (17.6%) stddev-sans-first:22.85 03:15:28 INFO - PROCESS | 2041 | Values: 116.0 119.0 132.0 172.0 161.0 118.0 117.0 120.0 123.0 165.0 03:15:28 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | [#1] audio-beat-detection Cycles:10 Average:120.70 Median:123.50 stddev:15.19 (12.3%) stddev-sans-first:13.06 03:15:28 INFO - PROCESS | 2041 | Values: 146.0 114.0 116.0 146.0 112.0 116.0 112.0 120.0 127.0 98.0 03:15:28 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | [#2] audio-dft Cycles:10 Average:196.10 Median:198.00 stddev:6.10 (3.1%) stddev-sans-first:6.47 03:15:28 INFO - PROCESS | 2041 | Values: 196.0 198.0 203.0 201.0 182.0 195.0 192.0 194.0 198.0 202.0 03:15:28 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | [#3] audio-fft Cycles:10 Average:75.70 Median:75.50 stddev:16.61 (22.0%) stddev-sans-first:16.91 03:15:28 INFO - PROCESS | 2041 | Values: 89.0 85.0 86.0 108.0 61.0 58.0 73.0 61.0 78.0 58.0 03:15:28 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | [#4] audio-oscillator Cycles:10 Average:84.00 Median:72.00 stddev:19.45 (27.0%) stddev-sans-first:20.57 03:15:28 INFO - PROCESS | 2041 | Values: 88.0 71.0 90.0 70.0 73.0 56.0 104.0 96.0 71.0 121.0 03:15:28 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:88.50 stddev:4.99 (5.6%) stddev-sans-first:5.29 03:15:28 INFO - PROCESS | 2041 | Values: 89.0 86.0 89.0 85.0 91.0 85.0 89.0 102.0 88.0 86.0 03:15:28 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | [#6] imaging-darkroom Cycles:10 Average:114.60 Median:95.00 stddev:23.56 (24.8%) stddev-sans-first:23.90 03:15:28 INFO - PROCESS | 2041 | Values: 95.0 108.0 153.0 96.0 127.0 153.0 95.0 125.0 95.0 99.0 03:15:28 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | [#7] imaging-desaturate Cycles:10 Average:91.10 Median:89.50 stddev:5.78 (6.5%) stddev-sans-first:6.12 03:15:28 INFO - PROCESS | 2041 | Values: 90.0 88.0 87.0 89.0 95.0 90.0 106.0 91.0 89.0 86.0 03:15:28 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:60.00 stddev:2.66 (4.4%) stddev-sans-first:2.62 03:15:28 INFO - PROCESS | 2041 | Values: 56.0 54.0 56.0 60.0 62.0 61.0 60.0 61.0 60.0 58.0 03:15:28 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | [#9] json-stringify-tinderbox Cycles:10 Average:81.10 Median:71.00 stddev:19.52 (27.5%) stddev-sans-first:20.12 03:15:28 INFO - PROCESS | 2041 | Values: 68.0 76.0 71.0 118.0 117.0 80.0 69.0 71.0 69.0 72.0 03:15:28 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.00 stddev:5.23 (6.3%) stddev-sans-first:5.53 03:15:28 INFO - PROCESS | 2041 | Values: 82.0 84.0 83.0 96.0 83.0 80.0 80.0 81.0 85.0 76.0 03:15:28 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | [#11] stanford-crypto-ccm Cycles:10 Average:164.90 Median:160.50 stddev:20.20 (12.6%) stddev-sans-first:20.96 03:15:28 INFO - PROCESS | 2041 | Values: 153.0 148.0 152.0 153.0 180.0 162.0 147.0 159.0 208.0 187.0 03:15:28 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.10 Median:193.00 stddev:30.57 (15.8%) stddev-sans-first:31.05 03:15:28 INFO - PROCESS | 2041 | Values: 167.0 167.0 166.0 166.0 223.0 237.0 174.0 175.0 235.0 211.0 03:15:28 INFO - PROCESS | 2041 | 03:15:28 INFO - PROCESS | 2041 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.30 Median:89.00 stddev:9.45 (10.6%) stddev-sans-first:9.65 03:15:28 INFO - PROCESS | 2041 | Values: 76.0 93.0 91.0 95.0 90.0 82.0 67.0 74.0 77.0 88.0 03:15:28 INFO - PROCESS | 2041 | -------- Summary: end -------- 03:15:28 INFO - PROCESS | 2041 | 03:15:29 INFO - TEST-INFO | 2041: exit 0 03:15:29 INFO - TEST-OK | kraken | took 61310ms 03:15:29 INFO - SUITE-END | took 1176s 03:15:29 INFO - Completed test suite (00:19:36) 03:15:29 INFO - javascript benchmark 03:15:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7041.747746177595, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3357.5803024621923, 3359.8824041158564, 3374.0721301642047, 3380.154961259685, 3380.5098260752206, 5600.131979543172, 5605.495505404513, 5644.266245388104, 5659.264295641566, 5669.262995810544, 4626.4910859805395, 5530.976769297673, 5578.525825304854, 5628.690422026787, 5681.346645135811, 5161.27742116104, 5190.143626301656, 5200.947990520084, 5250.186221135711, 5258.0535503609335, 5242.370915490127, 5244.632875698702, 5250.18622113573, 5262.947370526284, 5273.446288139748, 5133.281340612317, 5163.0, 5223.399309079447, 5228.503292187236, 5284.709340986245, 3776.716746244029, 5193.195054766498, 5289.44460831611, 5299.231611416323, 5341.03861304965, 5199.090159222159, 5211.062008838408, 5245.10833158364, 5248.448912864133, 5254.343207099113, 5548.112302031655, 5725.341585717612, 5733.541316694655, 5820.35976042635, 5820.388859169769, 6109.28320792043, 6112.412294442025, 6127.830981140894, 6133.478654314388, 6151.87696246077, 6205.968970155165, 6255.655938923357, 6256.906146407808, 6274.121622972787, 6279.811605651792, 5799.2750906136735, 5803.361630220715, 5803.82588522345, 5808.912866307009, 5841.977653910549, 5022.422421421583, 5158.97420512895, 6175.505959523227, 6182.196314479179, 6243.219597550306, 6099.969500152471, 6129.11127886454, 6140.508759299245, 6140.6315621061985, 6165.630062196193, 6102.206713127265, 6206.596571222946, 6258.561900666909, 6280.874382512416, 6284.057391391237, 5797.826065218051, 6029.6683682397015, 6107.816765497042, 6112.602680825732, 6124.594542075475, 6111.236095488064, 6149.41580549847, 6159.784407545804, 6165.198524191826, 6172.321044685081, 4920.114379411741, 4965.0, 4967.602591792648, 4972.378452693413, 4992.151334273182, 4314.741115533078, 4351.673624478113, 4352.347147927836, 4400.889977750518, 4462.129884672523, 5693.601447898607, 5830.562707796932, 5856.385079566669, 5862.973979553561, 5877.23595932526, 6151.5386346024225, 6183.072539119168, 6188.659623720649, 6215.285242197115, 6231.532635052298, 5638.548916086703, 5689.175069614883, 5788.942110578924, 5809.448102430262, 5824.825255242349, 5447.073997420448, 5449.1826226066405, 5449.700266485382, 5451.863703407367, 5460.945390546044, 6291.905621415683, 6296.748130074746, 6336.96831515858, 6337.207849018872, 6342.302346741947, 4982.451930287593, 5020.945601423742, 5683.715814209356, 6125.418085281891, 6176.5985210962945, 6294.748210071546, 6317.6209427434505, 6335.3981371769605, 6343.3022367538615, 6352.015437607179, 6155.261368635793, 6184.8763024738355, 6206.0, 6225.159603453476, 6228.252609686867, 6049.364816694184, 6063.575549711478, 6071.969640151771, 6112.541559382975, 6290.1508296381235, 4603.355530225704, 5200.791968321225, 6071.939280607137, 6136.2636483622255, 6143.723532440961, 6032.517398608013, 6048.879022419439, 6059.969700151471, 6083.270007599116, 6088.995315772848, 5702.857428564319, 5840.8247752567495, 5843.474087332161, 5893.734781934738, 5952.136940143741, 6024.66864322449, 6061.424164704346, 6105.389461053859, 6111.5110791137495, 6137.84655383619, 5943.197668314722, 5971.223740913827, 5980.192673988956, 5996.520278377807, 5997.13041608973], "value": 5635.215513852234, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.58883887759333, 482.12975579079745, 490.9877253068675, 497.2292945933802, 508.51182864450124, 32208.67791322089, 38288.234235315314, 40251.396229056554, 42449.93875153123, 42507.14985700284, 41665.37501937473, 42093.947651308736, 42125.78937105318, 42168.789156054256, 42277.577224227745, 9341.299402544802, 9485.383450075757, 9505.429674219535, 9527.23782097434, 9530.80938381232, 8184.181581841828, 9469.81060378792, 9501.524923753785, 9509.191718703918, 9543.236541076732, 9482.905170948306, 9503.80992380152, 9549.331546791727, 9555.140037396633, 9568.138867501924, 9476.28927830412, 9523.19052880506, 9535.904640953571, 9539.761505962337, 9550.904490955105, 9194.310426718022, 9339.486328251944, 9348.57931393089, 9352.906470935271, 9374.125174965035, 39335.21329573393, 39393.80303098495, 39655.40516892249, 39670.603293967266, 39773.00567485807, 3140.811551306929, 3158.210447388153, 3277.4592192288483, 3901.843926242947, 3967.761934283952, 29371.0, 29490.55764163518, 30593.69406305953, 31191.887551793192, 31485.842570787, 3101.922451938697, 3215.614126304835, 3236.676332366768, 3293.0943990402593, 3358.6641335866216, 350414.0, 416003.0, 426642.0, 428770.0, 436978.9999999968, 61961.0, 62659.746805063645, 62747.0, 62787.6860615694, 62815.0, 58896.0, 59235.815283694086, 59504.404955950755, 59554.70222648859, 59679.70160149172, 16166.838331616533, 16295.429659961845, 16372.181390930406, 16384.75422868658, 16395.672086558203, 12385.380730963596, 12394.070444716683, 12411.937940310241, 12430.005599552014, 12441.75116497665, 18026.97498920926, 18690.532736681693, 18744.062796860104, 18744.531386715167, 18988.24047038103, 7651.923480765121, 7754.302112809874, 7851.489653172525, 7858.960705196552, 7903.0, 810.8743144812563, 949.7863725008549, 961.9711408657752, 1053.536443964653, 1060.1677683018793, 28248.85875570609, 28593.857030714713, 29918.0, 29975.287974809748, 29999.70000299969, 796.9880451793227, 1021.3718563083798, 1024.3456817240103, 1027.337367398024, 1066.99466502667, 14615.488457903923, 15256.0, 15392.538223853302, 15441.382344706086, 15621.453249136453, 673605.0, 683631.0, 683764.0, 683920.0, 687302.0, 48560.02879942452, 56511.15233271504, 57126.42873571294, 57257.42742572521, 57363.42636573665, 1167.7430965187646, 1321.4648067532664, 1346.4412268908488, 1346.7845144776984, 1349.2039696579066, 23547.0, 28235.294117647223, 29193.562096568145, 29754.55368169436, 30024.24939376533, 49635.25547116724, 49666.50333496619, 49986.00027999347, 50133.498665012885, 50140.247896282315, 577586.0, 583328.0, 584876.0, 586347.0, 588012.0, 21833.344999650035, 26101.477970440104, 26593.734062659125, 26607.20178394651, 26607.866960665073, 45466.54533454623, 45806.54193458023, 45977.77011114923, 46242.53757462382, 46285.30572041355, 38318.616813831504, 38422.80788596039, 39099.82700518989, 39319.0, 39550.80224598858, 37429.064273393386, 37596.436053458674, 37990.620093799815, 38915.6108438912, 39145.021374464726, 32377.0, 32778.01665950025, 34886.65113348936, 36969.70606028776, 39032.41451378288, 33137.0, 34828.43052044428, 34837.65162348344, 35024.824875875456, 35100.64899350974, 25711.0, 25750.87124564365, 25770.613440798777, 25801.612975805, 26193.607095893196], "value": 19629.917426958764, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.97786132832016, 377.70538979595915, 386.325861371906, 398.02285389369104, 405.66127283718095, 18146.909265453673, 19097.331593394218, 19443.805561944413, 19448.513787155327, 19535.0, 19587.118579663915, 19853.404397868086, 19885.0, 20266.392008239778, 20268.39194824154, 4865.7105866945285, 5627.761892383669, 5807.999999999989, 5899.292084949812, 5912.763489460416, 5855.76576936922, 5894.292684877821, 5921.230240068785, 5962.0, 5979.701014949257, 5634.154876768476, 5787.47912687858, 5835.591508594379, 5850.297964244297, 5886.88226235475, 5782.681952492611, 5795.188673585681, 5808.041673123929, 5823.446772556643, 5830.4461076197695, 4803.687760295569, 4909.754512274372, 5052.797888084509, 5329.413764485904, 5408.432114627947, 10479.58081676732, 12458.377081145907, 12610.621681349574, 12629.747405051847, 12765.106442549026, 2193.9890300548554, 2261.683364328995, 2268.5576312619046, 2340.2745149003695, 2770.819896706708, 7739.806504837367, 7949.880751788728, 8502.532360720157, 9923.511473278993, 13401.592832752616, 1518.9772153417707, 1761.3747119772552, 1766.7349897515346, 1833.60577475842, 1850.4541160357726, 103661.96338036674, 106447.0, 114496.42751786271, 127898.0, 129898.0, 10612.20408469368, 11613.419329033515, 11681.415929203677, 11971.760564788656, 12197.634070977885, 14407.495737649131, 14424.350904209336, 14442.566722998326, 14459.710805784036, 14461.638459038608, 8132.634031468599, 8338.458000230086, 8397.6221070052, 8470.957645211734, 8516.361272904556, 6845.8973115403305, 8224.259816616533, 8247.381446391544, 8262.669493220204, 8412.116727743622, 4438.622717069021, 4511.30074838394, 4514.6388288936805, 4571.22289210835, 4649.256119020941, 1720.7762990811366, 1773.9556511087326, 1883.4632129842928, 1967.5277933296193, 1981.4451953453056, 816.4529765057425, 915.2860768600502, 928.5542939389131, 932.5104320231796, 966.7969726357403, 20793.37619871376, 20812.0, 20884.686729699068, 21047.368578942656, 21076.0, 918.8024574716468, 927.7773334399695, 945.2532499325594, 966.9226461883032, 967.7483854197959, 10450.216233782498, 11000.22998390105, 11002.834957475645, 11012.50443730018, 11027.393493357782, 472614.0, 613504.0, 616394.0, 631325.0, 632346.0, 101352.49323753334, 103986.96013039924, 124047.0, 124697.0, 124831.37584312202, 950.5294879034922, 989.3569180033036, 993.3245393132739, 998.5406712911996, 1011.8684571005722, 18225.90887045556, 18539.629207416045, 18866.3396781112, 18945.52636184051, 19225.711614326076, 55931.44068559425, 56128.0, 56459.71770141123, 56765.58086047882, 56799.29602111943, 82763.0, 83784.16215837763, 84173.57913210394, 85626.57186714027, 86705.0, 6646.401823835685, 7856.489328193649, 7871.173526779606, 8075.071366792659, 8130.430869839054, 9615.5672994714, 10317.896821031694, 12559.623211303675, 12691.111622186727, 12960.35198240103, 10715.571377144826, 10775.353478791298, 10917.072048875934, 11044.889551104707, 11062.391568463812, 8618.913810862063, 10755.784884302113, 10840.945795270973, 10851.40317282557, 11044.779104418027, 9569.090936361314, 9788.433850583873, 10924.945375273073, 11132.276402033842, 11236.438178091226, 7834.4907581007055, 7909.169537198742, 8069.991251093613, 8111.986001749781, 8116.391270654843, 7784.494007889469, 9211.493367864832, 9456.479893605643, 10704.518296676824, 10741.409222492837], "value": 9171.636296605353, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1288.619857142143, 1365.1365885566202, 1580.2888700084964, 1782.1801971093296, 1790.704533751931, 7007.5445096068715, 7691.538507689528, 7839.686412543491, 7983.680652773889, 8433.367497437694, 7442.809150535902, 8033.277005069543, 8051.396145289097, 8332.583370831464, 8368.748937531884, 2284.565932472829, 2310.9884450577724, 2314.467672435341, 2318.0, 2320.2807129789735, 2281.9201327953524, 2313.2944451942153, 2323.0591610397814, 2329.790318871301, 2332.8133749300014, 2310.480141968052, 2317.1310758465575, 2335.708036495438, 2338.0180324263765, 2349.4243910242017, 2296.609576372021, 2319.5824751544715, 2326.174208156097, 2329.1731435340466, 2333.2533589251448, 2275.203678712454, 2342.508073304608, 2345.6598793174894, 2345.8592484450987, 2349.6945397098266, 8375.162483751576, 8409.873851892227, 8416.873746893802, 8432.072472028072, 8432.704855330034, 4410.558944105596, 4991.5258050485145, 5146.228065790124, 5156.226566015091, 5166.992436474889, 3254.1213872254593, 3332.866685332584, 3413.1566209749294, 3459.6021457532456, 3563.251717139404, 834.3158609939852, 1034.3276870034463, 1063.643679367413, 1064.7178497698117, 1074.1997212077054, 10705.197110216764, 10745.62390316343, 10777.299475534066, 10868.021878031015, 10913.618023369145, 4513.480949690762, 4589.79345929434, 4688.062387522509, 4701.271302948048, 4753.215719406328, 9449.669261575811, 9513.096255855682, 9538.475383853816, 9546.997565255548, 9593.232541396672, 5168.147255702842, 5170.870728231749, 5172.612054095883, 5181.145111056709, 5198.4801519847715, 4212.49450065994, 4228.6617070634275, 4533.569310915524, 4729.196036673774, 4757.9048419032115, 3843.9231215375535, 3856.8071596420627, 3917.275304068756, 3961.3067713150085, 3973.98663340852, 1263.0527104671496, 1278.8273583066352, 1280.6862318731971, 1288.8775566321185, 1293.1852932652369, 817.464560712734, 878.9604467798964, 879.8416285068749, 895.0154829687422, 899.4153800029902, 5486.451354864562, 5552.91670624941, 5649.54803615718, 5692.886142277214, 5699.546615613075, 924.1359329027381, 968.0899954043177, 991.7322322972757, 1014.2139841622801, 1019.5412064570825, 3239.9956013635847, 3740.36413809653, 3833.8274777635074, 3856.8457261709777, 3920.9019774505864, 7603.2776886195725, 9966.800663986825, 10060.547275372626, 10077.445740484198, 10099.444530550743, 9554.617815287449, 10016.949915250523, 10030.548625311878, 10073.848892266622, 10073.94963025195, 6011.9699401503585, 6602.2407423146, 7508.061492313461, 7521.7743467696055, 7532.472726909064, 4175.770332631734, 5296.364436267672, 5437.891242175056, 5457.918131227955, 5562.527185189304, 9018.0, 9190.908090919005, 9330.253579713471, 9340.859887101835, 9391.342606017513, 9363.906360936577, 9422.0, 9430.056994300516, 9483.0, 9582.089701478348, 6563.146790917151, 6795.864082718219, 6971.337722916315, 7034.437245020284, 7265.20082790882, 7798.0, 7974.003249593801, 7995.320397766022, 7997.480163789335, 8002.359811215205, 5878.529717622494, 7193.208747037717, 7203.099612548432, 7238.131424229126, 7303.452241081834, 6943.722251110101, 6998.475114366341, 7015.298470152944, 7094.432445404458, 7127.144742630918, 6680.5991640501725, 6692.99605059245, 6757.121574195323, 6766.627835469097, 6782.0, 4843.854684359475, 4846.176150054429, 4867.440244371943, 4883.462819089827, 4898.510148985073, 4771.809127634856, 4813.326134341265, 4914.213725803854, 4930.556249937523, 4942.3080768693135], "value": 4381.580119421654, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.6708781742365, 307.16298087710976, 313.09827696234845, 316.6912260545968, 317.3763554615181, 5122.643583951378, 5277.1002439121885, 5368.436314187008, 5379.408265090837, 5435.917417214907, 5087.872803179922, 5130.743462826853, 5241.42344342122, 5281.947180528194, 5368.62419630626, 4064.4919385076864, 4067.511898572168, 4085.59144085592, 4091.1204091120403, 4092.4679791627195, 3773.415120656303, 3850.4801851749935, 3914.629879542166, 4078.3498768602085, 4094.754314741125, 4014.959850401488, 4026.1947610477873, 4034.798260086984, 4038.273110840062, 4040.3131467650423, 3541.9728278799116, 3679.0618392309925, 3720.1815600567834, 4061.939070913939, 4079.286124928126, 3554.36021516127, 3562.590302115264, 3573.803440810754, 3574.928501429983, 3580.6777390034763, 4972.8508144755715, 5109.540141387256, 5111.131107711698, 5141.922871156936, 5182.896342073175, 4638.930416043762, 4640.5127461616385, 4651.046535460223, 4653.0, 4677.976610116962, 5061.746912654389, 5105.33630628016, 5275.947240527622, 5583.162525621149, 5615.2700148980375, 3263.3310171414805, 3743.625637436262, 3859.4982652255026, 3895.357266051098, 3895.961040389588, 5532.695701736403, 5665.490105890449, 5878.412158784087, 5887.322957859858, 5943.286805583314, 3745.176061266517, 3869.5356557213313, 3949.921001579952, 3953.9604603954167, 3963.1875465529497, 3325.6840600142946, 3401.2857299967286, 3418.3505134024454, 3851.807409629507, 4006.779627120478, 3863.3001479348995, 4586.9679322152915, 4614.723116613014, 4617.399738034102, 4665.6734028617675, 5600.631507424216, 5607.495325420732, 5642.4075472075665, 5643.35101463337, 5693.3562519987245, 1048.0672201740458, 1154.7690461907653, 1213.1871645997203, 1289.7420515896858, 1315.2634524666025, 808.8918182090573, 835.9707410240612, 855.6620135046621, 874.2175752701297, 922.6540047482194, 4970.061675946384, 5011.749412529359, 5024.648274620815, 5054.545090941833, 5081.390233172043, 5229.790808367623, 5243.685378877203, 5303.681779093266, 5530.391656917735, 5566.888662226733, 4435.3125265583885, 4970.602351811846, 4987.351644286292, 4991.975040124777, 4997.825076122318, 2281.6235321172153, 2365.656979737963, 2882.5930348159104, 2885.614904845686, 2921.62018937541, 5143.177091665316, 5258.792136796551, 5274.0, 5692.943070569324, 5753.0, 4355.60799528044, 4954.355933728664, 5355.919661205085, 5510.283663123768, 5583.218349431083, 4846.830360937351, 5186.948130518646, 5235.869103272448, 5590.496855283044, 5660.433956604389, 4865.756712164307, 5252.5535329497425, 5429.864253393696, 5492.413834448281, 5505.449455054462, 5382.865428364322, 5394.489542927987, 5395.56835453155, 5405.756740946588, 5463.388300451297, 4875.171220892527, 4897.608191344755, 5025.597952163891, 5467.316585426822, 5786.797462088806, 3200.535922291301, 3424.7499662623463, 3518.3842827504573, 3730.291244663505, 3736.8692095777606, 4848.466668666373, 4969.602431805374, 5330.333708286465, 5388.405493238279, 5489.173075866012, 5094.974525127499, 5292.724128624728, 5346.091164501966, 5352.892942141213, 5362.919556206738, 4315.0, 4485.46174459067, 4695.060987802385, 4719.764011799465, 5265.78936842522, 4690.390249267573, 5198.0, 5257.237700533477, 5342.492463215988, 5392.514673679388, 5157.891053423532, 5387.299651045496, 5416.891662166656, 5531.7234138292115, 5682.772689092555, 5225.764840582259, 5508.118701007819, 5520.889582208253, 5583.972080139573, 5593.02122128634], "value": 3984.4023442425982, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.4333882811329, 703.5391818358974, 710.5239489542006, 755.8412733326002, 786.5595266650676, 13584.932075339622, 14451.927740361283, 14510.347034383452, 14537.0, 14553.563393098197, 12602.243865368084, 12913.354332283396, 12999.675008124801, 13088.672783180426, 13090.280034598094, 832.1927730101792, 841.7643059943196, 855.2687452228348, 855.80744332525, 856.0626114404716, 672.6703915081624, 686.9244383117853, 827.0736774812189, 832.50881979632, 834.7454026521908, 864.7665130414784, 890.9331800114985, 901.998781352698, 902.6028547439106, 904.159132007233, 872.5375550958003, 880.0803160697055, 880.8414485392627, 883.9779005524881, 887.8978917424514, 658.3054877104653, 660.7511802692915, 660.7522179182806, 661.6956200147938, 662.069791942317, 13440.260785656785, 13762.0, 13775.035747497579, 13808.309584520734, 13818.0, 12277.0, 12290.20113692607, 12358.196717213352, 12405.503779848796, 12427.192232504858, 10028.749281267954, 10281.53733082013, 10336.17310615149, 11260.0, 11548.884511154865, 3949.585293544194, 3953.525576930758, 4092.9181416371507, 4147.025449019478, 4157.937630935538, 69040.92877213692, 83353.58323208406, 91814.5409272956, 93610.53194734051, 95731.0, 9275.257979361635, 9444.386114902509, 9560.378575392577, 9590.856137157949, 9596.808063838684, 18391.0, 18604.44186674402, 18643.44069677912, 18742.1566029529, 18807.623847523246, 6872.656367181621, 7024.1571011478945, 7031.542949708252, 7169.5698258104685, 7171.211166771651, 3938.4289278055094, 3985.860494882665, 3987.0829709166474, 4014.297497937849, 4091.3044782387065, 3242.854071566785, 3355.5470011548127, 3394.372041172391, 3405.3700065487965, 3412.9488057679155, 540.9134538473826, 637.7385272038442, 641.7112299465259, 642.7364780439997, 659.6042374575312, 564.5850300029475, 571.5513322042177, 571.7884382778399, 650.2164891305965, 669.2805234373069, 6618.86762264752, 7297.452691048193, 7560.168381478033, 8309.418340716213, 8861.202491775772, 545.2012801246115, 598.6647477412663, 644.1239913717341, 646.809191288571, 672.1799404726227, 3635.581908080552, 4004.7797371144866, 4281.272183728774, 4306.246406878784, 4435.3125265583885, 75100.24899750932, 77841.0, 112413.0, 115473.4226328863, 116270.837291626, 97350.51324743472, 99833.00166998382, 101704.0, 104165.47917260365, 104282.0, 86011.56994214989, 90501.0, 90822.54588727147, 91904.0, 92996.0, 7293.671784769592, 8405.915940840758, 8440.366972476966, 8479.533625650649, 8610.182032706884, 48375.5162448371, 51822.74088629533, 54599.0, 55429.722851385486, 56011.719941400035, 58661.70669146627, 74132.62933685297, 80789.0, 80839.19160808552, 81755.59122204351, 9402.38884472507, 10059.994000599881, 10235.027672371112, 10373.688789336333, 10640.946795265974, 11442.885571144181, 11739.0, 11798.646040618794, 11997.64007079789, 12114.091443141624, 10784.622538211439, 10901.182411319025, 10993.780124397626, 11080.501377437873, 11168.441577921234, 10819.242653014213, 10844.620438284623, 10932.890671093188, 11103.555857765921, 11275.66173014811, 9561.330706850455, 10828.891711082788, 11147.219694621299, 11174.441277936234, 11701.356425396685, 7920.722774702857, 8924.152205540464, 8973.775655608662, 9077.273818094669, 9234.445933243758, 9313.580888860153, 9492.193163580896, 9496.47769372691, 9508.524573771421, 9547.42715437076], "value": 6882.802515340121, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1568.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 119.0, 132.0, 172.0, 161.0, 118.0, 117.0, 120.0, 123.0, 165.0], "value": 134.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 114.0, 116.0, 146.0, 112.0, 116.0, 112.0, 120.0, 127.0, 98.0], "value": 120.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 198.0, 203.0, 201.0, 182.0, 195.0, 192.0, 194.0, 198.0, 202.0], "value": 196.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 86.0, 108.0, 61.0, 58.0, 73.0, 61.0, 78.0, 58.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 90.0, 70.0, 73.0, 56.0, 104.0, 96.0, 71.0, 121.0], "value": 84.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 85.0, 91.0, 85.0, 89.0, 102.0, 88.0, 86.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 108.0, 153.0, 96.0, 127.0, 153.0, 95.0, 125.0, 95.0, 99.0], "value": 114.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 87.0, 89.0, 95.0, 90.0, 106.0, 91.0, 89.0, 86.0], "value": 91.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 56.0, 60.0, 62.0, 61.0, 60.0, 61.0, 60.0, 58.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 76.0, 71.0, 118.0, 117.0, 80.0, 69.0, 71.0, 69.0, 72.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 83.0, 96.0, 83.0, 80.0, 80.0, 81.0, 85.0, 76.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 148.0, 152.0, 153.0, 180.0, 162.0, 147.0, 159.0, 208.0, 187.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 166.0, 166.0, 223.0, 237.0, 174.0, 175.0, 235.0, 211.0], "value": 192.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 93.0, 91.0, 95.0, 90.0, 82.0, 67.0, 74.0, 77.0, 88.0], "value": 83.3, "unit": "score"}]}]} 03:15:29 INFO - Return code: 0 03:15:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:15:30 ERROR - # TBPL SUCCESS # 03:15:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:15:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:15:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:15:30 INFO - 2017-01-21 03:15:30.070 screenresolution[2047:31616] starting screenresolution argv=screenresolution get 03:15:30 INFO - 2017-01-21 03:15:30.072 screenresolution[2047:31616] Display 0: 1600x1200x32@60 03:15:30 INFO - 2017-01-21 03:15:30.079 screenresolution[2048:31619] starting screenresolution argv=screenresolution list 03:15:30 INFO - Available Modes on Display 0 03:15:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:15:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:15:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:15:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:15:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:15:30 INFO - Intel Iris: 03:15:30 INFO - Chipset Model: Intel Iris 03:15:30 INFO - Type: GPU 03:15:30 INFO - Bus: Built-In 03:15:30 INFO - VRAM (Dynamic, Max): 1536 MB 03:15:30 INFO - Vendor: Intel (0x8086) 03:15:30 INFO - Device ID: 0x0a2e 03:15:30 INFO - Revision ID: 0x0009 03:15:30 INFO - Displays: 03:15:30 INFO - Display: 03:15:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:15:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:15:30 INFO - Display Serial Number: 1600x1200 60 03:15:30 INFO - Main Display: Yes 03:15:30 INFO - Mirror: Off 03:15:30 INFO - Online: Yes 03:15:30 INFO - Rotation: Supported 03:15:30 INFO - Return code: 0 03:15:30 INFO - Running post-action listener: _resource_record_post_action 03:15:30 INFO - [mozharness: 2017-01-21 11:15:30.325491Z] Finished run-tests step (success) 03:15:30 INFO - Running post-run listener: _resource_record_post_run 03:15:30 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 3892224; Write bytes: 666185216; Read time: 30; Write time: 1873 03:15:30 INFO - TinderboxPrint: CPU usage
54.3% 03:15:30 INFO - TinderboxPrint: I/O read bytes / time
3,892,224 / 30 03:15:30 INFO - TinderboxPrint: I/O write bytes / time
666,185,216 / 1,873 03:15:30 INFO - TinderboxPrint: CPU idle
2,207.9 (45.7%) 03:15:30 INFO - TinderboxPrint: CPU system
978.4 (20.2%) 03:15:30 INFO - TinderboxPrint: CPU user
1,647.5 (34.1%) 03:15:30 INFO - TinderboxPrint: Swap in / out
355,504,128 / 0 03:15:30 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 347636224; Write bytes: 348624896; Read time: 27039; Write time: 663 03:15:30 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 3892224; Write bytes: 310658560; Read time: 30; Write time: 1182 03:15:30 INFO - Running post-run listener: _upload_blobber_files 03:15:30 INFO - Blob upload gear active. 03:15:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:15:30 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 03:15:30 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'] 03:15:30 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 03:15:31 INFO - (blobuploader) - INFO - Open directory for files ... 03:15:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:15:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:15:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:15:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:15:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:15:31 INFO - (blobuploader) - INFO - Done attempting. 03:15:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:15:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:15:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:15:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:15:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:15:32 INFO - (blobuploader) - INFO - Done attempting. 03:15:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:15:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:15:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:15:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:15:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:15:35 INFO - (blobuploader) - INFO - Done attempting. 03:15:35 INFO - (blobuploader) - INFO - Iteration through files over. 03:15:35 INFO - Return code: 0 03:15:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:15:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:15:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7d4f2824d43c749d3c2136239abc1dc553324156762df63179d8a5696fb07e75d1af85ce4b0a43a36ad6b61496959de4659a2452889d751e8094fbaf95b87ba2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac5b9547b24ad1feea4a096a2db55515570fe5a9ef288c774e103ecb91c727c1adf5e3746eec593cecd99ecc68b2bb726fc8dd0dc4c08e2f6b4074028fd031e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11d53080fe7f4a27c8207648788a10f1e3e9c91dd68c23e47a86b62d08cf8029580aa873b5ab31e826d61b04f207859df5b98b7d42014ac790affc06d87e5ff6"} 03:15:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:15:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:15:35 INFO - Contents: 03:15:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7d4f2824d43c749d3c2136239abc1dc553324156762df63179d8a5696fb07e75d1af85ce4b0a43a36ad6b61496959de4659a2452889d751e8094fbaf95b87ba2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac5b9547b24ad1feea4a096a2db55515570fe5a9ef288c774e103ecb91c727c1adf5e3746eec593cecd99ecc68b2bb726fc8dd0dc4c08e2f6b4074028fd031e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11d53080fe7f4a27c8207648788a10f1e3e9c91dd68c23e47a86b62d08cf8029580aa873b5ab31e826d61b04f207859df5b98b7d42014ac790affc06d87e5ff6"} 03:15:35 INFO - Running post-run listener: copy_logs_to_upload_dir 03:15:35 INFO - Copying logs to upload dir... 03:15:35 INFO - mkdir: /builds/slave/test/build/upload/logs 03:15:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.960704 ========= master_lag: 0.51 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-01-21 03:15:35.411765) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-21 03:15:35.415196) ========= 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.b9Ad1QhwM9/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.8v6tfFhstf/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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/7d4f2824d43c749d3c2136239abc1dc553324156762df63179d8a5696fb07e75d1af85ce4b0a43a36ad6b61496959de4659a2452889d751e8094fbaf95b87ba2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac5b9547b24ad1feea4a096a2db55515570fe5a9ef288c774e103ecb91c727c1adf5e3746eec593cecd99ecc68b2bb726fc8dd0dc4c08e2f6b4074028fd031e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11d53080fe7f4a27c8207648788a10f1e3e9c91dd68c23e47a86b62d08cf8029580aa873b5ab31e826d61b04f207859df5b98b7d42014ac790affc06d87e5ff6"} build_url:https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011254 build_url: 'https://queue.taskcluster.net/v1/task/eSC9gBcqQ9G-gwL_gezPww/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/7d4f2824d43c749d3c2136239abc1dc553324156762df63179d8a5696fb07e75d1af85ce4b0a43a36ad6b61496959de4659a2452889d751e8094fbaf95b87ba2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac5b9547b24ad1feea4a096a2db55515570fe5a9ef288c774e103ecb91c727c1adf5e3746eec593cecd99ecc68b2bb726fc8dd0dc4c08e2f6b4074028fd031e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11d53080fe7f4a27c8207648788a10f1e3e9c91dd68c23e47a86b62d08cf8029580aa873b5ab31e826d61b04f207859df5b98b7d42014ac790affc06d87e5ff6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-21 03:15:35.448611) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-21 03:15:35.448921) ========= 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.b9Ad1QhwM9/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.8v6tfFhstf/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.005358 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-21 03:15:35.497586) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-21 03:15:35.497903) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-21 03:15:35.498229) ========= ========= Total master_lag: 0.65 =========