builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0304 starttime: 1491911025.43 results: success (0) buildid: 20170411030208 builduid: 6849e3716c8547578ebd39dfdef02198 revision: f914d40a48009c5acd1093e9939cc0ec035696dd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 04:43:45.430746) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 04:43:45.431211) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 04:43:45.431489) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 04:43:45.456742) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 04:43:45.457177) ========= 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.QFOQFDXZzA/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.sBKY6oqUKD/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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-11 04:43:45-- 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% 12.5M=0.001s 2017-04-11 04:43:45 (12.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.215498 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 04:43:45.695988) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 04:43:45.696311) ========= 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.QFOQFDXZzA/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.sBKY6oqUKD/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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.020537 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 04:43:45.744537) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-11 04:43:45.744900) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f914d40a48009c5acd1093e9939cc0ec035696dd --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 f914d40a48009c5acd1093e9939cc0ec035696dd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QFOQFDXZzA/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.sBKY6oqUKD/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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-11 04:43:46,010 truncating revision to first 12 chars 2017-04-11 04:43:46,010 Setting DEBUG logging. 2017-04-11 04:43:46,010 attempt 1/10 2017-04-11 04:43:46,010 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f914d40a4800?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-11 04:43:47,394 unpacking tar archive at: mozilla-central-f914d40a4800/testing/mozharness/ program finished with exit code 0 elapsedTime=1.653624 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-11 04:43:47.421994) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 04:43:47.422316) ========= script_repo_revision: f914d40a48009c5acd1093e9939cc0ec035696dd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 04:43:47.422710) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 04:43:47.422990) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 04:43:47.442562) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 57 secs) (at 2017-04-11 04:43:47.442925) ========= /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.QFOQFDXZzA/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.sBKY6oqUKD/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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:43:47 INFO - MultiFileLogger online at 20170411 04:43:47 in /builds/slave/test 04:43:47 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:43:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:43:47 INFO - {'append_to_log': False, 04:43:47 INFO - 'base_work_dir': '/builds/slave/test', 04:43:47 INFO - 'blob_upload_branch': 'Firefox', 04:43:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:43:47 INFO - 'branch': 'Firefox', 04:43:47 INFO - 'buildbot_json_path': 'buildprops.json', 04:43:47 INFO - 'config_files': ('talos/mac_config.py',), 04:43:47 INFO - 'default_actions': ('clobber', 04:43:47 INFO - 'read-buildbot-config', 04:43:47 INFO - 'download-and-extract', 04:43:47 INFO - 'populate-webroot', 04:43:47 INFO - 'create-virtualenv', 04:43:47 INFO - 'install', 04:43:47 INFO - 'run-tests'), 04:43:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:43:47 INFO - 'download_minidump_stackwalk': True, 04:43:47 INFO - 'download_symbols': 'ondemand', 04:43:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:43:47 INFO - 'tooltool.py': '/tools/tooltool.py', 04:43:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:43:47 INFO - '/tools/misc-python/virtualenv.py')}, 04:43:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:43:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:43:47 INFO - 'gecko_profile': False, 04:43:47 INFO - 'gecko_profile_interval': 0, 04:43:47 INFO - 'installer_path': 'installer.exe', 04:43:47 INFO - 'log_level': 'info', 04:43:47 INFO - 'log_name': 'talos', 04:43:47 INFO - 'log_to_console': True, 04:43:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:43:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:43:47 INFO - 'opt_config_files': (), 04:43:47 INFO - 'pip_index': False, 04:43:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:43:47 INFO - 'cmd': ('bash', 04:43:47 INFO - '-c', 04:43:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:43:47 INFO - 'enabled': True, 04:43:47 INFO - 'halt_on_failure': False, 04:43:47 INFO - 'name': 'check_screen_resolution'},), 04:43:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:43:47 INFO - 'cmd': ('bash', 04:43:47 INFO - '-c', 04:43:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:43:47 INFO - 'enabled': True, 04:43:47 INFO - 'halt_on_failure': False, 04:43:47 INFO - 'name': 'check_screen_resolution'},), 04:43:47 INFO - 'run_cmd_checks_enabled': True, 04:43:47 INFO - 'suite': 'dromaeojs-e10s', 04:43:47 INFO - 'system_bits': '32', 04:43:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:43:47 INFO - 'title': 't-yosemite-r7-0304', 04:43:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:43:47 INFO - 'use_talos_json': True, 04:43:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:43:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:43:47 INFO - 'work_dir': 'build'} 04:43:47 INFO - [mozharness: 2017-04-11 11:43:47.829944Z] Running clobber step. 04:43:47 INFO - Running pre-action listener: _resource_record_pre_action 04:43:47 INFO - Running main action method: clobber 04:43:47 INFO - rmtree: /builds/slave/test/build 04:43:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:43:49 INFO - Running post-action listener: _resource_record_post_action 04:43:49 INFO - [mozharness: 2017-04-11 11:43:49.619590Z] Finished clobber step (success) 04:43:49 INFO - [mozharness: 2017-04-11 11:43:49.619765Z] Running read-buildbot-config step. 04:43:49 INFO - Running pre-action listener: _resource_record_pre_action 04:43:49 INFO - Running main action method: read_buildbot_config 04:43:49 INFO - Using buildbot properties: 04:43:49 INFO - { 04:43:49 INFO - "project": "", 04:43:49 INFO - "product": "firefox", 04:43:49 INFO - "script_repo_revision": "production", 04:43:49 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:43:49 INFO - "repository": "", 04:43:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:43:49 INFO - "buildid": "20170411030208", 04:43:49 INFO - "stage_platform": "macosx64", 04:43:49 INFO - "basedir": "/builds/slave/test", 04:43:49 INFO - "nightly_build": "True", 04:43:49 INFO - "buildnumber": 144, 04:43:49 INFO - "slavename": "t-yosemite-r7-0304", 04:43:49 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:43:49 INFO - "platform": "yosemite_r7", 04:43:49 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:43:49 INFO - "branch": "mozilla-central", 04:43:49 INFO - "repo_path": "mozilla-central", 04:43:49 INFO - "revision": "f914d40a48009c5acd1093e9939cc0ec035696dd", 04:43:49 INFO - "pgo_build": "False", 04:43:49 INFO - "builduid": "6849e3716c8547578ebd39dfdef02198", 04:43:49 INFO - "slavebuilddir": "test" 04:43:49 INFO - } 04:43:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:43:49 INFO - Running post-action listener: _resource_record_post_action 04:43:49 INFO - [mozharness: 2017-04-11 11:43:49.623162Z] Finished read-buildbot-config step (success) 04:43:49 INFO - [mozharness: 2017-04-11 11:43:49.623254Z] Running download-and-extract step. 04:43:49 INFO - Running pre-action listener: _resource_record_pre_action 04:43:49 INFO - Running main action method: download_and_extract 04:43:49 INFO - mkdir: /builds/slave/test/build/tests 04:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:49 INFO - trying https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:43:49 INFO - Downloading https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/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:43:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/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:43:51 INFO - Downloaded 1325 bytes. 04:43:51 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:43:51 INFO - Using the following test package requirements: 04:43:51 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:43:51 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:43:51 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:43:51 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:43:51 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:43:51 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:43:51 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:43:51 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:43:51 INFO - u'jsshell-mac.zip'], 04:43:51 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:43:51 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:43:51 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:43:51 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:43:51 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:43:51 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:43:51 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:43:51 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:43:51 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:43:51 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:43:51 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:43:51 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:43:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:43:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:43:51 INFO - Fetch https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:44:22 INFO - retry: attempt #1 caught exception: '' 04:44:22 INFO - retry: Failed, sleeping 60 seconds before retrying 04:45:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #2 04:45:23 INFO - Fetch https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:45:24 INFO - Expected file size: 52581481 04:45:24 INFO - Obtained file size: 52581481 04:45:41 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:45:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:45:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:45:41 INFO - Fetch https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:45:43 INFO - Expected file size: 52581481 04:45:43 INFO - Obtained file size: 52581481 04:45:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:45:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:45:58 INFO - Fetch https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:45:59 INFO - Expected file size: 14405596 04:45:59 INFO - Obtained file size: 14405596 04:46: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:46:00 INFO - trying https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:46:00 INFO - Downloading https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:46:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/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:46:02 INFO - Downloaded 71000482 bytes. 04:46:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:46:02 INFO - mkdir: /builds/slave/test/properties 04:46:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:46:02 INFO - Writing to file /builds/slave/test/properties/build_url 04:46:02 INFO - Contents: 04:46:02 INFO - build_url:https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:46:02 INFO - Running post-action listener: _resource_record_post_action 04:46:02 INFO - Running post-action listener: set_extra_try_arguments 04:46:02 INFO - [mozharness: 2017-04-11 11:46:02.892422Z] Finished download-and-extract step (success) 04:46:02 INFO - [mozharness: 2017-04-11 11:46:02.892544Z] Running populate-webroot step. 04:46:02 INFO - Running pre-action listener: _resource_record_pre_action 04:46:02 INFO - Running main action method: populate_webroot 04:46:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:46:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:46:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:46:02 INFO - '--tppagecycles', 04:46:02 INFO - '7'], 04:46:02 INFO - 'tests': ['tsvgm']}}, 04:46:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:46:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:46:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:46:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:46:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:46:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:46:02 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:46:02 INFO - 'talos_options': ['--disable-e10s'], 04:46:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:46:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:46:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:46:02 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:46:02 INFO - 'talos_options': ['--disable-e10s'], 04:46:02 INFO - 'tests': ['damp', 'tps']}, 04:46:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:46:02 INFO - 'tests': ['damp', 'tps']}, 04:46:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:46:02 INFO - 'tests': ['dromaeo_dom']}, 04:46:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:46:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:46:02 INFO - 'tests': ['basic_compositor_video']}, 04:46:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:46:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:46:02 INFO - 'tests': ['a11yr', 04:46:02 INFO - 'ts_paint', 04:46:02 INFO - 'tpaint', 04:46:02 INFO - 'sessionrestore', 04:46:02 INFO - 'sessionrestore_no_auto_restore', 04:46:02 INFO - 'tabpaint']}, 04:46:02 INFO - 'other-e10s': {'tests': ['a11yr', 04:46:02 INFO - 'ts_paint', 04:46:02 INFO - 'tpaint', 04:46:02 INFO - 'sessionrestore', 04:46:02 INFO - 'sessionrestore_no_auto_restore', 04:46:02 INFO - 'tabpaint']}, 04:46:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:46:02 INFO - 'ts_paint', 04:46:02 INFO - 'tpaint', 04:46:02 INFO - 'sessionrestore', 04:46:02 INFO - 'sessionrestore_no_auto_restore', 04:46:02 INFO - 'tabpaint']}, 04:46:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:46:02 INFO - 'ts_paint', 04:46:02 INFO - 'tpaint', 04:46:02 INFO - 'sessionrestore', 04:46:02 INFO - 'sessionrestore_no_auto_restore', 04:46:02 INFO - 'tabpaint']}, 04:46:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:46:02 INFO - 'tests': ['a11yr', 04:46:02 INFO - 'ts_paint', 04:46:02 INFO - 'tpaint', 04:46:02 INFO - 'sessionrestore', 04:46:02 INFO - 'sessionrestore_no_auto_restore', 04:46:02 INFO - 'tabpaint']}, 04:46:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:46:02 INFO - 'tests': ['a11yr', 04:46:02 INFO - 'ts_paint', 04:46:02 INFO - 'tpaint', 04:46:02 INFO - 'sessionrestore', 04:46:02 INFO - 'sessionrestore_no_auto_restore', 04:46:02 INFO - 'tabpaint']}, 04:46:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:46:02 INFO - 'tests': ['tsvgx', 04:46:02 INFO - 'tsvgr_opacity', 04:46:02 INFO - 'tart', 04:46:02 INFO - 'tscrollx', 04:46:02 INFO - 'cart', 04:46:02 INFO - 'tsvg_static']}, 04:46:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:46:02 INFO - 'tsvgr_opacity', 04:46:02 INFO - 'tart', 04:46:02 INFO - 'tscrollx', 04:46:02 INFO - 'cart', 04:46:02 INFO - 'tsvg_static']}, 04:46:02 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:46:02 INFO - 'talos_options': ['--disable-e10s'], 04:46:02 INFO - 'tests': ['tp5o']}, 04:46:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:46:02 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:46:02 INFO - 'talos_options': ['--disable-e10s', 04:46:02 INFO - '--xperf_path', 04:46:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:46:02 INFO - 'tests': ['tp5n']}, 04:46:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:46:02 INFO - 'talos_options': ['--xperf_path', 04:46:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:46:02 INFO - 'tests': ['tp5n']}}, 04:46:02 INFO - 'talos.zip': {'path': '', 04:46:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:46:02 INFO - Running post-action listener: _resource_record_post_action 04:46:02 INFO - [mozharness: 2017-04-11 11:46:02.910833Z] Finished populate-webroot step (success) 04:46:02 INFO - [mozharness: 2017-04-11 11:46:02.910945Z] Running create-virtualenv step. 04:46:02 INFO - Running pre-action listener: _resource_record_pre_action 04:46:02 INFO - Running main action method: create_virtualenv 04:46:02 INFO - Creating virtualenv /builds/slave/test/build/venv 04:46:02 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:46:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:46:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:46:03 INFO - Using real prefix '/tools/python27' 04:46:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:46:03 INFO - Installing distribute.............................................................................................................................................................................................done. 04:46:06 INFO - Installing pip.................done. 04:46:06 INFO - Return code: 0 04:46:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:46:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:46:06 INFO - Reading from file tmpfile_stdout 04:46:06 INFO - Output received: 04:46:06 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:46:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:46:06 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:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:06 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:46:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:07 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 0x105218b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95f8471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ff6d30>, '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 0x104f1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104febbe8>, '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.QFOQFDXZzA/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.sBKY6oqUKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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:46:07 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:46:07 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:46:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QFOQFDXZzA/Render', 04:46:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:07 INFO - 'HOME': '/Users/cltbld', 04:46:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:07 INFO - 'LOGNAME': 'cltbld', 04:46:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:07 INFO - 'MOZ_NO_REMOTE': '1', 04:46:07 INFO - 'NO_EM_RESTART': '1', 04:46:07 INFO - 'PAGER': '/bin/cat', 04:46:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:07 INFO - 'PWD': '/builds/slave/test', 04:46:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:07 INFO - 'SHELL': '/bin/bash', 04:46:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sBKY6oqUKD/Listeners', 04:46:07 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 04:46:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:07 INFO - 'USER': 'cltbld', 04:46:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:07 INFO - 'XPC_FLAGS': '0x0', 04:46:07 INFO - 'XPC_SERVICE_NAME': '0', 04:46:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:07 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:46:07 INFO - Cleaning up... 04:46:07 INFO - Return code: 0 04:46:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:46:07 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:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:07 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:46:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:07 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 0x105218b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95f8471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ff6d30>, '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 0x104f1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104febbe8>, '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.QFOQFDXZzA/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.sBKY6oqUKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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:46:07 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:46:07 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:46:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QFOQFDXZzA/Render', 04:46:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:07 INFO - 'HOME': '/Users/cltbld', 04:46:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:07 INFO - 'LOGNAME': 'cltbld', 04:46:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:07 INFO - 'MOZ_NO_REMOTE': '1', 04:46:07 INFO - 'NO_EM_RESTART': '1', 04:46:07 INFO - 'PAGER': '/bin/cat', 04:46:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:07 INFO - 'PWD': '/builds/slave/test', 04:46:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:07 INFO - 'SHELL': '/bin/bash', 04:46:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sBKY6oqUKD/Listeners', 04:46:07 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 04:46:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:07 INFO - 'USER': 'cltbld', 04:46:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:07 INFO - 'XPC_FLAGS': '0x0', 04:46:07 INFO - 'XPC_SERVICE_NAME': '0', 04:46:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:07 INFO - Downloading/unpacking psutil>=3.1.1 04:46:07 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:46:07 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:46:07 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:46:07 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:46:07 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:46:07 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:46:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:46:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:46:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:46:12 INFO - Installing collected packages: psutil 04:46:12 INFO - Running setup.py install for psutil 04:46:12 INFO - building 'psutil._psutil_osx' extension 04:46:12 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:46:12 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:46:12 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:46:12 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:46:12 INFO - building 'psutil._psutil_posix' extension 04:46:12 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:46:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:46:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:46:12 INFO - ^ 04:46:12 INFO - 1 warning generated. 04:46:12 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:46:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:46:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:46:12 INFO - Successfully installed psutil 04:46:12 INFO - Cleaning up... 04:46:13 INFO - Return code: 0 04:46:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:46:13 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:46:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:13 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:46:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:13 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 0x105218b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95f8471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ff6d30>, '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 0x104f1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104febbe8>, '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.QFOQFDXZzA/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.sBKY6oqUKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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:46:13 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:46:13 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:46:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QFOQFDXZzA/Render', 04:46:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:13 INFO - 'HOME': '/Users/cltbld', 04:46:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:13 INFO - 'LOGNAME': 'cltbld', 04:46:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:13 INFO - 'MOZ_NO_REMOTE': '1', 04:46:13 INFO - 'NO_EM_RESTART': '1', 04:46:13 INFO - 'PAGER': '/bin/cat', 04:46:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:13 INFO - 'PWD': '/builds/slave/test', 04:46:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:13 INFO - 'SHELL': '/bin/bash', 04:46:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sBKY6oqUKD/Listeners', 04:46:13 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 04:46:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:13 INFO - 'USER': 'cltbld', 04:46:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:13 INFO - 'XPC_FLAGS': '0x0', 04:46:13 INFO - 'XPC_SERVICE_NAME': '0', 04:46:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:46:13 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:46:13 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:46:13 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:46:13 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:46:13 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:46:13 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:46:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:46:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:46:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:46:17 INFO - Installing collected packages: mozsystemmonitor 04:46:17 INFO - Running setup.py install for mozsystemmonitor 04:46:17 INFO - Successfully installed mozsystemmonitor 04:46:17 INFO - Cleaning up... 04:46:17 INFO - Return code: 0 04:46:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:46:17 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:46:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:17 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:46:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:17 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 0x105218b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95f8471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ff6d30>, '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 0x104f1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104febbe8>, '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.QFOQFDXZzA/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.sBKY6oqUKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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:46:17 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:46:17 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:46:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QFOQFDXZzA/Render', 04:46:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:17 INFO - 'HOME': '/Users/cltbld', 04:46:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:17 INFO - 'LOGNAME': 'cltbld', 04:46:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:17 INFO - 'MOZ_NO_REMOTE': '1', 04:46:17 INFO - 'NO_EM_RESTART': '1', 04:46:17 INFO - 'PAGER': '/bin/cat', 04:46:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:17 INFO - 'PWD': '/builds/slave/test', 04:46:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:17 INFO - 'SHELL': '/bin/bash', 04:46:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sBKY6oqUKD/Listeners', 04:46:17 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 04:46:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:17 INFO - 'USER': 'cltbld', 04:46:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:17 INFO - 'XPC_FLAGS': '0x0', 04:46:17 INFO - 'XPC_SERVICE_NAME': '0', 04:46:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:17 INFO - Downloading/unpacking jsonschema==2.5.1 04:46:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:46:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:21 INFO - Downloading functools32-3.2.3-2.tar.gz 04:46:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:46:21 INFO - warning: no files found matching '*.txt' 04:46:21 INFO - no previously-included directories found matching 'build' 04:46:21 INFO - no previously-included directories found matching 'dist' 04:46:21 INFO - no previously-included directories found matching '.git*' 04:46:21 INFO - Installing collected packages: jsonschema, functools32 04:46:21 INFO - Running setup.py install for functools32 04:46:21 INFO - warning: no files found matching '*.txt' 04:46:21 INFO - no previously-included directories found matching 'build' 04:46:21 INFO - no previously-included directories found matching 'dist' 04:46:21 INFO - no previously-included directories found matching '.git*' 04:46:21 INFO - Successfully installed jsonschema functools32 04:46:21 INFO - Cleaning up... 04:46:21 INFO - Return code: 0 04:46:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105218b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95f8471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ff6d30>, '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 0x104f1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104febbe8>, '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.QFOQFDXZzA/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.sBKY6oqUKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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:46:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:46:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:46:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QFOQFDXZzA/Render', 04:46:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:21 INFO - 'HOME': '/Users/cltbld', 04:46:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:21 INFO - 'LOGNAME': 'cltbld', 04:46:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:21 INFO - 'MOZ_NO_REMOTE': '1', 04:46:21 INFO - 'NO_EM_RESTART': '1', 04:46:21 INFO - 'PAGER': '/bin/cat', 04:46:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:21 INFO - 'PWD': '/builds/slave/test', 04:46:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:21 INFO - 'SHELL': '/bin/bash', 04:46:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sBKY6oqUKD/Listeners', 04:46:21 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 04:46:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:21 INFO - 'USER': 'cltbld', 04:46:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:21 INFO - 'XPC_FLAGS': '0x0', 04:46:21 INFO - 'XPC_SERVICE_NAME': '0', 04:46:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:46:21 INFO - Cleaning up... 04:46:21 INFO - Return code: 0 04:46:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105218b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95f8471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ff6d30>, '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 0x104f1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104febbe8>, '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.QFOQFDXZzA/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.sBKY6oqUKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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:46:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:46:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:46:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QFOQFDXZzA/Render', 04:46:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:21 INFO - 'HOME': '/Users/cltbld', 04:46:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:21 INFO - 'LOGNAME': 'cltbld', 04:46:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:21 INFO - 'MOZ_NO_REMOTE': '1', 04:46:21 INFO - 'NO_EM_RESTART': '1', 04:46:21 INFO - 'PAGER': '/bin/cat', 04:46:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:21 INFO - 'PWD': '/builds/slave/test', 04:46:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:21 INFO - 'SHELL': '/bin/bash', 04:46:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sBKY6oqUKD/Listeners', 04:46:21 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 04:46:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:21 INFO - 'USER': 'cltbld', 04:46:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:21 INFO - 'XPC_FLAGS': '0x0', 04:46:21 INFO - 'XPC_SERVICE_NAME': '0', 04:46:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:22 INFO - Downloading/unpacking blobuploader==1.2.4 04:46:22 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:46:22 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:46:22 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:46:22 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:46:22 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:46:22 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:46:24 INFO - Downloading blobuploader-1.2.4.tar.gz 04:46:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:46:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:46:25 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:46:25 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:46:25 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:46:25 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:46:25 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:46:25 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:46:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:46:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:46:25 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:46:25 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:46:25 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:46:25 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:46:25 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:46:25 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:46:26 INFO - Downloading docopt-0.6.1.tar.gz 04:46:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:46:26 INFO - Installing collected packages: blobuploader, requests, docopt 04:46:26 INFO - Running setup.py install for blobuploader 04:46:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:46:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:46:26 INFO - Running setup.py install for requests 04:46:26 INFO - Running setup.py install for docopt 04:46:27 INFO - Successfully installed blobuploader requests docopt 04:46:27 INFO - Cleaning up... 04:46:27 INFO - Return code: 0 04:46:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:46:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:27 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 0x105218b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95f8471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ff6d30>, '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 0x104f1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104febbe8>, '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.QFOQFDXZzA/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.sBKY6oqUKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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:46:27 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:46:27 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:46:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QFOQFDXZzA/Render', 04:46:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:27 INFO - 'HOME': '/Users/cltbld', 04:46:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:27 INFO - 'LOGNAME': 'cltbld', 04:46:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:27 INFO - 'MOZ_NO_REMOTE': '1', 04:46:27 INFO - 'NO_EM_RESTART': '1', 04:46:27 INFO - 'PAGER': '/bin/cat', 04:46:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:27 INFO - 'PWD': '/builds/slave/test', 04:46:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:27 INFO - 'SHELL': '/bin/bash', 04:46:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sBKY6oqUKD/Listeners', 04:46:27 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 04:46:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:27 INFO - 'USER': 'cltbld', 04:46:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:27 INFO - 'XPC_FLAGS': '0x0', 04:46:27 INFO - 'XPC_SERVICE_NAME': '0', 04:46:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:46:27 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-0hQ9wh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:46:27 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-W26Jyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:46:27 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-BPvv4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:46:27 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-4w2yxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:46:27 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-YrOVNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:46:27 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-_jIi_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:46:28 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-nySKc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:46:28 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-zsGADc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:46:28 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-mVCx1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:46:28 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-LcIQZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:46:28 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-K4qVvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:46:28 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-9In6MN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:46:28 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-Dc5nle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:46:28 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-l56oM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:28 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-luCLtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:46:28 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-qauTaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:46:29 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-7b7u81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:46:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:46:29 INFO - Running setup.py install for manifestparser 04:46:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:46:29 INFO - Running setup.py install for mozcrash 04:46:29 INFO - Running setup.py install for mozdebug 04:46:29 INFO - Running setup.py install for mozdevice 04:46:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:46:29 INFO - Running setup.py install for mozfile 04:46:29 INFO - Running setup.py install for mozhttpd 04:46:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:46:30 INFO - Running setup.py install for mozinfo 04:46:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:46:30 INFO - Running setup.py install for mozInstall 04:46:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:46:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:46:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:46:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:46:30 INFO - Running setup.py install for mozleak 04:46:30 INFO - Running setup.py install for mozlog 04:46:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:46:30 INFO - Running setup.py install for moznetwork 04:46:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:46:30 INFO - Running setup.py install for mozprocess 04:46:30 INFO - Running setup.py install for mozprofile 04:46:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:46:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:46:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:46:31 INFO - Running setup.py install for mozrunner 04:46:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:46:31 INFO - Running setup.py install for mozscreenshot 04:46:31 INFO - Running setup.py install for moztest 04:46:31 INFO - Running setup.py install for mozversion 04:46:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:46:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:46:31 INFO - Cleaning up... 04:46:31 INFO - Return code: 0 04:46:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:46: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:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:31 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 0x105218b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95f8471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ff6d30>, '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 0x104f1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104febbe8>, '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.QFOQFDXZzA/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.sBKY6oqUKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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:46:31 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:46:31 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:46:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QFOQFDXZzA/Render', 04:46:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:31 INFO - 'HOME': '/Users/cltbld', 04:46:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:31 INFO - 'LOGNAME': 'cltbld', 04:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:31 INFO - 'MOZ_NO_REMOTE': '1', 04:46:31 INFO - 'NO_EM_RESTART': '1', 04:46:31 INFO - 'PAGER': '/bin/cat', 04:46:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:31 INFO - 'PWD': '/builds/slave/test', 04:46:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:31 INFO - 'SHELL': '/bin/bash', 04:46:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sBKY6oqUKD/Listeners', 04:46:31 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 04:46:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:31 INFO - 'USER': 'cltbld', 04:46:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:31 INFO - 'XPC_FLAGS': '0x0', 04:46:31 INFO - 'XPC_SERVICE_NAME': '0', 04:46:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:46:31 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-j62E5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:46:32 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:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:46:32 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-98IYPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:46:32 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:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:46:32 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-YyKdWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:46:32 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:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:46:32 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-h2Gl5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:46:32 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:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:46:32 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-94yWbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:46:32 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:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:46:32 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-DcsY10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:46:32 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:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:46:32 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-GwniZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:46:32 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:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:46:32 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-jsE3C8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:46:32 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:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:46:32 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-Zmh1te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:46:32 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:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:46:32 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-Ca6dC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:46:33 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:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:46:33 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-BqsMJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:46:33 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:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:46:33 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-tRVOGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:46:33 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:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:46:33 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-XF3ifU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:46:33 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:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:46:33 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-yBbnnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:46:33 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:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:33 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-6id5Og-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:33 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:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:46:33 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-36qH0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:46:33 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:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:46:33 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-hlU6r0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:46:33 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:46:33 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:46:33 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:46:33 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:46:33 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:46:33 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:46:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:37 INFO - Downloading blessings-1.6.tar.gz 04:46:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:46:37 INFO - Installing collected packages: blessings 04:46:37 INFO - Running setup.py install for blessings 04:46:37 INFO - Successfully installed blessings 04:46:37 INFO - Cleaning up... 04:46:37 INFO - Return code: 0 04:46:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:46:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:46:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:46:37 INFO - Reading from file tmpfile_stdout 04:46:37 INFO - Current package versions: 04:46:37 INFO - blessings == 1.6 04:46:37 INFO - blobuploader == 1.2.4 04:46:37 INFO - docopt == 0.6.1 04:46:37 INFO - functools32 == 3.2.3-2 04:46:37 INFO - jsonschema == 2.5.1 04:46:37 INFO - manifestparser == 1.1 04:46:37 INFO - mozInstall == 1.12 04:46:37 INFO - mozcrash == 1.0 04:46:37 INFO - mozdebug == 0.1 04:46:37 INFO - mozdevice == 0.50 04:46:37 INFO - mozfile == 1.2 04:46:37 INFO - mozhttpd == 0.7 04:46:37 INFO - mozinfo == 0.9 04:46:37 INFO - mozleak == 0.1 04:46:37 INFO - mozlog == 3.4 04:46:37 INFO - moznetwork == 0.27 04:46:37 INFO - mozprocess == 0.25 04:46:37 INFO - mozprofile == 0.28 04:46:37 INFO - mozrunner == 6.13 04:46:37 INFO - mozscreenshot == 0.1 04:46:37 INFO - mozsystemmonitor == 0.3 04:46:37 INFO - moztest == 0.8 04:46:37 INFO - mozversion == 1.4 04:46:37 INFO - psutil == 3.1.1 04:46:37 INFO - requests == 1.2.3 04:46:37 INFO - wsgiref == 0.1.2 04:46:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:46: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:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:37 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 0x105218b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95f8471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ff6d30>, '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 0x104f1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104febbe8>, '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.QFOQFDXZzA/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.sBKY6oqUKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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:46:37 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:46:37 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:46:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QFOQFDXZzA/Render', 04:46:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:37 INFO - 'HOME': '/Users/cltbld', 04:46:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:37 INFO - 'LOGNAME': 'cltbld', 04:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:37 INFO - 'MOZ_NO_REMOTE': '1', 04:46:37 INFO - 'NO_EM_RESTART': '1', 04:46:37 INFO - 'PAGER': '/bin/cat', 04:46:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:37 INFO - 'PWD': '/builds/slave/test', 04:46:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:37 INFO - 'SHELL': '/bin/bash', 04:46:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sBKY6oqUKD/Listeners', 04:46:37 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 04:46:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:37 INFO - 'USER': 'cltbld', 04:46:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:37 INFO - 'XPC_FLAGS': '0x0', 04:46:37 INFO - 'XPC_SERVICE_NAME': '0', 04:46:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:38 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:46:38 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:46:38 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:46:38 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:46:38 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:46:38 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:46:38 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:46:38 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:46:38 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:46:38 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:46:38 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:46:38 INFO - Cleaning up... 04:46:38 INFO - Return code: 0 04:46:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:46: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:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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:46:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105218b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10521b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95f8471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ff6d30>, '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 0x104f1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104febbe8>, '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.QFOQFDXZzA/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.sBKY6oqUKD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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:46: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', 'jsonschema'] in /builds/slave/test/build 04:46: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 jsonschema 04:46:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QFOQFDXZzA/Render', 04:46:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:38 INFO - 'HOME': '/Users/cltbld', 04:46:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:38 INFO - 'LOGNAME': 'cltbld', 04:46:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:38 INFO - 'MOZ_NO_REMOTE': '1', 04:46:38 INFO - 'NO_EM_RESTART': '1', 04:46:38 INFO - 'PAGER': '/bin/cat', 04:46:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:38 INFO - 'PWD': '/builds/slave/test', 04:46:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:38 INFO - 'SHELL': '/bin/bash', 04:46:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sBKY6oqUKD/Listeners', 04:46:38 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 04:46:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:38 INFO - 'USER': 'cltbld', 04:46:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:38 INFO - 'XPC_FLAGS': '0x0', 04:46:38 INFO - 'XPC_SERVICE_NAME': '0', 04:46:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:46:38 INFO - Cleaning up... 04:46:38 INFO - Return code: 0 04:46:38 INFO - Running post-action listener: _resource_record_post_action 04:46:38 INFO - Running post-action listener: _start_resource_monitoring 04:46:38 INFO - Starting resource monitoring. 04:46:38 INFO - [mozharness: 2017-04-11 11:46:38.465559Z] Finished create-virtualenv step (success) 04:46:38 INFO - [mozharness: 2017-04-11 11:46:38.466530Z] Running install step. 04:46:38 INFO - Running pre-action listener: _resource_record_pre_action 04:46:38 INFO - Running main action method: install 04:46:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:46:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:46:38 INFO - Reading from file tmpfile_stdout 04:46:38 INFO - Detecting whether we're running mozinstall >=1.0... 04:46:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:46:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:46:38 INFO - Reading from file tmpfile_stdout 04:46:38 INFO - Output received: 04:46:38 INFO - Usage: mozinstall [options] installer 04:46:38 INFO - Options: 04:46:38 INFO - -h, --help show this help message and exit 04:46:38 INFO - -d DEST, --destination=DEST 04:46:38 INFO - Directory to install application into. [default: 04:46:38 INFO - "/builds/slave/test"] 04:46:38 INFO - --app=APP Application being installed. [default: firefox] 04:46:38 INFO - mkdir: /builds/slave/test/build/application 04:46:38 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:46:38 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:46:58 INFO - Reading from file tmpfile_stdout 04:46:58 INFO - Output received: 04:46:58 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:46:58 INFO - Running post-action listener: _resource_record_post_action 04:46:58 INFO - [mozharness: 2017-04-11 11:46:58.692247Z] Finished install step (success) 04:46:58 INFO - [mozharness: 2017-04-11 11:46:58.692397Z] Running run-tests step. 04:46:58 INFO - Running pre-action listener: _resource_record_pre_action 04:46:58 INFO - Running main action method: run_tests 04:46:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:46:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:46:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:46:58 INFO - 2017-04-11 04:46:58.707 screenresolution[1910:16389] starting screenresolution argv=screenresolution get 04:46:58 INFO - 2017-04-11 04:46:58.709 screenresolution[1910:16389] Display 0: 1600x1200x32@60 04:46:58 INFO - 2017-04-11 04:46:58.715 screenresolution[1911:16392] starting screenresolution argv=screenresolution list 04:46:58 INFO - Available Modes on Display 0 04:46:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:46:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:46:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:46:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:46:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:46:58 INFO - Intel Iris: 04:46:58 INFO - Chipset Model: Intel Iris 04:46:58 INFO - Type: GPU 04:46:58 INFO - Bus: Built-In 04:46:58 INFO - VRAM (Dynamic, Max): 1536 MB 04:46:58 INFO - Vendor: Intel (0x8086) 04:46:58 INFO - Device ID: 0x0a2e 04:46:58 INFO - Revision ID: 0x0009 04:46:58 INFO - Displays: 04:46:58 INFO - Display: 04:46:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:46:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:46:58 INFO - Display Serial Number: 1600x1200 60 04:46:58 INFO - Main Display: Yes 04:46:58 INFO - Mirror: Off 04:46:58 INFO - Online: Yes 04:46:58 INFO - Rotation: Supported 04:46:58 INFO - Return code: 0 04:46:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:46:58 INFO - Python 2.7.3 04:46:58 INFO - Return code: 0 04:46:58 INFO - grabbing minidump binary from tooltool 04:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:58 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 0x104ff6d30>, '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 0x104f1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104febbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:46:58 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:46:58 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:46:58 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:46:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:46:59 INFO - Return code: 0 04:46:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:46:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:46:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:46:59 INFO - ENV: RUST_BACKTRACE is now 1 04:46:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:46:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:46:59 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/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0304', '--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:46:59 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/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0304 --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:46:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QFOQFDXZzA/Render', 04:46:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:59 INFO - 'HOME': '/Users/cltbld', 04:46:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:59 INFO - 'LOGNAME': 'cltbld', 04:46:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:46:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:59 INFO - 'MOZ_NO_REMOTE': '1', 04:46:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:46:59 INFO - 'NO_EM_RESTART': '1', 04:46:59 INFO - 'PAGER': '/bin/cat', 04:46:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:59 INFO - 'PWD': '/builds/slave/test', 04:46:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:46:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:59 INFO - 'RUST_BACKTRACE': '1', 04:46:59 INFO - 'SHELL': '/bin/bash', 04:46:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sBKY6oqUKD/Listeners', 04:46:59 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 04:46:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:59 INFO - 'USER': 'cltbld', 04:46:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:59 INFO - 'XPC_FLAGS': '0x0', 04:46:59 INFO - 'XPC_SERVICE_NAME': '0', 04:46:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:59 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/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0304', '--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:46:59 INFO - mozversion application_buildid: 20170411030208 04:46:59 INFO - mozversion application_changeset: f914d40a48009c5acd1093e9939cc0ec035696dd 04:46:59 INFO - mozversion application_display_name: Nightly 04:46:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:46:59 INFO - mozversion application_name: Firefox 04:46:59 INFO - mozversion application_remotingname: firefox 04:46:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:46:59 INFO - mozversion application_vendor: Mozilla 04:46:59 INFO - mozversion application_version: 55.0a1 04:46:59 INFO - mozversion platform_buildid: 20170411030208 04:46:59 INFO - mozversion platform_changeset: f914d40a48009c5acd1093e9939cc0ec035696dd 04:46:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:46:59 INFO - mozversion platform_version: 55.0a1 04:46:59 INFO - using testdate: 1491911219 04:46:59 INFO - actual date: 1491911219 04:46:59 INFO - starting webserver on 'localhost:52483' 04:46:59 INFO - SUITE-START | Running 2 tests 04:46:59 INFO - TEST-START | dromaeo_css 04:46:59 INFO - operating with platform_type : mac_ 04:46:59 INFO - Initialising browser for dromaeo_css test... 04:46:59 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmp8U3Rj5/profile http://localhost:52483/getInfo.html) 04:47:02 INFO - PID 1921 | 2017-04-11 04:47:02.633 plugin-container[1924:16566] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 04:47:02 INFO - PID 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:47:02 INFO - PID 1921 | 2017-04-11 04:47:02.634 plugin-container[1924:16566] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b23, name = 'com.apple.CFPasteboardClient' 04:47:02 INFO - PID 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:47:02 INFO - PID 1921 | 2017-04-11 04:47:02.634 plugin-container[1924:16566] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:47:02 INFO - PID 1921 | __metrics Screen width/height:1600/1200 04:47:02 INFO - PID 1921 | colorDepth:24 04:47:02 INFO - PID 1921 | Browser inner width/height: 1024/689 04:47:02 INFO - PID 1921 | __metrics 04:47:02 ERROR - PID 1921 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 04:47:03 INFO - TEST-INFO | 1921: exit 0 04:47:03 INFO - Browser initialized. 04:47:03 INFO - Running cycle 1/1 for dromaeo_css test... 04:47:03 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmp8U3Rj5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:47:06 INFO - PID 1925 | 2017-04-11 04:47:06.409 plugin-container[1926:16695] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 04:47:06 INFO - PID 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:47:06 INFO - PID 1925 | 2017-04-11 04:47:06.409 plugin-container[1926:16695] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 04:47:06 INFO - PID 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:47:06 INFO - PID 1925 | 2017-04-11 04:47:06.409 plugin-container[1926:16695] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:47:16 INFO - PID 1925 | RSS: Main: 234594304 04:47:16 INFO - PID 1925 | 04:50:19 INFO - PID 1925 | Cycle 1(1): loaded http://localhost:52483/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52483/tests/dromaeo/cssquery-ext.html) 04:50:21 INFO - PID 1925 | RSS: Main: 253206528 04:50:21 INFO - PID 1925 | 04:52:03 INFO - PID 1925 | ************************* 04:52:03 INFO - PID 1925 | A coding exception was thrown and uncaught in a Task. 04:52:03 INFO - PID 1925 | 04:52:03 ERROR - PID 1925 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:52:03 INFO - PID 1925 | Full stack: 04:52:03 INFO - PID 1925 | ************************* 04:52:03 INFO - PID 1925 | ************************* 04:52:03 INFO - PID 1925 | A coding exception was thrown and uncaught in a Task. 04:52:03 INFO - PID 1925 | 04:52:03 ERROR - PID 1925 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:52:03 INFO - PID 1925 | Full stack: 04:52:03 INFO - PID 1925 | ************************* 04:52:03 INFO - PID 1925 | ************************* 04:52:03 INFO - PID 1925 | A coding exception was thrown and uncaught in a Task. 04:52:03 INFO - PID 1925 | 04:52:03 ERROR - PID 1925 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:52:03 INFO - PID 1925 | Full stack: 04:52:03 INFO - PID 1925 | ************************* 04:52:03 INFO - PID 1925 | ************************* 04:52:03 INFO - PID 1925 | A coding exception was thrown and uncaught in a Task. 04:52:03 INFO - PID 1925 | 04:52:03 ERROR - PID 1925 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:52:03 INFO - PID 1925 | Full stack: 04:52:03 INFO - PID 1925 | ************************* 04:53:22 INFO - PID 1925 | Cycle 1(1): loaded http://localhost:52483/tests/dromaeo/cssquery-ext.html (next: http://localhost:52483/tests/dromaeo/cssquery-jquery.html) 04:53:23 INFO - PID 1925 | RSS: Main: 262033408 04:53:23 INFO - PID 1925 | 04:56:25 INFO - PID 1925 | Cycle 1(1): loaded http://localhost:52483/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52483/tests/dromaeo/cssquery-mootools.html) 04:56:26 INFO - PID 1925 | RSS: Main: 273113088 04:56:26 INFO - PID 1925 | 04:59:27 INFO - PID 1925 | Cycle 1(1): loaded http://localhost:52483/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52483/tests/dromaeo/cssquery-prototype.html) 04:59:28 INFO - PID 1925 | RSS: Main: 266055680 04:59:28 INFO - PID 1925 | 05:02:31 INFO - PID 1925 | Cycle 1(1): loaded http://localhost:52483/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52483/tests/dromaeo/cssquery-yui.html) 05:02:31 INFO - PID 1925 | RSS: Main: 276738048 05:02:31 INFO - PID 1925 | 05:05:33 INFO - PID 1925 | Cycle 1(1): loaded http://localhost:52483/tests/dromaeo/cssquery-yui.html (next: http://localhost:52483/tests/dromaeo/cssquery-dojo.html) 05:05:33 INFO - PID 1925 | RSS: Main: 270487552 05:05:33 INFO - PID 1925 | 05:05:33 INFO - PID 1925 | __start_tp_report 05:05:33 INFO - PID 1925 | _x_x_mozilla_page_load 05:05:33 INFO - PID 1925 | _x_x_mozilla_page_load_details 05:05:33 INFO - PID 1925 | |i|pagename|runs| 05:05:33 INFO - PID 1925 | |0;dojo.html;2817.2815931937357;2982.5824384586163;3338.349021940721;3563.2517171394006;3563.7149028077756;6141.9692901535545;6213.53398495113;6223.719932603032;6229.283632382278;6259.906101408483;6152.077188421739;6153.938460615393;6173.907391389122;6197.163382943301;6271;5451.781928722866;5477.561795056406;5487.368952570446;5564.554835613161;5595.888082238353;5401.756920938567;5521.061419558685;5554.777808887639;5587.329520457531;5627.493525582697;5371.194320851864;5455.399906010336;5475.151351540518;5520.91718624223;5529.364123125852;5442.9727851360885;5471.7264136793;5485.807996720136;5485.890282194334;5514.3658479275;5406.107992181324;5426.348838139448;5452.263944367539;5473.0969390050595;5481.123020316731;5068.746562671851;5391.703456309901;5713.600167958876;6148.1085242639565;6151.2618485781995;6672.666366681647;6689.397954184147;6696.330366963313;6697.095892054559;6697.799066028027;6773.864522709568;6776;6777.322267773183;6788.524803263774;6816.659167041628;4848.95151048485;6165.075238714139;6290.716917738713;6306.400891915261;6325.082862984841;6832.214295355998;6886.655667216719;6915.619640919697;6942.479314051467;6950.79147625572;5597.020521408737;6320.873582528416;6483.059956306309;6485.221773387224;6550.639714815731;5289.365276166807;5466.426025267369;6588.176477940257;6591.143151390384;6669.066330713704;6651.66741662915;6655.6339401332425;6659.600423974577;6668.666566671647;6687.063811066455;6404.135441715402;6426.260979987268;6669.1996960364095;6672.899906501407;6728.856094463953;4638.698484598491;5350.491703288181;5350.8127215547265;5395.973020134874;5433.103537916279;3982.4225487304757;4721.834735784231;4741.596964258008;4743.980044290494;4790.928136077892;6120.571559990758;6313.9368606314265;6316.147320111726;6322;6331.873362532723;6482.57863238888;6506.902396464056;6637.867242655217;6709.765158219537;6746.595204287661;6246.250449946036;6305.085762564494;6309.716062777186;6311.084892690534;6333.461655759312;5885.852853678693;5917.852053698692;5925.466707996301;5928.911066334009;5978.790742324085;5761.683107429131;6550.181227346582;6716.5634233774645;6823.999999999901;6849.1096157499205;6793.592384456751;6800.693968771516;6807.251202367636;6809.421199198124;6827.112475378169;6815;6860.691268892812;6879.140107486564;6881.690323935335;6922.688479018557;6437.613743175424;6486.416222539994;6554;6628.734850606114;6706.228783689938;5222.7127507987425;6707.698153583003;6780.118584583973;6786.76246331376;6818.352256535622;6543.312952139957;6547.410733033859;6548.574342667712;6559.442447392025;6564.606123632598;6249.843753906189;6388.1376014237485;6403.51973601991;6426.775062872777;6542.182227221598;6313.210848643919;6314.652694101868;6426.550141490051;6432.388923052303;6461.19235095613;5428.212909128075;6569.671516424255;6580.638064906476;6581.769638062645;6599.340065993554;6190.597611155261;6204.255489341308;6312.337204593635;6354.809355719336;6373.521985850987 05:05:33 INFO - PID 1925 | |1;ext.html;732.2128711634994;802.5024484819411;823.682882090395;1254.6737848159476;1271.7583659104776;31432.52851207234;36248.7312944047;37856.62143378565;37874;37886.621133788685;37502.24995500089;37965;37996.43005354915;38043;38057.23885522288;8009.559474228915;8156.469829461066;8431.747047588551;8620.65517379304;8693.391462597621;8580.39937204396;8722.781930451725;8728.43265187764;8756.649734010633;8771.386002979794;8688.522131282776;8688.522131282776;8754.343424243207;8757.168069033432;8795;8679.218870301704;8698.260647844942;8702.390832641717;8734.737957861274;8753.255973242281;8508;8550.52972086535;8559.358048146414;8559.572021398904;8599.011113721877;30357.089287321414;36917.89246322615;37235;37418.25163496715;37514.81242593769;3565.714742820568;3604.53141091659;3609.350316942949;3610.7472476926628;3612.981935090334;30428.087157385315;30741;30773;30798.692013079806;30856;3122.625284965796;3130.686931306851;3144.371125774854;3165.5568220449154;3173.841307934594;216538.91730541404;233384.66615333798;238251.8087409569;278223.6088819543;279491;47546.5245347549;47569.286460703115;47596;47683;47896.28155577669;48986.75506622515;49154.26268605974;49182.75408622934;49253.014939701;49291.753541232065;13835.308234588432;13909.791353129509;13971.720565588632;13989.440422382992;13995.020348575503;11380.658580242605;11520.020798232244;11531.827022594667;11544.538218471167;11566.363849988164;10852.891471085188;13167.473301068041;13188.604341869759;13309.93345033288;13312.800307995389;5877.9118313225335;5881.647221139162;5887.234659494238;5904.527637788967;5938.584299099021;872.2150064941477;1002.2783595811004;1007.5768177365524;1014.9340292880939;1045.8012977534245;18530.722039169425;19558.70661940072;21990.89004554999;23397.181098661466;23949.760502394754;1051.6897515233024;1116.972075698114;1130.728625129963;1140.8402823604702;1141.6746227325173;11768.764624707457;12214.434968656504;12260.877391225973;12348.506059757508;12352.876471235353;345376;348505;350080.4991950099;350374;350672;53984.03087830129;54344.45655543473;54389.72805135949;54659.906801864534;54679;1491.8209814822292;1523.5505525870117;1527.5493729349869;1540.0605630565346;1541.3295216580823;23571.76428235696;23609.881950590137;23680.644790327813;23715.52568948577;23746.762532374454;37238.62761372352;37277.62722372742;37441.06397340088;37451.06372340713;37465.4380184292;291939.5403022971;293587;294205.5289723538;294768;295056;26836.060737874082;32492.512612310362;33049.669503305624;33860.830695846365;34107.248202697185;43664.56335436605;44395.77802110969;44732.552674474144;44833.61996560434;44975;29240.415191696473;29867.25331866634;29938.95213667511;30704.692953070182;30879.2280192997;29817.570979336786;30325.848370758005;30494.08517744471;30898.073057808302;30998.690013099582;35593.28813423665;35713.64286357103;36331;36335.81832090928;36479;30444;30692;31161.53257701091;31288.37423251477;31412.842935786088;22261.888690556443;22539.211127610455;23129.88435057814;23573.939172736926;24284.63573046441 05:05:33 INFO - PID 1925 | |2;jquery.html;385.23915267346985;427.3333599584646;434.34197191255237;436.93227549729784;459.2743465324787;19773.40679779607;21342.35972920811;21959.011844466997;22187.889060554695;22193.55612887741;21623.891880540614;22503.987320570574;23266.069357225733;23584.64623030656;23661.526769464643;5983.670898100603;6228.750849966019;6246.125542424064;6247;6247.000479923213;5879.88240235195;6216.285127210361;6217.378262173769;6282.874342513147;6287.182666253403;6197.101420294055;6237.812865614016;6245.5003599712145;6248.593841400295;6266.624002559861;5120.974395128038;6186.628802271878;6189.6286222826775;6193.287771906221;6201.224846894138;5417.539509141728;5431.565474762009;5478.068728316156;5502.2296878436655;5513.20058591506;26590.936362545475;26960.59559106615;27044.918203271845;27226.863865680545;27381.17856464309;2216.556688662274;2273.897674604647;2283.931482055541;2284.4745708486976;2286.8337148054447;17289.443950044555;17372.82627173725;17614.471565853044;17748.91125544364;18014.82903611274;2154.881481518516;2189.6496560550236;2223.2218723446827;2229.5317983223545;2232.6427771556473;144752.27623861918;145578.5442145586;147624;147716;149336.99999999892;12029.458674359676;12630.81053784194;12651.367431628381;12652.557160499338;12668.36658167088;12629;13184.143030702973;16408.83591164073;16409.753853692204;16456.506304810875;8751.824963500821;8961.23829474489;8961.910380896108;9004.054574269738;9120.179183873483;8511.702090426805;8521.914780852112;8556.828863422697;8668;8690.652373904974;6581.74946760114;7806.882896756553;7831.921680783234;7898.565578893216;7930.246626570467;1830.5789668376349;2161.6109100362087;2176.238316589213;2188.8139508141985;2365.6096744037386;949.188443880486;960.7261930349816;979.1432496565506;1001.5593139018808;1002.6089824968268;19598.510037249183;19874.105665245097;20747.170113195603;20974.703272988885;21115.788842111382;850.8468475674314;911.9330383451378;970.708787363788;982.1259079419323;1020.5305559442739;9380.592911063259;9639.759006024766;10116.494175291205;10838.241323107464;10891.996453215736;621212.8939355274;650639;657390;663408;665113;124835;142941;148695;165232;167262;1129.4296380327883;1171.2328424881546;1195.0559058343986;1196.8264601632713;1198.4007996002;22049.684783097542;22234.555308894054;22278.108875644793;22419;22482.5503489926;63854.04218936813;64883.67558162179;65274;65506.34493655003;66174.34564135743;91915;91990.54004729934;92450.53774731084;92753;93357.53321233351;8856.095939373066;8859.424137431046;8993.685221017233;9247.213986810928;9411.670591529264;11679.591214307458;13485.662858428617;14695.412183512968;14811.703765924405;15271.92364038217;11850.525978960744;12098.455569499569;12115.818262726243;12281.815772763595;12293.262404255773;11563.132765042486;11603.88396116028;11663.708407289885;11736.823947640964;11861.288322700666;11989.98085162771;12388.876111239133;12511.687207819878;12562.87437125617;12748.10763246601;8599.269062129539;8874.467531948103;9003.864942026006;9300.39547429415;9321.906780932375;11879.524819007143;12006.159568830099;12300.261984280973;12310.569130080405;12401.317927514074 05:05:33 INFO - PID 1925 | |3;mootools.html;1365.4060483689593;1420.183394548135;1423.943042278309;1428.4000719697726;1453.5493996860973;11403.48684309206;11713.355765432898;11849.052075833933;11875.16873818832;12199.268043917371;12041;12045.518179272818;12157.209781364205;12189.963853072499;12245;2491.6137998610197;2529.936751581216;2540.3395117269456;2544.7709706126443;2546;2459.385153711572;2507.272890861657;2550.132954795369;2551.413174969749;2561;2470.493548822496;2524.823262371635;2555.9744402556016;2559.5008973250224;2568.743125687435;2458.287096741934;2513.7611926866916;2526.0401047601886;2549.961750573743;2552.0174732728033;2434.318390850565;2514.924552263435;2526.759957804006;2551.9489610207693;2553.885075171622;11446.484908179069;11975.820362694567;12024.639260822189;12034.759304813855;12048;5058.721770302632;6728.966355168192;6814.761483348108;6863.38229559342;6880.518363714542;4115.52671442785;4356.6514678825615;4443.42235509385;4446.3108218226225;4474.619657329131;1242.1243023668292;1270.733146039333;1284.9743005139937;1315.5790147152916;1318.044417797099;13305.334733263298;15068.623284417868;15619.219039048116;16655.50033498997;16679.91660041704;5531.225628412026;5706.087026075808;5889.322727886348;5920.526357891358;5974.283086029617;12367.6908077297;12408.007359411406;12420.937895310466;12477.500899964081;12660.81008784869;5876.7649294028615;6460.578672692094;6605.603663780189;6685.665716714145;6688.765893193715;6020.849478763066;6206.286277078194;6263.968680156661;6272.341404152589;6289.213848268966;4377.387165796791;4477.082198149372;4542.386777785023;4545.863624091283;4569.611583015414;1310.9868901310865;1334.299492766286;1337.6789570503204;1353.3571553512004;1356.3896246688832;777.3780975219802;798.0498707001011;802.7471346525825;805.7743831727008;815.6533473273835;4745.311929770164;6176.292035310173;6178.752849885954;6423.229212494531;6490.545661803723;590.5216774412739;601.9668918209453;611.0589691874463;615.092738211135;615.4215037864393;3527.365074286653;4018.9196216075516;4281.892952676208;4361.738295702268;4506.954930450719;14559.636009099646;14682.339294731762;15258.46595369179;15304.387824486896;15360.923195384175;15173.24133793328;15236.847631523764;15286.464973725866;15287.159206243763;15324.387024518894;10792.406417647106;10980.505877235384;11080.833787493195;11088.002079812693;11115.110791136689;6198.256209254738;6683.966580167068;6894.793156205322;6941.271166527644;6990;11063;13668.863311366758;13681.973851960944;13950.860491395362;14040.578782636538;13501.324933753076;13823.86176138266;13910.374033168735;14251.429942802171;14298.785518217443;9449.244060475283;9588.56851441673;9663;9748.220142388735;9777;10887.183461240596;10992.945035274772;11004.174686898676;11016.614418495314;11031;8986;9249.352545321763;9351;9387.953060234928;9415.62337506492;9124.680636177967;9149.725508234764;9153.313501487548;9193.954030229806;9208.079192080739;8200.507969521848;8236.752897413087;8249.958750206211;8326.875096873673;8339.041010283894;5340.626156169031;5685.431456854447;6056.7274472649715;6124.969375153096;6151.0773383992755;6149.7847575334645;6177.96911015442;6180.858824587627;6183.103449999815;6227.19046523949 05:05:33 INFO - PID 1925 | |4;prototype.html;342.9468432392979;370.2447008103469;384.8325978199483;400.7134898547538;403.5339183243354;5098.464661210575;5960.761569537219;6079.878402431948;6086.8478288042825;6094;4415.845445409412;5171.4311425743235;5777.855553611152;5957.731902064406;5995.340512543617;3681.987453450309;3913.7456065355805;3940.778358708795;4582.564656357641;4586.990862010352;3566.037169964108;3736.7197460190596;4574.634029277667;4584.151931892594;4619.8450387403145;4322.632576231024;4493.168763778695;4538.886527836814;4557.179707652621;4567;3947.50656167979;4235.894102647428;4505.52691967342;4515.525869783658;4525.275955847065;3993.5008123984503;4013.9397909031386;4026.396040593905;4034;4042.191561687674;4696.8590942271785;4938.765308672831;5663.7168141593165;5930.3773103823905;5945.256842894638;4612.7776139323105;5094.668846525001;5127.743612819381;5251.737413129328;5312.415634280226;5314.654547454403;6438.9375753000695;6442.806715798533;6466.0300954856675;6478.514111441661;4214.967333003403;4262.573742625744;4276.182622385474;4327.697061205717;4359.782010899474;6502.7398904043785;6742.235683369795;6744.865102697968;6870.862582748317;6887.965560172217;3714.33142034431;3734.8319325630414;4457.77711114443;4461.92913700716;4465.754383508938;3740.588535261119;3785.564660064073;4025.1547175092974;4516.480604730433;4542.364069030338;4874.951250487521;5105.978804239167;5213.296205012275;5313.946860531345;5340.385855626575;5864.736086876015;6463.547551671432;6499.8700025999215;6505.243584232326;6544.476441884638;1352.4387379237703;1380.9240491772848;1401.873831355183;1510.4033906606828;1537.7462718651298;913.6979803779587;946.342292106979;962.8663130125001;1015.7714514234239;1016.0745387490664;5160.8904085621025;5570.972145139248;6189.616997456012;6189.907151392733;6203.2556093268195;5925.34821169671;6105.694715264219;6409.391107844747;6854.280300568468;6926.515143939876;4758.643101767381;5380.229181971288;5463.453654634584;5705.286839145107;6193.969030154821;3389.6610338965907;3523.0487768302414;3528.382533056756;3534.1871369584624;3538.221591249921;6115.4190351916495;6307.990721484541;6966.164060312693;6972.302769722987;6988.965055174692;5687.03320435527;6604.174478190226;6907.309269073053;7043.788686339316;7114.336116899801;5115.846524604268;6169.9383006170265;6901.75843845463;7054.541454805524;7059.294070593002;6779.694913728993;6798.626075565891;6811.931880681328;6819.965900170468;6824.658767061726;6559.967200164159;6708.094407254958;6710.932890671031;6733.730650773914;6740.325967403221;5674.886502269849;6274.843128921813;6707.42986846104;6872.209695884837;7013.333733295329;4103.4050062741335;4296.978515107405;4448.243798554189;4464.799084041161;4476.485204201558;6580.341965803382;6741;6868.7252509901045;6878.518503704692;6889.827754305982;5113.718745469035;6414.583052101599;6537.900437658634;6543.149390579194;6550.705218265285;5473.31583552056;6190.380961903774;6277.372262773869;6587.505937054836;6601.207855057424;6122.102148334031;6301.212348456443;6331.5251356147555;6356.68216589178;6365.427111559982;6494.6361264133175;6527.379898909596;6551.868962620625;6572.112764776694;6608.008798680236;5777.768889244383;5845.269341332333;6162.599431036968;6386.489080873426;6396.6183164530585 05:05:33 INFO - PID 1925 | |5;yui.html;807.7172989453688;843.991560084399;847.5973912391614;858.9735266356703;860.6342304520579;15822.68354632908;16451.34194632216;16568.66862662746;17911.73132403015;18670.81329186711;17486.21312040958;17532.912335438305;17646.735298970492;17839;18198.908065516047;972.8200282947641;994.5922171909518;995.0944640377259;996.6013594562197;1000.7648202672366;1006.2805094357533;1014.2443879309924;1014.2697257974247;1015.9187265018818;1020.7448137965508;1050.2438244463974;1052.6263176572322;1053.5680371047833;1057.1437135919891;1058.7617785998166;1032.8554002439664;1045.6601604478537;1046.691226088302;1049.9580016799318;1050.8056009638165;736.9189389167187;758.9924100758977;777.9066512018537;781.9374450043982;782.9765107046798;16425.1787410629;16568.502944911674;16708.164591770364;16739.665206695798;16740.16299185036;16469.423570174986;16662.250198741087;17105.4078643485;17168;17241.65516689659;13460.663483412894;13557.796633050511;13847.307634618228;13856.653583660389;13865.722685546334;3457.135716070982;3517.103138699615;3526.84129214186;3528.029791807248;3528.788272703646;101062.49468752682;121104;121197;122679.3866030664;123038;12891.871081289255;13095.345232738326;13102.344882755824;13205.273709945854;13207.273599952096;23310.88344558266;24613.261602151604;24906.37734056627;24911.750882491306;24952.625710614357;8821.911780882108;9023.233025192802;9144.039875813078;9150.084991500797;9151.816963660689;4761.523847615211;4816.422029356429;4842.394700662418;4854.296127061556;4860.586850117779;4105.856295029719;4238.660907127484;4239.321708526621;4249.490061192677;4254.893627659333;928.0997432490474;943.9244860411151;953.8187744328694;967.0136460809935;968.1045033344128;630.6688988281189;643.2923783837834;647.2427260105687;655.2890114226118;686.9072675188886;10777.245592808586;11459.942700286445;11745.88254117465;11979.460924258256;12127.636170914886;640.2125385775522;648.7307767276527;651.2478087808589;655.2366493035655;676.5196710335716;4576.970181709155;4704.976475117671;5260.579153667697;5345.572354211654;5482.150266708666;93668.53165734127;95377;97467.51266243623;104910.4754476238;107386;93270.53364733132;93701.5314923421;94979;95170;97284.56788580201;82896.7565486506;84550;84690.99999999876;85060.57469712613;85683.14316856876;8942.552872356226;10639.829618742087;10924.453777311262;11214.88785112171;11262.662120136409;42029;42349.57650423457;42567.73639025849;42666.78666606647;42981.785091074344;50915.49084509107;55353.44646553483;55603;55995.72002139963;56643;13131.277779722206;13140.211587304791;13252.138610990254;13366.599002029954;13415.128016678884;14988.775168372702;15294.694106118039;15312.081275123528;15328;15428;14167.716645666822;14203.786943196066;14228.715425691635;14256;14368;14252.358643860844;14280.928595356956;14307.284635767961;14419.927900360432;14524.20116893581;13984.440622375398;13988.370523326272;14010.649733756738;14016.018878678802;14130.576082717535;10286.125679317342;10334.276600637882;10520;10741.892581074402;10779.622713205;9845.655402060893;11088.889111108785;11205.43972801373;11450.312981221154;11620.883791162318 05:05:33 INFO - PID 1925 | __end_tp_report 05:05:33 INFO - PID 1925 | __start_cc_report 05:05:33 INFO - PID 1925 | _x_x_mozilla_cycle_collect,432 05:05:33 INFO - PID 1925 | __end_cc_report 05:05:33 INFO - PID 1925 | __startTimestamp1491912333825__endTimestamp 05:05:33 INFO - PID 1925 | 05:05:33 INFO - PID 1925 | ------- Summary: start ------- 05:05:33 INFO - PID 1925 | Number of tests: 6 05:05:33 INFO - PID 1925 | 05:05:33 INFO - PID 1925 | [#0] dojo.html Cycles:165 Average:6091.25 Median:6316.15 stddev:777.80 (12.3%) stddev-sans-first:736.56 05:05:33 INFO - PID 1925 | Values: 2817.3 2982.6 3338.3 3563.3 3563.7 6142.0 6213.5 6223.7 6229.3 6259.9 6152.1 6153.9 6173.9 6197.2 6271.0 5451.8 5477.6 5487.4 5564.6 5595.9 5401.8 5521.1 5554.8 5587.3 5627.5 5371.2 5455.4 5475.2 5520.9 5529.4 5443.0 5471.7 5485.8 5485.9 5514.4 5406.1 5426.3 5452.3 5473.1 5481.1 5068.7 5391.7 5713.6 6148.1 6151.3 6672.7 6689.4 6696.3 6697.1 6697.8 6773.9 6776.0 6777.3 6788.5 6816.7 4849.0 6165.1 6290.7 6306.4 6325.1 6832.2 6886.7 6915.6 6942.5 6950.8 5597.0 6320.9 6483.1 6485.2 6550.6 5289.4 5466.4 6588.2 6591.1 6669.1 6651.7 6655.6 6659.6 6668.7 6687.1 6404.1 6426.3 6669.2 6672.9 6728.9 4638.7 5350.5 5350.8 5396.0 5433.1 3982.4 4721.8 4741.6 4744.0 4790.9 6120.6 6313.9 6316.1 6322.0 6331.9 6482.6 6506.9 6637.9 6709.8 6746.6 6246.3 6305.1 6309.7 6311.1 6333.5 5885.9 5917.9 5925.5 5928.9 5978.8 5761.7 6550.2 6716.6 6824.0 6849.1 6793.6 6800.7 6807.3 6809.4 6827.1 6815.0 6860.7 6879.1 6881.7 6922.7 6437.6 6486.4 6554.0 6628.7 6706.2 5222.7 6707.7 6780.1 6786.8 6818.4 6543.3 6547.4 6548.6 6559.4 6564.6 6249.8 6388.1 6403.5 6426.8 6542.2 6313.2 6314.7 6426.6 6432.4 6461.2 5428.2 6569.7 6580.6 6581.8 6599.3 6190.6 6204.3 6312.3 6354.8 6373.5 05:05:33 INFO - PID 1925 | 05:05:33 INFO - PID 1925 | [#1] ext.html Cycles:180 Average:44579.65 Median:33455.25 stddev:78955.80 (236.0%) stddev-sans-first:79108.66 05:05:33 INFO - PID 1925 | Values: 732.2 802.5 823.7 1254.7 1271.8 31432.5 36248.7 37856.6 37874.0 37886.6 37502.2 37965.0 37996.4 38043.0 38057.2 8009.6 8156.5 8431.7 8620.7 8693.4 8580.4 8722.8 8728.4 8756.6 8771.4 8688.5 8688.5 8754.3 8757.2 8795.0 8679.2 8698.3 8702.4 8734.7 8753.3 8508.0 8550.5 8559.4 8559.6 8599.0 30357.1 36917.9 37235.0 37418.3 37514.8 3565.7 3604.5 3609.4 3610.7 3613.0 30428.1 30741.0 30773.0 30798.7 30856.0 3122.6 3130.7 3144.4 3165.6 3173.8 216538.9 233384.7 238251.8 278223.6 279491.0 47546.5 47569.3 47596.0 47683.0 47896.3 48986.8 49154.3 49182.8 49253.0 49291.8 13835.3 13909.8 13971.7 13989.4 13995.0 11380.7 11520.0 11531.8 11544.5 11566.4 10852.9 13167.5 13188.6 13309.9 13312.8 5877.9 5881.6 5887.2 5904.5 5938.6 872.2 1002.3 1007.6 1014.9 1045.8 18530.7 19558.7 21990.9 23397.2 23949.8 1051.7 1117.0 1130.7 1140.8 1141.7 11768.8 12214.4 12260.9 12348.5 12352.9 345376.0 348505.0 350080.5 350374.0 350672.0 53984.0 54344.5 54389.7 54659.9 54679.0 1491.8 1523.6 1527.5 1540.1 1541.3 23571.8 23609.9 23680.6 23715.5 23746.8 37238.6 37277.6 37441.1 37451.1 37465.4 291939.5 293587.0 294205.5 294768.0 295056.0 26836.1 32492.5 33049.7 33860.8 34107.2 43664.6 44395.8 44732.6 44833.6 44975.0 29240.4 29867.3 29939.0 30704.7 30879.2 29817.6 30325.8 30494.1 30898.1 30998.7 35593.3 35713.6 36331.0 36335.8 36479.0 30444.0 30692.0 31161.5 31288.4 31412.8 22261.9 22539.2 23129.9 23573.9 24284.6 05:05:33 INFO - PID 1925 | 05:05:33 INFO - PID 1925 | [#2] jquery.html Cycles:180 Average:39483.36 Median:2285.65 stddev:109664.61 (4798.0%) stddev-sans-first:109932.95 05:05:33 INFO - PID 1925 | Values: 385.2 427.3 434.3 436.9 459.3 19773.4 21342.4 21959.0 22187.9 22193.6 21623.9 22504.0 23266.1 23584.6 23661.5 5983.7 6228.8 6246.1 6247.0 6247.0 5879.9 6216.3 6217.4 6282.9 6287.2 6197.1 6237.8 6245.5 6248.6 6266.6 5121.0 6186.6 6189.6 6193.3 6201.2 5417.5 5431.6 5478.1 5502.2 5513.2 26590.9 26960.6 27044.9 27226.9 27381.2 2216.6 2273.9 2283.9 2284.5 2286.8 17289.4 17372.8 17614.5 17748.9 18014.8 2154.9 2189.6 2223.2 2229.5 2232.6 144752.3 145578.5 147624.0 147716.0 149337.0 12029.5 12630.8 12651.4 12652.6 12668.4 12629.0 13184.1 16408.8 16409.8 16456.5 8751.8 8961.2 8961.9 9004.1 9120.2 8511.7 8521.9 8556.8 8668.0 8690.7 6581.7 7806.9 7831.9 7898.6 7930.2 1830.6 2161.6 2176.2 2188.8 2365.6 949.2 960.7 979.1 1001.6 1002.6 19598.5 19874.1 20747.2 20974.7 21115.8 850.8 911.9 970.7 982.1 1020.5 9380.6 9639.8 10116.5 10838.2 10892.0 621212.9 650639.0 657390.0 663408.0 665113.0 124835.0 142941.0 148695.0 165232.0 167262.0 1129.4 1171.2 1195.1 1196.8 1198.4 22049.7 22234.6 22278.1 22419.0 22482.6 63854.0 64883.7 65274.0 65506.3 66174.3 91915.0 91990.5 92450.5 92753.0 93357.5 8856.1 8859.4 8993.7 9247.2 9411.7 11679.6 13485.7 14695.4 14811.7 15271.9 11850.5 12098.5 12115.8 12281.8 12293.3 11563.1 11603.9 11663.7 11736.8 11861.3 11990.0 12388.9 12511.7 12562.9 12748.1 8599.3 8874.5 9003.9 9300.4 9321.9 11879.5 12006.2 12300.3 12310.6 12401.3 05:05:33 INFO - PID 1925 | 05:05:33 INFO - PID 1925 | [#3] mootools.html Cycles:180 Average:7236.34 Median:2554.93 stddev:4580.24 (179.3%) stddev-sans-first:4571.84 05:05:33 INFO - PID 1925 | Values: 1365.4 1420.2 1423.9 1428.4 1453.5 11403.5 11713.4 11849.1 11875.2 12199.3 12041.0 12045.5 12157.2 12190.0 12245.0 2491.6 2529.9 2540.3 2544.8 2546.0 2459.4 2507.3 2550.1 2551.4 2561.0 2470.5 2524.8 2556.0 2559.5 2568.7 2458.3 2513.8 2526.0 2550.0 2552.0 2434.3 2514.9 2526.8 2551.9 2553.9 11446.5 11975.8 12024.6 12034.8 12048.0 5058.7 6729.0 6814.8 6863.4 6880.5 4115.5 4356.7 4443.4 4446.3 4474.6 1242.1 1270.7 1285.0 1315.6 1318.0 13305.3 15068.6 15619.2 16655.5 16679.9 5531.2 5706.1 5889.3 5920.5 5974.3 12367.7 12408.0 12420.9 12477.5 12660.8 5876.8 6460.6 6605.6 6685.7 6688.8 6020.8 6206.3 6264.0 6272.3 6289.2 4377.4 4477.1 4542.4 4545.9 4569.6 1311.0 1334.3 1337.7 1353.4 1356.4 777.4 798.0 802.7 805.8 815.7 4745.3 6176.3 6178.8 6423.2 6490.5 590.5 602.0 611.1 615.1 615.4 3527.4 4018.9 4281.9 4361.7 4507.0 14559.6 14682.3 15258.5 15304.4 15360.9 15173.2 15236.8 15286.5 15287.2 15324.4 10792.4 10980.5 11080.8 11088.0 11115.1 6198.3 6684.0 6894.8 6941.3 6990.0 11063.0 13668.9 13682.0 13950.9 14040.6 13501.3 13823.9 13910.4 14251.4 14298.8 9449.2 9588.6 9663.0 9748.2 9777.0 10887.2 10992.9 11004.2 11016.6 11031.0 8986.0 9249.4 9351.0 9388.0 9415.6 9124.7 9149.7 9153.3 9194.0 9208.1 8200.5 8236.8 8250.0 8326.9 8339.0 5340.6 5685.4 6056.7 6125.0 6151.1 6149.8 6178.0 6180.9 6183.1 6227.2 05:05:33 INFO - PID 1925 | 05:05:33 INFO - PID 1925 | [#4] prototype.html Cycles:180 Average:5184.39 Median:5777.81 stddev:1665.22 (28.8%) stddev-sans-first:1629.76 05:05:33 INFO - PID 1925 | Values: 342.9 370.2 384.8 400.7 403.5 5098.5 5960.8 6079.9 6086.8 6094.0 4415.8 5171.4 5777.9 5957.7 5995.3 3682.0 3913.7 3940.8 4582.6 4587.0 3566.0 3736.7 4574.6 4584.2 4619.8 4322.6 4493.2 4538.9 4557.2 4567.0 3947.5 4235.9 4505.5 4515.5 4525.3 3993.5 4013.9 4026.4 4034.0 4042.2 4696.9 4938.8 5663.7 5930.4 5945.3 4612.8 5094.7 5127.7 5251.7 5312.4 5314.7 6438.9 6442.8 6466.0 6478.5 4215.0 4262.6 4276.2 4327.7 4359.8 6502.7 6742.2 6744.9 6870.9 6888.0 3714.3 3734.8 4457.8 4461.9 4465.8 3740.6 3785.6 4025.2 4516.5 4542.4 4875.0 5106.0 5213.3 5313.9 5340.4 5864.7 6463.5 6499.9 6505.2 6544.5 1352.4 1380.9 1401.9 1510.4 1537.7 913.7 946.3 962.9 1015.8 1016.1 5160.9 5571.0 6189.6 6189.9 6203.3 5925.3 6105.7 6409.4 6854.3 6926.5 4758.6 5380.2 5463.5 5705.3 6194.0 3389.7 3523.0 3528.4 3534.2 3538.2 6115.4 6308.0 6966.2 6972.3 6989.0 5687.0 6604.2 6907.3 7043.8 7114.3 5115.8 6169.9 6901.8 7054.5 7059.3 6779.7 6798.6 6811.9 6820.0 6824.7 6560.0 6708.1 6710.9 6733.7 6740.3 5674.9 6274.8 6707.4 6872.2 7013.3 4103.4 4297.0 4448.2 4464.8 4476.5 6580.3 6741.0 6868.7 6878.5 6889.8 5113.7 6414.6 6537.9 6543.1 6550.7 5473.3 6190.4 6277.4 6587.5 6601.2 6122.1 6301.2 6331.5 6356.7 6365.4 6494.6 6527.4 6551.9 6572.1 6608.0 5777.8 5845.3 6162.6 6386.5 6396.6 05:05:33 INFO - PID 1925 | 05:05:33 INFO - PID 1925 | [#5] yui.html Cycles:180 Average:21217.20 Median:17589.82 stddev:30134.93 (171.3%) stddev-sans-first:30180.50 05:05:33 INFO - PID 1925 | Values: 807.7 844.0 847.6 859.0 860.6 15822.7 16451.3 16568.7 17911.7 18670.8 17486.2 17532.9 17646.7 17839.0 18198.9 972.8 994.6 995.1 996.6 1000.8 1006.3 1014.2 1014.3 1015.9 1020.7 1050.2 1052.6 1053.6 1057.1 1058.8 1032.9 1045.7 1046.7 1050.0 1050.8 736.9 759.0 777.9 781.9 783.0 16425.2 16568.5 16708.2 16739.7 16740.2 16469.4 16662.3 17105.4 17168.0 17241.7 13460.7 13557.8 13847.3 13856.7 13865.7 3457.1 3517.1 3526.8 3528.0 3528.8 101062.5 121104.0 121197.0 122679.4 123038.0 12891.9 13095.3 13102.3 13205.3 13207.3 23310.9 24613.3 24906.4 24911.8 24952.6 8821.9 9023.2 9144.0 9150.1 9151.8 4761.5 4816.4 4842.4 4854.3 4860.6 4105.9 4238.7 4239.3 4249.5 4254.9 928.1 943.9 953.8 967.0 968.1 630.7 643.3 647.2 655.3 686.9 10777.2 11459.9 11745.9 11979.5 12127.6 640.2 648.7 651.2 655.2 676.5 4577.0 4705.0 5260.6 5345.6 5482.2 93668.5 95377.0 97467.5 104910.5 107386.0 93270.5 93701.5 94979.0 95170.0 97284.6 82896.8 84550.0 84691.0 85060.6 85683.1 8942.6 10639.8 10924.5 11214.9 11262.7 42029.0 42349.6 42567.7 42666.8 42981.8 50915.5 55353.4 55603.0 55995.7 56643.0 13131.3 13140.2 13252.1 13366.6 13415.1 14988.8 15294.7 15312.1 15328.0 15428.0 14167.7 14203.8 14228.7 14256.0 14368.0 14252.4 14280.9 14307.3 14419.9 14524.2 13984.4 13988.4 14010.6 14016.0 14130.6 10286.1 10334.3 10520.0 10741.9 10779.6 9845.7 11088.9 11205.4 11450.3 11620.9 05:05:33 INFO - PID 1925 | -------- Summary: end -------- 05:05:33 INFO - PID 1925 | 05:05:34 INFO - TEST-INFO | 1925: exit 0 05:05:34 INFO - TEST-OK | dromaeo_css | took 1115195ms 05:05:34 INFO - TEST-START | kraken 05:05:34 INFO - operating with platform_type : mac_ 05:05:34 INFO - Initialising browser for kraken test... 05:05:34 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmpacIUCV/profile http://localhost:52483/getInfo.html) 05:05:37 INFO - PID 1931 | 2017-04-11 05:05:37.547 plugin-container[1933:19183] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 05:05:37 INFO - PID 1931 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:05:37 INFO - PID 1931 | 2017-04-11 05:05:37.547 plugin-container[1933:19183] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 05:05:37 INFO - PID 1931 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:05:37 INFO - PID 1931 | 2017-04-11 05:05:37.547 plugin-container[1933:19183] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:05:37 INFO - PID 1931 | __metrics Screen width/height:1600/1200 05:05:37 INFO - PID 1931 | colorDepth:24 05:05:37 INFO - PID 1931 | Browser inner width/height: 1024/689 05:05:37 INFO - PID 1931 | __metrics 05:05:37 ERROR - PID 1931 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 05:05:38 INFO - TEST-INFO | 1931: exit 0 05:05:38 INFO - Browser initialized. 05:05:38 INFO - Running cycle 1/1 for kraken test... 05:05:38 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmpacIUCV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:05:41 INFO - PID 1934 | 2017-04-11 05:05:41.262 plugin-container[1935:19313] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9043, name = 'com.apple.tsm.portname' 05:05:41 INFO - PID 1934 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:05:41 INFO - PID 1934 | 2017-04-11 05:05:41.263 plugin-container[1935:19313] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a13, name = 'com.apple.CFPasteboardClient' 05:05:41 INFO - PID 1934 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:05:41 INFO - PID 1934 | 2017-04-11 05:05:41.263 plugin-container[1935:19313] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:05:51 INFO - PID 1934 | RSS: Main: 234848256 05:05:51 INFO - PID 1934 | 05:05:54 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52483/tests/kraken/driver.html?testName=audio-beat-detection) 05:05:55 INFO - PID 1934 | RSS: Main: 250228736 05:05:55 INFO - PID 1934 | 05:05:57 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52483/tests/kraken/driver.html?testName=audio-dft) 05:05:58 INFO - PID 1934 | RSS: Main: 258678784 05:05:58 INFO - PID 1934 | 05:06:02 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52483/tests/kraken/driver.html?testName=audio-fft) 05:06:02 INFO - PID 1934 | RSS: Main: 260894720 05:06:02 INFO - PID 1934 | 05:06:05 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52483/tests/kraken/driver.html?testName=audio-oscillator) 05:06:05 INFO - PID 1934 | RSS: Main: 261939200 05:06:05 INFO - PID 1934 | 05:06:07 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52483/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:06:08 INFO - PID 1934 | RSS: Main: 262213632 05:06:08 INFO - PID 1934 | 05:06:11 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52483/tests/kraken/driver.html?testName=imaging-darkroom) 05:06:12 INFO - PID 1934 | RSS: Main: 268738560 05:06:12 INFO - PID 1934 | 05:06:15 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52483/tests/kraken/driver.html?testName=imaging-desaturate) 05:06:15 INFO - PID 1934 | RSS: Main: 269729792 05:06:15 INFO - PID 1934 | 05:06:19 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52483/tests/kraken/driver.html?testName=json-parse-financial) 05:06:19 INFO - PID 1934 | RSS: Main: 269381632 05:06:19 INFO - PID 1934 | 05:06:21 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52483/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:06:22 INFO - PID 1934 | RSS: Main: 268926976 05:06:22 INFO - PID 1934 | 05:06:23 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52483/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:06:24 INFO - PID 1934 | RSS: Main: 268836864 05:06:24 INFO - PID 1934 | 05:06:26 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52483/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:06:27 INFO - PID 1934 | RSS: Main: 268906496 05:06:27 INFO - PID 1934 | 05:06:30 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52483/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:06:31 INFO - PID 1934 | RSS: Main: 269443072 05:06:31 INFO - PID 1934 | 05:06:35 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52483/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:06:35 INFO - PID 1934 | RSS: Main: 266477568 05:06:35 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52483/tests/kraken/driver.html?testName=ai-astar) 05:06:37 INFO - PID 1934 | RSS: Main: 269885440 05:06:37 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | __start_tp_report 05:06:37 INFO - PID 1934 | _x_x_mozilla_page_load 05:06:37 INFO - PID 1934 | _x_x_mozilla_page_load_details 05:06:37 INFO - PID 1934 | |i|pagename|runs| 05:06:37 INFO - PID 1934 | |0;ai-astar;153;172;107;181;180;110;180;181;182;123 05:06:37 INFO - PID 1934 | |1;audio-beat-detection;137;100;144;199;152;114;116;118;138;119 05:06:37 INFO - PID 1934 | |2;audio-dft;224;233;229;311;300;215;210;210;212;216 05:06:37 INFO - PID 1934 | |3;audio-fft;84;59;71;115;94;88;65;58;79;64 05:06:37 INFO - PID 1934 | |4;audio-oscillator;87;73;86;75;72;74;88;73;73;89 05:06:37 INFO - PID 1934 | |5;imaging-gaussian-blur;96;130;99;140;137;92;94;93;93;119 05:06:37 INFO - PID 1934 | |6;imaging-darkroom;100;101;102;103;101;101;104;103;101;159 05:06:37 INFO - PID 1934 | |7;imaging-desaturate;106;103;105;114;103;100;144;155;119;133 05:06:37 INFO - PID 1934 | |8;json-parse-financial;52;52;51;53;54;62;81;58;46;48 05:06:37 INFO - PID 1934 | |9;json-stringify-tinderbox;61;59;66;72;69;66;61;59;58;60 05:06:37 INFO - PID 1934 | |10;stanford-crypto-aes;87;102;89;108;114;114;87;88;87;88 05:06:37 INFO - PID 1934 | |11;stanford-crypto-ccm;163;170;144;139;162;150;190;172;225;178 05:06:37 INFO - PID 1934 | |12;stanford-crypto-pbkdf2;200;241;197;229;201;260;258;196;196;195 05:06:37 INFO - PID 1934 | |13;stanford-crypto-sha256-iterative;70;68;71;61;93;73;74;99;106;102 05:06:37 INFO - PID 1934 | __end_tp_report 05:06:37 INFO - PID 1934 | __start_cc_report 05:06:37 INFO - PID 1934 | _x_x_mozilla_cycle_collect,1020 05:06:37 INFO - PID 1934 | __end_cc_report 05:06:37 INFO - PID 1934 | __startTimestamp1491912397819__endTimestamp 05:06:37 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | ------- Summary: start ------- 05:06:37 INFO - PID 1934 | Number of tests: 14 05:06:37 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | [#0] ai-astar Cycles:10 Average:156.90 Median:180.00 stddev:31.52 (17.5%) stddev-sans-first:33.40 05:06:37 INFO - PID 1934 | Values: 153.0 172.0 107.0 181.0 180.0 110.0 180.0 181.0 182.0 123.0 05:06:37 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | [#1] audio-beat-detection Cycles:10 Average:133.70 Median:137.50 stddev:27.96 (20.3%) stddev-sans-first:29.63 05:06:37 INFO - PID 1934 | Values: 137.0 100.0 144.0 199.0 152.0 114.0 116.0 118.0 138.0 119.0 05:06:37 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | [#2] audio-dft Cycles:10 Average:236.00 Median:226.50 stddev:37.55 (16.6%) stddev-sans-first:39.58 05:06:37 INFO - PID 1934 | Values: 224.0 233.0 229.0 311.0 300.0 215.0 210.0 210.0 212.0 216.0 05:06:37 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | [#3] audio-fft Cycles:10 Average:77.70 Median:75.00 stddev:18.06 (24.1%) stddev-sans-first:19.01 05:06:37 INFO - PID 1934 | Values: 84.0 59.0 71.0 115.0 94.0 88.0 65.0 58.0 79.0 64.0 05:06:37 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | [#4] audio-oscillator Cycles:10 Average:79.00 Median:80.50 stddev:7.39 (9.2%) stddev-sans-first:7.25 05:06:37 INFO - PID 1934 | Values: 87.0 73.0 86.0 75.0 72.0 74.0 88.0 73.0 73.0 89.0 05:06:37 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:93.00 stddev:19.94 (21.4%) stddev-sans-first:20.57 05:06:37 INFO - PID 1934 | Values: 96.0 130.0 99.0 140.0 137.0 92.0 94.0 93.0 93.0 119.0 05:06:37 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | [#6] imaging-darkroom Cycles:10 Average:107.50 Median:102.50 stddev:18.14 (17.7%) stddev-sans-first:19.03 05:06:37 INFO - PID 1934 | Values: 100.0 101.0 102.0 103.0 101.0 101.0 104.0 103.0 101.0 159.0 05:06:37 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | [#7] imaging-desaturate Cycles:10 Average:118.20 Median:116.50 stddev:19.36 (16.6%) stddev-sans-first:20.03 05:06:37 INFO - PID 1934 | Values: 106.0 103.0 105.0 114.0 103.0 100.0 144.0 155.0 119.0 133.0 05:06:37 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:53.50 stddev:9.99 (18.7%) stddev-sans-first:10.51 05:06:37 INFO - PID 1934 | Values: 52.0 52.0 51.0 53.0 54.0 62.0 81.0 58.0 46.0 48.0 05:06:37 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | [#9] json-stringify-tinderbox Cycles:10 Average:63.10 Median:63.50 stddev:4.82 (7.6%) stddev-sans-first:5.05 05:06:37 INFO - PID 1934 | Values: 61.0 59.0 66.0 72.0 69.0 66.0 61.0 59.0 58.0 60.0 05:06:37 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | [#10] stanford-crypto-aes Cycles:10 Average:96.40 Median:87.00 stddev:11.77 (13.5%) stddev-sans-first:11.98 05:06:37 INFO - PID 1934 | Values: 87.0 102.0 89.0 108.0 114.0 114.0 87.0 88.0 87.0 88.0 05:06:37 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | [#11] stanford-crypto-ccm Cycles:10 Average:169.30 Median:171.00 stddev:25.03 (14.6%) stddev-sans-first:26.44 05:06:37 INFO - PID 1934 | Values: 163.0 170.0 144.0 139.0 162.0 150.0 190.0 172.0 225.0 178.0 05:06:37 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:217.30 Median:215.00 stddev:27.00 (12.6%) stddev-sans-first:27.90 05:06:37 INFO - PID 1934 | Values: 200.0 241.0 197.0 229.0 201.0 260.0 258.0 196.0 196.0 195.0 05:06:37 INFO - PID 1934 | 05:06:37 INFO - PID 1934 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.70 Median:72.00 stddev:16.44 (22.8%) stddev-sans-first:16.88 05:06:37 INFO - PID 1934 | Values: 70.0 68.0 71.0 61.0 93.0 73.0 74.0 99.0 106.0 102.0 05:06:37 INFO - PID 1934 | -------- Summary: end -------- 05:06:37 INFO - PID 1934 | 05:06:38 INFO - TEST-INFO | 1934: exit 0 05:06:38 INFO - TEST-OK | kraken | took 64375ms 05:06:38 INFO - SUITE-END | took 1179s 05:06:38 INFO - Completed test suite (00:19:39) 05:06:38 INFO - javascript benchmark 05:06:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7828.477963363926, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2817.2815931937357, 2982.5824384586163, 3338.349021940721, 3563.2517171394006, 3563.7149028077756, 6141.9692901535545, 6213.53398495113, 6223.719932603032, 6229.283632382278, 6259.906101408483, 6152.077188421739, 6153.938460615393, 6173.907391389122, 6197.163382943301, 6271.0, 5451.781928722866, 5477.561795056406, 5487.368952570446, 5564.554835613161, 5595.888082238353, 5401.756920938567, 5521.061419558685, 5554.777808887639, 5587.329520457531, 5627.493525582697, 5371.194320851864, 5455.399906010336, 5475.151351540518, 5520.91718624223, 5529.364123125852, 5442.9727851360885, 5471.7264136793, 5485.807996720136, 5485.890282194334, 5514.3658479275, 5406.107992181324, 5426.348838139448, 5452.263944367539, 5473.0969390050595, 5481.123020316731, 5068.746562671851, 5391.703456309901, 5713.600167958876, 6148.1085242639565, 6151.2618485781995, 6672.666366681647, 6689.397954184147, 6696.330366963313, 6697.095892054559, 6697.799066028027, 6773.864522709568, 6776.0, 6777.322267773183, 6788.524803263774, 6816.659167041628, 4848.95151048485, 6165.075238714139, 6290.716917738713, 6306.400891915261, 6325.082862984841, 6832.214295355998, 6886.655667216719, 6915.619640919697, 6942.479314051467, 6950.79147625572, 5597.020521408737, 6320.873582528416, 6483.059956306309, 6485.221773387224, 6550.639714815731, 5289.365276166807, 5466.426025267369, 6588.176477940257, 6591.143151390384, 6669.066330713704, 6651.66741662915, 6655.6339401332425, 6659.600423974577, 6668.666566671647, 6687.063811066455, 6404.135441715402, 6426.260979987268, 6669.1996960364095, 6672.899906501407, 6728.856094463953, 4638.698484598491, 5350.491703288181, 5350.8127215547265, 5395.973020134874, 5433.103537916279, 3982.4225487304757, 4721.834735784231, 4741.596964258008, 4743.980044290494, 4790.928136077892, 6120.571559990758, 6313.9368606314265, 6316.147320111726, 6322.0, 6331.873362532723, 6482.57863238888, 6506.902396464056, 6637.867242655217, 6709.765158219537, 6746.595204287661, 6246.250449946036, 6305.085762564494, 6309.716062777186, 6311.084892690534, 6333.461655759312, 5885.852853678693, 5917.852053698692, 5925.466707996301, 5928.911066334009, 5978.790742324085, 5761.683107429131, 6550.181227346582, 6716.5634233774645, 6823.999999999901, 6849.1096157499205, 6793.592384456751, 6800.693968771516, 6807.251202367636, 6809.421199198124, 6827.112475378169, 6815.0, 6860.691268892812, 6879.140107486564, 6881.690323935335, 6922.688479018557, 6437.613743175424, 6486.416222539994, 6554.0, 6628.734850606114, 6706.228783689938, 5222.7127507987425, 6707.698153583003, 6780.118584583973, 6786.76246331376, 6818.352256535622, 6543.312952139957, 6547.410733033859, 6548.574342667712, 6559.442447392025, 6564.606123632598, 6249.843753906189, 6388.1376014237485, 6403.51973601991, 6426.775062872777, 6542.182227221598, 6313.210848643919, 6314.652694101868, 6426.550141490051, 6432.388923052303, 6461.19235095613, 5428.212909128075, 6569.671516424255, 6580.638064906476, 6581.769638062645, 6599.340065993554, 6190.597611155261, 6204.255489341308, 6312.337204593635, 6354.809355719336, 6373.521985850987], "value": 6036.767422408279, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.2128711634994, 802.5024484819411, 823.682882090395, 1254.6737848159476, 1271.7583659104776, 31432.52851207234, 36248.7312944047, 37856.62143378565, 37874.0, 37886.621133788685, 37502.24995500089, 37965.0, 37996.43005354915, 38043.0, 38057.23885522288, 8009.559474228915, 8156.469829461066, 8431.747047588551, 8620.65517379304, 8693.391462597621, 8580.39937204396, 8722.781930451725, 8728.43265187764, 8756.649734010633, 8771.386002979794, 8688.522131282776, 8688.522131282776, 8754.343424243207, 8757.168069033432, 8795.0, 8679.218870301704, 8698.260647844942, 8702.390832641717, 8734.737957861274, 8753.255973242281, 8508.0, 8550.52972086535, 8559.358048146414, 8559.572021398904, 8599.011113721877, 30357.089287321414, 36917.89246322615, 37235.0, 37418.25163496715, 37514.81242593769, 3565.714742820568, 3604.53141091659, 3609.350316942949, 3610.7472476926628, 3612.981935090334, 30428.087157385315, 30741.0, 30773.0, 30798.692013079806, 30856.0, 3122.625284965796, 3130.686931306851, 3144.371125774854, 3165.5568220449154, 3173.841307934594, 216538.91730541404, 233384.66615333798, 238251.8087409569, 278223.6088819543, 279491.0, 47546.5245347549, 47569.286460703115, 47596.0, 47683.0, 47896.28155577669, 48986.75506622515, 49154.26268605974, 49182.75408622934, 49253.014939701, 49291.753541232065, 13835.308234588432, 13909.791353129509, 13971.720565588632, 13989.440422382992, 13995.020348575503, 11380.658580242605, 11520.020798232244, 11531.827022594667, 11544.538218471167, 11566.363849988164, 10852.891471085188, 13167.473301068041, 13188.604341869759, 13309.93345033288, 13312.800307995389, 5877.9118313225335, 5881.647221139162, 5887.234659494238, 5904.527637788967, 5938.584299099021, 872.2150064941477, 1002.2783595811004, 1007.5768177365524, 1014.9340292880939, 1045.8012977534245, 18530.722039169425, 19558.70661940072, 21990.89004554999, 23397.181098661466, 23949.760502394754, 1051.6897515233024, 1116.972075698114, 1130.728625129963, 1140.8402823604702, 1141.6746227325173, 11768.764624707457, 12214.434968656504, 12260.877391225973, 12348.506059757508, 12352.876471235353, 345376.0, 348505.0, 350080.4991950099, 350374.0, 350672.0, 53984.03087830129, 54344.45655543473, 54389.72805135949, 54659.906801864534, 54679.0, 1491.8209814822292, 1523.5505525870117, 1527.5493729349869, 1540.0605630565346, 1541.3295216580823, 23571.76428235696, 23609.881950590137, 23680.644790327813, 23715.52568948577, 23746.762532374454, 37238.62761372352, 37277.62722372742, 37441.06397340088, 37451.06372340713, 37465.4380184292, 291939.5403022971, 293587.0, 294205.5289723538, 294768.0, 295056.0, 26836.060737874082, 32492.512612310362, 33049.669503305624, 33860.830695846365, 34107.248202697185, 43664.56335436605, 44395.77802110969, 44732.552674474144, 44833.61996560434, 44975.0, 29240.415191696473, 29867.25331866634, 29938.95213667511, 30704.692953070182, 30879.2280192997, 29817.570979336786, 30325.848370758005, 30494.08517744471, 30898.073057808302, 30998.690013099582, 35593.28813423665, 35713.64286357103, 36331.0, 36335.81832090928, 36479.0, 30444.0, 30692.0, 31161.53257701091, 31288.37423251477, 31412.842935786088, 22261.888690556443, 22539.211127610455, 23129.88435057814, 23573.939172736926, 24284.63573046441], "value": 17357.041950230156, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.23915267346985, 427.3333599584646, 434.34197191255237, 436.93227549729784, 459.2743465324787, 19773.40679779607, 21342.35972920811, 21959.011844466997, 22187.889060554695, 22193.55612887741, 21623.891880540614, 22503.987320570574, 23266.069357225733, 23584.64623030656, 23661.526769464643, 5983.670898100603, 6228.750849966019, 6246.125542424064, 6247.0, 6247.000479923213, 5879.88240235195, 6216.285127210361, 6217.378262173769, 6282.874342513147, 6287.182666253403, 6197.101420294055, 6237.812865614016, 6245.5003599712145, 6248.593841400295, 6266.624002559861, 5120.974395128038, 6186.628802271878, 6189.6286222826775, 6193.287771906221, 6201.224846894138, 5417.539509141728, 5431.565474762009, 5478.068728316156, 5502.2296878436655, 5513.20058591506, 26590.936362545475, 26960.59559106615, 27044.918203271845, 27226.863865680545, 27381.17856464309, 2216.556688662274, 2273.897674604647, 2283.931482055541, 2284.4745708486976, 2286.8337148054447, 17289.443950044555, 17372.82627173725, 17614.471565853044, 17748.91125544364, 18014.82903611274, 2154.881481518516, 2189.6496560550236, 2223.2218723446827, 2229.5317983223545, 2232.6427771556473, 144752.27623861918, 145578.5442145586, 147624.0, 147716.0, 149336.99999999892, 12029.458674359676, 12630.81053784194, 12651.367431628381, 12652.557160499338, 12668.36658167088, 12629.0, 13184.143030702973, 16408.83591164073, 16409.753853692204, 16456.506304810875, 8751.824963500821, 8961.23829474489, 8961.910380896108, 9004.054574269738, 9120.179183873483, 8511.702090426805, 8521.914780852112, 8556.828863422697, 8668.0, 8690.652373904974, 6581.74946760114, 7806.882896756553, 7831.921680783234, 7898.565578893216, 7930.246626570467, 1830.5789668376349, 2161.6109100362087, 2176.238316589213, 2188.8139508141985, 2365.6096744037386, 949.188443880486, 960.7261930349816, 979.1432496565506, 1001.5593139018808, 1002.6089824968268, 19598.510037249183, 19874.105665245097, 20747.170113195603, 20974.703272988885, 21115.788842111382, 850.8468475674314, 911.9330383451378, 970.708787363788, 982.1259079419323, 1020.5305559442739, 9380.592911063259, 9639.759006024766, 10116.494175291205, 10838.241323107464, 10891.996453215736, 621212.8939355274, 650639.0, 657390.0, 663408.0, 665113.0, 124835.0, 142941.0, 148695.0, 165232.0, 167262.0, 1129.4296380327883, 1171.2328424881546, 1195.0559058343986, 1196.8264601632713, 1198.4007996002, 22049.684783097542, 22234.555308894054, 22278.108875644793, 22419.0, 22482.5503489926, 63854.04218936813, 64883.67558162179, 65274.0, 65506.34493655003, 66174.34564135743, 91915.0, 91990.54004729934, 92450.53774731084, 92753.0, 93357.53321233351, 8856.095939373066, 8859.424137431046, 8993.685221017233, 9247.213986810928, 9411.670591529264, 11679.591214307458, 13485.662858428617, 14695.412183512968, 14811.703765924405, 15271.92364038217, 11850.525978960744, 12098.455569499569, 12115.818262726243, 12281.815772763595, 12293.262404255773, 11563.132765042486, 11603.88396116028, 11663.708407289885, 11736.823947640964, 11861.288322700666, 11989.98085162771, 12388.876111239133, 12511.687207819878, 12562.87437125617, 12748.10763246601, 8599.269062129539, 8874.467531948103, 9003.864942026006, 9300.39547429415, 9321.906780932375, 11879.524819007143, 12006.159568830099, 12300.261984280973, 12310.569130080405, 12401.317927514074], "value": 10661.936373540482, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1365.4060483689593, 1420.183394548135, 1423.943042278309, 1428.4000719697726, 1453.5493996860973, 11403.48684309206, 11713.355765432898, 11849.052075833933, 11875.16873818832, 12199.268043917371, 12041.0, 12045.518179272818, 12157.209781364205, 12189.963853072499, 12245.0, 2491.6137998610197, 2529.936751581216, 2540.3395117269456, 2544.7709706126443, 2546.0, 2459.385153711572, 2507.272890861657, 2550.132954795369, 2551.413174969749, 2561.0, 2470.493548822496, 2524.823262371635, 2555.9744402556016, 2559.5008973250224, 2568.743125687435, 2458.287096741934, 2513.7611926866916, 2526.0401047601886, 2549.961750573743, 2552.0174732728033, 2434.318390850565, 2514.924552263435, 2526.759957804006, 2551.9489610207693, 2553.885075171622, 11446.484908179069, 11975.820362694567, 12024.639260822189, 12034.759304813855, 12048.0, 5058.721770302632, 6728.966355168192, 6814.761483348108, 6863.38229559342, 6880.518363714542, 4115.52671442785, 4356.6514678825615, 4443.42235509385, 4446.3108218226225, 4474.619657329131, 1242.1243023668292, 1270.733146039333, 1284.9743005139937, 1315.5790147152916, 1318.044417797099, 13305.334733263298, 15068.623284417868, 15619.219039048116, 16655.50033498997, 16679.91660041704, 5531.225628412026, 5706.087026075808, 5889.322727886348, 5920.526357891358, 5974.283086029617, 12367.6908077297, 12408.007359411406, 12420.937895310466, 12477.500899964081, 12660.81008784869, 5876.7649294028615, 6460.578672692094, 6605.603663780189, 6685.665716714145, 6688.765893193715, 6020.849478763066, 6206.286277078194, 6263.968680156661, 6272.341404152589, 6289.213848268966, 4377.387165796791, 4477.082198149372, 4542.386777785023, 4545.863624091283, 4569.611583015414, 1310.9868901310865, 1334.299492766286, 1337.6789570503204, 1353.3571553512004, 1356.3896246688832, 777.3780975219802, 798.0498707001011, 802.7471346525825, 805.7743831727008, 815.6533473273835, 4745.311929770164, 6176.292035310173, 6178.752849885954, 6423.229212494531, 6490.545661803723, 590.5216774412739, 601.9668918209453, 611.0589691874463, 615.092738211135, 615.4215037864393, 3527.365074286653, 4018.9196216075516, 4281.892952676208, 4361.738295702268, 4506.954930450719, 14559.636009099646, 14682.339294731762, 15258.46595369179, 15304.387824486896, 15360.923195384175, 15173.24133793328, 15236.847631523764, 15286.464973725866, 15287.159206243763, 15324.387024518894, 10792.406417647106, 10980.505877235384, 11080.833787493195, 11088.002079812693, 11115.110791136689, 6198.256209254738, 6683.966580167068, 6894.793156205322, 6941.271166527644, 6990.0, 11063.0, 13668.863311366758, 13681.973851960944, 13950.860491395362, 14040.578782636538, 13501.324933753076, 13823.86176138266, 13910.374033168735, 14251.429942802171, 14298.785518217443, 9449.244060475283, 9588.56851441673, 9663.0, 9748.220142388735, 9777.0, 10887.183461240596, 10992.945035274772, 11004.174686898676, 11016.614418495314, 11031.0, 8986.0, 9249.352545321763, 9351.0, 9387.953060234928, 9415.62337506492, 9124.680636177967, 9149.725508234764, 9153.313501487548, 9193.954030229806, 9208.079192080739, 8200.507969521848, 8236.752897413087, 8249.958750206211, 8326.875096873673, 8339.041010283894, 5340.626156169031, 5685.431456854447, 6056.7274472649715, 6124.969375153096, 6151.0773383992755, 6149.7847575334645, 6177.96911015442, 6180.858824587627, 6183.103449999815, 6227.19046523949], "value": 5412.848250122708, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.9468432392979, 370.2447008103469, 384.8325978199483, 400.7134898547538, 403.5339183243354, 5098.464661210575, 5960.761569537219, 6079.878402431948, 6086.8478288042825, 6094.0, 4415.845445409412, 5171.4311425743235, 5777.855553611152, 5957.731902064406, 5995.340512543617, 3681.987453450309, 3913.7456065355805, 3940.778358708795, 4582.564656357641, 4586.990862010352, 3566.037169964108, 3736.7197460190596, 4574.634029277667, 4584.151931892594, 4619.8450387403145, 4322.632576231024, 4493.168763778695, 4538.886527836814, 4557.179707652621, 4567.0, 3947.50656167979, 4235.894102647428, 4505.52691967342, 4515.525869783658, 4525.275955847065, 3993.5008123984503, 4013.9397909031386, 4026.396040593905, 4034.0, 4042.191561687674, 4696.8590942271785, 4938.765308672831, 5663.7168141593165, 5930.3773103823905, 5945.256842894638, 4612.7776139323105, 5094.668846525001, 5127.743612819381, 5251.737413129328, 5312.415634280226, 5314.654547454403, 6438.9375753000695, 6442.806715798533, 6466.0300954856675, 6478.514111441661, 4214.967333003403, 4262.573742625744, 4276.182622385474, 4327.697061205717, 4359.782010899474, 6502.7398904043785, 6742.235683369795, 6744.865102697968, 6870.862582748317, 6887.965560172217, 3714.33142034431, 3734.8319325630414, 4457.77711114443, 4461.92913700716, 4465.754383508938, 3740.588535261119, 3785.564660064073, 4025.1547175092974, 4516.480604730433, 4542.364069030338, 4874.951250487521, 5105.978804239167, 5213.296205012275, 5313.946860531345, 5340.385855626575, 5864.736086876015, 6463.547551671432, 6499.8700025999215, 6505.243584232326, 6544.476441884638, 1352.4387379237703, 1380.9240491772848, 1401.873831355183, 1510.4033906606828, 1537.7462718651298, 913.6979803779587, 946.342292106979, 962.8663130125001, 1015.7714514234239, 1016.0745387490664, 5160.8904085621025, 5570.972145139248, 6189.616997456012, 6189.907151392733, 6203.2556093268195, 5925.34821169671, 6105.694715264219, 6409.391107844747, 6854.280300568468, 6926.515143939876, 4758.643101767381, 5380.229181971288, 5463.453654634584, 5705.286839145107, 6193.969030154821, 3389.6610338965907, 3523.0487768302414, 3528.382533056756, 3534.1871369584624, 3538.221591249921, 6115.4190351916495, 6307.990721484541, 6966.164060312693, 6972.302769722987, 6988.965055174692, 5687.03320435527, 6604.174478190226, 6907.309269073053, 7043.788686339316, 7114.336116899801, 5115.846524604268, 6169.9383006170265, 6901.75843845463, 7054.541454805524, 7059.294070593002, 6779.694913728993, 6798.626075565891, 6811.931880681328, 6819.965900170468, 6824.658767061726, 6559.967200164159, 6708.094407254958, 6710.932890671031, 6733.730650773914, 6740.325967403221, 5674.886502269849, 6274.843128921813, 6707.42986846104, 6872.209695884837, 7013.333733295329, 4103.4050062741335, 4296.978515107405, 4448.243798554189, 4464.799084041161, 4476.485204201558, 6580.341965803382, 6741.0, 6868.7252509901045, 6878.518503704692, 6889.827754305982, 5113.718745469035, 6414.583052101599, 6537.900437658634, 6543.149390579194, 6550.705218265285, 5473.31583552056, 6190.380961903774, 6277.372262773869, 6587.505937054836, 6601.207855057424, 6122.102148334031, 6301.212348456443, 6331.5251356147555, 6356.68216589178, 6365.427111559982, 6494.6361264133175, 6527.379898909596, 6551.868962620625, 6572.112764776694, 6608.008798680236, 5777.768889244383, 5845.269341332333, 6162.599431036968, 6386.489080873426, 6396.6183164530585], "value": 4663.409771773694, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [807.7172989453688, 843.991560084399, 847.5973912391614, 858.9735266356703, 860.6342304520579, 15822.68354632908, 16451.34194632216, 16568.66862662746, 17911.73132403015, 18670.81329186711, 17486.21312040958, 17532.912335438305, 17646.735298970492, 17839.0, 18198.908065516047, 972.8200282947641, 994.5922171909518, 995.0944640377259, 996.6013594562197, 1000.7648202672366, 1006.2805094357533, 1014.2443879309924, 1014.2697257974247, 1015.9187265018818, 1020.7448137965508, 1050.2438244463974, 1052.6263176572322, 1053.5680371047833, 1057.1437135919891, 1058.7617785998166, 1032.8554002439664, 1045.6601604478537, 1046.691226088302, 1049.9580016799318, 1050.8056009638165, 736.9189389167187, 758.9924100758977, 777.9066512018537, 781.9374450043982, 782.9765107046798, 16425.1787410629, 16568.502944911674, 16708.164591770364, 16739.665206695798, 16740.16299185036, 16469.423570174986, 16662.250198741087, 17105.4078643485, 17168.0, 17241.65516689659, 13460.663483412894, 13557.796633050511, 13847.307634618228, 13856.653583660389, 13865.722685546334, 3457.135716070982, 3517.103138699615, 3526.84129214186, 3528.029791807248, 3528.788272703646, 101062.49468752682, 121104.0, 121197.0, 122679.3866030664, 123038.0, 12891.871081289255, 13095.345232738326, 13102.344882755824, 13205.273709945854, 13207.273599952096, 23310.88344558266, 24613.261602151604, 24906.37734056627, 24911.750882491306, 24952.625710614357, 8821.911780882108, 9023.233025192802, 9144.039875813078, 9150.084991500797, 9151.816963660689, 4761.523847615211, 4816.422029356429, 4842.394700662418, 4854.296127061556, 4860.586850117779, 4105.856295029719, 4238.660907127484, 4239.321708526621, 4249.490061192677, 4254.893627659333, 928.0997432490474, 943.9244860411151, 953.8187744328694, 967.0136460809935, 968.1045033344128, 630.6688988281189, 643.2923783837834, 647.2427260105687, 655.2890114226118, 686.9072675188886, 10777.245592808586, 11459.942700286445, 11745.88254117465, 11979.460924258256, 12127.636170914886, 640.2125385775522, 648.7307767276527, 651.2478087808589, 655.2366493035655, 676.5196710335716, 4576.970181709155, 4704.976475117671, 5260.579153667697, 5345.572354211654, 5482.150266708666, 93668.53165734127, 95377.0, 97467.51266243623, 104910.4754476238, 107386.0, 93270.53364733132, 93701.5314923421, 94979.0, 95170.0, 97284.56788580201, 82896.7565486506, 84550.0, 84690.99999999876, 85060.57469712613, 85683.14316856876, 8942.552872356226, 10639.829618742087, 10924.453777311262, 11214.88785112171, 11262.662120136409, 42029.0, 42349.57650423457, 42567.73639025849, 42666.78666606647, 42981.785091074344, 50915.49084509107, 55353.44646553483, 55603.0, 55995.72002139963, 56643.0, 13131.277779722206, 13140.211587304791, 13252.138610990254, 13366.599002029954, 13415.128016678884, 14988.775168372702, 15294.694106118039, 15312.081275123528, 15328.0, 15428.0, 14167.716645666822, 14203.786943196066, 14228.715425691635, 14256.0, 14368.0, 14252.358643860844, 14280.928595356956, 14307.284635767961, 14419.927900360432, 14524.20116893581, 13984.440622375398, 13988.370523326272, 14010.649733756738, 14016.018878678802, 14130.576082717535, 10286.125679317342, 10334.276600637882, 10520.0, 10741.892581074402, 10779.622713205, 9845.655402060893, 11088.889111108785, 11205.43972801373, 11450.312981221154, 11620.883791162318], "value": 8161.8183208413675, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1701.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 172.0, 107.0, 181.0, 180.0, 110.0, 180.0, 181.0, 182.0, 123.0], "value": 156.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 100.0, 144.0, 199.0, 152.0, 114.0, 116.0, 118.0, 138.0, 119.0], "value": 133.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 233.0, 229.0, 311.0, 300.0, 215.0, 210.0, 210.0, 212.0, 216.0], "value": 236.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 71.0, 115.0, 94.0, 88.0, 65.0, 58.0, 79.0, 64.0], "value": 77.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 73.0, 86.0, 75.0, 72.0, 74.0, 88.0, 73.0, 73.0, 89.0], "value": 79.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 130.0, 99.0, 140.0, 137.0, 92.0, 94.0, 93.0, 93.0, 119.0], "value": 109.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 101.0, 102.0, 103.0, 101.0, 101.0, 104.0, 103.0, 101.0, 159.0], "value": 107.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 103.0, 105.0, 114.0, 103.0, 100.0, 144.0, 155.0, 119.0, 133.0], "value": 118.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 51.0, 53.0, 54.0, 62.0, 81.0, 58.0, 46.0, 48.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 66.0, 72.0, 69.0, 66.0, 61.0, 59.0, 58.0, 60.0], "value": 63.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 102.0, 89.0, 108.0, 114.0, 114.0, 87.0, 88.0, 87.0, 88.0], "value": 96.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 170.0, 144.0, 139.0, 162.0, 150.0, 190.0, 172.0, 225.0, 178.0], "value": 169.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 241.0, 197.0, 229.0, 201.0, 260.0, 258.0, 196.0, 196.0, 195.0], "value": 217.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 71.0, 61.0, 93.0, 73.0, 74.0, 99.0, 106.0, 102.0], "value": 81.7, "unit": "score"}]}]} 05:06:39 INFO - Return code: 0 05:06:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:06:39 ERROR - # TBPL SUCCESS # 05:06:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:06:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:06:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:06:39 INFO - 2017-04-11 05:06:39.129 screenresolution[1939:19702] starting screenresolution argv=screenresolution get 05:06:39 INFO - 2017-04-11 05:06:39.131 screenresolution[1939:19702] Display 0: 1600x1200x32@60 05:06:39 INFO - 2017-04-11 05:06:39.137 screenresolution[1940:19705] starting screenresolution argv=screenresolution list 05:06:39 INFO - Available Modes on Display 0 05:06:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:06:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:06:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:06:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:06:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:06:39 INFO - Intel Iris: 05:06:39 INFO - Chipset Model: Intel Iris 05:06:39 INFO - Type: GPU 05:06:39 INFO - Bus: Built-In 05:06:39 INFO - VRAM (Dynamic, Max): 1536 MB 05:06:39 INFO - Vendor: Intel (0x8086) 05:06:39 INFO - Device ID: 0x0a2e 05:06:39 INFO - Revision ID: 0x0009 05:06:39 INFO - Displays: 05:06:39 INFO - Display: 05:06:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:06:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:06:39 INFO - Display Serial Number: 1600x1200 60 05:06:39 INFO - Main Display: Yes 05:06:39 INFO - Mirror: Off 05:06:39 INFO - Online: Yes 05:06:39 INFO - Rotation: Supported 05:06:39 INFO - Return code: 0 05:06:39 INFO - Running post-action listener: _resource_record_post_action 05:06:39 INFO - [mozharness: 2017-04-11 12:06:39.391968Z] Finished run-tests step (success) 05:06:39 INFO - Running post-run listener: _resource_record_post_run 05:06:39 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 1523712; Write bytes: 523705856; Read time: 16; Write time: 1691 05:06:39 INFO - TinderboxPrint: CPU usage
54.2% 05:06:39 INFO - TinderboxPrint: I/O read bytes / time
1,523,712 / 16 05:06:39 INFO - TinderboxPrint: I/O write bytes / time
523,705,856 / 1,691 05:06:39 INFO - TinderboxPrint: CPU idle
2,201.7 (45.8%) 05:06:39 INFO - TinderboxPrint: CPU system
972.3 (20.2%) 05:06:39 INFO - TinderboxPrint: CPU user
1,628.6 (33.9%) 05:06:39 INFO - TinderboxPrint: Swap in / out
209,666,048 / 0 05:06:39 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207077888; Write bytes: 204527616; Read time: 16216; Write time: 446 05:06:39 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 1474560; Write bytes: 312026624; Read time: 15; Write time: 1212 05:06:39 INFO - Running post-run listener: _upload_blobber_files 05:06:39 INFO - Blob upload gear active. 05:06:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:06:39 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 05:06:39 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'] 05:06:39 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 05:06:40 INFO - (blobuploader) - INFO - Open directory for files ... 05:06:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:06:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:06:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:41 INFO - (blobuploader) - INFO - Done attempting. 05:06:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:06:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:06:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:42 INFO - (blobuploader) - INFO - Done attempting. 05:06:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:06:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:06:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:43 INFO - (blobuploader) - INFO - Done attempting. 05:06:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:06:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:06:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:45 INFO - (blobuploader) - INFO - Done attempting. 05:06:45 INFO - (blobuploader) - INFO - Iteration through files over. 05:06:45 INFO - Return code: 0 05:06:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:06:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:06:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8164f549b281b6ce2a466a7067a5862c9b1840a42772f139e038981018c3744640bc013cfc3d19451a50749eb97658c7a3b01f9b1a3bbbe6ffef824e3003a37c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4f26a89624429f78ff8c3206f7c69c6ea09264f4090ae450da013d23109bac62554442b142b49ff5e80e6f225894fd62e91826dbfc348358fe2928440a768a88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8244fc39e47ff6efe693fd8fb4107465f4f292a3249920282fbe6594704fd69cca86c2f27245ba0c0be22ba2a11ebca2a012d7468acf7c1efc1d1a5aeca3dbb3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f26d4b1974c9a94260890311cf55833270bf6cf6111967b9cee82afd81c75da9a73f712657e90d56c3831e3f9bb334e67b622daa2a12bf2bccd0e99b5ca70995"} 05:06:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:06:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:06:45 INFO - Contents: 05:06:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8164f549b281b6ce2a466a7067a5862c9b1840a42772f139e038981018c3744640bc013cfc3d19451a50749eb97658c7a3b01f9b1a3bbbe6ffef824e3003a37c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4f26a89624429f78ff8c3206f7c69c6ea09264f4090ae450da013d23109bac62554442b142b49ff5e80e6f225894fd62e91826dbfc348358fe2928440a768a88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8244fc39e47ff6efe693fd8fb4107465f4f292a3249920282fbe6594704fd69cca86c2f27245ba0c0be22ba2a11ebca2a012d7468acf7c1efc1d1a5aeca3dbb3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f26d4b1974c9a94260890311cf55833270bf6cf6111967b9cee82afd81c75da9a73f712657e90d56c3831e3f9bb334e67b622daa2a12bf2bccd0e99b5ca70995"} 05:06:45 INFO - Running post-run listener: copy_logs_to_upload_dir 05:06:45 INFO - Copying logs to upload dir... 05:06:45 INFO - mkdir: /builds/slave/test/build/upload/logs 05:06:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1377.570747 ========= master_lag: 0.37 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 57 secs) (at 2017-04-11 05:06:45.380557) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 05:06:45.384549) ========= 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.QFOQFDXZzA/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.sBKY6oqUKD/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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/8164f549b281b6ce2a466a7067a5862c9b1840a42772f139e038981018c3744640bc013cfc3d19451a50749eb97658c7a3b01f9b1a3bbbe6ffef824e3003a37c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4f26a89624429f78ff8c3206f7c69c6ea09264f4090ae450da013d23109bac62554442b142b49ff5e80e6f225894fd62e91826dbfc348358fe2928440a768a88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8244fc39e47ff6efe693fd8fb4107465f4f292a3249920282fbe6594704fd69cca86c2f27245ba0c0be22ba2a11ebca2a012d7468acf7c1efc1d1a5aeca3dbb3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f26d4b1974c9a94260890311cf55833270bf6cf6111967b9cee82afd81c75da9a73f712657e90d56c3831e3f9bb334e67b622daa2a12bf2bccd0e99b5ca70995"} build_url:https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011139 build_url: 'https://queue.taskcluster.net/v1/task/UDc03ovWTk2Bzp8JngEIMg/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/8164f549b281b6ce2a466a7067a5862c9b1840a42772f139e038981018c3744640bc013cfc3d19451a50749eb97658c7a3b01f9b1a3bbbe6ffef824e3003a37c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4f26a89624429f78ff8c3206f7c69c6ea09264f4090ae450da013d23109bac62554442b142b49ff5e80e6f225894fd62e91826dbfc348358fe2928440a768a88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8244fc39e47ff6efe693fd8fb4107465f4f292a3249920282fbe6594704fd69cca86c2f27245ba0c0be22ba2a11ebca2a012d7468acf7c1efc1d1a5aeca3dbb3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f26d4b1974c9a94260890311cf55833270bf6cf6111967b9cee82afd81c75da9a73f712657e90d56c3831e3f9bb334e67b622daa2a12bf2bccd0e99b5ca70995"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 05:06:45.423226) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 05:06:45.423555) ========= 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.QFOQFDXZzA/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.sBKY6oqUKD/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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.005173 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 05:06:45.472558) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 05:06:45.472896) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 05:06:45.473211) ========= ========= Total master_lag: 0.51 =========