builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0365 starttime: 1483871753.88 results: success (0) buildid: 20170108004005 builduid: 444daa4f3acd44f295665250c309df75 revision: 4195687bda73679988aaff7b27a8c3b31e38a0fc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:53.884579) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:53.885041) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:53.885318) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:53.913223) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:53.913616) ========= 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.QH8uurrJpV/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.4S06lLIuBW/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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-08 02:35:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.9M=0.001s 2017-01-08 02:35:54 (17.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.568842 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:54.516380) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:54.516725) ========= 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.QH8uurrJpV/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.4S06lLIuBW/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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.025992 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:54.575028) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:54.575738) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4195687bda73679988aaff7b27a8c3b31e38a0fc --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 4195687bda73679988aaff7b27a8c3b31e38a0fc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QH8uurrJpV/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.4S06lLIuBW/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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-08 02:35:54,682 truncating revision to first 12 chars 2017-01-08 02:35:54,682 Setting DEBUG logging. 2017-01-08 02:35:54,682 attempt 1/10 2017-01-08 02:35:54,682 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4195687bda73?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-08 02:35:54,996 unpacking tar archive at: mozilla-aurora-4195687bda73/testing/mozharness/ program finished with exit code 0 elapsedTime=0.590155 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:55.196700) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:55.197113) ========= script_repo_revision: 4195687bda73679988aaff7b27a8c3b31e38a0fc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:55.197566) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:55.197944) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:55.218195) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-08 02:35:55.218631) ========= /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.QH8uurrJpV/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.4S06lLIuBW/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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:35:55 INFO - MultiFileLogger online at 20170108 02:35:55 in /builds/slave/test 02:35:55 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:35:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:35:55 INFO - {'append_to_log': False, 02:35:55 INFO - 'base_work_dir': '/builds/slave/test', 02:35:55 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:35:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:35:55 INFO - 'branch': 'Mozilla-Aurora', 02:35:55 INFO - 'buildbot_json_path': 'buildprops.json', 02:35:55 INFO - 'config_files': ('talos/mac_config.py',), 02:35:55 INFO - 'default_actions': ('clobber', 02:35:55 INFO - 'read-buildbot-config', 02:35:55 INFO - 'download-and-extract', 02:35:55 INFO - 'populate-webroot', 02:35:55 INFO - 'create-virtualenv', 02:35:55 INFO - 'install', 02:35:55 INFO - 'run-tests'), 02:35:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:35:55 INFO - 'download_minidump_stackwalk': True, 02:35:55 INFO - 'download_symbols': 'ondemand', 02:35:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:35:55 INFO - 'tooltool.py': '/tools/tooltool.py', 02:35:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:35:55 INFO - '/tools/misc-python/virtualenv.py')}, 02:35:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:35:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:35:55 INFO - 'installer_path': 'installer.exe', 02:35:55 INFO - 'log_level': 'info', 02:35:55 INFO - 'log_name': 'talos', 02:35:55 INFO - 'log_to_console': True, 02:35:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:35:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:35:55 INFO - 'opt_config_files': (), 02:35:55 INFO - 'pip_index': False, 02:35:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:35:55 INFO - 'cmd': ('bash', 02:35:55 INFO - '-c', 02:35:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:35:55 INFO - 'enabled': True, 02:35:55 INFO - 'halt_on_failure': False, 02:35:55 INFO - 'name': 'check_screen_resolution'},), 02:35:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:35:55 INFO - 'cmd': ('bash', 02:35:55 INFO - '-c', 02:35:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:35:55 INFO - 'enabled': True, 02:35:55 INFO - 'halt_on_failure': False, 02:35:55 INFO - 'name': 'check_screen_resolution'},), 02:35:55 INFO - 'run_cmd_checks_enabled': True, 02:35:55 INFO - 'sps_profile': False, 02:35:55 INFO - 'sps_profile_interval': 0, 02:35:55 INFO - 'suite': 'dromaeojs-e10s', 02:35:55 INFO - 'system_bits': '32', 02:35:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:35:55 INFO - 'title': 't-yosemite-r7-0365', 02:35:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:35:55 INFO - 'use_talos_json': True, 02:35:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:35:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:35:55 INFO - 'work_dir': 'build'} 02:35:55 INFO - [mozharness: 2017-01-08 10:35:55.424739Z] Running clobber step. 02:35:55 INFO - Running pre-action listener: _resource_record_pre_action 02:35:55 INFO - Running main action method: clobber 02:35:55 INFO - rmtree: /builds/slave/test/build 02:35:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:35:57 INFO - Running post-action listener: _resource_record_post_action 02:35:57 INFO - [mozharness: 2017-01-08 10:35:57.821082Z] Finished clobber step (success) 02:35:57 INFO - [mozharness: 2017-01-08 10:35:57.821238Z] Running read-buildbot-config step. 02:35:57 INFO - Running pre-action listener: _resource_record_pre_action 02:35:57 INFO - Running main action method: read_buildbot_config 02:35:57 INFO - Using buildbot properties: 02:35:57 INFO - { 02:35:57 INFO - "project": "", 02:35:57 INFO - "product": "firefox", 02:35:57 INFO - "script_repo_revision": "production", 02:35:57 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:35:57 INFO - "repository": "", 02:35:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:35:57 INFO - "buildid": "20170108004005", 02:35:57 INFO - "stage_platform": "macosx64", 02:35:57 INFO - "basedir": "/builds/slave/test", 02:35:57 INFO - "nightly_build": "True", 02:35:57 INFO - "buildnumber": 24, 02:35:57 INFO - "slavename": "t-yosemite-r7-0365", 02:35:57 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 02:35:57 INFO - "platform": "yosemite_r7", 02:35:57 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:35:57 INFO - "branch": "mozilla-aurora", 02:35:57 INFO - "repo_path": "releases/mozilla-aurora", 02:35:57 INFO - "revision": "4195687bda73679988aaff7b27a8c3b31e38a0fc", 02:35:57 INFO - "pgo_build": "False", 02:35:57 INFO - "builduid": "444daa4f3acd44f295665250c309df75", 02:35:57 INFO - "slavebuilddir": "test" 02:35:57 INFO - } 02:35:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:35:57 INFO - Running post-action listener: _resource_record_post_action 02:35:57 INFO - [mozharness: 2017-01-08 10:35:57.824831Z] Finished read-buildbot-config step (success) 02:35:57 INFO - [mozharness: 2017-01-08 10:35:57.824963Z] Running download-and-extract step. 02:35:57 INFO - Running pre-action listener: _resource_record_pre_action 02:35:57 INFO - Running main action method: download_and_extract 02:35:57 INFO - mkdir: /builds/slave/test/build/tests 02:35:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:35:57 INFO - trying https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:35:57 INFO - Downloading https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/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:35:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/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:35:59 INFO - Downloaded 1199 bytes. 02:35:59 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:35:59 INFO - Using the following test package requirements: 02:35:59 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:35:59 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:35:59 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:35:59 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:35:59 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:35:59 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:35:59 INFO - u'jsshell-mac.zip'], 02:35:59 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:35:59 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:35:59 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:35:59 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:35:59 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:35:59 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:35:59 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:35:59 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:35:59 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:35:59 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:35:59 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:35:59 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:35:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:35:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:35:59 INFO - Fetch https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:36:00 INFO - Http code: 200 02:36:00 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 02:36:00 INFO - x-amz-cf-id: zczF0-okpM3YfHYkxmIQmedVW8NnesUpga9eguXsKGZF8WQGHgksGg== 02:36:00 INFO - x-amz-version-id: pAZ44Ngzs71LNCIGGrPsUKguk3dze3xi 02:36:00 INFO - Expected file size: 16270967 02:36:00 INFO - Obtained file size: 16270967 02:36:06 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:36:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:36:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:36:06 INFO - Fetch https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:36:07 INFO - Http code: 200 02:36:07 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 02:36:07 INFO - x-amz-cf-id: _Fh6D2wl75Scr-4qXbaY5pGeXjrfAmAxv86xL96_ZX7BnrrDZx3xbQ== 02:36:07 INFO - x-amz-version-id: pAZ44Ngzs71LNCIGGrPsUKguk3dze3xi 02:36:07 INFO - Expected file size: 16270967 02:36:07 INFO - Obtained file size: 16270967 02:36:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:36:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:36:14 INFO - Fetch https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:36:15 INFO - Http code: 200 02:36:15 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 02:36:15 INFO - x-amz-cf-id: Hj-J2BQptGGLTthAlUUhxpK_8khLOc0cgtO5k1r9BvBztMurICyUYA== 02:36:15 INFO - x-amz-version-id: HT.wyrkeqXe0f4bVVJBe9.0ezEYqB_3d 02:36:15 INFO - Expected file size: 14409038 02:36:15 INFO - Obtained file size: 14409038 02:36: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:36:15 INFO - trying https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:36:15 INFO - Downloading https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:36:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/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:36:18 INFO - Downloaded 110001326 bytes. 02:36:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:36:18 INFO - mkdir: /builds/slave/test/properties 02:36:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:36:18 INFO - Writing to file /builds/slave/test/properties/build_url 02:36:18 INFO - Contents: 02:36:18 INFO - build_url:https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:36:19 INFO - Running post-action listener: _resource_record_post_action 02:36:19 INFO - Running post-action listener: set_extra_try_arguments 02:36:19 INFO - [mozharness: 2017-01-08 10:36:19.227317Z] Finished download-and-extract step (success) 02:36:19 INFO - [mozharness: 2017-01-08 10:36:19.227445Z] Running populate-webroot step. 02:36:19 INFO - Running pre-action listener: _resource_record_pre_action 02:36:19 INFO - Running main action method: populate_webroot 02:36:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:36:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:36:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:36:19 INFO - '--tppagecycles', 02:36:19 INFO - '7'], 02:36:19 INFO - 'tests': ['tsvgm']}}, 02:36:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:36:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:36:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:36:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:36:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:36:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:36:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:36:19 INFO - 'talos_options': ['--disable-e10s'], 02:36:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:36:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:36:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:36:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:36:19 INFO - 'talos_options': ['--disable-e10s'], 02:36:19 INFO - 'tests': ['damp', 'tps']}, 02:36:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:36:19 INFO - 'tests': ['damp', 'tps']}, 02:36:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:36:19 INFO - 'tests': ['dromaeo_dom']}, 02:36:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:36:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:36:19 INFO - 'tests': ['basic_compositor_video']}, 02:36:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:36:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:36:19 INFO - 'tests': ['a11yr', 02:36:19 INFO - 'ts_paint', 02:36:19 INFO - 'tpaint', 02:36:19 INFO - 'sessionrestore', 02:36:19 INFO - 'sessionrestore_no_auto_restore', 02:36:19 INFO - 'tabpaint']}, 02:36:19 INFO - 'other-e10s': {'tests': ['a11yr', 02:36:19 INFO - 'ts_paint', 02:36:19 INFO - 'tpaint', 02:36:19 INFO - 'sessionrestore', 02:36:19 INFO - 'sessionrestore_no_auto_restore', 02:36:19 INFO - 'tabpaint']}, 02:36:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:36:19 INFO - 'ts_paint', 02:36:19 INFO - 'tpaint', 02:36:19 INFO - 'sessionrestore', 02:36:19 INFO - 'sessionrestore_no_auto_restore', 02:36:19 INFO - 'tabpaint']}, 02:36:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:36:19 INFO - 'ts_paint', 02:36:19 INFO - 'tpaint', 02:36:19 INFO - 'sessionrestore', 02:36:19 INFO - 'sessionrestore_no_auto_restore', 02:36:19 INFO - 'tabpaint']}, 02:36:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:36:19 INFO - 'tests': ['a11yr', 02:36:19 INFO - 'ts_paint', 02:36:19 INFO - 'tpaint', 02:36:19 INFO - 'sessionrestore', 02:36:19 INFO - 'sessionrestore_no_auto_restore', 02:36:19 INFO - 'tabpaint']}, 02:36:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:36:19 INFO - 'tests': ['a11yr', 02:36:19 INFO - 'ts_paint', 02:36:19 INFO - 'tpaint', 02:36:19 INFO - 'sessionrestore', 02:36:19 INFO - 'sessionrestore_no_auto_restore', 02:36:19 INFO - 'tabpaint']}, 02:36:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:36:19 INFO - 'tests': ['tsvgx', 02:36:19 INFO - 'tsvgr_opacity', 02:36:19 INFO - 'tart', 02:36:19 INFO - 'tscrollx', 02:36:19 INFO - 'cart']}, 02:36:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:36:19 INFO - 'tsvgr_opacity', 02:36:19 INFO - 'tart', 02:36:19 INFO - 'tscrollx', 02:36:19 INFO - 'cart']}, 02:36:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:36:19 INFO - 'talos_options': ['--disable-e10s'], 02:36:19 INFO - 'tests': ['tp5o']}, 02:36:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:36:19 INFO - 'tests': ['tp5o']}, 02:36:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:36:19 INFO - 'talos_options': ['--disable-e10s', 02:36:19 INFO - '--xperf_path', 02:36:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:36:19 INFO - 'tests': ['tp5n']}, 02:36:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:36:19 INFO - 'talos_options': ['--xperf_path', 02:36:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:36:19 INFO - 'tests': ['tp5n']}}, 02:36:19 INFO - 'talos.zip': {'path': '', 02:36:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:36:19 INFO - Running post-action listener: _resource_record_post_action 02:36:19 INFO - [mozharness: 2017-01-08 10:36:19.246258Z] Finished populate-webroot step (success) 02:36:19 INFO - [mozharness: 2017-01-08 10:36:19.246368Z] Running create-virtualenv step. 02:36:19 INFO - Running pre-action listener: _resource_record_pre_action 02:36:19 INFO - Running main action method: create_virtualenv 02:36:19 INFO - Creating virtualenv /builds/slave/test/build/venv 02:36:19 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:36:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:36:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:36:19 INFO - Using real prefix '/tools/python27' 02:36:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:36:20 INFO - Installing distribute.............................................................................................................................................................................................done. 02:36:22 INFO - Installing pip.................done. 02:36:22 INFO - Return code: 0 02:36:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:36:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:36:23 INFO - Reading from file tmpfile_stdout 02:36:23 INFO - Output received: 02:36:23 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:36:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e70a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3c95d5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e51ed30>, '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 0x10e384db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e516b20>, '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.QH8uurrJpV/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.4S06lLIuBW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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:36:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:36:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QH8uurrJpV/Render', 02:36:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:23 INFO - 'HOME': '/Users/cltbld', 02:36:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:23 INFO - 'LOGNAME': 'cltbld', 02:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:23 INFO - 'MOZ_NO_REMOTE': '1', 02:36:23 INFO - 'NO_EM_RESTART': '1', 02:36:23 INFO - 'PAGER': '/bin/cat', 02:36:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:23 INFO - 'PWD': '/builds/slave/test', 02:36:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:23 INFO - 'SHELL': '/bin/bash', 02:36:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4S06lLIuBW/Listeners', 02:36:23 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 02:36:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:23 INFO - 'USER': 'cltbld', 02:36:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:23 INFO - 'XPC_FLAGS': '0x0', 02:36:23 INFO - 'XPC_SERVICE_NAME': '0', 02:36:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:23 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:36:23 INFO - Cleaning up... 02:36:23 INFO - Return code: 0 02:36:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e70a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3c95d5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e51ed30>, '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 0x10e384db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e516b20>, '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.QH8uurrJpV/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.4S06lLIuBW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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:36:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:36:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QH8uurrJpV/Render', 02:36:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:23 INFO - 'HOME': '/Users/cltbld', 02:36:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:23 INFO - 'LOGNAME': 'cltbld', 02:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:23 INFO - 'MOZ_NO_REMOTE': '1', 02:36:23 INFO - 'NO_EM_RESTART': '1', 02:36:23 INFO - 'PAGER': '/bin/cat', 02:36:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:23 INFO - 'PWD': '/builds/slave/test', 02:36:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:23 INFO - 'SHELL': '/bin/bash', 02:36:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4S06lLIuBW/Listeners', 02:36:23 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 02:36:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:23 INFO - 'USER': 'cltbld', 02:36:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:23 INFO - 'XPC_FLAGS': '0x0', 02:36:23 INFO - 'XPC_SERVICE_NAME': '0', 02:36:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:23 INFO - Downloading/unpacking psutil>=3.1.1 02:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:36:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:36:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:36:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:36:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:36:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:36:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:36:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:36:27 INFO - Installing collected packages: psutil 02:36:27 INFO - Running setup.py install for psutil 02:36:27 INFO - building 'psutil._psutil_osx' extension 02:36:27 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:36:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:36:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:36:28 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:36:28 INFO - building 'psutil._psutil_posix' extension 02:36:28 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:36:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:36:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:36:28 INFO - ^ 02:36:28 INFO - 1 warning generated. 02:36:28 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:36:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:36:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:36:28 INFO - Successfully installed psutil 02:36:28 INFO - Cleaning up... 02:36:28 INFO - Return code: 0 02:36:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:36:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e70a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3c95d5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e51ed30>, '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 0x10e384db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e516b20>, '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.QH8uurrJpV/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.4S06lLIuBW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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:36:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:36:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:36:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QH8uurrJpV/Render', 02:36:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:28 INFO - 'HOME': '/Users/cltbld', 02:36:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:28 INFO - 'LOGNAME': 'cltbld', 02:36:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:28 INFO - 'MOZ_NO_REMOTE': '1', 02:36:28 INFO - 'NO_EM_RESTART': '1', 02:36:28 INFO - 'PAGER': '/bin/cat', 02:36:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:28 INFO - 'PWD': '/builds/slave/test', 02:36:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:28 INFO - 'SHELL': '/bin/bash', 02:36:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4S06lLIuBW/Listeners', 02:36:28 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 02:36:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:28 INFO - 'USER': 'cltbld', 02:36:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:28 INFO - 'XPC_FLAGS': '0x0', 02:36:28 INFO - 'XPC_SERVICE_NAME': '0', 02:36:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:36:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:36:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:36:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:36:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:36:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:36:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:36:31 INFO - Installing collected packages: mozsystemmonitor 02:36:31 INFO - Running setup.py install for mozsystemmonitor 02:36:31 INFO - Successfully installed mozsystemmonitor 02:36:31 INFO - Cleaning up... 02:36:32 INFO - Return code: 0 02:36:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e70a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3c95d5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e51ed30>, '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 0x10e384db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e516b20>, '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.QH8uurrJpV/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.4S06lLIuBW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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:36:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:36:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QH8uurrJpV/Render', 02:36:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:32 INFO - 'HOME': '/Users/cltbld', 02:36:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:32 INFO - 'LOGNAME': 'cltbld', 02:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:32 INFO - 'MOZ_NO_REMOTE': '1', 02:36:32 INFO - 'NO_EM_RESTART': '1', 02:36:32 INFO - 'PAGER': '/bin/cat', 02:36:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:32 INFO - 'PWD': '/builds/slave/test', 02:36:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:32 INFO - 'SHELL': '/bin/bash', 02:36:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4S06lLIuBW/Listeners', 02:36:32 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 02:36:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:32 INFO - 'USER': 'cltbld', 02:36:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:32 INFO - 'XPC_FLAGS': '0x0', 02:36:32 INFO - 'XPC_SERVICE_NAME': '0', 02:36:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:32 INFO - Downloading/unpacking jsonschema==2.5.1 02:36:32 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:36:32 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:36:32 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:36:32 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:36:32 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:36:32 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:36:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:36:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:36:34 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:36:34 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:36:34 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:36:34 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:36:34 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:36:34 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:36:35 INFO - Downloading functools32-3.2.3-2.tar.gz 02:36:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:36:35 INFO - warning: no files found matching '*.txt' 02:36:35 INFO - no previously-included directories found matching 'build' 02:36:35 INFO - no previously-included directories found matching 'dist' 02:36:35 INFO - no previously-included directories found matching '.git*' 02:36:35 INFO - Installing collected packages: jsonschema, functools32 02:36:35 INFO - Running setup.py install for functools32 02:36:35 INFO - warning: no files found matching '*.txt' 02:36:35 INFO - no previously-included directories found matching 'build' 02:36:35 INFO - no previously-included directories found matching 'dist' 02:36:35 INFO - no previously-included directories found matching '.git*' 02:36:35 INFO - Successfully installed jsonschema functools32 02:36:35 INFO - Cleaning up... 02:36:35 INFO - Return code: 0 02:36:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:36:35 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:36:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:35 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:36:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:35 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 0x10e70a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3c95d5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e51ed30>, '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 0x10e384db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e516b20>, '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.QH8uurrJpV/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.4S06lLIuBW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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:36:35 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:36:35 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:36:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QH8uurrJpV/Render', 02:36:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:35 INFO - 'HOME': '/Users/cltbld', 02:36:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:35 INFO - 'LOGNAME': 'cltbld', 02:36:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:35 INFO - 'MOZ_NO_REMOTE': '1', 02:36:35 INFO - 'NO_EM_RESTART': '1', 02:36:35 INFO - 'PAGER': '/bin/cat', 02:36:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:35 INFO - 'PWD': '/builds/slave/test', 02:36:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:35 INFO - 'SHELL': '/bin/bash', 02:36:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4S06lLIuBW/Listeners', 02:36:35 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 02:36:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:35 INFO - 'USER': 'cltbld', 02:36:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:35 INFO - 'XPC_FLAGS': '0x0', 02:36:35 INFO - 'XPC_SERVICE_NAME': '0', 02:36:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:36:35 INFO - Cleaning up... 02:36:35 INFO - Return code: 0 02:36:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:36:35 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:36:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:35 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:36:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:35 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 0x10e70a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3c95d5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e51ed30>, '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 0x10e384db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e516b20>, '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.QH8uurrJpV/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.4S06lLIuBW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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:36:35 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:36:35 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:36:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QH8uurrJpV/Render', 02:36:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:35 INFO - 'HOME': '/Users/cltbld', 02:36:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:35 INFO - 'LOGNAME': 'cltbld', 02:36:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:35 INFO - 'MOZ_NO_REMOTE': '1', 02:36:35 INFO - 'NO_EM_RESTART': '1', 02:36:35 INFO - 'PAGER': '/bin/cat', 02:36:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:35 INFO - 'PWD': '/builds/slave/test', 02:36:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:35 INFO - 'SHELL': '/bin/bash', 02:36:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4S06lLIuBW/Listeners', 02:36:35 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 02:36:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:35 INFO - 'USER': 'cltbld', 02:36:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:35 INFO - 'XPC_FLAGS': '0x0', 02:36:35 INFO - 'XPC_SERVICE_NAME': '0', 02:36:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:36 INFO - Downloading/unpacking blobuploader==1.2.4 02:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:36:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:36:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:36:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:36:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:36:38 INFO - Downloading blobuploader-1.2.4.tar.gz 02:36:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:36:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:36:38 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:36:38 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:36:38 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:36:38 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:36:38 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:36:38 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:36:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:36:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:36:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:36:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:36:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:36:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:36:40 INFO - Downloading docopt-0.6.1.tar.gz 02:36:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:36:40 INFO - Installing collected packages: blobuploader, requests, docopt 02:36:40 INFO - Running setup.py install for blobuploader 02:36:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:36:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:36:40 INFO - Running setup.py install for requests 02:36:40 INFO - Running setup.py install for docopt 02:36:40 INFO - Successfully installed blobuploader requests docopt 02:36:40 INFO - Cleaning up... 02:36:41 INFO - Return code: 0 02:36:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:36:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e70a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3c95d5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e51ed30>, '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 0x10e384db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e516b20>, '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.QH8uurrJpV/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.4S06lLIuBW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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:36:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:36:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:36:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QH8uurrJpV/Render', 02:36:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:41 INFO - 'HOME': '/Users/cltbld', 02:36:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:41 INFO - 'LOGNAME': 'cltbld', 02:36:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:41 INFO - 'MOZ_NO_REMOTE': '1', 02:36:41 INFO - 'NO_EM_RESTART': '1', 02:36:41 INFO - 'PAGER': '/bin/cat', 02:36:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:41 INFO - 'PWD': '/builds/slave/test', 02:36:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:41 INFO - 'SHELL': '/bin/bash', 02:36:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4S06lLIuBW/Listeners', 02:36:41 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 02:36:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:41 INFO - 'USER': 'cltbld', 02:36:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:41 INFO - 'XPC_FLAGS': '0x0', 02:36:41 INFO - 'XPC_SERVICE_NAME': '0', 02:36:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:36:41 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-2RP5pr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:36:41 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-aXwPVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:36:41 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-qQpxVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:36:41 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-R6QQK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:36:41 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-09bSe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:36:41 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-QxPsUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:36:41 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-13eB4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:36:42 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-MNqrQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:36:42 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-oL_V8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:36:42 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-P7t7lJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:36:42 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-8scCcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:36:42 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-f5dymE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:36:42 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-aFyCoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:36:42 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-AEVTw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:36:42 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-2cctj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:36:42 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-rM2Aqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:36:43 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-nuwZu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:36:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:36:43 INFO - Running setup.py install for manifestparser 02:36:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:36:43 INFO - Running setup.py install for mozcrash 02:36:43 INFO - Running setup.py install for mozdebug 02:36:43 INFO - Running setup.py install for mozdevice 02:36:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:36:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:36:43 INFO - Running setup.py install for mozfile 02:36:43 INFO - Running setup.py install for mozhttpd 02:36:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:36:44 INFO - Running setup.py install for mozinfo 02:36:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:36:44 INFO - Running setup.py install for mozInstall 02:36:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:36:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:36:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:36:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:36:44 INFO - Running setup.py install for mozleak 02:36:44 INFO - Running setup.py install for mozlog 02:36:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:36:44 INFO - Running setup.py install for moznetwork 02:36:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:36:44 INFO - Running setup.py install for mozprocess 02:36:44 INFO - Running setup.py install for mozprofile 02:36:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:36:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:36:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:36:45 INFO - Running setup.py install for mozrunner 02:36:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:36:45 INFO - Running setup.py install for mozscreenshot 02:36:45 INFO - Running setup.py install for moztest 02:36:45 INFO - Running setup.py install for mozversion 02:36:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:36:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:36:45 INFO - Cleaning up... 02:36:45 INFO - Return code: 0 02:36:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:36:45 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:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:45 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:36:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:45 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 0x10e70a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3c95d5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e51ed30>, '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 0x10e384db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e516b20>, '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.QH8uurrJpV/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.4S06lLIuBW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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:36:45 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:36:45 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:36:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QH8uurrJpV/Render', 02:36:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:45 INFO - 'HOME': '/Users/cltbld', 02:36:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:45 INFO - 'LOGNAME': 'cltbld', 02:36:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:45 INFO - 'MOZ_NO_REMOTE': '1', 02:36:45 INFO - 'NO_EM_RESTART': '1', 02:36:45 INFO - 'PAGER': '/bin/cat', 02:36:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:45 INFO - 'PWD': '/builds/slave/test', 02:36:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:45 INFO - 'SHELL': '/bin/bash', 02:36:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4S06lLIuBW/Listeners', 02:36:45 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 02:36:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:45 INFO - 'USER': 'cltbld', 02:36:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:45 INFO - 'XPC_FLAGS': '0x0', 02:36:45 INFO - 'XPC_SERVICE_NAME': '0', 02:36:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:36:46 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-bhjm9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:36:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:36:46 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-CyKAUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:36:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:36:46 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-scTTWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:36:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:36:46 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-tA52M5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:36:46 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:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:36:46 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-HN2OJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:36:46 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:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:36:46 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-GpJzoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:36:46 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:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:36:46 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-QPUYll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:36:46 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:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:36:46 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-SsZSXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:36:46 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:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:36:46 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-sbgQzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:36:46 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:36:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:36:46 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-YbMoEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:36:47 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Ok9bLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:36:47 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-8OyJHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:36:47 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-ycH1yk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:36:47 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-p8yH_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:36:47 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:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:36:47 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-CUNvi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:36:47 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:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:36:47 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-WsgQg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:36:47 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:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:36:47 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-cWw3bG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:36:47 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:36:47 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:36:47 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:36:47 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:36:47 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:36:47 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:36:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:36:47 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:36:47 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:36:47 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:36:47 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:36:47 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:36:47 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:36:50 INFO - Downloading blessings-1.6.tar.gz 02:36:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:36:50 INFO - Installing collected packages: blessings 02:36:50 INFO - Running setup.py install for blessings 02:36:50 INFO - Successfully installed blessings 02:36:50 INFO - Cleaning up... 02:36:50 INFO - Return code: 0 02:36:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:36:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:36:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:36:51 INFO - Reading from file tmpfile_stdout 02:36:51 INFO - Current package versions: 02:36:51 INFO - blessings == 1.6 02:36:51 INFO - blobuploader == 1.2.4 02:36:51 INFO - docopt == 0.6.1 02:36:51 INFO - functools32 == 3.2.3-2 02:36:51 INFO - jsonschema == 2.5.1 02:36:51 INFO - manifestparser == 1.1 02:36:51 INFO - mozInstall == 1.12 02:36:51 INFO - mozcrash == 1.0 02:36:51 INFO - mozdebug == 0.1 02:36:51 INFO - mozdevice == 0.48 02:36:51 INFO - mozfile == 1.2 02:36:51 INFO - mozhttpd == 0.7 02:36:51 INFO - mozinfo == 0.9 02:36:51 INFO - mozleak == 0.1 02:36:51 INFO - mozlog == 3.4 02:36:51 INFO - moznetwork == 0.27 02:36:51 INFO - mozprocess == 0.23 02:36:51 INFO - mozprofile == 0.28 02:36:51 INFO - mozrunner == 6.13 02:36:51 INFO - mozscreenshot == 0.1 02:36:51 INFO - mozsystemmonitor == 0.3 02:36:51 INFO - moztest == 0.8 02:36:51 INFO - mozversion == 1.4 02:36:51 INFO - psutil == 3.1.1 02:36:51 INFO - requests == 1.2.3 02:36:51 INFO - wsgiref == 0.1.2 02:36:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:36:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:36:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e70a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3c95d5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e51ed30>, '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 0x10e384db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e516b20>, '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.QH8uurrJpV/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.4S06lLIuBW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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:36:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:36:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QH8uurrJpV/Render', 02:36:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:51 INFO - 'HOME': '/Users/cltbld', 02:36:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:51 INFO - 'LOGNAME': 'cltbld', 02:36:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:51 INFO - 'MOZ_NO_REMOTE': '1', 02:36:51 INFO - 'NO_EM_RESTART': '1', 02:36:51 INFO - 'PAGER': '/bin/cat', 02:36:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:51 INFO - 'PWD': '/builds/slave/test', 02:36:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:51 INFO - 'SHELL': '/bin/bash', 02:36:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4S06lLIuBW/Listeners', 02:36:51 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 02:36:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:51 INFO - 'USER': 'cltbld', 02:36:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:51 INFO - 'XPC_FLAGS': '0x0', 02:36:51 INFO - 'XPC_SERVICE_NAME': '0', 02:36:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:36:51 INFO - Cleaning up... 02:36:51 INFO - Return code: 0 02:36:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:36:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:36:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e70a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3c95d5590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e51ed30>, '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 0x10e384db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e516b20>, '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.QH8uurrJpV/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.4S06lLIuBW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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:36:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:36:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QH8uurrJpV/Render', 02:36:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:51 INFO - 'HOME': '/Users/cltbld', 02:36:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:51 INFO - 'LOGNAME': 'cltbld', 02:36:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:51 INFO - 'MOZ_NO_REMOTE': '1', 02:36:51 INFO - 'NO_EM_RESTART': '1', 02:36:51 INFO - 'PAGER': '/bin/cat', 02:36:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:51 INFO - 'PWD': '/builds/slave/test', 02:36:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:51 INFO - 'SHELL': '/bin/bash', 02:36:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4S06lLIuBW/Listeners', 02:36:51 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 02:36:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:51 INFO - 'USER': 'cltbld', 02:36:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:51 INFO - 'XPC_FLAGS': '0x0', 02:36:51 INFO - 'XPC_SERVICE_NAME': '0', 02:36:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:36:51 INFO - Cleaning up... 02:36:51 INFO - Return code: 0 02:36:51 INFO - Running post-action listener: _resource_record_post_action 02:36:51 INFO - Running post-action listener: _start_resource_monitoring 02:36:51 INFO - Starting resource monitoring. 02:36:51 INFO - [mozharness: 2017-01-08 10:36:51.726315Z] Finished create-virtualenv step (success) 02:36:51 INFO - [mozharness: 2017-01-08 10:36:51.727390Z] Running install step. 02:36:51 INFO - Running pre-action listener: _resource_record_pre_action 02:36:51 INFO - Running main action method: install 02:36:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:36:51 INFO - Reading from file tmpfile_stdout 02:36:51 INFO - Detecting whether we're running mozinstall >=1.0... 02:36:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:36:52 INFO - Reading from file tmpfile_stdout 02:36:52 INFO - Output received: 02:36:52 INFO - Usage: mozinstall [options] installer 02:36:52 INFO - Options: 02:36:52 INFO - -h, --help show this help message and exit 02:36:52 INFO - -d DEST, --destination=DEST 02:36:52 INFO - Directory to install application into. [default: 02:36:52 INFO - "/builds/slave/test"] 02:36:52 INFO - --app=APP Application being installed. [default: firefox] 02:36:52 INFO - mkdir: /builds/slave/test/build/application 02:36:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:36:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:37:23 INFO - Reading from file tmpfile_stdout 02:37:23 INFO - Output received: 02:37:23 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:37:23 INFO - Running post-action listener: _resource_record_post_action 02:37:23 INFO - [mozharness: 2017-01-08 10:37:23.436152Z] Finished install step (success) 02:37:23 INFO - [mozharness: 2017-01-08 10:37:23.436299Z] Running run-tests step. 02:37:23 INFO - Running pre-action listener: _resource_record_pre_action 02:37:23 INFO - Running main action method: run_tests 02:37:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:37:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:37:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:37:23 INFO - 2017-01-08 02:37:23.451 screenresolution[1629:7767] starting screenresolution argv=screenresolution get 02:37:23 INFO - 2017-01-08 02:37:23.454 screenresolution[1629:7767] Display 0: 1600x1200x32@60 02:37:23 INFO - 2017-01-08 02:37:23.459 screenresolution[1630:7769] starting screenresolution argv=screenresolution list 02:37:23 INFO - Available Modes on Display 0 02:37:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:37:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:37:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:37:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:37:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:37:23 INFO - Intel Iris: 02:37:23 INFO - Chipset Model: Intel Iris 02:37:23 INFO - Type: GPU 02:37:23 INFO - Bus: Built-In 02:37:23 INFO - VRAM (Dynamic, Max): 1536 MB 02:37:23 INFO - Vendor: Intel (0x8086) 02:37:23 INFO - Device ID: 0x0a2e 02:37:23 INFO - Revision ID: 0x0009 02:37:23 INFO - Displays: 02:37:23 INFO - Display: 02:37:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:37:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:37:23 INFO - Display Serial Number: 1600x1200 60 02:37:23 INFO - Main Display: Yes 02:37:23 INFO - Mirror: Off 02:37:23 INFO - Online: Yes 02:37:23 INFO - Rotation: Supported 02:37:23 INFO - Return code: 0 02:37:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:37:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:37:23 INFO - Python 2.7.3 02:37:23 INFO - Return code: 0 02:37:23 INFO - grabbing minidump binary from tooltool 02:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:23 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e51ed30>, '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 0x10e384db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e516b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:37:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:37:23 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:37:23 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:37:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:37:23 INFO - Return code: 0 02:37:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:37:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:37:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:37:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:37:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:37:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--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:37:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0365 --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:37:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QH8uurrJpV/Render', 02:37:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:23 INFO - 'HOME': '/Users/cltbld', 02:37:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:23 INFO - 'LOGNAME': 'cltbld', 02:37:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:37:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:23 INFO - 'MOZ_NO_REMOTE': '1', 02:37:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:37:23 INFO - 'NO_EM_RESTART': '1', 02:37:23 INFO - 'PAGER': '/bin/cat', 02:37:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:23 INFO - 'PWD': '/builds/slave/test', 02:37:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:37:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:23 INFO - 'SHELL': '/bin/bash', 02:37:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4S06lLIuBW/Listeners', 02:37:23 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 02:37:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:23 INFO - 'USER': 'cltbld', 02:37:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:23 INFO - 'XPC_FLAGS': '0x0', 02:37:23 INFO - 'XPC_SERVICE_NAME': '0', 02:37:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--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:37:24 INFO - mozversion application_buildid: 20170108004005 02:37:24 INFO - mozversion application_changeset: 4195687bda73679988aaff7b27a8c3b31e38a0fc 02:37:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:37:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:37:24 INFO - mozversion application_name: Firefox 02:37:24 INFO - mozversion application_remotingname: firefox-dev 02:37:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:37:24 INFO - mozversion application_vendor: Mozilla 02:37:24 INFO - mozversion application_version: 52.0a2 02:37:24 INFO - mozversion platform_buildid: 20170108004005 02:37:24 INFO - mozversion platform_changeset: 4195687bda73679988aaff7b27a8c3b31e38a0fc 02:37:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:37:24 INFO - mozversion platform_version: 52.0a2 02:37:24 INFO - using testdate: 1483871844 02:37:24 INFO - actual date: 1483871844 02:37:24 INFO - starting webserver on 'localhost:49243' 02:37:24 INFO - SUITE-START | Running 2 tests 02:37:24 INFO - TEST-START | dromaeo_css 02:37:24 INFO - operating with platform_type : mac_ 02:37:24 INFO - Initialising browser for dromaeo_css test... 02:37:24 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpbvuImm/profile http://localhost:49243/getInfo.html) 02:37:27 INFO - PROCESS | 1640 | 2017-01-08 02:37:27.472 plugin-container[1643:7955] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 02:37:27 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:37:27 INFO - PROCESS | 1640 | 2017-01-08 02:37:27.473 plugin-container[1643:7955] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x911f, name = 'com.apple.CFPasteboardClient' 02:37:27 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:37:27 INFO - PROCESS | 1640 | 2017-01-08 02:37:27.473 plugin-container[1643:7955] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:37:27 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 02:37:27 INFO - PROCESS | 1640 | colorDepth:24 02:37:27 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 02:37:27 INFO - PROCESS | 1640 | __metrics 02:37:27 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:37:28 INFO - TEST-INFO | 1640: exit 0 02:37:28 INFO - Browser initialized. 02:37:28 INFO - Running cycle 1/1 for dromaeo_css test... 02:37:28 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpbvuImm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:37:31 INFO - PROCESS | 1644 | 2017-01-08 02:37:31.128 plugin-container[1645:8087] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 02:37:31 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:37:31 INFO - PROCESS | 1644 | 2017-01-08 02:37:31.128 plugin-container[1645:8087] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a1b, name = 'com.apple.CFPasteboardClient' 02:37:31 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:37:31 INFO - PROCESS | 1644 | 2017-01-08 02:37:31.129 plugin-container[1645:8087] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:37:40 INFO - PROCESS | 1644 | RSS: Main: 229642240 02:37:40 INFO - PROCESS | 1644 | 02:40:43 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 02:40:45 INFO - PROCESS | 1644 | RSS: Main: 232546304 02:40:45 INFO - PROCESS | 1644 | 02:43:48 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 02:43:48 INFO - PROCESS | 1644 | RSS: Main: 243441664 02:43:48 INFO - PROCESS | 1644 | 02:46:50 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 02:46:51 INFO - PROCESS | 1644 | RSS: Main: 246415360 02:46:51 INFO - PROCESS | 1644 | 02:49:53 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 02:49:53 INFO - PROCESS | 1644 | RSS: Main: 245682176 02:49:53 INFO - PROCESS | 1644 | 02:52:56 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 02:52:57 INFO - PROCESS | 1644 | RSS: Main: 249102336 02:52:57 INFO - PROCESS | 1644 | 02:55:59 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 02:55:59 INFO - PROCESS | 1644 | RSS: Main: 250351616 02:55:59 INFO - PROCESS | 1644 | 02:55:59 INFO - PROCESS | 1644 | __start_tp_report 02:55:59 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 02:55:59 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 02:55:59 INFO - PROCESS | 1644 | |i|pagename|runs| 02:55:59 INFO - PROCESS | 1644 | |0;dojo.html;3003.309238875059;3315.867365305388;3341.649126841683;3354.698077173054;3366.0070279267616;5604.523615492683;5636.4363563643665;5669.404712505189;5678.545716342693;5698.515626171775;5625.38120806711;5654.971725141369;5725.14122881568;5751.913721294173;5767.6827774472495;4772.451168115659;4923.18767403378;5180.1970694542315;5211.244369566411;5228.5555727763185;5243.947560524403;5266.7629956652045;5281.94361127774;5289.656172348804;5296.92054619181;5124.6419698779855;5152.149895267276;5238.711870847102;5247.1866860636665;5289.2859463972445;5179.119549676564;5196.948030519684;5198.6880787152895;5229.267902493654;5230.189320655304;5065.645404821664;5153.304303918998;5161.045206636784;5165.302684137631;5208.088584497697;5630.352509461423;5634.239377684001;5665;5681.261436013292;5693.316801983747;5163.922541161885;5562.221289019541;5940.524758019348;6043.697815109227;6057.636541807506;6213.099100630428;6244.3755624437645;6263.561550691454;6265.59273647216;6276.497880169575;5717.056685646863;5720.48515633595;5721.8569535761935;5724.885502289931;5764.51001664859;6146.754129834757;6152.846178845564;6222.782202622977;6262.561620686508;6268.937310626927;5926.348101708809;5929.229200203946;5946.583739138306;5954.970225148846;6078.149059131725;6178.289496707936;6184.257889053342;6203.286622038433;6207.068939659087;6215.813525594149;5941.55438342126;5948.375420580864;6102.9694851525455;6109.480694141048;6153.661548614779;5618.353889302701;5754.539636829044;6007.699615019232;6015.759369625166;6053.878922421615;4878.390201224847;4885.462599114095;4889.951100489021;4912.066707325596;4937.802487900515;4266.552012038754;4324.070324880166;4361.825526978949;4370.868873933787;4379.430674012358;5572.219889215513;5686.232358631614;5714.828555143353;5742.196092547047;5810.448007439287;5239.423663397023;6218.31598524162;6250.0937364082865;6253.749850005949;6274.592151510137;5577.776888924398;5737.340205876294;5742.253507044057;5761.164631128463;5772.480476757028;5278.340207474066;5299.735013249322;5313.388960269618;5337.893242135136;5396.109641909119;6271.937280627135;6280.717367718555;6309.432151106423;6313.08460273267;6318.020706790455;5695.316562012585;5981.671008094597;6054.757809687563;6121.39200414323;6190.288116866618;6289.276733175743;6292;6303.054541818764;6304.5586808922935;6309.558330916791;6065.059915713072;6077.878442431215;6092.573519853568;6099;6101;5898.764049438146;6021.367756385516;6064.908316503072;6172.166757487861;6197.411245931629;5831.067029275465;5894;5894.174815525916;5903.7048147593305;5926.703664816828;5539.612227144061;5885.146653735099;5912.852178695567;5933.406659334032;5954.910676339771;4800.303955926441;5585.553155747449;5616.185653080199;5777.306723193244;5816.680082595498;5808.912866307093;5886.764529418775;5906.202662640489;5938.61399009063;5958.225430693982;4917;5778;5796.739146738322;5846.970765146147;5886.823395297976 02:55:59 INFO - PROCESS | 1644 | |1;ext.html;467.77079231176725;484.36306257271684;502.7436007636106;505.6637336171446;552.6545908806995;31239.68760312399;40079.998000050015;40225.39661905066;40409;40470.7976460118;41810.37284440736;42169.789151054276;42238;42281;42318.78840605792;9352.111549402796;9521.285903557226;9542.141207291343;9555.665551705692;9574.952125239399;9422.727931729232;9516.143547080761;9526.28552858535;9538;9571.18644915183;9531.571079301413;9574.617015319378;9578.994205608415;9583.472908990003;9596.136347728703;9503.762405939873;9540.761480963032;9556.761080972961;9568.473733944631;9590.80818383632;9274.211692006187;9349.158575728217;9356.391834530734;9359.672411465634;9372.8594071089;29141.708582914325;32853;39110;39308.23112959923;39492.407613885814;2977.836219007975;3095.436804413779;3262.575865137541;3270.3949769292517;3292.5719656444508;29561.40877182466;29684.812607495674;30015.099547013626;30063.098107056823;30311.24221894448;3120.7035331643683;3200.4079245339685;3259.282957749291;3301.4882693182594;3342.6824451677294;468243;487957;495267;496457;497434;43574.56425435768;50556.24165637518;59230.703846480494;59794.40205597975;59850.40149598535;50234;62340.818071367285;63439;63549;63799;16031.839681603267;16052.839471605368;16209.432669856498;16216.513504594881;16277.104759238118;12532.436040378205;12616.432260548298;12627.747445051047;12637.93681031589;12649.620511384674;14777.113373197428;15837.52487425356;15967.441139560293;15996.76004859928;16041.358345666044;6212.347703491158;6337.271213810471;6450.741970321134;6471.611703297723;6474.223093228748;839.4249938791825;970.737900766789;1007.5969612155196;1025.9384436933808;1030.294248439821;25589.3602659935;28568.857155714504;29726.554101688493;30014.039210219697;30123.849380753396;1003.1924300937815;1037.9481025948671;1046.424466543398;1048.192891473561;1056.4189695667353;15181.696366072616;15278.083315000913;15444.92277538605;15580.454684086002;15602;653275;677231;689524;691658;945901;58602.413975859694;59634.70182649059;59851.401485984585;60007.39992600105;60031.39968600258;1334.292824802856;1370.8149399830895;1379.9448022079005;1381.9239941803498;1393.5262010916338;29673.81304747786;30476.84761576178;30570.388592228475;30669.926552570552;31100.68899310978;50272;50369;50533;50632.24051639302;50635.24047139222;588481;591900;591980;592012;594507;25405;25838.224853254433;25863.965441382134;25926.092586759372;25984;44140;44486.55513444953;45223.547764521936;45225.32162017506;45463.31805022994;33914.830425847715;35851;38893.80553097217;39040.41439378468;39275.2144957105;36818.63181368152;37636.811815940746;38373.61626383701;38836.61163388443;39093.80453097716;30135.547966780076;32109.51835722419;37270.69552565647;37921.2415751689;38051.61948380592;30318.54522182125;33642.831785840914;33853.32293354168;34227.65772342344;34449.65550344565;25500.617490737284;25511.6173257405;25532.48935021252;25535.489290214464;25929 02:55:59 INFO - PROCESS | 1644 | |2;jquery.html;370.4258399480804;371.9795411252382;377.51489336202974;378.39079082676903;395.7111308744616;18311.72532412015;19750.209991600335;19972.201111955503;20157.697634535492;20376.796232037676;18001.7299740504;19544.72292481033;19957.60084798303;20025.699614505793;20220.39338819834;4623.352730617717;5769.451902069297;5874.676892770896;5892.057270836667;6007.399260074001;5836.270466191726;5977.790777322794;5980.581359304851;5989.071693887455;5998;5917.319508256541;5926.940730592703;6232.6883655817255;6592.3407659233935;6612.206535215781;6095.2685677718955;6615.37153970372;6627.204735431755;6669.199696036459;6701.966490167542;4937.777799998973;5867.589268751189;5867.794627188028;5876.647401155944;5887.970560147215;13107.606771796765;13211.537596184087;21493.92530373475;24648.260552183463;24793.88427520766;2089.2061016813664;2360.917367892133;2391.892364843586;2400.975990240093;2622.7508386703234;9741.464219467927;10128.898711012944;10305.433201173932;13014.219146851125;13725.176489410567;1802.9008404537744;1812.139233863904;1837.0355563329226;1877.2115711401107;1880.4452686457528;98203.50898245534;114102;118523.4073829634;119261;119666.40166799197;10615.52230149645;11083.891610838851;11499.655010349703;11560.884391156149;11871.406429678482;14614.853851461561;14683.339249733788;14706;14731.410743570352;14732.484363047457;8363.414560980795;8402.705905293407;8536.914630853737;8589.26991205742;8629.568521573896;8398.538080405515;8408.747737567883;8468;8548.572571371407;8602.268807151337;4493.910121797546;4531.365608814768;4549.977250113729;4572.154151481986;4608.28571571407;1887.452638734779;1937.2347922570507;1942.3201879342116;1945.3385848811472;1952.326447375653;823.5758584329076;911.5852287209303;916.6104405627582;941.7975135345933;1008.1670505878504;19979.900100499694;20769;20794.48013799637;20800.687989680166;20855.95720213983;939.5208443693765;956.1824639932896;957.8084383123403;986.5609803637456;989.5794287427815;10549.525271362807;10966.451677416097;11019.944900275448;11021;11035.44822758875;537444;546365;571267;580530;581088;133091;136991;137008;137396.51698617544;143094;794.3049831397527;909.9954500227457;967.2213867836459;971.0871780526423;975.4244995452717;17511.649767004845;18106.81893181104;18472.076396180408;18523.44429667112;18873.71689424632;52808.73595631997;53117.672058198856;53625.731871340395;54262.18606720823;55916.44083559112;74813.62593186999;76176.85734713874;76759;77047.22952770401;77567.61216193883;6150.384961503993;6929.618870962146;7301.488895777245;7330.633468326456;7465.06686664167;7491.550506969599;11944.64166075019;12297;12788.488460461478;12852.164609300366;10226.897731022595;10385.740356491147;10445.11216546602;10563.63027294041;10819.080378167944;10672.57309707634;10807;10812.5134368952;10888.128949683847;10981.890181098086;9141.451512909247;10800.351978880977;11011.669649910515;11269.267497612629;11409.372484513431;7226.385757210696;7333.816654583465;7391.371733402506;7451;7502.3622992046285;9785.07041831025;10796.460176990962;10853.891461085288;11149.944250278697;11163.944180279046 02:55:59 INFO - PROCESS | 1644 | |3;mootools.html;1429.7354989326975;1470.2207829850179;1606.148741167181;1696.3129932377376;1749.6588165307762;7489.662965166567;7771.533707977511;8059.597020148991;8501.829963400736;8516.105808890063;7982.800429989253;8292.253697167254;8295.253427191552;8353.039400468948;8394.874076888851;2313.4332088638266;2347.8239132065164;2350.27141586108;2353.5410594933996;2356.375560476475;2335.311083230451;2365.727941286757;2370.7510711375317;2375.2755409600063;2382.5473160099614;2310.156792770635;2313.803326717231;2342.847714898526;2344.812415006804;2348.260298006131;2299.9310020699404;2303.6659684345677;2324.535092981402;2325.4883925536355;2333.658146565723;2345.8592484450987;2355.4582446037334;2356.964645530318;2361.3388251289493;2363.7399886012527;8239.217274358927;8328.208820162075;8371.95814020932;8390.202930721633;8413.95793021031;4839.44346400165;4913.606911447076;4976.601871850244;5147.4080480744815;5160.0969830279555;3374.6456622054575;3412.129906873756;3527.7883327000463;3554.48459973305;3571.535700358963;1029.3155051890483;1046.858674079005;1073.602766976216;1080.724415274108;1082.897124773153;10782.083522900562;10812.243142979994;10887.673369798918;10891.891081089167;10922.017018468376;4471.8434854779925;4606.309053641913;4731;4739.289106634033;4739.857804265877;8116.107228204893;8197.672093116209;8722.563871806384;9362.485063321583;9367.391119577205;4539.931901021488;5169.844904652867;5171.63798534099;5177.119889618773;5187.818426355135;3834.367329390675;4338.783060846945;4408.0522687622315;4589.931151032737;4693.9765301173275;3748.606396328401;3803.0492376905772;3848.730588858809;3860.96139038606;3880.9805950970062;1255.2468518888777;1266.265565971734;1266.65167079054;1278.4949944771763;1283.5186804948144;758.3743411685382;844.1094645149426;901.9368644194843;903.9186473217311;914.6432891172448;5432.967736130123;5496.395396506381;5509.752061157257;5618.91571626426;5688.459596338341;882.1266945723687;912.2792993535165;918.86676431917;951.8572214167792;986.1814693804125;3717.70258379329;3880.96119038806;3928.705347098979;3929.7445666031617;3945.0926286953586;9717.416954982582;9995.200383969264;10010.949945250228;10030.197584193247;10069.949650251701;9533;10013.198944084601;10014.298999069995;10034.297599167989;10155.949220253999;7629.656665450068;7850.136484986533;7862.095858976291;7897.486663366978;7907.841843163104;5689.943100568941;5723.399043100415;5760.539156867357;5789.964261325856;5914.142449344735;9339.439633622005;9425;9467.573959171716;9486.098820612031;9556.092171243721;9123.863142053007;9384;9457.810843783223;9473.478958657064;9625.855612165684;6623.470122390101;6826.180858297036;6963.338482844122;7076.893846592202;7240.348368646847;7785.610719464117;7890.802729931797;7984.84030319402;7985.001874765654;7994.800129996796;7023.648817559204;7135.286471352823;7172.175199852084;7206.315400036988;7294.270572942663;6965.991782585101;7090.680919358538;7093.964530177316;7181.245969173162;7230.13238411394;6673.366030227121;6698.966505167443;6765.188177418741;6773.153355830521;6789.2871248518195;4437.778111094368;4810.677063807481;4834.009028149291;4885.2427873679635;4948.257761335828;4126.793660317032;4730.810767569396;4750.073735621659;4945.7527123644395;4971.676841005323 02:55:59 INFO - PROCESS | 1644 | |4;prototype.html;288.8931095494667;302.11480362537765;309.7367237847829;313.4342511766261;314.5816064634037;4932.186189278769;5111.744412779365;5190.377154741432;5296.682199068054;5330.520253177213;5032.572231360339;5068.518490743373;5237.135872581029;5276.604254680895;5300.92048619271;3352.971452271377;3429.759916805831;4030.3148464760916;4052.5542190359183;4080.469538959931;3911.9046666933305;3986.561478237406;4095.856645017425;4104.343305071189;4124.216398884218;3463.4458486642143;4026.255142798592;4050.2255954670995;4050.8784736457956;4059.518275829316;4054.8175332110127;4060.8172632231326;4062.9639441942263;4066.0648050948153;4103.85636502721;3577.4454959481322;3589.300086483137;3600.531930848999;3609.5127157833617;3623.1304486923214;4482.439695038121;5043.5965122790085;5164.612654050961;5164.690118592859;5199.48005199477;4027.375756757678;4660;4679.274712419547;4716.981132075474;4765;4471.306947423154;4642.792873852789;5196.740162991835;5631.774729010835;5669.716514174274;3779.5842457329672;3878.864239751595;3905.2384784966944;3919.2161567686576;3919.3141200289833;5246.475352464761;5659.5755318351285;5920.733566989496;5921.970390148022;5956.523478121783;3452.2922800825772;3988.8603898863403;3993.9201215975518;4024.8188831502653;4062.370332598452;3554;3893.649571538575;4009.6792256619983;4024.3158663027357;4091.9181616367505;4112.897177570585;4456.799444024962;4542.728036149883;4592;4600.608948239437;4959.8840260941;5361.731913404392;5714.7428365723645;5744.109663002241;5745.224394706661;1110.8558185069392;1113.4154568851418;1145.4845319606045;1242.6955395928055;1285.5243559882715;776.7475570439517;780.9804754881173;871.324723339417;886.259972922604;909.2634965677822;4621.815127394933;4913.410390753133;4963.950360496421;4975.457608141488;5069.949300506948;4539.800684367014;4923.8276660316715;4927.934454075402;5428.348598168166;5437.4928171329275;4823.855284341475;4930.7288099154175;5082.034413461431;5193.740312984336;5318.330352057145;2585.03061351993;2921.956170657442;2945.381469891304;2948.82307061577;2950.4099180164053;5152.793888244503;5369.758360873692;5394.460553944653;5803.330467984774;5877.941220587825;5624.128260119688;5639.3232812062815;5663.546916246691;5663.971680141655;5715.056880660512;5051.236828562744;5092.668976516515;5155.845324640267;5749.3963133871175;5828.475437210672;5354.3842458117815;5384.488473595002;5526.171074338881;5812.18629391877;5902.822915312548;5198.298229738937;5353;5394.703291319015;5835.591508594358;5839.0949402842625;5628.887422251614;5809.779946211343;6091.451769340781;6094.786682466092;6133.539984501091;3453.4819777033645;3471.3057388522902;3871.509468854455;3874.5396673112214;3879.941800873046;5365.014944470571;5792.160136780228;5806.883862322815;5824.9708751457665;5860.824175274749;5235.408472805985;5283.411128547061;5346.144616861439;5482.5613950884635;5557.527610153021;5578.541304391141;5624.803131890364;5666.886662266649;5668.744906479301;5675.290588676416;4385.868423947287;4545.704529205591;4758.64310176745;5256.868578285574;5552.52803511706;4947.957006440941;5055.87360315995;5683.272029425982;5731.283589551306;5778;4709.929351059805;5072.909324495251;5081.92377114351;5547.920903898375;5550.222968784293 02:55:59 INFO - PROCESS | 1644 | |5;yui.html;614.6250787019918;721.3003386714885;730.2186660273507;776.1539921485579;776.3323541754094;13776.311184440776;14433.350499227534;14544.709105817878;14554.927225363872;14564.27178641069;12094;12234.694132646688;12318.445669944851;14656.267186640678;14667.973241873073;817.166490180016;853.9615717292721;874.8337815814975;876.3865294293988;878.7232021913109;861.6940985949991;867.3234876796109;868.5440143924427;869.2307308032405;870.6778491957953;892.5225004622529;894.516960841145;912.2838571721201;913.7350168451142;915.716128000322;870.2211520689011;889.1775108025083;908.52756566585;909.5315912305168;915.9542022898862;558.511302610216;614.8954677704801;671.6238906212536;674.197704731368;675.8377989282537;11054.668359949215;11924.46339914706;13214.273214973173;13571.185728856202;13576.321183940763;13412.46350145993;13537.59387218385;13619.455221791117;13639.454421823115;13764.380602872894;10977.341359518454;11087.390193539351;11100.333979961228;11156.832647510293;11174.161937854693;3509.7192224621967;3584.3823341156326;3617.602063772983;3708.462272970431;3737.9626203737885;78169.82745258769;80679.19320806835;80848.59575702142;80972.59513702393;81317.59341203315;10210.183185345155;10452.895471045193;10550.366977981346;10575.576976920991;10643.042126208678;20033.59932801336;20071.16657363849;20283.087261073288;20302;20753.377398678065;7650;7696.884546731803;7732.342750866127;7742.883856742041;7856.292933635886;4231.217224813419;4250.511191212988;4271.765052922119;4289.185054839633;4327.37253098299;3165.3352795913115;3387.983060084684;3459.8097104659487;3477.6000759912936;3810.8666196682984;595.0598055072975;612.2285919741158;655.0665301944709;661.0249881424955;692.4322055914103;612.6201754911983;621.4531212533033;641.3746597067915;659.6536818170499;700.4501466348994;8203.30271926881;9055.818883622422;9164.450132992042;9247.861282080774;9350.438973661601;525.8606469285643;579.5161040531119;606.1271768653213;639.9840003999844;657.4707360574688;3589.9102522437147;4467.195904737114;4467.865964021084;4482.529334419904;4535.979404633997;78123.60938195273;78266.6086669563;80349;91887;93071;59996.100058499156;61366.69316653388;61903;68857;88331;57485;58158.12762808561;61180.47048823726;63502.36497635057;78143;9209.125133112344;9280.767980800263;9600.615975360908;9634.084761947604;9660.32377733552;41998.95002624836;52459.73770131125;52509.474905250456;52836.73581632067;54934;66737;68399.65800170967;70000.29999699938;71570;73349.6332518334;12193.390330483262;12297.077719170918;12310.81533777012;12398.380080995734;12553.874461255271;13614;13695;14102.506412275521;14151.575452736435;14228.928855355656;12554;12975.286359250333;13222;13314.600561982768;13322.33388330599;12808.423620936894;12887.987522610181;12979.740405191655;13200.867991319963;13227.007974402151;12539.87460125387;12836.16564923277;12868.806967895676;13082.345882706017;13187.142835715647;10628.309159904582;10750.139988801033;10951.671449856518;11111.498865817613;11497.137714671266;9447.724557184967;10755.085817705583;11106.777864442503;11211.215214934877;11244.26912250701 02:55:59 INFO - PROCESS | 1644 | __end_tp_report 02:55:59 INFO - PROCESS | 1644 | __start_cc_report 02:55:59 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,370 02:55:59 INFO - PROCESS | 1644 | __end_cc_report 02:55:59 INFO - PROCESS | 1644 | __startTimestamp1483872959837__endTimestamp 02:55:59 INFO - PROCESS | 1644 | 02:55:59 INFO - PROCESS | 1644 | ------- Summary: start ------- 02:55:59 INFO - PROCESS | 1644 | Number of tests: 6 02:55:59 INFO - PROCESS | 1644 | 02:55:59 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5632.14 Median:5764.51 stddev:626.47 (10.9%) stddev-sans-first:593.48 02:55:59 INFO - PROCESS | 1644 | Values: 3003.3 3315.9 3341.6 3354.7 3366.0 5604.5 5636.4 5669.4 5678.5 5698.5 5625.4 5655.0 5725.1 5751.9 5767.7 4772.5 4923.2 5180.2 5211.2 5228.6 5243.9 5266.8 5281.9 5289.7 5296.9 5124.6 5152.1 5238.7 5247.2 5289.3 5179.1 5196.9 5198.7 5229.3 5230.2 5065.6 5153.3 5161.0 5165.3 5208.1 5630.4 5634.2 5665.0 5681.3 5693.3 5163.9 5562.2 5940.5 6043.7 6057.6 6213.1 6244.4 6263.6 6265.6 6276.5 5717.1 5720.5 5721.9 5724.9 5764.5 6146.8 6152.8 6222.8 6262.6 6268.9 5926.3 5929.2 5946.6 5955.0 6078.1 6178.3 6184.3 6203.3 6207.1 6215.8 5941.6 5948.4 6103.0 6109.5 6153.7 5618.4 5754.5 6007.7 6015.8 6053.9 4878.4 4885.5 4890.0 4912.1 4937.8 4266.6 4324.1 4361.8 4370.9 4379.4 5572.2 5686.2 5714.8 5742.2 5810.4 5239.4 6218.3 6250.1 6253.7 6274.6 5577.8 5737.3 5742.3 5761.2 5772.5 5278.3 5299.7 5313.4 5337.9 5396.1 6271.9 6280.7 6309.4 6313.1 6318.0 5695.3 5981.7 6054.8 6121.4 6190.3 6289.3 6292.0 6303.1 6304.6 6309.6 6065.1 6077.9 6092.6 6099.0 6101.0 5898.8 6021.4 6064.9 6172.2 6197.4 5831.1 5894.0 5894.2 5903.7 5926.7 5539.6 5885.1 5912.9 5933.4 5954.9 4800.3 5585.6 5616.2 5777.3 5816.7 5808.9 5886.8 5906.2 5938.6 5958.2 4917.0 5778.0 5796.7 5847.0 5886.8 02:55:59 INFO - PROCESS | 1644 | 02:55:59 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:72443.72 Median:39192.61 stddev:165234.77 (421.6%) stddev-sans-first:165609.93 02:55:59 INFO - PROCESS | 1644 | Values: 467.8 484.4 502.7 505.7 552.7 31239.7 40080.0 40225.4 40409.0 40470.8 41810.4 42169.8 42238.0 42281.0 42318.8 9352.1 9521.3 9542.1 9555.7 9575.0 9422.7 9516.1 9526.3 9538.0 9571.2 9531.6 9574.6 9579.0 9583.5 9596.1 9503.8 9540.8 9556.8 9568.5 9590.8 9274.2 9349.2 9356.4 9359.7 9372.9 29141.7 32853.0 39110.0 39308.2 39492.4 2977.8 3095.4 3262.6 3270.4 3292.6 29561.4 29684.8 30015.1 30063.1 30311.2 3120.7 3200.4 3259.3 3301.5 3342.7 468243.0 487957.0 495267.0 496457.0 497434.0 43574.6 50556.2 59230.7 59794.4 59850.4 50234.0 62340.8 63439.0 63549.0 63799.0 16031.8 16052.8 16209.4 16216.5 16277.1 12532.4 12616.4 12627.7 12637.9 12649.6 14777.1 15837.5 15967.4 15996.8 16041.4 6212.3 6337.3 6450.7 6471.6 6474.2 839.4 970.7 1007.6 1025.9 1030.3 25589.4 28568.9 29726.6 30014.0 30123.8 1003.2 1037.9 1046.4 1048.2 1056.4 15181.7 15278.1 15444.9 15580.5 15602.0 653275.0 677231.0 689524.0 691658.0 945901.0 58602.4 59634.7 59851.4 60007.4 60031.4 1334.3 1370.8 1379.9 1381.9 1393.5 29673.8 30476.8 30570.4 30669.9 31100.7 50272.0 50369.0 50533.0 50632.2 50635.2 588481.0 591900.0 591980.0 592012.0 594507.0 25405.0 25838.2 25864.0 25926.1 25984.0 44140.0 44486.6 45223.5 45225.3 45463.3 33914.8 35851.0 38893.8 39040.4 39275.2 36818.6 37636.8 38373.6 38836.6 39093.8 30135.5 32109.5 37270.7 37921.2 38051.6 30318.5 33642.8 33853.3 34227.7 34449.7 25500.6 25511.6 25532.5 25535.5 25929.0 02:55:59 INFO - PROCESS | 1644 | 02:55:59 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:33912.22 Median:1496.59 stddev:94633.16 (6323.3%) stddev-sans-first:94865.12 02:55:59 INFO - PROCESS | 1644 | Values: 370.4 372.0 377.5 378.4 395.7 18311.7 19750.2 19972.2 20157.7 20376.8 18001.7 19544.7 19957.6 20025.7 20220.4 4623.4 5769.5 5874.7 5892.1 6007.4 5836.3 5977.8 5980.6 5989.1 5998.0 5917.3 5926.9 6232.7 6592.3 6612.2 6095.3 6615.4 6627.2 6669.2 6702.0 4937.8 5867.6 5867.8 5876.6 5888.0 13107.6 13211.5 21493.9 24648.3 24793.9 2089.2 2360.9 2391.9 2401.0 2622.8 9741.5 10128.9 10305.4 13014.2 13725.2 1802.9 1812.1 1837.0 1877.2 1880.4 98203.5 114102.0 118523.4 119261.0 119666.4 10615.5 11083.9 11499.7 11560.9 11871.4 14614.9 14683.3 14706.0 14731.4 14732.5 8363.4 8402.7 8536.9 8589.3 8629.6 8398.5 8408.7 8468.0 8548.6 8602.3 4493.9 4531.4 4550.0 4572.2 4608.3 1887.5 1937.2 1942.3 1945.3 1952.3 823.6 911.6 916.6 941.8 1008.2 19979.9 20769.0 20794.5 20800.7 20856.0 939.5 956.2 957.8 986.6 989.6 10549.5 10966.5 11019.9 11021.0 11035.4 537444.0 546365.0 571267.0 580530.0 581088.0 133091.0 136991.0 137008.0 137396.5 143094.0 794.3 910.0 967.2 971.1 975.4 17511.6 18106.8 18472.1 18523.4 18873.7 52808.7 53117.7 53625.7 54262.2 55916.4 74813.6 76176.9 76759.0 77047.2 77567.6 6150.4 6929.6 7301.5 7330.6 7465.1 7491.6 11944.6 12297.0 12788.5 12852.2 10226.9 10385.7 10445.1 10563.6 10819.1 10672.6 10807.0 10812.5 10888.1 10981.9 9141.5 10800.4 11011.7 11269.3 11409.4 7226.4 7333.8 7391.4 7451.0 7502.4 9785.1 10796.5 10853.9 11149.9 11163.9 02:55:59 INFO - PROCESS | 1644 | 02:55:59 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5425.82 Median:5062.00 stddev:2950.53 (58.3%) stddev-sans-first:2943.53 02:55:59 INFO - PROCESS | 1644 | Values: 1429.7 1470.2 1606.1 1696.3 1749.7 7489.7 7771.5 8059.6 8501.8 8516.1 7982.8 8292.3 8295.3 8353.0 8394.9 2313.4 2347.8 2350.3 2353.5 2356.4 2335.3 2365.7 2370.8 2375.3 2382.5 2310.2 2313.8 2342.8 2344.8 2348.3 2299.9 2303.7 2324.5 2325.5 2333.7 2345.9 2355.5 2357.0 2361.3 2363.7 8239.2 8328.2 8372.0 8390.2 8414.0 4839.4 4913.6 4976.6 5147.4 5160.1 3374.6 3412.1 3527.8 3554.5 3571.5 1029.3 1046.9 1073.6 1080.7 1082.9 10782.1 10812.2 10887.7 10891.9 10922.0 4471.8 4606.3 4731.0 4739.3 4739.9 8116.1 8197.7 8722.6 9362.5 9367.4 4539.9 5169.8 5171.6 5177.1 5187.8 3834.4 4338.8 4408.1 4589.9 4694.0 3748.6 3803.0 3848.7 3861.0 3881.0 1255.2 1266.3 1266.7 1278.5 1283.5 758.4 844.1 901.9 903.9 914.6 5433.0 5496.4 5509.8 5618.9 5688.5 882.1 912.3 918.9 951.9 986.2 3717.7 3881.0 3928.7 3929.7 3945.1 9717.4 9995.2 10010.9 10030.2 10069.9 9533.0 10013.2 10014.3 10034.3 10155.9 7629.7 7850.1 7862.1 7897.5 7907.8 5689.9 5723.4 5760.5 5790.0 5914.1 9339.4 9425.0 9467.6 9486.1 9556.1 9123.9 9384.0 9457.8 9473.5 9625.9 6623.5 6826.2 6963.3 7076.9 7240.3 7785.6 7890.8 7984.8 7985.0 7994.8 7023.6 7135.3 7172.2 7206.3 7294.3 6966.0 7090.7 7094.0 7181.2 7230.1 6673.4 6699.0 6765.2 6773.2 6789.3 4437.8 4810.7 4834.0 4885.2 4948.3 4126.8 4730.8 4750.1 4945.8 4971.7 02:55:59 INFO - PROCESS | 1644 | 02:55:59 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4493.16 Median:5047.42 stddev:1376.88 (27.3%) stddev-sans-first:1344.09 02:55:59 INFO - PROCESS | 1644 | Values: 288.9 302.1 309.7 313.4 314.6 4932.2 5111.7 5190.4 5296.7 5330.5 5032.6 5068.5 5237.1 5276.6 5300.9 3353.0 3429.8 4030.3 4052.6 4080.5 3911.9 3986.6 4095.9 4104.3 4124.2 3463.4 4026.3 4050.2 4050.9 4059.5 4054.8 4060.8 4063.0 4066.1 4103.9 3577.4 3589.3 3600.5 3609.5 3623.1 4482.4 5043.6 5164.6 5164.7 5199.5 4027.4 4660.0 4679.3 4717.0 4765.0 4471.3 4642.8 5196.7 5631.8 5669.7 3779.6 3878.9 3905.2 3919.2 3919.3 5246.5 5659.6 5920.7 5922.0 5956.5 3452.3 3988.9 3993.9 4024.8 4062.4 3554.0 3893.6 4009.7 4024.3 4091.9 4112.9 4456.8 4542.7 4592.0 4600.6 4959.9 5361.7 5714.7 5744.1 5745.2 1110.9 1113.4 1145.5 1242.7 1285.5 776.7 781.0 871.3 886.3 909.3 4621.8 4913.4 4964.0 4975.5 5069.9 4539.8 4923.8 4927.9 5428.3 5437.5 4823.9 4930.7 5082.0 5193.7 5318.3 2585.0 2922.0 2945.4 2948.8 2950.4 5152.8 5369.8 5394.5 5803.3 5877.9 5624.1 5639.3 5663.5 5664.0 5715.1 5051.2 5092.7 5155.8 5749.4 5828.5 5354.4 5384.5 5526.2 5812.2 5902.8 5198.3 5353.0 5394.7 5835.6 5839.1 5628.9 5809.8 6091.5 6094.8 6133.5 3453.5 3471.3 3871.5 3874.5 3879.9 5365.0 5792.2 5806.9 5825.0 5860.8 5235.4 5283.4 5346.1 5482.6 5557.5 5578.5 5624.8 5666.9 5668.7 5675.3 4385.9 4545.7 4758.6 5256.9 5552.5 4948.0 5055.9 5683.3 5731.3 5778.0 4709.9 5072.9 5081.9 5547.9 5550.2 02:55:59 INFO - PROCESS | 1644 | 02:55:59 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:18025.46 Median:26492.80 stddev:24262.87 (91.6%) stddev-sans-first:24295.71 02:55:59 INFO - PROCESS | 1644 | Values: 614.6 721.3 730.2 776.2 776.3 13776.3 14433.4 14544.7 14554.9 14564.3 12094.0 12234.7 12318.4 14656.3 14668.0 817.2 854.0 874.8 876.4 878.7 861.7 867.3 868.5 869.2 870.7 892.5 894.5 912.3 913.7 915.7 870.2 889.2 908.5 909.5 916.0 558.5 614.9 671.6 674.2 675.8 11054.7 11924.5 13214.3 13571.2 13576.3 13412.5 13537.6 13619.5 13639.5 13764.4 10977.3 11087.4 11100.3 11156.8 11174.2 3509.7 3584.4 3617.6 3708.5 3738.0 78169.8 80679.2 80848.6 80972.6 81317.6 10210.2 10452.9 10550.4 10575.6 10643.0 20033.6 20071.2 20283.1 20302.0 20753.4 7650.0 7696.9 7732.3 7742.9 7856.3 4231.2 4250.5 4271.8 4289.2 4327.4 3165.3 3388.0 3459.8 3477.6 3810.9 595.1 612.2 655.1 661.0 692.4 612.6 621.5 641.4 659.7 700.5 8203.3 9055.8 9164.5 9247.9 9350.4 525.9 579.5 606.1 640.0 657.5 3589.9 4467.2 4467.9 4482.5 4536.0 78123.6 78266.6 80349.0 91887.0 93071.0 59996.1 61366.7 61903.0 68857.0 88331.0 57485.0 58158.1 61180.5 63502.4 78143.0 9209.1 9280.8 9600.6 9634.1 9660.3 41999.0 52459.7 52509.5 52836.7 54934.0 66737.0 68399.7 70000.3 71570.0 73349.6 12193.4 12297.1 12310.8 12398.4 12553.9 13614.0 13695.0 14102.5 14151.6 14228.9 12554.0 12975.3 13222.0 13314.6 13322.3 12808.4 12888.0 12979.7 13200.9 13227.0 12539.9 12836.2 12868.8 13082.3 13187.1 10628.3 10750.1 10951.7 11111.5 11497.1 9447.7 10755.1 11106.8 11211.2 11244.3 02:55:59 INFO - PROCESS | 1644 | -------- Summary: end -------- 02:55:59 INFO - PROCESS | 1644 | 02:56:00 INFO - TEST-INFO | 1644: exit 0 02:56:00 INFO - TEST-OK | dromaeo_css | took 1116449ms 02:56:00 INFO - TEST-START | kraken 02:56:00 INFO - operating with platform_type : mac_ 02:56:00 INFO - Initialising browser for kraken test... 02:56:00 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpfbFexf/profile http://localhost:49243/getInfo.html) 02:56:03 INFO - PROCESS | 1653 | 2017-01-08 02:56:03.492 plugin-container[1655:10396] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 02:56:03 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:56:03 INFO - PROCESS | 1653 | 2017-01-08 02:56:03.492 plugin-container[1655:10396] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 02:56:03 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:56:03 INFO - PROCESS | 1653 | 2017-01-08 02:56:03.493 plugin-container[1655:10396] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:56:03 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 02:56:03 INFO - PROCESS | 1653 | colorDepth:24 02:56:03 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 02:56:03 INFO - PROCESS | 1653 | __metrics 02:56:03 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:56:04 INFO - TEST-INFO | 1653: exit 0 02:56:04 INFO - Browser initialized. 02:56:04 INFO - Running cycle 1/1 for kraken test... 02:56:04 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpfbFexf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:56:07 INFO - PROCESS | 1656 | 2017-01-08 02:56:07.119 plugin-container[1657:10520] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 02:56:07 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:56:07 INFO - PROCESS | 1656 | 2017-01-08 02:56:07.119 plugin-container[1657:10520] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x842f, name = 'com.apple.CFPasteboardClient' 02:56:07 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:56:07 INFO - PROCESS | 1656 | 2017-01-08 02:56:07.119 plugin-container[1657:10520] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:56:16 INFO - PROCESS | 1656 | RSS: Main: 229806080 02:56:16 INFO - PROCESS | 1656 | 02:56:19 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 02:56:20 INFO - PROCESS | 1656 | RSS: Main: 241991680 02:56:20 INFO - PROCESS | 1656 | 02:56:23 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 02:56:23 INFO - PROCESS | 1656 | RSS: Main: 249602048 02:56:23 INFO - PROCESS | 1656 | 02:56:27 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 02:56:27 INFO - PROCESS | 1656 | RSS: Main: 253435904 02:56:27 INFO - PROCESS | 1656 | 02:56:29 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 02:56:30 INFO - PROCESS | 1656 | RSS: Main: 254980096 02:56:30 INFO - PROCESS | 1656 | 02:56:32 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:56:33 INFO - PROCESS | 1656 | RSS: Main: 254812160 02:56:33 INFO - PROCESS | 1656 | 02:56:35 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 02:56:36 INFO - PROCESS | 1656 | RSS: Main: 259682304 02:56:36 INFO - PROCESS | 1656 | 02:56:39 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 02:56:40 INFO - PROCESS | 1656 | RSS: Main: 258789376 02:56:40 INFO - PROCESS | 1656 | 02:56:43 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 02:56:43 INFO - PROCESS | 1656 | RSS: Main: 258936832 02:56:43 INFO - PROCESS | 1656 | 02:56:45 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:56:46 INFO - PROCESS | 1656 | RSS: Main: 257953792 02:56:46 INFO - PROCESS | 1656 | 02:56:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:56:48 INFO - PROCESS | 1656 | RSS: Main: 258277376 02:56:48 INFO - PROCESS | 1656 | 02:56:50 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:56:51 INFO - PROCESS | 1656 | RSS: Main: 258084864 02:56:51 INFO - PROCESS | 1656 | 02:56:54 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:56:55 INFO - PROCESS | 1656 | RSS: Main: 258240512 02:56:55 INFO - PROCESS | 1656 | 02:56:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:56:58 INFO - PROCESS | 1656 | RSS: Main: 258314240 02:56:58 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 02:57:00 INFO - PROCESS | 1656 | RSS: Main: 259997696 02:57:00 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | __start_tp_report 02:57:00 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 02:57:00 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 02:57:00 INFO - PROCESS | 1656 | |i|pagename|runs| 02:57:00 INFO - PROCESS | 1656 | |0;ai-astar;106;119;120;120;118;121;120;120;119;119 02:57:00 INFO - PROCESS | 1656 | |1;audio-beat-detection;140;112;108;143;109;132;140;110;136;110 02:57:00 INFO - PROCESS | 1656 | |2;audio-dft;195;202;196;200;199;198;198;201;239;270 02:57:00 INFO - PROCESS | 1656 | |3;audio-fft;81;58;59;78;63;76;78;79;70;75 02:57:00 INFO - PROCESS | 1656 | |4;audio-oscillator;86;71;117;95;86;58;117;99;99;116 02:57:00 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;90;88;90;87;89;87;96;99;127;86 02:57:00 INFO - PROCESS | 1656 | |6;imaging-darkroom;147;146;97;98;96;97;108;144;147;104 02:57:00 INFO - PROCESS | 1656 | |7;imaging-desaturate;88;92;92;86;87;89;91;92;129;134 02:57:00 INFO - PROCESS | 1656 | |8;json-parse-financial;54;54;56;61;57;60;59;61;60;60 02:57:00 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;67;74;70;84;81;99;98;101;101;62 02:57:00 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;105;100;101;86;85;84;84;83;86;85 02:57:00 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;172;184;172;166;152;161;156;146;192;141 02:57:00 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;164;167;165;165;166;165;164;168;166;164 02:57:00 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;70;68;66;70;68;69;71;86;89;73 02:57:00 INFO - PROCESS | 1656 | __end_tp_report 02:57:00 INFO - PROCESS | 1656 | __start_cc_report 02:57:00 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,970 02:57:00 INFO - PROCESS | 1656 | __end_cc_report 02:57:00 INFO - PROCESS | 1656 | __startTimestamp1483873020836__endTimestamp 02:57:00 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | ------- Summary: start ------- 02:57:00 INFO - PROCESS | 1656 | Number of tests: 14 02:57:00 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:118.20 Median:120.00 stddev:4.37 (3.6%) stddev-sans-first:0.88 02:57:00 INFO - PROCESS | 1656 | Values: 106.0 119.0 120.0 120.0 118.0 121.0 120.0 120.0 119.0 119.0 02:57:00 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:124.00 Median:134.00 stddev:15.27 (11.4%) stddev-sans-first:15.06 02:57:00 INFO - PROCESS | 1656 | Values: 140.0 112.0 108.0 143.0 109.0 132.0 140.0 110.0 136.0 110.0 02:57:00 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:209.80 Median:200.50 stddev:24.76 (12.3%) stddev-sans-first:25.67 02:57:00 INFO - PROCESS | 1656 | Values: 195.0 202.0 196.0 200.0 199.0 198.0 198.0 201.0 239.0 270.0 02:57:00 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:71.70 Median:77.00 stddev:8.67 (11.3%) stddev-sans-first:8.51 02:57:00 INFO - PROCESS | 1656 | Values: 81.0 58.0 59.0 78.0 63.0 76.0 78.0 79.0 70.0 75.0 02:57:00 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:86.00 stddev:19.84 (23.1%) stddev-sans-first:20.81 02:57:00 INFO - PROCESS | 1656 | Values: 86.0 71.0 117.0 95.0 86.0 58.0 117.0 99.0 99.0 116.0 02:57:00 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:93.90 Median:89.50 stddev:12.35 (13.8%) stddev-sans-first:13.02 02:57:00 INFO - PROCESS | 1656 | Values: 90.0 88.0 90.0 87.0 89.0 87.0 96.0 99.0 127.0 86.0 02:57:00 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:118.40 Median:121.50 stddev:24.04 (19.8%) stddev-sans-first:23.17 02:57:00 INFO - PROCESS | 1656 | Values: 147.0 146.0 97.0 98.0 96.0 97.0 108.0 144.0 147.0 104.0 02:57:00 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:98.00 Median:90.00 stddev:17.83 (19.8%) stddev-sans-first:18.54 02:57:00 INFO - PROCESS | 1656 | Values: 88.0 92.0 92.0 86.0 87.0 89.0 91.0 92.0 129.0 134.0 02:57:00 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:58.20 Median:60.00 stddev:2.74 (4.6%) stddev-sans-first:2.45 02:57:00 INFO - PROCESS | 1656 | Values: 54.0 54.0 56.0 61.0 57.0 60.0 59.0 61.0 60.0 60.0 02:57:00 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:83.70 Median:77.50 stddev:15.19 (19.6%) stddev-sans-first:14.86 02:57:00 INFO - PROCESS | 1656 | Values: 67.0 74.0 70.0 84.0 81.0 99.0 98.0 101.0 101.0 62.0 02:57:00 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:84.50 stddev:8.49 (10.0%) stddev-sans-first:7.03 02:57:00 INFO - PROCESS | 1656 | Values: 105.0 100.0 101.0 86.0 85.0 84.0 84.0 83.0 86.0 85.0 02:57:00 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:164.20 Median:169.00 stddev:16.28 (9.6%) stddev-sans-first:17.02 02:57:00 INFO - PROCESS | 1656 | Values: 172.0 184.0 172.0 166.0 152.0 161.0 156.0 146.0 192.0 141.0 02:57:00 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:165.50 stddev:1.35 (0.8%) stddev-sans-first:1.33 02:57:00 INFO - PROCESS | 1656 | Values: 164.0 167.0 165.0 165.0 166.0 165.0 164.0 168.0 166.0 164.0 02:57:00 INFO - PROCESS | 1656 | 02:57:00 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.00 Median:70.50 stddev:7.90 (11.2%) stddev-sans-first:8.31 02:57:00 INFO - PROCESS | 1656 | Values: 70.0 68.0 66.0 70.0 68.0 69.0 71.0 86.0 89.0 73.0 02:57:00 INFO - PROCESS | 1656 | -------- Summary: end -------- 02:57:00 INFO - PROCESS | 1656 | 02:57:01 INFO - TEST-INFO | 1656: exit 0 02:57:01 INFO - TEST-OK | kraken | took 61368ms 02:57:01 INFO - SUITE-END | took 1177s 02:57:01 INFO - Completed test suite (00:19:37) 02:57:01 INFO - javascript benchmark 02:57:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7115.521261925591, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3003.309238875059, 3315.867365305388, 3341.649126841683, 3354.698077173054, 3366.0070279267616, 5604.523615492683, 5636.4363563643665, 5669.404712505189, 5678.545716342693, 5698.515626171775, 5625.38120806711, 5654.971725141369, 5725.14122881568, 5751.913721294173, 5767.6827774472495, 4772.451168115659, 4923.18767403378, 5180.1970694542315, 5211.244369566411, 5228.5555727763185, 5243.947560524403, 5266.7629956652045, 5281.94361127774, 5289.656172348804, 5296.92054619181, 5124.6419698779855, 5152.149895267276, 5238.711870847102, 5247.1866860636665, 5289.2859463972445, 5179.119549676564, 5196.948030519684, 5198.6880787152895, 5229.267902493654, 5230.189320655304, 5065.645404821664, 5153.304303918998, 5161.045206636784, 5165.302684137631, 5208.088584497697, 5630.352509461423, 5634.239377684001, 5665.0, 5681.261436013292, 5693.316801983747, 5163.922541161885, 5562.221289019541, 5940.524758019348, 6043.697815109227, 6057.636541807506, 6213.099100630428, 6244.3755624437645, 6263.561550691454, 6265.59273647216, 6276.497880169575, 5717.056685646863, 5720.48515633595, 5721.8569535761935, 5724.885502289931, 5764.51001664859, 6146.754129834757, 6152.846178845564, 6222.782202622977, 6262.561620686508, 6268.937310626927, 5926.348101708809, 5929.229200203946, 5946.583739138306, 5954.970225148846, 6078.149059131725, 6178.289496707936, 6184.257889053342, 6203.286622038433, 6207.068939659087, 6215.813525594149, 5941.55438342126, 5948.375420580864, 6102.9694851525455, 6109.480694141048, 6153.661548614779, 5618.353889302701, 5754.539636829044, 6007.699615019232, 6015.759369625166, 6053.878922421615, 4878.390201224847, 4885.462599114095, 4889.951100489021, 4912.066707325596, 4937.802487900515, 4266.552012038754, 4324.070324880166, 4361.825526978949, 4370.868873933787, 4379.430674012358, 5572.219889215513, 5686.232358631614, 5714.828555143353, 5742.196092547047, 5810.448007439287, 5239.423663397023, 6218.31598524162, 6250.0937364082865, 6253.749850005949, 6274.592151510137, 5577.776888924398, 5737.340205876294, 5742.253507044057, 5761.164631128463, 5772.480476757028, 5278.340207474066, 5299.735013249322, 5313.388960269618, 5337.893242135136, 5396.109641909119, 6271.937280627135, 6280.717367718555, 6309.432151106423, 6313.08460273267, 6318.020706790455, 5695.316562012585, 5981.671008094597, 6054.757809687563, 6121.39200414323, 6190.288116866618, 6289.276733175743, 6292.0, 6303.054541818764, 6304.5586808922935, 6309.558330916791, 6065.059915713072, 6077.878442431215, 6092.573519853568, 6099.0, 6101.0, 5898.764049438146, 6021.367756385516, 6064.908316503072, 6172.166757487861, 6197.411245931629, 5831.067029275465, 5894.0, 5894.174815525916, 5903.7048147593305, 5926.703664816828, 5539.612227144061, 5885.146653735099, 5912.852178695567, 5933.406659334032, 5954.910676339771, 4800.303955926441, 5585.553155747449, 5616.185653080199, 5777.306723193244, 5816.680082595498, 5808.912866307093, 5886.764529418775, 5906.202662640489, 5938.61399009063, 5958.225430693982, 4917.0, 5778.0, 5796.739146738322, 5846.970765146147, 5886.823395297976], "value": 5592.870510080644, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.77079231176725, 484.36306257271684, 502.7436007636106, 505.6637336171446, 552.6545908806995, 31239.68760312399, 40079.998000050015, 40225.39661905066, 40409.0, 40470.7976460118, 41810.37284440736, 42169.789151054276, 42238.0, 42281.0, 42318.78840605792, 9352.111549402796, 9521.285903557226, 9542.141207291343, 9555.665551705692, 9574.952125239399, 9422.727931729232, 9516.143547080761, 9526.28552858535, 9538.0, 9571.18644915183, 9531.571079301413, 9574.617015319378, 9578.994205608415, 9583.472908990003, 9596.136347728703, 9503.762405939873, 9540.761480963032, 9556.761080972961, 9568.473733944631, 9590.80818383632, 9274.211692006187, 9349.158575728217, 9356.391834530734, 9359.672411465634, 9372.8594071089, 29141.708582914325, 32853.0, 39110.0, 39308.23112959923, 39492.407613885814, 2977.836219007975, 3095.436804413779, 3262.575865137541, 3270.3949769292517, 3292.5719656444508, 29561.40877182466, 29684.812607495674, 30015.099547013626, 30063.098107056823, 30311.24221894448, 3120.7035331643683, 3200.4079245339685, 3259.282957749291, 3301.4882693182594, 3342.6824451677294, 468243.0, 487957.0, 495267.0, 496457.0, 497434.0, 43574.56425435768, 50556.24165637518, 59230.703846480494, 59794.40205597975, 59850.40149598535, 50234.0, 62340.818071367285, 63439.0, 63549.0, 63799.0, 16031.839681603267, 16052.839471605368, 16209.432669856498, 16216.513504594881, 16277.104759238118, 12532.436040378205, 12616.432260548298, 12627.747445051047, 12637.93681031589, 12649.620511384674, 14777.113373197428, 15837.52487425356, 15967.441139560293, 15996.76004859928, 16041.358345666044, 6212.347703491158, 6337.271213810471, 6450.741970321134, 6471.611703297723, 6474.223093228748, 839.4249938791825, 970.737900766789, 1007.5969612155196, 1025.9384436933808, 1030.294248439821, 25589.3602659935, 28568.857155714504, 29726.554101688493, 30014.039210219697, 30123.849380753396, 1003.1924300937815, 1037.9481025948671, 1046.424466543398, 1048.192891473561, 1056.4189695667353, 15181.696366072616, 15278.083315000913, 15444.92277538605, 15580.454684086002, 15602.0, 653275.0, 677231.0, 689524.0, 691658.0, 945901.0, 58602.413975859694, 59634.70182649059, 59851.401485984585, 60007.39992600105, 60031.39968600258, 1334.292824802856, 1370.8149399830895, 1379.9448022079005, 1381.9239941803498, 1393.5262010916338, 29673.81304747786, 30476.84761576178, 30570.388592228475, 30669.926552570552, 31100.68899310978, 50272.0, 50369.0, 50533.0, 50632.24051639302, 50635.24047139222, 588481.0, 591900.0, 591980.0, 592012.0, 594507.0, 25405.0, 25838.224853254433, 25863.965441382134, 25926.092586759372, 25984.0, 44140.0, 44486.55513444953, 45223.547764521936, 45225.32162017506, 45463.31805022994, 33914.830425847715, 35851.0, 38893.80553097217, 39040.41439378468, 39275.2144957105, 36818.63181368152, 37636.811815940746, 38373.61626383701, 38836.61163388443, 39093.80453097716, 30135.547966780076, 32109.51835722419, 37270.69552565647, 37921.2415751689, 38051.61948380592, 30318.54522182125, 33642.831785840914, 33853.32293354168, 34227.65772342344, 34449.65550344565, 25500.617490737284, 25511.6173257405, 25532.48935021252, 25535.489290214464, 25929.0], "value": 19513.429671664642, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.4258399480804, 371.9795411252382, 377.51489336202974, 378.39079082676903, 395.7111308744616, 18311.72532412015, 19750.209991600335, 19972.201111955503, 20157.697634535492, 20376.796232037676, 18001.7299740504, 19544.72292481033, 19957.60084798303, 20025.699614505793, 20220.39338819834, 4623.352730617717, 5769.451902069297, 5874.676892770896, 5892.057270836667, 6007.399260074001, 5836.270466191726, 5977.790777322794, 5980.581359304851, 5989.071693887455, 5998.0, 5917.319508256541, 5926.940730592703, 6232.6883655817255, 6592.3407659233935, 6612.206535215781, 6095.2685677718955, 6615.37153970372, 6627.204735431755, 6669.199696036459, 6701.966490167542, 4937.777799998973, 5867.589268751189, 5867.794627188028, 5876.647401155944, 5887.970560147215, 13107.606771796765, 13211.537596184087, 21493.92530373475, 24648.260552183463, 24793.88427520766, 2089.2061016813664, 2360.917367892133, 2391.892364843586, 2400.975990240093, 2622.7508386703234, 9741.464219467927, 10128.898711012944, 10305.433201173932, 13014.219146851125, 13725.176489410567, 1802.9008404537744, 1812.139233863904, 1837.0355563329226, 1877.2115711401107, 1880.4452686457528, 98203.50898245534, 114102.0, 118523.4073829634, 119261.0, 119666.40166799197, 10615.52230149645, 11083.891610838851, 11499.655010349703, 11560.884391156149, 11871.406429678482, 14614.853851461561, 14683.339249733788, 14706.0, 14731.410743570352, 14732.484363047457, 8363.414560980795, 8402.705905293407, 8536.914630853737, 8589.26991205742, 8629.568521573896, 8398.538080405515, 8408.747737567883, 8468.0, 8548.572571371407, 8602.268807151337, 4493.910121797546, 4531.365608814768, 4549.977250113729, 4572.154151481986, 4608.28571571407, 1887.452638734779, 1937.2347922570507, 1942.3201879342116, 1945.3385848811472, 1952.326447375653, 823.5758584329076, 911.5852287209303, 916.6104405627582, 941.7975135345933, 1008.1670505878504, 19979.900100499694, 20769.0, 20794.48013799637, 20800.687989680166, 20855.95720213983, 939.5208443693765, 956.1824639932896, 957.8084383123403, 986.5609803637456, 989.5794287427815, 10549.525271362807, 10966.451677416097, 11019.944900275448, 11021.0, 11035.44822758875, 537444.0, 546365.0, 571267.0, 580530.0, 581088.0, 133091.0, 136991.0, 137008.0, 137396.51698617544, 143094.0, 794.3049831397527, 909.9954500227457, 967.2213867836459, 971.0871780526423, 975.4244995452717, 17511.649767004845, 18106.81893181104, 18472.076396180408, 18523.44429667112, 18873.71689424632, 52808.73595631997, 53117.672058198856, 53625.731871340395, 54262.18606720823, 55916.44083559112, 74813.62593186999, 76176.85734713874, 76759.0, 77047.22952770401, 77567.61216193883, 6150.384961503993, 6929.618870962146, 7301.488895777245, 7330.633468326456, 7465.06686664167, 7491.550506969599, 11944.64166075019, 12297.0, 12788.488460461478, 12852.164609300366, 10226.897731022595, 10385.740356491147, 10445.11216546602, 10563.63027294041, 10819.080378167944, 10672.57309707634, 10807.0, 10812.5134368952, 10888.128949683847, 10981.890181098086, 9141.451512909247, 10800.351978880977, 11011.669649910515, 11269.267497612629, 11409.372484513431, 7226.385757210696, 7333.816654583465, 7391.371733402506, 7451.0, 7502.3622992046285, 9785.07041831025, 10796.460176990962, 10853.891461085288, 11149.944250278697, 11163.944180279046], "value": 9412.120367921774, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1429.7354989326975, 1470.2207829850179, 1606.148741167181, 1696.3129932377376, 1749.6588165307762, 7489.662965166567, 7771.533707977511, 8059.597020148991, 8501.829963400736, 8516.105808890063, 7982.800429989253, 8292.253697167254, 8295.253427191552, 8353.039400468948, 8394.874076888851, 2313.4332088638266, 2347.8239132065164, 2350.27141586108, 2353.5410594933996, 2356.375560476475, 2335.311083230451, 2365.727941286757, 2370.7510711375317, 2375.2755409600063, 2382.5473160099614, 2310.156792770635, 2313.803326717231, 2342.847714898526, 2344.812415006804, 2348.260298006131, 2299.9310020699404, 2303.6659684345677, 2324.535092981402, 2325.4883925536355, 2333.658146565723, 2345.8592484450987, 2355.4582446037334, 2356.964645530318, 2361.3388251289493, 2363.7399886012527, 8239.217274358927, 8328.208820162075, 8371.95814020932, 8390.202930721633, 8413.95793021031, 4839.44346400165, 4913.606911447076, 4976.601871850244, 5147.4080480744815, 5160.0969830279555, 3374.6456622054575, 3412.129906873756, 3527.7883327000463, 3554.48459973305, 3571.535700358963, 1029.3155051890483, 1046.858674079005, 1073.602766976216, 1080.724415274108, 1082.897124773153, 10782.083522900562, 10812.243142979994, 10887.673369798918, 10891.891081089167, 10922.017018468376, 4471.8434854779925, 4606.309053641913, 4731.0, 4739.289106634033, 4739.857804265877, 8116.107228204893, 8197.672093116209, 8722.563871806384, 9362.485063321583, 9367.391119577205, 4539.931901021488, 5169.844904652867, 5171.63798534099, 5177.119889618773, 5187.818426355135, 3834.367329390675, 4338.783060846945, 4408.0522687622315, 4589.931151032737, 4693.9765301173275, 3748.606396328401, 3803.0492376905772, 3848.730588858809, 3860.96139038606, 3880.9805950970062, 1255.2468518888777, 1266.265565971734, 1266.65167079054, 1278.4949944771763, 1283.5186804948144, 758.3743411685382, 844.1094645149426, 901.9368644194843, 903.9186473217311, 914.6432891172448, 5432.967736130123, 5496.395396506381, 5509.752061157257, 5618.91571626426, 5688.459596338341, 882.1266945723687, 912.2792993535165, 918.86676431917, 951.8572214167792, 986.1814693804125, 3717.70258379329, 3880.96119038806, 3928.705347098979, 3929.7445666031617, 3945.0926286953586, 9717.416954982582, 9995.200383969264, 10010.949945250228, 10030.197584193247, 10069.949650251701, 9533.0, 10013.198944084601, 10014.298999069995, 10034.297599167989, 10155.949220253999, 7629.656665450068, 7850.136484986533, 7862.095858976291, 7897.486663366978, 7907.841843163104, 5689.943100568941, 5723.399043100415, 5760.539156867357, 5789.964261325856, 5914.142449344735, 9339.439633622005, 9425.0, 9467.573959171716, 9486.098820612031, 9556.092171243721, 9123.863142053007, 9384.0, 9457.810843783223, 9473.478958657064, 9625.855612165684, 6623.470122390101, 6826.180858297036, 6963.338482844122, 7076.893846592202, 7240.348368646847, 7785.610719464117, 7890.802729931797, 7984.84030319402, 7985.001874765654, 7994.800129996796, 7023.648817559204, 7135.286471352823, 7172.175199852084, 7206.315400036988, 7294.270572942663, 6965.991782585101, 7090.680919358538, 7093.964530177316, 7181.245969173162, 7230.13238411394, 6673.366030227121, 6698.966505167443, 6765.188177418741, 6773.153355830521, 6789.2871248518195, 4437.778111094368, 4810.677063807481, 4834.009028149291, 4885.2427873679635, 4948.257761335828, 4126.793660317032, 4730.810767569396, 4750.073735621659, 4945.7527123644395, 4971.676841005323], "value": 4413.3374427244935, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.8931095494667, 302.11480362537765, 309.7367237847829, 313.4342511766261, 314.5816064634037, 4932.186189278769, 5111.744412779365, 5190.377154741432, 5296.682199068054, 5330.520253177213, 5032.572231360339, 5068.518490743373, 5237.135872581029, 5276.604254680895, 5300.92048619271, 3352.971452271377, 3429.759916805831, 4030.3148464760916, 4052.5542190359183, 4080.469538959931, 3911.9046666933305, 3986.561478237406, 4095.856645017425, 4104.343305071189, 4124.216398884218, 3463.4458486642143, 4026.255142798592, 4050.2255954670995, 4050.8784736457956, 4059.518275829316, 4054.8175332110127, 4060.8172632231326, 4062.9639441942263, 4066.0648050948153, 4103.85636502721, 3577.4454959481322, 3589.300086483137, 3600.531930848999, 3609.5127157833617, 3623.1304486923214, 4482.439695038121, 5043.5965122790085, 5164.612654050961, 5164.690118592859, 5199.48005199477, 4027.375756757678, 4660.0, 4679.274712419547, 4716.981132075474, 4765.0, 4471.306947423154, 4642.792873852789, 5196.740162991835, 5631.774729010835, 5669.716514174274, 3779.5842457329672, 3878.864239751595, 3905.2384784966944, 3919.2161567686576, 3919.3141200289833, 5246.475352464761, 5659.5755318351285, 5920.733566989496, 5921.970390148022, 5956.523478121783, 3452.2922800825772, 3988.8603898863403, 3993.9201215975518, 4024.8188831502653, 4062.370332598452, 3554.0, 3893.649571538575, 4009.6792256619983, 4024.3158663027357, 4091.9181616367505, 4112.897177570585, 4456.799444024962, 4542.728036149883, 4592.0, 4600.608948239437, 4959.8840260941, 5361.731913404392, 5714.7428365723645, 5744.109663002241, 5745.224394706661, 1110.8558185069392, 1113.4154568851418, 1145.4845319606045, 1242.6955395928055, 1285.5243559882715, 776.7475570439517, 780.9804754881173, 871.324723339417, 886.259972922604, 909.2634965677822, 4621.815127394933, 4913.410390753133, 4963.950360496421, 4975.457608141488, 5069.949300506948, 4539.800684367014, 4923.8276660316715, 4927.934454075402, 5428.348598168166, 5437.4928171329275, 4823.855284341475, 4930.7288099154175, 5082.034413461431, 5193.740312984336, 5318.330352057145, 2585.03061351993, 2921.956170657442, 2945.381469891304, 2948.82307061577, 2950.4099180164053, 5152.793888244503, 5369.758360873692, 5394.460553944653, 5803.330467984774, 5877.941220587825, 5624.128260119688, 5639.3232812062815, 5663.546916246691, 5663.971680141655, 5715.056880660512, 5051.236828562744, 5092.668976516515, 5155.845324640267, 5749.3963133871175, 5828.475437210672, 5354.3842458117815, 5384.488473595002, 5526.171074338881, 5812.18629391877, 5902.822915312548, 5198.298229738937, 5353.0, 5394.703291319015, 5835.591508594358, 5839.0949402842625, 5628.887422251614, 5809.779946211343, 6091.451769340781, 6094.786682466092, 6133.539984501091, 3453.4819777033645, 3471.3057388522902, 3871.509468854455, 3874.5396673112214, 3879.941800873046, 5365.014944470571, 5792.160136780228, 5806.883862322815, 5824.9708751457665, 5860.824175274749, 5235.408472805985, 5283.411128547061, 5346.144616861439, 5482.5613950884635, 5557.527610153021, 5578.541304391141, 5624.803131890364, 5666.886662266649, 5668.744906479301, 5675.290588676416, 4385.868423947287, 4545.704529205591, 4758.64310176745, 5256.868578285574, 5552.52803511706, 4947.957006440941, 5055.87360315995, 5683.272029425982, 5731.283589551306, 5778.0, 4709.929351059805, 5072.909324495251, 5081.92377114351, 5547.920903898375, 5550.222968784293], "value": 4045.076836857115, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.6250787019918, 721.3003386714885, 730.2186660273507, 776.1539921485579, 776.3323541754094, 13776.311184440776, 14433.350499227534, 14544.709105817878, 14554.927225363872, 14564.27178641069, 12094.0, 12234.694132646688, 12318.445669944851, 14656.267186640678, 14667.973241873073, 817.166490180016, 853.9615717292721, 874.8337815814975, 876.3865294293988, 878.7232021913109, 861.6940985949991, 867.3234876796109, 868.5440143924427, 869.2307308032405, 870.6778491957953, 892.5225004622529, 894.516960841145, 912.2838571721201, 913.7350168451142, 915.716128000322, 870.2211520689011, 889.1775108025083, 908.52756566585, 909.5315912305168, 915.9542022898862, 558.511302610216, 614.8954677704801, 671.6238906212536, 674.197704731368, 675.8377989282537, 11054.668359949215, 11924.46339914706, 13214.273214973173, 13571.185728856202, 13576.321183940763, 13412.46350145993, 13537.59387218385, 13619.455221791117, 13639.454421823115, 13764.380602872894, 10977.341359518454, 11087.390193539351, 11100.333979961228, 11156.832647510293, 11174.161937854693, 3509.7192224621967, 3584.3823341156326, 3617.602063772983, 3708.462272970431, 3737.9626203737885, 78169.82745258769, 80679.19320806835, 80848.59575702142, 80972.59513702393, 81317.59341203315, 10210.183185345155, 10452.895471045193, 10550.366977981346, 10575.576976920991, 10643.042126208678, 20033.59932801336, 20071.16657363849, 20283.087261073288, 20302.0, 20753.377398678065, 7650.0, 7696.884546731803, 7732.342750866127, 7742.883856742041, 7856.292933635886, 4231.217224813419, 4250.511191212988, 4271.765052922119, 4289.185054839633, 4327.37253098299, 3165.3352795913115, 3387.983060084684, 3459.8097104659487, 3477.6000759912936, 3810.8666196682984, 595.0598055072975, 612.2285919741158, 655.0665301944709, 661.0249881424955, 692.4322055914103, 612.6201754911983, 621.4531212533033, 641.3746597067915, 659.6536818170499, 700.4501466348994, 8203.30271926881, 9055.818883622422, 9164.450132992042, 9247.861282080774, 9350.438973661601, 525.8606469285643, 579.5161040531119, 606.1271768653213, 639.9840003999844, 657.4707360574688, 3589.9102522437147, 4467.195904737114, 4467.865964021084, 4482.529334419904, 4535.979404633997, 78123.60938195273, 78266.6086669563, 80349.0, 91887.0, 93071.0, 59996.100058499156, 61366.69316653388, 61903.0, 68857.0, 88331.0, 57485.0, 58158.12762808561, 61180.47048823726, 63502.36497635057, 78143.0, 9209.125133112344, 9280.767980800263, 9600.615975360908, 9634.084761947604, 9660.32377733552, 41998.95002624836, 52459.73770131125, 52509.474905250456, 52836.73581632067, 54934.0, 66737.0, 68399.65800170967, 70000.29999699938, 71570.0, 73349.6332518334, 12193.390330483262, 12297.077719170918, 12310.81533777012, 12398.380080995734, 12553.874461255271, 13614.0, 13695.0, 14102.506412275521, 14151.575452736435, 14228.928855355656, 12554.0, 12975.286359250333, 13222.0, 13314.600561982768, 13322.33388330599, 12808.423620936894, 12887.987522610181, 12979.740405191655, 13200.867991319963, 13227.007974402151, 12539.87460125387, 12836.16564923277, 12868.806967895676, 13082.345882706017, 13187.142835715647, 10628.309159904582, 10750.139988801033, 10951.671449856518, 11111.498865817613, 11497.137714671266, 9447.724557184967, 10755.085817705583, 11106.777864442503, 11211.215214934877, 11244.26912250701], "value": 7076.908901900894, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 120.0, 120.0, 118.0, 121.0, 120.0, 120.0, 119.0, 119.0], "value": 118.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 112.0, 108.0, 143.0, 109.0, 132.0, 140.0, 110.0, 136.0, 110.0], "value": 124.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 202.0, 196.0, 200.0, 199.0, 198.0, 198.0, 201.0, 239.0, 270.0], "value": 209.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 59.0, 78.0, 63.0, 76.0, 78.0, 79.0, 70.0, 75.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 117.0, 95.0, 86.0, 58.0, 117.0, 99.0, 99.0, 116.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 90.0, 87.0, 89.0, 87.0, 96.0, 99.0, 127.0, 86.0], "value": 93.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 97.0, 98.0, 96.0, 97.0, 108.0, 144.0, 147.0, 104.0], "value": 118.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 92.0, 92.0, 86.0, 87.0, 89.0, 91.0, 92.0, 129.0, 134.0], "value": 98.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 56.0, 61.0, 57.0, 60.0, 59.0, 61.0, 60.0, 60.0], "value": 58.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 70.0, 84.0, 81.0, 99.0, 98.0, 101.0, 101.0, 62.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 100.0, 101.0, 86.0, 85.0, 84.0, 84.0, 83.0, 86.0, 85.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 184.0, 172.0, 166.0, 152.0, 161.0, 156.0, 146.0, 192.0, 141.0], "value": 164.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 165.0, 165.0, 166.0, 165.0, 164.0, 168.0, 166.0, 164.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 66.0, 70.0, 68.0, 69.0, 71.0, 86.0, 89.0, 73.0], "value": 73.0, "unit": "score"}]}]} 02:57:02 INFO - Return code: 0 02:57:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:57:02 ERROR - # TBPL SUCCESS # 02:57:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:57:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:57:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:57:02 INFO - 2017-01-08 02:57:02.143 screenresolution[1661:10908] starting screenresolution argv=screenresolution get 02:57:02 INFO - 2017-01-08 02:57:02.145 screenresolution[1661:10908] Display 0: 1600x1200x32@60 02:57:02 INFO - 2017-01-08 02:57:02.152 screenresolution[1662:10911] starting screenresolution argv=screenresolution list 02:57:02 INFO - Available Modes on Display 0 02:57:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:57:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:57:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:57:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:57:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:57:02 INFO - Intel Iris: 02:57:02 INFO - Chipset Model: Intel Iris 02:57:02 INFO - Type: GPU 02:57:02 INFO - Bus: Built-In 02:57:02 INFO - VRAM (Dynamic, Max): 1536 MB 02:57:02 INFO - Vendor: Intel (0x8086) 02:57:02 INFO - Device ID: 0x0a2e 02:57:02 INFO - Revision ID: 0x0009 02:57:02 INFO - Displays: 02:57:02 INFO - Display: 02:57:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:57:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:57:02 INFO - Display Serial Number: 1600x1200 60 02:57:02 INFO - Main Display: Yes 02:57:02 INFO - Mirror: Off 02:57:02 INFO - Online: Yes 02:57:02 INFO - Rotation: Supported 02:57:02 INFO - Return code: 0 02:57:02 INFO - Running post-action listener: _resource_record_post_action 02:57:02 INFO - [mozharness: 2017-01-08 10:57:02.396230Z] Finished run-tests step (success) 02:57:02 INFO - Running post-run listener: _resource_record_post_run 02:57:02 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 33415168; Write bytes: 659913728; Read time: 200; Write time: 1906 02:57:02 INFO - TinderboxPrint: CPU usage
55.0% 02:57:02 INFO - TinderboxPrint: I/O read bytes / time
33,415,168 / 200 02:57:02 INFO - TinderboxPrint: I/O write bytes / time
659,913,728 / 1,906 02:57:02 INFO - TinderboxPrint: CPU idle
2,180.6 (45.0%) 02:57:02 INFO - TinderboxPrint: CPU system
990.4 (20.5%) 02:57:02 INFO - TinderboxPrint: CPU user
1,670.7 (34.5%) 02:57:02 INFO - TinderboxPrint: Swap in / out
379,342,848 / 0 02:57:02 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 340886016; Write bytes: 334677504; Read time: 26853; Write time: 666 02:57:02 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 32501760; Write bytes: 308581888; Read time: 190; Write time: 1186 02:57:02 INFO - Running post-run listener: _upload_blobber_files 02:57:02 INFO - Blob upload gear active. 02:57:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:57:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:57:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:57:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:57:03 INFO - (blobuploader) - INFO - Open directory for files ... 02:57:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:57:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:57:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:57:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:57:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:57:04 INFO - (blobuploader) - INFO - Done attempting. 02:57:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:57:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:57:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:57:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:57:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:57:05 INFO - (blobuploader) - INFO - Done attempting. 02:57:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:57:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:57:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:57:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:57:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:57:07 INFO - (blobuploader) - INFO - Done attempting. 02:57:07 INFO - (blobuploader) - INFO - Iteration through files over. 02:57:07 INFO - Return code: 0 02:57:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:57:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:57:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/871791cddf6bad9ad319d7baa02b9416a0983ceaf18e47a026a009a3af20f585960a7948b778115609fb4177f23865df3554bcb21d72bf44f04824b092ea992b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f0e7931d36eb73c3463c282f4c9463e8e3849281d26acfb910fc878c2c837eccf9c94ac7d4f0e6445d3d985394b085526f95ac9496178883a235bf7c4092d70e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2315e5e1416718da0e7b8349ca7b45fce5f1b06b69dd8ba56004f5ac6e5127812e8ffaa2142aaf273ecc0e497c16578ded868f888ab3572c5eb0505908b80626"} 02:57:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:57:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:57:07 INFO - Contents: 02:57:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/871791cddf6bad9ad319d7baa02b9416a0983ceaf18e47a026a009a3af20f585960a7948b778115609fb4177f23865df3554bcb21d72bf44f04824b092ea992b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f0e7931d36eb73c3463c282f4c9463e8e3849281d26acfb910fc878c2c837eccf9c94ac7d4f0e6445d3d985394b085526f95ac9496178883a235bf7c4092d70e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2315e5e1416718da0e7b8349ca7b45fce5f1b06b69dd8ba56004f5ac6e5127812e8ffaa2142aaf273ecc0e497c16578ded868f888ab3572c5eb0505908b80626"} 02:57:07 INFO - Running post-run listener: copy_logs_to_upload_dir 02:57:07 INFO - Copying logs to upload dir... 02:57:07 INFO - mkdir: /builds/slave/test/build/upload/logs 02:57:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.168486 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-08 02:57:07.427258) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-08 02:57:07.431281) ========= 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.QH8uurrJpV/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.4S06lLIuBW/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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/871791cddf6bad9ad319d7baa02b9416a0983ceaf18e47a026a009a3af20f585960a7948b778115609fb4177f23865df3554bcb21d72bf44f04824b092ea992b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f0e7931d36eb73c3463c282f4c9463e8e3849281d26acfb910fc878c2c837eccf9c94ac7d4f0e6445d3d985394b085526f95ac9496178883a235bf7c4092d70e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2315e5e1416718da0e7b8349ca7b45fce5f1b06b69dd8ba56004f5ac6e5127812e8ffaa2142aaf273ecc0e497c16578ded868f888ab3572c5eb0505908b80626"} build_url:https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011154 build_url: 'https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/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/871791cddf6bad9ad319d7baa02b9416a0983ceaf18e47a026a009a3af20f585960a7948b778115609fb4177f23865df3554bcb21d72bf44f04824b092ea992b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f0e7931d36eb73c3463c282f4c9463e8e3849281d26acfb910fc878c2c837eccf9c94ac7d4f0e6445d3d985394b085526f95ac9496178883a235bf7c4092d70e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2315e5e1416718da0e7b8349ca7b45fce5f1b06b69dd8ba56004f5ac6e5127812e8ffaa2142aaf273ecc0e497c16578ded868f888ab3572c5eb0505908b80626"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-08 02:57:07.466510) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 02:57:07.466887) ========= 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.QH8uurrJpV/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.4S06lLIuBW/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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.005185 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 02:57:07.515937) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-08 02:57:07.516254) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-08 02:57:07.516577) ========= ========= Total master_lag: 0.21 =========