builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0318 starttime: 1490193150.65 results: success (0) buildid: 20170322062535 builduid: 1f60f2262a9e46faad01fed684b42d99 revision: ee30286771eb83c0878621485009dc2722daa78f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 07:32:30.647506) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 07:32:30.647962) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 07:32:30.648251) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 07:32:30.675203) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 07:32:30.675572) ========= 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.F5eAYLpliq/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.AIrjM7Bscv/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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-03-22 07:32:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 22.3M=0.001s 2017-03-22 07:32:31 (22.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.096636 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 07:32:30.796224) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 07:32:30.796530) ========= 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.F5eAYLpliq/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.AIrjM7Bscv/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.026396 ========= master_lag: 0.27 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 07:32:31.097896) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-22 07:32:31.098255) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ee30286771eb83c0878621485009dc2722daa78f --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 ee30286771eb83c0878621485009dc2722daa78f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.F5eAYLpliq/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.AIrjM7Bscv/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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-03-22 07:32:31,760 truncating revision to first 12 chars 2017-03-22 07:32:31,760 Setting DEBUG logging. 2017-03-22 07:32:31,760 attempt 1/10 2017-03-22 07:32:31,760 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ee30286771eb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-22 07:32:33,788 unpacking tar archive at: mozilla-central-ee30286771eb/testing/mozharness/ program finished with exit code 0 elapsedTime=2.308500 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-22 07:32:33.430026) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 07:32:33.430355) ========= script_repo_revision: ee30286771eb83c0878621485009dc2722daa78f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 07:32:33.430729) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 07:32:33.430987) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 07:32:33.450860) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-22 07:32:33.451255) ========= /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.F5eAYLpliq/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.AIrjM7Bscv/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 07:32:34 INFO - MultiFileLogger online at 20170322 07:32:34 in /builds/slave/test 07:32:34 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 07:32:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:32:34 INFO - {'append_to_log': False, 07:32:34 INFO - 'base_work_dir': '/builds/slave/test', 07:32:34 INFO - 'blob_upload_branch': 'Firefox', 07:32:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:32:34 INFO - 'branch': 'Firefox', 07:32:34 INFO - 'buildbot_json_path': 'buildprops.json', 07:32:34 INFO - 'config_files': ('talos/mac_config.py',), 07:32:34 INFO - 'default_actions': ('clobber', 07:32:34 INFO - 'read-buildbot-config', 07:32:34 INFO - 'download-and-extract', 07:32:34 INFO - 'populate-webroot', 07:32:34 INFO - 'create-virtualenv', 07:32:34 INFO - 'install', 07:32:34 INFO - 'run-tests'), 07:32:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:32:34 INFO - 'download_minidump_stackwalk': True, 07:32:34 INFO - 'download_symbols': 'ondemand', 07:32:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:32:34 INFO - 'tooltool.py': '/tools/tooltool.py', 07:32:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:32:34 INFO - '/tools/misc-python/virtualenv.py')}, 07:32:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:32:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:32:34 INFO - 'gecko_profile': False, 07:32:34 INFO - 'gecko_profile_interval': 0, 07:32:34 INFO - 'installer_path': 'installer.exe', 07:32:34 INFO - 'log_level': 'info', 07:32:34 INFO - 'log_name': 'talos', 07:32:34 INFO - 'log_to_console': True, 07:32:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:32:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:32:34 INFO - 'opt_config_files': (), 07:32:34 INFO - 'pip_index': False, 07:32:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:32:34 INFO - 'cmd': ('bash', 07:32:34 INFO - '-c', 07:32:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:32:34 INFO - 'enabled': True, 07:32:34 INFO - 'halt_on_failure': False, 07:32:34 INFO - 'name': 'check_screen_resolution'},), 07:32:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:32:34 INFO - 'cmd': ('bash', 07:32:34 INFO - '-c', 07:32:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:32:34 INFO - 'enabled': True, 07:32:34 INFO - 'halt_on_failure': False, 07:32:34 INFO - 'name': 'check_screen_resolution'},), 07:32:34 INFO - 'run_cmd_checks_enabled': True, 07:32:34 INFO - 'suite': 'dromaeojs-e10s', 07:32:34 INFO - 'system_bits': '32', 07:32:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:32:34 INFO - 'title': 't-yosemite-r7-0318', 07:32:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:32:34 INFO - 'use_talos_json': True, 07:32:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:32:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:32:34 INFO - 'work_dir': 'build'} 07:32:34 INFO - [mozharness: 2017-03-22 14:32:34.219643Z] Running clobber step. 07:32:34 INFO - Running pre-action listener: _resource_record_pre_action 07:32:34 INFO - Running main action method: clobber 07:32:34 INFO - rmtree: /builds/slave/test/build 07:32:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:32:36 INFO - Running post-action listener: _resource_record_post_action 07:32:36 INFO - [mozharness: 2017-03-22 14:32:36.594445Z] Finished clobber step (success) 07:32:36 INFO - [mozharness: 2017-03-22 14:32:36.594569Z] Running read-buildbot-config step. 07:32:36 INFO - Running pre-action listener: _resource_record_pre_action 07:32:36 INFO - Running main action method: read_buildbot_config 07:32:36 INFO - Using buildbot properties: 07:32:36 INFO - { 07:32:36 INFO - "project": "", 07:32:36 INFO - "product": "firefox", 07:32:36 INFO - "script_repo_revision": "production", 07:32:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:32:36 INFO - "repository": "", 07:32:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:32:36 INFO - "buildid": "20170322062535", 07:32:36 INFO - "pgo_build": "False", 07:32:36 INFO - "basedir": "/builds/slave/test", 07:32:36 INFO - "buildnumber": 16, 07:32:36 INFO - "slavename": "t-yosemite-r7-0318", 07:32:36 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:32:36 INFO - "platform": "yosemite_r7", 07:32:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:32:36 INFO - "branch": "mozilla-central", 07:32:36 INFO - "repo_path": "mozilla-central", 07:32:36 INFO - "revision": "ee30286771eb83c0878621485009dc2722daa78f", 07:32:36 INFO - "stage_platform": "macosx64", 07:32:36 INFO - "builduid": "1f60f2262a9e46faad01fed684b42d99", 07:32:36 INFO - "slavebuilddir": "test" 07:32:36 INFO - } 07:32:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 07:32:36 INFO - Running post-action listener: _resource_record_post_action 07:32:36 INFO - [mozharness: 2017-03-22 14:32:36.597974Z] Finished read-buildbot-config step (success) 07:32:36 INFO - [mozharness: 2017-03-22 14:32:36.598111Z] Running download-and-extract step. 07:32:36 INFO - Running pre-action listener: _resource_record_pre_action 07:32:36 INFO - Running main action method: download_and_extract 07:32:36 INFO - mkdir: /builds/slave/test/build/tests 07:32:36 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')]} 07:32:36 INFO - trying https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:32:36 INFO - Downloading https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/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 07:32:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/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 07:32:37 INFO - Downloaded 1325 bytes. 07:32:37 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:32:37 INFO - Using the following test package requirements: 07:32:37 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:32:37 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:32:37 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:32:37 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:32:37 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:32:37 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:32:37 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:32:37 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:32:37 INFO - u'jsshell-mac.zip'], 07:32:37 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:32:37 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:32:37 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:32:37 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:32:37 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:32:37 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:32:37 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:32:37 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:32:37 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 07:32:37 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:32:37 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:32:37 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:32:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:32:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:32:37 INFO - Fetch https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:32:39 INFO - Expected file size: 52478802 07:32:39 INFO - Obtained file size: 52478802 07:32:56 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 07:32:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:32:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:32:56 INFO - Fetch https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:32:58 INFO - Expected file size: 52478802 07:32:58 INFO - Obtained file size: 52478802 07:33:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:33:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:33:14 INFO - Fetch https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:33:14 INFO - Expected file size: 14404171 07:33:14 INFO - Obtained file size: 14404171 07:33:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:15 INFO - trying https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:33:15 INFO - Downloading https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:33:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/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 07:33:17 INFO - Downloaded 75286187 bytes. 07:33:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:33:17 INFO - mkdir: /builds/slave/test/properties 07:33:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:33:17 INFO - Writing to file /builds/slave/test/properties/build_url 07:33:17 INFO - Contents: 07:33:17 INFO - build_url:https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:33:18 INFO - Running post-action listener: _resource_record_post_action 07:33:18 INFO - Running post-action listener: set_extra_try_arguments 07:33:18 INFO - [mozharness: 2017-03-22 14:33:18.085107Z] Finished download-and-extract step (success) 07:33:18 INFO - [mozharness: 2017-03-22 14:33:18.085233Z] Running populate-webroot step. 07:33:18 INFO - Running pre-action listener: _resource_record_pre_action 07:33:18 INFO - Running main action method: populate_webroot 07:33:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:33:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:33:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:33:18 INFO - '--tppagecycles', 07:33:18 INFO - '7'], 07:33:18 INFO - 'tests': ['tsvgm']}}, 07:33:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:33:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:33:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:33:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:33:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:33:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:33:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:33:18 INFO - 'talos_options': ['--disable-e10s'], 07:33:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:33:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:33:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:33:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:33:18 INFO - 'talos_options': ['--disable-e10s'], 07:33:18 INFO - 'tests': ['damp', 'tps']}, 07:33:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:33:18 INFO - 'tests': ['damp', 'tps']}, 07:33:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:33:18 INFO - 'tests': ['dromaeo_dom']}, 07:33:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:33:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:33:18 INFO - 'tests': ['basic_compositor_video']}, 07:33:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:33:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:33:18 INFO - 'tests': ['a11yr', 07:33:18 INFO - 'ts_paint', 07:33:18 INFO - 'tpaint', 07:33:18 INFO - 'sessionrestore', 07:33:18 INFO - 'sessionrestore_no_auto_restore', 07:33:18 INFO - 'tabpaint']}, 07:33:18 INFO - 'other-e10s': {'tests': ['a11yr', 07:33:18 INFO - 'ts_paint', 07:33:18 INFO - 'tpaint', 07:33:18 INFO - 'sessionrestore', 07:33:18 INFO - 'sessionrestore_no_auto_restore', 07:33:18 INFO - 'tabpaint']}, 07:33:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:33:18 INFO - 'ts_paint', 07:33:18 INFO - 'tpaint', 07:33:18 INFO - 'sessionrestore', 07:33:18 INFO - 'sessionrestore_no_auto_restore', 07:33:18 INFO - 'tabpaint']}, 07:33:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:33:18 INFO - 'ts_paint', 07:33:18 INFO - 'tpaint', 07:33:18 INFO - 'sessionrestore', 07:33:18 INFO - 'sessionrestore_no_auto_restore', 07:33:18 INFO - 'tabpaint']}, 07:33:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:33:18 INFO - 'tests': ['a11yr', 07:33:18 INFO - 'ts_paint', 07:33:18 INFO - 'tpaint', 07:33:18 INFO - 'sessionrestore', 07:33:18 INFO - 'sessionrestore_no_auto_restore', 07:33:18 INFO - 'tabpaint']}, 07:33:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:33:18 INFO - 'tests': ['a11yr', 07:33:18 INFO - 'ts_paint', 07:33:18 INFO - 'tpaint', 07:33:18 INFO - 'sessionrestore', 07:33:18 INFO - 'sessionrestore_no_auto_restore', 07:33:18 INFO - 'tabpaint']}, 07:33:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:33:18 INFO - 'tests': ['tsvgx', 07:33:18 INFO - 'tsvgr_opacity', 07:33:18 INFO - 'tart', 07:33:18 INFO - 'tscrollx', 07:33:18 INFO - 'cart', 07:33:18 INFO - 'tsvg_static']}, 07:33:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:33:18 INFO - 'tsvgr_opacity', 07:33:18 INFO - 'tart', 07:33:18 INFO - 'tscrollx', 07:33:18 INFO - 'cart', 07:33:18 INFO - 'tsvg_static']}, 07:33:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:33:18 INFO - 'talos_options': ['--disable-e10s'], 07:33:18 INFO - 'tests': ['tp5o']}, 07:33:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:33:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:33:18 INFO - 'talos_options': ['--disable-e10s', 07:33:18 INFO - '--xperf_path', 07:33:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:33:18 INFO - 'tests': ['tp5n']}, 07:33:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:33:18 INFO - 'talos_options': ['--xperf_path', 07:33:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:33:18 INFO - 'tests': ['tp5n']}}, 07:33:18 INFO - 'talos.zip': {'path': '', 07:33:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:33:18 INFO - Running post-action listener: _resource_record_post_action 07:33:18 INFO - [mozharness: 2017-03-22 14:33:18.103878Z] Finished populate-webroot step (success) 07:33:18 INFO - [mozharness: 2017-03-22 14:33:18.103993Z] Running create-virtualenv step. 07:33:18 INFO - Running pre-action listener: _resource_record_pre_action 07:33:18 INFO - Running main action method: create_virtualenv 07:33:18 INFO - Creating virtualenv /builds/slave/test/build/venv 07:33:18 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 07:33:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:33:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:33:18 INFO - Using real prefix '/tools/python27' 07:33:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:33:18 INFO - Installing distribute.............................................................................................................................................................................................done. 07:33:21 INFO - Installing pip.................done. 07:33:21 INFO - Return code: 0 07:33:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:33:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:33:22 INFO - Reading from file tmpfile_stdout 07:33:22 INFO - Output received: 07:33:22 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) 07:33:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:33:22 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')]} 07:33:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:22 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')]} 07:33:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:22 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 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd11c8e210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.F5eAYLpliq/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.AIrjM7Bscv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 07:33:22 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 07:33:22 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 07:33:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F5eAYLpliq/Render', 07:33:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:22 INFO - 'HOME': '/Users/cltbld', 07:33:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:22 INFO - 'LOGNAME': 'cltbld', 07:33:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:22 INFO - 'MOZ_NO_REMOTE': '1', 07:33:22 INFO - 'NO_EM_RESTART': '1', 07:33:22 INFO - 'PAGER': '/bin/cat', 07:33:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:22 INFO - 'PWD': '/builds/slave/test', 07:33:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:22 INFO - 'SHELL': '/bin/bash', 07:33:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIrjM7Bscv/Listeners', 07:33:22 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 07:33:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:22 INFO - 'USER': 'cltbld', 07:33:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:22 INFO - 'XPC_FLAGS': '0x0', 07:33:22 INFO - 'XPC_SERVICE_NAME': '0', 07:33:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:22 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 07:33:22 INFO - Cleaning up... 07:33:22 INFO - Return code: 0 07:33:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:33:22 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')]} 07:33:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:22 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')]} 07:33:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:22 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 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd11c8e210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.F5eAYLpliq/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.AIrjM7Bscv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 07:33:22 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 07:33:22 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 07:33:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F5eAYLpliq/Render', 07:33:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:22 INFO - 'HOME': '/Users/cltbld', 07:33:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:22 INFO - 'LOGNAME': 'cltbld', 07:33:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:22 INFO - 'MOZ_NO_REMOTE': '1', 07:33:22 INFO - 'NO_EM_RESTART': '1', 07:33:22 INFO - 'PAGER': '/bin/cat', 07:33:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:22 INFO - 'PWD': '/builds/slave/test', 07:33:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:22 INFO - 'SHELL': '/bin/bash', 07:33:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIrjM7Bscv/Listeners', 07:33:22 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 07:33:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:22 INFO - 'USER': 'cltbld', 07:33:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:22 INFO - 'XPC_FLAGS': '0x0', 07:33:22 INFO - 'XPC_SERVICE_NAME': '0', 07:33:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:22 INFO - Downloading/unpacking psutil>=3.1.1 07:33: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 07:33: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 07:33: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 07:33: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 07:33: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 07:33: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 07:33:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:33:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:33:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:33:26 INFO - Installing collected packages: psutil 07:33:26 INFO - Running setup.py install for psutil 07:33:26 INFO - building 'psutil._psutil_osx' extension 07:33:26 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 07:33:26 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 07:33:26 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 07:33:26 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 07:33:26 INFO - building 'psutil._psutil_posix' extension 07:33:26 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 07:33:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:33:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:33:27 INFO - ^ 07:33:27 INFO - 1 warning generated. 07:33:27 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 07:33:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:33:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:33:27 INFO - Successfully installed psutil 07:33:27 INFO - Cleaning up... 07:33:27 INFO - Return code: 0 07:33:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:33: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')]} 07:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33: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')]} 07:33:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:27 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 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd11c8e210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.F5eAYLpliq/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.AIrjM7Bscv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 07:33:27 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 07:33:27 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 07:33:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F5eAYLpliq/Render', 07:33:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:27 INFO - 'HOME': '/Users/cltbld', 07:33:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:27 INFO - 'LOGNAME': 'cltbld', 07:33:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:27 INFO - 'MOZ_NO_REMOTE': '1', 07:33:27 INFO - 'NO_EM_RESTART': '1', 07:33:27 INFO - 'PAGER': '/bin/cat', 07:33:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:27 INFO - 'PWD': '/builds/slave/test', 07:33:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:27 INFO - 'SHELL': '/bin/bash', 07:33:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIrjM7Bscv/Listeners', 07:33:27 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 07:33:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:27 INFO - 'USER': 'cltbld', 07:33:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:27 INFO - 'XPC_FLAGS': '0x0', 07:33:27 INFO - 'XPC_SERVICE_NAME': '0', 07:33:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:33:28 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 07:33:28 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 07:33:28 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 07:33:28 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 07:33:28 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 07:33:28 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 07:33:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:33:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:33:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:33:31 INFO - Installing collected packages: mozsystemmonitor 07:33:31 INFO - Running setup.py install for mozsystemmonitor 07:33:31 INFO - Successfully installed mozsystemmonitor 07:33:31 INFO - Cleaning up... 07:33:31 INFO - Return code: 0 07:33:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:33: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')]} 07:33:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33: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')]} 07:33:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:31 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 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd11c8e210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.F5eAYLpliq/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.AIrjM7Bscv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 07:33:31 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 07:33:31 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 07:33:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F5eAYLpliq/Render', 07:33:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:31 INFO - 'HOME': '/Users/cltbld', 07:33:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:31 INFO - 'LOGNAME': 'cltbld', 07:33:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:31 INFO - 'MOZ_NO_REMOTE': '1', 07:33:31 INFO - 'NO_EM_RESTART': '1', 07:33:31 INFO - 'PAGER': '/bin/cat', 07:33:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:31 INFO - 'PWD': '/builds/slave/test', 07:33:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:31 INFO - 'SHELL': '/bin/bash', 07:33:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIrjM7Bscv/Listeners', 07:33:31 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 07:33:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:31 INFO - 'USER': 'cltbld', 07:33:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:31 INFO - 'XPC_FLAGS': '0x0', 07:33:31 INFO - 'XPC_SERVICE_NAME': '0', 07:33:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:31 INFO - Downloading/unpacking jsonschema==2.5.1 07:33:31 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 07:33:31 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 07:33:31 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 07:33:31 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 07:33:31 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 07:33:31 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 07:33:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:33:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:33:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:34 INFO - Downloading functools32-3.2.3-2.tar.gz 07:33:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:33:35 INFO - warning: no files found matching '*.txt' 07:33:35 INFO - no previously-included directories found matching 'build' 07:33:35 INFO - no previously-included directories found matching 'dist' 07:33:35 INFO - no previously-included directories found matching '.git*' 07:33:35 INFO - Installing collected packages: jsonschema, functools32 07:33:35 INFO - Running setup.py install for functools32 07:33:35 INFO - warning: no files found matching '*.txt' 07:33:35 INFO - no previously-included directories found matching 'build' 07:33:35 INFO - no previously-included directories found matching 'dist' 07:33:35 INFO - no previously-included directories found matching '.git*' 07:33:35 INFO - Successfully installed jsonschema functools32 07:33:35 INFO - Cleaning up... 07:33:35 INFO - Return code: 0 07:33:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd11c8e210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.F5eAYLpliq/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.AIrjM7Bscv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 07:33:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:33:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:33:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F5eAYLpliq/Render', 07:33:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:35 INFO - 'HOME': '/Users/cltbld', 07:33:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:35 INFO - 'LOGNAME': 'cltbld', 07:33:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:35 INFO - 'MOZ_NO_REMOTE': '1', 07:33:35 INFO - 'NO_EM_RESTART': '1', 07:33:35 INFO - 'PAGER': '/bin/cat', 07:33:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:35 INFO - 'PWD': '/builds/slave/test', 07:33:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:35 INFO - 'SHELL': '/bin/bash', 07:33:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIrjM7Bscv/Listeners', 07:33:35 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 07:33:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:35 INFO - 'USER': 'cltbld', 07:33:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:35 INFO - 'XPC_FLAGS': '0x0', 07:33:35 INFO - 'XPC_SERVICE_NAME': '0', 07:33:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:33:35 INFO - Cleaning up... 07:33:35 INFO - Return code: 0 07:33:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd11c8e210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.F5eAYLpliq/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.AIrjM7Bscv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 07:33:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:33:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:33:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F5eAYLpliq/Render', 07:33:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:35 INFO - 'HOME': '/Users/cltbld', 07:33:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:35 INFO - 'LOGNAME': 'cltbld', 07:33:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:35 INFO - 'MOZ_NO_REMOTE': '1', 07:33:35 INFO - 'NO_EM_RESTART': '1', 07:33:35 INFO - 'PAGER': '/bin/cat', 07:33:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:35 INFO - 'PWD': '/builds/slave/test', 07:33:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:35 INFO - 'SHELL': '/bin/bash', 07:33:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIrjM7Bscv/Listeners', 07:33:35 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 07:33:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:35 INFO - 'USER': 'cltbld', 07:33:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:35 INFO - 'XPC_FLAGS': '0x0', 07:33:35 INFO - 'XPC_SERVICE_NAME': '0', 07:33:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:35 INFO - Downloading/unpacking blobuploader==1.2.4 07:33:35 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 07:33:35 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 07:33:35 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 07:33:35 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 07:33:35 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 07:33:35 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 07:33:38 INFO - Downloading blobuploader-1.2.4.tar.gz 07:33:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:33:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:33:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:33:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:39 INFO - Downloading docopt-0.6.1.tar.gz 07:33:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:33:39 INFO - Installing collected packages: blobuploader, requests, docopt 07:33:39 INFO - Running setup.py install for blobuploader 07:33:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:33:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:33:39 INFO - Running setup.py install for requests 07:33:40 INFO - Running setup.py install for docopt 07:33:40 INFO - Successfully installed blobuploader requests docopt 07:33:40 INFO - Cleaning up... 07:33:40 INFO - Return code: 0 07:33:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:33:40 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')]} 07:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:40 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')]} 07:33:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:40 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 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd11c8e210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.F5eAYLpliq/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.AIrjM7Bscv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 07:33:40 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 07:33:40 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 07:33:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F5eAYLpliq/Render', 07:33:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:40 INFO - 'HOME': '/Users/cltbld', 07:33:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:40 INFO - 'LOGNAME': 'cltbld', 07:33:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:40 INFO - 'MOZ_NO_REMOTE': '1', 07:33:40 INFO - 'NO_EM_RESTART': '1', 07:33:40 INFO - 'PAGER': '/bin/cat', 07:33:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:40 INFO - 'PWD': '/builds/slave/test', 07:33:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:40 INFO - 'SHELL': '/bin/bash', 07:33:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIrjM7Bscv/Listeners', 07:33:40 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 07:33:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:40 INFO - 'USER': 'cltbld', 07:33:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:40 INFO - 'XPC_FLAGS': '0x0', 07:33:40 INFO - 'XPC_SERVICE_NAME': '0', 07:33:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:33:40 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-SNRHP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:33:41 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-yZw7c3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:33:41 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-hUq0jB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:33:41 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-2iEZOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:33:41 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-NiOKRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:33:41 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-xLU9hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:33:41 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-J_uTj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:33:41 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-oZ6IMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:33:41 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-IdCu3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:33:41 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-fOQR4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:33:41 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-aH0dZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:33:42 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-D6qvBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:33:42 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-IsFMma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:33:42 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-KyQji_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:33:42 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-hxj6f6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:33:42 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-XtHBdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:33:42 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-AYy1to-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:33:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:33:42 INFO - Running setup.py install for manifestparser 07:33:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:33:42 INFO - Running setup.py install for mozcrash 07:33:42 INFO - Running setup.py install for mozdebug 07:33:43 INFO - Running setup.py install for mozdevice 07:33:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:33:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:33:43 INFO - Running setup.py install for mozfile 07:33:43 INFO - Running setup.py install for mozhttpd 07:33:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:33:43 INFO - Running setup.py install for mozinfo 07:33:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:33:43 INFO - Running setup.py install for mozInstall 07:33:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:33:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:33:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:33:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:33:43 INFO - Running setup.py install for mozleak 07:33:44 INFO - Running setup.py install for mozlog 07:33:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:33:44 INFO - Running setup.py install for moznetwork 07:33:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:33:44 INFO - Running setup.py install for mozprocess 07:33:44 INFO - Running setup.py install for mozprofile 07:33:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:33:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:33:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:33:44 INFO - Running setup.py install for mozrunner 07:33:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:33:44 INFO - Running setup.py install for mozscreenshot 07:33:45 INFO - Running setup.py install for moztest 07:33:45 INFO - Running setup.py install for mozversion 07:33:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:33:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:33:45 INFO - Cleaning up... 07:33:45 INFO - Return code: 0 07:33:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:33:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd11c8e210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.F5eAYLpliq/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.AIrjM7Bscv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 07:33:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:33:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:33:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F5eAYLpliq/Render', 07:33:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:45 INFO - 'HOME': '/Users/cltbld', 07:33:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:45 INFO - 'LOGNAME': 'cltbld', 07:33:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:45 INFO - 'MOZ_NO_REMOTE': '1', 07:33:45 INFO - 'NO_EM_RESTART': '1', 07:33:45 INFO - 'PAGER': '/bin/cat', 07:33:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:45 INFO - 'PWD': '/builds/slave/test', 07:33:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:45 INFO - 'SHELL': '/bin/bash', 07:33:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIrjM7Bscv/Listeners', 07:33:45 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 07:33:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:45 INFO - 'USER': 'cltbld', 07:33:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:45 INFO - 'XPC_FLAGS': '0x0', 07:33:45 INFO - 'XPC_SERVICE_NAME': '0', 07:33:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:33:45 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-p_5x5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:33:45 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)) 07:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:33:45 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Wa6lBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:33:45 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)) 07:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:33:45 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-uVIXpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:33:45 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)) 07:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:33:45 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-V0k4Wi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 07:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:33:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-_EWmSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:33:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-4ZY5wV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:33:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-NXKVVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:33:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-9pSnRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:33:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-NOhLv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:33:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-naH4Fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:33:46 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)) 07:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:33:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-MM8W0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:33:46 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)) 07:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:33:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-rDBl0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:33:46 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)) 07:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:33:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-34o24s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:33:46 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)) 07:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:33:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-PaBUhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:33:47 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-P6stvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:33:47 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-n6ksl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:33:47 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-iddkVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:33:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:49 INFO - Downloading blessings-1.6.tar.gz 07:33:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:33:50 INFO - Installing collected packages: blessings 07:33:50 INFO - Running setup.py install for blessings 07:33:50 INFO - Successfully installed blessings 07:33:50 INFO - Cleaning up... 07:33:50 INFO - Return code: 0 07:33:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:33:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:33:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:33:50 INFO - Reading from file tmpfile_stdout 07:33:50 INFO - Current package versions: 07:33:50 INFO - blessings == 1.6 07:33:50 INFO - blobuploader == 1.2.4 07:33:50 INFO - docopt == 0.6.1 07:33:50 INFO - functools32 == 3.2.3-2 07:33:50 INFO - jsonschema == 2.5.1 07:33:50 INFO - manifestparser == 1.1 07:33:50 INFO - mozInstall == 1.12 07:33:50 INFO - mozcrash == 1.0 07:33:50 INFO - mozdebug == 0.1 07:33:50 INFO - mozdevice == 0.49 07:33:50 INFO - mozfile == 1.2 07:33:50 INFO - mozhttpd == 0.7 07:33:50 INFO - mozinfo == 0.9 07:33:50 INFO - mozleak == 0.1 07:33:50 INFO - mozlog == 3.4 07:33:50 INFO - moznetwork == 0.27 07:33:50 INFO - mozprocess == 0.25 07:33:50 INFO - mozprofile == 0.28 07:33:50 INFO - mozrunner == 6.13 07:33:50 INFO - mozscreenshot == 0.1 07:33:50 INFO - mozsystemmonitor == 0.3 07:33:50 INFO - moztest == 0.8 07:33:50 INFO - mozversion == 1.4 07:33:50 INFO - psutil == 3.1.1 07:33:50 INFO - requests == 1.2.3 07:33:50 INFO - wsgiref == 0.1.2 07:33:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:50 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 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd11c8e210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.F5eAYLpliq/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.AIrjM7Bscv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 07:33:50 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 07:33:50 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 07:33:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F5eAYLpliq/Render', 07:33:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:50 INFO - 'HOME': '/Users/cltbld', 07:33:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:50 INFO - 'LOGNAME': 'cltbld', 07:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:50 INFO - 'MOZ_NO_REMOTE': '1', 07:33:50 INFO - 'NO_EM_RESTART': '1', 07:33:50 INFO - 'PAGER': '/bin/cat', 07:33:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:50 INFO - 'PWD': '/builds/slave/test', 07:33:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:50 INFO - 'SHELL': '/bin/bash', 07:33:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIrjM7Bscv/Listeners', 07:33:50 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 07:33:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:50 INFO - 'USER': 'cltbld', 07:33:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:50 INFO - 'XPC_FLAGS': '0x0', 07:33:50 INFO - 'XPC_SERVICE_NAME': '0', 07:33:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:50 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)) 07:33:50 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)) 07:33:50 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)) 07:33:50 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)) 07:33:50 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)) 07:33:50 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)) 07:33:50 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)) 07:33:50 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)) 07:33:50 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)) 07:33:50 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)) 07:33:50 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)) 07:33:50 INFO - Cleaning up... 07:33:50 INFO - Return code: 0 07:33:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd11c8e210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.F5eAYLpliq/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.AIrjM7Bscv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 07:33:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:33:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:33:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F5eAYLpliq/Render', 07:33:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:50 INFO - 'HOME': '/Users/cltbld', 07:33:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:50 INFO - 'LOGNAME': 'cltbld', 07:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:50 INFO - 'MOZ_NO_REMOTE': '1', 07:33:50 INFO - 'NO_EM_RESTART': '1', 07:33:50 INFO - 'PAGER': '/bin/cat', 07:33:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:50 INFO - 'PWD': '/builds/slave/test', 07:33:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:50 INFO - 'SHELL': '/bin/bash', 07:33:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIrjM7Bscv/Listeners', 07:33:50 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 07:33:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:50 INFO - 'USER': 'cltbld', 07:33:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:50 INFO - 'XPC_FLAGS': '0x0', 07:33:50 INFO - 'XPC_SERVICE_NAME': '0', 07:33:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:33:51 INFO - Cleaning up... 07:33:51 INFO - Return code: 0 07:33:51 INFO - Running post-action listener: _resource_record_post_action 07:33:51 INFO - Running post-action listener: _start_resource_monitoring 07:33:51 INFO - Starting resource monitoring. 07:33:51 INFO - [mozharness: 2017-03-22 14:33:51.172333Z] Finished create-virtualenv step (success) 07:33:51 INFO - [mozharness: 2017-03-22 14:33:51.173289Z] Running install step. 07:33:51 INFO - Running pre-action listener: _resource_record_pre_action 07:33:51 INFO - Running main action method: install 07:33:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:33:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:33:51 INFO - Reading from file tmpfile_stdout 07:33:51 INFO - Detecting whether we're running mozinstall >=1.0... 07:33:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:33:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:33:51 INFO - Reading from file tmpfile_stdout 07:33:51 INFO - Output received: 07:33:51 INFO - Usage: mozinstall [options] installer 07:33:51 INFO - Options: 07:33:51 INFO - -h, --help show this help message and exit 07:33:51 INFO - -d DEST, --destination=DEST 07:33:51 INFO - Directory to install application into. [default: 07:33:51 INFO - "/builds/slave/test"] 07:33:51 INFO - --app=APP Application being installed. [default: firefox] 07:33:51 INFO - mkdir: /builds/slave/test/build/application 07:33:51 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'] 07:33:51 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 07:34:11 INFO - Reading from file tmpfile_stdout 07:34:11 INFO - Output received: 07:34:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:34:11 INFO - Running post-action listener: _resource_record_post_action 07:34:11 INFO - [mozharness: 2017-03-22 14:34:11.920392Z] Finished install step (success) 07:34:11 INFO - [mozharness: 2017-03-22 14:34:11.920521Z] Running run-tests step. 07:34:11 INFO - Running pre-action listener: _resource_record_pre_action 07:34:11 INFO - Running main action method: run_tests 07:34:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:34:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:34:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:34:11 INFO - 2017-03-22 07:34:11.935 screenresolution[1628:7423] starting screenresolution argv=screenresolution get 07:34:11 INFO - 2017-03-22 07:34:11.937 screenresolution[1628:7423] Display 0: 1600x1200x32@60 07:34:11 INFO - 2017-03-22 07:34:11.943 screenresolution[1629:7425] starting screenresolution argv=screenresolution list 07:34:11 INFO - Available Modes on Display 0 07:34:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:34:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:34:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:34:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:34:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:34:12 INFO - Intel Iris: 07:34:12 INFO - Chipset Model: Intel Iris 07:34:12 INFO - Type: GPU 07:34:12 INFO - Bus: Built-In 07:34:12 INFO - VRAM (Dynamic, Max): 1536 MB 07:34:12 INFO - Vendor: Intel (0x8086) 07:34:12 INFO - Device ID: 0x0a2e 07:34:12 INFO - Revision ID: 0x0009 07:34:12 INFO - Displays: 07:34:12 INFO - Display: 07:34:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:34:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:34:12 INFO - Display Serial Number: 1600x1200 60 07:34:12 INFO - Main Display: Yes 07:34:12 INFO - Mirror: Off 07:34:12 INFO - Online: Yes 07:34:12 INFO - Rotation: Supported 07:34:12 INFO - Return code: 0 07:34:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:34:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:34:12 INFO - Python 2.7.3 07:34:12 INFO - Return code: 0 07:34:12 INFO - grabbing minidump binary from tooltool 07:34:12 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')]} 07:34:12 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 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:34:12 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 07:34:12 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 07:34:12 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 07:34:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:34:12 INFO - Return code: 0 07:34:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:34:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:34:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:34:12 INFO - ENV: RUST_BACKTRACE is now 1 07:34:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:34:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:34:12 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 07:34:12 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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0318 --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 07:34:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F5eAYLpliq/Render', 07:34:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:34:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:34:12 INFO - 'HOME': '/Users/cltbld', 07:34:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:34:12 INFO - 'LOGNAME': 'cltbld', 07:34:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:34:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:12 INFO - 'MOZ_NO_REMOTE': '1', 07:34:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:34:12 INFO - 'NO_EM_RESTART': '1', 07:34:12 INFO - 'PAGER': '/bin/cat', 07:34:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:12 INFO - 'PWD': '/builds/slave/test', 07:34:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:34:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:34:12 INFO - 'RUST_BACKTRACE': '1', 07:34:12 INFO - 'SHELL': '/bin/bash', 07:34:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIrjM7Bscv/Listeners', 07:34:12 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 07:34:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:34:12 INFO - 'USER': 'cltbld', 07:34:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:34:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:12 INFO - 'XPC_FLAGS': '0x0', 07:34:12 INFO - 'XPC_SERVICE_NAME': '0', 07:34:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:34:12 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 07:34:12 INFO - mozversion application_buildid: 20170322062535 07:34:12 INFO - mozversion application_changeset: ee30286771eb83c0878621485009dc2722daa78f 07:34:12 INFO - mozversion application_display_name: Nightly 07:34:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:34:12 INFO - mozversion application_name: Firefox 07:34:12 INFO - mozversion application_remotingname: firefox 07:34:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:34:12 INFO - mozversion application_vendor: Mozilla 07:34:12 INFO - mozversion application_version: 55.0a1 07:34:12 INFO - mozversion platform_buildid: 20170322062535 07:34:12 INFO - mozversion platform_changeset: ee30286771eb83c0878621485009dc2722daa78f 07:34:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:34:12 INFO - mozversion platform_version: 55.0a1 07:34:12 INFO - using testdate: 1490193252 07:34:12 INFO - actual date: 1490193252 07:34:12 INFO - starting webserver on 'localhost:49242' 07:34:12 INFO - SUITE-START | Running 2 tests 07:34:12 INFO - TEST-START | dromaeo_css 07:34:12 INFO - operating with platform_type : mac_ 07:34:12 INFO - Initialising browser for dromaeo_css test... 07:34:12 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpEh_T9Q/profile http://localhost:49242/getInfo.html) 07:34:15 INFO - PID 1639 | 2017-03-22 07:34:15.272 plugin-container[1641:7599] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 07:34:15 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:34:15 INFO - PID 1639 | 2017-03-22 07:34:15.275 plugin-container[1641:7599] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a13, name = 'com.apple.CFPasteboardClient' 07:34:15 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:34:15 INFO - PID 1639 | 2017-03-22 07:34:15.275 plugin-container[1641:7599] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:34:15 INFO - PID 1639 | __metrics Screen width/height:1600/1200 07:34:15 INFO - PID 1639 | colorDepth:24 07:34:15 INFO - PID 1639 | Browser inner width/height: 1024/689 07:34:15 INFO - PID 1639 | __metrics 07:34:15 ERROR - PID 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 07:34:16 INFO - TEST-INFO | 1639: exit 0 07:34:16 INFO - Browser initialized. 07:34:16 INFO - Running cycle 1/1 for dromaeo_css test... 07:34:16 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpEh_T9Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:34:18 INFO - PID 1642 | 2017-03-22 07:34:18.598 plugin-container[1643:7742] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 07:34:18 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:34:18 INFO - PID 1642 | 2017-03-22 07:34:18.599 plugin-container[1643:7742] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 07:34:18 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:34:18 INFO - PID 1642 | 2017-03-22 07:34:18.599 plugin-container[1643:7742] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:34:28 INFO - PID 1642 | RSS: Main: 226775040 07:34:28 INFO - PID 1642 | 07:37:32 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 07:37:34 INFO - PID 1642 | RSS: Main: 252133376 07:37:34 INFO - PID 1642 | 07:39:16 INFO - PID 1642 | ************************* 07:39:16 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 07:39:16 INFO - PID 1642 | 07:39:16 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:39:16 INFO - PID 1642 | Full stack: 07:39:16 INFO - PID 1642 | ************************* 07:40:36 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 07:40:37 INFO - PID 1642 | RSS: Main: 258633728 07:40:37 INFO - PID 1642 | 07:43:39 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 07:43:39 INFO - PID 1642 | RSS: Main: 270712832 07:43:39 INFO - PID 1642 | 07:46:41 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 07:46:42 INFO - PID 1642 | RSS: Main: 263159808 07:46:42 INFO - PID 1642 | 07:49:45 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 07:49:46 INFO - PID 1642 | RSS: Main: 273293312 07:49:46 INFO - PID 1642 | 07:52:48 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 07:52:49 INFO - PID 1642 | RSS: Main: 267329536 07:52:49 INFO - PID 1642 | 07:52:49 INFO - PID 1642 | __start_tp_report 07:52:49 INFO - PID 1642 | _x_x_mozilla_page_load 07:52:49 INFO - PID 1642 | _x_x_mozilla_page_load_details 07:52:49 INFO - PID 1642 | |i|pagename|runs| 07:52:49 INFO - PID 1642 | |0;dojo.html;3031.757459403248;3353.161709572607;3617.7829330240206;3661.4690869823885;3662.285854258419;6132.508481341045;6167.506599472048;6201.472874805636;6267.43593076623;6267.811965641038;5143.305653736745;6108.877822443548;6165.876682466348;6203.410675985785;6296.999999999988;4178.791060446981;4811.566958973692;5502.697351645658;5565.6104072714925;5798.7100644967795;5633.436656334375;5693.743781529821;5782.479576838084;5793.565482588822;5797.855053623651;5628.268325117729;5656.4060773619;5671.8582035449035;5694.886102277952;5711.942880571182;4796.544328288807;5646.350669672999;5659.151127330933;5662.6036177467595;5712.057510510761;4530.818767249306;5075.746212689351;5580.63725857822;5620.634658747168;5625.043742563774;5216.2958000669805;5885.970570147122;6093.11649810775;6131.040261761333;6175.197224360849;6492.513061520405;6650.102236198148;6702;6703.597784132968;6738.4609231261875;5677.716114194273;6740.32596740327;6787.830304242433;6803.183617965876;6821.727130914757;6319.368063193736;6328.8101356958905;6329.145565348619;6338.841528961812;6339.492840572743;6683.799486015428;6689;6882.311768823078;6924.169099708067;6940.826479337956;6348.968255158695;6570.404690147365;6590.044443555657;6621.536492445579;6625.204975402886;6580.703868325937;6596.8350791230605;6717.328267173243;6758.831029224309;6819.897701534382;5238.397584277857;5540.335159780852;6661.26726060133;6676.930151653003;6699.464042876558;6437.549371544041;6494.155759751202;6644.568103073381;6648.501362397839;6656.167979002625;5367.194920761839;5368.275282836845;5372.167314066326;5387.0841956867425;5419.3225846769155;4764.595009424169;4769.785359658824;4770.856874293708;4773.1408346497965;4839.008003359303;6304.464120549805;6323.399277068763;6325.493960483151;6396.904046439307;6400.839979000562;6778.389944904982;6817.659117044128;6832.214295356098;6833.58998460094;6837.145356830396;6367.267764207083;6377.617342959345;6379.840503987438;6398.584092034003;6400.807975760735;5796.188533605298;5914.083317085859;6052.636841789506;6060.181875446847;6067.180930574316;6830.077939478107;6852.657367131723;6860.3139686031;6917.792466226021;6966;6818.62497562619;6833.829154271183;6859.108315918898;6861.691223895036;6863.38229559342;6920.7231710731;6978.988046733297;6987.047717883632;7005.0893383859775;7016.122984626922;6440.194975628046;6496.545241833026;6577.079208910661;6687.297833727389;6708.966455167693;5485.9725701371235;6818.420434262945;6835.453163746988;6835.7949261522235;6846.691898864464;5922.822315330547;6226.283977342482;6387.137736405526;6569.737210511526;6591.209054913441;6480.38436348565;6544.083828263952;6546.934530654823;6555.639439830665;6567.934320656924;6278.0896769967185;6310.179676642191;6397.168368112116;6415.358464153734;6429.196350456193;5531.197976293495;6470.28826829039;6484.448821850006;6512.544121911421;6579.638119903451;5377.408485066718;6386.744530218739;6401.903971440525;6427.742890284337;6447.6131432114225 07:52:49 INFO - PID 1642 | |1;ext.html;702.6697452197468;825.5500752090111;1057.7831544533371;1249.1755441408673;1290.948362065517;37654.43518347223;38212;38270.4259436108;38297.808510957475;38320.42519362208;37827.62172378275;38247.80876095623;38255;38343.61656383435;38400.80799595998;7880.724174653888;8386.203310685476;8612.56937153143;8619.482831030158;8623.26702230308;8672;8761.342899282547;8777.42946708465;8803.60383782728;8836.204741573258;8738.995015573195;8805.735827925173;8829.60266787996;8849.513276769776;8850.203481686647;8778.6927457539;8826.382153249275;8827.77930551735;8828.42615230008;8841.911580884205;8514.446560973516;8632.913670863336;8637.136286371375;8642.265407440374;8649.308055355556;38235.61764382348;38286.80856595727;38357.80821095905;38423.61576384228;38446;3117.1116231874034;3117.189530722006;3125.6092988376454;3150.1337132288577;3241.1410976091356;27075.458490830075;27802.443951120866;27883.581746273823;27894.86052569724;27982.580261296098;2806.256342069353;2850.230437781808;2922.0649392194505;2944.1167649705003;2967.2433529449854;336497.3175134109;337217;337382;338152;338530;49674.75162624164;50000.24999625009;50022.24966625503;50101.49898500968;50241;53501.73249133807;53574.196387054224;53575;53840;53874.46125538773;14394.352254148589;14839.777403338958;14862;14871.553853384416;14883.553493395215;11792.174547781744;11891.28652280866;11942;11977.520899163936;12016.63950081499;13310.870260758233;13694.178349299074;13820.032597718264;13862;13905.582832514838;5584.664920104807;6014.699265036731;6076.301225359052;6085;6100.359462256488;994.2443742755413;996.1283876607968;1000.9599616015278;1017.7557386227252;1029.8558201851747;24451.38871528226;24553.386165346008;24743;24753.752462475506;24779;965.6378857928277;974.1281553010158;983.2134292565919;1055.9524821383056;1138.5332013874272;12124.534639747566;12448.688782780502;12524.307273936147;12561.937190313989;12588.874111258954;442640;442754;449080;450188;450195;55537.722311388185;55673;55787;55810.720946395006;55871.44128558744;1308.6074177746827;1346.7710489216856;1502.6393665520197;1530.066659337803;1538.8768898488095;27560.862195688893;27667.169984900484;28490.715092848804;28925.855370723013;29106.417871642025;51547.48452515427;51635.48364516457;51699.74150129225;51813;51849.740751296005;386044;386921;388248;389014;389746;25127.24618261455;31806.568704407895;33164;33305.66694333026;33345.166370840925;41255;41270;41731.58268417277;42580.36129458123;42820.78589607157;34283.485747713305;34476;34541.82729086339;35207;35712;34714;34751;35402;35572.7549535765;35649.643503564635;26309.342266442727;34504.309913802084;34817.825910870284;35568.64431355757;35688.82155589206;30730.84634576813;30908.53637195399;31027.534586981667;31038.22404439907;31241.84379078181;22293;23731.525369492858;23738.050477980687;24117.75882241155;24195.879020604785 07:52:49 INFO - PID 1642 | |2;jquery.html;421.24193067589295;424.61148049534677;433.27642836463104;444.85764555342286;452.9388532548107;19171.520711982208;20695.06872190751;21432.57134857302;21563.13747450102;21597;21548.676769848425;22194.667079993815;22243.666345004836;22458;23309.417264568394;6005.639661620306;6273.529485288599;6305.085762564426;6328.398802113792;6329.2404911410695;5715.3141623005085;6165.845853853667;6220.657863817488;6239.750409983618;6239.937600624004;6092.969535152318;6170.629762214259;6208.192934918477;6218.533609979247;6239.625622462644;5491.917621235664;6272.843178920518;6318.2733985591785;6344.238691357066;6378.968105159491;5414.702191379472;5606.775728970877;5608.046632072557;5646.887062258773;5675.744591493351;26776.73223267762;27109.72890271092;27860;27903.860480697465;28179.718202817916;2039.4085715142592;2040.0921589892503;2051.200031987511;2057.372501387076;2087.217293514932;15074.623134421618;15562.299696513684;16085.678286434206;16280.348786048544;17267.91366043162;1470.6029372069588;1559.8206206286195;1698.0830351610116;1717.9398721044704;1724.422318523296;138427;154294;158084;166394.3360566391;167246;9824.803503929881;12205.572804951962;12319.322437266037;12693.365331733561;12700.237985720887;15900.363985440454;16093.517194484184;16138.596535086483;16190;16305.918470407572;8650.264727498234;8939.597718102701;8944.37389382737;8970.730878073668;9063.501507417155;8506.78733031679;8519.74440766778;8677.39258251929;8704.390692651581;8903.688370906988;6678.465722742169;7947.483413578099;8050.919490805134;8057.234562716532;8070.273675369128;2211.900464479102;2226.844120911521;2235.1282999630157;2235.2847088931712;2406.9638955415685;874.4971641306274;912.3795818843117;917.7659696777279;955.2214944819949;967.7387105481481;16996.915015425093;18076.728849067276;19620;20837.895810521153;21455.356339309845;954.6086104697041;959.5825815770162;966.1594412860856;992.4640694025146;992.8163289791412;10613.771108067238;11044.558217671203;11053.944730276296;11105.72235694098;11254.831177532344;539624;642948;643487;664394;706441;139291.30354348163;143685;153163;154495;169922;1181.2676140792764;1215.5684731920041;1241.4661695471034;1241.6523373455627;1241.6647505173732;18557;21296.680549792076;22667.433314167276;22702.9783659739;22727.431814204778;61786;62326.68836655969;62927;62958.685206573675;63020.42448938813;63930.3606963943;78504.64486065427;85993.14006860102;89152.10847891438;93457;12170.391480425766;12451.937740311603;12711.80932286035;13041.804372934603;13067.934660327019;13482.595522134352;13825.308734563432;13826.516071937434;14168.149911005372;14200.573982780534;11667.708307292047;11740.35635011082;11896.76206475883;11960.222585531912;12111.878881211074;11594.956453919187;11615.012723918562;11706.004989575982;11886.28682279066;11951.641450756491;11555.88444115548;11703.2978021319;12131.878681213428;12133.453994570442;12133.878661213275;10946.945265273622;12715.584039032554;12828.486860525847;13159.276239806903;13172.934135329262;11765.588204412805;11777.352245626573;11846.466908988943;12012.399380030789;12106.213096148722 07:52:49 INFO - PID 1642 | |3;mootools.html;1237.5730373021308;1331.8268625078738;1433.1472773699645;1450.3980847948103;1481.762917933131;9865.60537578497;10766.78466430671;10788.190885683576;10802.297850639703;10965.50655220515;10566.260361774679;10699.251052426333;10719.83920241197;10723.731906702336;10798.406087665175;2400.72761436439;2432.6959130108735;2436.378723425524;2436.671049408325;2438.146648672968;2331.7318508371586;2379.643053541974;2380.6071998120374;2384.1059602649007;2386.1648423051965;2345.7419683834764;2388.283514945518;2391.8325717199805;2404.206611818096;2407.0853075831246;1850.5743678953781;2079.8232150267168;2257.5146343536135;2303.124812571229;2333.894974726133;1983.722278880958;2030.7664618568758;2300.804431623297;2371.5968285391355;2396.1733202045266;10923.617673381474;11035.889641103566;11036.558537658564;11106.722331941684;11127.276727012719;6097.390260973912;6352.809415717536;6367.235931688181;6374.107624932513;6375.075614035985;3329.30084682217;3920.353141731611;3944.2900277949957;3972.324704800191;4010.017545701293;863.3956230638578;1050.453764042701;1102.5259138570411;1179.7109708121566;1193.6478738772082;13623.863761362458;14101.858981410158;15302.540923772192;15431.691366172614;15446.073235605787;5141.3830340358645;5304.443033481506;5311.362636483569;5343.091674415358;5358.169483730028;11307.830382544269;11379.715507112223;11381.089512838953;11389.088872890314;11396.829047564293;6049.939500604937;6103.206583144251;6172.537059720449;6189.690515474208;6194.163787888578;5837.474627283565;5892.852678682982;6001.729922153513;6015.909761353583;6021.7591296347655;4112.3420252759415;4261.190373828963;4264.466941632296;4307.289297265978;4339.544347843431;1241.211830487647;1260.6470188347278;1271.9491220351267;1276.24063682109;1279.3539262672477;646.421452799751;743;784.5371230973753;790.8615992201342;793.7499687598388;6143.938560614337;6511.120998665215;6536.705848236746;6582.539222254396;6604.075429439882;573.5153795043182;573.7819628541127;578.4706993101349;583.8073435766187;587.1281147495965;3132.530120481946;3418.5214070030215;3754.887353379403;3803.1823158021157;3840.231953609289;13490.055696101375;13588.728225435634;13971.301434928213;13981;14027.649308767159;13532.93233533826;14086.013979021574;14098;14104;14109.647258818406;10044.799104017879;10172.796544069077;10348.327358721659;10383.63657272007;10390.168786497063;6311.990081586924;6452.516061295515;6473.190851143607;6474.676266186578;6568.7372505099265;12288.75422491563;12451.25292482454;12452.377381131088;12529.874701252871;12609.49562017509;11834.526618935146;12064.45709943037;12547.811782823448;12720.745585088061;12733;8244.216799404046;8379.664813407395;8389.11914248995;8400.663973441238;8408.495490270603;10080.747981300527;10142.137918277029;10193.490325483845;10293.43386113771;10300.742481438025;8842.77893052653;8988.955055224682;9085.046070162538;9121.726348209564;9208.723738287594;6975.511714179958;8781.868271976053;8842.115788421106;8845.071267517076;8947.284217262733;6673.566218195801;7702.152763196156;7806.92193078062;7810;7900.723474678359;5537.363203231519;5711.257536520226;5749.223854779551;5766.452187042224;5839.0949402842625;4588.7476188808605;5781.248437703071;5910.379410161871;5953.91069133972;5983.252093488314 07:52:49 INFO - PID 1642 | |4;prototype.html;347.70445121646594;395.54117224020143;397.25514660012476;409.524951056774;412.3237889860628;5650.971745141279;6094.26868775747;6190.411910868466;6190.628562286261;6233.1273621693;4813.831515896944;6000.3699611540815;6009;6012.278526576806;6070.757169713206;3783.5270591176104;4057.695672824535;4563.589276965073;4587.38070360502;4596.287575425815;3899.5710471848074;4443.37792709021;4470.396496472967;4551.226291530431;4622.2835460503675;4032.6370626643597;4570.7257564546235;4574.290984897346;4584.724916505003;4594.3338215958665;3992.580779018205;4301.526832048472;4557.088582283556;4579.542045795427;4583.977080114578;3820.59883712209;3842.1163132479405;3923.5880232575455;3938.803059847025;3960.5643379228263;4840.443349014873;5108.514691104377;5967.9701601491715;5968.061778530347;5984.611000285011;5045.84862454127;5264.763085661154;5302.310699609026;5329.440408757102;5375.462453754632;5944.008757066388;6161.938380616181;6423.582467139621;6443.420092191725;6474.352564743535;4001.4598029265967;4122.505299364066;4357.27887484445;4361.323994780814;4393.968161968263;5188.870278243036;5208.479152084799;5509.586780991442;5682.460166284196;6017.939820601826;4022.3363145080734;4145.357469592218;4455.1757924784015;4459.509453960061;4486.7083639563325;3609.7112231021456;4530.728156310632;4534.387857639243;4545.2954792007295;4545.818167273338;5215.843524694189;5283.098084689509;5332.226827110047;5370.946290537123;5383.327084114486;6505.178550006006;6526.445252153525;6542.3130571290285;6546.279909209983;6820.31796820314;1364.1678576068591;1537.8077740282465;1547.3269127929386;1552.596324955497;1554.3394057525732;866.0211416912451;1001.5743309093606;1018.8471729240525;1022.3252653248969;1027.6043723166486;6284.402981716729;6291;6297.716602752887;6315.084312774714;6317.178766760292;5123.666961647481;6597.86804263912;6769.830754231184;6788.219354774165;6807.693653785592;5599.272094627672;5711.628744131618;6246.875062498724;6291.5281353898645;6292.276388215322;3107.9689203108132;3521.6654417830264;3540.4866294387684;3552.378333791628;3583.372909740785;6161.8125565423625;6608.4052435281055;6889.530451547601;6948.583085014915;6993.300669932966;5816.360200377955;6589.308122647149;6850.588964662036;6944.055664413972;6960.65196740161;6571.404295303424;6592.9340706593275;6692.364225398579;6972.616506092214;6990.999999999898;5709.486146246858;6406.583572067801;6795.218549866631;6803.693833777393;6810.8637827244165;5461.617686762047;5749.770009199585;6503.894337962463;6606.372394622503;6694.661067786365;5523.033469142964;6025.758969641341;6371.49028077762;6992.405645520188;7057.7176912922905;3915.647591716759;4359.237133501561;4389.23188442015;4409.492908315585;4436.5119836817275;6223.661912688794;6692.866142677217;6736.93263067363;6752.763653272112;6758.560693554903;5689.857753556194;6313.621182729051;6440.550876052851;6483.610983341015;6556.8360790978695;6423.229212494531;6468.902966455413;6506.934930650822;6518.837029074311;6528.510361722795;5810.5351571875;6070.1501789750355;6358.968205158944;6363.236411630634;6396.9040464394;5240.895182096413;5669.234653321749;6187.288461826767;6349.047642853424;6483.800496908088;6308.432241098323;6330.39861213184;6376.524793987195;6399.608207649953;6408.61548307103 07:52:49 INFO - PID 1642 | |5;yui.html;700.4956431369413;712.5475323169792;740.5593671765301;742.7845924681841;745.8359160984585;14964.176970266632;15886;15898.84101158988;15933.601659958493;15935.920320398396;14543.854561454382;14697.33861976211;14713.264336783172;14996.775048374257;15037.699246015072;929.4423345992418;939.5912777941618;957.310736269888;983.2625530851861;987.0770829274616;959.040959040959;974.9658761943333;980.1668581705565;980.6959842448828;984.6947446291673;1009.8283291840405;1017.1760873692333;1025.318163421324;1026.9691909242736;1027.1474676018886;978.6966040527459;987.521052289639;1017.120191034757;1018.210886562916;1021.1269364693192;759.787259567322;760.8972788673514;761.0638914135588;762.4434163060942;766.0271455251855;15936.681266374724;16230;16278.43025494102;16474.75287870683;16688.6662266754;12813.743725125445;14434.422623095064;14676.339564719614;14719.55841324762;14755.26223688822;11712.707182320424;11774.705632359173;12064.638060858188;12197;12442;3001.1296723950027;3024.8185108893535;3113.128324069264;3182.316041023589;3208.0375887233968;82630.17369826345;93634.53182734111;105883.47058264737;109468;110515;11239.651241850866;11265.324080555029;11348.943255283835;11658.6502404928;11844.822327665092;18397;24595;24803;24835;24867.12965046251;7216.458765592602;7366.710825605498;7724.497907635986;7937.563434011185;8114.391420643476;3672.412414013745;3692.778433294011;3967.0479085019388;4117.732347397409;4155.896102597399;4103.384492326175;4137.213929353413;4203.726757760735;4205.474315710536;4236.745795252295;666.8132922781629;677.9830504237434;788.3811208201535;822.1737154160031;856.1181982557976;547.854818473105;567.0287692491315;587.5270407322062;591.9289685237799;614.2015380005975;9526.428414295164;9704.369216000938;10278.947263403523;10304.793904121876;10514.303859015408;481.0451254260291;546.9589780766458;589.1633879890566;598.9191459153046;603.4508597176548;4515.198525002096;4720.362751028636;4815.927761083586;5054.873628159252;5217;92791;93716.53141734247;95189;98438;98845.5173172403;88770;90036.5498172518;90140.54929725309;90671.54664226636;90792;77535;78881.21118788853;79992.60003699944;80048.59975700085;80681.59659201784;10484.056434920894;10740.46297685097;10808.621698240524;10956.835647465135;10985.780284394426;28342.716572834008;37088.258234835695;38458.80770596241;38849;40119;51945;52045.73977130242;52292;62303.6884815573;65613;13390.732185356044;13605.387757550736;13758.243296618783;13791.793123102962;14031.36858841375;14493.347799348843;14717.116972981656;14775.261236938326;14897.702045958804;15363.001404908646;13684.794728079285;13735.381907813973;13929.791053134413;13967.580972570839;14300;13289.401976911255;13348.666283342995;13653.931730341683;13984.230867302396;14080.647983800487;13138.883194928156;13326.733465330446;13511.932440337736;13659.248741318925;13942.998562989162;9880.255918743538;10983.725406864893;11075.944620276847;11123.27698699582;11384.772304553697;9235.399699019543;9421.858672119786;10004;10835.945820270848;11278.323300601991 07:52:49 INFO - PID 1642 | __end_tp_report 07:52:49 INFO - PID 1642 | __start_cc_report 07:52:49 INFO - PID 1642 | _x_x_mozilla_cycle_collect,425 07:52:49 INFO - PID 1642 | __end_cc_report 07:52:49 INFO - PID 1642 | __startTimestamp1490194369250__endTimestamp 07:52:49 INFO - PID 1642 | 07:52:49 INFO - PID 1642 | ------- Summary: start ------- 07:52:49 INFO - PID 1642 | Number of tests: 6 07:52:49 INFO - PID 1642 | 07:52:49 INFO - PID 1642 | [#0] dojo.html Cycles:165 Average:6139.86 Median:6386.74 stddev:764.31 (12.0%) stddev-sans-first:726.73 07:52:49 INFO - PID 1642 | Values: 3031.8 3353.2 3617.8 3661.5 3662.3 6132.5 6167.5 6201.5 6267.4 6267.8 5143.3 6108.9 6165.9 6203.4 6297.0 4178.8 4811.6 5502.7 5565.6 5798.7 5633.4 5693.7 5782.5 5793.6 5797.9 5628.3 5656.4 5671.9 5694.9 5711.9 4796.5 5646.4 5659.2 5662.6 5712.1 4530.8 5075.7 5580.6 5620.6 5625.0 5216.3 5886.0 6093.1 6131.0 6175.2 6492.5 6650.1 6702.0 6703.6 6738.5 5677.7 6740.3 6787.8 6803.2 6821.7 6319.4 6328.8 6329.1 6338.8 6339.5 6683.8 6689.0 6882.3 6924.2 6940.8 6349.0 6570.4 6590.0 6621.5 6625.2 6580.7 6596.8 6717.3 6758.8 6819.9 5238.4 5540.3 6661.3 6676.9 6699.5 6437.5 6494.2 6644.6 6648.5 6656.2 5367.2 5368.3 5372.2 5387.1 5419.3 4764.6 4769.8 4770.9 4773.1 4839.0 6304.5 6323.4 6325.5 6396.9 6400.8 6778.4 6817.7 6832.2 6833.6 6837.1 6367.3 6377.6 6379.8 6398.6 6400.8 5796.2 5914.1 6052.6 6060.2 6067.2 6830.1 6852.7 6860.3 6917.8 6966.0 6818.6 6833.8 6859.1 6861.7 6863.4 6920.7 6979.0 6987.0 7005.1 7016.1 6440.2 6496.5 6577.1 6687.3 6709.0 5486.0 6818.4 6835.5 6835.8 6846.7 5922.8 6226.3 6387.1 6569.7 6591.2 6480.4 6544.1 6546.9 6555.6 6567.9 6278.1 6310.2 6397.2 6415.4 6429.2 5531.2 6470.3 6484.4 6512.5 6579.6 5377.4 6386.7 6401.9 6427.7 6447.6 07:52:49 INFO - PID 1642 | 07:52:49 INFO - PID 1642 | [#1] ext.html Cycles:180 Average:53423.71 Median:35669.23 stddev:104140.06 (292.0%) stddev-sans-first:104356.97 07:52:49 INFO - PID 1642 | Values: 702.7 825.6 1057.8 1249.2 1290.9 37654.4 38212.0 38270.4 38297.8 38320.4 37827.6 38247.8 38255.0 38343.6 38400.8 7880.7 8386.2 8612.6 8619.5 8623.3 8672.0 8761.3 8777.4 8803.6 8836.2 8739.0 8805.7 8829.6 8849.5 8850.2 8778.7 8826.4 8827.8 8828.4 8841.9 8514.4 8632.9 8637.1 8642.3 8649.3 38235.6 38286.8 38357.8 38423.6 38446.0 3117.1 3117.2 3125.6 3150.1 3241.1 27075.5 27802.4 27883.6 27894.9 27982.6 2806.3 2850.2 2922.1 2944.1 2967.2 336497.3 337217.0 337382.0 338152.0 338530.0 49674.8 50000.2 50022.2 50101.5 50241.0 53501.7 53574.2 53575.0 53840.0 53874.5 14394.4 14839.8 14862.0 14871.6 14883.6 11792.2 11891.3 11942.0 11977.5 12016.6 13310.9 13694.2 13820.0 13862.0 13905.6 5584.7 6014.7 6076.3 6085.0 6100.4 994.2 996.1 1001.0 1017.8 1029.9 24451.4 24553.4 24743.0 24753.8 24779.0 965.6 974.1 983.2 1056.0 1138.5 12124.5 12448.7 12524.3 12561.9 12588.9 442640.0 442754.0 449080.0 450188.0 450195.0 55537.7 55673.0 55787.0 55810.7 55871.4 1308.6 1346.8 1502.6 1530.1 1538.9 27560.9 27667.2 28490.7 28925.9 29106.4 51547.5 51635.5 51699.7 51813.0 51849.7 386044.0 386921.0 388248.0 389014.0 389746.0 25127.2 31806.6 33164.0 33305.7 33345.2 41255.0 41270.0 41731.6 42580.4 42820.8 34283.5 34476.0 34541.8 35207.0 35712.0 34714.0 34751.0 35402.0 35572.8 35649.6 26309.3 34504.3 34817.8 35568.6 35688.8 30730.8 30908.5 31027.5 31038.2 31241.8 22293.0 23731.5 23738.1 24117.8 24195.9 07:52:49 INFO - PID 1642 | 07:52:49 INFO - PID 1642 | [#2] jquery.html Cycles:180 Average:39219.26 Median:21580.07 stddev:108317.55 (501.9%) stddev-sans-first:108582.24 07:52:49 INFO - PID 1642 | Values: 421.2 424.6 433.3 444.9 452.9 19171.5 20695.1 21432.6 21563.1 21597.0 21548.7 22194.7 22243.7 22458.0 23309.4 6005.6 6273.5 6305.1 6328.4 6329.2 5715.3 6165.8 6220.7 6239.8 6239.9 6093.0 6170.6 6208.2 6218.5 6239.6 5491.9 6272.8 6318.3 6344.2 6379.0 5414.7 5606.8 5608.0 5646.9 5675.7 26776.7 27109.7 27860.0 27903.9 28179.7 2039.4 2040.1 2051.2 2057.4 2087.2 15074.6 15562.3 16085.7 16280.3 17267.9 1470.6 1559.8 1698.1 1717.9 1724.4 138427.0 154294.0 158084.0 166394.3 167246.0 9824.8 12205.6 12319.3 12693.4 12700.2 15900.4 16093.5 16138.6 16190.0 16305.9 8650.3 8939.6 8944.4 8970.7 9063.5 8506.8 8519.7 8677.4 8704.4 8903.7 6678.5 7947.5 8050.9 8057.2 8070.3 2211.9 2226.8 2235.1 2235.3 2407.0 874.5 912.4 917.8 955.2 967.7 16996.9 18076.7 19620.0 20837.9 21455.4 954.6 959.6 966.2 992.5 992.8 10613.8 11044.6 11053.9 11105.7 11254.8 539624.0 642948.0 643487.0 664394.0 706441.0 139291.3 143685.0 153163.0 154495.0 169922.0 1181.3 1215.6 1241.5 1241.7 1241.7 18557.0 21296.7 22667.4 22703.0 22727.4 61786.0 62326.7 62927.0 62958.7 63020.4 63930.4 78504.6 85993.1 89152.1 93457.0 12170.4 12451.9 12711.8 13041.8 13067.9 13482.6 13825.3 13826.5 14168.1 14200.6 11667.7 11740.4 11896.8 11960.2 12111.9 11595.0 11615.0 11706.0 11886.3 11951.6 11555.9 11703.3 12131.9 12133.5 12133.9 10946.9 12715.6 12828.5 13159.3 13172.9 11765.6 11777.4 11846.5 12012.4 12106.2 07:52:49 INFO - PID 1642 | 07:52:49 INFO - PID 1642 | [#3] mootools.html Cycles:180 Average:6706.50 Median:2436.52 stddev:4207.66 (172.7%) stddev-sans-first:4199.39 07:52:49 INFO - PID 1642 | Values: 1237.6 1331.8 1433.1 1450.4 1481.8 9865.6 10766.8 10788.2 10802.3 10965.5 10566.3 10699.3 10719.8 10723.7 10798.4 2400.7 2432.7 2436.4 2436.7 2438.1 2331.7 2379.6 2380.6 2384.1 2386.2 2345.7 2388.3 2391.8 2404.2 2407.1 1850.6 2079.8 2257.5 2303.1 2333.9 1983.7 2030.8 2300.8 2371.6 2396.2 10923.6 11035.9 11036.6 11106.7 11127.3 6097.4 6352.8 6367.2 6374.1 6375.1 3329.3 3920.4 3944.3 3972.3 4010.0 863.4 1050.5 1102.5 1179.7 1193.6 13623.9 14101.9 15302.5 15431.7 15446.1 5141.4 5304.4 5311.4 5343.1 5358.2 11307.8 11379.7 11381.1 11389.1 11396.8 6049.9 6103.2 6172.5 6189.7 6194.2 5837.5 5892.9 6001.7 6015.9 6021.8 4112.3 4261.2 4264.5 4307.3 4339.5 1241.2 1260.6 1271.9 1276.2 1279.4 646.4 743.0 784.5 790.9 793.7 6143.9 6511.1 6536.7 6582.5 6604.1 573.5 573.8 578.5 583.8 587.1 3132.5 3418.5 3754.9 3803.2 3840.2 13490.1 13588.7 13971.3 13981.0 14027.6 13532.9 14086.0 14098.0 14104.0 14109.6 10044.8 10172.8 10348.3 10383.6 10390.2 6312.0 6452.5 6473.2 6474.7 6568.7 12288.8 12451.3 12452.4 12529.9 12609.5 11834.5 12064.5 12547.8 12720.7 12733.0 8244.2 8379.7 8389.1 8400.7 8408.5 10080.7 10142.1 10193.5 10293.4 10300.7 8842.8 8989.0 9085.0 9121.7 9208.7 6975.5 8781.9 8842.1 8845.1 8947.3 6673.6 7702.2 7806.9 7810.0 7900.7 5537.4 5711.3 5749.2 5766.5 5839.1 4588.7 5781.2 5910.4 5953.9 5983.3 07:52:49 INFO - PID 1642 | 07:52:49 INFO - PID 1642 | [#4] prototype.html Cycles:180 Average:5205.49 Median:5730.70 stddev:1628.61 (28.4%) stddev-sans-first:1591.84 07:52:49 INFO - PID 1642 | Values: 347.7 395.5 397.3 409.5 412.3 5651.0 6094.3 6190.4 6190.6 6233.1 4813.8 6000.4 6009.0 6012.3 6070.8 3783.5 4057.7 4563.6 4587.4 4596.3 3899.6 4443.4 4470.4 4551.2 4622.3 4032.6 4570.7 4574.3 4584.7 4594.3 3992.6 4301.5 4557.1 4579.5 4584.0 3820.6 3842.1 3923.6 3938.8 3960.6 4840.4 5108.5 5968.0 5968.1 5984.6 5045.8 5264.8 5302.3 5329.4 5375.5 5944.0 6161.9 6423.6 6443.4 6474.4 4001.5 4122.5 4357.3 4361.3 4394.0 5188.9 5208.5 5509.6 5682.5 6017.9 4022.3 4145.4 4455.2 4459.5 4486.7 3609.7 4530.7 4534.4 4545.3 4545.8 5215.8 5283.1 5332.2 5370.9 5383.3 6505.2 6526.4 6542.3 6546.3 6820.3 1364.2 1537.8 1547.3 1552.6 1554.3 866.0 1001.6 1018.8 1022.3 1027.6 6284.4 6291.0 6297.7 6315.1 6317.2 5123.7 6597.9 6769.8 6788.2 6807.7 5599.3 5711.6 6246.9 6291.5 6292.3 3108.0 3521.7 3540.5 3552.4 3583.4 6161.8 6608.4 6889.5 6948.6 6993.3 5816.4 6589.3 6850.6 6944.1 6960.7 6571.4 6592.9 6692.4 6972.6 6991.0 5709.5 6406.6 6795.2 6803.7 6810.9 5461.6 5749.8 6503.9 6606.4 6694.7 5523.0 6025.8 6371.5 6992.4 7057.7 3915.6 4359.2 4389.2 4409.5 4436.5 6223.7 6692.9 6736.9 6752.8 6758.6 5689.9 6313.6 6440.6 6483.6 6556.8 6423.2 6468.9 6506.9 6518.8 6528.5 5810.5 6070.2 6359.0 6363.2 6396.9 5240.9 5669.2 6187.3 6349.0 6483.8 6308.4 6330.4 6376.5 6399.6 6408.6 07:52:49 INFO - PID 1642 | 07:52:49 INFO - PID 1642 | [#5] yui.html Cycles:180 Average:19721.04 Median:3195.18 stddev:27839.33 (871.3%) stddev-sans-first:27880.79 07:52:49 INFO - PID 1642 | Values: 700.5 712.5 740.6 742.8 745.8 14964.2 15886.0 15898.8 15933.6 15935.9 14543.9 14697.3 14713.3 14996.8 15037.7 929.4 939.6 957.3 983.3 987.1 959.0 975.0 980.2 980.7 984.7 1009.8 1017.2 1025.3 1027.0 1027.1 978.7 987.5 1017.1 1018.2 1021.1 759.8 760.9 761.1 762.4 766.0 15936.7 16230.0 16278.4 16474.8 16688.7 12813.7 14434.4 14676.3 14719.6 14755.3 11712.7 11774.7 12064.6 12197.0 12442.0 3001.1 3024.8 3113.1 3182.3 3208.0 82630.2 93634.5 105883.5 109468.0 110515.0 11239.7 11265.3 11348.9 11658.7 11844.8 18397.0 24595.0 24803.0 24835.0 24867.1 7216.5 7366.7 7724.5 7937.6 8114.4 3672.4 3692.8 3967.0 4117.7 4155.9 4103.4 4137.2 4203.7 4205.5 4236.7 666.8 678.0 788.4 822.2 856.1 547.9 567.0 587.5 591.9 614.2 9526.4 9704.4 10278.9 10304.8 10514.3 481.0 547.0 589.2 598.9 603.5 4515.2 4720.4 4815.9 5054.9 5217.0 92791.0 93716.5 95189.0 98438.0 98845.5 88770.0 90036.5 90140.5 90671.5 90792.0 77535.0 78881.2 79992.6 80048.6 80681.6 10484.1 10740.5 10808.6 10956.8 10985.8 28342.7 37088.3 38458.8 38849.0 40119.0 51945.0 52045.7 52292.0 62303.7 65613.0 13390.7 13605.4 13758.2 13791.8 14031.4 14493.3 14717.1 14775.3 14897.7 15363.0 13684.8 13735.4 13929.8 13967.6 14300.0 13289.4 13348.7 13653.9 13984.2 14080.6 13138.9 13326.7 13511.9 13659.2 13943.0 9880.3 10983.7 11075.9 11123.3 11384.8 9235.4 9421.9 10004.0 10835.9 11278.3 07:52:49 INFO - PID 1642 | -------- Summary: end -------- 07:52:49 INFO - PID 1642 | 07:52:49 INFO - TEST-INFO | 1642: exit 0 07:52:49 INFO - TEST-OK | dromaeo_css | took 1117401ms 07:52:49 INFO - TEST-START | kraken 07:52:49 INFO - operating with platform_type : mac_ 07:52:49 INFO - Initialising browser for kraken test... 07:52:49 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpaHNsAf/profile http://localhost:49242/getInfo.html) 07:52:52 INFO - PID 1654 | 2017-03-22 07:52:52.792 plugin-container[1655:10213] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 07:52:52 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:52:52 INFO - PID 1654 | 2017-03-22 07:52:52.795 plugin-container[1655:10213] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 07:52:52 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:52:52 INFO - PID 1654 | 2017-03-22 07:52:52.795 plugin-container[1655:10213] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:52:52 INFO - PID 1654 | __metrics Screen width/height:1600/1200 07:52:52 INFO - PID 1654 | colorDepth:24 07:52:52 INFO - PID 1654 | Browser inner width/height: 1024/689 07:52:52 INFO - PID 1654 | __metrics 07:52:52 ERROR - PID 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 07:52:53 INFO - TEST-INFO | 1654: exit 0 07:52:53 INFO - Browser initialized. 07:52:53 INFO - Running cycle 1/1 for kraken test... 07:52:53 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpaHNsAf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:52:56 INFO - PID 1656 | 2017-03-22 07:52:56.269 plugin-container[1657:10333] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 07:52:56 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:52:56 INFO - PID 1656 | 2017-03-22 07:52:56.271 plugin-container[1657:10333] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4413, name = 'com.apple.CFPasteboardClient' 07:52:56 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:52:56 INFO - PID 1656 | 2017-03-22 07:52:56.272 plugin-container[1657:10333] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:53:06 INFO - PID 1656 | RSS: Main: 227078144 07:53:06 INFO - PID 1656 | 07:53:08 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 07:53:09 INFO - PID 1656 | RSS: Main: 241754112 07:53:09 INFO - PID 1656 | 07:53:12 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 07:53:13 INFO - PID 1656 | RSS: Main: 250011648 07:53:13 INFO - PID 1656 | 07:53:16 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 07:53:17 INFO - PID 1656 | RSS: Main: 252755968 07:53:17 INFO - PID 1656 | 07:53:19 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 07:53:20 INFO - PID 1656 | RSS: Main: 254009344 07:53:20 INFO - PID 1656 | 07:53:22 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:53:22 INFO - PID 1656 | RSS: Main: 254300160 07:53:22 INFO - PID 1656 | 07:53:25 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 07:53:26 INFO - PID 1656 | RSS: Main: 261062656 07:53:26 INFO - PID 1656 | 07:53:29 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 07:53:30 INFO - PID 1656 | RSS: Main: 261681152 07:53:30 INFO - PID 1656 | 07:53:33 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 07:53:34 INFO - PID 1656 | RSS: Main: 262303744 07:53:34 INFO - PID 1656 | 07:53:35 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:53:36 INFO - PID 1656 | RSS: Main: 261099520 07:53:36 INFO - PID 1656 | 07:53:38 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:53:39 INFO - PID 1656 | RSS: Main: 261140480 07:53:39 INFO - PID 1656 | 07:53:41 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:53:41 INFO - PID 1656 | RSS: Main: 261201920 07:53:41 INFO - PID 1656 | 07:53:44 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:53:45 INFO - PID 1656 | RSS: Main: 261578752 07:53:45 INFO - PID 1656 | 07:53:48 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:53:49 INFO - PID 1656 | RSS: Main: 258633728 07:53:49 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 07:53:51 INFO - PID 1656 | RSS: Main: 261783552 07:53:51 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | __start_tp_report 07:53:51 INFO - PID 1656 | _x_x_mozilla_page_load 07:53:51 INFO - PID 1656 | _x_x_mozilla_page_load_details 07:53:51 INFO - PID 1656 | |i|pagename|runs| 07:53:51 INFO - PID 1656 | |0;ai-astar;103;119;114;116;116;117;98;168;171;169 07:53:51 INFO - PID 1656 | |1;audio-beat-detection;148;134;143;194;108;150;149;102;168;142 07:53:51 INFO - PID 1656 | |2;audio-dft;205;212;206;227;192;197;205;200;223;201 07:53:51 INFO - PID 1656 | |3;audio-fft;82;49;59;107;87;49;66;88;100;50 07:53:51 INFO - PID 1656 | |4;audio-oscillator;87;58;85;58;75;98;81;96;106;118 07:53:51 INFO - PID 1656 | |5;imaging-gaussian-blur;136;92;102;81;135;134;109;89;89;89 07:53:51 INFO - PID 1656 | |6;imaging-darkroom;97;138;153;96;95;126;109;100;94;154 07:53:51 INFO - PID 1656 | |7;imaging-desaturate;104;145;146;94;97;90;99;114;91;91 07:53:51 INFO - PID 1656 | |8;json-parse-financial;69;68;67;67;70;69;67;68;68;68 07:53:51 INFO - PID 1656 | |9;json-stringify-tinderbox;61;60;65;66;66;61;59;57;60;60 07:53:51 INFO - PID 1656 | |10;stanford-crypto-aes;82;83;85;86;84;84;81;100;84;85 07:53:51 INFO - PID 1656 | |11;stanford-crypto-ccm;147;151;154;145;141;156;160;149;206;165 07:53:51 INFO - PID 1656 | |12;stanford-crypto-pbkdf2;244;222;190;186;192;188;191;188;214;240 07:53:51 INFO - PID 1656 | |13;stanford-crypto-sha256-iterative;69;70;69;67;68;68;71;67;67;107 07:53:51 INFO - PID 1656 | __end_tp_report 07:53:51 INFO - PID 1656 | __start_cc_report 07:53:51 INFO - PID 1656 | _x_x_mozilla_cycle_collect,1015 07:53:51 INFO - PID 1656 | __end_cc_report 07:53:51 INFO - PID 1656 | __startTimestamp1490194431626__endTimestamp 07:53:51 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | ------- Summary: start ------- 07:53:51 INFO - PID 1656 | Number of tests: 14 07:53:51 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | [#0] ai-astar Cycles:10 Average:129.10 Median:143.50 stddev:28.54 (19.9%) stddev-sans-first:28.66 07:53:51 INFO - PID 1656 | Values: 103.0 119.0 114.0 116.0 116.0 117.0 98.0 168.0 171.0 169.0 07:53:51 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | [#1] audio-beat-detection Cycles:10 Average:143.80 Median:148.50 stddev:26.49 (17.8%) stddev-sans-first:28.06 07:53:51 INFO - PID 1656 | Values: 148.0 134.0 143.0 194.0 108.0 150.0 149.0 102.0 168.0 142.0 07:53:51 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | [#2] audio-dft Cycles:10 Average:206.80 Median:205.50 stddev:11.05 (5.4%) stddev-sans-first:11.70 07:53:51 INFO - PID 1656 | Values: 205.0 212.0 206.0 227.0 192.0 197.0 205.0 200.0 223.0 201.0 07:53:51 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | [#3] audio-fft Cycles:10 Average:73.70 Median:62.50 stddev:21.88 (35.0%) stddev-sans-first:23.00 07:53:51 INFO - PID 1656 | Values: 82.0 49.0 59.0 107.0 87.0 49.0 66.0 88.0 100.0 50.0 07:53:51 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | [#4] audio-oscillator Cycles:10 Average:86.20 Median:83.00 stddev:19.39 (23.4%) stddev-sans-first:20.56 07:53:51 INFO - PID 1656 | Values: 87.0 58.0 85.0 58.0 75.0 98.0 81.0 96.0 106.0 118.0 07:53:51 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:105.60 Median:85.00 stddev:21.70 (25.5%) stddev-sans-first:20.03 07:53:51 INFO - PID 1656 | Values: 136.0 92.0 102.0 81.0 135.0 134.0 109.0 89.0 89.0 89.0 07:53:51 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | [#6] imaging-darkroom Cycles:10 Average:116.20 Median:124.00 stddev:24.47 (19.7%) stddev-sans-first:24.94 07:53:51 INFO - PID 1656 | Values: 97.0 138.0 153.0 96.0 95.0 126.0 109.0 100.0 94.0 154.0 07:53:51 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | [#7] imaging-desaturate Cycles:10 Average:107.10 Median:91.00 stddev:21.49 (23.6%) stddev-sans-first:22.77 07:53:51 INFO - PID 1656 | Values: 104.0 145.0 146.0 94.0 97.0 90.0 99.0 114.0 91.0 91.0 07:53:51 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | [#8] json-parse-financial Cycles:10 Average:68.10 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:1.00 07:53:51 INFO - PID 1656 | Values: 69.0 68.0 67.0 67.0 70.0 69.0 67.0 68.0 68.0 68.0 07:53:51 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:61.00 stddev:3.10 (5.1%) stddev-sans-first:3.28 07:53:51 INFO - PID 1656 | Values: 61.0 60.0 65.0 66.0 66.0 61.0 59.0 57.0 60.0 60.0 07:53:51 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:84.00 stddev:5.34 (6.4%) stddev-sans-first:5.52 07:53:51 INFO - PID 1656 | Values: 82.0 83.0 85.0 86.0 84.0 84.0 81.0 100.0 84.0 85.0 07:53:51 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:157.40 Median:155.00 stddev:18.51 (11.9%) stddev-sans-first:19.24 07:53:51 INFO - PID 1656 | Values: 147.0 151.0 154.0 145.0 141.0 156.0 160.0 149.0 206.0 165.0 07:53:51 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:205.50 Median:203.00 stddev:22.71 (11.2%) stddev-sans-first:19.35 07:53:51 INFO - PID 1656 | Values: 244.0 222.0 190.0 186.0 192.0 188.0 191.0 188.0 214.0 240.0 07:53:51 INFO - PID 1656 | 07:53:51 INFO - PID 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.30 Median:68.50 stddev:12.27 (17.9%) stddev-sans-first:12.95 07:53:51 INFO - PID 1656 | Values: 69.0 70.0 69.0 67.0 68.0 68.0 71.0 67.0 67.0 107.0 07:53:51 INFO - PID 1656 | -------- Summary: end -------- 07:53:51 INFO - PID 1656 | 07:53:52 INFO - TEST-INFO | 1656: exit 0 07:53:52 INFO - TEST-OK | kraken | took 62566ms 07:53:52 INFO - SUITE-END | took 1179s 07:53:52 INFO - Completed test suite (00:19:40) 07:53:52 INFO - javascript benchmark 07:53:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7718.738027651774, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3031.757459403248, 3353.161709572607, 3617.7829330240206, 3661.4690869823885, 3662.285854258419, 6132.508481341045, 6167.506599472048, 6201.472874805636, 6267.43593076623, 6267.811965641038, 5143.305653736745, 6108.877822443548, 6165.876682466348, 6203.410675985785, 6296.999999999988, 4178.791060446981, 4811.566958973692, 5502.697351645658, 5565.6104072714925, 5798.7100644967795, 5633.436656334375, 5693.743781529821, 5782.479576838084, 5793.565482588822, 5797.855053623651, 5628.268325117729, 5656.4060773619, 5671.8582035449035, 5694.886102277952, 5711.942880571182, 4796.544328288807, 5646.350669672999, 5659.151127330933, 5662.6036177467595, 5712.057510510761, 4530.818767249306, 5075.746212689351, 5580.63725857822, 5620.634658747168, 5625.043742563774, 5216.2958000669805, 5885.970570147122, 6093.11649810775, 6131.040261761333, 6175.197224360849, 6492.513061520405, 6650.102236198148, 6702.0, 6703.597784132968, 6738.4609231261875, 5677.716114194273, 6740.32596740327, 6787.830304242433, 6803.183617965876, 6821.727130914757, 6319.368063193736, 6328.8101356958905, 6329.145565348619, 6338.841528961812, 6339.492840572743, 6683.799486015428, 6689.0, 6882.311768823078, 6924.169099708067, 6940.826479337956, 6348.968255158695, 6570.404690147365, 6590.044443555657, 6621.536492445579, 6625.204975402886, 6580.703868325937, 6596.8350791230605, 6717.328267173243, 6758.831029224309, 6819.897701534382, 5238.397584277857, 5540.335159780852, 6661.26726060133, 6676.930151653003, 6699.464042876558, 6437.549371544041, 6494.155759751202, 6644.568103073381, 6648.501362397839, 6656.167979002625, 5367.194920761839, 5368.275282836845, 5372.167314066326, 5387.0841956867425, 5419.3225846769155, 4764.595009424169, 4769.785359658824, 4770.856874293708, 4773.1408346497965, 4839.008003359303, 6304.464120549805, 6323.399277068763, 6325.493960483151, 6396.904046439307, 6400.839979000562, 6778.389944904982, 6817.659117044128, 6832.214295356098, 6833.58998460094, 6837.145356830396, 6367.267764207083, 6377.617342959345, 6379.840503987438, 6398.584092034003, 6400.807975760735, 5796.188533605298, 5914.083317085859, 6052.636841789506, 6060.181875446847, 6067.180930574316, 6830.077939478107, 6852.657367131723, 6860.3139686031, 6917.792466226021, 6966.0, 6818.62497562619, 6833.829154271183, 6859.108315918898, 6861.691223895036, 6863.38229559342, 6920.7231710731, 6978.988046733297, 6987.047717883632, 7005.0893383859775, 7016.122984626922, 6440.194975628046, 6496.545241833026, 6577.079208910661, 6687.297833727389, 6708.966455167693, 5485.9725701371235, 6818.420434262945, 6835.453163746988, 6835.7949261522235, 6846.691898864464, 5922.822315330547, 6226.283977342482, 6387.137736405526, 6569.737210511526, 6591.209054913441, 6480.38436348565, 6544.083828263952, 6546.934530654823, 6555.639439830665, 6567.934320656924, 6278.0896769967185, 6310.179676642191, 6397.168368112116, 6415.358464153734, 6429.196350456193, 5531.197976293495, 6470.28826829039, 6484.448821850006, 6512.544121911421, 6579.638119903451, 5377.408485066718, 6386.744530218739, 6401.903971440525, 6427.742890284337, 6447.6131432114225], "value": 6090.177479434145, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.6697452197468, 825.5500752090111, 1057.7831544533371, 1249.1755441408673, 1290.948362065517, 37654.43518347223, 38212.0, 38270.4259436108, 38297.808510957475, 38320.42519362208, 37827.62172378275, 38247.80876095623, 38255.0, 38343.61656383435, 38400.80799595998, 7880.724174653888, 8386.203310685476, 8612.56937153143, 8619.482831030158, 8623.26702230308, 8672.0, 8761.342899282547, 8777.42946708465, 8803.60383782728, 8836.204741573258, 8738.995015573195, 8805.735827925173, 8829.60266787996, 8849.513276769776, 8850.203481686647, 8778.6927457539, 8826.382153249275, 8827.77930551735, 8828.42615230008, 8841.911580884205, 8514.446560973516, 8632.913670863336, 8637.136286371375, 8642.265407440374, 8649.308055355556, 38235.61764382348, 38286.80856595727, 38357.80821095905, 38423.61576384228, 38446.0, 3117.1116231874034, 3117.189530722006, 3125.6092988376454, 3150.1337132288577, 3241.1410976091356, 27075.458490830075, 27802.443951120866, 27883.581746273823, 27894.86052569724, 27982.580261296098, 2806.256342069353, 2850.230437781808, 2922.0649392194505, 2944.1167649705003, 2967.2433529449854, 336497.3175134109, 337217.0, 337382.0, 338152.0, 338530.0, 49674.75162624164, 50000.24999625009, 50022.24966625503, 50101.49898500968, 50241.0, 53501.73249133807, 53574.196387054224, 53575.0, 53840.0, 53874.46125538773, 14394.352254148589, 14839.777403338958, 14862.0, 14871.553853384416, 14883.553493395215, 11792.174547781744, 11891.28652280866, 11942.0, 11977.520899163936, 12016.63950081499, 13310.870260758233, 13694.178349299074, 13820.032597718264, 13862.0, 13905.582832514838, 5584.664920104807, 6014.699265036731, 6076.301225359052, 6085.0, 6100.359462256488, 994.2443742755413, 996.1283876607968, 1000.9599616015278, 1017.7557386227252, 1029.8558201851747, 24451.38871528226, 24553.386165346008, 24743.0, 24753.752462475506, 24779.0, 965.6378857928277, 974.1281553010158, 983.2134292565919, 1055.9524821383056, 1138.5332013874272, 12124.534639747566, 12448.688782780502, 12524.307273936147, 12561.937190313989, 12588.874111258954, 442640.0, 442754.0, 449080.0, 450188.0, 450195.0, 55537.722311388185, 55673.0, 55787.0, 55810.720946395006, 55871.44128558744, 1308.6074177746827, 1346.7710489216856, 1502.6393665520197, 1530.066659337803, 1538.8768898488095, 27560.862195688893, 27667.169984900484, 28490.715092848804, 28925.855370723013, 29106.417871642025, 51547.48452515427, 51635.48364516457, 51699.74150129225, 51813.0, 51849.740751296005, 386044.0, 386921.0, 388248.0, 389014.0, 389746.0, 25127.24618261455, 31806.568704407895, 33164.0, 33305.66694333026, 33345.166370840925, 41255.0, 41270.0, 41731.58268417277, 42580.36129458123, 42820.78589607157, 34283.485747713305, 34476.0, 34541.82729086339, 35207.0, 35712.0, 34714.0, 34751.0, 35402.0, 35572.7549535765, 35649.643503564635, 26309.342266442727, 34504.309913802084, 34817.825910870284, 35568.64431355757, 35688.82155589206, 30730.84634576813, 30908.53637195399, 31027.534586981667, 31038.22404439907, 31241.84379078181, 22293.0, 23731.525369492858, 23738.050477980687, 24117.75882241155, 24195.879020604785], "value": 18222.268082916195, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.24193067589295, 424.61148049534677, 433.27642836463104, 444.85764555342286, 452.9388532548107, 19171.520711982208, 20695.06872190751, 21432.57134857302, 21563.13747450102, 21597.0, 21548.676769848425, 22194.667079993815, 22243.666345004836, 22458.0, 23309.417264568394, 6005.639661620306, 6273.529485288599, 6305.085762564426, 6328.398802113792, 6329.2404911410695, 5715.3141623005085, 6165.845853853667, 6220.657863817488, 6239.750409983618, 6239.937600624004, 6092.969535152318, 6170.629762214259, 6208.192934918477, 6218.533609979247, 6239.625622462644, 5491.917621235664, 6272.843178920518, 6318.2733985591785, 6344.238691357066, 6378.968105159491, 5414.702191379472, 5606.775728970877, 5608.046632072557, 5646.887062258773, 5675.744591493351, 26776.73223267762, 27109.72890271092, 27860.0, 27903.860480697465, 28179.718202817916, 2039.4085715142592, 2040.0921589892503, 2051.200031987511, 2057.372501387076, 2087.217293514932, 15074.623134421618, 15562.299696513684, 16085.678286434206, 16280.348786048544, 17267.91366043162, 1470.6029372069588, 1559.8206206286195, 1698.0830351610116, 1717.9398721044704, 1724.422318523296, 138427.0, 154294.0, 158084.0, 166394.3360566391, 167246.0, 9824.803503929881, 12205.572804951962, 12319.322437266037, 12693.365331733561, 12700.237985720887, 15900.363985440454, 16093.517194484184, 16138.596535086483, 16190.0, 16305.918470407572, 8650.264727498234, 8939.597718102701, 8944.37389382737, 8970.730878073668, 9063.501507417155, 8506.78733031679, 8519.74440766778, 8677.39258251929, 8704.390692651581, 8903.688370906988, 6678.465722742169, 7947.483413578099, 8050.919490805134, 8057.234562716532, 8070.273675369128, 2211.900464479102, 2226.844120911521, 2235.1282999630157, 2235.2847088931712, 2406.9638955415685, 874.4971641306274, 912.3795818843117, 917.7659696777279, 955.2214944819949, 967.7387105481481, 16996.915015425093, 18076.728849067276, 19620.0, 20837.895810521153, 21455.356339309845, 954.6086104697041, 959.5825815770162, 966.1594412860856, 992.4640694025146, 992.8163289791412, 10613.771108067238, 11044.558217671203, 11053.944730276296, 11105.72235694098, 11254.831177532344, 539624.0, 642948.0, 643487.0, 664394.0, 706441.0, 139291.30354348163, 143685.0, 153163.0, 154495.0, 169922.0, 1181.2676140792764, 1215.5684731920041, 1241.4661695471034, 1241.6523373455627, 1241.6647505173732, 18557.0, 21296.680549792076, 22667.433314167276, 22702.9783659739, 22727.431814204778, 61786.0, 62326.68836655969, 62927.0, 62958.685206573675, 63020.42448938813, 63930.3606963943, 78504.64486065427, 85993.14006860102, 89152.10847891438, 93457.0, 12170.391480425766, 12451.937740311603, 12711.80932286035, 13041.804372934603, 13067.934660327019, 13482.595522134352, 13825.308734563432, 13826.516071937434, 14168.149911005372, 14200.573982780534, 11667.708307292047, 11740.35635011082, 11896.76206475883, 11960.222585531912, 12111.878881211074, 11594.956453919187, 11615.012723918562, 11706.004989575982, 11886.28682279066, 11951.641450756491, 11555.88444115548, 11703.2978021319, 12131.878681213428, 12133.453994570442, 12133.878661213275, 10946.945265273622, 12715.584039032554, 12828.486860525847, 13159.276239806903, 13172.934135329262, 11765.588204412805, 11777.352245626573, 11846.466908988943, 12012.399380030789, 12106.213096148722], "value": 10697.889213621806, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1237.5730373021308, 1331.8268625078738, 1433.1472773699645, 1450.3980847948103, 1481.762917933131, 9865.60537578497, 10766.78466430671, 10788.190885683576, 10802.297850639703, 10965.50655220515, 10566.260361774679, 10699.251052426333, 10719.83920241197, 10723.731906702336, 10798.406087665175, 2400.72761436439, 2432.6959130108735, 2436.378723425524, 2436.671049408325, 2438.146648672968, 2331.7318508371586, 2379.643053541974, 2380.6071998120374, 2384.1059602649007, 2386.1648423051965, 2345.7419683834764, 2388.283514945518, 2391.8325717199805, 2404.206611818096, 2407.0853075831246, 1850.5743678953781, 2079.8232150267168, 2257.5146343536135, 2303.124812571229, 2333.894974726133, 1983.722278880958, 2030.7664618568758, 2300.804431623297, 2371.5968285391355, 2396.1733202045266, 10923.617673381474, 11035.889641103566, 11036.558537658564, 11106.722331941684, 11127.276727012719, 6097.390260973912, 6352.809415717536, 6367.235931688181, 6374.107624932513, 6375.075614035985, 3329.30084682217, 3920.353141731611, 3944.2900277949957, 3972.324704800191, 4010.017545701293, 863.3956230638578, 1050.453764042701, 1102.5259138570411, 1179.7109708121566, 1193.6478738772082, 13623.863761362458, 14101.858981410158, 15302.540923772192, 15431.691366172614, 15446.073235605787, 5141.3830340358645, 5304.443033481506, 5311.362636483569, 5343.091674415358, 5358.169483730028, 11307.830382544269, 11379.715507112223, 11381.089512838953, 11389.088872890314, 11396.829047564293, 6049.939500604937, 6103.206583144251, 6172.537059720449, 6189.690515474208, 6194.163787888578, 5837.474627283565, 5892.852678682982, 6001.729922153513, 6015.909761353583, 6021.7591296347655, 4112.3420252759415, 4261.190373828963, 4264.466941632296, 4307.289297265978, 4339.544347843431, 1241.211830487647, 1260.6470188347278, 1271.9491220351267, 1276.24063682109, 1279.3539262672477, 646.421452799751, 743.0, 784.5371230973753, 790.8615992201342, 793.7499687598388, 6143.938560614337, 6511.120998665215, 6536.705848236746, 6582.539222254396, 6604.075429439882, 573.5153795043182, 573.7819628541127, 578.4706993101349, 583.8073435766187, 587.1281147495965, 3132.530120481946, 3418.5214070030215, 3754.887353379403, 3803.1823158021157, 3840.231953609289, 13490.055696101375, 13588.728225435634, 13971.301434928213, 13981.0, 14027.649308767159, 13532.93233533826, 14086.013979021574, 14098.0, 14104.0, 14109.647258818406, 10044.799104017879, 10172.796544069077, 10348.327358721659, 10383.63657272007, 10390.168786497063, 6311.990081586924, 6452.516061295515, 6473.190851143607, 6474.676266186578, 6568.7372505099265, 12288.75422491563, 12451.25292482454, 12452.377381131088, 12529.874701252871, 12609.49562017509, 11834.526618935146, 12064.45709943037, 12547.811782823448, 12720.745585088061, 12733.0, 8244.216799404046, 8379.664813407395, 8389.11914248995, 8400.663973441238, 8408.495490270603, 10080.747981300527, 10142.137918277029, 10193.490325483845, 10293.43386113771, 10300.742481438025, 8842.77893052653, 8988.955055224682, 9085.046070162538, 9121.726348209564, 9208.723738287594, 6975.511714179958, 8781.868271976053, 8842.115788421106, 8845.071267517076, 8947.284217262733, 6673.566218195801, 7702.152763196156, 7806.92193078062, 7810.0, 7900.723474678359, 5537.363203231519, 5711.257536520226, 5749.223854779551, 5766.452187042224, 5839.0949402842625, 4588.7476188808605, 5781.248437703071, 5910.379410161871, 5953.91069133972, 5983.252093488314], "value": 5021.244331920054, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.70445121646594, 395.54117224020143, 397.25514660012476, 409.524951056774, 412.3237889860628, 5650.971745141279, 6094.26868775747, 6190.411910868466, 6190.628562286261, 6233.1273621693, 4813.831515896944, 6000.3699611540815, 6009.0, 6012.278526576806, 6070.757169713206, 3783.5270591176104, 4057.695672824535, 4563.589276965073, 4587.38070360502, 4596.287575425815, 3899.5710471848074, 4443.37792709021, 4470.396496472967, 4551.226291530431, 4622.2835460503675, 4032.6370626643597, 4570.7257564546235, 4574.290984897346, 4584.724916505003, 4594.3338215958665, 3992.580779018205, 4301.526832048472, 4557.088582283556, 4579.542045795427, 4583.977080114578, 3820.59883712209, 3842.1163132479405, 3923.5880232575455, 3938.803059847025, 3960.5643379228263, 4840.443349014873, 5108.514691104377, 5967.9701601491715, 5968.061778530347, 5984.611000285011, 5045.84862454127, 5264.763085661154, 5302.310699609026, 5329.440408757102, 5375.462453754632, 5944.008757066388, 6161.938380616181, 6423.582467139621, 6443.420092191725, 6474.352564743535, 4001.4598029265967, 4122.505299364066, 4357.27887484445, 4361.323994780814, 4393.968161968263, 5188.870278243036, 5208.479152084799, 5509.586780991442, 5682.460166284196, 6017.939820601826, 4022.3363145080734, 4145.357469592218, 4455.1757924784015, 4459.509453960061, 4486.7083639563325, 3609.7112231021456, 4530.728156310632, 4534.387857639243, 4545.2954792007295, 4545.818167273338, 5215.843524694189, 5283.098084689509, 5332.226827110047, 5370.946290537123, 5383.327084114486, 6505.178550006006, 6526.445252153525, 6542.3130571290285, 6546.279909209983, 6820.31796820314, 1364.1678576068591, 1537.8077740282465, 1547.3269127929386, 1552.596324955497, 1554.3394057525732, 866.0211416912451, 1001.5743309093606, 1018.8471729240525, 1022.3252653248969, 1027.6043723166486, 6284.402981716729, 6291.0, 6297.716602752887, 6315.084312774714, 6317.178766760292, 5123.666961647481, 6597.86804263912, 6769.830754231184, 6788.219354774165, 6807.693653785592, 5599.272094627672, 5711.628744131618, 6246.875062498724, 6291.5281353898645, 6292.276388215322, 3107.9689203108132, 3521.6654417830264, 3540.4866294387684, 3552.378333791628, 3583.372909740785, 6161.8125565423625, 6608.4052435281055, 6889.530451547601, 6948.583085014915, 6993.300669932966, 5816.360200377955, 6589.308122647149, 6850.588964662036, 6944.055664413972, 6960.65196740161, 6571.404295303424, 6592.9340706593275, 6692.364225398579, 6972.616506092214, 6990.999999999898, 5709.486146246858, 6406.583572067801, 6795.218549866631, 6803.693833777393, 6810.8637827244165, 5461.617686762047, 5749.770009199585, 6503.894337962463, 6606.372394622503, 6694.661067786365, 5523.033469142964, 6025.758969641341, 6371.49028077762, 6992.405645520188, 7057.7176912922905, 3915.647591716759, 4359.237133501561, 4389.23188442015, 4409.492908315585, 4436.5119836817275, 6223.661912688794, 6692.866142677217, 6736.93263067363, 6752.763653272112, 6758.560693554903, 5689.857753556194, 6313.621182729051, 6440.550876052851, 6483.610983341015, 6556.8360790978695, 6423.229212494531, 6468.902966455413, 6506.934930650822, 6518.837029074311, 6528.510361722795, 5810.5351571875, 6070.1501789750355, 6358.968205158944, 6363.236411630634, 6396.9040464394, 5240.895182096413, 5669.234653321749, 6187.288461826767, 6349.047642853424, 6483.800496908088, 6308.432241098323, 6330.39861213184, 6376.524793987195, 6399.608207649953, 6408.61548307103], "value": 4698.011118624727, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.4956431369413, 712.5475323169792, 740.5593671765301, 742.7845924681841, 745.8359160984585, 14964.176970266632, 15886.0, 15898.84101158988, 15933.601659958493, 15935.920320398396, 14543.854561454382, 14697.33861976211, 14713.264336783172, 14996.775048374257, 15037.699246015072, 929.4423345992418, 939.5912777941618, 957.310736269888, 983.2625530851861, 987.0770829274616, 959.040959040959, 974.9658761943333, 980.1668581705565, 980.6959842448828, 984.6947446291673, 1009.8283291840405, 1017.1760873692333, 1025.318163421324, 1026.9691909242736, 1027.1474676018886, 978.6966040527459, 987.521052289639, 1017.120191034757, 1018.210886562916, 1021.1269364693192, 759.787259567322, 760.8972788673514, 761.0638914135588, 762.4434163060942, 766.0271455251855, 15936.681266374724, 16230.0, 16278.43025494102, 16474.75287870683, 16688.6662266754, 12813.743725125445, 14434.422623095064, 14676.339564719614, 14719.55841324762, 14755.26223688822, 11712.707182320424, 11774.705632359173, 12064.638060858188, 12197.0, 12442.0, 3001.1296723950027, 3024.8185108893535, 3113.128324069264, 3182.316041023589, 3208.0375887233968, 82630.17369826345, 93634.53182734111, 105883.47058264737, 109468.0, 110515.0, 11239.651241850866, 11265.324080555029, 11348.943255283835, 11658.6502404928, 11844.822327665092, 18397.0, 24595.0, 24803.0, 24835.0, 24867.12965046251, 7216.458765592602, 7366.710825605498, 7724.497907635986, 7937.563434011185, 8114.391420643476, 3672.412414013745, 3692.778433294011, 3967.0479085019388, 4117.732347397409, 4155.896102597399, 4103.384492326175, 4137.213929353413, 4203.726757760735, 4205.474315710536, 4236.745795252295, 666.8132922781629, 677.9830504237434, 788.3811208201535, 822.1737154160031, 856.1181982557976, 547.854818473105, 567.0287692491315, 587.5270407322062, 591.9289685237799, 614.2015380005975, 9526.428414295164, 9704.369216000938, 10278.947263403523, 10304.793904121876, 10514.303859015408, 481.0451254260291, 546.9589780766458, 589.1633879890566, 598.9191459153046, 603.4508597176548, 4515.198525002096, 4720.362751028636, 4815.927761083586, 5054.873628159252, 5217.0, 92791.0, 93716.53141734247, 95189.0, 98438.0, 98845.5173172403, 88770.0, 90036.5498172518, 90140.54929725309, 90671.54664226636, 90792.0, 77535.0, 78881.21118788853, 79992.60003699944, 80048.59975700085, 80681.59659201784, 10484.056434920894, 10740.46297685097, 10808.621698240524, 10956.835647465135, 10985.780284394426, 28342.716572834008, 37088.258234835695, 38458.80770596241, 38849.0, 40119.0, 51945.0, 52045.73977130242, 52292.0, 62303.6884815573, 65613.0, 13390.732185356044, 13605.387757550736, 13758.243296618783, 13791.793123102962, 14031.36858841375, 14493.347799348843, 14717.116972981656, 14775.261236938326, 14897.702045958804, 15363.001404908646, 13684.794728079285, 13735.381907813973, 13929.791053134413, 13967.580972570839, 14300.0, 13289.401976911255, 13348.666283342995, 13653.931730341683, 13984.230867302396, 14080.647983800487, 13138.883194928156, 13326.733465330446, 13511.932440337736, 13659.248741318925, 13942.998562989162, 9880.255918743538, 10983.725406864893, 11075.944620276847, 11123.27698699582, 11384.772304553697, 9235.399699019543, 9421.858672119786, 10004.0, 10835.945820270848, 11278.323300601991], "value": 7550.702238105347, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1618.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 119.0, 114.0, 116.0, 116.0, 117.0, 98.0, 168.0, 171.0, 169.0], "value": 129.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 134.0, 143.0, 194.0, 108.0, 150.0, 149.0, 102.0, 168.0, 142.0], "value": 143.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 212.0, 206.0, 227.0, 192.0, 197.0, 205.0, 200.0, 223.0, 201.0], "value": 206.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 49.0, 59.0, 107.0, 87.0, 49.0, 66.0, 88.0, 100.0, 50.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 58.0, 85.0, 58.0, 75.0, 98.0, 81.0, 96.0, 106.0, 118.0], "value": 86.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 92.0, 102.0, 81.0, 135.0, 134.0, 109.0, 89.0, 89.0, 89.0], "value": 105.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 138.0, 153.0, 96.0, 95.0, 126.0, 109.0, 100.0, 94.0, 154.0], "value": 116.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 145.0, 146.0, 94.0, 97.0, 90.0, 99.0, 114.0, 91.0, 91.0], "value": 107.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 67.0, 67.0, 70.0, 69.0, 67.0, 68.0, 68.0, 68.0], "value": 68.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 65.0, 66.0, 66.0, 61.0, 59.0, 57.0, 60.0, 60.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 85.0, 86.0, 84.0, 84.0, 81.0, 100.0, 84.0, 85.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 151.0, 154.0, 145.0, 141.0, 156.0, 160.0, 149.0, 206.0, 165.0], "value": 157.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [244.0, 222.0, 190.0, 186.0, 192.0, 188.0, 191.0, 188.0, 214.0, 240.0], "value": 205.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 69.0, 67.0, 68.0, 68.0, 71.0, 67.0, 67.0, 107.0], "value": 72.3, "unit": "score"}]}]} 07:53:52 INFO - Return code: 0 07:53:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:53:52 ERROR - # TBPL SUCCESS # 07:53:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:53:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:53:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:53:52 INFO - 2017-03-22 07:53:52.914 screenresolution[1661:10729] starting screenresolution argv=screenresolution get 07:53:52 INFO - 2017-03-22 07:53:52.916 screenresolution[1661:10729] Display 0: 1600x1200x32@60 07:53:52 INFO - 2017-03-22 07:53:52.923 screenresolution[1662:10732] starting screenresolution argv=screenresolution list 07:53:52 INFO - Available Modes on Display 0 07:53:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:53:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:53:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:53:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:53:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:53:53 INFO - Intel Iris: 07:53:53 INFO - Chipset Model: Intel Iris 07:53:53 INFO - Type: GPU 07:53:53 INFO - Bus: Built-In 07:53:53 INFO - VRAM (Dynamic, Max): 1536 MB 07:53:53 INFO - Vendor: Intel (0x8086) 07:53:53 INFO - Device ID: 0x0a2e 07:53:53 INFO - Revision ID: 0x0009 07:53:53 INFO - Displays: 07:53:53 INFO - Display: 07:53:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:53:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:53:53 INFO - Display Serial Number: 1600x1200 60 07:53:53 INFO - Main Display: Yes 07:53:53 INFO - Mirror: Off 07:53:53 INFO - Online: Yes 07:53:53 INFO - Rotation: Supported 07:53:53 INFO - Return code: 0 07:53:53 INFO - Running post-action listener: _resource_record_post_action 07:53:53 INFO - [mozharness: 2017-03-22 14:53:53.161063Z] Finished run-tests step (success) 07:53:53 INFO - Running post-run listener: _resource_record_post_run 07:53:53 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 38734336; Write bytes: 515112960; Read time: 247; Write time: 1611 07:53:53 INFO - TinderboxPrint: CPU usage
54.2% 07:53:53 INFO - TinderboxPrint: I/O read bytes / time
38,734,336 / 247 07:53:53 INFO - TinderboxPrint: I/O write bytes / time
515,112,960 / 1,611 07:53:53 INFO - TinderboxPrint: CPU idle
2,201.8 (45.8%) 07:53:53 INFO - TinderboxPrint: CPU system
967.3 (20.1%) 07:53:53 INFO - TinderboxPrint: CPU user
1,636.7 (34.1%) 07:53:53 INFO - TinderboxPrint: Swap in / out
249,819,136 / 0 07:53:53 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203049472; Write bytes: 203249152; Read time: 15967; Write time: 431 07:53:53 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 37767680; Write bytes: 301922816; Read time: 231; Write time: 1143 07:53:53 INFO - Running post-run listener: _upload_blobber_files 07:53:53 INFO - Blob upload gear active. 07:53:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:53:53 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 07:53:53 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'] 07:53:53 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 07:53:54 INFO - (blobuploader) - INFO - Open directory for files ... 07:53:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:53:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:53:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:54 INFO - (blobuploader) - INFO - Done attempting. 07:53:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:53:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:53:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:55 INFO - (blobuploader) - INFO - Done attempting. 07:53:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:53:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:53:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:57 INFO - (blobuploader) - INFO - Done attempting. 07:53:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:53:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:53:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:59 INFO - (blobuploader) - INFO - Done attempting. 07:53:59 INFO - (blobuploader) - INFO - Iteration through files over. 07:53:59 INFO - Return code: 0 07:53:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:53:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:53:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8897d8beb0984fe6d0d85e7c15d8753ee4647b9b43eb5c3fc0741c449df0c33d4b4965d706737b44f03c90e4b96ebfcd340b56676143c447bf9db99aa6c7ee8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6636631bfe6e0f417b682de3cdb8ce7692e9ee539d5ab415bb0dc7a100c432b28ebe465f5373ba22ec813c6aff9a2aec081d70f6342f71fcb523cd27c89b6494", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c61c2245014b2118e0d7c28335d93fa9331f66fc72ec934cdd6812baab6e0c6c03a980b97dc32f78d5e7cd970d9b2d7921f59c0d82a32fe12d3c5369a5c76328", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/683f64d21f2ff88cc311cf398d55c43aeff9a93b5e30e491f90d0af0032135eaac1bc3650c95549a293ff38d978628c9c26c705da3c63985a5d94cc98c3787aa"} 07:53:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:53:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:53:59 INFO - Contents: 07:53:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8897d8beb0984fe6d0d85e7c15d8753ee4647b9b43eb5c3fc0741c449df0c33d4b4965d706737b44f03c90e4b96ebfcd340b56676143c447bf9db99aa6c7ee8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6636631bfe6e0f417b682de3cdb8ce7692e9ee539d5ab415bb0dc7a100c432b28ebe465f5373ba22ec813c6aff9a2aec081d70f6342f71fcb523cd27c89b6494", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c61c2245014b2118e0d7c28335d93fa9331f66fc72ec934cdd6812baab6e0c6c03a980b97dc32f78d5e7cd970d9b2d7921f59c0d82a32fe12d3c5369a5c76328", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/683f64d21f2ff88cc311cf398d55c43aeff9a93b5e30e491f90d0af0032135eaac1bc3650c95549a293ff38d978628c9c26c705da3c63985a5d94cc98c3787aa"} 07:53:59 INFO - Running post-run listener: copy_logs_to_upload_dir 07:53:59 INFO - Copying logs to upload dir... 07:53:59 INFO - mkdir: /builds/slave/test/build/upload/logs 07:53:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.788116 ========= master_lag: 0.64 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-22 07:53:59.876522) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 07:53:59.880444) ========= 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.F5eAYLpliq/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.AIrjM7Bscv/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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/e8897d8beb0984fe6d0d85e7c15d8753ee4647b9b43eb5c3fc0741c449df0c33d4b4965d706737b44f03c90e4b96ebfcd340b56676143c447bf9db99aa6c7ee8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6636631bfe6e0f417b682de3cdb8ce7692e9ee539d5ab415bb0dc7a100c432b28ebe465f5373ba22ec813c6aff9a2aec081d70f6342f71fcb523cd27c89b6494", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c61c2245014b2118e0d7c28335d93fa9331f66fc72ec934cdd6812baab6e0c6c03a980b97dc32f78d5e7cd970d9b2d7921f59c0d82a32fe12d3c5369a5c76328", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/683f64d21f2ff88cc311cf398d55c43aeff9a93b5e30e491f90d0af0032135eaac1bc3650c95549a293ff38d978628c9c26c705da3c63985a5d94cc98c3787aa"} build_url:https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011109 build_url: 'https://queue.taskcluster.net/v1/task/FcBY8WRRS8uGMSz0qmbyKA/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/e8897d8beb0984fe6d0d85e7c15d8753ee4647b9b43eb5c3fc0741c449df0c33d4b4965d706737b44f03c90e4b96ebfcd340b56676143c447bf9db99aa6c7ee8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6636631bfe6e0f417b682de3cdb8ce7692e9ee539d5ab415bb0dc7a100c432b28ebe465f5373ba22ec813c6aff9a2aec081d70f6342f71fcb523cd27c89b6494", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c61c2245014b2118e0d7c28335d93fa9331f66fc72ec934cdd6812baab6e0c6c03a980b97dc32f78d5e7cd970d9b2d7921f59c0d82a32fe12d3c5369a5c76328", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/683f64d21f2ff88cc311cf398d55c43aeff9a93b5e30e491f90d0af0032135eaac1bc3650c95549a293ff38d978628c9c26c705da3c63985a5d94cc98c3787aa"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 07:53:59.915078) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 07:53:59.915452) ========= 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.F5eAYLpliq/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.AIrjM7Bscv/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.005187 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 07:53:59.964107) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 07:53:59.964466) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 07:53:59.964795) ========= ========= Total master_lag: 1.03 =========