builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0117 starttime: 1492860404.65 results: success (0) buildid: 20170422030205 builduid: d556da6f77a14e54ac35b497def50801 revision: 7d85b081bfabe91dbf18a421f1eee2a32f335823 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-22 04:26:44.655167) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-22 04:26:44.655652) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-22 04:26:44.655940) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-22 04:26:44.754037) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-22 04:26:44.754364) ========= 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.USJrvEcwEE/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.mK3bdY4Ttc/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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-04-22 04:26:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.36M=0.002s 2017-04-22 04:26:45 (7.36 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.644756 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-22 04:26:45.423753) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-22 04:26:45.424071) ========= 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.USJrvEcwEE/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.mK3bdY4Ttc/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.022157 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-22 04:26:45.472019) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-22 04:26:45.472331) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7d85b081bfabe91dbf18a421f1eee2a32f335823 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 7d85b081bfabe91dbf18a421f1eee2a32f335823 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.USJrvEcwEE/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.mK3bdY4Ttc/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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-04-22 04:26:45,520 truncating revision to first 12 chars 2017-04-22 04:26:45,520 Setting DEBUG logging. 2017-04-22 04:26:45,520 attempt 1/10 2017-04-22 04:26:45,520 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7d85b081bfab?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-22 04:26:46,557 unpacking tar archive at: mozilla-central-7d85b081bfab/testing/mozharness/ program finished with exit code 0 elapsedTime=1.297666 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-22 04:26:46.794537) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-22 04:26:46.794848) ========= script_repo_revision: 7d85b081bfabe91dbf18a421f1eee2a32f335823 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-22 04:26:46.795274) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-22 04:26:46.795588) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-22 04:26:46.816032) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-22 04:26:46.816436) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.USJrvEcwEE/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.mK3bdY4Ttc/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:26:46 INFO - MultiFileLogger online at 20170422 04:26:46 in /builds/slave/test 04:26:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:26:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:26:46 INFO - {'append_to_log': False, 04:26:46 INFO - 'base_work_dir': '/builds/slave/test', 04:26:46 INFO - 'blob_upload_branch': 'Firefox', 04:26:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:26:46 INFO - 'branch': 'Firefox', 04:26:46 INFO - 'buildbot_json_path': 'buildprops.json', 04:26:46 INFO - 'config_files': ('talos/mac_config.py',), 04:26:46 INFO - 'default_actions': ('clobber', 04:26:46 INFO - 'read-buildbot-config', 04:26:46 INFO - 'download-and-extract', 04:26:46 INFO - 'populate-webroot', 04:26:46 INFO - 'create-virtualenv', 04:26:46 INFO - 'install', 04:26:46 INFO - 'run-tests'), 04:26:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:26:46 INFO - 'download_minidump_stackwalk': True, 04:26:46 INFO - 'download_symbols': 'ondemand', 04:26:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:26:46 INFO - 'tooltool.py': '/tools/tooltool.py', 04:26:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:26:46 INFO - '/tools/misc-python/virtualenv.py')}, 04:26:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:26:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:26:46 INFO - 'gecko_profile': False, 04:26:46 INFO - 'gecko_profile_interval': 0, 04:26:46 INFO - 'installer_path': 'installer.exe', 04:26:46 INFO - 'log_level': 'info', 04:26:46 INFO - 'log_name': 'talos', 04:26:46 INFO - 'log_to_console': True, 04:26:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:26:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:26:46 INFO - 'opt_config_files': (), 04:26:46 INFO - 'pip_index': False, 04:26:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:26:46 INFO - 'cmd': ('bash', 04:26:46 INFO - '-c', 04:26:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:26:46 INFO - 'enabled': True, 04:26:46 INFO - 'halt_on_failure': False, 04:26:46 INFO - 'name': 'check_screen_resolution'},), 04:26:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:26:46 INFO - 'cmd': ('bash', 04:26:46 INFO - '-c', 04:26:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:26:46 INFO - 'enabled': True, 04:26:46 INFO - 'halt_on_failure': False, 04:26:46 INFO - 'name': 'check_screen_resolution'},), 04:26:46 INFO - 'run_cmd_checks_enabled': True, 04:26:46 INFO - 'suite': 'dromaeojs-e10s', 04:26:46 INFO - 'system_bits': '32', 04:26:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:26:46 INFO - 'title': 't-yosemite-r7-0117', 04:26:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:26:46 INFO - 'use_talos_json': True, 04:26:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:26:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:26:46 INFO - 'work_dir': 'build'} 04:26:46 INFO - [mozharness: 2017-04-22 11:26:46.991120Z] Running clobber step. 04:26:46 INFO - Running pre-action listener: _resource_record_pre_action 04:26:46 INFO - Running main action method: clobber 04:26:46 INFO - rmtree: /builds/slave/test/build 04:26:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:26:48 INFO - Running post-action listener: _resource_record_post_action 04:26:48 INFO - [mozharness: 2017-04-22 11:26:48.985112Z] Finished clobber step (success) 04:26:48 INFO - [mozharness: 2017-04-22 11:26:48.985239Z] Running read-buildbot-config step. 04:26:48 INFO - Running pre-action listener: _resource_record_pre_action 04:26:48 INFO - Running main action method: read_buildbot_config 04:26:48 INFO - Using buildbot properties: 04:26:48 INFO - { 04:26:48 INFO - "project": "", 04:26:48 INFO - "product": "firefox", 04:26:48 INFO - "script_repo_revision": "production", 04:26:48 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:26:48 INFO - "repository": "", 04:26:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:26:48 INFO - "buildid": "20170422030205", 04:26:48 INFO - "stage_platform": "macosx64", 04:26:48 INFO - "basedir": "/builds/slave/test", 04:26:48 INFO - "nightly_build": "True", 04:26:48 INFO - "buildnumber": 38, 04:26:48 INFO - "slavename": "t-yosemite-r7-0117", 04:26:48 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:26:48 INFO - "platform": "yosemite_r7", 04:26:48 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:26:48 INFO - "branch": "mozilla-central", 04:26:48 INFO - "repo_path": "mozilla-central", 04:26:48 INFO - "revision": "7d85b081bfabe91dbf18a421f1eee2a32f335823", 04:26:48 INFO - "pgo_build": "False", 04:26:48 INFO - "builduid": "d556da6f77a14e54ac35b497def50801", 04:26:48 INFO - "slavebuilddir": "test" 04:26:48 INFO - } 04:26:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:26:48 INFO - Running post-action listener: _resource_record_post_action 04:26:48 INFO - [mozharness: 2017-04-22 11:26:48.988747Z] Finished read-buildbot-config step (success) 04:26:48 INFO - [mozharness: 2017-04-22 11:26:48.988879Z] Running download-and-extract step. 04:26:48 INFO - Running pre-action listener: _resource_record_pre_action 04:26:48 INFO - Running main action method: download_and_extract 04:26:48 INFO - mkdir: /builds/slave/test/build/tests 04:26:48 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')]} 04:26:48 INFO - trying https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:26:48 INFO - Downloading https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:26:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 04:26:50 INFO - Downloaded 1325 bytes. 04:26:50 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:26:50 INFO - Using the following test package requirements: 04:26:50 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:26:50 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:26:50 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:26:50 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:26:50 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:26:50 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:26:50 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:26:50 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:26:50 INFO - u'jsshell-mac.zip'], 04:26:50 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:26:50 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:26:50 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:26:50 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:26:50 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:26:50 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:26:50 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:26:50 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:26:50 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:26:50 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:26:50 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:26:50 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:26:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:26:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:26:50 INFO - Fetch https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:26:52 INFO - Content-Length response header: 52523100 04:26:52 INFO - Bytes received: 52523100 04:27:08 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:27:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:27:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:27:08 INFO - Fetch https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:27:14 INFO - Content-Length response header: 52523100 04:27:14 INFO - Bytes received: 52523100 04:27:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:27:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:27:29 INFO - Fetch https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:27:30 INFO - Content-Length response header: 14408811 04:27:30 INFO - Bytes received: 14408811 04:27:31 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')]} 04:27:31 INFO - trying https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:27:31 INFO - Downloading https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:27:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 04:27:33 INFO - Downloaded 71119761 bytes. 04:27:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:27:33 INFO - mkdir: /builds/slave/test/properties 04:27:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:27:33 INFO - Writing to file /builds/slave/test/properties/build_url 04:27:33 INFO - Contents: 04:27:33 INFO - build_url:https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:27:34 INFO - Running post-action listener: _resource_record_post_action 04:27:34 INFO - Running post-action listener: set_extra_try_arguments 04:27:34 INFO - [mozharness: 2017-04-22 11:27:34.007834Z] Finished download-and-extract step (success) 04:27:34 INFO - [mozharness: 2017-04-22 11:27:34.007955Z] Running populate-webroot step. 04:27:34 INFO - Running pre-action listener: _resource_record_pre_action 04:27:34 INFO - Running main action method: populate_webroot 04:27:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:27:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:27:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:27:34 INFO - '--tppagecycles', 04:27:34 INFO - '7'], 04:27:34 INFO - 'tests': ['tsvgm']}}, 04:27:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:27:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:27:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:27:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:27:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:27:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:27:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:27:34 INFO - 'talos_options': ['--disable-e10s'], 04:27:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:27:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:27:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:27:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:27:34 INFO - 'talos_options': ['--disable-e10s'], 04:27:34 INFO - 'tests': ['damp', 'tps']}, 04:27:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:27:34 INFO - 'tests': ['damp', 'tps']}, 04:27:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:27:34 INFO - 'tests': ['dromaeo_dom']}, 04:27:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:27:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:27:34 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:27:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:27:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:27:34 INFO - 'tests': ['a11yr', 04:27:34 INFO - 'ts_paint', 04:27:34 INFO - 'tpaint', 04:27:34 INFO - 'sessionrestore', 04:27:34 INFO - 'sessionrestore_no_auto_restore', 04:27:34 INFO - 'tabpaint']}, 04:27:34 INFO - 'other-e10s': {'tests': ['a11yr', 04:27:34 INFO - 'ts_paint', 04:27:34 INFO - 'tpaint', 04:27:34 INFO - 'sessionrestore', 04:27:34 INFO - 'sessionrestore_no_auto_restore', 04:27:34 INFO - 'tabpaint']}, 04:27:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:27:34 INFO - 'ts_paint', 04:27:34 INFO - 'tpaint', 04:27:34 INFO - 'sessionrestore', 04:27:34 INFO - 'sessionrestore_no_auto_restore', 04:27:34 INFO - 'tabpaint']}, 04:27:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:27:34 INFO - 'ts_paint', 04:27:34 INFO - 'tpaint', 04:27:34 INFO - 'sessionrestore', 04:27:34 INFO - 'sessionrestore_no_auto_restore', 04:27:34 INFO - 'tabpaint']}, 04:27:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:27:34 INFO - 'tests': ['a11yr', 04:27:34 INFO - 'ts_paint', 04:27:34 INFO - 'tpaint', 04:27:34 INFO - 'sessionrestore', 04:27:34 INFO - 'sessionrestore_no_auto_restore', 04:27:34 INFO - 'tabpaint']}, 04:27:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:27:34 INFO - 'tests': ['a11yr', 04:27:34 INFO - 'ts_paint', 04:27:34 INFO - 'tpaint', 04:27:34 INFO - 'sessionrestore', 04:27:34 INFO - 'sessionrestore_no_auto_restore', 04:27:34 INFO - 'tabpaint']}, 04:27:34 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:27:34 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:27:34 INFO - 'bloom_basic_ref']}, 04:27:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:27:34 INFO - 'tests': ['tsvgx', 04:27:34 INFO - 'tsvgr_opacity', 04:27:34 INFO - 'tart', 04:27:34 INFO - 'tscrollx', 04:27:34 INFO - 'cart', 04:27:34 INFO - 'tsvg_static']}, 04:27:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:27:34 INFO - 'tsvgr_opacity', 04:27:34 INFO - 'tart', 04:27:34 INFO - 'tscrollx', 04:27:34 INFO - 'cart', 04:27:34 INFO - 'tsvg_static']}, 04:27:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:27:34 INFO - 'talos_options': ['--disable-e10s'], 04:27:34 INFO - 'tests': ['tp5o']}, 04:27:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:27:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:27:34 INFO - 'talos_options': ['--disable-e10s', 04:27:34 INFO - '--xperf_path', 04:27:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:27:34 INFO - 'tests': ['tp5n']}, 04:27:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:27:34 INFO - 'talos_options': ['--xperf_path', 04:27:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:27:34 INFO - 'tests': ['tp5n']}}, 04:27:34 INFO - 'talos.zip': {'path': '', 04:27:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:27:34 INFO - Running post-action listener: _resource_record_post_action 04:27:34 INFO - [mozharness: 2017-04-22 11:27:34.026414Z] Finished populate-webroot step (success) 04:27:34 INFO - [mozharness: 2017-04-22 11:27:34.026519Z] Running create-virtualenv step. 04:27:34 INFO - Running pre-action listener: _resource_record_pre_action 04:27:34 INFO - Running main action method: create_virtualenv 04:27:34 INFO - Creating virtualenv /builds/slave/test/build/venv 04:27:34 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 04:27:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:27:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:27:34 INFO - Using real prefix '/tools/python27' 04:27:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:27:34 INFO - Installing distribute.............................................................................................................................................................................................done. 04:27:37 INFO - Installing pip.................done. 04:27:37 INFO - Return code: 0 04:27:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:27:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:27:37 INFO - Reading from file tmpfile_stdout 04:27:37 INFO - Output received: 04:27:37 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) 04:27:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:27:37 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')]} 04:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:37 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')]} 04:27:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:37 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 0x10c64cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c64f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea204ab060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42ad30>, '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 0x10c350db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c41fbe8>, '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.USJrvEcwEE/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.mK3bdY4Ttc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:27:37 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 04:27:37 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 04:27:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.USJrvEcwEE/Render', 04:27:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:37 INFO - 'HOME': '/Users/cltbld', 04:27:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:37 INFO - 'LOGNAME': 'cltbld', 04:27:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:37 INFO - 'MOZ_NO_REMOTE': '1', 04:27:37 INFO - 'NO_EM_RESTART': '1', 04:27:37 INFO - 'PAGER': '/bin/cat', 04:27:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:37 INFO - 'PWD': '/builds/slave/test', 04:27:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:37 INFO - 'SHELL': '/bin/bash', 04:27:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mK3bdY4Ttc/Listeners', 04:27:37 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:27:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:37 INFO - 'USER': 'cltbld', 04:27:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:37 INFO - 'XPC_FLAGS': '0x0', 04:27:37 INFO - 'XPC_SERVICE_NAME': '0', 04:27:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:38 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 04:27:38 INFO - Cleaning up... 04:27:38 INFO - Return code: 0 04:27:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:38 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 0x10c64cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c64f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea204ab060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42ad30>, '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 0x10c350db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c41fbe8>, '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.USJrvEcwEE/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.mK3bdY4Ttc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:27:38 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 04:27:38 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 04:27:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.USJrvEcwEE/Render', 04:27:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:38 INFO - 'HOME': '/Users/cltbld', 04:27:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:38 INFO - 'LOGNAME': 'cltbld', 04:27:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:38 INFO - 'MOZ_NO_REMOTE': '1', 04:27:38 INFO - 'NO_EM_RESTART': '1', 04:27:38 INFO - 'PAGER': '/bin/cat', 04:27:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:38 INFO - 'PWD': '/builds/slave/test', 04:27:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:38 INFO - 'SHELL': '/bin/bash', 04:27:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mK3bdY4Ttc/Listeners', 04:27:38 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:27:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:38 INFO - 'USER': 'cltbld', 04:27:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:38 INFO - 'XPC_FLAGS': '0x0', 04:27:38 INFO - 'XPC_SERVICE_NAME': '0', 04:27:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:38 INFO - Downloading/unpacking psutil>=3.1.1 04:27: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 04:27: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 04:27: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 04:27: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 04:27: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 04:27: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 04:27:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:27:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:27:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:27:42 INFO - Installing collected packages: psutil 04:27:42 INFO - Running setup.py install for psutil 04:27:42 INFO - building 'psutil._psutil_osx' extension 04:27:42 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 04:27:42 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 04:27:42 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 04:27:42 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 04:27:42 INFO - building 'psutil._psutil_posix' extension 04:27:42 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 04:27:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:27:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:27:42 INFO - ^ 04:27:42 INFO - 1 warning generated. 04:27:42 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 04:27:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:27:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:27:43 INFO - Successfully installed psutil 04:27:43 INFO - Cleaning up... 04:27:43 INFO - Return code: 0 04:27:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:27:43 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')]} 04:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:43 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')]} 04:27:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:43 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 0x10c64cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c64f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea204ab060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42ad30>, '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 0x10c350db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c41fbe8>, '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.USJrvEcwEE/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.mK3bdY4Ttc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:27:43 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 04:27:43 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 04:27:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.USJrvEcwEE/Render', 04:27:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:43 INFO - 'HOME': '/Users/cltbld', 04:27:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:43 INFO - 'LOGNAME': 'cltbld', 04:27:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:43 INFO - 'MOZ_NO_REMOTE': '1', 04:27:43 INFO - 'NO_EM_RESTART': '1', 04:27:43 INFO - 'PAGER': '/bin/cat', 04:27:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:43 INFO - 'PWD': '/builds/slave/test', 04:27:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:43 INFO - 'SHELL': '/bin/bash', 04:27:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mK3bdY4Ttc/Listeners', 04:27:43 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:27:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:43 INFO - 'USER': 'cltbld', 04:27:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:43 INFO - 'XPC_FLAGS': '0x0', 04:27:43 INFO - 'XPC_SERVICE_NAME': '0', 04:27:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:27:43 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 04:27:43 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 04:27:43 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 04:27:43 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 04:27:43 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 04:27:43 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 04:27:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:27:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:27:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:27:46 INFO - Installing collected packages: mozsystemmonitor 04:27:46 INFO - Running setup.py install for mozsystemmonitor 04:27:46 INFO - Successfully installed mozsystemmonitor 04:27:46 INFO - Cleaning up... 04:27:46 INFO - Return code: 0 04:27:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:27:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:46 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 0x10c64cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c64f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea204ab060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42ad30>, '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 0x10c350db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c41fbe8>, '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.USJrvEcwEE/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.mK3bdY4Ttc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:27:46 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 04:27:46 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 04:27:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.USJrvEcwEE/Render', 04:27:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:46 INFO - 'HOME': '/Users/cltbld', 04:27:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:46 INFO - 'LOGNAME': 'cltbld', 04:27:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:46 INFO - 'MOZ_NO_REMOTE': '1', 04:27:46 INFO - 'NO_EM_RESTART': '1', 04:27:46 INFO - 'PAGER': '/bin/cat', 04:27:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:46 INFO - 'PWD': '/builds/slave/test', 04:27:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:46 INFO - 'SHELL': '/bin/bash', 04:27:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mK3bdY4Ttc/Listeners', 04:27:46 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:27:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:46 INFO - 'USER': 'cltbld', 04:27:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:46 INFO - 'XPC_FLAGS': '0x0', 04:27:46 INFO - 'XPC_SERVICE_NAME': '0', 04:27:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:46 INFO - Downloading/unpacking jsonschema==2.5.1 04:27:46 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 04:27:46 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 04:27:46 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 04:27:46 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 04:27:46 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 04:27:46 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 04:27:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:27:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:27:49 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 04:27:49 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 04:27:49 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 04:27:49 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 04:27:49 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 04:27:49 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 04:27:49 INFO - Downloading functools32-3.2.3-2.tar.gz 04:27:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:27:49 INFO - warning: no files found matching '*.txt' 04:27:49 INFO - no previously-included directories found matching 'build' 04:27:49 INFO - no previously-included directories found matching 'dist' 04:27:49 INFO - no previously-included directories found matching '.git*' 04:27:49 INFO - Installing collected packages: jsonschema, functools32 04:27:49 INFO - Running setup.py install for functools32 04:27:50 INFO - warning: no files found matching '*.txt' 04:27:50 INFO - no previously-included directories found matching 'build' 04:27:50 INFO - no previously-included directories found matching 'dist' 04:27:50 INFO - no previously-included directories found matching '.git*' 04:27:50 INFO - Successfully installed jsonschema functools32 04:27:50 INFO - Cleaning up... 04:27:50 INFO - Return code: 0 04:27:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:27:50 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')]} 04:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:50 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')]} 04:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:50 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 0x10c64cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c64f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea204ab060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42ad30>, '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 0x10c350db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c41fbe8>, '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.USJrvEcwEE/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.mK3bdY4Ttc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:27:50 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 04:27:50 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 04:27:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.USJrvEcwEE/Render', 04:27:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:50 INFO - 'HOME': '/Users/cltbld', 04:27:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:50 INFO - 'LOGNAME': 'cltbld', 04:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:50 INFO - 'MOZ_NO_REMOTE': '1', 04:27:50 INFO - 'NO_EM_RESTART': '1', 04:27:50 INFO - 'PAGER': '/bin/cat', 04:27:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:50 INFO - 'PWD': '/builds/slave/test', 04:27:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:50 INFO - 'SHELL': '/bin/bash', 04:27:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mK3bdY4Ttc/Listeners', 04:27:50 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:27:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:50 INFO - 'USER': 'cltbld', 04:27:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:50 INFO - 'XPC_FLAGS': '0x0', 04:27:50 INFO - 'XPC_SERVICE_NAME': '0', 04:27:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:27:50 INFO - Cleaning up... 04:27:50 INFO - Return code: 0 04:27:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:27:50 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')]} 04:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:50 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')]} 04:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:50 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 0x10c64cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c64f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea204ab060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42ad30>, '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 0x10c350db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c41fbe8>, '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.USJrvEcwEE/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.mK3bdY4Ttc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:27:50 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 04:27:50 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 04:27:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.USJrvEcwEE/Render', 04:27:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:50 INFO - 'HOME': '/Users/cltbld', 04:27:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:50 INFO - 'LOGNAME': 'cltbld', 04:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:50 INFO - 'MOZ_NO_REMOTE': '1', 04:27:50 INFO - 'NO_EM_RESTART': '1', 04:27:50 INFO - 'PAGER': '/bin/cat', 04:27:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:50 INFO - 'PWD': '/builds/slave/test', 04:27:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:50 INFO - 'SHELL': '/bin/bash', 04:27:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mK3bdY4Ttc/Listeners', 04:27:50 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:27:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:50 INFO - 'USER': 'cltbld', 04:27:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:50 INFO - 'XPC_FLAGS': '0x0', 04:27:50 INFO - 'XPC_SERVICE_NAME': '0', 04:27:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:50 INFO - Downloading/unpacking blobuploader==1.2.4 04:27:50 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 04:27:50 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 04:27:50 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 04:27:50 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 04:27:50 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 04:27:50 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 04:27:53 INFO - Downloading blobuploader-1.2.4.tar.gz 04:27:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:27:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:27:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:27:54 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 04:27:54 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 04:27:54 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 04:27:54 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 04:27:54 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 04:27:54 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 04:27:54 INFO - Downloading docopt-0.6.1.tar.gz 04:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:27:54 INFO - Installing collected packages: blobuploader, requests, docopt 04:27:54 INFO - Running setup.py install for blobuploader 04:27:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:27:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:27:54 INFO - Running setup.py install for requests 04:27:55 INFO - Running setup.py install for docopt 04:27:55 INFO - Successfully installed blobuploader requests docopt 04:27:55 INFO - Cleaning up... 04:27:55 INFO - Return code: 0 04:27:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:27:55 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')]} 04:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:55 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')]} 04:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:55 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 0x10c64cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c64f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea204ab060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42ad30>, '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 0x10c350db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c41fbe8>, '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.USJrvEcwEE/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.mK3bdY4Ttc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:27:55 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 04:27:55 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 04:27:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.USJrvEcwEE/Render', 04:27:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:55 INFO - 'HOME': '/Users/cltbld', 04:27:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:55 INFO - 'LOGNAME': 'cltbld', 04:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:55 INFO - 'MOZ_NO_REMOTE': '1', 04:27:55 INFO - 'NO_EM_RESTART': '1', 04:27:55 INFO - 'PAGER': '/bin/cat', 04:27:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:55 INFO - 'PWD': '/builds/slave/test', 04:27:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:55 INFO - 'SHELL': '/bin/bash', 04:27:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mK3bdY4Ttc/Listeners', 04:27:55 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:27:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:55 INFO - 'USER': 'cltbld', 04:27:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:55 INFO - 'XPC_FLAGS': '0x0', 04:27:55 INFO - 'XPC_SERVICE_NAME': '0', 04:27:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:27:55 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-XwTux4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:27:55 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-e4wHcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:27:56 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-xEpFzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:27:56 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-BcRrvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:27:56 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Emr75N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:27:56 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-cLuoqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:27:56 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-5TZxMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:27:56 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-olrETj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:27:56 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-quJpJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:27:56 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-nubUia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:27:56 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-3MKNBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:27:56 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-pVdyjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:27:57 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-mGiBU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:27:57 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-PCPkqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:27:57 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-2qg6iC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:27:57 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-oLpmkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:27:57 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-YZL9Ye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:27:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:27:57 INFO - Running setup.py install for manifestparser 04:27:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:27:57 INFO - Running setup.py install for mozcrash 04:27:57 INFO - Running setup.py install for mozdebug 04:27:57 INFO - Running setup.py install for mozdevice 04:27:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:27:58 INFO - Running setup.py install for mozfile 04:27:58 INFO - Running setup.py install for mozhttpd 04:27:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:27:58 INFO - Running setup.py install for mozinfo 04:27:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:27:58 INFO - Running setup.py install for mozInstall 04:27:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:27:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:27:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:27:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:27:58 INFO - Running setup.py install for mozleak 04:27:58 INFO - Running setup.py install for mozlog 04:27:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:27:59 INFO - Running setup.py install for moznetwork 04:27:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:27:59 INFO - Running setup.py install for mozprocess 04:27:59 INFO - Running setup.py install for mozprofile 04:27:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:27:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:27:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:27:59 INFO - Running setup.py install for mozrunner 04:27:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:27:59 INFO - Running setup.py install for mozscreenshot 04:27:59 INFO - Running setup.py install for moztest 04:27:59 INFO - Running setup.py install for mozversion 04:28:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:28:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:28:00 INFO - Cleaning up... 04:28:00 INFO - Return code: 0 04:28:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:28:00 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')]} 04:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:00 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')]} 04:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:00 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 0x10c64cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c64f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea204ab060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42ad30>, '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 0x10c350db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c41fbe8>, '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.USJrvEcwEE/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.mK3bdY4Ttc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:28:00 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 04:28:00 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 04:28:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.USJrvEcwEE/Render', 04:28:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:00 INFO - 'HOME': '/Users/cltbld', 04:28:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:00 INFO - 'LOGNAME': 'cltbld', 04:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:00 INFO - 'MOZ_NO_REMOTE': '1', 04:28:00 INFO - 'NO_EM_RESTART': '1', 04:28:00 INFO - 'PAGER': '/bin/cat', 04:28:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:00 INFO - 'PWD': '/builds/slave/test', 04:28:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:00 INFO - 'SHELL': '/bin/bash', 04:28:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mK3bdY4Ttc/Listeners', 04:28:00 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:28:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:00 INFO - 'USER': 'cltbld', 04:28:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:00 INFO - 'XPC_FLAGS': '0x0', 04:28:00 INFO - 'XPC_SERVICE_NAME': '0', 04:28:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:28:00 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-nYFKo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:28:00 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)) 04:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:28:00 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-gHizR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:28:00 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)) 04:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:28:00 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-9kmzXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:28:00 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)) 04:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:28:00 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-nJOoBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 04:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:28:00 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-LOHEXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:28:00 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)) 04:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:28:00 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-rKley8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:28:01 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)) 04:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:28:01 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-V3YNq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:28:01 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)) 04:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:28:01 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-dapjRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:28:01 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)) 04:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:28:01 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-oTcEg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:28:01 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)) 04:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:28:01 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-FHUCvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:28:01 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)) 04:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:28:01 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-1ah3NJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:28:01 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)) 04:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:28:01 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ES7B1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 04:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:28:01 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-2hSnOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:28:01 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)) 04:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:28:01 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-7YZ3fW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:28:01 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)) 04:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:28:01 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Rksfgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:28:01 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)) 04:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:28:01 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-wWOWiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:28:02 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)) 04:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:28:02 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-uTyf1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:28:02 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)) 04:28:02 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)) 04:28:02 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)) 04:28:02 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)) 04:28:02 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:28:02 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:28:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:28:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:04 INFO - Downloading blessings-1.6.tar.gz 04:28:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:28:04 INFO - Installing collected packages: blessings 04:28:04 INFO - Running setup.py install for blessings 04:28:05 INFO - Successfully installed blessings 04:28:05 INFO - Cleaning up... 04:28:05 INFO - Return code: 0 04:28:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:28:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:28:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:28:05 INFO - Reading from file tmpfile_stdout 04:28:05 INFO - Current package versions: 04:28:05 INFO - blessings == 1.6 04:28:05 INFO - blobuploader == 1.2.4 04:28:05 INFO - docopt == 0.6.1 04:28:05 INFO - functools32 == 3.2.3-2 04:28:05 INFO - jsonschema == 2.5.1 04:28:05 INFO - manifestparser == 1.1 04:28:05 INFO - mozInstall == 1.12 04:28:05 INFO - mozcrash == 1.0 04:28:05 INFO - mozdebug == 0.1 04:28:05 INFO - mozdevice == 0.50 04:28:05 INFO - mozfile == 1.2 04:28:05 INFO - mozhttpd == 0.7 04:28:05 INFO - mozinfo == 0.9 04:28:05 INFO - mozleak == 0.1 04:28:05 INFO - mozlog == 3.4 04:28:05 INFO - moznetwork == 0.27 04:28:05 INFO - mozprocess == 0.25 04:28:05 INFO - mozprofile == 0.28 04:28:05 INFO - mozrunner == 6.13 04:28:05 INFO - mozscreenshot == 0.1 04:28:05 INFO - mozsystemmonitor == 0.3 04:28:05 INFO - moztest == 0.8 04:28:05 INFO - mozversion == 1.4 04:28:05 INFO - psutil == 3.1.1 04:28:05 INFO - requests == 1.2.3 04:28:05 INFO - wsgiref == 0.1.2 04:28:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:05 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 0x10c64cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c64f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea204ab060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42ad30>, '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 0x10c350db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c41fbe8>, '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.USJrvEcwEE/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.mK3bdY4Ttc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:28:05 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 04:28:05 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 04:28:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.USJrvEcwEE/Render', 04:28:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:05 INFO - 'HOME': '/Users/cltbld', 04:28:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:05 INFO - 'LOGNAME': 'cltbld', 04:28:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:05 INFO - 'MOZ_NO_REMOTE': '1', 04:28:05 INFO - 'NO_EM_RESTART': '1', 04:28:05 INFO - 'PAGER': '/bin/cat', 04:28:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:05 INFO - 'PWD': '/builds/slave/test', 04:28:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:05 INFO - 'SHELL': '/bin/bash', 04:28:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mK3bdY4Ttc/Listeners', 04:28:05 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:28:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:05 INFO - 'USER': 'cltbld', 04:28:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:05 INFO - 'XPC_FLAGS': '0x0', 04:28:05 INFO - 'XPC_SERVICE_NAME': '0', 04:28:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:05 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)) 04:28:05 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)) 04:28:05 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)) 04:28:05 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)) 04:28:05 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)) 04:28:05 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)) 04:28:05 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)) 04:28:05 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)) 04:28:05 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)) 04:28:05 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)) 04:28:05 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)) 04:28:05 INFO - Cleaning up... 04:28:05 INFO - Return code: 0 04:28:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:28:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c64cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c64f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea204ab060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42ad30>, '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 0x10c350db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c41fbe8>, '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.USJrvEcwEE/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.mK3bdY4Ttc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:28:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:28:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:28:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.USJrvEcwEE/Render', 04:28:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:05 INFO - 'HOME': '/Users/cltbld', 04:28:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:05 INFO - 'LOGNAME': 'cltbld', 04:28:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:05 INFO - 'MOZ_NO_REMOTE': '1', 04:28:05 INFO - 'NO_EM_RESTART': '1', 04:28:05 INFO - 'PAGER': '/bin/cat', 04:28:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:05 INFO - 'PWD': '/builds/slave/test', 04:28:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:05 INFO - 'SHELL': '/bin/bash', 04:28:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mK3bdY4Ttc/Listeners', 04:28:05 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:28:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:05 INFO - 'USER': 'cltbld', 04:28:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:05 INFO - 'XPC_FLAGS': '0x0', 04:28:05 INFO - 'XPC_SERVICE_NAME': '0', 04:28:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:28:05 INFO - Cleaning up... 04:28:05 INFO - Return code: 0 04:28:05 INFO - Running post-action listener: _resource_record_post_action 04:28:05 INFO - Running post-action listener: _start_resource_monitoring 04:28:06 INFO - Starting resource monitoring. 04:28:06 INFO - [mozharness: 2017-04-22 11:28:06.023525Z] Finished create-virtualenv step (success) 04:28:06 INFO - [mozharness: 2017-04-22 11:28:06.024500Z] Running install step. 04:28:06 INFO - Running pre-action listener: _resource_record_pre_action 04:28:06 INFO - Running main action method: install 04:28:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:28:06 INFO - Reading from file tmpfile_stdout 04:28:06 INFO - Detecting whether we're running mozinstall >=1.0... 04:28:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:28:06 INFO - Reading from file tmpfile_stdout 04:28:06 INFO - Output received: 04:28:06 INFO - Usage: mozinstall [options] installer 04:28:06 INFO - Options: 04:28:06 INFO - -h, --help show this help message and exit 04:28:06 INFO - -d DEST, --destination=DEST 04:28:06 INFO - Directory to install application into. [default: 04:28:06 INFO - "/builds/slave/test"] 04:28:06 INFO - --app=APP Application being installed. [default: firefox] 04:28:06 INFO - mkdir: /builds/slave/test/build/application 04:28:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:28:26 INFO - Reading from file tmpfile_stdout 04:28:26 INFO - Output received: 04:28:26 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:28:26 INFO - Running post-action listener: _resource_record_post_action 04:28:26 INFO - [mozharness: 2017-04-22 11:28:26.232308Z] Finished install step (success) 04:28:26 INFO - [mozharness: 2017-04-22 11:28:26.232451Z] Running run-tests step. 04:28:26 INFO - Running pre-action listener: _resource_record_pre_action 04:28:26 INFO - Running main action method: run_tests 04:28:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:28:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:28:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:28:26 INFO - 2017-04-22 04:28:26.247 screenresolution[1932:13330] starting screenresolution argv=screenresolution get 04:28:26 INFO - 2017-04-22 04:28:26.249 screenresolution[1932:13330] Display 0: 1600x1200x32@60 04:28:26 INFO - 2017-04-22 04:28:26.255 screenresolution[1933:13333] starting screenresolution argv=screenresolution list 04:28:26 INFO - Available Modes on Display 0 04:28:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:28:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:28:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:28:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:28:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:28:26 INFO - Intel Iris: 04:28:26 INFO - Chipset Model: Intel Iris 04:28:26 INFO - Type: GPU 04:28:26 INFO - Bus: Built-In 04:28:26 INFO - VRAM (Dynamic, Max): 1536 MB 04:28:26 INFO - Vendor: Intel (0x8086) 04:28:26 INFO - Device ID: 0x0a2e 04:28:26 INFO - Revision ID: 0x0009 04:28:26 INFO - Displays: 04:28:26 INFO - Display: 04:28:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:28:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:28:26 INFO - Display Serial Number: 1600x1200 60 04:28:26 INFO - Main Display: Yes 04:28:26 INFO - Mirror: Off 04:28:26 INFO - Online: Yes 04:28:26 INFO - Rotation: Supported 04:28:26 INFO - Return code: 0 04:28:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:28:26 INFO - Python 2.7.3 04:28:26 INFO - Return code: 0 04:28:26 INFO - grabbing minidump binary from tooltool 04:28:26 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')]} 04:28:26 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 0x10c42ad30>, '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 0x10c350db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c41fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:28:26 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 04:28:26 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 04:28:26 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 04:28:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:28:26 INFO - Return code: 0 04:28:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:28:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:28:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:28:26 INFO - ENV: RUST_BACKTRACE is now 1 04:28:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:28:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:28:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--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 04:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0117 --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 04:28:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.USJrvEcwEE/Render', 04:28:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:26 INFO - 'HOME': '/Users/cltbld', 04:28:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:26 INFO - 'LOGNAME': 'cltbld', 04:28:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:28:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:26 INFO - 'MOZ_NO_REMOTE': '1', 04:28:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:28:26 INFO - 'NO_EM_RESTART': '1', 04:28:26 INFO - 'PAGER': '/bin/cat', 04:28:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:26 INFO - 'PWD': '/builds/slave/test', 04:28:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:28:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:26 INFO - 'RUST_BACKTRACE': '1', 04:28:26 INFO - 'SHELL': '/bin/bash', 04:28:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mK3bdY4Ttc/Listeners', 04:28:26 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:28:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:26 INFO - 'USER': 'cltbld', 04:28:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:26 INFO - 'XPC_FLAGS': '0x0', 04:28:26 INFO - 'XPC_SERVICE_NAME': '0', 04:28:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--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 04:28:26 INFO - mozversion application_buildid: 20170422030205 04:28:26 INFO - mozversion application_changeset: 7d85b081bfabe91dbf18a421f1eee2a32f335823 04:28:26 INFO - mozversion application_display_name: Nightly 04:28:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:28:26 INFO - mozversion application_name: Firefox 04:28:26 INFO - mozversion application_remotingname: firefox 04:28:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:28:26 INFO - mozversion application_vendor: Mozilla 04:28:26 INFO - mozversion application_version: 55.0a1 04:28:26 INFO - mozversion platform_buildid: 20170422030205 04:28:26 INFO - mozversion platform_changeset: 7d85b081bfabe91dbf18a421f1eee2a32f335823 04:28:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:28:26 INFO - mozversion platform_version: 55.0a1 04:28:26 INFO - using testdate: 1492860506 04:28:26 INFO - actual date: 1492860506 04:28:26 INFO - starting webserver on 'localhost:49727' 04:28:26 INFO - SUITE-START | Running 2 tests 04:28:26 INFO - TEST-START | dromaeo_css 04:28:26 INFO - operating with platform_type : mac_ 04:28:26 INFO - Initialising browser for dromaeo_css test... 04:28:26 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpqzzZD8/profile http://localhost:49727/getInfo.html) 04:28:29 INFO - PID 1943 | 2017-04-22 04:28:29.966 plugin-container[1946:13516] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 04:28:29 INFO - PID 1943 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:28:29 INFO - PID 1943 | 2017-04-22 04:28:29.967 plugin-container[1946:13516] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d23, name = 'com.apple.CFPasteboardClient' 04:28:29 INFO - PID 1943 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:28:29 INFO - PID 1943 | 2017-04-22 04:28:29.967 plugin-container[1946:13516] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:28:30 INFO - PID 1943 | __metrics Screen width/height:1600/1200 04:28:30 INFO - PID 1943 | colorDepth:24 04:28:30 INFO - PID 1943 | Browser inner width/height: 1024/689 04:28:30 INFO - PID 1943 | __metrics 04:28:30 ERROR - PID 1943 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3403: TypeError: this.tabs is undefined 04:28:31 INFO - TEST-INFO | 1943: exit 0 04:28:31 INFO - Browser initialized. 04:28:31 INFO - Running cycle 1/1 for dromaeo_css test... 04:28:31 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpqzzZD8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:28:33 INFO - PID 1947 | 2017-04-22 04:28:33.471 plugin-container[1948:13650] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 04:28:33 INFO - PID 1947 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:28:33 INFO - PID 1947 | 2017-04-22 04:28:33.472 plugin-container[1948:13650] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9417, name = 'com.apple.CFPasteboardClient' 04:28:33 INFO - PID 1947 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:28:33 INFO - PID 1947 | 2017-04-22 04:28:33.472 plugin-container[1948:13650] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:28:43 INFO - PID 1947 | RSS: Main: 236929024 04:28:43 INFO - PID 1947 | 04:31:47 INFO - PID 1947 | Cycle 1(1): loaded http://localhost:49727/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49727/tests/dromaeo/cssquery-ext.html) 04:31:48 INFO - PID 1947 | RSS: Main: 252002304 04:31:48 INFO - PID 1947 | 04:33:31 INFO - PID 1947 | ************************* 04:33:31 INFO - PID 1947 | A coding exception was thrown and uncaught in a Task. 04:33:31 INFO - PID 1947 | 04:33:31 ERROR - PID 1947 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:33:31 INFO - PID 1947 | Full stack: 04:33:31 INFO - PID 1947 | ************************* 04:33:31 INFO - PID 1947 | ************************* 04:33:31 INFO - PID 1947 | A coding exception was thrown and uncaught in a Task. 04:33:31 INFO - PID 1947 | 04:33:31 ERROR - PID 1947 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:33:31 INFO - PID 1947 | Full stack: 04:33:31 INFO - PID 1947 | ************************* 04:33:31 INFO - PID 1947 | ************************* 04:33:31 INFO - PID 1947 | A coding exception was thrown and uncaught in a Task. 04:33:31 INFO - PID 1947 | 04:33:31 ERROR - PID 1947 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:33:31 INFO - PID 1947 | Full stack: 04:33:31 INFO - PID 1947 | ************************* 04:33:31 INFO - PID 1947 | ************************* 04:33:31 INFO - PID 1947 | A coding exception was thrown and uncaught in a Task. 04:33:31 INFO - PID 1947 | 04:33:31 ERROR - PID 1947 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:33:31 INFO - PID 1947 | Full stack: 04:33:31 INFO - PID 1947 | ************************* 04:34:50 INFO - PID 1947 | Cycle 1(1): loaded http://localhost:49727/tests/dromaeo/cssquery-ext.html (next: http://localhost:49727/tests/dromaeo/cssquery-jquery.html) 04:34:51 INFO - PID 1947 | RSS: Main: 258551808 04:34:51 INFO - PID 1947 | 04:37:52 INFO - PID 1947 | Cycle 1(1): loaded http://localhost:49727/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49727/tests/dromaeo/cssquery-mootools.html) 04:37:53 INFO - PID 1947 | RSS: Main: 267481088 04:37:53 INFO - PID 1947 | 04:40:55 INFO - PID 1947 | Cycle 1(1): loaded http://localhost:49727/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49727/tests/dromaeo/cssquery-prototype.html) 04:40:56 INFO - PID 1947 | RSS: Main: 259960832 04:40:56 INFO - PID 1947 | 04:43:58 INFO - PID 1947 | Cycle 1(1): loaded http://localhost:49727/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49727/tests/dromaeo/cssquery-yui.html) 04:43:59 INFO - PID 1947 | RSS: Main: 270151680 04:43:59 INFO - PID 1947 | 04:47:01 INFO - PID 1947 | Cycle 1(1): loaded http://localhost:49727/tests/dromaeo/cssquery-yui.html (next: http://localhost:49727/tests/dromaeo/cssquery-dojo.html) 04:47:01 INFO - PID 1947 | RSS: Main: 267948032 04:47:01 INFO - PID 1947 | 04:47:01 INFO - PID 1947 | __start_tp_report 04:47:01 INFO - PID 1947 | _x_x_mozilla_page_load 04:47:01 INFO - PID 1947 | _x_x_mozilla_page_load_details 04:47:01 INFO - PID 1947 | |i|pagename|runs| 04:47:01 INFO - PID 1947 | |0;dojo.html;3517.3844577198993;3576.3383774001813;3577.052081198481;3617.656322649349;3624.9456258156133;6010.729517171727;6176.073588961653;6183.165272688186;6219.942609756341;6262.874742505147;6102.2468898001325;6107.279845777768;6119.685352982181;6132.141500189977;6228.595141315811;5384.219288203209;5403;5415.83752487425;5434.510894019538;5512.862178445551;5382.515573598375;5466.207399927008;5521.834344969638;5533.197686335479;5543.861403464905;4721.716696998191;5470.234167216593;5486.451354864522;5515.779368825242;5533.418991005947;5256.763445644955;5330.733463326818;5340.198970154469;5345.7594408251725;5390.946090539083;5353.7590808413715;5372.0330340538685;5429.53848922842;5443.374011988592;5458.344998600153;5208.427073021964;5441.673499590037;6242.875142497124;6244.219472565929;6258.374162583706;6401.615903045786;6474.643894585796;6574.868502629968;6661.467082633378;6668.433183179435;6740.966295168493;6752.324767523258;6764.391204791543;6784.253732089466;6784.76253331131;6328.936710632881;6340.144080549113;6342.651154186472;6356.618602883842;6357.364263573606;6563.081168636395;6640.601563906181;6825.965870170618;6871.312868713088;6879.6560171991205;6301.464375528131;6441.838954026093;6451.225852897588;6537.901931471032;6540.084388185658;4929.334539837076;5458.9454105458435;6512.902306465407;6757.256701762803;6770.1537307836525;6511.739530418825;6613.834654133685;6640.302768209365;6641.169853768279;6649.667516624149;5544.362398324244;6402.9359706403275;6479.092926990225;6662.700178491979;6676.399124078856;4167.958320416757;5221.921671174935;5234.0840352938085;5309.309789727311;5353.0899747043095;4542.863714088583;4703;4710.62315014798;4713.198756211452;4718.457377401643;6117.847053823601;6330.177076979963;6334.1765570475545;6335.271443783931;6343.841403964938;6337.524685648595;6460.741570337228;6655.267920528738;6754.527183097234;6774.187097548227;6294.118823364733;6317.6209427434505;6332.366763323631;6339.809805705836;6357.8728425432155;5803.506701930299;5872.677002764889;5879.2944846618675;5898.734556944948;5920;6604.8348791280605;6735.6295403753265;6835.58986460814;6895.069165662772;6932.514723969374;6140;6779.254282029072;6781.830454238683;6798.082258894986;6807.5575087619145;6452.645104519296;6818.386345228953;6925.549839260432;6986.301369863177;6991.895121573282;6377.649229292433;6479.773207937699;6542.44389226902;6594.70323835419;6630.900536492053;5482.095454250004;6586.308437614173;6703.229128650072;6779.559328643622;6790.117284752951;6543.378379053982;6575.2767195607485;6577.309382514767;6578.506612004023;6592.538522303585;6247.281562620356;6396.072569477493;6481.125048118443;6535.738570457319;6571.5399922005;5515.862103447446;6352.809415717536;6425.261094973951;6477.190351206099;6481.4166724994975;5461.590380721383;6557;6607.636579987954;6617.735290588323;6621.370969757865;6220.34686357944;6236.937630623635;6345.619262844244;6374.107624932421;6437.935620643733 04:47:01 INFO - PID 1947 | |1;ext.html;592.5230189697292;796.5579103597503;818.2553875972864;1265.4621785741058;1285.8071289306608;38057.80971095144;38664.99999999996;38716.612833871724;38773.224535509275;40465.59534404655;38501.61498385015;38841.80579097108;38883;38921.41617875734;38927.61072389275;7929.326007289387;8071.596420178997;8404.873926891101;8603.44077634955;8621.180987806149;6952.5480843745245;7502.774916752506;8693.913060869405;8735.868961965543;8757.168069033463;8701;8722.607482663296;8763.255123314493;8766.211041006309;8773.95613021934;8688.304935605169;8723.345749068845;8732.257758090507;8746.125387461267;8755.343349248831;7181.210066892638;8195.918040819575;8353.415260931737;8401.41190116692;8406.243438090602;36429.81785091084;36733.265334693424;36771.632283677085;36781;36882.63117368819;3193.8243396613175;3617.276544691072;3623.6376362363553;3663.816809159531;3678.264347130558;30834.07497775048;30924.536131958037;31075.844620776978;31086.37827243465;31091.37817243665;3166.9208269793207;3188.792728472642;3202.551642770014;3204.198950262434;3211.2293049668147;300557;302443;303430;308220;309677.4516127405;47124.764376177896;47416.05167896692;47491;47583;47633.76183119062;49940.0011999758;49962.250566241535;54500.45499544954;55367.72316138474;55442;14177.716445671234;14324.427022919175;14328.355224014944;14328.928355358365;14330.140191588538;11339.829902551468;11403.657890263305;11419;11432;11450.312981221154;14059.71880562383;14092.788608170886;14097.85902140986;14224;14256.572302830724;6124.6325220486915;6152;6196.659183744938;6226.782062627786;6229.159063526366;1019.8929112443235;1025.123519390925;1048.3500229857384;1075.6235317638764;1076.8546246256656;17593.208305626278;20577.38267851967;21012.684809727867;24808.751912481006;24824;1056.9735756606146;1140.823172408278;1172.9941350293193;1175.1715040896147;1175.2419689300352;12429.316387598588;12726.745465090646;16370.263338149574;16375.181240937905;16501.318473555944;325825;328165.35917320737;328222;328698;329789;55450.44549554533;55718.88562228733;55725.721371393694;55803.60490987677;55838.72080639652;1475.0707054555562;1507.2388443835794;1511.508759653117;1517.1049081042238;1533.4939469975104;25152.62271065899;29152.27119322034;29498.410031798816;30092.946746863756;30155.436787873277;37402;37428.62571374251;37673.05817354588;37683.62316376911;37700.43449348207;294309;294544;295005;295360.52319739125;295398.0752405949;33724.27860099637;33824;33971.4904276441;34028.149296267795;34339.14152146217;42903.57096428996;42929.92675183145;42978;42987;43092.13815723731;28349.433011339246;29089.272768180967;30249.395012099194;30361.544576830922;30440.39119217559;29391.118266452042;29783.404331913673;29985.400291994476;30246.530971328062;30284.84857575698;29308;35663.82168089143;35792.82103589465;36248.45627315645;36281;23318.766812331658;30170.396592068475;30333;30348.393032138792;30368.088957331314;19545.775593645598;23482.06071757179;23912.641310380706;24373.369059878565;24660.1776724608 04:47:01 INFO - PID 1947 | |2;jquery.html;369.1270146104464;428.9227938970986;440.6474820143884;445.8417261872036;458.15699113630933;19101.99999999998;20446.591068178626;21157.788422115773;21449.142034318607;21551.56896862064;18231.45305640833;21644;21764.238251661194;22287.888560557174;22647.660285095735;6001.429864162908;6224.62652240867;6250.562460627758;6259.937400625981;6263.592866463665;5966.850828729295;6071.271447426293;6080.753445543675;6163.198784158053;6168.938310616903;6074.878502429949;6096.39036096389;6121.387861213865;6129.264488261415;6148.969255153718;6006.8798624027495;6110.327863934964;6165.2601687797305;6171.413715697002;6174.84562885927;4525.570070843265;5415.431379705153;5430.13117901134;5487.862803429906;5496.477834605706;26359.73640263592;26700.198994030212;26778.196654100408;27060.86469567659;27161.45677086447;2193.1775584155944;2246.7079279693535;2266.2974477911735;2266.77332266772;2268.1834539565743;17673.469795906145;17736.91131544347;17966.461006169837;18082.909585451987;18246.0876956153;2144.120910426733;2183.7816218378193;2205.691203231549;2205.7353117625826;2209.3924170853143;131890.0432987012;143796.99999999895;151092;152482;153651.23174384056;12348.320842353756;12917.160384574972;12927.353632318534;12937.54718584845;13000;16732.330706771594;16808.411705590246;16812.991220526565;16813;16814.495565133064;8789.824203515895;8796.604152813139;8997.190252877272;9008.189262966365;9105.180533751995;6079.544034197364;8258.628361723737;8389.580520973926;8474.74575762728;8527.40308178434;6968.616726080013;7821.6089195541135;7957.652492514722;8056.308175283139;8176.713815016446;2033.6441122803747;2072.1089931329675;2158.827293816491;2305.4005958450925;2497.038640123529;900.8738776571286;939.6570251858022;982.2240430060173;988.4909271725068;1020.9693709188737;21001.264955726478;21241.468963275733;21489.78510214909;21549.89225053896;21651.458713532287;1027.1679939249232;1031.0511935863424;1032.746976990642;1045.10643399892;1056.4823236614004;10932.672019839418;11026.503807328689;11111.38887361203;11150.665480035612;11167.218294719292;441032;535705;539697;541748;548644;115290;122346;123289;128273;130951.03573446407;1078.946052697362;1231.2550906701317;1245.1844042152404;1251.9061070419755;1260.0423678004781;16868.409605663743;21016.789832101484;22091.895405229996;22189.667154992367;22192.778072219073;41665.58334416738;53241.73379133079;53418.73290633522;53795;54297.45702543082;57479.85040299087;58544.70727646335;73237.6338118306;74604;75275.62362188155;8383.790405239917;8495.660173593235;8945.105489451264;9048.864267036131;9060.365774395992;13765.311734413439;14025.752688970775;14036.228007459687;14256.501022464165;14288.285585720465;8957.731268061969;10700.785984280115;10824.079953204066;11893.524259029542;11995.880041199476;9602.615895364108;10533.894661053599;11171.329720216812;11198.944005280247;11405.600803971822;11549.42252887369;12053.57812476559;12057;12124.84813942674;12146.149769516049;8715.869261961203;8763.824723505366;8903.3322500814;8998;9048.547572621212;11626.70933226676;11940.343281119622;12013.81979270329;12067.81898271509;12190.146689731633 04:47:01 INFO - PID 1947 | |3;mootools.html;1300.6098170548837;1394.6164804678713;1404.052264721313;1416.4475854416785;1419.8935079869009;11438.771224575503;11902.285862848214;12062.81905771413;12087.879121208796;12251.75496490072;11705.180637355388;11850.407479626007;11894.810518948101;11938.985186259179;11947.46236419361;2093.8010888965564;2143.6677315016195;2577.1108967406217;2584.93537661559;2591.1578736910487;2503.7120731115974;2555.1951135392383;2558.296468471167;2563.1797824696105;2565.3971316740644;2506.135383292761;2529.734377890323;2569.498947705198;2571.010161087985;2573.395252115751;2494.8128890333205;2523.987380063088;2555.220657699401;2556;2560.167945417728;2454.374134595667;2523.9116630918015;2541.453587478701;2568.8844002019955;2572.6398304237423;11808.173427860052;11854.762904741943;11970.281783093042;11983.820242696367;12050.09624278174;6606.405423511907;6834.077399551046;6849.1438570178725;6863.313668633146;6885.793426197222;4134.297169481196;4430.091831174634;4514.9774251128865;4515.277555591122;4519.819207231707;1208.3897631696025;1242.372601836067;1244.7946088895412;1247.1270110922392;1311.6065180445828;15101.395944162221;15548.92225538865;16734;16736.6652666946;16893.83106168935;5628;5724.742386592613;5894;5950.375210602911;5952.464278214981;12326.568570100004;12360.505579776709;12465.688357790947;12491.250524968349;12498.3125928073;6657.800265991931;6665.800025999228;6677.899831502531;6681.164854393201;6685.799426017227;6046.2139921809885;6106;6235.688215589203;6248.0003199487865;6283.8114856554375;3717.925641487155;3784.0642371834465;4349.521162804662;4498.3927169832305;4543.0232500012;1247.8689737577606;1248.082659245454;1261.4764872577962;1265.544404014554;1266.6959929616826;761.9885701714479;773.8529679360903;789.3290702902486;789.3290702902486;793.3890904003885;6784.864302714017;7024.192217894905;7042.190148133031;7104.538205016657;7162.283771622796;818.6397984886631;902.120432578244;927.3369540778241;928.9581968811419;945.7138291922979;3670.8164591770305;4496.482904465963;4592.471865735416;4737.478877323491;4799.712017278975;15033.323500442506;15050.774238386433;15118;15262.542123736306;15316.846831531542;14740.410383584536;15113.09321440717;15280.46518371874;15298.235088245543;15299.235038248044;6732.798016059526;7289.307515785886;8227.341812654973;8405.243528082501;8443.662253509914;6349.904751428639;7300;7308.30571095745;7335.706571737071;7359.337659610661;11986.400679966142;12446.813297800722;13255.933720331337;13498.73002539924;13663.521776737767;13700.177989320673;13991;14033;14034;14050.648733781738;9447.433154010781;9771.12059914611;9785.07041831025;9812.901870981199;9816.65641702537;10675.57297708083;10704.197185210984;10773.784524309627;10781.784364312512;10959.945200274267;9051.456912585267;9125.634974601207;9218.769530761785;9264.39781414206;9288.071192880658;8377.83244335122;8524.318054555497;8530.957345213234;8916.33127515426;9286.860697089414;8029.759107226792;8180.141085186207;8196.836063278583;8197.877031844408;8199.22107399796;5074.6194035448225;6067.757289708362;6188.535859810622;6251.437370636664;6268.905966410416;6154.3845615438095;6286.508209211691;6360.045993101072;6401.039844023434;6423.42189202974 04:47:01 INFO - PID 1947 | |4;prototype.html;294.93087557603684;356.2892030399353;396.3104198694273;414.9087200815819;415.87523742877147;5253.527182553569;5456.63584103974;5651.3218413790355;6054.697265136748;6066.117684681807;4797.664163508556;5762.769489220436;5853.50245229156;5903.144044113601;5909.349971503142;4438.400815889862;4505.6170225530705;4513.7517436540975;4518.887027824298;4543.591076803087;3882.611738826109;4190.078182799779;4546.65900057497;4550.431196100488;4555.2256116460285;4073.9185216295655;4410.360497727827;4509.368688383629;4516.503184649686;4526.275795872661;4451.354553589727;4470.463544374696;4480.48474425442;4487.080148569536;4492.595666390041;3974.5230572331216;3995.720299579031;4001.679865610744;4013.3578627419765;4031.21391328691;5551.555875529948;5767.5962682612235;5799.565032622528;5822.1266809978415;5958.165856780054;5390.51485366319;5392.272043274147;5422.53908417785;5431.4840090191365;5443.537299329561;4792.688475249098;5248.9737551312;5981.461668449861;6400.967995160041;6425.678716064178;4406.471223453174;4598.287265473857;4614.2617181250835;4615.746133962631;4617.422822147231;5696.458836410535;6422.839429014265;6453.645049522274;6651.401373876326;6727.39453449187;4326.329418940069;4346.65226781863;4348.086901750605;4355.5208927018;4358.084802191512;4408.448943882015;4422.624076953431;4436.911261774746;4442.8667139985855;4449.087936972913;4943.752812359367;5165.498743837801;5166.509181627739;5188.325517682677;5206.661566998133;4860.441049279308;6148.544028769774;6407.903881441782;6409.102725618417;6409.596401349494;1505.1495904813746;1518.506485392252;1524.3826250368618;1525.9008164469274;1530.4949366708959;949.8622699708503;1000.4847503535685;1008.3748076192661;1011.4588695048114;1022.2077889635592;5026.798928042837;5262.868428289324;5280.498352656491;5613.522850557667;6068.817935461943;5297.622618119317;5859.589828711949;6075.392460753889;6606.86786264272;6701.229358623821;5692.6299790513485;5983.132445795335;6016.217891674054;6020.668863212565;6042.425969532887;3043.193553708269;3064.3564851381016;3093.6751641077813;3464.757466977338;3470.548828652259;5653.519450846724;5802.245708057925;6023.126646636213;6061.393860613992;6927.618980956096;5408.3509978802795;5946.108083787466;6346.143270658402;6438.678066096676;6488.221413430419;6217.285012223561;6824.863502730017;6835.350641689031;6838.5213035089055;6878.140232470942;6258.249010118815;6548.410643042149;6603.6745931415;6637.236717777518;6659.4006539411685;5402.595325215394;5458.863528411663;6564.770233041821;6576.638284894186;6578.572392794453;5893.499052580579;6625.569337993208;6813.523053386215;6874;6890.586564806128;3692.3353796315846;4145.97927010363;4374.825006999685;4377.102693947797;4399.384086227867;6486.286508484157;6640.169978752656;6642.5018123641885;6667;6687.230968438692;6419.977707022273;6420.935790642221;6437.195350581177;6441.227052753512;6453.612783233021;5266.55234305073;5787.472963320103;6173.69131543412;6428;6429.710663020269;5341.359036915595;5499.587530935116;6187.876242475035;6209.565330426827;6311.897266776632;5291.3121294231505;6445.1943507061615;6446.5165112615805;6454.644994525346;6462.3537646235;5451.918221226605;5924.229850119457;6193.473554747897;6236.781712640036;6267.871783079089 04:47:01 INFO - PID 1947 | |5;yui.html;834.57019634888;839.4375768235282;841.8484672758901;866.0949307973171;878.5475480127732;14251.92874035631;14795.482158124469;16339.183040847955;16359.182040897953;16755.497335079937;15121.84878151218;16556.337746490157;16819.91590042051;16879.40922067728;17185.914070429662;968.1625394034163;985.6698006167942;985.9654912078079;988.1205726903062;990.0544979544553;984.2814745235981;984.3847595252967;984.8178087053882;985.2019863910256;987.151050096916;1019.9745006374826;1030.8453731940194;1033.9327943683675;1034.2243317511866;1035.0063938618935;1004.5379125602232;1021.6934919524113;1025.3027941000155;1025.3847691385183;1028.2596530498067;629.3957800511514;750.9098908131059;760.4714723267332;761.029687148884;761.208343322949;15047.322870470745;15242.39030438781;15939.760903586455;16408.83591164085;16623.83376166235;16078.83921160785;16187.83812161875;16241.51275461738;16258.430954916521;16416.425425110065;13319.467221311135;13624;13952.2326272055;13962.930185349009;13981.02132850701;3354.37943980362;3391.3556424279554;3424.366492198951;3424.6404127566743;3431.2108215110416;116717.83282167155;118000;118816;119852.61768587738;121334;11654.592089277005;11711.707207319714;11751.412429378497;11839.230450020721;11858.110641701907;19522;19713.01434928248;19764.90117549403;19864.404067877986;19880;7315.817104572322;7548.66031028605;7570.583617900958;7575.204603516661;7576.24237576249;3529.6999755020597;3589.2821435712963;3591.730620203495;3597.0647631615966;3601.4597810328137;4293.742375457483;4316.028893498938;4374.562543745664;4376.606105450461;4384.802683879233;869.8042940338499;915.949622770754;932.6549176804625;958.9136977672043;960.4573416019927;546.469924173551;641.9454346380517;648.6302807399832;654.0973456629821;678.9883074219377;11092.722681933015;11911.52353905834;11927.105467090003;11934.641960741192;11976.52093916251;636.8758092171983;645.3707635055877;649.909012738217;653.8169312592481;660.0824108913613;5322.973385133049;5434.130539113802;5442.945570544323;5455.263539422207;5529.419410961791;102748.48625756823;104479.95520044854;108044;108833;109027.45486272518;91942;92589.90755508511;92672;93021;94028;84273.57863210645;86185.56907215549;86199;86213.56893215494;87584.12415875887;12381.814272785736;12958.805617915927;12966.80549791735;12977.870221297666;13019.934900325437;37369.25261494809;40504.59495405008;41152;42323.57676423196;43379.78310108555;66575.00137497844;68837.65581172062;69071;74571;75467;15584.142872141687;15748.60628484297;15819.129947852978;15830.44593439224;16529.421470248482;17456.389026384015;17923.37268195607;17964;17971.640567188846;18660.160292787128;16344.019358838508;16507.504774856774;16680;16797.41209057677;16888.915555422634;16601;16799.244034018255;16841.048377877338;16861.578460538585;16992.830071699125;16135.75796363032;16235.107069111313;16402.179891005162;16433.91783041125;16610.169491525616;11488.885111148782;11498.597549085742;11835.644930652094;12050.517979281081;12067.939660301643;10580.412938059353;11711.590094346657;12268.631941041784;12902.80645790295;13672.65818354549 04:47:01 INFO - PID 1947 | __end_tp_report 04:47:01 INFO - PID 1947 | __start_cc_report 04:47:01 INFO - PID 1947 | _x_x_mozilla_cycle_collect,418 04:47:01 INFO - PID 1947 | __end_cc_report 04:47:01 INFO - PID 1947 | __startTimestamp1492861621404__endTimestamp 04:47:01 INFO - PID 1947 | 04:47:01 INFO - PID 1947 | ------- Summary: start ------- 04:47:01 INFO - PID 1947 | Number of tests: 6 04:47:01 INFO - PID 1947 | 04:47:01 INFO - PID 1947 | [#0] dojo.html Cycles:165 Average:6084.57 Median:6342.65 stddev:744.41 (11.7%) stddev-sans-first:718.94 04:47:01 INFO - PID 1947 | Values: 3517.4 3576.3 3577.1 3617.7 3624.9 6010.7 6176.1 6183.2 6219.9 6262.9 6102.2 6107.3 6119.7 6132.1 6228.6 5384.2 5403.0 5415.8 5434.5 5512.9 5382.5 5466.2 5521.8 5533.2 5543.9 4721.7 5470.2 5486.5 5515.8 5533.4 5256.8 5330.7 5340.2 5345.8 5390.9 5353.8 5372.0 5429.5 5443.4 5458.3 5208.4 5441.7 6242.9 6244.2 6258.4 6401.6 6474.6 6574.9 6661.5 6668.4 6741.0 6752.3 6764.4 6784.3 6784.8 6328.9 6340.1 6342.7 6356.6 6357.4 6563.1 6640.6 6826.0 6871.3 6879.7 6301.5 6441.8 6451.2 6537.9 6540.1 4929.3 5458.9 6512.9 6757.3 6770.2 6511.7 6613.8 6640.3 6641.2 6649.7 5544.4 6402.9 6479.1 6662.7 6676.4 4168.0 5221.9 5234.1 5309.3 5353.1 4542.9 4703.0 4710.6 4713.2 4718.5 6117.8 6330.2 6334.2 6335.3 6343.8 6337.5 6460.7 6655.3 6754.5 6774.2 6294.1 6317.6 6332.4 6339.8 6357.9 5803.5 5872.7 5879.3 5898.7 5920.0 6604.8 6735.6 6835.6 6895.1 6932.5 6140.0 6779.3 6781.8 6798.1 6807.6 6452.6 6818.4 6925.5 6986.3 6991.9 6377.6 6479.8 6542.4 6594.7 6630.9 5482.1 6586.3 6703.2 6779.6 6790.1 6543.4 6575.3 6577.3 6578.5 6592.5 6247.3 6396.1 6481.1 6535.7 6571.5 5515.9 6352.8 6425.3 6477.2 6481.4 5461.6 6557.0 6607.6 6617.7 6621.4 6220.3 6236.9 6345.6 6374.1 6437.9 04:47:01 INFO - PID 1947 | 04:47:01 INFO - PID 1947 | [#1] ext.html Cycles:180 Average:45985.16 Median:328193.68 stddev:81239.61 (24.8%) stddev-sans-first:81396.02 04:47:01 INFO - PID 1947 | Values: 592.5 796.6 818.3 1265.5 1285.8 38057.8 38665.0 38716.6 38773.2 40465.6 38501.6 38841.8 38883.0 38921.4 38927.6 7929.3 8071.6 8404.9 8603.4 8621.2 6952.5 7502.8 8693.9 8735.9 8757.2 8701.0 8722.6 8763.3 8766.2 8774.0 8688.3 8723.3 8732.3 8746.1 8755.3 7181.2 8195.9 8353.4 8401.4 8406.2 36429.8 36733.3 36771.6 36781.0 36882.6 3193.8 3617.3 3623.6 3663.8 3678.3 30834.1 30924.5 31075.8 31086.4 31091.4 3166.9 3188.8 3202.6 3204.2 3211.2 300557.0 302443.0 303430.0 308220.0 309677.5 47124.8 47416.1 47491.0 47583.0 47633.8 49940.0 49962.3 54500.5 55367.7 55442.0 14177.7 14324.4 14328.4 14328.9 14330.1 11339.8 11403.7 11419.0 11432.0 11450.3 14059.7 14092.8 14097.9 14224.0 14256.6 6124.6 6152.0 6196.7 6226.8 6229.2 1019.9 1025.1 1048.4 1075.6 1076.9 17593.2 20577.4 21012.7 24808.8 24824.0 1057.0 1140.8 1173.0 1175.2 1175.2 12429.3 12726.7 16370.3 16375.2 16501.3 325825.0 328165.4 328222.0 328698.0 329789.0 55450.4 55718.9 55725.7 55803.6 55838.7 1475.1 1507.2 1511.5 1517.1 1533.5 25152.6 29152.3 29498.4 30092.9 30155.4 37402.0 37428.6 37673.1 37683.6 37700.4 294309.0 294544.0 295005.0 295360.5 295398.1 33724.3 33824.0 33971.5 34028.1 34339.1 42903.6 42929.9 42978.0 42987.0 43092.1 28349.4 29089.3 30249.4 30361.5 30440.4 29391.1 29783.4 29985.4 30246.5 30284.8 29308.0 35663.8 35792.8 36248.5 36281.0 23318.8 30170.4 30333.0 30348.4 30368.1 19545.8 23482.1 23912.6 24373.4 24660.2 04:47:01 INFO - PID 1947 | 04:47:01 INFO - PID 1947 | [#2] jquery.html Cycles:180 Average:33972.14 Median:22240.33 stddev:88573.89 (398.3%) stddev-sans-first:88786.43 04:47:01 INFO - PID 1947 | Values: 369.1 428.9 440.6 445.8 458.2 19102.0 20446.6 21157.8 21449.1 21551.6 18231.5 21644.0 21764.2 22287.9 22647.7 6001.4 6224.6 6250.6 6259.9 6263.6 5966.9 6071.3 6080.8 6163.2 6168.9 6074.9 6096.4 6121.4 6129.3 6149.0 6006.9 6110.3 6165.3 6171.4 6174.8 4525.6 5415.4 5430.1 5487.9 5496.5 26359.7 26700.2 26778.2 27060.9 27161.5 2193.2 2246.7 2266.3 2266.8 2268.2 17673.5 17736.9 17966.5 18082.9 18246.1 2144.1 2183.8 2205.7 2205.7 2209.4 131890.0 143797.0 151092.0 152482.0 153651.2 12348.3 12917.2 12927.4 12937.5 13000.0 16732.3 16808.4 16813.0 16813.0 16814.5 8789.8 8796.6 8997.2 9008.2 9105.2 6079.5 8258.6 8389.6 8474.7 8527.4 6968.6 7821.6 7957.7 8056.3 8176.7 2033.6 2072.1 2158.8 2305.4 2497.0 900.9 939.7 982.2 988.5 1021.0 21001.3 21241.5 21489.8 21549.9 21651.5 1027.2 1031.1 1032.7 1045.1 1056.5 10932.7 11026.5 11111.4 11150.7 11167.2 441032.0 535705.0 539697.0 541748.0 548644.0 115290.0 122346.0 123289.0 128273.0 130951.0 1078.9 1231.3 1245.2 1251.9 1260.0 16868.4 21016.8 22091.9 22189.7 22192.8 41665.6 53241.7 53418.7 53795.0 54297.5 57479.9 58544.7 73237.6 74604.0 75275.6 8383.8 8495.7 8945.1 9048.9 9060.4 13765.3 14025.8 14036.2 14256.5 14288.3 8957.7 10700.8 10824.1 11893.5 11995.9 9602.6 10533.9 11171.3 11198.9 11405.6 11549.4 12053.6 12057.0 12124.8 12146.1 8715.9 8763.8 8903.3 8998.0 9048.5 11626.7 11940.3 12013.8 12067.8 12190.1 04:47:01 INFO - PID 1947 | 04:47:01 INFO - PID 1947 | [#3] mootools.html Cycles:180 Average:7199.28 Median:3694.37 stddev:4531.37 (122.7%) stddev-sans-first:4522.40 04:47:01 INFO - PID 1947 | Values: 1300.6 1394.6 1404.1 1416.4 1419.9 11438.8 11902.3 12062.8 12087.9 12251.8 11705.2 11850.4 11894.8 11939.0 11947.5 2093.8 2143.7 2577.1 2584.9 2591.2 2503.7 2555.2 2558.3 2563.2 2565.4 2506.1 2529.7 2569.5 2571.0 2573.4 2494.8 2524.0 2555.2 2556.0 2560.2 2454.4 2523.9 2541.5 2568.9 2572.6 11808.2 11854.8 11970.3 11983.8 12050.1 6606.4 6834.1 6849.1 6863.3 6885.8 4134.3 4430.1 4515.0 4515.3 4519.8 1208.4 1242.4 1244.8 1247.1 1311.6 15101.4 15548.9 16734.0 16736.7 16893.8 5628.0 5724.7 5894.0 5950.4 5952.5 12326.6 12360.5 12465.7 12491.3 12498.3 6657.8 6665.8 6677.9 6681.2 6685.8 6046.2 6106.0 6235.7 6248.0 6283.8 3717.9 3784.1 4349.5 4498.4 4543.0 1247.9 1248.1 1261.5 1265.5 1266.7 762.0 773.9 789.3 789.3 793.4 6784.9 7024.2 7042.2 7104.5 7162.3 818.6 902.1 927.3 929.0 945.7 3670.8 4496.5 4592.5 4737.5 4799.7 15033.3 15050.8 15118.0 15262.5 15316.8 14740.4 15113.1 15280.5 15298.2 15299.2 6732.8 7289.3 8227.3 8405.2 8443.7 6349.9 7300.0 7308.3 7335.7 7359.3 11986.4 12446.8 13255.9 13498.7 13663.5 13700.2 13991.0 14033.0 14034.0 14050.6 9447.4 9771.1 9785.1 9812.9 9816.7 10675.6 10704.2 10773.8 10781.8 10959.9 9051.5 9125.6 9218.8 9264.4 9288.1 8377.8 8524.3 8531.0 8916.3 9286.9 8029.8 8180.1 8196.8 8197.9 8199.2 5074.6 6067.8 6188.5 6251.4 6268.9 6154.4 6286.5 6360.0 6401.0 6423.4 04:47:01 INFO - PID 1947 | 04:47:01 INFO - PID 1947 | [#4] prototype.html Cycles:180 Average:5117.52 Median:5479.23 stddev:1566.56 (28.6%) stddev-sans-first:1528.56 04:47:01 INFO - PID 1947 | Values: 294.9 356.3 396.3 414.9 415.9 5253.5 5456.6 5651.3 6054.7 6066.1 4797.7 5762.8 5853.5 5903.1 5909.3 4438.4 4505.6 4513.8 4518.9 4543.6 3882.6 4190.1 4546.7 4550.4 4555.2 4073.9 4410.4 4509.4 4516.5 4526.3 4451.4 4470.5 4480.5 4487.1 4492.6 3974.5 3995.7 4001.7 4013.4 4031.2 5551.6 5767.6 5799.6 5822.1 5958.2 5390.5 5392.3 5422.5 5431.5 5443.5 4792.7 5249.0 5981.5 6401.0 6425.7 4406.5 4598.3 4614.3 4615.7 4617.4 5696.5 6422.8 6453.6 6651.4 6727.4 4326.3 4346.7 4348.1 4355.5 4358.1 4408.4 4422.6 4436.9 4442.9 4449.1 4943.8 5165.5 5166.5 5188.3 5206.7 4860.4 6148.5 6407.9 6409.1 6409.6 1505.1 1518.5 1524.4 1525.9 1530.5 949.9 1000.5 1008.4 1011.5 1022.2 5026.8 5262.9 5280.5 5613.5 6068.8 5297.6 5859.6 6075.4 6606.9 6701.2 5692.6 5983.1 6016.2 6020.7 6042.4 3043.2 3064.4 3093.7 3464.8 3470.5 5653.5 5802.2 6023.1 6061.4 6927.6 5408.4 5946.1 6346.1 6438.7 6488.2 6217.3 6824.9 6835.4 6838.5 6878.1 6258.2 6548.4 6603.7 6637.2 6659.4 5402.6 5458.9 6564.8 6576.6 6578.6 5893.5 6625.6 6813.5 6874.0 6890.6 3692.3 4146.0 4374.8 4377.1 4399.4 6486.3 6640.2 6642.5 6667.0 6687.2 6420.0 6420.9 6437.2 6441.2 6453.6 5266.6 5787.5 6173.7 6428.0 6429.7 5341.4 5499.6 6187.9 6209.6 6311.9 5291.3 6445.2 6446.5 6454.6 6462.4 5451.9 5924.2 6193.5 6236.8 6267.9 04:47:01 INFO - PID 1947 | 04:47:01 INFO - PID 1947 | [#5] yui.html Cycles:180 Average:21956.10 Median:17689.88 stddev:31184.70 (176.3%) stddev-sans-first:31231.85 04:47:01 INFO - PID 1947 | Values: 834.6 839.4 841.8 866.1 878.5 14251.9 14795.5 16339.2 16359.2 16755.5 15121.8 16556.3 16819.9 16879.4 17185.9 968.2 985.7 986.0 988.1 990.1 984.3 984.4 984.8 985.2 987.2 1020.0 1030.8 1033.9 1034.2 1035.0 1004.5 1021.7 1025.3 1025.4 1028.3 629.4 750.9 760.5 761.0 761.2 15047.3 15242.4 15939.8 16408.8 16623.8 16078.8 16187.8 16241.5 16258.4 16416.4 13319.5 13624.0 13952.2 13962.9 13981.0 3354.4 3391.4 3424.4 3424.6 3431.2 116717.8 118000.0 118816.0 119852.6 121334.0 11654.6 11711.7 11751.4 11839.2 11858.1 19522.0 19713.0 19764.9 19864.4 19880.0 7315.8 7548.7 7570.6 7575.2 7576.2 3529.7 3589.3 3591.7 3597.1 3601.5 4293.7 4316.0 4374.6 4376.6 4384.8 869.8 915.9 932.7 958.9 960.5 546.5 641.9 648.6 654.1 679.0 11092.7 11911.5 11927.1 11934.6 11976.5 636.9 645.4 649.9 653.8 660.1 5323.0 5434.1 5442.9 5455.3 5529.4 102748.5 104480.0 108044.0 108833.0 109027.5 91942.0 92589.9 92672.0 93021.0 94028.0 84273.6 86185.6 86199.0 86213.6 87584.1 12381.8 12958.8 12966.8 12977.9 13019.9 37369.3 40504.6 41152.0 42323.6 43379.8 66575.0 68837.7 69071.0 74571.0 75467.0 15584.1 15748.6 15819.1 15830.4 16529.4 17456.4 17923.4 17964.0 17971.6 18660.2 16344.0 16507.5 16680.0 16797.4 16888.9 16601.0 16799.2 16841.0 16861.6 16992.8 16135.8 16235.1 16402.2 16433.9 16610.2 11488.9 11498.6 11835.6 12050.5 12067.9 10580.4 11711.6 12268.6 12902.8 13672.7 04:47:01 INFO - PID 1947 | -------- Summary: end -------- 04:47:01 INFO - PID 1947 | 04:47:02 INFO - TEST-INFO | 1947: exit 0 04:47:02 INFO - TEST-OK | dromaeo_css | took 1115338ms 04:47:02 INFO - TEST-START | kraken 04:47:02 INFO - operating with platform_type : mac_ 04:47:02 INFO - Initialising browser for kraken test... 04:47:02 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpRSTiDG/profile http://localhost:49727/getInfo.html) 04:47:05 INFO - PID 1953 | 2017-04-22 04:47:05.173 plugin-container[1955:15949] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 04:47:05 INFO - PID 1953 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:47:05 INFO - PID 1953 | 2017-04-22 04:47:05.173 plugin-container[1955:15949] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 04:47:05 INFO - PID 1953 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:47:05 INFO - PID 1953 | 2017-04-22 04:47:05.174 plugin-container[1955:15949] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:47:05 INFO - PID 1953 | __metrics Screen width/height:1600/1200 04:47:05 INFO - PID 1953 | colorDepth:24 04:47:05 INFO - PID 1953 | Browser inner width/height: 1024/689 04:47:05 INFO - PID 1953 | __metrics 04:47:05 ERROR - PID 1953 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3403: TypeError: this.tabs is undefined 04:47:06 INFO - TEST-INFO | 1953: exit 0 04:47:06 INFO - Browser initialized. 04:47:06 INFO - Running cycle 1/1 for kraken test... 04:47:06 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpRSTiDG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:47:08 INFO - PID 1956 | 2017-04-22 04:47:08.784 plugin-container[1957:16078] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 04:47:08 INFO - PID 1956 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:47:08 INFO - PID 1956 | 2017-04-22 04:47:08.785 plugin-container[1957:16078] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a03, name = 'com.apple.CFPasteboardClient' 04:47:08 INFO - PID 1956 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:47:08 INFO - PID 1956 | 2017-04-22 04:47:08.785 plugin-container[1957:16078] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:47:18 INFO - PID 1956 | RSS: Main: 237256704 04:47:18 INFO - PID 1956 | 04:47:21 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:49727/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49727/tests/kraken/driver.html?testName=audio-beat-detection) 04:47:22 INFO - PID 1956 | RSS: Main: 249618432 04:47:22 INFO - PID 1956 | 04:47:24 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:49727/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49727/tests/kraken/driver.html?testName=audio-dft) 04:47:25 INFO - PID 1956 | RSS: Main: 257642496 04:47:25 INFO - PID 1956 | 04:47:28 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:49727/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49727/tests/kraken/driver.html?testName=audio-fft) 04:47:29 INFO - PID 1956 | RSS: Main: 260104192 04:47:29 INFO - PID 1956 | 04:47:31 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:49727/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49727/tests/kraken/driver.html?testName=audio-oscillator) 04:47:31 INFO - PID 1956 | RSS: Main: 261406720 04:47:31 INFO - PID 1956 | 04:47:33 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:49727/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49727/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:47:34 INFO - PID 1956 | RSS: Main: 262914048 04:47:34 INFO - PID 1956 | 04:47:37 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:49727/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49727/tests/kraken/driver.html?testName=imaging-darkroom) 04:47:38 INFO - PID 1956 | RSS: Main: 269049856 04:47:38 INFO - PID 1956 | 04:47:40 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:49727/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49727/tests/kraken/driver.html?testName=imaging-desaturate) 04:47:41 INFO - PID 1956 | RSS: Main: 270602240 04:47:41 INFO - PID 1956 | 04:47:44 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:49727/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49727/tests/kraken/driver.html?testName=json-parse-financial) 04:47:45 INFO - PID 1956 | RSS: Main: 269963264 04:47:45 INFO - PID 1956 | 04:47:46 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:49727/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49727/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:47:47 INFO - PID 1956 | RSS: Main: 269832192 04:47:47 INFO - PID 1956 | 04:47:49 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:49727/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49727/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:47:49 INFO - PID 1956 | RSS: Main: 269615104 04:47:49 INFO - PID 1956 | 04:47:51 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:49727/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49727/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:47:52 INFO - PID 1956 | RSS: Main: 269107200 04:47:52 INFO - PID 1956 | 04:47:55 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:49727/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49727/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:47:56 INFO - PID 1956 | RSS: Main: 269197312 04:47:56 INFO - PID 1956 | 04:47:59 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:49727/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49727/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:47:59 INFO - PID 1956 | RSS: Main: 269897728 04:47:59 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | Cycle 1(1): loaded http://localhost:49727/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49727/tests/kraken/driver.html?testName=ai-astar) 04:48:01 INFO - PID 1956 | RSS: Main: 271523840 04:48:01 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | __start_tp_report 04:48:01 INFO - PID 1956 | _x_x_mozilla_page_load 04:48:01 INFO - PID 1956 | _x_x_mozilla_page_load_details 04:48:01 INFO - PID 1956 | |i|pagename|runs| 04:48:01 INFO - PID 1956 | |0;ai-astar;103;117;116;113;114;113;115;113;114;115 04:48:01 INFO - PID 1956 | |1;audio-beat-detection;147;111;111;148;113;109;111;105;138;114 04:48:01 INFO - PID 1956 | |2;audio-dft;202;198;197;214;216;268;274;199;201;200 04:48:01 INFO - PID 1956 | |3;audio-fft;82;87;61;84;57;58;55;60;76;58 04:48:01 INFO - PID 1956 | |4;audio-oscillator;91;70;80;72;70;93;111;76;92;90 04:48:01 INFO - PID 1956 | |5;imaging-gaussian-blur;88;83;137;89;88;88;89;90;89;88 04:48:01 INFO - PID 1956 | |6;imaging-darkroom;96;97;99;96;97;95;95;97;96;97 04:48:01 INFO - PID 1956 | |7;imaging-desaturate;92;96;92;90;92;92;128;109;78;150 04:48:01 INFO - PID 1956 | |8;json-parse-financial;50;49;52;51;51;50;51;53;51;49 04:48:01 INFO - PID 1956 | |9;json-stringify-tinderbox;56;59;60;61;57;55;56;53;55;58 04:48:01 INFO - PID 1956 | |10;stanford-crypto-aes;82;84;84;82;83;83;84;82;83;82 04:48:01 INFO - PID 1956 | |11;stanford-crypto-ccm;148;164;139;135;138;145;145;139;200;124 04:48:01 INFO - PID 1956 | |12;stanford-crypto-pbkdf2;189;189;203;184;222;175;225;169;214;172 04:48:01 INFO - PID 1956 | |13;stanford-crypto-sha256-iterative;70;66;70;71;68;71;72;97;98;93 04:48:01 INFO - PID 1956 | __end_tp_report 04:48:01 INFO - PID 1956 | __start_cc_report 04:48:01 INFO - PID 1956 | _x_x_mozilla_cycle_collect,1007 04:48:01 INFO - PID 1956 | __end_cc_report 04:48:01 INFO - PID 1956 | __startTimestamp1492861681842__endTimestamp 04:48:01 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | ------- Summary: start ------- 04:48:01 INFO - PID 1956 | Number of tests: 14 04:48:01 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | [#0] ai-astar Cycles:10 Average:113.30 Median:114.50 stddev:3.86 (3.4%) stddev-sans-first:1.42 04:48:01 INFO - PID 1956 | Values: 103.0 117.0 116.0 113.0 114.0 113.0 115.0 113.0 114.0 115.0 04:48:01 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | [#1] audio-beat-detection Cycles:10 Average:120.70 Median:113.50 stddev:16.69 (14.7%) stddev-sans-first:14.74 04:48:01 INFO - PID 1956 | Values: 147.0 111.0 111.0 148.0 113.0 109.0 111.0 105.0 138.0 114.0 04:48:01 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | [#2] audio-dft Cycles:10 Average:216.90 Median:208.00 stddev:29.28 (14.1%) stddev-sans-first:30.55 04:48:01 INFO - PID 1956 | Values: 202.0 198.0 197.0 214.0 216.0 268.0 274.0 199.0 201.0 200.0 04:48:01 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | [#3] audio-fft Cycles:10 Average:67.80 Median:68.50 stddev:12.82 (18.7%) stddev-sans-first:12.53 04:48:01 INFO - PID 1956 | Values: 82.0 87.0 61.0 84.0 57.0 58.0 55.0 60.0 76.0 58.0 04:48:01 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | [#4] audio-oscillator Cycles:10 Average:84.50 Median:85.00 stddev:13.22 (15.6%) stddev-sans-first:13.81 04:48:01 INFO - PID 1956 | Values: 91.0 70.0 80.0 72.0 70.0 93.0 111.0 76.0 92.0 90.0 04:48:01 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | [#5] imaging-gaussian-blur Cycles:10 Average:92.90 Median:88.50 stddev:15.61 (17.6%) stddev-sans-first:16.46 04:48:01 INFO - PID 1956 | Values: 88.0 83.0 137.0 89.0 88.0 88.0 89.0 90.0 89.0 88.0 04:48:01 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | [#6] imaging-darkroom Cycles:10 Average:96.50 Median:97.00 stddev:1.18 (1.2%) stddev-sans-first:1.24 04:48:01 INFO - PID 1956 | Values: 96.0 97.0 99.0 96.0 97.0 95.0 95.0 97.0 96.0 97.0 04:48:01 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | [#7] imaging-desaturate Cycles:10 Average:101.90 Median:92.00 stddev:21.56 (23.4%) stddev-sans-first:22.57 04:48:01 INFO - PID 1956 | Values: 92.0 96.0 92.0 90.0 92.0 92.0 128.0 109.0 78.0 150.0 04:48:01 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:1.25 (2.5%) stddev-sans-first:1.30 04:48:01 INFO - PID 1956 | Values: 50.0 49.0 52.0 51.0 51.0 50.0 51.0 53.0 51.0 49.0 04:48:01 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | [#9] json-stringify-tinderbox Cycles:10 Average:57.00 Median:57.50 stddev:2.49 (4.3%) stddev-sans-first:2.62 04:48:01 INFO - PID 1956 | Values: 56.0 59.0 60.0 61.0 57.0 55.0 56.0 53.0 55.0 58.0 04:48:01 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:83.00 stddev:0.88 (1.1%) stddev-sans-first:0.87 04:48:01 INFO - PID 1956 | Values: 82.0 84.0 84.0 82.0 83.0 83.0 84.0 82.0 83.0 82.0 04:48:01 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | [#11] stanford-crypto-ccm Cycles:10 Average:147.70 Median:145.00 stddev:21.04 (14.5%) stddev-sans-first:22.32 04:48:01 INFO - PID 1956 | Values: 148.0 164.0 139.0 135.0 138.0 145.0 145.0 139.0 200.0 124.0 04:48:01 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.20 Median:196.00 stddev:20.67 (10.5%) stddev-sans-first:21.84 04:48:01 INFO - PID 1956 | Values: 189.0 189.0 203.0 184.0 222.0 175.0 225.0 169.0 214.0 172.0 04:48:01 INFO - PID 1956 | 04:48:01 INFO - PID 1956 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.60 Median:71.50 stddev:12.87 (18.0%) stddev-sans-first:13.35 04:48:01 INFO - PID 1956 | Values: 70.0 66.0 70.0 71.0 68.0 71.0 72.0 97.0 98.0 93.0 04:48:01 INFO - PID 1956 | -------- Summary: end -------- 04:48:01 INFO - PID 1956 | 04:48:02 INFO - TEST-INFO | 1956: exit 0 04:48:02 INFO - TEST-OK | kraken | took 60730ms 04:48:02 INFO - SUITE-END | took 1176s 04:48:02 INFO - Completed test suite (00:19:36) 04:48:02 INFO - javascript benchmark 04:48:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7824.587556494443, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3517.3844577198993, 3576.3383774001813, 3577.052081198481, 3617.656322649349, 3624.9456258156133, 6010.729517171727, 6176.073588961653, 6183.165272688186, 6219.942609756341, 6262.874742505147, 6102.2468898001325, 6107.279845777768, 6119.685352982181, 6132.141500189977, 6228.595141315811, 5384.219288203209, 5403.0, 5415.83752487425, 5434.510894019538, 5512.862178445551, 5382.515573598375, 5466.207399927008, 5521.834344969638, 5533.197686335479, 5543.861403464905, 4721.716696998191, 5470.234167216593, 5486.451354864522, 5515.779368825242, 5533.418991005947, 5256.763445644955, 5330.733463326818, 5340.198970154469, 5345.7594408251725, 5390.946090539083, 5353.7590808413715, 5372.0330340538685, 5429.53848922842, 5443.374011988592, 5458.344998600153, 5208.427073021964, 5441.673499590037, 6242.875142497124, 6244.219472565929, 6258.374162583706, 6401.615903045786, 6474.643894585796, 6574.868502629968, 6661.467082633378, 6668.433183179435, 6740.966295168493, 6752.324767523258, 6764.391204791543, 6784.253732089466, 6784.76253331131, 6328.936710632881, 6340.144080549113, 6342.651154186472, 6356.618602883842, 6357.364263573606, 6563.081168636395, 6640.601563906181, 6825.965870170618, 6871.312868713088, 6879.6560171991205, 6301.464375528131, 6441.838954026093, 6451.225852897588, 6537.901931471032, 6540.084388185658, 4929.334539837076, 5458.9454105458435, 6512.902306465407, 6757.256701762803, 6770.1537307836525, 6511.739530418825, 6613.834654133685, 6640.302768209365, 6641.169853768279, 6649.667516624149, 5544.362398324244, 6402.9359706403275, 6479.092926990225, 6662.700178491979, 6676.399124078856, 4167.958320416757, 5221.921671174935, 5234.0840352938085, 5309.309789727311, 5353.0899747043095, 4542.863714088583, 4703.0, 4710.62315014798, 4713.198756211452, 4718.457377401643, 6117.847053823601, 6330.177076979963, 6334.1765570475545, 6335.271443783931, 6343.841403964938, 6337.524685648595, 6460.741570337228, 6655.267920528738, 6754.527183097234, 6774.187097548227, 6294.118823364733, 6317.6209427434505, 6332.366763323631, 6339.809805705836, 6357.8728425432155, 5803.506701930299, 5872.677002764889, 5879.2944846618675, 5898.734556944948, 5920.0, 6604.8348791280605, 6735.6295403753265, 6835.58986460814, 6895.069165662772, 6932.514723969374, 6140.0, 6779.254282029072, 6781.830454238683, 6798.082258894986, 6807.5575087619145, 6452.645104519296, 6818.386345228953, 6925.549839260432, 6986.301369863177, 6991.895121573282, 6377.649229292433, 6479.773207937699, 6542.44389226902, 6594.70323835419, 6630.900536492053, 5482.095454250004, 6586.308437614173, 6703.229128650072, 6779.559328643622, 6790.117284752951, 6543.378379053982, 6575.2767195607485, 6577.309382514767, 6578.506612004023, 6592.538522303585, 6247.281562620356, 6396.072569477493, 6481.125048118443, 6535.738570457319, 6571.5399922005, 5515.862103447446, 6352.809415717536, 6425.261094973951, 6477.190351206099, 6481.4166724994975, 5461.590380721383, 6557.0, 6607.636579987954, 6617.735290588323, 6621.370969757865, 6220.34686357944, 6236.937630623635, 6345.619262844244, 6374.107624932421, 6437.935620643733], "value": 6037.3337978481495, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.5230189697292, 796.5579103597503, 818.2553875972864, 1265.4621785741058, 1285.8071289306608, 38057.80971095144, 38664.99999999996, 38716.612833871724, 38773.224535509275, 40465.59534404655, 38501.61498385015, 38841.80579097108, 38883.0, 38921.41617875734, 38927.61072389275, 7929.326007289387, 8071.596420178997, 8404.873926891101, 8603.44077634955, 8621.180987806149, 6952.5480843745245, 7502.774916752506, 8693.913060869405, 8735.868961965543, 8757.168069033463, 8701.0, 8722.607482663296, 8763.255123314493, 8766.211041006309, 8773.95613021934, 8688.304935605169, 8723.345749068845, 8732.257758090507, 8746.125387461267, 8755.343349248831, 7181.210066892638, 8195.918040819575, 8353.415260931737, 8401.41190116692, 8406.243438090602, 36429.81785091084, 36733.265334693424, 36771.632283677085, 36781.0, 36882.63117368819, 3193.8243396613175, 3617.276544691072, 3623.6376362363553, 3663.816809159531, 3678.264347130558, 30834.07497775048, 30924.536131958037, 31075.844620776978, 31086.37827243465, 31091.37817243665, 3166.9208269793207, 3188.792728472642, 3202.551642770014, 3204.198950262434, 3211.2293049668147, 300557.0, 302443.0, 303430.0, 308220.0, 309677.4516127405, 47124.764376177896, 47416.05167896692, 47491.0, 47583.0, 47633.76183119062, 49940.0011999758, 49962.250566241535, 54500.45499544954, 55367.72316138474, 55442.0, 14177.716445671234, 14324.427022919175, 14328.355224014944, 14328.928355358365, 14330.140191588538, 11339.829902551468, 11403.657890263305, 11419.0, 11432.0, 11450.312981221154, 14059.71880562383, 14092.788608170886, 14097.85902140986, 14224.0, 14256.572302830724, 6124.6325220486915, 6152.0, 6196.659183744938, 6226.782062627786, 6229.159063526366, 1019.8929112443235, 1025.123519390925, 1048.3500229857384, 1075.6235317638764, 1076.8546246256656, 17593.208305626278, 20577.38267851967, 21012.684809727867, 24808.751912481006, 24824.0, 1056.9735756606146, 1140.823172408278, 1172.9941350293193, 1175.1715040896147, 1175.2419689300352, 12429.316387598588, 12726.745465090646, 16370.263338149574, 16375.181240937905, 16501.318473555944, 325825.0, 328165.35917320737, 328222.0, 328698.0, 329789.0, 55450.44549554533, 55718.88562228733, 55725.721371393694, 55803.60490987677, 55838.72080639652, 1475.0707054555562, 1507.2388443835794, 1511.508759653117, 1517.1049081042238, 1533.4939469975104, 25152.62271065899, 29152.27119322034, 29498.410031798816, 30092.946746863756, 30155.436787873277, 37402.0, 37428.62571374251, 37673.05817354588, 37683.62316376911, 37700.43449348207, 294309.0, 294544.0, 295005.0, 295360.52319739125, 295398.0752405949, 33724.27860099637, 33824.0, 33971.4904276441, 34028.149296267795, 34339.14152146217, 42903.57096428996, 42929.92675183145, 42978.0, 42987.0, 43092.13815723731, 28349.433011339246, 29089.272768180967, 30249.395012099194, 30361.544576830922, 30440.39119217559, 29391.118266452042, 29783.404331913673, 29985.400291994476, 30246.530971328062, 30284.84857575698, 29308.0, 35663.82168089143, 35792.82103589465, 36248.45627315645, 36281.0, 23318.766812331658, 30170.396592068475, 30333.0, 30348.393032138792, 30368.088957331314, 19545.775593645598, 23482.06071757179, 23912.641310380706, 24373.369059878565, 24660.1776724608], "value": 17722.396234695763, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.1270146104464, 428.9227938970986, 440.6474820143884, 445.8417261872036, 458.15699113630933, 19101.99999999998, 20446.591068178626, 21157.788422115773, 21449.142034318607, 21551.56896862064, 18231.45305640833, 21644.0, 21764.238251661194, 22287.888560557174, 22647.660285095735, 6001.429864162908, 6224.62652240867, 6250.562460627758, 6259.937400625981, 6263.592866463665, 5966.850828729295, 6071.271447426293, 6080.753445543675, 6163.198784158053, 6168.938310616903, 6074.878502429949, 6096.39036096389, 6121.387861213865, 6129.264488261415, 6148.969255153718, 6006.8798624027495, 6110.327863934964, 6165.2601687797305, 6171.413715697002, 6174.84562885927, 4525.570070843265, 5415.431379705153, 5430.13117901134, 5487.862803429906, 5496.477834605706, 26359.73640263592, 26700.198994030212, 26778.196654100408, 27060.86469567659, 27161.45677086447, 2193.1775584155944, 2246.7079279693535, 2266.2974477911735, 2266.77332266772, 2268.1834539565743, 17673.469795906145, 17736.91131544347, 17966.461006169837, 18082.909585451987, 18246.0876956153, 2144.120910426733, 2183.7816218378193, 2205.691203231549, 2205.7353117625826, 2209.3924170853143, 131890.0432987012, 143796.99999999895, 151092.0, 152482.0, 153651.23174384056, 12348.320842353756, 12917.160384574972, 12927.353632318534, 12937.54718584845, 13000.0, 16732.330706771594, 16808.411705590246, 16812.991220526565, 16813.0, 16814.495565133064, 8789.824203515895, 8796.604152813139, 8997.190252877272, 9008.189262966365, 9105.180533751995, 6079.544034197364, 8258.628361723737, 8389.580520973926, 8474.74575762728, 8527.40308178434, 6968.616726080013, 7821.6089195541135, 7957.652492514722, 8056.308175283139, 8176.713815016446, 2033.6441122803747, 2072.1089931329675, 2158.827293816491, 2305.4005958450925, 2497.038640123529, 900.8738776571286, 939.6570251858022, 982.2240430060173, 988.4909271725068, 1020.9693709188737, 21001.264955726478, 21241.468963275733, 21489.78510214909, 21549.89225053896, 21651.458713532287, 1027.1679939249232, 1031.0511935863424, 1032.746976990642, 1045.10643399892, 1056.4823236614004, 10932.672019839418, 11026.503807328689, 11111.38887361203, 11150.665480035612, 11167.218294719292, 441032.0, 535705.0, 539697.0, 541748.0, 548644.0, 115290.0, 122346.0, 123289.0, 128273.0, 130951.03573446407, 1078.946052697362, 1231.2550906701317, 1245.1844042152404, 1251.9061070419755, 1260.0423678004781, 16868.409605663743, 21016.789832101484, 22091.895405229996, 22189.667154992367, 22192.778072219073, 41665.58334416738, 53241.73379133079, 53418.73290633522, 53795.0, 54297.45702543082, 57479.85040299087, 58544.70727646335, 73237.6338118306, 74604.0, 75275.62362188155, 8383.790405239917, 8495.660173593235, 8945.105489451264, 9048.864267036131, 9060.365774395992, 13765.311734413439, 14025.752688970775, 14036.228007459687, 14256.501022464165, 14288.285585720465, 8957.731268061969, 10700.785984280115, 10824.079953204066, 11893.524259029542, 11995.880041199476, 9602.615895364108, 10533.894661053599, 11171.329720216812, 11198.944005280247, 11405.600803971822, 11549.42252887369, 12053.57812476559, 12057.0, 12124.84813942674, 12146.149769516049, 8715.869261961203, 8763.824723505366, 8903.3322500814, 8998.0, 9048.547572621212, 11626.70933226676, 11940.343281119622, 12013.81979270329, 12067.81898271509, 12190.146689731633], "value": 10333.635286798466, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.6098170548837, 1394.6164804678713, 1404.052264721313, 1416.4475854416785, 1419.8935079869009, 11438.771224575503, 11902.285862848214, 12062.81905771413, 12087.879121208796, 12251.75496490072, 11705.180637355388, 11850.407479626007, 11894.810518948101, 11938.985186259179, 11947.46236419361, 2093.8010888965564, 2143.6677315016195, 2577.1108967406217, 2584.93537661559, 2591.1578736910487, 2503.7120731115974, 2555.1951135392383, 2558.296468471167, 2563.1797824696105, 2565.3971316740644, 2506.135383292761, 2529.734377890323, 2569.498947705198, 2571.010161087985, 2573.395252115751, 2494.8128890333205, 2523.987380063088, 2555.220657699401, 2556.0, 2560.167945417728, 2454.374134595667, 2523.9116630918015, 2541.453587478701, 2568.8844002019955, 2572.6398304237423, 11808.173427860052, 11854.762904741943, 11970.281783093042, 11983.820242696367, 12050.09624278174, 6606.405423511907, 6834.077399551046, 6849.1438570178725, 6863.313668633146, 6885.793426197222, 4134.297169481196, 4430.091831174634, 4514.9774251128865, 4515.277555591122, 4519.819207231707, 1208.3897631696025, 1242.372601836067, 1244.7946088895412, 1247.1270110922392, 1311.6065180445828, 15101.395944162221, 15548.92225538865, 16734.0, 16736.6652666946, 16893.83106168935, 5628.0, 5724.742386592613, 5894.0, 5950.375210602911, 5952.464278214981, 12326.568570100004, 12360.505579776709, 12465.688357790947, 12491.250524968349, 12498.3125928073, 6657.800265991931, 6665.800025999228, 6677.899831502531, 6681.164854393201, 6685.799426017227, 6046.2139921809885, 6106.0, 6235.688215589203, 6248.0003199487865, 6283.8114856554375, 3717.925641487155, 3784.0642371834465, 4349.521162804662, 4498.3927169832305, 4543.0232500012, 1247.8689737577606, 1248.082659245454, 1261.4764872577962, 1265.544404014554, 1266.6959929616826, 761.9885701714479, 773.8529679360903, 789.3290702902486, 789.3290702902486, 793.3890904003885, 6784.864302714017, 7024.192217894905, 7042.190148133031, 7104.538205016657, 7162.283771622796, 818.6397984886631, 902.120432578244, 927.3369540778241, 928.9581968811419, 945.7138291922979, 3670.8164591770305, 4496.482904465963, 4592.471865735416, 4737.478877323491, 4799.712017278975, 15033.323500442506, 15050.774238386433, 15118.0, 15262.542123736306, 15316.846831531542, 14740.410383584536, 15113.09321440717, 15280.46518371874, 15298.235088245543, 15299.235038248044, 6732.798016059526, 7289.307515785886, 8227.341812654973, 8405.243528082501, 8443.662253509914, 6349.904751428639, 7300.0, 7308.30571095745, 7335.706571737071, 7359.337659610661, 11986.400679966142, 12446.813297800722, 13255.933720331337, 13498.73002539924, 13663.521776737767, 13700.177989320673, 13991.0, 14033.0, 14034.0, 14050.648733781738, 9447.433154010781, 9771.12059914611, 9785.07041831025, 9812.901870981199, 9816.65641702537, 10675.57297708083, 10704.197185210984, 10773.784524309627, 10781.784364312512, 10959.945200274267, 9051.456912585267, 9125.634974601207, 9218.769530761785, 9264.39781414206, 9288.071192880658, 8377.83244335122, 8524.318054555497, 8530.957345213234, 8916.33127515426, 9286.860697089414, 8029.759107226792, 8180.141085186207, 8196.836063278583, 8197.877031844408, 8199.22107399796, 5074.6194035448225, 6067.757289708362, 6188.535859810622, 6251.437370636664, 6268.905966410416, 6154.3845615438095, 6286.508209211691, 6360.045993101072, 6401.039844023434, 6423.42189202974], "value": 5438.964966900611, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.93087557603684, 356.2892030399353, 396.3104198694273, 414.9087200815819, 415.87523742877147, 5253.527182553569, 5456.63584103974, 5651.3218413790355, 6054.697265136748, 6066.117684681807, 4797.664163508556, 5762.769489220436, 5853.50245229156, 5903.144044113601, 5909.349971503142, 4438.400815889862, 4505.6170225530705, 4513.7517436540975, 4518.887027824298, 4543.591076803087, 3882.611738826109, 4190.078182799779, 4546.65900057497, 4550.431196100488, 4555.2256116460285, 4073.9185216295655, 4410.360497727827, 4509.368688383629, 4516.503184649686, 4526.275795872661, 4451.354553589727, 4470.463544374696, 4480.48474425442, 4487.080148569536, 4492.595666390041, 3974.5230572331216, 3995.720299579031, 4001.679865610744, 4013.3578627419765, 4031.21391328691, 5551.555875529948, 5767.5962682612235, 5799.565032622528, 5822.1266809978415, 5958.165856780054, 5390.51485366319, 5392.272043274147, 5422.53908417785, 5431.4840090191365, 5443.537299329561, 4792.688475249098, 5248.9737551312, 5981.461668449861, 6400.967995160041, 6425.678716064178, 4406.471223453174, 4598.287265473857, 4614.2617181250835, 4615.746133962631, 4617.422822147231, 5696.458836410535, 6422.839429014265, 6453.645049522274, 6651.401373876326, 6727.39453449187, 4326.329418940069, 4346.65226781863, 4348.086901750605, 4355.5208927018, 4358.084802191512, 4408.448943882015, 4422.624076953431, 4436.911261774746, 4442.8667139985855, 4449.087936972913, 4943.752812359367, 5165.498743837801, 5166.509181627739, 5188.325517682677, 5206.661566998133, 4860.441049279308, 6148.544028769774, 6407.903881441782, 6409.102725618417, 6409.596401349494, 1505.1495904813746, 1518.506485392252, 1524.3826250368618, 1525.9008164469274, 1530.4949366708959, 949.8622699708503, 1000.4847503535685, 1008.3748076192661, 1011.4588695048114, 1022.2077889635592, 5026.798928042837, 5262.868428289324, 5280.498352656491, 5613.522850557667, 6068.817935461943, 5297.622618119317, 5859.589828711949, 6075.392460753889, 6606.86786264272, 6701.229358623821, 5692.6299790513485, 5983.132445795335, 6016.217891674054, 6020.668863212565, 6042.425969532887, 3043.193553708269, 3064.3564851381016, 3093.6751641077813, 3464.757466977338, 3470.548828652259, 5653.519450846724, 5802.245708057925, 6023.126646636213, 6061.393860613992, 6927.618980956096, 5408.3509978802795, 5946.108083787466, 6346.143270658402, 6438.678066096676, 6488.221413430419, 6217.285012223561, 6824.863502730017, 6835.350641689031, 6838.5213035089055, 6878.140232470942, 6258.249010118815, 6548.410643042149, 6603.6745931415, 6637.236717777518, 6659.4006539411685, 5402.595325215394, 5458.863528411663, 6564.770233041821, 6576.638284894186, 6578.572392794453, 5893.499052580579, 6625.569337993208, 6813.523053386215, 6874.0, 6890.586564806128, 3692.3353796315846, 4145.97927010363, 4374.825006999685, 4377.102693947797, 4399.384086227867, 6486.286508484157, 6640.169978752656, 6642.5018123641885, 6667.0, 6687.230968438692, 6419.977707022273, 6420.935790642221, 6437.195350581177, 6441.227052753512, 6453.612783233021, 5266.55234305073, 5787.472963320103, 6173.69131543412, 6428.0, 6429.710663020269, 5341.359036915595, 5499.587530935116, 6187.876242475035, 6209.565330426827, 6311.897266776632, 5291.3121294231505, 6445.1943507061615, 6446.5165112615805, 6454.644994525346, 6462.3537646235, 5451.918221226605, 5924.229850119457, 6193.473554747897, 6236.781712640036, 6267.871783079089], "value": 4629.033655266209, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [834.57019634888, 839.4375768235282, 841.8484672758901, 866.0949307973171, 878.5475480127732, 14251.92874035631, 14795.482158124469, 16339.183040847955, 16359.182040897953, 16755.497335079937, 15121.84878151218, 16556.337746490157, 16819.91590042051, 16879.40922067728, 17185.914070429662, 968.1625394034163, 985.6698006167942, 985.9654912078079, 988.1205726903062, 990.0544979544553, 984.2814745235981, 984.3847595252967, 984.8178087053882, 985.2019863910256, 987.151050096916, 1019.9745006374826, 1030.8453731940194, 1033.9327943683675, 1034.2243317511866, 1035.0063938618935, 1004.5379125602232, 1021.6934919524113, 1025.3027941000155, 1025.3847691385183, 1028.2596530498067, 629.3957800511514, 750.9098908131059, 760.4714723267332, 761.029687148884, 761.208343322949, 15047.322870470745, 15242.39030438781, 15939.760903586455, 16408.83591164085, 16623.83376166235, 16078.83921160785, 16187.83812161875, 16241.51275461738, 16258.430954916521, 16416.425425110065, 13319.467221311135, 13624.0, 13952.2326272055, 13962.930185349009, 13981.02132850701, 3354.37943980362, 3391.3556424279554, 3424.366492198951, 3424.6404127566743, 3431.2108215110416, 116717.83282167155, 118000.0, 118816.0, 119852.61768587738, 121334.0, 11654.592089277005, 11711.707207319714, 11751.412429378497, 11839.230450020721, 11858.110641701907, 19522.0, 19713.01434928248, 19764.90117549403, 19864.404067877986, 19880.0, 7315.817104572322, 7548.66031028605, 7570.583617900958, 7575.204603516661, 7576.24237576249, 3529.6999755020597, 3589.2821435712963, 3591.730620203495, 3597.0647631615966, 3601.4597810328137, 4293.742375457483, 4316.028893498938, 4374.562543745664, 4376.606105450461, 4384.802683879233, 869.8042940338499, 915.949622770754, 932.6549176804625, 958.9136977672043, 960.4573416019927, 546.469924173551, 641.9454346380517, 648.6302807399832, 654.0973456629821, 678.9883074219377, 11092.722681933015, 11911.52353905834, 11927.105467090003, 11934.641960741192, 11976.52093916251, 636.8758092171983, 645.3707635055877, 649.909012738217, 653.8169312592481, 660.0824108913613, 5322.973385133049, 5434.130539113802, 5442.945570544323, 5455.263539422207, 5529.419410961791, 102748.48625756823, 104479.95520044854, 108044.0, 108833.0, 109027.45486272518, 91942.0, 92589.90755508511, 92672.0, 93021.0, 94028.0, 84273.57863210645, 86185.56907215549, 86199.0, 86213.56893215494, 87584.12415875887, 12381.814272785736, 12958.805617915927, 12966.80549791735, 12977.870221297666, 13019.934900325437, 37369.25261494809, 40504.59495405008, 41152.0, 42323.57676423196, 43379.78310108555, 66575.00137497844, 68837.65581172062, 69071.0, 74571.0, 75467.0, 15584.142872141687, 15748.60628484297, 15819.129947852978, 15830.44593439224, 16529.421470248482, 17456.389026384015, 17923.37268195607, 17964.0, 17971.640567188846, 18660.160292787128, 16344.019358838508, 16507.504774856774, 16680.0, 16797.41209057677, 16888.915555422634, 16601.0, 16799.244034018255, 16841.048377877338, 16861.578460538585, 16992.830071699125, 16135.75796363032, 16235.107069111313, 16402.179891005162, 16433.91783041125, 16610.169491525616, 11488.885111148782, 11498.597549085742, 11835.644930652094, 12050.517979281081, 12067.939660301643, 10580.412938059353, 11711.590094346657, 12268.631941041784, 12902.80645790295, 13672.65818354549], "value": 8243.460846747059, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1504.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 117.0, 116.0, 113.0, 114.0, 113.0, 115.0, 113.0, 114.0, 115.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 111.0, 111.0, 148.0, 113.0, 109.0, 111.0, 105.0, 138.0, 114.0], "value": 120.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 198.0, 197.0, 214.0, 216.0, 268.0, 274.0, 199.0, 201.0, 200.0], "value": 216.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 87.0, 61.0, 84.0, 57.0, 58.0, 55.0, 60.0, 76.0, 58.0], "value": 67.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 80.0, 72.0, 70.0, 93.0, 111.0, 76.0, 92.0, 90.0], "value": 84.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 137.0, 89.0, 88.0, 88.0, 89.0, 90.0, 89.0, 88.0], "value": 92.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 99.0, 96.0, 97.0, 95.0, 95.0, 97.0, 96.0, 97.0], "value": 96.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 96.0, 92.0, 90.0, 92.0, 92.0, 128.0, 109.0, 78.0, 150.0], "value": 101.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 52.0, 51.0, 51.0, 50.0, 51.0, 53.0, 51.0, 49.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 60.0, 61.0, 57.0, 55.0, 56.0, 53.0, 55.0, 58.0], "value": 57.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 84.0, 82.0, 83.0, 83.0, 84.0, 82.0, 83.0, 82.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 164.0, 139.0, 135.0, 138.0, 145.0, 145.0, 139.0, 200.0, 124.0], "value": 147.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 189.0, 203.0, 184.0, 222.0, 175.0, 225.0, 169.0, 214.0, 172.0], "value": 194.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 70.0, 71.0, 68.0, 71.0, 72.0, 97.0, 98.0, 93.0], "value": 77.6, "unit": "score"}]}]} 04:48:03 INFO - Return code: 0 04:48:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:48:03 ERROR - # TBPL SUCCESS # 04:48:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:48:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:48:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:48:03 INFO - 2017-04-22 04:48:03.156 screenresolution[1961:16465] starting screenresolution argv=screenresolution get 04:48:03 INFO - 2017-04-22 04:48:03.159 screenresolution[1961:16465] Display 0: 1600x1200x32@60 04:48:03 INFO - 2017-04-22 04:48:03.165 screenresolution[1962:16468] starting screenresolution argv=screenresolution list 04:48:03 INFO - Available Modes on Display 0 04:48:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:48:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:48:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:48:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:48:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:48:03 INFO - Intel Iris: 04:48:03 INFO - Chipset Model: Intel Iris 04:48:03 INFO - Type: GPU 04:48:03 INFO - Bus: Built-In 04:48:03 INFO - VRAM (Dynamic, Max): 1536 MB 04:48:03 INFO - Vendor: Intel (0x8086) 04:48:03 INFO - Device ID: 0x0a2e 04:48:03 INFO - Revision ID: 0x0009 04:48:03 INFO - Displays: 04:48:03 INFO - Display: 04:48:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:48:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:48:03 INFO - Display Serial Number: 1600x1200 60 04:48:03 INFO - Main Display: Yes 04:48:03 INFO - Mirror: Off 04:48:03 INFO - Online: Yes 04:48:03 INFO - Rotation: Supported 04:48:03 INFO - Return code: 0 04:48:03 INFO - Running post-action listener: _resource_record_post_action 04:48:03 INFO - [mozharness: 2017-04-22 11:48:03.407754Z] Finished run-tests step (success) 04:48:03 INFO - Running post-run listener: _resource_record_post_run 04:48:03 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 946176; Write bytes: 514573312; Read time: 10; Write time: 1908 04:48:03 INFO - TinderboxPrint: CPU usage
53.9% 04:48:03 INFO - TinderboxPrint: I/O read bytes / time
946,176 / 10 04:48:03 INFO - TinderboxPrint: I/O write bytes / time
514,573,312 / 1,908 04:48:03 INFO - TinderboxPrint: CPU idle
2,205.1 (46.1%) 04:48:03 INFO - TinderboxPrint: CPU system
957.6 (20.0%) 04:48:03 INFO - TinderboxPrint: CPU user
1,622.9 (33.9%) 04:48:03 INFO - TinderboxPrint: Swap in / out
208,269,312 / 0 04:48:03 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207383040; Write bytes: 204072960; Read time: 16011; Write time: 377 04:48:03 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 937984; Write bytes: 303320064; Read time: 10; Write time: 1501 04:48:03 INFO - Running post-run listener: _upload_blobber_files 04:48:03 INFO - Blob upload gear active. 04:48:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:48:03 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 04:48:03 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:48:03 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:48:04 INFO - (blobuploader) - INFO - Open directory for files ... 04:48:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:48:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:48:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:05 INFO - (blobuploader) - INFO - Done attempting. 04:48:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:48:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:48:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:06 INFO - (blobuploader) - INFO - Done attempting. 04:48:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:48:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:48:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:06 INFO - (blobuploader) - INFO - Done attempting. 04:48:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:48:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:48:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:09 INFO - (blobuploader) - INFO - Done attempting. 04:48:09 INFO - (blobuploader) - INFO - Iteration through files over. 04:48:09 INFO - Return code: 0 04:48:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:48:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:48:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b1a61077331afbfc6929369558f3d3af7572ace718030b393556eb5dcc7f2944d9a4410d25f81702ec28248401decb066de752dcb11038d85e688901f9279a9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d84e5c4c39c78163e063e3b3879abce19182937663cec17151b0f738245ff6cc71b30fe7dc7f25d6451743ee8ffc22a1b3ce51059e309f76fcaca7bd517b532", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a43fe08c0758eb0b18c078cb7adf7cf1e9906c06c9c329a855e6a370f8572cf59715fb3ef18759f1d291ef2985d0e0ef6250eee615cb3f0dc8e1eee359a5882d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52524ad8f77e8960c15c7c2948dd6052a8028d3f111ba1cad9b011861b54d6dddfe7de29691283777454277a71502aae19091fb062cf1d63bd61663b7b804d8b"} 04:48:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:48:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:48:09 INFO - Contents: 04:48:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b1a61077331afbfc6929369558f3d3af7572ace718030b393556eb5dcc7f2944d9a4410d25f81702ec28248401decb066de752dcb11038d85e688901f9279a9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d84e5c4c39c78163e063e3b3879abce19182937663cec17151b0f738245ff6cc71b30fe7dc7f25d6451743ee8ffc22a1b3ce51059e309f76fcaca7bd517b532", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a43fe08c0758eb0b18c078cb7adf7cf1e9906c06c9c329a855e6a370f8572cf59715fb3ef18759f1d291ef2985d0e0ef6250eee615cb3f0dc8e1eee359a5882d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52524ad8f77e8960c15c7c2948dd6052a8028d3f111ba1cad9b011861b54d6dddfe7de29691283777454277a71502aae19091fb062cf1d63bd61663b7b804d8b"} 04:48:09 INFO - Running post-run listener: copy_logs_to_upload_dir 04:48:09 INFO - Copying logs to upload dir... 04:48:09 INFO - mkdir: /builds/slave/test/build/upload/logs 04:48:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.495908 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-22 04:48:09.339590) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-22 04:48:09.342896) ========= 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.USJrvEcwEE/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.mK3bdY4Ttc/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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/Firefox/sha512/9b1a61077331afbfc6929369558f3d3af7572ace718030b393556eb5dcc7f2944d9a4410d25f81702ec28248401decb066de752dcb11038d85e688901f9279a9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d84e5c4c39c78163e063e3b3879abce19182937663cec17151b0f738245ff6cc71b30fe7dc7f25d6451743ee8ffc22a1b3ce51059e309f76fcaca7bd517b532", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a43fe08c0758eb0b18c078cb7adf7cf1e9906c06c9c329a855e6a370f8572cf59715fb3ef18759f1d291ef2985d0e0ef6250eee615cb3f0dc8e1eee359a5882d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52524ad8f77e8960c15c7c2948dd6052a8028d3f111ba1cad9b011861b54d6dddfe7de29691283777454277a71502aae19091fb062cf1d63bd61663b7b804d8b"} build_url:https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011171 build_url: 'https://queue.taskcluster.net/v1/task/LS5Yufv8T_KdVlaWrJUi5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b1a61077331afbfc6929369558f3d3af7572ace718030b393556eb5dcc7f2944d9a4410d25f81702ec28248401decb066de752dcb11038d85e688901f9279a9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d84e5c4c39c78163e063e3b3879abce19182937663cec17151b0f738245ff6cc71b30fe7dc7f25d6451743ee8ffc22a1b3ce51059e309f76fcaca7bd517b532", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a43fe08c0758eb0b18c078cb7adf7cf1e9906c06c9c329a855e6a370f8572cf59715fb3ef18759f1d291ef2985d0e0ef6250eee615cb3f0dc8e1eee359a5882d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52524ad8f77e8960c15c7c2948dd6052a8028d3f111ba1cad9b011861b54d6dddfe7de29691283777454277a71502aae19091fb062cf1d63bd61663b7b804d8b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-22 04:48:09.377658) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-22 04:48:09.377994) ========= 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.USJrvEcwEE/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.mK3bdY4Ttc/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.005298 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-22 04:48:09.426264) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-22 04:48:09.426602) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-22 04:48:09.426991) ========= ========= Total master_lag: 0.17 =========